Project

General

Profile

Bug #20201 » options-and-failing-post-20170531.txt

Dave Holland, 06/06/2017 03:00 PM

 
2017-05-31 16:03:20.493904 7f289affd700 20 RGWEnv::set(): HTTP_HOST: fine20170531.cog.sanger.ac.uk
2017-05-31 16:03:20.493922 7f289affd700 20 RGWEnv::set(): HTTP_CONNECTION: keep-alive
2017-05-31 16:03:20.493925 7f289affd700 20 RGWEnv::set(): HTTP_ACCESS_CONTROL_REQUEST_METHOD: POST
2017-05-31 16:03:20.493926 7f289affd700 20 RGWEnv::set(): HTTP_ORIGIN: http://172.27.84.164:5000
2017-05-31 16:03:20.493927 7f289affd700 20 RGWEnv::set(): HTTP_USER_AGENT: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-05-31 16:03:20.493929 7f289affd700 20 RGWEnv::set(): HTTP_ACCEPT: */*
2017-05-31 16:03:20.493930 7f289affd700 20 RGWEnv::set(): HTTP_REFERER: http://172.27.84.164:5000/
2017-05-31 16:03:20.493932 7f289affd700 20 RGWEnv::set(): HTTP_ACCEPT_ENCODING: gzip, deflate, sdch, br
2017-05-31 16:03:20.493933 7f289affd700 20 RGWEnv::set(): HTTP_ACCEPT_LANGUAGE: en-GB,en-US;q=0.8,en;q=0.6
2017-05-31 16:03:20.493934 7f289affd700 20 RGWEnv::set(): HTTP_DNT: 1
2017-05-31 16:03:20.493935 7f289affd700 20 RGWEnv::set(): REQUEST_METHOD: OPTIONS
2017-05-31 16:03:20.493936 7f289affd700 20 RGWEnv::set(): REQUEST_URI: /
2017-05-31 16:03:20.493937 7f289affd700 20 RGWEnv::set(): QUERY_STRING:
2017-05-31 16:03:20.493937 7f289affd700 20 RGWEnv::set(): REMOTE_USER:
2017-05-31 16:03:20.493938 7f289affd700 20 RGWEnv::set(): SCRIPT_URI: /
2017-05-31 16:03:20.493940 7f289affd700 20 RGWEnv::set(): SERVER_PORT: 0
2017-05-31 16:03:20.493941 7f289affd700 20 RGWEnv::set(): SERVER_PORT_SECURE: 443
2017-05-31 16:03:20.493941 7f289affd700 20 HTTP_ACCEPT=*/*
2017-05-31 16:03:20.493942 7f289affd700 20 HTTP_ACCEPT_ENCODING=gzip, deflate, sdch, br
2017-05-31 16:03:20.493943 7f289affd700 20 HTTP_ACCEPT_LANGUAGE=en-GB,en-US;q=0.8,en;q=0.6
2017-05-31 16:03:20.493943 7f289affd700 20 HTTP_ACCESS_CONTROL_REQUEST_METHOD=POST
2017-05-31 16:03:20.493944 7f289affd700 20 HTTP_CONNECTION=keep-alive
2017-05-31 16:03:20.493944 7f289affd700 20 HTTP_DNT=1
2017-05-31 16:03:20.493944 7f289affd700 20 HTTP_HOST=fine20170531.cog.sanger.ac.uk
2017-05-31 16:03:20.493945 7f289affd700 20 HTTP_ORIGIN=http://172.27.84.164:5000
2017-05-31 16:03:20.493945 7f289affd700 20 HTTP_REFERER=http://172.27.84.164:5000/
2017-05-31 16:03:20.493946 7f289affd700 20 HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-05-31 16:03:20.493946 7f289affd700 20 QUERY_STRING=
2017-05-31 16:03:20.493947 7f289affd700 20 REMOTE_USER=
2017-05-31 16:03:20.493947 7f289affd700 20 REQUEST_METHOD=OPTIONS
2017-05-31 16:03:20.493948 7f289affd700 20 REQUEST_URI=/
2017-05-31 16:03:20.493948 7f289affd700 20 SCRIPT_URI=/
2017-05-31 16:03:20.493949 7f289affd700 20 SERVER_PORT=0
2017-05-31 16:03:20.493949 7f289affd700 20 SERVER_PORT_SECURE=443
2017-05-31 16:03:20.493951 7f289affd700 1 ====== starting new request req=0x7f289aff77e0 =====
2017-05-31 16:03:20.493972 7f289affd700 2 req 1:0.000021::OPTIONS /::initializing for trans_id = tx000000000000000000001-00592edb38-cbc930-default
2017-05-31 16:03:20.493981 7f289affd700 10 rgw api priority: s3=5 s3website=4
2017-05-31 16:03:20.493983 7f289affd700 10 host=fine20170531.cog.sanger.ac.uk
2017-05-31 16:03:20.493987 7f289affd700 20 subdomain=fine20170531 domain=cog.sanger.ac.uk in_hosted_domain=1 in_hosted_domain_s3website=0
2017-05-31 16:03:20.493991 7f289affd700 20 final domain/bucket subdomain=fine20170531 domain=cog.sanger.ac.uk in_hosted_domain=1 in_hosted_domain_s3website=0 s->info.domain=cog.sanger.ac.uk s->info.request_uri=/fine20170531/
2017-05-31 16:03:20.494036 7f289affd700 20 get_handler handler=25RGWHandler_REST_Bucket_S3
2017-05-31 16:03:20.494040 7f289affd700 10 handler=25RGWHandler_REST_Bucket_S3
2017-05-31 16:03:20.494042 7f289affd700 2 req 1:0.000091:s3:OPTIONS /::getting op 6
2017-05-31 16:03:20.494045 7f289affd700 10 op=26RGWOptionsCORS_ObjStore_S3
2017-05-31 16:03:20.494046 7f289affd700 2 req 1:0.000096:s3:OPTIONS /:options_cors:authorizing
2017-05-31 16:03:20.494056 7f289affd700 2 req 1:0.000105:s3:OPTIONS /:options_cors:normalizing buckets and tenants
2017-05-31 16:03:20.494058 7f289affd700 10 s->object=<NULL> s->bucket=fine20170531
2017-05-31 16:03:20.494060 7f289affd700 2 req 1:0.000110:s3:OPTIONS /:options_cors:init permissions
2017-05-31 16:03:20.494084 7f289affd700 20 get_system_obj_state: rctx=0x7f289aff66c0 obj=default.rgw.data.root:fine20170531 state=0x7f2a20009228 s->prefetch_data=0
2017-05-31 16:03:20.494096 7f289affd700 10 cache get: name=default.rgw.data.root+fine20170531 : miss
2017-05-31 16:03:20.496991 7f289affd700 10 cache put: name=default.rgw.data.root+fine20170531 info.flags=22
2017-05-31 16:03:20.497005 7f289affd700 10 adding default.rgw.data.root+fine20170531 to cache LRU end
2017-05-31 16:03:20.497011 7f289affd700 20 get_system_obj_state: s->obj_tag was set empty
2017-05-31 16:03:20.497013 7f289affd700 20 Read xattr: user.rgw.idtag
2017-05-31 16:03:20.497014 7f289affd700 20 Read xattr: user.rgw.manifest
2017-05-31 16:03:20.497018 7f289affd700 10 cache get: name=default.rgw.data.root+fine20170531 : type miss (requested=17, cached=22)
2017-05-31 16:03:20.497023 7f289affd700 20 get_system_obj_state: rctx=0x7f289aff66c0 obj=default.rgw.data.root:fine20170531 state=0x7f2a20009228 s->prefetch_data=0
2017-05-31 16:03:20.497046 7f289affd700 20 rados->read ofs=0 len=524288
2017-05-31 16:03:20.497957 7f289affd700 20 rados->read r=0 bl.length=260
2017-05-31 16:03:20.497979 7f289affd700 10 cache put: name=default.rgw.data.root+fine20170531 info.flags=17
2017-05-31 16:03:20.497980 7f289affd700 10 moving default.rgw.data.root+fine20170531 to cache LRU end
2017-05-31 16:03:20.497991 7f289affd700 20 rgw_get_bucket_info: bucket instance: fine20170531(@{i=default.rgw.buckets.index,e=default.rgw.buckets.non-ec}default.rgw.buckets.data[861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6])
2017-05-31 16:03:20.498003 7f289affd700 20 reading from default.rgw.data.root:.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6
2017-05-31 16:03:20.498013 7f289affd700 20 get_system_obj_state: rctx=0x7f289aff66c0 obj=default.rgw.data.root:.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6 state=0x7f2a2001ccd8 s->prefetch_data=0
2017-05-31 16:03:20.498021 7f289affd700 10 cache get: name=default.rgw.data.root+.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6 : miss
2017-05-31 16:03:20.500625 7f289affd700 10 cache put: name=default.rgw.data.root+.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6 info.flags=22
2017-05-31 16:03:20.500633 7f289affd700 10 adding default.rgw.data.root+.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6 to cache LRU end
2017-05-31 16:03:20.500635 7f289affd700 10 updating xattr: name=user.rgw.acl bl.length()=133
2017-05-31 16:03:20.500636 7f289affd700 10 updating xattr: name=user.rgw.cors bl.length()=55
2017-05-31 16:03:20.500640 7f289affd700 20 get_system_obj_state: s->obj_tag was set empty
2017-05-31 16:03:20.500642 7f289affd700 20 Read xattr: user.rgw.acl
2017-05-31 16:03:20.500642 7f289affd700 20 Read xattr: user.rgw.cors
2017-05-31 16:03:20.500643 7f289affd700 20 Read xattr: user.rgw.idtag
2017-05-31 16:03:20.500643 7f289affd700 20 Read xattr: user.rgw.manifest
2017-05-31 16:03:20.500647 7f289affd700 10 cache get: name=default.rgw.data.root+.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6 : type miss (requested=17, cached=22)
2017-05-31 16:03:20.500650 7f289affd700 20 get_system_obj_state: rctx=0x7f289aff66c0 obj=default.rgw.data.root:.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6 state=0x7f2a2001ccd8 s->prefetch_data=0
2017-05-31 16:03:20.500672 7f289affd700 20 rados->read ofs=0 len=524288
2017-05-31 16:03:20.501279 7f289affd700 20 rados->read r=0 bl.length=347
2017-05-31 16:03:20.501290 7f289affd700 10 cache put: name=default.rgw.data.root+.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6 info.flags=17
2017-05-31 16:03:20.501292 7f289affd700 10 moving default.rgw.data.root+.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6 to cache LRU end
2017-05-31 16:03:20.501309 7f289affd700 10 chain_cache_entry: cache_locator=default.rgw.data.root+fine20170531
2017-05-31 16:03:20.501309 7f289affd700 10 chain_cache_entry: cache_locator=default.rgw.data.root+.bucket.meta.fine20170531:861ae926-7ff0-48c5-86d6-a6ba8d0a7a14.8290825.6
2017-05-31 16:03:20.501334 7f289affd700 15 decode_policy Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>dh3</ID><DisplayName>Dave Holland</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>dh3</ID><DisplayName>Dave Holland</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
2017-05-31 16:03:20.501352 7f289affd700 2 req 1:0.007401:s3:OPTIONS /:options_cors:recalculating target
2017-05-31 16:03:20.501357 7f289affd700 2 req 1:0.007406:s3:OPTIONS /:options_cors:reading permissions
2017-05-31 16:03:20.501358 7f289affd700 2 req 1:0.007408:s3:OPTIONS /:options_cors:init op
2017-05-31 16:03:20.501360 7f289affd700 2 req 1:0.007409:s3:OPTIONS /:options_cors:verifying op mask
2017-05-31 16:03:20.501361 7f289affd700 20 required_mask= 1 user.op_mask=7
2017-05-31 16:03:20.501362 7f289affd700 2 req 1:0.007412:s3:OPTIONS /:options_cors:verifying op permissions
2017-05-31 16:03:20.501363 7f289affd700 2 req 1:0.007413:s3:OPTIONS /:options_cors:verifying op params
2017-05-31 16:03:20.501365 7f289affd700 2 req 1:0.007414:s3:OPTIONS /:options_cors:pre-executing
2017-05-31 16:03:20.501366 7f289affd700 2 req 1:0.007415:s3:OPTIONS /:options_cors:executing
2017-05-31 16:03:20.501371 7f289affd700 15 Read RGWCORSConfiguration<CORSConfiguration xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><CORSRule><AllowedMethod>PUT</AllowedMethod><AllowedMethod>DELETE</AllowedMethod><AllowedMethod>POST</AllowedMethod><AllowedOrigin>*</AllowedOrigin><AllowedHeader>*</AllowedHeader><MaxAgeSeconds>3000</MaxAgeSeconds><ExposeHeader>ETag</ExposeHeader></CORSRule></CORSConfiguration>
2017-05-31 16:03:20.501401 7f289affd700 10 Method POST is supported
2017-05-31 16:03:20.501405 7f289affd700 2 req 1:0.007454:s3:OPTIONS /:options_cors:completing
2017-05-31 16:03:20.501470 7f289affd700 2 req 1:0.007519:s3:OPTIONS /:options_cors:op status=0
2017-05-31 16:03:20.501477 7f289affd700 2 req 1:0.007526:s3:OPTIONS /:options_cors:http status=200
2017-05-31 16:03:20.501479 7f289affd700 1 ====== req done req=0x7f289aff77e0 op status=0 http_status=200 ======
2017-05-31 16:03:20.501526 7f289affd700 1 civetweb: 0x7f2a20003160: 172.30.17.111 - - [31/May/2017:16:03:20 +0100] "OPTIONS / HTTP/1.1" 200 0 http://172.27.84.164:5000/ Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-05-31 16:03:20.513932 7f289affd700 20 RGWEnv::set(): HTTP_HOST: fine20170531.cog.sanger.ac.uk
2017-05-31 16:03:20.513937 7f289affd700 20 RGWEnv::set(): HTTP_CONNECTION: keep-alive
2017-05-31 16:03:20.513938 7f289affd700 20 RGWEnv::set(): CONTENT_LENGTH: 1650
2017-05-31 16:03:20.513939 7f289affd700 20 RGWEnv::set(): HTTP_ORIGIN: http://172.27.84.164:5000
2017-05-31 16:03:20.513941 7f289affd700 20 RGWEnv::set(): HTTP_USER_AGENT: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-05-31 16:03:20.513943 7f289affd700 20 RGWEnv::set(): CONTENT_TYPE: multipart/form-data; boundary=----WebKitFormBoundaryaLTJ77693qE2IUbc
2017-05-31 16:03:20.513944 7f289affd700 20 RGWEnv::set(): HTTP_ACCEPT: */*
2017-05-31 16:03:20.513945 7f289affd700 20 RGWEnv::set(): HTTP_REFERER: http://172.27.84.164:5000/
2017-05-31 16:03:20.513947 7f289affd700 20 RGWEnv::set(): HTTP_ACCEPT_ENCODING: gzip, deflate, br
2017-05-31 16:03:20.513948 7f289affd700 20 RGWEnv::set(): HTTP_ACCEPT_LANGUAGE: en-GB,en-US;q=0.8,en;q=0.6
2017-05-31 16:03:20.513949 7f289affd700 20 RGWEnv::set(): HTTP_DNT: 1
2017-05-31 16:03:20.513950 7f289affd700 20 RGWEnv::set(): REQUEST_METHOD: POST
2017-05-31 16:03:20.513951 7f289affd700 20 RGWEnv::set(): REQUEST_URI: /
2017-05-31 16:03:20.513952 7f289affd700 20 RGWEnv::set(): QUERY_STRING:
2017-05-31 16:03:20.513953 7f289affd700 20 RGWEnv::set(): REMOTE_USER:
2017-05-31 16:03:20.513953 7f289affd700 20 RGWEnv::set(): SCRIPT_URI: /
2017-05-31 16:03:20.513955 7f289affd700 20 RGWEnv::set(): SERVER_PORT: 0
2017-05-31 16:03:20.513956 7f289affd700 20 RGWEnv::set(): SERVER_PORT_SECURE: 443
2017-05-31 16:03:20.513956 7f289affd700 20 CONTENT_LENGTH=1650
2017-05-31 16:03:20.513957 7f289affd700 20 CONTENT_TYPE=multipart/form-data; boundary=----WebKitFormBoundaryaLTJ77693qE2IUbc
2017-05-31 16:03:20.513958 7f289affd700 20 HTTP_ACCEPT=*/*
2017-05-31 16:03:20.513958 7f289affd700 20 HTTP_ACCEPT_ENCODING=gzip, deflate, br
2017-05-31 16:03:20.513958 7f289affd700 20 HTTP_ACCEPT_LANGUAGE=en-GB,en-US;q=0.8,en;q=0.6
2017-05-31 16:03:20.513959 7f289affd700 20 HTTP_CONNECTION=keep-alive
2017-05-31 16:03:20.513960 7f289affd700 20 HTTP_DNT=1
2017-05-31 16:03:20.513960 7f289affd700 20 HTTP_HOST=fine20170531.cog.sanger.ac.uk
2017-05-31 16:03:20.513961 7f289affd700 20 HTTP_ORIGIN=http://172.27.84.164:5000
2017-05-31 16:03:20.513961 7f289affd700 20 HTTP_REFERER=http://172.27.84.164:5000/
2017-05-31 16:03:20.513962 7f289affd700 20 HTTP_USER_AGENT=Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-05-31 16:03:20.513962 7f289affd700 20 QUERY_STRING=
2017-05-31 16:03:20.513963 7f289affd700 20 REMOTE_USER=
2017-05-31 16:03:20.513964 7f289affd700 20 REQUEST_METHOD=POST
2017-05-31 16:03:20.513964 7f289affd700 20 REQUEST_URI=/
2017-05-31 16:03:20.513965 7f289affd700 20 SCRIPT_URI=/
2017-05-31 16:03:20.513965 7f289affd700 20 SERVER_PORT=0
2017-05-31 16:03:20.513965 7f289affd700 20 SERVER_PORT_SECURE=443
2017-05-31 16:03:20.513967 7f289affd700 1 ====== starting new request req=0x7f289aff77e0 =====
2017-05-31 16:03:20.513975 7f289affd700 2 req 2:0.000009::POST /::initializing for trans_id = tx000000000000000000002-00592edb38-cbc930-default
2017-05-31 16:03:20.513980 7f289affd700 10 rgw api priority: s3=5 s3website=4
2017-05-31 16:03:20.513981 7f289affd700 10 host=fine20170531.cog.sanger.ac.uk
2017-05-31 16:03:20.513983 7f289affd700 20 subdomain=fine20170531 domain=cog.sanger.ac.uk in_hosted_domain=1 in_hosted_domain_s3website=0
2017-05-31 16:03:20.513985 7f289affd700 20 final domain/bucket subdomain=fine20170531 domain=cog.sanger.ac.uk in_hosted_domain=1 in_hosted_domain_s3website=0 s->info.domain=cog.sanger.ac.uk s->info.request_uri=/fine20170531/
2017-05-31 16:03:20.514000 7f289affd700 20 get_handler handler=25RGWHandler_REST_Bucket_S3
2017-05-31 16:03:20.514002 7f289affd700 10 handler=25RGWHandler_REST_Bucket_S3
2017-05-31 16:03:20.514003 7f289affd700 2 req 2:0.000037:s3:POST /::getting op 4
2017-05-31 16:03:20.514008 7f289affd700 10 op=22RGWPostObj_ObjStore_S3
2017-05-31 16:03:20.514009 7f289affd700 2 req 2:0.000043:s3:POST /:post_obj:authorizing
2017-05-31 16:03:20.514012 7f289affd700 2 req 2:0.000045:s3:POST /:post_obj:normalizing buckets and tenants
2017-05-31 16:03:20.514013 7f289affd700 10 s->object=<NULL> s->bucket=fine20170531
2017-05-31 16:03:20.514014 7f289affd700 2 req 2:0.000048:s3:POST /:post_obj:init permissions
2017-05-31 16:03:20.514028 7f289affd700 15 decode_policy Read AccessControlPolicy<AccessControlPolicy xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Owner><ID>dh3</ID><DisplayName>Dave Holland</DisplayName></Owner><AccessControlList><Grant><Grantee xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="CanonicalUser"><ID>dh3</ID><DisplayName>Dave Holland</DisplayName></Grantee><Permission>FULL_CONTROL</Permission></Grant></AccessControlList></AccessControlPolicy>
2017-05-31 16:03:20.514034 7f289affd700 2 req 2:0.000068:s3:POST /:post_obj:recalculating target
2017-05-31 16:03:20.514036 7f289affd700 2 req 2:0.000069:s3:POST /:post_obj:reading permissions
2017-05-31 16:03:20.514038 7f289affd700 2 req 2:0.000071:s3:POST /:post_obj:init op
2017-05-31 16:03:20.514039 7f289affd700 2 req 2:0.000072:s3:POST /:post_obj:verifying op mask
2017-05-31 16:03:20.514040 7f289affd700 20 required_mask= 2 user.op_mask=7
2017-05-31 16:03:20.514040 7f289affd700 2 req 2:0.000074:s3:POST /:post_obj:verifying op permissions
2017-05-31 16:03:20.514041 7f289affd700 2 req 2:0.000075:s3:POST /:post_obj:verifying op params
2017-05-31 16:03:20.514042 7f289affd700 2 req 2:0.000076:s3:POST /:post_obj:pre-executing
2017-05-31 16:03:20.514044 7f289affd700 2 req 2:0.000078:s3:POST /:post_obj:executing
2017-05-31 16:03:20.514103 7f289affd700 20 request content_type_str=multipart/form-data; boundary=----WebKitFormBoundaryaLTJ77693qE2IUbc
2017-05-31 16:03:20.514110 7f289affd700 20 request content_type params:
2017-05-31 16:03:20.514111 7f289affd700 20 boundary -> ----WebKitFormBoundaryaLTJ77693qE2IUbc
2017-05-31 16:03:20.514112 7f289affd700 20 adding bucket to policy env: fine20170531
2017-05-31 16:03:20.514184 7f289affd700 20 read part header: name=key content_type=
2017-05-31 16:03:20.514189 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514190 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514191 7f289affd700 20 params:
2017-05-31 16:03:20.514192 7f289affd700 20 name -> key
2017-05-31 16:03:20.514214 7f289affd700 20 read part header: name=success_action_status content_type=
2017-05-31 16:03:20.514217 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514217 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514218 7f289affd700 20 params:
2017-05-31 16:03:20.514222 7f289affd700 20 name -> success_action_status
2017-05-31 16:03:20.514228 7f289affd700 20 read part header: name=acl content_type=
2017-05-31 16:03:20.514229 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514229 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514230 7f289affd700 20 params:
2017-05-31 16:03:20.514230 7f289affd700 20 name -> acl
2017-05-31 16:03:20.514239 7f289affd700 20 read part header: name=x-amz-meta-user content_type=
2017-05-31 16:03:20.514240 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514241 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514241 7f289affd700 20 params:
2017-05-31 16:03:20.514241 7f289affd700 20 name -> x-amz-meta-user
2017-05-31 16:03:20.514253 7f289affd700 20 read part header: name=x-amz-meta-saltything content_type=
2017-05-31 16:03:20.514254 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514255 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514255 7f289affd700 20 params:
2017-05-31 16:03:20.514256 7f289affd700 20 name -> x-amz-meta-saltything
2017-05-31 16:03:20.514265 7f289affd700 20 read part header: name=x-amz-meta-qqfilename content_type=
2017-05-31 16:03:20.514266 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514267 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514267 7f289affd700 20 params:
2017-05-31 16:03:20.514267 7f289affd700 20 name -> x-amz-meta-qqfilename
2017-05-31 16:03:20.514279 7f289affd700 20 read part header: name=AWSAccessKeyId content_type=
2017-05-31 16:03:20.514280 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514281 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514281 7f289affd700 20 params:
2017-05-31 16:03:20.514282 7f289affd700 20 name -> AWSAccessKeyId
2017-05-31 16:03:20.514288 7f289affd700 20 read part header: name=policy content_type=
2017-05-31 16:03:20.514289 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514289 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514289 7f289affd700 20 params:
2017-05-31 16:03:20.514290 7f289affd700 20 name -> policy
2017-05-31 16:03:20.514302 7f289affd700 20 read part header: name=signature content_type=
2017-05-31 16:03:20.514305 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514305 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514306 7f289affd700 20 params:
2017-05-31 16:03:20.514306 7f289affd700 20 name -> signature
2017-05-31 16:03:20.514316 7f289affd700 20 read part header: name=file content_type=
2017-05-31 16:03:20.514319 7f289affd700 20 name=Content-Disposition
2017-05-31 16:03:20.514320 7f289affd700 20 val=form-data
2017-05-31 16:03:20.514320 7f289affd700 20 params:
2017-05-31 16:03:20.514320 7f289affd700 20 filename -> weird-extension.wtf
2017-05-31 16:03:20.514321 7f289affd700 20 name -> file
2017-05-31 16:03:20.514321 7f289affd700 20 read part header: name=file content_type=
2017-05-31 16:03:20.514322 7f289affd700 20 name=Content-Type
2017-05-31 16:03:20.514322 7f289affd700 20 val=application/octet-stream
2017-05-31 16:03:20.514323 7f289affd700 20 params:
2017-05-31 16:03:20.514357 7f289affd700 20 get_system_obj_state: rctx=0x7f289aff4e40 obj=default.rgw.users.keys:5UD1UC7JHC9CV7QYNXXE state=0x7f2a20030f48 s->prefetch_data=0
2017-05-31 16:03:20.514369 7f289affd700 10 cache get: name=default.rgw.users.keys+5UD1UC7JHC9CV7QYNXXE : miss
2017-05-31 16:03:20.516211 7f289affd700 10 cache put: name=default.rgw.users.keys+5UD1UC7JHC9CV7QYNXXE info.flags=6
2017-05-31 16:03:20.516219 7f289affd700 10 adding default.rgw.users.keys+5UD1UC7JHC9CV7QYNXXE to cache LRU end
2017-05-31 16:03:20.516222 7f289affd700 20 get_system_obj_state: s->obj_tag was set empty
2017-05-31 16:03:20.516226 7f289affd700 10 cache get: name=default.rgw.users.keys+5UD1UC7JHC9CV7QYNXXE : type miss (requested=1, cached=6)
2017-05-31 16:03:20.516231 7f289affd700 20 get_system_obj_state: rctx=0x7f289aff4e40 obj=default.rgw.users.keys:5UD1UC7JHC9CV7QYNXXE state=0x7f2a20030f48 s->prefetch_data=0
2017-05-31 16:03:20.516233 7f289affd700 20 rados->read ofs=0 len=524288
2017-05-31 16:03:20.523747 7f289affd700 20 rados->read r=0 bl.length=7
2017-05-31 16:03:20.523771 7f289affd700 10 cache put: name=default.rgw.users.keys+5UD1UC7JHC9CV7QYNXXE info.flags=1
2017-05-31 16:03:20.523772 7f289affd700 10 moving default.rgw.users.keys+5UD1UC7JHC9CV7QYNXXE to cache LRU end
2017-05-31 16:03:20.523789 7f289affd700 20 get_system_obj_state: rctx=0x7f289aff4a70 obj=default.rgw.users.uid:dh3 state=0x7f2a20037f68 s->prefetch_data=0
2017-05-31 16:03:20.523798 7f289affd700 10 cache get: name=default.rgw.users.uid+dh3 : miss
2017-05-31 16:03:20.525740 7f289affd700 10 cache put: name=default.rgw.users.uid+dh3 info.flags=22
2017-05-31 16:03:20.525758 7f289affd700 10 adding default.rgw.users.uid+dh3 to cache LRU end
2017-05-31 16:03:20.525765 7f289affd700 20 get_system_obj_state: s->obj_tag was set empty
2017-05-31 16:03:20.525772 7f289affd700 10 cache get: name=default.rgw.users.uid+dh3 : type miss (requested=17, cached=22)
2017-05-31 16:03:20.525777 7f289affd700 20 get_system_obj_state: rctx=0x7f289aff4a70 obj=default.rgw.users.uid:dh3 state=0x7f2a20037f68 s->prefetch_data=0
2017-05-31 16:03:20.525808 7f289affd700 20 rados->read ofs=0 len=524288
2017-05-31 16:03:20.526229 7f289affd700 20 rados->read r=0 bl.length=320
2017-05-31 16:03:20.526237 7f289affd700 10 cache put: name=default.rgw.users.uid+dh3 info.flags=17
2017-05-31 16:03:20.526240 7f289affd700 10 moving default.rgw.users.uid+dh3 to cache LRU end
2017-05-31 16:03:20.526259 7f289affd700 10 chain_cache_entry: cache_locator=default.rgw.users.uid+dh3
2017-05-31 16:03:20.526602 7f289affd700 0 Successful Signature Verification!
2017-05-31 16:03:20.526617 7f289affd700 0 POST policy: {"expiration":"2017-05-31T15:08:20.459Z","conditions":[{"acl":"private"},{"bucket":"fine20170531"},{"success_action_status":"200"},{"key":"e8652ead-0200-4efc-ac54-016c5e20810f.wtf"},{"x-amz-meta-user":"wibble"},{"x-amz-meta-saltything":"YYYYYYYYYYYYYY"},{"x-amz-meta-qqfilename":"weird-extension.wtf"}]}
2017-05-31 16:03:20.526770 7f289affd700 20 data={"acl":"private"}
2017-05-31 16:03:20.526775 7f289affd700 20 is_object=1
2017-05-31 16:03:20.526776 7f289affd700 20 is_array=0
2017-05-31 16:03:20.526777 7f289affd700 0 adding simple_check: acl : private
2017-05-31 16:03:20.526779 7f289affd700 20 data={"bucket":"fine20170531"}
2017-05-31 16:03:20.526779 7f289affd700 20 is_object=1
2017-05-31 16:03:20.526780 7f289affd700 20 is_array=0
2017-05-31 16:03:20.526780 7f289affd700 0 adding simple_check: bucket : fine20170531
2017-05-31 16:03:20.526781 7f289affd700 20 data={"success_action_status":"200"}
2017-05-31 16:03:20.526782 7f289affd700 20 is_object=1
2017-05-31 16:03:20.526782 7f289affd700 20 is_array=0
2017-05-31 16:03:20.526783 7f289affd700 0 adding simple_check: success_action_status : 200
2017-05-31 16:03:20.526784 7f289affd700 20 data={"key":"e8652ead-0200-4efc-ac54-016c5e20810f.wtf"}
2017-05-31 16:03:20.526784 7f289affd700 20 is_object=1
2017-05-31 16:03:20.526784 7f289affd700 20 is_array=0
2017-05-31 16:03:20.526785 7f289affd700 0 adding simple_check: key : e8652ead-0200-4efc-ac54-016c5e20810f.wtf
2017-05-31 16:03:20.526786 7f289affd700 20 data={"x-amz-meta-user":"wibble"}
2017-05-31 16:03:20.526787 7f289affd700 20 is_object=1
2017-05-31 16:03:20.526787 7f289affd700 20 is_array=0
2017-05-31 16:03:20.526788 7f289affd700 0 adding simple_check: x-amz-meta-user : wibble
2017-05-31 16:03:20.526788 7f289affd700 20 data={"x-amz-meta-saltything":"YYYYYYYYYYYYYY"}
2017-05-31 16:03:20.526789 7f289affd700 20 is_object=1
2017-05-31 16:03:20.526791 7f289affd700 20 is_array=0
2017-05-31 16:03:20.526792 7f289affd700 0 adding simple_check: x-amz-meta-saltything : YYYYYYYYYYYYYY
2017-05-31 16:03:20.526793 7f289affd700 20 data={"x-amz-meta-qqfilename":"weird-extension.wtf"}
2017-05-31 16:03:20.526793 7f289affd700 20 is_object=1
2017-05-31 16:03:20.526794 7f289affd700 20 is_array=0
2017-05-31 16:03:20.526794 7f289affd700 0 adding simple_check: x-amz-meta-qqfilename : weird-extension.wtf
2017-05-31 16:03:20.526804 7f289affd700 20 comparing acl [private], private
2017-05-31 16:03:20.526806 7f289affd700 20 comparing bucket [fine20170531], fine20170531
2017-05-31 16:03:20.526807 7f289affd700 20 comparing success_action_status [200], 200
2017-05-31 16:03:20.526808 7f289affd700 20 comparing key [e8652ead-0200-4efc-ac54-016c5e20810f.wtf], e8652ead-0200-4efc-ac54-016c5e20810f.wtf
2017-05-31 16:03:20.526810 7f289affd700 20 comparing x-amz-meta-user [wibble], wibble
2017-05-31 16:03:20.526811 7f289affd700 20 comparing x-amz-meta-saltything [YYYYYYYYYYYYYY], YYYYYYYYYYYYYY
2017-05-31 16:03:20.526812 7f289affd700 20 comparing x-amz-meta-qqfilename [weird-extension.wtf], weird-extension.wtf
2017-05-31 16:03:20.526814 7f289affd700 1 env var missing in policy: Content-Type
2017-05-31 16:03:20.526815 7f289affd700 1 missing policy condition
2017-05-31 16:03:20.526816 7f289affd700 0 policy check failed
2017-05-31 16:03:20.526825 7f289affd700 2 req 2:0.012858:s3:POST /:post_obj:completing
2017-05-31 16:03:20.526849 7f289affd700 15 Read RGWCORSConfiguration<CORSConfiguration xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><CORSRule><AllowedMethod>PUT</AllowedMethod><AllowedMethod>DELETE</AllowedMethod><AllowedMethod>POST</AllowedMethod><AllowedOrigin>*</AllowedOrigin><AllowedHeader>*</AllowedHeader><MaxAgeSeconds>3000</MaxAgeSeconds><ExposeHeader>ETag</ExposeHeader></CORSRule></CORSConfiguration>
2017-05-31 16:03:20.526862 7f289affd700 10 Method POST is supported
2017-05-31 16:03:20.526914 7f289affd700 2 req 2:0.012947:s3:POST /:post_obj:op status=0
2017-05-31 16:03:20.526920 7f289affd700 2 req 2:0.012953:s3:POST /:post_obj:http status=403
2017-05-31 16:03:20.526940 7f289affd700 1 ====== req done req=0x7f289aff77e0 op status=0 http_status=403 ======
2017-05-31 16:03:20.526950 7f289affd700 20 process_request() returned -13
2017-05-31 16:03:20.526973 7f289affd700 1 civetweb: 0x7f2a20003160: 172.30.17.111 - - [31/May/2017:16:03:20 +0100] "POST / HTTP/1.1" 403 0 http://172.27.84.164:5000/ Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
(1-1/2)