client.radosgw.set004.log - rgw - Ceph

Bug #10946 » client.radosgw.set004.log

Artem Savinov, 02/25/2015 08:51 AM

 -02-25 10:35:28.088990 7f2cadddd8a0  0 ceph version 0.87 (c51c8f9d80fa4e0168aa52685b8de40e42758578), process radosgw, pid 30448
 -02-25 10:35:28.101746 7f2cadddd8a0 20 get_obj_state: rctx=0x241a8b0 obj=.rgw.root:default.region state=0x23e5cd8 s->prefetch_data=0
 -02-25 10:35:28.101768 7f2cadddd8a0 10 cache get: name=.rgw.root+default.region : miss
 -02-25 10:35:28.102123 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:35:28.820340 7f2cadddd8a0 10 cache put: name=.rgw.root+default.region
 -02-25 10:35:28.820360 7f2cadddd8a0 10 adding .rgw.root+default.region to cache LRU end
 -02-25 10:35:28.820369 7f2cadddd8a0 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:35:28.820382 7f2cadddd8a0 10 cache get: name=.rgw.root+default.region : type miss (requested=1, cached=6)
 -02-25 10:35:28.820399 7f2cadddd8a0 20 get_obj_state: rctx=0x241a8b0 obj=.rgw.root:default.region state=0x23e5cd8 s->prefetch_data=0
 -02-25 10:35:28.820404 7f2cadddd8a0 10 cache get: name=.rgw.root+default.region : hit
 -02-25 10:35:28.820407 7f2cadddd8a0 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:35:28.820428 7f2cadddd8a0 20 get_obj_state: rctx=0x241a8b0 obj=.rgw.root:default.region state=0x23e5cd8 s->prefetch_data=0
 -02-25 10:35:28.820430 7f2cadddd8a0 20 state for obj=.rgw.root:default.region is not atomic, not appending atomic test
 -02-25 10:35:28.820433 7f2cadddd8a0 20 rados->read obj-ofs=0 read_ofs=0 read_len=524288
 -02-25 10:35:28.821401 7f2cadddd8a0 20 rados->read r=0 bl.length=17
 -02-25 10:35:28.821416 7f2cadddd8a0 10 cache put: name=.rgw.root+default.region
 -02-25 10:35:28.821418 7f2cadddd8a0 10 moving .rgw.root+default.region to cache LRU end
 -02-25 10:35:28.821446 7f2cadddd8a0 20 get_obj_state: rctx=0x23e62d0 obj=.rgw.root:region_info.default state=0x23e5cd8 s->prefetch_data=0
 -02-25 10:35:28.821452 7f2cadddd8a0 10 cache get: name=.rgw.root+region_info.default : miss
 -02-25 10:35:28.824013 7f2cadddd8a0 10 cache put: name=.rgw.root+region_info.default
 -02-25 10:35:28.824018 7f2cadddd8a0 10 adding .rgw.root+region_info.default to cache LRU end
 -02-25 10:35:28.824022 7f2cadddd8a0 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:35:28.824027 7f2cadddd8a0 10 cache get: name=.rgw.root+region_info.default : type miss (requested=1, cached=6)
 -02-25 10:35:28.824033 7f2cadddd8a0 20 get_obj_state: rctx=0x23e62d0 obj=.rgw.root:region_info.default state=0x23e5cd8 s->prefetch_data=0
 -02-25 10:35:28.824036 7f2cadddd8a0 10 cache get: name=.rgw.root+region_info.default : hit
 -02-25 10:35:28.824038 7f2cadddd8a0 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:35:28.824043 7f2cadddd8a0 20 get_obj_state: rctx=0x23e62d0 obj=.rgw.root:region_info.default state=0x23e5cd8 s->prefetch_data=0
 -02-25 10:35:28.824044 7f2cadddd8a0 20 state for obj=.rgw.root:region_info.default is not atomic, not appending atomic test
 -02-25 10:35:28.824046 7f2cadddd8a0 20 rados->read obj-ofs=0 read_ofs=0 read_len=524288
 -02-25 10:35:28.825002 7f2cadddd8a0 20 rados->read r=0 bl.length=145
 -02-25 10:35:28.825019 7f2cadddd8a0 10 cache put: name=.rgw.root+region_info.default
 -02-25 10:35:28.825021 7f2cadddd8a0 10 moving .rgw.root+region_info.default to cache LRU end
 -02-25 10:35:28.825060 7f2cadddd8a0 20 get_obj_state: rctx=0x241ce10 obj=.rgw.root:zone_info.default state=0x241eb28 s->prefetch_data=0
 -02-25 10:35:28.825065 7f2cadddd8a0 10 cache get: name=.rgw.root+zone_info.default : miss
 -02-25 10:35:28.837026 7f2cadddd8a0 10 cache put: name=.rgw.root+zone_info.default
 -02-25 10:35:28.837032 7f2cadddd8a0 10 adding .rgw.root+zone_info.default to cache LRU end
 -02-25 10:35:28.837036 7f2cadddd8a0 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:35:28.837041 7f2cadddd8a0 10 cache get: name=.rgw.root+zone_info.default : type miss (requested=1, cached=6)
 -02-25 10:35:28.837046 7f2cadddd8a0 20 get_obj_state: rctx=0x2421a70 obj=.rgw.root:zone_info.default state=0x241eb28 s->prefetch_data=0
 -02-25 10:35:28.837050 7f2cadddd8a0 10 cache get: name=.rgw.root+zone_info.default : hit
 -02-25 10:35:28.837053 7f2cadddd8a0 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:35:28.837058 7f2cadddd8a0 20 get_obj_state: rctx=0x2421a70 obj=.rgw.root:zone_info.default state=0x241eb28 s->prefetch_data=0
 -02-25 10:35:28.837060 7f2cadddd8a0 20 state for obj=.rgw.root:zone_info.default is not atomic, not appending atomic test
 -02-25 10:35:28.837061 7f2cadddd8a0 20 rados->read obj-ofs=0 read_ofs=0 read_len=524288
 -02-25 10:35:28.838000 7f2cadddd8a0 20 rados->read r=0 bl.length=678
 -02-25 10:35:28.838008 7f2cadddd8a0 10 cache put: name=.rgw.root+zone_info.default
 -02-25 10:35:28.838010 7f2cadddd8a0 10 moving .rgw.root+zone_info.default to cache LRU end
 -02-25 10:35:28.838034 7f2cadddd8a0  2 zone default is master
 -02-25 10:35:28.838047 7f2cadddd8a0 20 get_obj_state: rctx=0x2421fb0 obj=.rgw.root:region_map state=0x241eb28 s->prefetch_data=0
 -02-25 10:35:28.838052 7f2cadddd8a0 10 cache get: name=.rgw.root+region_map : miss
 -02-25 10:35:28.863684 7f2cadddd8a0 10 cache put: name=.rgw.root+region_map
 -02-25 10:35:28.863816 7f2cadddd8a0 10 adding .rgw.root+region_map to cache LRU end
 -02-25 10:35:31.353057 7f2cadddd8a0  0 framework: fastcgi
 -02-25 10:35:31.353068 7f2cadddd8a0  0 framework: civetweb
 -02-25 10:35:31.353083 7f2cadddd8a0  0 framework conf key: port, val: 7480
 -02-25 10:35:31.353099 7f2cadddd8a0  0 starting handler: civetweb
 -02-25 10:35:31.355948 7f2cadddd8a0  0 starting handler: fastcgi
 -02-25 10:35:31.356013 7f2c7b5fe700 20 BucketsSyncThread: start
 -02-25 10:35:31.356019 7f2c7abfd700 20 UserSyncThread: start
 -02-25 10:35:31.357778 7f2c7bfff700  2 garbage collection: start
 -02-25 10:35:31.365281 7f2c3a596700 10 allocated request req=0x7f2c80040710
 -02-25 10:35:31.424940 7f2c7abfd700 20 RGWRados::pool_iterate: got functests
 -02-25 10:35:31.540882 7f2c7abfd700 20 RGWRados::pool_iterate: got admin
 -02-25 10:35:31.540894 7f2c7abfd700 20 RGWUserStatsCache: sync user=functests
 -02-25 10:35:31.541638 7f2c7abfd700  0 ERROR: can't read user header: ret=-2
 -02-25 10:35:31.541643 7f2c7abfd700  0 ERROR: sync_user() failed, user=functests ret=-2
 -02-25 10:35:31.541645 7f2c7abfd700 20 RGWUserStatsCache: sync user=admin
 -02-25 10:35:31.542215 7f2c7abfd700  0 ERROR: can't read user header: ret=-2
 -02-25 10:35:31.542219 7f2c7abfd700  0 ERROR: sync_user() failed, user=admin ret=-2
 -02-25 10:35:32.550644 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:35:50.102275 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:36:12.102410 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:36:31.550746 7f2c7bfff700  2 garbage collection: start
 -02-25 10:36:34.102697 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:36:39.019652 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:36:56.102838 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:37:18.102949 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:37:32.019760 7f2c7bfff700  2 garbage collection: start
 -02-25 10:37:33.490680 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:37:40.103064 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:38:02.103190 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:38:24.103321 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:38:32.490792 7f2c7bfff700  2 garbage collection: start
 -02-25 10:38:41.144023 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:38:46.103441 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:39:08.103561 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:39:30.103687 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:39:33.144108 7f2c7bfff700  2 garbage collection: start
 -02-25 10:39:37.019898 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:39:38.297331 7f2c3a596700 20 enqueued request req=0x7f2c80040710
 -02-25 10:39:38.297355 7f2c3a596700 20 RGWWQ:
 -02-25 10:39:38.297357 7f2c3a596700 20 req: 0x7f2c80040710
 -02-25 10:39:38.297363 7f2c3a596700 10 allocated request req=0x7f2c8003de60
 -02-25 10:39:38.297383 7f2c37d92700 20 dequeued request req=0x7f2c80040710
 -02-25 10:39:38.297387 7f2c37d92700 20 RGWWQ: empty
 -02-25 10:39:38.297436 7f2c37d92700 20 CONTENT_LENGTH=0
 -02-25 10:39:38.297438 7f2c37d92700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:39:38.297439 7f2c37d92700 20 FCGI_ROLE=RESPONDER
 -02-25 10:39:38.297440 7f2c37d92700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:39:38.297440 7f2c37d92700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:39:38.297441 7f2c37d92700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:6sYIaqItV4zOalFPk9IHk8WqbBk=
 -02-25 10:39:38.297441 7f2c37d92700 20 HTTP_HOST=storage.midtest.c2
 -02-25 10:39:38.297442 7f2c37d92700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:39:38 +0000
 -02-25 10:39:38.297443 7f2c37d92700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:39:38.297444 7f2c37d92700 20 QUERY_STRING=
 -02-25 10:39:38.297445 7f2c37d92700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:39:38.297445 7f2c37d92700 20 REMOTE_PORT=48013
 -02-25 10:39:38.297446 7f2c37d92700 20 REQUEST_METHOD=GET
 -02-25 10:39:38.297447 7f2c37d92700 20 REQUEST_URI=/
 -02-25 10:39:38.297447 7f2c37d92700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:39:38.297448 7f2c37d92700 20 SCRIPT_NAME=/
 -02-25 10:39:38.297449 7f2c37d92700 20 SCRIPT_URI=http://storage.midtest.c2/
 -02-25 10:39:38.297449 7f2c37d92700 20 SCRIPT_URL=/
 -02-25 10:39:38.297450 7f2c37d92700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:39:38.297450 7f2c37d92700 20 SERVER_ADMIN=root@localhost
 -02-25 10:39:38.297451 7f2c37d92700 20 SERVER_NAME=storage.midtest.c2
 -02-25 10:39:38.297451 7f2c37d92700 20 SERVER_PORT=80
 -02-25 10:39:38.297452 7f2c37d92700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:39:38.297452 7f2c37d92700 20 SERVER_SIGNATURE=
 -02-25 10:39:38.297453 7f2c37d92700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:39:38.297455 7f2c37d92700  1 ====== starting new request req=0x7f2c80040710 =====
 -02-25 10:39:38.297479 7f2c37d92700  2 req 1:0.000024::GET /::initializing
 -02-25 10:39:38.297484 7f2c37d92700 10 host=storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:39:38.297498 7f2c37d92700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:39:38.297507 7f2c37d92700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:39:38 +0000
 -02-25 10:39:38.297584 7f2c37d92700 10 s->object=<NULL> s->bucket=<NULL>
 -02-25 10:39:38.297600 7f2c37d92700  2 req 1:0.000145:s3:GET /::getting op
 -02-25 10:39:38.297606 7f2c37d92700  2 req 1:0.000151:s3:GET /:list_buckets:authorizing
 -02-25 10:39:38.297647 7f2c37d92700 20 get_obj_state: rctx=0x7f2c7c002f10 obj=.users:FA7GCBZJYADXE2ZZU43J state=0x7f2c7c002fe8 s->prefetch_data=0
 -02-25 10:39:38.297656 7f2c37d92700 10 cache get: name=.users+FA7GCBZJYADXE2ZZU43J : miss
 -02-25 10:39:38.299189 7f2c37d92700 10 cache put: name=.users+FA7GCBZJYADXE2ZZU43J
 -02-25 10:39:38.299198 7f2c37d92700 10 adding .users+FA7GCBZJYADXE2ZZU43J to cache LRU end
 -02-25 10:39:38.299203 7f2c37d92700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:39:38.299210 7f2c37d92700 10 cache get: name=.users+FA7GCBZJYADXE2ZZU43J : type miss (requested=1, cached=6)
 -02-25 10:39:38.299216 7f2c37d92700 20 get_obj_state: rctx=0x7f2c7c002f10 obj=.users:FA7GCBZJYADXE2ZZU43J state=0x7f2c7c002fe8 s->prefetch_data=0
 -02-25 10:39:38.299220 7f2c37d92700 10 cache get: name=.users+FA7GCBZJYADXE2ZZU43J : hit
 -02-25 10:39:38.299223 7f2c37d92700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:39:38.299228 7f2c37d92700 20 get_obj_state: rctx=0x7f2c7c002f10 obj=.users:FA7GCBZJYADXE2ZZU43J state=0x7f2c7c002fe8 s->prefetch_data=0
 -02-25 10:39:38.299230 7f2c37d92700 20 state for obj=.users:FA7GCBZJYADXE2ZZU43J is not atomic, not appending atomic test
 -02-25 10:39:38.299232 7f2c37d92700 20 rados->read obj-ofs=0 read_ofs=0 read_len=524288
 -02-25 10:39:38.299938 7f2c37d92700 20 rados->read r=0 bl.length=13
 -02-25 10:39:38.299948 7f2c37d92700 10 cache put: name=.users+FA7GCBZJYADXE2ZZU43J
 -02-25 10:39:38.299950 7f2c37d92700 10 moving .users+FA7GCBZJYADXE2ZZU43J to cache LRU end
 -02-25 10:39:38.299965 7f2c37d92700 20 get_obj_state: rctx=0x7f2c7c002c90 obj=.users.uid:functests state=0x7f2c7c002de8 s->prefetch_data=0
 -02-25 10:39:38.299970 7f2c37d92700 10 cache get: name=.users.uid+functests : miss
 -02-25 10:39:38.301515 7f2c37d92700 10 cache put: name=.users.uid+functests
 -02-25 10:39:38.301524 7f2c37d92700 10 adding .users.uid+functests to cache LRU end
 -02-25 10:39:38.301530 7f2c37d92700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:39:38.301558 7f2c37d92700 10 cache get: name=.users.uid+functests : type miss (requested=17, cached=22)
 -02-25 10:39:38.301568 7f2c37d92700 20 get_obj_state: rctx=0x7f2c7c002c90 obj=.users.uid:functests state=0x7f2c7c002de8 s->prefetch_data=0
 -02-25 10:39:38.301572 7f2c37d92700 10 cache get: name=.users.uid+functests : hit
 -02-25 10:39:38.301575 7f2c37d92700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:39:38.301581 7f2c37d92700 20 get_obj_state: rctx=0x7f2c7c002c90 obj=.users.uid:functests state=0x7f2c7c002de8 s->prefetch_data=0
 -02-25 10:39:38.301583 7f2c37d92700 20 state for obj=.users.uid:functests is not atomic, not appending atomic test
 -02-25 10:39:38.301600 7f2c37d92700 20 rados->read obj-ofs=0 read_ofs=0 read_len=524288
 -02-25 10:39:38.302476 7f2c37d92700 20 rados->read r=0 bl.length=394
 -02-25 10:39:38.302487 7f2c37d92700 10 cache put: name=.users.uid+functests
 -02-25 10:39:38.302489 7f2c37d92700 10 moving .users.uid+functests to cache LRU end
 -02-25 10:39:38.302509 7f2c37d92700 10 chain_cache_entry: cache_locator=.users.uid+functests
 -02-25 10:39:38.302594 7f2c37d92700 10 get_canon_resource(): dest=/
 -02-25 10:39:38.302598 7f2c37d92700 10 auth_hdr:
       GET
       x-amz-date:Wed, 25 Feb 2015 07:39:38 +0000
+      /
 -02-25 10:39:38.302662 7f2c37d92700 15 calculated digest=6sYIaqItV4zOalFPk9IHk8WqbBk=
 -02-25 10:39:38.302665 7f2c37d92700 15 auth_sign=6sYIaqItV4zOalFPk9IHk8WqbBk=
 -02-25 10:39:38.302666 7f2c37d92700 15 compare=0
 -02-25 10:39:38.302668 7f2c37d92700  2 req 1:0.005213:s3:GET /:list_buckets:reading permissions
 -02-25 10:39:38.302677 7f2c37d92700  2 req 1:0.005223:s3:GET /:list_buckets:init op
 -02-25 10:39:38.302681 7f2c37d92700  2 req 1:0.005226:s3:GET /:list_buckets:verifying op mask
 -02-25 10:39:38.302685 7f2c37d92700 20 required_mask= 1 user.op_mask=7
 -02-25 10:39:38.302687 7f2c37d92700  2 req 1:0.005233:s3:GET /:list_buckets:verifying op permissions
 -02-25 10:39:38.302689 7f2c37d92700  2 req 1:0.005234:s3:GET /:list_buckets:verifying op params
 -02-25 10:39:38.302690 7f2c37d92700  2 req 1:0.005236:s3:GET /:list_buckets:executing
 -02-25 10:39:38.303569 7f2c37d92700  2 req 1:0.006114:s3:GET /:list_buckets:http status=200
 -02-25 10:39:38.303578 7f2c37d92700  1 ====== req done req=0x7f2c80040710 http_status=200 ======
 -02-25 10:39:47.709026 7f2c3a596700 20 enqueued request req=0x7f2c8003de60
 -02-25 10:39:47.709053 7f2c3a596700 20 RGWWQ:
 -02-25 10:39:47.709054 7f2c3a596700 20 req: 0x7f2c8003de60
 -02-25 10:39:47.709058 7f2c3a596700 10 allocated request req=0x7f2c8003e230
 -02-25 10:39:47.709161 7f2c37d92700 20 dequeued request req=0x7f2c8003de60
 -02-25 10:39:47.709168 7f2c37d92700 20 RGWWQ: empty
 -02-25 10:39:47.709207 7f2c37d92700 20 CONTENT_LENGTH=0
 -02-25 10:39:47.709208 7f2c37d92700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:39:47.709209 7f2c37d92700 20 FCGI_ROLE=RESPONDER
 -02-25 10:39:47.709210 7f2c37d92700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:39:47.709211 7f2c37d92700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:39:47.709211 7f2c37d92700 20 HTTP_AUTHORIZATION=AWS4-HMAC-SHA256 Credential=FA7GCBZJYADXE2ZZU43J/20150225/US/s3/aws4_request,SignedHeaders=host;x-amz-content-sha256;x-amz-date,Signature=d947b6414bc299f3b3c9eb43cf38fc7d4134659092cab4816f7c45a4fb022929
 -02-25 10:39:47.709215 7f2c37d92700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:39:47.709216 7f2c37d92700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:39:47.709216 7f2c37d92700 20 HTTP_X_AMZ_DATE=20150225T073947Z
 -02-25 10:39:47.709217 7f2c37d92700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:39:47.709217 7f2c37d92700 20 QUERY_STRING=
 -02-25 10:39:47.709218 7f2c37d92700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:39:47.709219 7f2c37d92700 20 REMOTE_PORT=48014
 -02-25 10:39:47.709219 7f2c37d92700 20 REQUEST_METHOD=PUT
 -02-25 10:39:47.709220 7f2c37d92700 20 REQUEST_URI=/
 -02-25 10:39:47.709221 7f2c37d92700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:39:47.709221 7f2c37d92700 20 SCRIPT_NAME=/
 -02-25 10:39:47.709222 7f2c37d92700 20 SCRIPT_URI=http://test.storage.midtest.c2/
 -02-25 10:39:47.709223 7f2c37d92700 20 SCRIPT_URL=/
 -02-25 10:39:47.709223 7f2c37d92700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:39:47.709224 7f2c37d92700 20 SERVER_ADMIN=root@localhost
 -02-25 10:39:47.709224 7f2c37d92700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:39:47.709225 7f2c37d92700 20 SERVER_PORT=80
 -02-25 10:39:47.709226 7f2c37d92700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:39:47.709227 7f2c37d92700 20 SERVER_SIGNATURE=
 -02-25 10:39:47.709228 7f2c37d92700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:39:47.709230 7f2c37d92700  1 ====== starting new request req=0x7f2c8003de60 =====
 -02-25 10:39:47.709242 7f2c37d92700  2 req 2:0.000013::PUT /::initializing
 -02-25 10:39:47.709246 7f2c37d92700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:39:47.709259 7f2c37d92700 10 meta>> HTTP_X_AMZ_CONTENT_SHA256
 -02-25 10:39:47.709264 7f2c37d92700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:39:47.709268 7f2c37d92700 10 x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:39:47.709270 7f2c37d92700 10 x>> x-amz-date:20150225T073947Z
 -02-25 10:39:47.709296 7f2c37d92700 10 s->object=<NULL> s->bucket=test
 -02-25 10:39:47.709304 7f2c37d92700  2 req 2:0.000075:s3:PUT /::getting op
 -02-25 10:39:47.709315 7f2c37d92700  2 req 2:0.000085:s3:PUT /:create_bucket:authorizing
 -02-25 10:39:47.709318 7f2c37d92700 10 failed to authorize request
 -02-25 10:39:47.709362 7f2c37d92700  2 req 2:0.000132:s3:PUT /:create_bucket:http status=400
 -02-25 10:39:47.709372 7f2c37d92700  1 ====== req done req=0x7f2c8003de60 http_status=400 ======
 -02-25 10:39:47.709378 7f2c37d92700 20 process_request() returned -22
 -02-25 10:39:47.752350 7f2c3a596700 20 enqueued request req=0x7f2c8003e230
 -02-25 10:39:47.752368 7f2c3a596700 20 RGWWQ:
 -02-25 10:39:47.752370 7f2c3a596700 20 req: 0x7f2c8003e230
 -02-25 10:39:47.752373 7f2c3a596700 10 allocated request req=0x7f2c8003e530
 -02-25 10:39:47.752389 7f2c36990700 20 dequeued request req=0x7f2c8003e230
 -02-25 10:39:47.752393 7f2c36990700 20 RGWWQ: empty
 -02-25 10:39:47.752434 7f2c36990700 20 CONTENT_LENGTH=0
 -02-25 10:39:47.752436 7f2c36990700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:39:47.752437 7f2c36990700 20 FCGI_ROLE=RESPONDER
 -02-25 10:39:47.752438 7f2c36990700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:39:47.752438 7f2c36990700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:39:47.752439 7f2c36990700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:NBcBlHUjnE1aV3df32aLjI8TJNw=
 -02-25 10:39:47.752439 7f2c36990700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:39:47.752440 7f2c36990700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:39:47.752442 7f2c36990700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:39:47 +0000
 -02-25 10:39:47.752442 7f2c36990700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:39:47.752443 7f2c36990700 20 QUERY_STRING=
 -02-25 10:39:47.752444 7f2c36990700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:39:47.752444 7f2c36990700 20 REMOTE_PORT=48015
 -02-25 10:39:47.752445 7f2c36990700 20 REQUEST_METHOD=PUT
 -02-25 10:39:47.752445 7f2c36990700 20 REQUEST_URI=/
 -02-25 10:39:47.752446 7f2c36990700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:39:47.752446 7f2c36990700 20 SCRIPT_NAME=/
 -02-25 10:39:47.752447 7f2c36990700 20 SCRIPT_URI=http://test.storage.midtest.c2/
 -02-25 10:39:47.752447 7f2c36990700 20 SCRIPT_URL=/
 -02-25 10:39:47.752454 7f2c36990700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:39:47.752455 7f2c36990700 20 SERVER_ADMIN=root@localhost
 -02-25 10:39:47.752456 7f2c36990700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:39:47.752456 7f2c36990700 20 SERVER_PORT=80
 -02-25 10:39:47.752457 7f2c36990700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:39:47.752457 7f2c36990700 20 SERVER_SIGNATURE=
 -02-25 10:39:47.752458 7f2c36990700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:39:47.752459 7f2c36990700  1 ====== starting new request req=0x7f2c8003e230 =====
 -02-25 10:39:47.752472 7f2c36990700  2 req 3:0.000014::PUT /::initializing
 -02-25 10:39:47.752476 7f2c36990700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:39:47.752486 7f2c36990700 10 meta>> HTTP_X_AMZ_CONTENT_SHA256
 -02-25 10:39:47.752489 7f2c36990700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:39:47.752492 7f2c36990700 10 x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:39:47.752494 7f2c36990700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:39:47 +0000
 -02-25 10:39:47.752514 7f2c36990700 10 s->object=<NULL> s->bucket=test
 -02-25 10:39:47.752519 7f2c36990700  2 req 3:0.000060:s3:PUT /::getting op
 -02-25 10:39:47.752523 7f2c36990700  2 req 3:0.000065:s3:PUT /:create_bucket:authorizing
 -02-25 10:39:47.752578 7f2c36990700 10 get_canon_resource(): dest=/test/
 -02-25 10:39:47.752589 7f2c36990700 10 auth_hdr:
       PUT
       x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
       x-amz-date:Wed, 25 Feb 2015 07:39:47 +0000
       /test/
 -02-25 10:39:47.752678 7f2c36990700 15 calculated digest=NBcBlHUjnE1aV3df32aLjI8TJNw=
 -02-25 10:39:47.752682 7f2c36990700 15 auth_sign=NBcBlHUjnE1aV3df32aLjI8TJNw=
 -02-25 10:39:47.752683 7f2c36990700 15 compare=0
 -02-25 10:39:47.752685 7f2c36990700  2 req 3:0.000227:s3:PUT /:create_bucket:reading permissions
 -02-25 10:39:47.752688 7f2c36990700  2 req 3:0.000230:s3:PUT /:create_bucket:init op
 -02-25 10:39:47.752691 7f2c36990700  2 req 3:0.000232:s3:PUT /:create_bucket:verifying op mask
 -02-25 10:39:47.752692 7f2c36990700 20 required_mask= 2 user.op_mask=7
 -02-25 10:39:47.752693 7f2c36990700  2 req 3:0.000235:s3:PUT /:create_bucket:verifying op permissions
 -02-25 10:39:47.754164 7f2c36990700  2 req 3:0.001706:s3:PUT /:create_bucket:verifying op params
 -02-25 10:39:47.754172 7f2c36990700  2 req 3:0.001714:s3:PUT /:create_bucket:executing
 -02-25 10:39:47.754222 7f2c36990700 20 get_obj_state: rctx=0x7f2c3698f5d0 obj=.rgw:test state=0x7f2c8403ac98 s->prefetch_data=0
 -02-25 10:39:47.754230 7f2c36990700 10 cache get: name=.rgw+test : miss
 -02-25 10:39:47.755574 7f2c36990700 10 cache put: name=.rgw+test
 -02-25 10:39:47.755581 7f2c36990700 10 adding .rgw+test to cache LRU end
 -02-25 10:39:51.008193 7f2c36990700 10 cache put: name=.rgw+.bucket.meta.test:default.4303.1
 -02-25 10:39:51.008204 7f2c36990700 10 adding .rgw+.bucket.meta.test:default.4303.1 to cache LRU end
 -02-25 10:39:51.008208 7f2c36990700 10 updating xattr: name=user.rgw.acl bl.length()=151
 -02-25 10:39:51.008233 7f2c36990700 10 distributing notification oid=notify.2 bl.length()=620
 -02-25 10:39:51.009631 7f2c997fb700 10 RGWWatcher::notify() opcode=1 ver=30 bl.length()=620
 -02-25 10:39:51.009652 7f2c997fb700 10 cache put: name=.rgw+.bucket.meta.test:default.4303.1
 -02-25 10:39:51.009654 7f2c997fb700 10 moving .rgw+.bucket.meta.test:default.4303.1 to cache LRU end
 -02-25 10:39:51.009666 7f2c997fb700 10 updating xattr: name=user.rgw.acl bl.length()=151
 -02-25 10:39:52.103829 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:39:52.742329 7f2c36990700 10 cache put: name=.rgw+test
 -02-25 10:39:52.742337 7f2c36990700 10 moving .rgw+test to cache LRU end
 -02-25 10:39:52.742353 7f2c36990700 10 distributing notification oid=notify.1 bl.length()=334
 -02-25 10:39:52.743455 7f2c997fb700 10 RGWWatcher::notify() opcode=1 ver=30 bl.length()=334
 -02-25 10:39:52.743468 7f2c997fb700 10 cache put: name=.rgw+test
 -02-25 10:39:52.743471 7f2c997fb700 10 moving .rgw+test to cache LRU end
 -02-25 10:39:52.744431 7f2c36990700 20 rgw_create_bucket returned ret=0 bucket=test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1])
 -02-25 10:39:53.495054 7f2c36990700  2 req 3:5.742595:s3:PUT /:create_bucket:http status=200
 -02-25 10:39:53.495067 7f2c36990700  1 ====== req done req=0x7f2c8003e230 http_status=200 ======
 -02-25 10:40:01.353888 7f2ca4ffa700  0 WARNING: RGWRados::log_usage(): user name empty (bucket=), skipping
 -02-25 10:40:14.103970 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:40:34.020032 7f2c7bfff700  2 garbage collection: start
 -02-25 10:40:36.104150 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:40:43.251378 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:40:58.104281 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:41:02.222840 7f2c3a596700 20 enqueued request req=0x7f2c8003e530
 -02-25 10:41:02.222873 7f2c3a596700 20 RGWWQ:
 -02-25 10:41:02.222876 7f2c3a596700 20 req: 0x7f2c8003e530
 -02-25 10:41:02.222884 7f2c3a596700 10 allocated request req=0x7f2c80044e90
 -02-25 10:41:02.223047 7f2c35f8f700 20 dequeued request req=0x7f2c8003e530
 -02-25 10:41:02.223058 7f2c35f8f700 20 RGWWQ: empty
 -02-25 10:41:02.223134 7f2c35f8f700 20 CONTENT_LENGTH=0
 -02-25 10:41:02.223140 7f2c35f8f700 20 CONTENT_TYPE=application/x-iso9660-image
 -02-25 10:41:02.223143 7f2c35f8f700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:41:02.223146 7f2c35f8f700 20 FCGI_ROLE=RESPONDER
 -02-25 10:41:02.223148 7f2c35f8f700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:41:02.223151 7f2c35f8f700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:41:02.223153 7f2c35f8f700 20 HTTP_AUTHORIZATION=AWS4-HMAC-SHA256 Credential=FA7GCBZJYADXE2ZZU43J/20150225/US/s3/aws4_request,SignedHeaders=content-type;host;x-amz-content-sha256;x-amz-date;x-amz-meta-s3cmd-attrs,Signature=11d8b48702e47ac86189c09221d0c40c989538ae8912de18050d9f58540325b7
 -02-25 10:41:02.223160 7f2c35f8f700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:41:02.223162 7f2c35f8f700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:41:02.223165 7f2c35f8f700 20 HTTP_X_AMZ_DATE=20150225T074102Z
 -02-25 10:41:02.223188 7f2c35f8f700 20 HTTP_X_AMZ_META_S3CMD_ATTRS=uid:107/gname:qemu/uname:qemu/gid:107/mode:33204/mtime:1420995560/atime:1424770827/md5:7c01dae8617020e2f7634e2137a1e297/ctime:1421080731
 -02-25 10:41:02.223192 7f2c35f8f700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:41:02.223194 7f2c35f8f700 20 QUERY_STRING=uploads
 -02-25 10:41:02.223196 7f2c35f8f700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:41:02.223198 7f2c35f8f700 20 REMOTE_PORT=48016
 -02-25 10:41:02.223200 7f2c35f8f700 20 REQUEST_METHOD=POST
 -02-25 10:41:02.223202 7f2c35f8f700 20 REQUEST_URI=/putfile?uploads
 -02-25 10:41:02.223203 7f2c35f8f700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:41:02.223205 7f2c35f8f700 20 SCRIPT_NAME=/putfile
 -02-25 10:41:02.223207 7f2c35f8f700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:41:02.223209 7f2c35f8f700 20 SCRIPT_URL=/putfile
 -02-25 10:41:02.223212 7f2c35f8f700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:41:02.223214 7f2c35f8f700 20 SERVER_ADMIN=root@localhost
 -02-25 10:41:02.223217 7f2c35f8f700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:41:02.223219 7f2c35f8f700 20 SERVER_PORT=80
 -02-25 10:41:02.223221 7f2c35f8f700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:41:02.223223 7f2c35f8f700 20 SERVER_SIGNATURE=
 -02-25 10:41:02.223225 7f2c35f8f700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:41:02.223230 7f2c35f8f700  1 ====== starting new request req=0x7f2c8003e530 =====
 -02-25 10:41:02.223267 7f2c35f8f700  2 req 4:0.000038::POST /putfile::initializing
 -02-25 10:41:02.223275 7f2c35f8f700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:41:02.223297 7f2c35f8f700 10 meta>> HTTP_X_AMZ_CONTENT_SHA256
 -02-25 10:41:02.223306 7f2c35f8f700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:41:02.223311 7f2c35f8f700 10 meta>> HTTP_X_AMZ_META_S3CMD_ATTRS
 -02-25 10:41:02.223319 7f2c35f8f700 10 x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:41:02.223324 7f2c35f8f700 10 x>> x-amz-date:20150225T074102Z
 -02-25 10:41:02.223326 7f2c35f8f700 10 x>> x-amz-meta-s3cmd-attrs:uid:107/gname:qemu/uname:qemu/gid:107/mode:33204/mtime:1420995560/atime:1424770827/md5:7c01dae8617020e2f7634e2137a1e297/ctime:1421080731
 -02-25 10:41:02.223412 7f2c35f8f700 10 s->object=putfile s->bucket=test
 -02-25 10:41:02.223433 7f2c35f8f700  2 req 4:0.000204:s3:POST /putfile::getting op
 -02-25 10:41:02.223463 7f2c35f8f700  2 req 4:0.000233:s3:POST /putfile:init_multipart:authorizing
 -02-25 10:41:02.223474 7f2c35f8f700 10 failed to authorize request
 -02-25 10:41:02.223587 7f2c35f8f700  2 req 4:0.000358:s3:POST /putfile:init_multipart:http status=400
 -02-25 10:41:02.223599 7f2c35f8f700  1 ====== req done req=0x7f2c8003e530 http_status=400 ======
 -02-25 10:41:02.223610 7f2c35f8f700 20 process_request() returned -22
 -02-25 10:41:02.270733 7f2c3a596700 20 enqueued request req=0x7f2c80044e90
 -02-25 10:41:02.270762 7f2c3a596700 20 RGWWQ:
 -02-25 10:41:02.270763 7f2c3a596700 20 req: 0x7f2c80044e90
 -02-25 10:41:02.270767 7f2c3a596700 10 allocated request req=0x7f2c8003ec30
 -02-25 10:41:02.270784 7f2c3558e700 20 dequeued request req=0x7f2c80044e90
 -02-25 10:41:02.270788 7f2c3558e700 20 RGWWQ: empty
 -02-25 10:41:02.270831 7f2c3558e700 20 CONTENT_LENGTH=0
 -02-25 10:41:02.270833 7f2c3558e700 20 CONTENT_TYPE=application/x-iso9660-image
 -02-25 10:41:02.270834 7f2c3558e700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:41:02.270835 7f2c3558e700 20 FCGI_ROLE=RESPONDER
 -02-25 10:41:02.270836 7f2c3558e700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:41:02.270836 7f2c3558e700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:41:02.270837 7f2c3558e700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:fzvNP5EtqQPbZK3bY3BWbnfSNCk=
 -02-25 10:41:02.270838 7f2c3558e700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:41:02.270838 7f2c3558e700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:41:02.270841 7f2c3558e700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:41:02 +0000
 -02-25 10:41:02.270842 7f2c3558e700 20 HTTP_X_AMZ_META_S3CMD_ATTRS=uid:107/gname:qemu/uname:qemu/gid:107/mode:33204/mtime:1420995560/atime:1424770827/md5:7c01dae8617020e2f7634e2137a1e297/ctime:1421080731
 -02-25 10:41:02.270843 7f2c3558e700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:41:02.270844 7f2c3558e700 20 QUERY_STRING=uploads
 -02-25 10:41:02.270845 7f2c3558e700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:41:02.270845 7f2c3558e700 20 REMOTE_PORT=48017
 -02-25 10:41:02.270846 7f2c3558e700 20 REQUEST_METHOD=POST
 -02-25 10:41:02.270846 7f2c3558e700 20 REQUEST_URI=/putfile?uploads
 -02-25 10:41:02.270847 7f2c3558e700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:41:02.270848 7f2c3558e700 20 SCRIPT_NAME=/putfile
 -02-25 10:41:02.270848 7f2c3558e700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:41:02.270849 7f2c3558e700 20 SCRIPT_URL=/putfile
 -02-25 10:41:02.270850 7f2c3558e700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:41:02.270850 7f2c3558e700 20 SERVER_ADMIN=root@localhost
 -02-25 10:41:02.270851 7f2c3558e700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:41:02.270852 7f2c3558e700 20 SERVER_PORT=80
 -02-25 10:41:02.270853 7f2c3558e700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:41:02.270853 7f2c3558e700 20 SERVER_SIGNATURE=
 -02-25 10:41:02.270854 7f2c3558e700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:41:02.270856 7f2c3558e700  1 ====== starting new request req=0x7f2c80044e90 =====
 -02-25 10:41:02.270871 7f2c3558e700  2 req 5:0.000016::POST /putfile::initializing
 -02-25 10:41:02.270876 7f2c3558e700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:41:02.270892 7f2c3558e700 10 meta>> HTTP_X_AMZ_CONTENT_SHA256
 -02-25 10:41:02.270895 7f2c3558e700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:41:02.270897 7f2c3558e700 10 meta>> HTTP_X_AMZ_META_S3CMD_ATTRS
 -02-25 10:41:02.270900 7f2c3558e700 10 x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:41:02.270902 7f2c3558e700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:41:02 +0000
 -02-25 10:41:02.270902 7f2c3558e700 10 x>> x-amz-meta-s3cmd-attrs:uid:107/gname:qemu/uname:qemu/gid:107/mode:33204/mtime:1420995560/atime:1424770827/md5:7c01dae8617020e2f7634e2137a1e297/ctime:1421080731
 -02-25 10:41:02.270919 7f2c3558e700 10 s->object=putfile s->bucket=test
 -02-25 10:41:02.270924 7f2c3558e700  2 req 5:0.000069:s3:POST /putfile::getting op
 -02-25 10:41:02.270928 7f2c3558e700  2 req 5:0.000073:s3:POST /putfile:init_multipart:authorizing
 -02-25 10:41:02.270966 7f2c3558e700 10 get_canon_resource(): dest=/test/putfile?uploads
 -02-25 10:41:02.270968 7f2c3558e700 10 auth_hdr:
       POST
       application/x-iso9660-image
       x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
       x-amz-date:Wed, 25 Feb 2015 07:41:02 +0000
       x-amz-meta-s3cmd-attrs:uid:107/gname:qemu/uname:qemu/gid:107/mode:33204/mtime:1420995560/atime:1424770827/md5:7c01dae8617020e2f7634e2137a1e297/ctime:1421080731
       /test/putfile?uploads
 -02-25 10:41:02.271065 7f2c3558e700 15 calculated digest=fzvNP5EtqQPbZK3bY3BWbnfSNCk=
 -02-25 10:41:02.271068 7f2c3558e700 15 auth_sign=fzvNP5EtqQPbZK3bY3BWbnfSNCk=
 -02-25 10:41:02.271069 7f2c3558e700 15 compare=0
 -02-25 10:41:02.271071 7f2c3558e700  2 req 5:0.000216:s3:POST /putfile:init_multipart:reading permissions
 -02-25 10:41:02.271112 7f2c3558e700 20 get_obj_state: rctx=0x7f2c3558d5d0 obj=.rgw:test state=0x7f2c8c038a58 s->prefetch_data=0
 -02-25 10:41:02.271121 7f2c3558e700 10 cache get: name=.rgw+test : type miss (requested=22, cached=19)
 -02-25 10:41:02.272517 7f2c3558e700 10 cache put: name=.rgw+test
 -02-25 10:41:02.272521 7f2c3558e700 10 moving .rgw+test to cache LRU end
 -02-25 10:41:02.272526 7f2c3558e700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:41:02.272529 7f2c3558e700 20 Read xattr: user.rgw.idtag
 -02-25 10:41:02.272530 7f2c3558e700 20 Read xattr: user.rgw.manifest
 -02-25 10:41:02.272554 7f2c3558e700 10 cache get: name=.rgw+test : hit
 -02-25 10:41:02.272586 7f2c3558e700 20 rgw_get_bucket_info: bucket instance: test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1])
 -02-25 10:41:02.272601 7f2c3558e700 20 reading from .rgw:.bucket.meta.test:default.4303.1
 -02-25 10:41:02.272610 7f2c3558e700 20 get_obj_state: rctx=0x7f2c3558d5d0 obj=.rgw:.bucket.meta.test:default.4303.1 state=0x7f2c8c039fc8 s->prefetch_data=0
 -02-25 10:41:02.272616 7f2c3558e700 10 cache get: name=.rgw+.bucket.meta.test:default.4303.1 : type miss (requested=22, cached=19)
 -02-25 10:41:02.273813 7f2c3558e700 10 cache put: name=.rgw+.bucket.meta.test:default.4303.1
 -02-25 10:41:02.273816 7f2c3558e700 10 moving .rgw+.bucket.meta.test:default.4303.1 to cache LRU end
 -02-25 10:41:02.273819 7f2c3558e700 10 updating xattr: name=user.rgw.acl bl.length()=151
 -02-25 10:41:02.273823 7f2c3558e700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:41:02.273825 7f2c3558e700 20 Read xattr: user.rgw.acl
 -02-25 10:41:02.273826 7f2c3558e700 20 Read xattr: user.rgw.idtag
 -02-25 10:41:02.273827 7f2c3558e700 20 Read xattr: user.rgw.manifest
 -02-25 10:41:02.273830 7f2c3558e700 10 cache get: name=.rgw+.bucket.meta.test:default.4303.1 : hit
 -02-25 10:41:02.273840 7f2c3558e700 10 chain_cache_entry: cache_locator=.rgw+test
 -02-25 10:41:02.273841 7f2c3558e700 10 chain_cache_entry: cache_locator=.rgw+.bucket.meta.test:default.4303.1
 -02-25 10:41:02.273861 7f2c3558e700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:41:02.273872 7f2c3558e700  2 req 5:0.003017:s3:POST /putfile:init_multipart:init op
 -02-25 10:41:02.273876 7f2c3558e700  2 req 5:0.003021:s3:POST /putfile:init_multipart:verifying op mask
 -02-25 10:41:02.273878 7f2c3558e700 20 required_mask= 2 user.op_mask=7
 -02-25 10:41:02.273879 7f2c3558e700  2 req 5:0.003024:s3:POST /putfile:init_multipart:verifying op permissions
 -02-25 10:41:02.273881 7f2c3558e700  5 Searching permissions for uid=functests mask=50
 -02-25 10:41:02.273883 7f2c3558e700  5 Found permission: 15
 -02-25 10:41:02.273884 7f2c3558e700  5 Searching permissions for group=1 mask=50
 -02-25 10:41:02.273885 7f2c3558e700  5 Permissions for group not found
 -02-25 10:41:02.273886 7f2c3558e700  5 Searching permissions for group=2 mask=50
 -02-25 10:41:02.273886 7f2c3558e700  5 Permissions for group not found
 -02-25 10:41:02.273887 7f2c3558e700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:41:02.273888 7f2c3558e700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:41:02.273889 7f2c3558e700  2 req 5:0.003034:s3:POST /putfile:init_multipart:verifying op params
 -02-25 10:41:02.273891 7f2c3558e700  2 req 5:0.003036:s3:POST /putfile:init_multipart:executing
 -02-25 10:41:02.273922 7f2c3558e700 10 x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:41:02.273942 7f2c3558e700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:41:02 +0000
 -02-25 10:41:02.273953 7f2c3558e700 10 x>> x-amz-meta-s3cmd-attrs:uid:107/gname:qemu/uname:qemu/gid:107/mode:33204/mtime:1420995560/atime:1424770827/md5:7c01dae8617020e2f7634e2137a1e297/ctime:1421080731
 -02-25 10:41:10.674114 7f2ca4ffa700  0 WARNING: RGWRados::log_usage(): user name empty (bucket=), skipping
 -02-25 10:41:20.104393 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:41:24.989561 7f2c3558e700  2 req 5:22.718705:s3:POST /putfile:init_multipart:http status=200
 -02-25 10:41:24.989568 7f2c3558e700  1 ====== req done req=0x7f2c80044e90 http_status=200 ======
 -02-25 10:41:25.070453 7f2c3a596700 20 enqueued request req=0x7f2c8003ec30
 -02-25 10:41:25.070468 7f2c3a596700 20 RGWWQ:
 -02-25 10:41:25.070470 7f2c3a596700 20 req: 0x7f2c8003ec30
 -02-25 10:41:25.070473 7f2c3a596700 10 allocated request req=0x7f2c8003f000
 -02-25 10:41:25.070488 7f2c34b8d700 20 dequeued request req=0x7f2c8003ec30
 -02-25 10:41:25.070491 7f2c34b8d700 20 RGWWQ: empty
 -02-25 10:41:25.070551 7f2c34b8d700 20 CONTENT_LENGTH=0
 -02-25 10:41:25.070554 7f2c34b8d700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:41:25.070555 7f2c34b8d700 20 FCGI_ROLE=RESPONDER
 -02-25 10:41:25.070556 7f2c34b8d700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:41:25.070557 7f2c34b8d700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:41:25.070558 7f2c34b8d700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:0uHbZSxaFa0uagvzNCgFxXhh3uk=
 -02-25 10:41:25.070558 7f2c34b8d700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:41:25.070559 7f2c34b8d700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:41:25 +0000
 -02-25 10:41:25.070560 7f2c34b8d700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:41:25.070560 7f2c34b8d700 20 QUERY_STRING=location
 -02-25 10:41:25.070561 7f2c34b8d700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:41:25.070562 7f2c34b8d700 20 REMOTE_PORT=48018
 -02-25 10:41:25.070563 7f2c34b8d700 20 REQUEST_METHOD=GET
 -02-25 10:41:25.070564 7f2c34b8d700 20 REQUEST_URI=/?location
 -02-25 10:41:25.070564 7f2c34b8d700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:41:25.070565 7f2c34b8d700 20 SCRIPT_NAME=/
 -02-25 10:41:25.070566 7f2c34b8d700 20 SCRIPT_URI=http://test.storage.midtest.c2/
 -02-25 10:41:25.070566 7f2c34b8d700 20 SCRIPT_URL=/
 -02-25 10:41:25.070567 7f2c34b8d700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:41:25.070567 7f2c34b8d700 20 SERVER_ADMIN=root@localhost
 -02-25 10:41:25.070568 7f2c34b8d700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:41:25.070568 7f2c34b8d700 20 SERVER_PORT=80
 -02-25 10:41:25.070569 7f2c34b8d700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:41:25.070570 7f2c34b8d700 20 SERVER_SIGNATURE=
 -02-25 10:41:25.070570 7f2c34b8d700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:41:25.070572 7f2c34b8d700  1 ====== starting new request req=0x7f2c8003ec30 =====
 -02-25 10:41:25.070583 7f2c34b8d700  2 req 6:0.000012::GET /::initializing
 -02-25 10:41:25.070588 7f2c34b8d700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:41:25.070599 7f2c34b8d700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:41:25.070617 7f2c34b8d700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:41:25 +0000
 -02-25 10:41:25.070632 7f2c34b8d700 10 s->object=<NULL> s->bucket=test
 -02-25 10:41:25.070637 7f2c34b8d700  2 req 6:0.000066:s3:GET /::getting op
 -02-25 10:41:25.070645 7f2c34b8d700  2 req 6:0.000074:s3:GET /:get_bucket_location:authorizing
 -02-25 10:41:25.070680 7f2c34b8d700 10 get_canon_resource(): dest=/test/?location
 -02-25 10:41:25.070683 7f2c34b8d700 10 auth_hdr:
       GET
       x-amz-date:Wed, 25 Feb 2015 07:41:25 +0000
       /test/?location
 -02-25 10:41:25.070764 7f2c34b8d700 15 calculated digest=0uHbZSxaFa0uagvzNCgFxXhh3uk=
 -02-25 10:41:25.070767 7f2c34b8d700 15 auth_sign=0uHbZSxaFa0uagvzNCgFxXhh3uk=
 -02-25 10:41:25.070768 7f2c34b8d700 15 compare=0
 -02-25 10:41:25.070770 7f2c34b8d700  2 req 6:0.000199:s3:GET /:get_bucket_location:reading permissions
 -02-25 10:41:25.070792 7f2c34b8d700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:41:25.070799 7f2c34b8d700  2 req 6:0.000228:s3:GET /:get_bucket_location:init op
 -02-25 10:41:25.070802 7f2c34b8d700  2 req 6:0.000231:s3:GET /:get_bucket_location:verifying op mask
 -02-25 10:41:25.070804 7f2c34b8d700 20 required_mask= 1 user.op_mask=7
 -02-25 10:41:25.070805 7f2c34b8d700  2 req 6:0.000234:s3:GET /:get_bucket_location:verifying op permissions
 -02-25 10:41:25.070810 7f2c34b8d700  2 req 6:0.000239:s3:GET /:get_bucket_location:verifying op params
 -02-25 10:41:25.070812 7f2c34b8d700  2 req 6:0.000240:s3:GET /:get_bucket_location:executing
 -02-25 10:41:25.070856 7f2c34b8d700  2 req 6:0.000285:s3:GET /:get_bucket_location:http status=200
 -02-25 10:41:25.070860 7f2c34b8d700  1 ====== req done req=0x7f2c8003ec30 http_status=200 ======
 -02-25 10:41:25.247229 7f2c3a596700 20 enqueued request req=0x7f2c8003f000
 -02-25 10:41:25.247256 7f2c3a596700 20 RGWWQ:
 -02-25 10:41:25.247257 7f2c3a596700 20 req: 0x7f2c8003f000
 -02-25 10:41:25.247261 7f2c3a596700 10 allocated request req=0x7f2c8003f3d0
 -02-25 10:41:25.247348 7f2c3418c700 20 dequeued request req=0x7f2c8003f000
 -02-25 10:41:25.247353 7f2c3418c700 20 RGWWQ: empty
 -02-25 10:41:25.247582 7f2c3418c700 20 CONTENT_LENGTH=15728640
 -02-25 10:41:25.247587 7f2c3418c700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:41:25.247587 7f2c3418c700 20 FCGI_ROLE=RESPONDER
 -02-25 10:41:25.247588 7f2c3418c700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:41:25.247589 7f2c3418c700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:41:25.247589 7f2c3418c700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:66eOJ6z0e4BVR4FiURBe4/qzcFQ=
 -02-25 10:41:25.247590 7f2c3418c700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:41:25.247674 7f2c3418c700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:41:25 +0000
 -02-25 10:41:25.247676 7f2c3418c700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:41:25.247676 7f2c3418c700 20 QUERY_STRING=partNumber=1&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:41:25.247677 7f2c3418c700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:41:25.247678 7f2c3418c700 20 REMOTE_PORT=48019
 -02-25 10:41:25.247678 7f2c3418c700 20 REQUEST_METHOD=PUT
 -02-25 10:41:25.247679 7f2c3418c700 20 REQUEST_URI=/putfile?partNumber=1&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:41:25.247679 7f2c3418c700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:41:25.247680 7f2c3418c700 20 SCRIPT_NAME=/putfile
 -02-25 10:41:25.247681 7f2c3418c700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:41:25.247681 7f2c3418c700 20 SCRIPT_URL=/putfile
 -02-25 10:41:25.247682 7f2c3418c700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:41:25.247682 7f2c3418c700 20 SERVER_ADMIN=root@localhost
 -02-25 10:41:25.247683 7f2c3418c700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:41:25.247684 7f2c3418c700 20 SERVER_PORT=80
 -02-25 10:41:25.247684 7f2c3418c700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:41:25.247685 7f2c3418c700 20 SERVER_SIGNATURE=
 -02-25 10:41:25.247685 7f2c3418c700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:41:25.247687 7f2c3418c700  1 ====== starting new request req=0x7f2c8003f000 =====
 -02-25 10:41:25.247709 7f2c3418c700  2 req 7:0.000023::PUT /putfile::initializing
 -02-25 10:41:25.247714 7f2c3418c700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:41:25.247727 7f2c3418c700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:41:25.247733 7f2c3418c700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:41:25 +0000
 -02-25 10:41:25.247751 7f2c3418c700 10 s->object=putfile s->bucket=test
 -02-25 10:41:25.247755 7f2c3418c700  2 req 7:0.000069:s3:PUT /putfile::getting op
 -02-25 10:41:25.247765 7f2c3418c700  2 req 7:0.000078:s3:PUT /putfile:put_obj:authorizing
 -02-25 10:41:25.247803 7f2c3418c700 10 get_canon_resource(): dest=/test/putfile?partNumber=1&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:41:25.247806 7f2c3418c700 10 auth_hdr:
       PUT
       x-amz-date:Wed, 25 Feb 2015 07:41:25 +0000
       /test/putfile?partNumber=1&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:41:25.247909 7f2c3418c700 15 calculated digest=66eOJ6z0e4BVR4FiURBe4/qzcFQ=
 -02-25 10:41:25.247912 7f2c3418c700 15 auth_sign=66eOJ6z0e4BVR4FiURBe4/qzcFQ=
 -02-25 10:41:25.247913 7f2c3418c700 15 compare=0
 -02-25 10:41:25.247916 7f2c3418c700  2 req 7:0.000230:s3:PUT /putfile:put_obj:reading permissions
 -02-25 10:41:25.247941 7f2c3418c700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:41:25.247949 7f2c3418c700  2 req 7:0.000262:s3:PUT /putfile:put_obj:init op
 -02-25 10:41:25.247952 7f2c3418c700  2 req 7:0.000266:s3:PUT /putfile:put_obj:verifying op mask
 -02-25 10:41:25.247954 7f2c3418c700 20 required_mask= 2 user.op_mask=7
 -02-25 10:41:25.247955 7f2c3418c700  2 req 7:0.000269:s3:PUT /putfile:put_obj:verifying op permissions
 -02-25 10:41:25.247957 7f2c3418c700  5 Searching permissions for uid=functests mask=50
 -02-25 10:41:25.247958 7f2c3418c700  5 Found permission: 15
 -02-25 10:41:25.247959 7f2c3418c700  5 Searching permissions for group=1 mask=50
 -02-25 10:41:25.247960 7f2c3418c700  5 Permissions for group not found
 -02-25 10:41:25.247961 7f2c3418c700  5 Searching permissions for group=2 mask=50
 -02-25 10:41:25.247962 7f2c3418c700  5 Permissions for group not found
 -02-25 10:41:25.247963 7f2c3418c700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:41:25.247964 7f2c3418c700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:41:25.247965 7f2c3418c700  2 req 7:0.000279:s3:PUT /putfile:put_obj:verifying op params
 -02-25 10:41:25.247968 7f2c3418c700  2 req 7:0.000281:s3:PUT /putfile:put_obj:executing
 -02-25 10:41:32.633783 7f2c3418c700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:41:25 +0000
 -02-25 10:41:33.898324 7f2c3418c700 20 get_obj_state: rctx=0x7f2c3418b5d0 obj=test:_multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.1 state=0x7f2c9c045498 s->prefetch_data=0
 -02-25 10:41:33.899712 7f2c3418c700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:41:33.899716 7f2c3418c700 20 prepare_atomic_for_write_impl: state is not atomic. state=0x7f2c9c045498
 -02-25 10:41:34.251463 7f2c7bfff700  2 garbage collection: start
 -02-25 10:41:37.330578 7f2c3418c700 15 omap_set bucket=test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1]) oid=default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.meta key=part.00000001
 -02-25 10:41:37.369117 7f2c3418c700  2 req 7:12.121430:s3:PUT /putfile:put_obj:http status=200
 -02-25 10:41:37.369125 7f2c3418c700  1 ====== req done req=0x7f2c8003f000 http_status=200 ======
 -02-25 10:41:37.555654 7f2c3a596700 20 enqueued request req=0x7f2c8003f3d0
 -02-25 10:41:37.555688 7f2c3a596700 20 RGWWQ:
 -02-25 10:41:37.555691 7f2c3a596700 20 req: 0x7f2c8003f3d0
 -02-25 10:41:37.555877 7f2c3a596700 10 allocated request req=0x7f2c8003f7a0
 -02-25 10:41:37.555948 7f2c37d92700 20 dequeued request req=0x7f2c8003f3d0
 -02-25 10:41:37.555952 7f2c37d92700 20 RGWWQ: empty
 -02-25 10:41:37.555992 7f2c37d92700 20 CONTENT_LENGTH=15728640
 -02-25 10:41:37.555995 7f2c37d92700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:41:37.555998 7f2c37d92700 20 FCGI_ROLE=RESPONDER
 -02-25 10:41:37.556000 7f2c37d92700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:41:37.556002 7f2c37d92700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:41:37.556004 7f2c37d92700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:f5RjviQFcrBImpsziEDqAuT7ZC4=
 -02-25 10:41:37.556006 7f2c37d92700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:41:37.556008 7f2c37d92700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:41:37 +0000
 -02-25 10:41:37.556010 7f2c37d92700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:41:37.556012 7f2c37d92700 20 QUERY_STRING=partNumber=2&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:41:37.556014 7f2c37d92700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:41:37.556015 7f2c37d92700 20 REMOTE_PORT=48020
 -02-25 10:41:37.556017 7f2c37d92700 20 REQUEST_METHOD=PUT
 -02-25 10:41:37.556020 7f2c37d92700 20 REQUEST_URI=/putfile?partNumber=2&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:41:37.556022 7f2c37d92700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:41:37.556205 7f2c37d92700 20 SCRIPT_NAME=/putfile
 -02-25 10:41:37.556210 7f2c37d92700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:41:37.556211 7f2c37d92700 20 SCRIPT_URL=/putfile
 -02-25 10:41:37.556212 7f2c37d92700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:41:37.556213 7f2c37d92700 20 SERVER_ADMIN=root@localhost
 -02-25 10:41:37.556216 7f2c37d92700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:41:37.556217 7f2c37d92700 20 SERVER_PORT=80
 -02-25 10:41:37.556219 7f2c37d92700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:41:37.556220 7f2c37d92700 20 SERVER_SIGNATURE=
 -02-25 10:41:37.556222 7f2c37d92700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:41:37.556225 7f2c37d92700  1 ====== starting new request req=0x7f2c8003f3d0 =====
 -02-25 10:41:37.556312 7f2c37d92700  2 req 8:0.000087::PUT /putfile::initializing
 -02-25 10:41:37.556319 7f2c37d92700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:41:37.556333 7f2c37d92700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:41:37.556338 7f2c37d92700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:41:37 +0000
 -02-25 10:41:37.556357 7f2c37d92700 10 s->object=putfile s->bucket=test
 -02-25 10:41:37.556362 7f2c37d92700  2 req 8:0.000138:s3:PUT /putfile::getting op
 -02-25 10:41:37.556366 7f2c37d92700  2 req 8:0.000141:s3:PUT /putfile:put_obj:authorizing
 -02-25 10:41:37.556400 7f2c37d92700 10 get_canon_resource(): dest=/test/putfile?partNumber=2&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:41:37.556403 7f2c37d92700 10 auth_hdr:
       PUT
       x-amz-date:Wed, 25 Feb 2015 07:41:37 +0000
       /test/putfile?partNumber=2&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:41:37.556583 7f2c37d92700 15 calculated digest=f5RjviQFcrBImpsziEDqAuT7ZC4=
 -02-25 10:41:37.556588 7f2c37d92700 15 auth_sign=f5RjviQFcrBImpsziEDqAuT7ZC4=
 -02-25 10:41:37.556590 7f2c37d92700 15 compare=0
 -02-25 10:41:37.556595 7f2c37d92700  2 req 8:0.000370:s3:PUT /putfile:put_obj:reading permissions
 -02-25 10:41:37.556694 7f2c37d92700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:41:37.556710 7f2c37d92700  2 req 8:0.000485:s3:PUT /putfile:put_obj:init op
 -02-25 10:41:37.556808 7f2c37d92700  2 req 8:0.000583:s3:PUT /putfile:put_obj:verifying op mask
 -02-25 10:41:37.556812 7f2c37d92700 20 required_mask= 2 user.op_mask=7
 -02-25 10:41:37.556814 7f2c37d92700  2 req 8:0.000590:s3:PUT /putfile:put_obj:verifying op permissions
 -02-25 10:41:37.556816 7f2c37d92700  5 Searching permissions for uid=functests mask=50
 -02-25 10:41:37.556818 7f2c37d92700  5 Found permission: 15
 -02-25 10:41:37.556819 7f2c37d92700  5 Searching permissions for group=1 mask=50
 -02-25 10:41:37.556820 7f2c37d92700  5 Permissions for group not found
 -02-25 10:41:37.556821 7f2c37d92700  5 Searching permissions for group=2 mask=50
 -02-25 10:41:37.556822 7f2c37d92700  5 Permissions for group not found
 -02-25 10:41:37.556822 7f2c37d92700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:41:37.556824 7f2c37d92700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:41:37.556826 7f2c37d92700  2 req 8:0.000601:s3:PUT /putfile:put_obj:verifying op params
 -02-25 10:41:37.556828 7f2c37d92700  2 req 8:0.000604:s3:PUT /putfile:put_obj:executing
 -02-25 10:41:40.465444 7f2c37d92700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:41:37 +0000
 -02-25 10:41:42.104511 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:42:04.104663 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:42:13.681412 7f2c3a596700 20 enqueued request req=0x7f2c8003f7a0
 -02-25 10:42:13.681439 7f2c3a596700 20 RGWWQ:
 -02-25 10:42:13.681441 7f2c3a596700 20 req: 0x7f2c8003f7a0
 -02-25 10:42:13.681445 7f2c3a596700 10 allocated request req=0x7f2c80049a00
 -02-25 10:42:13.681837 7f2c35f8f700 20 dequeued request req=0x7f2c8003f7a0
 -02-25 10:42:13.681844 7f2c35f8f700 20 RGWWQ: empty
 -02-25 10:42:13.681897 7f2c35f8f700 20 CONTENT_LENGTH=15728640
 -02-25 10:42:13.681900 7f2c35f8f700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:42:13.681901 7f2c35f8f700 20 FCGI_ROLE=RESPONDER
 -02-25 10:42:13.681902 7f2c35f8f700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:42:13.681902 7f2c35f8f700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:42:13.681903 7f2c35f8f700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:ecCR4MOeLAICIdvop/QkRNzr3lI=
 -02-25 10:42:13.681904 7f2c35f8f700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:42:13.681905 7f2c35f8f700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:42:13 +0000
 -02-25 10:42:13.681906 7f2c35f8f700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:42:13.681907 7f2c35f8f700 20 QUERY_STRING=partNumber=2&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:13.681907 7f2c35f8f700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:42:13.681908 7f2c35f8f700 20 REMOTE_PORT=48021
 -02-25 10:42:13.681909 7f2c35f8f700 20 REQUEST_METHOD=PUT
 -02-25 10:42:13.681910 7f2c35f8f700 20 REQUEST_URI=/putfile?partNumber=2&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:13.681910 7f2c35f8f700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:42:13.681911 7f2c35f8f700 20 SCRIPT_NAME=/putfile
 -02-25 10:42:13.681912 7f2c35f8f700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:42:13.681913 7f2c35f8f700 20 SCRIPT_URL=/putfile
 -02-25 10:42:13.681913 7f2c35f8f700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:42:13.681914 7f2c35f8f700 20 SERVER_ADMIN=root@localhost
 -02-25 10:42:13.681915 7f2c35f8f700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:42:13.681915 7f2c35f8f700 20 SERVER_PORT=80
 -02-25 10:42:13.681916 7f2c35f8f700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:42:13.681917 7f2c35f8f700 20 SERVER_SIGNATURE=
 -02-25 10:42:13.681917 7f2c35f8f700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:42:13.681919 7f2c35f8f700  1 ====== starting new request req=0x7f2c8003f7a0 =====
 -02-25 10:42:13.681954 7f2c35f8f700  2 req 9:0.000036::PUT /putfile::initializing
 -02-25 10:42:13.681960 7f2c35f8f700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:42:13.681978 7f2c35f8f700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:42:13.682071 7f2c35f8f700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:13 +0000
 -02-25 10:42:13.682095 7f2c35f8f700 10 s->object=putfile s->bucket=test
 -02-25 10:42:13.682100 7f2c35f8f700  2 req 9:0.000182:s3:PUT /putfile::getting op
 -02-25 10:42:13.682105 7f2c35f8f700  2 req 9:0.000186:s3:PUT /putfile:put_obj:authorizing
 -02-25 10:42:13.682143 7f2c35f8f700 10 get_canon_resource(): dest=/test/putfile?partNumber=2&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:13.682156 7f2c35f8f700 10 auth_hdr:
       PUT
       x-amz-date:Wed, 25 Feb 2015 07:42:13 +0000
       /test/putfile?partNumber=2&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:13.682579 7f2c35f8f700 15 calculated digest=ecCR4MOeLAICIdvop/QkRNzr3lI=
 -02-25 10:42:13.682585 7f2c35f8f700 15 auth_sign=ecCR4MOeLAICIdvop/QkRNzr3lI=
 -02-25 10:42:13.682588 7f2c35f8f700 15 compare=0
 -02-25 10:42:13.682593 7f2c35f8f700  2 req 9:0.000674:s3:PUT /putfile:put_obj:reading permissions
 -02-25 10:42:13.682636 7f2c35f8f700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:42:13.682648 7f2c35f8f700  2 req 9:0.000730:s3:PUT /putfile:put_obj:init op
 -02-25 10:42:13.682654 7f2c35f8f700  2 req 9:0.000736:s3:PUT /putfile:put_obj:verifying op mask
 -02-25 10:42:13.682656 7f2c35f8f700 20 required_mask= 2 user.op_mask=7
 -02-25 10:42:13.682657 7f2c35f8f700  2 req 9:0.000739:s3:PUT /putfile:put_obj:verifying op permissions
 -02-25 10:42:13.682659 7f2c35f8f700  5 Searching permissions for uid=functests mask=50
 -02-25 10:42:13.682660 7f2c35f8f700  5 Found permission: 15
 -02-25 10:42:13.682661 7f2c35f8f700  5 Searching permissions for group=1 mask=50
 -02-25 10:42:13.682662 7f2c35f8f700  5 Permissions for group not found
 -02-25 10:42:13.682663 7f2c35f8f700  5 Searching permissions for group=2 mask=50
 -02-25 10:42:13.682664 7f2c35f8f700  5 Permissions for group not found
 -02-25 10:42:13.682664 7f2c35f8f700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:42:13.682665 7f2c35f8f700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:42:13.682667 7f2c35f8f700  2 req 9:0.000749:s3:PUT /putfile:put_obj:verifying op params
 -02-25 10:42:13.682669 7f2c35f8f700  2 req 9:0.000751:s3:PUT /putfile:put_obj:executing
 -02-25 10:42:19.477031 7f2c35f8f700  5 NOTICE: processor->throttle_data() returned -EEXIST, need to restart write
 -02-25 10:42:19.477070 7f2c35f8f700 20 get_obj_state: rctx=0x7f2c35f8e5d0 obj=test:_multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.2 state=0x7f2c90135ae8 s->prefetch_data=0
 -02-25 10:42:19.477915 7f2c35f8f700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:42:19.477918 7f2c35f8f700 20 prepare_atomic_for_write_impl: state is not atomic. state=0x7f2c90135ae8
 -02-25 10:42:19.997205 7f2c37d92700 20 get_obj_state: rctx=0x7f2c37d915d0 obj=test:_multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.2 state=0x7f2c7c4507f8 s->prefetch_data=0
 -02-25 10:42:20.036904 7f2c37d92700 20 prepare_atomic_for_write_impl: state is not atomic. state=0x7f2c7c4507f8
 -02-25 10:42:20.280653 7f2c37d92700 15 omap_set bucket=test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1]) oid=default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.meta key=part.00000002
 -02-25 10:42:20.351157 7f2c37d92700  2 req 8:42.794932:s3:PUT /putfile:put_obj:http status=200
 -02-25 10:42:20.351168 7f2c37d92700  1 ====== req done req=0x7f2c8003f3d0 http_status=200 ======
 -02-25 10:42:21.459705 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:42:21.676132 7f2c35f8f700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:13 +0000
 -02-25 10:42:21.690459 7f2c35f8f700 20 get_obj_state: rctx=0x7f2c35f8e5d0 obj=test:_multipart_putfile.edb9KfijsVddlUKMZRm8A-hjtm-XkhM.2 state=0x7f2c900cdab8 s->prefetch_data=0
 -02-25 10:42:21.691262 7f2c35f8f700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:42:21.691267 7f2c35f8f700 20 prepare_atomic_for_write_impl: state is not atomic. state=0x7f2c900cdab8
 -02-25 10:42:21.701843 7f2c35f8f700 15 omap_set bucket=test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1]) oid=default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.meta key=part.00000002
 -02-25 10:42:21.709364 7f2c35f8f700  2 req 9:8.027445:s3:PUT /putfile:put_obj:http status=200
 -02-25 10:42:21.709370 7f2c35f8f700  1 ====== req done req=0x7f2c8003f7a0 http_status=200 ======
 -02-25 10:42:21.888382 7f2c3a596700 20 enqueued request req=0x7f2c80049a00
 -02-25 10:42:21.888416 7f2c3a596700 20 RGWWQ:
 -02-25 10:42:21.888418 7f2c3a596700 20 req: 0x7f2c80049a00
 -02-25 10:42:21.888422 7f2c3a596700 10 allocated request req=0x7f2c80037ec0
 -02-25 10:42:21.888442 7f2c37d92700 20 dequeued request req=0x7f2c80049a00
 -02-25 10:42:21.888447 7f2c37d92700 20 RGWWQ: empty
 -02-25 10:42:21.888635 7f2c37d92700 20 CONTENT_LENGTH=15728640
 -02-25 10:42:21.888639 7f2c37d92700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:42:21.888642 7f2c37d92700 20 FCGI_ROLE=RESPONDER
 -02-25 10:42:21.888644 7f2c37d92700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:42:21.888646 7f2c37d92700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:42:21.888648 7f2c37d92700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:scLxG4TDTd7EYLvJus9fTLC5yYk=
 -02-25 10:42:21.888650 7f2c37d92700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:42:21.888652 7f2c37d92700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:42:21 +0000
 -02-25 10:42:21.888654 7f2c37d92700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:42:21.888657 7f2c37d92700 20 QUERY_STRING=partNumber=3&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:21.888659 7f2c37d92700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:42:21.888661 7f2c37d92700 20 REMOTE_PORT=48022
 -02-25 10:42:21.888663 7f2c37d92700 20 REQUEST_METHOD=PUT
 -02-25 10:42:21.888666 7f2c37d92700 20 REQUEST_URI=/putfile?partNumber=3&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:21.888667 7f2c37d92700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:42:21.888668 7f2c37d92700 20 SCRIPT_NAME=/putfile
 -02-25 10:42:21.888669 7f2c37d92700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:42:21.888670 7f2c37d92700 20 SCRIPT_URL=/putfile
 -02-25 10:42:21.888671 7f2c37d92700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:42:21.888672 7f2c37d92700 20 SERVER_ADMIN=root@localhost
 -02-25 10:42:21.888673 7f2c37d92700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:42:21.888674 7f2c37d92700 20 SERVER_PORT=80
 -02-25 10:42:21.888674 7f2c37d92700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:42:21.888675 7f2c37d92700 20 SERVER_SIGNATURE=
 -02-25 10:42:21.888676 7f2c37d92700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:42:21.888679 7f2c37d92700  1 ====== starting new request req=0x7f2c80049a00 =====
 -02-25 10:42:21.888697 7f2c37d92700  2 req 10:0.000020::PUT /putfile::initializing
 -02-25 10:42:21.888702 7f2c37d92700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:42:21.888717 7f2c37d92700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:42:21.888724 7f2c37d92700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:21 +0000
 -02-25 10:42:21.888744 7f2c37d92700 10 s->object=putfile s->bucket=test
 -02-25 10:42:21.888750 7f2c37d92700  2 req 10:0.000072:s3:PUT /putfile::getting op
 -02-25 10:42:21.888753 7f2c37d92700  2 req 10:0.000076:s3:PUT /putfile:put_obj:authorizing
 -02-25 10:42:21.888792 7f2c37d92700 10 get_canon_resource(): dest=/test/putfile?partNumber=3&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:21.888796 7f2c37d92700 10 auth_hdr:
       PUT
       x-amz-date:Wed, 25 Feb 2015 07:42:21 +0000
       /test/putfile?partNumber=3&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:21.888874 7f2c37d92700 15 calculated digest=scLxG4TDTd7EYLvJus9fTLC5yYk=
 -02-25 10:42:21.888876 7f2c37d92700 15 auth_sign=scLxG4TDTd7EYLvJus9fTLC5yYk=
 -02-25 10:42:21.888877 7f2c37d92700 15 compare=0
 -02-25 10:42:21.888879 7f2c37d92700  2 req 10:0.000202:s3:PUT /putfile:put_obj:reading permissions
 -02-25 10:42:21.888907 7f2c37d92700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:42:21.888920 7f2c37d92700  2 req 10:0.000242:s3:PUT /putfile:put_obj:init op
 -02-25 10:42:21.888926 7f2c37d92700  2 req 10:0.000248:s3:PUT /putfile:put_obj:verifying op mask
 -02-25 10:42:21.888929 7f2c37d92700 20 required_mask= 2 user.op_mask=7
 -02-25 10:42:21.888932 7f2c37d92700  2 req 10:0.000255:s3:PUT /putfile:put_obj:verifying op permissions
 -02-25 10:42:21.888936 7f2c37d92700  5 Searching permissions for uid=functests mask=50
 -02-25 10:42:21.888938 7f2c37d92700  5 Found permission: 15
 -02-25 10:42:21.888940 7f2c37d92700  5 Searching permissions for group=1 mask=50
 -02-25 10:42:21.888941 7f2c37d92700  5 Permissions for group not found
 -02-25 10:42:21.888942 7f2c37d92700  5 Searching permissions for group=2 mask=50
 -02-25 10:42:21.888942 7f2c37d92700  5 Permissions for group not found
 -02-25 10:42:21.888944 7f2c37d92700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:42:21.888945 7f2c37d92700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:42:21.888947 7f2c37d92700  2 req 10:0.000269:s3:PUT /putfile:put_obj:verifying op params
 -02-25 10:42:21.888949 7f2c37d92700  2 req 10:0.000272:s3:PUT /putfile:put_obj:executing
 -02-25 10:42:24.116352 7f2c37d92700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:21 +0000
 -02-25 10:42:26.104815 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:42:27.068996 7f2c37d92700 20 get_obj_state: rctx=0x7f2c37d915d0 obj=test:_multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.3 state=0x7f2c7c44e588 s->prefetch_data=0
 -02-25 10:42:27.071461 7f2c37d92700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:42:27.071465 7f2c37d92700 20 prepare_atomic_for_write_impl: state is not atomic. state=0x7f2c7c44e588
 -02-25 10:42:27.092348 7f2c37d92700 15 omap_set bucket=test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1]) oid=default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.meta key=part.00000003
 -02-25 10:42:27.176913 7f2c37d92700  2 req 10:5.288236:s3:PUT /putfile:put_obj:http status=200
 -02-25 10:42:27.176922 7f2c37d92700  1 ====== req done req=0x7f2c80049a00 http_status=200 ======
 -02-25 10:42:27.358038 7f2c3a596700 20 enqueued request req=0x7f2c80037ec0
 -02-25 10:42:27.358171 7f2c3a596700 20 RGWWQ:
 -02-25 10:42:27.358173 7f2c3a596700 20 req: 0x7f2c80037ec0
 -02-25 10:42:27.358177 7f2c3a596700 10 allocated request req=0x7f2c80049630
 -02-25 10:42:27.358195 7f2c3418c700 20 dequeued request req=0x7f2c80037ec0
 -02-25 10:42:27.358199 7f2c3418c700 20 RGWWQ: empty
 -02-25 10:42:27.358231 7f2c3418c700 20 CONTENT_LENGTH=15728640
 -02-25 10:42:27.358233 7f2c3418c700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:42:27.358234 7f2c3418c700 20 FCGI_ROLE=RESPONDER
 -02-25 10:42:27.358235 7f2c3418c700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:42:27.358236 7f2c3418c700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:42:27.358237 7f2c3418c700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:LDTt/7tD+35sTcjueS/rS3Jb2Ss=
 -02-25 10:42:27.358238 7f2c3418c700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:42:27.358239 7f2c3418c700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:42:27 +0000
 -02-25 10:42:27.358240 7f2c3418c700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:42:27.358241 7f2c3418c700 20 QUERY_STRING=partNumber=4&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:27.358242 7f2c3418c700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:42:27.358242 7f2c3418c700 20 REMOTE_PORT=48023
 -02-25 10:42:27.358243 7f2c3418c700 20 REQUEST_METHOD=PUT
 -02-25 10:42:27.358244 7f2c3418c700 20 REQUEST_URI=/putfile?partNumber=4&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:27.358245 7f2c3418c700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:42:27.358246 7f2c3418c700 20 SCRIPT_NAME=/putfile
 -02-25 10:42:27.358247 7f2c3418c700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:42:27.358247 7f2c3418c700 20 SCRIPT_URL=/putfile
 -02-25 10:42:27.358248 7f2c3418c700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:42:27.358249 7f2c3418c700 20 SERVER_ADMIN=root@localhost
 -02-25 10:42:27.358250 7f2c3418c700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:42:27.358251 7f2c3418c700 20 SERVER_PORT=80
 -02-25 10:42:27.358252 7f2c3418c700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:42:27.358253 7f2c3418c700 20 SERVER_SIGNATURE=
 -02-25 10:42:27.358254 7f2c3418c700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:42:27.358256 7f2c3418c700  1 ====== starting new request req=0x7f2c80037ec0 =====
 -02-25 10:42:27.358272 7f2c3418c700  2 req 11:0.000017::PUT /putfile::initializing
 -02-25 10:42:27.358277 7f2c3418c700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:42:27.358289 7f2c3418c700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:42:27.358295 7f2c3418c700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:27 +0000
 -02-25 10:42:27.358322 7f2c3418c700 10 s->object=putfile s->bucket=test
 -02-25 10:42:27.358327 7f2c3418c700  2 req 11:0.000072:s3:PUT /putfile::getting op
 -02-25 10:42:27.358331 7f2c3418c700  2 req 11:0.000076:s3:PUT /putfile:put_obj:authorizing
 -02-25 10:42:27.358368 7f2c3418c700 10 get_canon_resource(): dest=/test/putfile?partNumber=4&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:27.358372 7f2c3418c700 10 auth_hdr:
       PUT
       x-amz-date:Wed, 25 Feb 2015 07:42:27 +0000
       /test/putfile?partNumber=4&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:27.358624 7f2c3418c700 15 calculated digest=LDTt/7tD+35sTcjueS/rS3Jb2Ss=
 -02-25 10:42:27.358630 7f2c3418c700 15 auth_sign=LDTt/7tD+35sTcjueS/rS3Jb2Ss=
 -02-25 10:42:27.358631 7f2c3418c700 15 compare=0
 -02-25 10:42:27.358634 7f2c3418c700  2 req 11:0.000379:s3:PUT /putfile:put_obj:reading permissions
 -02-25 10:42:27.358658 7f2c3418c700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:42:27.358665 7f2c3418c700  2 req 11:0.000410:s3:PUT /putfile:put_obj:init op
 -02-25 10:42:27.358668 7f2c3418c700  2 req 11:0.000413:s3:PUT /putfile:put_obj:verifying op mask
 -02-25 10:42:27.358670 7f2c3418c700 20 required_mask= 2 user.op_mask=7
 -02-25 10:42:27.358671 7f2c3418c700  2 req 11:0.000416:s3:PUT /putfile:put_obj:verifying op permissions
 -02-25 10:42:27.358673 7f2c3418c700  5 Searching permissions for uid=functests mask=50
 -02-25 10:42:27.358675 7f2c3418c700  5 Found permission: 15
 -02-25 10:42:27.358676 7f2c3418c700  5 Searching permissions for group=1 mask=50
 -02-25 10:42:27.358677 7f2c3418c700  5 Permissions for group not found
 -02-25 10:42:27.358678 7f2c3418c700  5 Searching permissions for group=2 mask=50
 -02-25 10:42:27.358678 7f2c3418c700  5 Permissions for group not found
 -02-25 10:42:27.358679 7f2c3418c700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:42:27.358680 7f2c3418c700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:42:27.358682 7f2c3418c700  2 req 11:0.000427:s3:PUT /putfile:put_obj:verifying op params
 -02-25 10:42:27.358684 7f2c3418c700  2 req 11:0.000429:s3:PUT /putfile:put_obj:executing
 -02-25 10:42:29.312171 7f2c3418c700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:27 +0000
 -02-25 10:42:29.330836 7f2c3418c700 20 get_obj_state: rctx=0x7f2c3418b5d0 obj=test:_multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.4 state=0x7f2c9c04b678 s->prefetch_data=0
 -02-25 10:42:29.331751 7f2c3418c700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:42:29.331755 7f2c3418c700 20 prepare_atomic_for_write_impl: state is not atomic. state=0x7f2c9c04b678
 -02-25 10:42:29.341383 7f2c3418c700 15 omap_set bucket=test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1]) oid=default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.meta key=part.00000004
 -02-25 10:42:29.348928 7f2c3418c700  2 req 11:1.990673:s3:PUT /putfile:put_obj:http status=200
 -02-25 10:42:29.348934 7f2c3418c700  1 ====== req done req=0x7f2c80037ec0 http_status=200 ======
 -02-25 10:42:29.467837 7f2c3a596700 20 enqueued request req=0x7f2c80049630
 -02-25 10:42:29.467863 7f2c3a596700 20 RGWWQ:
 -02-25 10:42:29.467864 7f2c3a596700 20 req: 0x7f2c80049630
 -02-25 10:42:29.467868 7f2c3a596700 10 allocated request req=0x7f2c80040ae0
 -02-25 10:42:29.467884 7f2c36990700 20 dequeued request req=0x7f2c80049630
 -02-25 10:42:29.467888 7f2c36990700 20 RGWWQ: empty
 -02-25 10:42:29.468077 7f2c36990700 20 CONTENT_LENGTH=7051264
 -02-25 10:42:29.468081 7f2c36990700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:42:29.468081 7f2c36990700 20 FCGI_ROLE=RESPONDER
 -02-25 10:42:29.468082 7f2c36990700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:42:29.468082 7f2c36990700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:42:29.468083 7f2c36990700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:I3UkZdybv3CD9xANFiipxNBxU4I=
 -02-25 10:42:29.468084 7f2c36990700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:42:29.468085 7f2c36990700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:42:29 +0000
 -02-25 10:42:29.468086 7f2c36990700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:42:29.468086 7f2c36990700 20 QUERY_STRING=partNumber=5&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:29.468087 7f2c36990700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:42:29.468087 7f2c36990700 20 REMOTE_PORT=48024
 -02-25 10:42:29.468088 7f2c36990700 20 REQUEST_METHOD=PUT
 -02-25 10:42:29.468089 7f2c36990700 20 REQUEST_URI=/putfile?partNumber=5&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:29.468090 7f2c36990700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:42:29.468091 7f2c36990700 20 SCRIPT_NAME=/putfile
 -02-25 10:42:29.468091 7f2c36990700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:42:29.468092 7f2c36990700 20 SCRIPT_URL=/putfile
 -02-25 10:42:29.468093 7f2c36990700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:42:29.468093 7f2c36990700 20 SERVER_ADMIN=root@localhost
 -02-25 10:42:29.468094 7f2c36990700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:42:29.468094 7f2c36990700 20 SERVER_PORT=80
 -02-25 10:42:29.468095 7f2c36990700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:42:29.468095 7f2c36990700 20 SERVER_SIGNATURE=
 -02-25 10:42:29.468096 7f2c36990700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:42:29.468097 7f2c36990700  1 ====== starting new request req=0x7f2c80049630 =====
 -02-25 10:42:29.468113 7f2c36990700  2 req 12:0.000016::PUT /putfile::initializing
 -02-25 10:42:29.468229 7f2c36990700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:42:29.468246 7f2c36990700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:42:29.468370 7f2c36990700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:29 +0000
 -02-25 10:42:29.468451 7f2c36990700 10 s->object=putfile s->bucket=test
 -02-25 10:42:29.468459 7f2c36990700  2 req 12:0.000361:s3:PUT /putfile::getting op
 -02-25 10:42:29.468463 7f2c36990700  2 req 12:0.000366:s3:PUT /putfile:put_obj:authorizing
 -02-25 10:42:29.468502 7f2c36990700 10 get_canon_resource(): dest=/test/putfile?partNumber=5&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:29.468505 7f2c36990700 10 auth_hdr:
       PUT
       x-amz-date:Wed, 25 Feb 2015 07:42:29 +0000
       /test/putfile?partNumber=5&uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:29.468594 7f2c36990700 15 calculated digest=I3UkZdybv3CD9xANFiipxNBxU4I=
 -02-25 10:42:29.468597 7f2c36990700 15 auth_sign=I3UkZdybv3CD9xANFiipxNBxU4I=
 -02-25 10:42:29.468598 7f2c36990700 15 compare=0
 -02-25 10:42:29.468601 7f2c36990700  2 req 12:0.000503:s3:PUT /putfile:put_obj:reading permissions
 -02-25 10:42:29.468623 7f2c36990700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:42:29.468631 7f2c36990700  2 req 12:0.000534:s3:PUT /putfile:put_obj:init op
 -02-25 10:42:29.468634 7f2c36990700  2 req 12:0.000537:s3:PUT /putfile:put_obj:verifying op mask
 -02-25 10:42:29.468636 7f2c36990700 20 required_mask= 2 user.op_mask=7
 -02-25 10:42:29.468637 7f2c36990700  2 req 12:0.000540:s3:PUT /putfile:put_obj:verifying op permissions
 -02-25 10:42:29.468639 7f2c36990700  5 Searching permissions for uid=functests mask=50
 -02-25 10:42:29.468640 7f2c36990700  5 Found permission: 15
 -02-25 10:42:29.468641 7f2c36990700  5 Searching permissions for group=1 mask=50
 -02-25 10:42:29.468642 7f2c36990700  5 Permissions for group not found
 -02-25 10:42:29.468643 7f2c36990700  5 Searching permissions for group=2 mask=50
 -02-25 10:42:29.468644 7f2c36990700  5 Permissions for group not found
 -02-25 10:42:29.468644 7f2c36990700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:42:29.468645 7f2c36990700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:42:29.468647 7f2c36990700  2 req 12:0.000550:s3:PUT /putfile:put_obj:verifying op params
 -02-25 10:42:29.468649 7f2c36990700  2 req 12:0.000552:s3:PUT /putfile:put_obj:executing
 -02-25 10:42:30.584438 7f2c36990700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:29 +0000
 -02-25 10:42:31.997792 7f2c36990700 20 get_obj_state: rctx=0x7f2c3698f5d0 obj=test:_multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.5 state=0x7f2c84206848 s->prefetch_data=0
 -02-25 10:42:31.998533 7f2c36990700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:42:31.998554 7f2c36990700 20 prepare_atomic_for_write_impl: state is not atomic. state=0x7f2c84206848
 -02-25 10:42:32.009464 7f2c36990700 15 omap_set bucket=test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1]) oid=default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.meta key=part.00000005
 -02-25 10:42:32.024185 7f2c36990700  2 req 12:2.556088:s3:PUT /putfile:put_obj:http status=200
 -02-25 10:42:32.024194 7f2c36990700  1 ====== req done req=0x7f2c80049630 http_status=200 ======
 -02-25 10:42:32.067236 7f2c3a596700 20 enqueued request req=0x7f2c80040ae0
 -02-25 10:42:32.067254 7f2c3a596700 20 RGWWQ:
 -02-25 10:42:32.067255 7f2c3a596700 20 req: 0x7f2c80040ae0
 -02-25 10:42:32.067259 7f2c3a596700 10 allocated request req=0x7f2c8003f7a0
 -02-25 10:42:32.067273 7f2c3558e700 20 dequeued request req=0x7f2c80040ae0
 -02-25 10:42:32.067276 7f2c3558e700 20 RGWWQ: empty
 -02-25 10:42:32.067305 7f2c3558e700 20 CONTENT_LENGTH=481
 -02-25 10:42:32.067307 7f2c3558e700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:42:32.067307 7f2c3558e700 20 FCGI_ROLE=RESPONDER
 -02-25 10:42:32.067308 7f2c3558e700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:42:32.067309 7f2c3558e700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:42:32.067310 7f2c3558e700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:DkAjsiNEjMgpL6O7BMztXagOrkc=
 -02-25 10:42:32.067311 7f2c3558e700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:42:32.067312 7f2c3558e700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:42:32 +0000
 -02-25 10:42:32.067312 7f2c3558e700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:42:32.067313 7f2c3558e700 20 QUERY_STRING=uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:32.067314 7f2c3558e700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:42:32.067315 7f2c3558e700 20 REMOTE_PORT=48025
 -02-25 10:42:32.067316 7f2c3558e700 20 REQUEST_METHOD=POST
 -02-25 10:42:32.067317 7f2c3558e700 20 REQUEST_URI=/putfile?uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:32.067318 7f2c3558e700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:42:32.067318 7f2c3558e700 20 SCRIPT_NAME=/putfile
 -02-25 10:42:32.067319 7f2c3558e700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:42:32.067320 7f2c3558e700 20 SCRIPT_URL=/putfile
 -02-25 10:42:32.067321 7f2c3558e700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:42:32.067322 7f2c3558e700 20 SERVER_ADMIN=root@localhost
 -02-25 10:42:32.067322 7f2c3558e700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:42:32.067323 7f2c3558e700 20 SERVER_PORT=80
 -02-25 10:42:32.067324 7f2c3558e700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:42:32.067325 7f2c3558e700 20 SERVER_SIGNATURE=
 -02-25 10:42:32.067326 7f2c3558e700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:42:32.067328 7f2c3558e700  1 ====== starting new request req=0x7f2c80040ae0 =====
 -02-25 10:42:32.067348 7f2c3558e700  2 req 13:0.000021::POST /putfile::initializing
 -02-25 10:42:32.067353 7f2c3558e700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:42:32.067364 7f2c3558e700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:42:32.067371 7f2c3558e700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:42:32 +0000
 -02-25 10:42:32.067385 7f2c3558e700 10 s->object=putfile s->bucket=test
 -02-25 10:42:32.067389 7f2c3558e700  2 req 13:0.000062:s3:POST /putfile::getting op
 -02-25 10:42:32.067392 7f2c3558e700  2 req 13:0.000065:s3:POST /putfile:complete_multipart:authorizing
 -02-25 10:42:32.067426 7f2c3558e700 10 get_canon_resource(): dest=/test/putfile?uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:32.067429 7f2c3558e700 10 auth_hdr:
       POST
       x-amz-date:Wed, 25 Feb 2015 07:42:32 +0000
       /test/putfile?uploadId=2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt
 -02-25 10:42:32.067503 7f2c3558e700 15 calculated digest=DkAjsiNEjMgpL6O7BMztXagOrkc=
 -02-25 10:42:32.067505 7f2c3558e700 15 auth_sign=DkAjsiNEjMgpL6O7BMztXagOrkc=
 -02-25 10:42:32.067506 7f2c3558e700 15 compare=0
 -02-25 10:42:32.067508 7f2c3558e700  2 req 13:0.000181:s3:POST /putfile:complete_multipart:reading permissions
 -02-25 10:42:32.067528 7f2c3558e700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:42:32.067554 7f2c3558e700  2 req 13:0.000227:s3:POST /putfile:complete_multipart:init op
 -02-25 10:42:32.067558 7f2c3558e700  2 req 13:0.000231:s3:POST /putfile:complete_multipart:verifying op mask
 -02-25 10:42:32.067560 7f2c3558e700 20 required_mask= 2 user.op_mask=7
 -02-25 10:42:32.067561 7f2c3558e700  2 req 13:0.000234:s3:POST /putfile:complete_multipart:verifying op permissions
 -02-25 10:42:32.067564 7f2c3558e700  5 Searching permissions for uid=functests mask=50
 -02-25 10:42:32.067565 7f2c3558e700  5 Found permission: 15
 -02-25 10:42:32.067566 7f2c3558e700  5 Searching permissions for group=1 mask=50
 -02-25 10:42:32.067567 7f2c3558e700  5 Permissions for group not found
 -02-25 10:42:32.067568 7f2c3558e700  5 Searching permissions for group=2 mask=50
 -02-25 10:42:32.067569 7f2c3558e700  5 Permissions for group not found
 -02-25 10:42:32.067569 7f2c3558e700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:42:32.067570 7f2c3558e700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:42:32.067572 7f2c3558e700  2 req 13:0.000244:s3:POST /putfile:complete_multipart:verifying op params
 -02-25 10:42:32.067573 7f2c3558e700  2 req 13:0.000246:s3:POST /putfile:complete_multipart:executing
 -02-25 10:42:32.067701 7f2c3558e700 20 get_obj_state: rctx=0x7f2c3558d5d0 obj=test:_multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.meta state=0x7f2c8c03f9b8 s->prefetch_data=0
 -02-25 10:42:32.129014 7f2c3558e700 20 get_obj_state: s->obj_tag was set empty
 -02-25 10:42:32.129020 7f2c3558e700 20 Read xattr: user.rgw.acl
 -02-25 10:42:32.129022 7f2c3558e700 20 Read xattr: user.rgw.content_type
 -02-25 10:42:32.129023 7f2c3558e700 20 Read xattr: user.rgw.idtag
 -02-25 10:42:32.129023 7f2c3558e700 20 Read xattr: user.rgw.manifest
 -02-25 10:42:32.129024 7f2c3558e700 20 Read xattr: user.rgw.x-amz-content-sha256
 -02-25 10:42:32.129025 7f2c3558e700 20 Read xattr: user.rgw.x-amz-date
 -02-25 10:42:32.129026 7f2c3558e700 20 Read xattr: user.rgw.x-amz-meta-s3cmd-attrs
 -02-25 10:42:32.130254 7f2c3558e700 10 calculated etag: 37675a29bf27b4e05ff13f970e81a597-5
 -02-25 10:42:32.130272 7f2c3558e700 20 get_obj_state: rctx=0x7f2c3558d5d0 obj=test:putfile state=0x7f2c8c04d818 s->prefetch_data=0
 -02-25 10:42:32.131140 7f2c3558e700 10 setting object write_tag=default.4303.13
 -02-25 10:42:32.266971 7f2c3558e700 20 get_obj_state: rctx=0x7f2c3558d5d0 obj=test:_multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.meta state=0x7f2c8c03f9b8 s->prefetch_data=0
 -02-25 10:42:32.266976 7f2c3558e700 20 prepare_atomic_for_write_impl: state is not atomic. state=0x7f2c8c03f9b8
 -02-25 10:42:32.539705 7f2c3558e700  2 req 13:0.472378:s3:POST /putfile:complete_multipart:http status=200
 -02-25 10:42:32.539710 7f2c3558e700  1 ====== req done req=0x7f2c80040ae0 http_status=200 ======
 -02-25 10:42:34.459825 7f2c7bfff700  2 garbage collection: start
 -02-25 10:42:35.277632 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:42:48.104931 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:43:04.286911 7f2c3a596700 20 enqueued request req=0x7f2c8003f7a0
 -02-25 10:43:04.286939 7f2c3a596700 20 RGWWQ:
 -02-25 10:43:04.286940 7f2c3a596700 20 req: 0x7f2c8003f7a0
 -02-25 10:43:04.286944 7f2c3a596700 10 allocated request req=0x7f2c8003de60
 -02-25 10:43:04.287033 7f2c3558e700 20 dequeued request req=0x7f2c8003f7a0
 -02-25 10:43:04.287041 7f2c3558e700 20 RGWWQ: empty
 -02-25 10:43:04.287094 7f2c3558e700 20 CONTENT_LENGTH=0
 -02-25 10:43:04.287096 7f2c3558e700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:43:04.287098 7f2c3558e700 20 FCGI_ROLE=RESPONDER
 -02-25 10:43:04.287099 7f2c3558e700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:43:04.287100 7f2c3558e700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:43:04.287101 7f2c3558e700 20 HTTP_AUTHORIZATION=AWS4-HMAC-SHA256 Credential=FA7GCBZJYADXE2ZZU43J/20150225/US/s3/aws4_request,SignedHeaders=host;x-amz-content-sha256;x-amz-date,Signature=b8f9a186dc2ef4832f2b0dfc2b5545c15e85518a015b71fbdd6f3698d5e14430
 -02-25 10:43:04.287104 7f2c3558e700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:43:04.287105 7f2c3558e700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:43:04.287106 7f2c3558e700 20 HTTP_X_AMZ_DATE=20150225T074304Z
 -02-25 10:43:04.287107 7f2c3558e700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:43:04.287108 7f2c3558e700 20 QUERY_STRING=
 -02-25 10:43:04.287109 7f2c3558e700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:43:04.287110 7f2c3558e700 20 REMOTE_PORT=48026
 -02-25 10:43:04.287111 7f2c3558e700 20 REQUEST_METHOD=DELETE
 -02-25 10:43:04.287111 7f2c3558e700 20 REQUEST_URI=/putfile
 -02-25 10:43:04.287112 7f2c3558e700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:43:04.287113 7f2c3558e700 20 SCRIPT_NAME=/putfile
 -02-25 10:43:04.287114 7f2c3558e700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:43:04.287115 7f2c3558e700 20 SCRIPT_URL=/putfile
 -02-25 10:43:04.287115 7f2c3558e700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:43:04.287116 7f2c3558e700 20 SERVER_ADMIN=root@localhost
 -02-25 10:43:04.287117 7f2c3558e700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:43:04.287118 7f2c3558e700 20 SERVER_PORT=80
 -02-25 10:43:04.287127 7f2c3558e700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:43:04.287128 7f2c3558e700 20 SERVER_SIGNATURE=
 -02-25 10:43:04.287129 7f2c3558e700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:43:04.287131 7f2c3558e700  1 ====== starting new request req=0x7f2c8003f7a0 =====
 -02-25 10:43:04.287143 7f2c3558e700  2 req 14:0.000014::DELETE /putfile::initializing
 -02-25 10:43:04.287148 7f2c3558e700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:43:04.287160 7f2c3558e700 10 meta>> HTTP_X_AMZ_CONTENT_SHA256
 -02-25 10:43:04.287164 7f2c3558e700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:43:04.287168 7f2c3558e700 10 x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:43:04.287170 7f2c3558e700 10 x>> x-amz-date:20150225T074304Z
 -02-25 10:43:04.287187 7f2c3558e700 10 s->object=putfile s->bucket=test
 -02-25 10:43:04.287191 7f2c3558e700  2 req 14:0.000062:s3:DELETE /putfile::getting op
 -02-25 10:43:04.287194 7f2c3558e700  2 req 14:0.000065:s3:DELETE /putfile:delete_obj:authorizing
 -02-25 10:43:04.287198 7f2c3558e700 10 failed to authorize request
 -02-25 10:43:04.287236 7f2c3558e700  2 req 14:0.000107:s3:DELETE /putfile:delete_obj:http status=400
 -02-25 10:43:04.287240 7f2c3558e700  1 ====== req done req=0x7f2c8003f7a0 http_status=400 ======
 -02-25 10:43:04.287246 7f2c3558e700 20 process_request() returned -22
 -02-25 10:43:04.331423 7f2c3a596700 20 enqueued request req=0x7f2c8003de60
 -02-25 10:43:04.331451 7f2c3a596700 20 RGWWQ:
 -02-25 10:43:04.331453 7f2c3a596700 20 req: 0x7f2c8003de60
 -02-25 10:43:04.331456 7f2c3a596700 10 allocated request req=0x7f2c8003f3d0
 -02-25 10:43:04.331474 7f2c34b8d700 20 dequeued request req=0x7f2c8003de60
 -02-25 10:43:04.331478 7f2c34b8d700 20 RGWWQ: empty
 -02-25 10:43:04.331513 7f2c34b8d700 20 CONTENT_LENGTH=0
 -02-25 10:43:04.331516 7f2c34b8d700 20 DOCUMENT_ROOT=/var/www/html
 -02-25 10:43:04.331516 7f2c34b8d700 20 FCGI_ROLE=RESPONDER
 -02-25 10:43:04.331517 7f2c34b8d700 20 GATEWAY_INTERFACE=CGI/1.1
 -02-25 10:43:04.331518 7f2c34b8d700 20 HTTP_ACCEPT_ENCODING=identity
 -02-25 10:43:04.331519 7f2c34b8d700 20 HTTP_AUTHORIZATION=AWS FA7GCBZJYADXE2ZZU43J:GWBVdQZ8q3TZpNiKRRNrfjFF7Qg=
 -02-25 10:43:04.331519 7f2c34b8d700 20 HTTP_HOST=test.storage.midtest.c2
 -02-25 10:43:04.331520 7f2c34b8d700 20 HTTP_X_AMZ_CONTENT_SHA256=e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:43:04.331522 7f2c34b8d700 20 HTTP_X_AMZ_DATE=Wed, 25 Feb 2015 07:43:04 +0000
 -02-25 10:43:04.331523 7f2c34b8d700 20 PATH=/sbin:/usr/sbin:/bin:/usr/bin
 -02-25 10:43:04.331524 7f2c34b8d700 20 QUERY_STRING=
 -02-25 10:43:04.331524 7f2c34b8d700 20 REMOTE_ADDR=172.20.32.1
 -02-25 10:43:04.331525 7f2c34b8d700 20 REMOTE_PORT=48027
 -02-25 10:43:04.331526 7f2c34b8d700 20 REQUEST_METHOD=DELETE
 -02-25 10:43:04.331527 7f2c34b8d700 20 REQUEST_URI=/putfile
 -02-25 10:43:04.331527 7f2c34b8d700 20 SCRIPT_FILENAME=/var/www/html/s3gw.fcgi
 -02-25 10:43:04.331528 7f2c34b8d700 20 SCRIPT_NAME=/putfile
 -02-25 10:43:04.331529 7f2c34b8d700 20 SCRIPT_URI=http://test.storage.midtest.c2/putfile
 -02-25 10:43:04.331530 7f2c34b8d700 20 SCRIPT_URL=/putfile
 -02-25 10:43:04.331530 7f2c34b8d700 20 SERVER_ADDR=172.20.32.100
 -02-25 10:43:04.331531 7f2c34b8d700 20 SERVER_ADMIN=root@localhost
 -02-25 10:43:04.331554 7f2c34b8d700 20 SERVER_NAME=test.storage.midtest.c2
 -02-25 10:43:04.331555 7f2c34b8d700 20 SERVER_PORT=80
 -02-25 10:43:04.331556 7f2c34b8d700 20 SERVER_PROTOCOL=HTTP/1.1
 -02-25 10:43:04.331557 7f2c34b8d700 20 SERVER_SIGNATURE=
 -02-25 10:43:04.331558 7f2c34b8d700 20 SERVER_SOFTWARE=Apache/2.2.22 (Fedora)
 -02-25 10:43:04.331560 7f2c34b8d700  1 ====== starting new request req=0x7f2c8003de60 =====
 -02-25 10:43:04.331576 7f2c34b8d700  2 req 15:0.000017::DELETE /putfile::initializing
 -02-25 10:43:04.331581 7f2c34b8d700 10 host=test.storage.midtest.c2 rgw_dns_name=storage.midtest.c2
 -02-25 10:43:04.331595 7f2c34b8d700 10 meta>> HTTP_X_AMZ_CONTENT_SHA256
 -02-25 10:43:04.331599 7f2c34b8d700 10 meta>> HTTP_X_AMZ_DATE
 -02-25 10:43:04.331603 7f2c34b8d700 10 x>> x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
 -02-25 10:43:04.331605 7f2c34b8d700 10 x>> x-amz-date:Wed, 25 Feb 2015 07:43:04 +0000
 -02-25 10:43:04.331624 7f2c34b8d700 10 s->object=putfile s->bucket=test
 -02-25 10:43:04.331629 7f2c34b8d700  2 req 15:0.000069:s3:DELETE /putfile::getting op
 -02-25 10:43:04.331632 7f2c34b8d700  2 req 15:0.000073:s3:DELETE /putfile:delete_obj:authorizing
 -02-25 10:43:04.331669 7f2c34b8d700 10 get_canon_resource(): dest=/test/putfile
 -02-25 10:43:04.331671 7f2c34b8d700 10 auth_hdr:
       DELETE
       x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
       x-amz-date:Wed, 25 Feb 2015 07:43:04 +0000
       /test/putfile
 -02-25 10:43:04.331774 7f2c34b8d700 15 calculated digest=GWBVdQZ8q3TZpNiKRRNrfjFF7Qg=
 -02-25 10:43:04.331777 7f2c34b8d700 15 auth_sign=GWBVdQZ8q3TZpNiKRRNrfjFF7Qg=
 -02-25 10:43:04.331778 7f2c34b8d700 15 compare=0
 -02-25 10:43:04.331780 7f2c34b8d700  2 req 15:0.000221:s3:DELETE /putfile:delete_obj:reading permissions
 -02-25 10:43:04.331805 7f2c34b8d700 15 Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>functests</ID><DisplayName>functests</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>functests</ID><DisplayName>functests</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
 -02-25 10:43:04.331812 7f2c34b8d700  2 req 15:0.000253:s3:DELETE /putfile:delete_obj:init op
 -02-25 10:43:04.331815 7f2c34b8d700  2 req 15:0.000256:s3:DELETE /putfile:delete_obj:verifying op mask
 -02-25 10:43:04.331817 7f2c34b8d700 20 required_mask= 4 user.op_mask=7
 -02-25 10:43:04.331818 7f2c34b8d700  2 req 15:0.000259:s3:DELETE /putfile:delete_obj:verifying op permissions
 -02-25 10:43:04.331820 7f2c34b8d700  5 Searching permissions for uid=functests mask=50
 -02-25 10:43:04.331821 7f2c34b8d700  5 Found permission: 15
 -02-25 10:43:04.331822 7f2c34b8d700  5 Searching permissions for group=1 mask=50
 -02-25 10:43:04.331823 7f2c34b8d700  5 Permissions for group not found
 -02-25 10:43:04.331824 7f2c34b8d700  5 Searching permissions for group=2 mask=50
 -02-25 10:43:04.331825 7f2c34b8d700  5 Permissions for group not found
 -02-25 10:43:04.331825 7f2c34b8d700  5 Getting permissions id=functests owner=functests perm=2
 -02-25 10:43:04.331826 7f2c34b8d700 10  uid=functests requested perm (type)=2, policy perm=2, user_perm_mask=2, acl perm=2
 -02-25 10:43:04.331828 7f2c34b8d700  2 req 15:0.000269:s3:DELETE /putfile:delete_obj:verifying op params
 -02-25 10:43:04.331830 7f2c34b8d700  2 req 15:0.000270:s3:DELETE /putfile:delete_obj:executing
 -02-25 10:43:04.331853 7f2c34b8d700 20 get_obj_state: rctx=0x7f2c34b8c5d0 obj=test:putfile state=0x7f2c9403aa18 s->prefetch_data=0
 -02-25 10:43:04.333806 7f2c34b8d700 10 manifest: total_size = 69441536
 -02-25 10:43:04.333815 7f2c34b8d700 20 get_obj_state: setting s->obj_tag to default.4303.13
 -02-25 10:43:04.333886 7f2c34b8d700 10 setting object write_tag=_QzmKhUYxgnsvrFA0_SyXZbNj1NCu7se
 -02-25 10:43:04.828884 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.828889 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=4194304 part_ofs=0 rule->part_size=15728640
 -02-25 10:43:04.828892 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=4194304 stripe_ofs=4194304 part_ofs=0 rule->part_size=15728640
 -02-25 10:43:04.828903 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.828906 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=8388608 part_ofs=0 rule->part_size=15728640
 -02-25 10:43:04.828909 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=8388608 stripe_ofs=8388608 part_ofs=0 rule->part_size=15728640
 -02-25 10:43:04.828915 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.828918 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=12582912 part_ofs=0 rule->part_size=15728640
 -02-25 10:43:04.828921 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=12582912 stripe_ofs=12582912 part_ofs=0 rule->part_size=15728640
 -02-25 10:43:04.828927 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.828929 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=16777216 part_ofs=0 rule->part_size=15728640
 -02-25 10:43:04.828933 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=15728640 stripe_ofs=15728640 part_ofs=15728640 rule->part_size=15204352
 -02-25 10:43:04.828940 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15204352 rules.size()=4
 -02-25 10:43:04.828942 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=19922944 part_ofs=15728640 rule->part_size=15204352
 -02-25 10:43:04.828945 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=19922944 stripe_ofs=19922944 part_ofs=15728640 rule->part_size=15204352
 -02-25 10:43:04.828951 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15204352 rules.size()=4
 -02-25 10:43:04.828954 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=24117248 part_ofs=15728640 rule->part_size=15204352
 -02-25 10:43:04.828958 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=24117248 stripe_ofs=24117248 part_ofs=15728640 rule->part_size=15204352
 -02-25 10:43:04.828964 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15204352 rules.size()=4
 -02-25 10:43:04.828967 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=28311552 part_ofs=15728640 rule->part_size=15204352
 -02-25 10:43:04.828970 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=28311552 stripe_ofs=28311552 part_ofs=15728640 rule->part_size=15204352
 -02-25 10:43:04.828976 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15204352 rules.size()=4
 -02-25 10:43:04.828979 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=32505856 part_ofs=15728640 rule->part_size=15204352
 -02-25 10:43:04.828981 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=30932992 stripe_ofs=30932992 part_ofs=30932992 rule->part_size=15728640
 -02-25 10:43:04.828988 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.828991 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=35127296 part_ofs=30932992 rule->part_size=15728640
 -02-25 10:43:04.828993 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=35127296 stripe_ofs=35127296 part_ofs=30932992 rule->part_size=15728640
 -02-25 10:43:04.828999 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.829001 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=39321600 part_ofs=30932992 rule->part_size=15728640
 -02-25 10:43:04.829004 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=39321600 stripe_ofs=39321600 part_ofs=30932992 rule->part_size=15728640
 -02-25 10:43:04.829010 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.829013 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=43515904 part_ofs=30932992 rule->part_size=15728640
 -02-25 10:43:04.829015 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=43515904 stripe_ofs=43515904 part_ofs=30932992 rule->part_size=15728640
 -02-25 10:43:04.829021 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.829023 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=47710208 part_ofs=30932992 rule->part_size=15728640
 -02-25 10:43:04.829026 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=46661632 stripe_ofs=46661632 part_ofs=46661632 rule->part_size=15728640
 -02-25 10:43:04.829032 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.829035 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=50855936 part_ofs=46661632 rule->part_size=15728640
 -02-25 10:43:04.829037 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=50855936 stripe_ofs=50855936 part_ofs=46661632 rule->part_size=15728640
 -02-25 10:43:04.829043 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.829046 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=55050240 part_ofs=46661632 rule->part_size=15728640
 -02-25 10:43:04.829049 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=55050240 stripe_ofs=55050240 part_ofs=46661632 rule->part_size=15728640
 -02-25 10:43:04.829055 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.829057 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=59244544 part_ofs=46661632 rule->part_size=15728640
 -02-25 10:43:04.829060 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=59244544 stripe_ofs=59244544 part_ofs=46661632 rule->part_size=15728640
 -02-25 10:43:04.829065 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=15728640 rules.size()=4
 -02-25 10:43:04.829068 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=63438848 part_ofs=46661632 rule->part_size=15728640
 -02-25 10:43:04.829071 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=62390272 stripe_ofs=62390272 part_ofs=62390272 rule->part_size=7051264
 -02-25 10:43:04.829077 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=7051264 rules.size()=4
 -02-25 10:43:04.829080 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=66584576 part_ofs=62390272 rule->part_size=7051264
 -02-25 10:43:04.829082 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=66584576 stripe_ofs=66584576 part_ofs=62390272 rule->part_size=7051264
 -02-25 10:43:04.829089 7f2c34b8d700 20 RGWObjManifest::operator++(): rule->part_size=7051264 rules.size()=4
 -02-25 10:43:04.829091 7f2c34b8d700 20 RGWObjManifest::operator++(): stripe_ofs=70778880 part_ofs=62390272 rule->part_size=7051264
 -02-25 10:43:04.829094 7f2c34b8d700  0 RGWObjManifest::operator++(): result: ofs=69441536 stripe_ofs=69441536 part_ofs=69441536 rule->part_size=7051264
 -02-25 10:43:04.829292 7f2c34b8d700  2 req 15:0.497732:s3:DELETE /putfile:delete_obj:http status=204
 -02-25 10:43:04.829301 7f2c34b8d700  1 ====== req done req=0x7f2c8003de60 http_status=204 ======
 -02-25 10:43:04.829328 7f2c34b8d700 20 process_request() returned -1902
 -02-25 10:43:10.105057 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:43:21.252159 7f2ca4ffa700  0 WARNING: RGWRados::log_usage(): user name empty (bucket=), skipping
 -02-25 10:43:32.105196 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:43:35.277736 7f2c7bfff700  2 garbage collection: start
 -02-25 10:43:35.916768 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.1
 -02-25 10:43:35.934263 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.1_1
 -02-25 10:43:35.940875 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.1_2
 -02-25 10:43:35.946217 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.1_3
 -02-25 10:43:35.953687 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__multipart_putfile.edb9KfijsVddlUKMZRm8A-hjtm-XkhM.2
 -02-25 10:43:35.963927 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.edb9KfijsVddlUKMZRm8A-hjtm-XkhM.2_1
 -02-25 10:43:35.981333 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.edb9KfijsVddlUKMZRm8A-hjtm-XkhM.2_2
 -02-25 10:43:36.000531 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.edb9KfijsVddlUKMZRm8A-hjtm-XkhM.2_3
 -02-25 10:43:36.005852 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.3
 -02-25 10:43:36.016827 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.3_1
 -02-25 10:43:36.036875 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.3_2
 -02-25 10:43:36.053409 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.3_3
 -02-25 10:43:36.057334 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.4
 -02-25 10:43:36.068094 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.4_1
 -02-25 10:43:36.094587 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.4_2
 -02-25 10:43:36.116152 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.4_3
 -02-25 10:43:36.135310 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__multipart_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.5
 -02-25 10:43:36.150656 7f2c7bfff700  0 gc::process: removing .rgw.buckets:default.4303.1__shadow_putfile.2/fFEtO5aTFYNO7tjxbbmw6QkGOmeeOFt.5_1
 -02-25 10:43:36.236954 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:43:54.105356 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:44:16.105479 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:44:31.358282 7f2c7b5fe700 20 BucketsSyncThread: sync user=functests bucket=test(@{i=.rgw.buckets.index,e=.rgw.buckets.extra}.rgw.buckets[default.4303.1])
 -02-25 10:44:36.237046 7f2c7bfff700  2 garbage collection: start
 -02-25 10:44:37.582263 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:44:38.105682 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:45:00.105896 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:45:22.106040 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:45:36.582366 7f2c7bfff700  2 garbage collection: start
 -02-25 10:45:39.689035 7f2c7bfff700  2 garbage collection: stop
 -02-25 10:45:44.106175 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:46:06.106313 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start
 -02-25 10:46:28.106448 7f2c98dfa700  2 RGWDataChangesLog::ChangesRenewThread: start

(3-3/10)

Project

General

Profile

Ceph » rgw

Bug #10946 » client.radosgw.set004.log