ok, enabling cache helps :-) What was wrong ? -- Dominik 2013/12/7 Dominik Mostowiec <dominikmostowiec@xxxxxxxxx>: > Yes, it is disabled > grep 'cache' /etc/ceph/ceph.conf | grep rgw > rgw_cache_enabled = false ;rgw cache enabled > rgw_cache_lru_size = 10000 ;num of entries in rgw cache > > -- > Regards > Dominik > > 2013/12/7 Yehuda Sadeh <yehuda@xxxxxxxxxxx>: >> Did you disable the cache by any chance (e.g., 'rgw cache enabled = false')? >> >> >> >> On Sat, Dec 7, 2013 at 8:34 AM, Dominik Mostowiec >> <dominikmostowiec@xxxxxxxxx> wrote: >>> Hi, >>> Log: >>> ------------------------- >>> 2013-12-07 17:32:42.736396 7ffbe36d3780 10 allocated request req=0xe66f40 >>> 2013-12-07 17:32:42.736438 7ff79b1c6700 1 ====== starting new request >>> req=0xe60860 ===== >>> 2013-12-07 17:32:42.736590 7ff79b1c6700 2 req 1:0.000153::PUT >>> /test1/::initializing >>> 2013-12-07 17:32:42.736659 7ff79b1c6700 10 meta>> HTTP_X_AMZ_DATE >>> 2013-12-07 17:32:42.736686 7ff79b1c6700 10 x>> x-amz-date:Sat, 07 Dec >>> 2013 16:32:42 GMT >>> 2013-12-07 17:32:42.736758 7ff79b1c6700 10 s->object=<NULL> s->bucket=test1 >>> 2013-12-07 17:32:42.736788 7ff79b1c6700 2 req 1:0.000351:s3:PUT >>> /test1/::getting op >>> 2013-12-07 17:32:42.736800 7ff79b1c6700 2 req 1:0.000364:s3:PUT >>> /test1/:create_bucket:authorizing >>> 2013-12-07 17:32:42.736907 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6804/161220 -- osd_op(client.95391.0:107 >>> F7P3E755K6BN5LR3N85U [getxattrs,stat] 7.62531457 e192) v4 -- ?+0 0x7ff >>> 794005a90 con 0xd908d0 >>> 2013-12-07 17:32:42.738159 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.1 10.174.33.11:6804/161220 23 ==== osd_op_reply(107 >>> F7P3E755K6BN5LR3N85U [getxattrs,stat] ondisk = 0) v4 ==== 161+0+20 ( >>> 1539386342 0 1044315090) 0x7ffba40019c0 con 0xd908d0 >>> 2013-12-07 17:32:42.738341 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6804/161220 -- osd_op(client.95391.0:108 >>> F7P3E755K6BN5LR3N85U [getxattrs,stat] 7.62531457 e192) v4 -- ?+0 0x7ff >>> 794005c20 con 0xd908d0 >>> 2013-12-07 17:32:42.739230 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.1 10.174.33.11:6804/161220 24 ==== osd_op_reply(108 >>> F7P3E755K6BN5LR3N85U [getxattrs,stat] ondisk = 0) v4 ==== 161+0+20 ( >>> 1539386342 0 1044315090) 0x7ffba40019c0 con 0xd908d0 >>> 2013-12-07 17:32:42.739349 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6804/161220 -- osd_op(client.95391.0:109 >>> F7P3E755K6BN5LR3N85U [read 0~524288] 7.62531457 e192) v4 -- ?+0 0x7ff7 >>> 94005a90 con 0xd908d0 >>> 2013-12-07 17:32:42.740172 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.1 10.174.33.11:6804/161220 25 ==== osd_op_reply(109 >>> F7P3E755K6BN5LR3N85U [read 0~5] ondisk = 0) v4 ==== 119+0+5 (9359852 >>> 51 0 150087197) 0x7ffba40019c0 con 0xd908d0 >>> 2013-12-07 17:32:42.740310 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:110 0 >>> [getxattrs,stat] 6.f18a3536 e192) v4 -- ?+0 0x7ff794006570 con 0xd8b >>> 210 >>> 2013-12-07 17:32:42.741208 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 49 ==== osd_op_reply(110 0 >>> [getxattrs,stat] ondisk = 0) v4 ==== 142+0+91 (2507304458 0 349659 >>> 2801) 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.741326 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:111 0 >>> [getxattrs,stat] 6.f18a3536 e192) v4 -- ?+0 0x7ff7940063a0 con 0xd8b >>> 210 >>> 2013-12-07 17:32:42.742153 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 50 ==== osd_op_reply(111 0 >>> [getxattrs,stat] ondisk = 0) v4 ==== 142+0+91 (2507304458 0 349659 >>> 2801) 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.742268 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:112 0 [read >>> 0~524288] 6.f18a3536 e192) v4 -- ?+0 0x7ff7940063a0 con 0xd8b2 >>> 10 >>> 2013-12-07 17:32:42.743159 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 51 ==== osd_op_reply(112 0 [read >>> 0~254] ondisk = 0) v4 ==== 100+0+254 (3890176355 0 345783013 >>> 2) 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.743315 7ff79b1c6700 10 get_canon_resource(): dest= >>> 2013-12-07 17:32:42.743326 7ff79b1c6700 10 auth_hdr: >>> PUT >>> >>> >>> >>> x-amz-date:Sat, 07 Dec 2013 16:32:42 GMT >>> /test1/ >>> 2013-12-07 17:32:42.743459 7ff79b1c6700 2 req 1:0.007023:s3:PUT >>> /test1/:create_bucket:reading permissions >>> 2013-12-07 17:32:42.743475 7ff79b1c6700 2 req 1:0.007038:s3:PUT >>> /test1/:create_bucket:verifying op mask >>> 2013-12-07 17:32:42.743479 7ff79b1c6700 2 req 1:0.007043:s3:PUT >>> /test1/:create_bucket:verifying op permissions >>> 2013-12-07 17:32:42.743542 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:113 0.buckets >>> [omap-get-vals 0~16] 6.f01626b7 e192) v4 -- ?+0 0x7ff7940066 >>> 00 con 0xd8b210 >>> 2013-12-07 17:32:42.744679 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 52 ==== osd_op_reply(113 0.buckets >>> [omap-get-vals 0~16] ondisk = 0) v4 ==== 108+0+288 (360462 >>> 5994 0 2810901914) 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.744782 7ff79b1c6700 2 req 1:0.008346:s3:PUT >>> /test1/:create_bucket:verifying op params >>> 2013-12-07 17:32:42.744791 7ff79b1c6700 2 req 1:0.008355:s3:PUT >>> /test1/:create_bucket:executing >>> 2013-12-07 17:32:42.744895 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:114 test1 [call >>> version.read,getxattrs,stat] 4.bddbf0b9 e192) v4 -- ?+0 0x >>> 7ff7940083a0 con 0xd8b210 >>> 2013-12-07 17:32:42.745873 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 53 ==== osd_op_reply(114 test1 >>> [call,getxattrs,stat] ondisk = 0) v4 ==== 188+0+139 (2921607002 0 >>> 1685432528) 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.746052 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:115 test1 [call >>> version.check_conds,call version.read,read 0~524288] 4.bddbf0b9 e192) >>> v4 -- ?+0 0x7ff794008d80 con 0xd8b210 >>> 2013-12-07 17:32:42.746920 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 54 ==== osd_op_reply(115 test1 >>> [call,call,read 0~111] ondisk = 0) v4 ==== 188+0+159 (3524501340 0 >>> 3138215039) 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.747053 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.13:6800/296091 -- osd_op(client.95391.0:116 >>> .bucket.meta.test1:default.78189.1 [call version.read,getxattrs,stat] >>> 4.50558ec5 e192) v4 -- ?+0 0x7ff794007390 con 0xd8f710 >>> 2013-12-07 17:32:42.748661 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.24 10.174.33.13:6800/296091 19 ==== osd_op_reply(116 >>> .bucket.meta.test1:default.78189.1 [call,getxattrs,stat] ondisk = 0) >>> v4 ==== 217+0+268 (1054813027 0 2886434386) 0x7ffbac0057b0 con >>> 0xd8f710 >>> 2013-12-07 17:32:42.748815 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.13:6800/296091 -- osd_op(client.95391.0:117 >>> .bucket.meta.test1:default.78189.1 [call version.check_conds,call >>> version.read,read 0~524288] 4.50558ec5 e192) v4 -- ?+0 0x7ff79400c050 >>> con 0xd8f710 >>> 2013-12-07 17:32:42.750149 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.24 10.174.33.13:6800/296091 20 ==== osd_op_reply(117 >>> .bucket.meta.test1:default.78189.1 [call,call,read 0~147] ondisk = 0) >>> v4 ==== 217+0+195 (284787856 0 526624477) 0x7ffbac001f80 con 0xd8f710 >>> 2013-12-07 17:32:42.750245 7ff79b1c6700 0 WARNING: couldn't find acl >>> header for object, generating default >>> 2013-12-07 17:32:42.750288 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:118 0 >>> [getxattrs,stat] 6.f18a3536 e192) v4 -- ?+0 0x7ff794007670 con >>> 0xd8b210 >>> 2013-12-07 17:32:42.751004 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 55 ==== osd_op_reply(118 0 >>> [getxattrs,stat] ondisk = 0) v4 ==== 142+0+91 (2507304458 0 >>> 3496592801) 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.751107 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:119 0 >>> [getxattrs,stat] 6.f18a3536 e192) v4 -- ?+0 0x7ff794007650 con >>> 0xd8b210 >>> 2013-12-07 17:32:42.751786 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 56 ==== osd_op_reply(119 0 >>> [getxattrs,stat] ondisk = 0) v4 ==== 142+0+91 (2507304458 0 >>> 3496592801) 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.751881 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:120 0 [read >>> 0~524288] 6.f18a3536 e192) v4 -- ?+0 0x7ff794007000 con 0xd8b210 >>> 2013-12-07 17:32:42.752528 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 57 ==== osd_op_reply(120 0 [read >>> 0~254] ondisk = 0) v4 ==== 100+0+254 (3890176355 0 3457830132) >>> 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.752684 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6804/161220 -- osd_op(client.95391.0:121 >>> .dir.default.95391.1 [create 0~0,call rgw.bucket_init_index] >>> 8.5a45c1c6 e192) v4 -- ?+0 0x7ff794007540 con 0xd908d0 >>> 2013-12-07 17:32:42.756042 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.1 10.174.33.11:6804/161220 26 ==== osd_op_reply(121 >>> .dir.default.95391.1 [create 0~0,call] ack = 0) v4 ==== 161+0+0 >>> (905452886 0 0) 0x7ffba40019c0 con 0xd908d0 >>> 2013-12-07 17:32:42.756314 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:122 >>> .bucket.meta.test1:default.95391.1 [create 0~0,call >>> version.set,writefull 0~147,setxattr user.rgw.acl (109)] 4.e2d85709 >>> e192) v4 -- ?+0 0x7ff794007540 con 0xd8b210 >>> 2013-12-07 17:32:42.758993 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 58 ==== osd_op_reply(122 >>> .bucket.meta.test1:default.95391.1 [create 0~0,call,writefull >>> 0~147,setxattr (109)] ack = 0) v4 ==== 259+0+0 (1466420163 0 0) >>> 0x7ffbb4000a60 con 0xd8b210 >>> 2013-12-07 17:32:42.759256 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:123 test1 >>> [create 0~0,call version.set,writefull 0~111] 4.bddbf0b9 e192) v4 -- >>> ?+0 0x7ff794008960 con 0xd8b210 >>> 2013-12-07 17:32:42.760000 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 59 ==== osd_op_reply(123 test1 >>> [create 0~0,call,writefull 0~111] ondisk = -17 (File exists)) v4 ==== >>> 188+0+0 (208006733 0 0) 0x7ffbb4000a30 con 0xd8b210 >>> 2013-12-07 17:32:42.760144 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:124 test1 [call >>> version.read,getxattrs,stat] 4.bddbf0b9 e192) v4 -- ?+0 0x7ff794008490 >>> con 0xd8b210 >>> 2013-12-07 17:32:42.760924 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 60 ==== osd_op_reply(124 test1 >>> [call,getxattrs,stat] ondisk = 0) v4 ==== 188+0+139 (2921607002 0 >>> 1685432528) 0x7ffbb4000a30 con 0xd8b210 >>> 2013-12-07 17:32:42.761044 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:125 test1 >>> [getxattrs,stat] 4.bddbf0b9 e192) v4 -- ?+0 0x7ff794008100 con >>> 0xd8b210 >>> 2013-12-07 17:32:42.761759 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 61 ==== osd_op_reply(125 test1 >>> [getxattrs,stat] ondisk = 0) v4 ==== 146+0+91 (167695905 0 2385301879) >>> 0x7ffbb4000a30 con 0xd8b210 >>> 2013-12-07 17:32:42.761908 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.11:6802/225929 -- osd_op(client.95391.0:126 test1 [call >>> version.check_conds,call version.read,read 0~524288] 4.bddbf0b9 e192) >>> v4 -- ?+0 0x7ff79400c6e0 con 0xd8b210 >>> 2013-12-07 17:32:42.762712 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.0 10.174.33.11:6802/225929 62 ==== osd_op_reply(126 test1 >>> [call,call,read 0~111] ondisk = 0) v4 ==== 188+0+159 (3524501340 0 >>> 3138215039) 0x7ffbb4000a30 con 0xd8b210 >>> 2013-12-07 17:32:42.762848 7ff79b1c6700 1 -- 10.174.33.11:0/1270294 >>> --> 10.174.33.13:6800/296091 -- osd_op(client.95391.0:127 >>> .bucket.meta.test1:default.78189.1 [call version.check_conds,call >>> version.read,getxattrs,stat] 4.50558ec5 e192) v4 -- ?+0 0x7ff79400c6e0 >>> con 0xd8f710 >>> 2013-12-07 17:32:42.764173 7ffbd96ec700 1 -- 10.174.33.11:0/1270294 >>> <== osd.24 10.174.33.13:6800/296091 21 ==== osd_op_reply(127 >>> .bucket.meta.test1:default.78189.1 [call,call,getxattrs,stat] ondisk = >>> -125 (Operation canceled)) v4 ==== 259+0+0 (3728706219 0 0) >>> 0x7ffbac002090 con 0xd8f710 >>> 2013-12-07 17:32:42.764338 7ff79b1c6700 0 get_bucket_info returned -125 >>> 2013-12-07 17:32:42.764368 7ff79b1c6700 0 WARNING: set_req_state_err >>> err_no=125 resorting to 500 >>> 2013-12-07 17:32:42.764431 7ff79b1c6700 2 req 1:0.027995:s3:PUT >>> /test1/:create_bucket:http status=500 >>> 2013-12-07 17:32:42.764561 7ff79b1c6700 1 ====== req done >>> req=0xe60860 http_status=500 ====== >>> 2013-12-07 17:32:42.794156 7ffbd1ffb700 2 >>> RGWDataChangesLog::ChangesRenewThread: start >>> >>> ------------------- >>> >>> >>> -- >>> Regards >>> Dominik >>> >>> 2013/12/7 Yehuda Sadeh <yehuda@xxxxxxxxxxx>: >>>> Not sure what could be the reason. Can you turn set 'debug ms = 1', >>>> and 'debug rgw = 20'? >>>> >>>> Thanks, >>>> Yehuda >>>> >>>> On Sat, Dec 7, 2013 at 4:33 AM, Dominik Mostowiec >>>> <dominikmostowiec@xxxxxxxxx> wrote: >>>>>> Are you running on latest dumpling >>>>> Yes. It was installed, not upgraded from prev version. >>>>> This is new crated bucket. >>>>> I rebuilded cluster from scratch. >>>>> >>>>> User add command "radosgw-admin user create ..." shows: >>>>> WARNING: cannot read region map >>>>> >>>>> Create first time bucket 'test1' via 's3 -u craete test1' got error. >>>>> But in radosgw logs it seems ok: >>>>> ------- >>>>> 2013-12-07 10:40:04.328277 7f7746ff5700 1 ====== starting new request >>>>> req=0x1f5c7b0 ===== >>>>> 2013-12-07 10:40:04.328339 7f7746ff5700 2 req 2:0.000063::PUT >>>>> /test1/::initializing >>>>> 2013-12-07 10:40:04.328361 7f7746ff5700 10 meta>> HTTP_X_AMZ_DATE >>>>> 2013-12-07 10:40:04.328368 7f7746ff5700 10 x>> x-amz-date:Sat, 07 Dec >>>>> 2013 09:40:04 GMT >>>>> 2013-12-07 10:40:04.328383 7f7746ff5700 10 s->object=<NULL> s->bucket=test1 >>>>> 2013-12-07 10:40:04.328390 7f7746ff5700 2 req 2:0.000114:s3:PUT >>>>> /test1/::getting op >>>>> 2013-12-07 10:40:04.328394 7f7746ff5700 2 req 2:0.000118:s3:PUT >>>>> /test1/:create_bucket:authorizing >>>>> 2013-12-07 10:40:04.334424 7f7746ff5700 10 get_canon_resource(): dest= >>>>> 2013-12-07 10:40:04.334433 7f7746ff5700 10 auth_hdr: >>>>> PUT >>>>> >>>>> >>>>> >>>>> x-amz-date:Sat, 07 Dec 2013 09:40:04 GMT >>>>> /test1/ >>>>> 2013-12-07 10:40:04.334476 7f7746ff5700 2 req 2:0.006200:s3:PUT >>>>> /test1/:create_bucket:reading permissions >>>>> 2013-12-07 10:40:04.334481 7f7746ff5700 2 req 2:0.006205:s3:PUT >>>>> /test1/:create_bucket:verifying op mask >>>>> 2013-12-07 10:40:04.334483 7f7746ff5700 2 req 2:0.006207:s3:PUT >>>>> /test1/:create_bucket:verifying op permissions >>>>> 2013-12-07 10:40:04.335391 7f7746ff5700 2 req 2:0.007115:s3:PUT >>>>> /test1/:create_bucket:verifying op params >>>>> 2013-12-07 10:40:04.335402 7f7746ff5700 2 req 2:0.007126:s3:PUT >>>>> /test1/:create_bucket:executing >>>>> 2013-12-07 10:40:05.420459 7f7746ff5700 2 req 2:1.092182:s3:PUT >>>>> /test1/:create_bucket:http status=200 >>>>> 2013-12-07 10:40:05.420621 7f7746ff5700 1 ====== req done >>>>> req=0x1f5c7b0 http_status=200 ====== >>>>> ------- >>>>> >>>>> >>>>> Second try: >>>>> ------- >>>>> 2013-12-07 10:40:06.421876 7f778d95d780 10 allocated request req=0x1f5c710 >>>>> 2013-12-07 10:40:06.421936 7f77317ca700 1 ====== starting new request >>>>> req=0x1f5bc10 ===== >>>>> 2013-12-07 10:40:06.422038 7f77317ca700 2 req 3:0.000104::PUT >>>>> /test1/::initializing >>>>> 2013-12-07 10:40:06.422069 7f77317ca700 10 meta>> HTTP_X_AMZ_DATE >>>>> 2013-12-07 10:40:06.422080 7f77317ca700 10 x>> x-amz-date:Sat, 07 Dec >>>>> 2013 09:40:06 GMT >>>>> 2013-12-07 10:40:06.422101 7f77317ca700 10 s->object=<NULL> s->bucket=test1 >>>>> 2013-12-07 10:40:06.422110 7f77317ca700 2 req 3:0.000176:s3:PUT >>>>> /test1/::getting op >>>>> 2013-12-07 10:40:06.422117 7f77317ca700 2 req 3:0.000183:s3:PUT >>>>> /test1/:create_bucket:authorizing >>>>> 2013-12-07 10:40:06.429576 7f77317ca700 10 get_canon_resource(): dest= >>>>> 2013-12-07 10:40:06.429592 7f77317ca700 10 auth_hdr: >>>>> PUT >>>>> >>>>> >>>>> >>>>> x-amz-date:Sat, 07 Dec 2013 09:40:06 GMT >>>>> /test1/ >>>>> 2013-12-07 10:40:06.429679 7f77317ca700 2 req 3:0.007745:s3:PUT >>>>> /test1/:create_bucket:reading permissions >>>>> 2013-12-07 10:40:06.429690 7f77317ca700 2 req 3:0.007756:s3:PUT >>>>> /test1/:create_bucket:verifying op mask >>>>> 2013-12-07 10:40:06.429693 7f77317ca700 2 req 3:0.007759:s3:PUT >>>>> /test1/:create_bucket:verifying op permissions >>>>> 2013-12-07 10:40:06.430945 7f77317ca700 2 req 3:0.009010:s3:PUT >>>>> /test1/:create_bucket:verifying op params >>>>> 2013-12-07 10:40:06.430964 7f77317ca700 2 req 3:0.009030:s3:PUT >>>>> /test1/:create_bucket:executing >>>>> 2013-12-07 10:40:06.436301 7f77317ca700 0 WARNING: couldn't find acl >>>>> header for object, generating default >>>>> 2013-12-07 10:40:06.451160 7f77317ca700 0 get_bucket_info returned -125 >>>>> 2013-12-07 10:40:06.451188 7f77317ca700 0 WARNING: set_req_state_err >>>>> err_no=125 resorting to 500 >>>>> 2013-12-07 10:40:06.451223 7f77317ca700 2 req 3:0.029289:s3:PUT >>>>> /test1/:create_bucket:http status=500 >>>>> 2013-12-07 10:40:06.451335 7f77317ca700 1 ====== req done >>>>> req=0x1f5bc10 http_status=500 ====== >>>>> ------- >>>>> >>>>> ceph -s >>>>> cluster 92833861-954f-4c66-a72b-5a83090a2b3f >>>>> health HEALTH_OK >>>>> >>>>> ceph -v >>>>> ceph version 0.67.4 (ad85b8bfafea6232d64cb7ba76a8b6e8252fa0c7) >>>>> >>>>> I have strange behavior after cluster create. >>>>> All PGs were on osd.0 and marked as stale degraded. >>>>> After add more osd's it didn't ifxed. >>>>> "ceph pg force_create_pg" helps. >>>>> >>>>> After setup cluster I changed crushmap. >>>>> Removed osd.0 and added it again to cluster. >>>>> >>>>> It can be a reason? >>>>> >>>>> ---- >>>>> Regards >>>>> Dominik >>>>> >>>>> 2013/12/6 Yehuda Sadeh <yehuda@xxxxxxxxxxx>: >>>>>> I'm having trouble reproducing this one. Are you running on latest >>>>>> dumpling? Does it happen with any newly created bucket, or just with >>>>>> buckets that existed before? >>>>>> >>>>>> Yehuda >>>>>> >>>>>> On Fri, Dec 6, 2013 at 5:07 AM, Dominik Mostowiec >>>>>> <dominikmostowiec@xxxxxxxxx> wrote: >>>>>>> Hi, >>>>>>> In version dumpling upgraded from bobtail working create the same bucket. >>>>>>> >>>>>>> root@vm-1:/etc/apache2/sites-enabled# s3 -u create testcreate >>>>>>> Bucket successfully created. >>>>>>> root@vm-1:/etc/apache2/sites-enabled# s3 -u create testcreate >>>>>>> Bucket successfully created. >>>>>>> >>>>>>> I installed new dumpling cluster and: >>>>>>> root@s1:/var/log/radosgw# s3 -u create test1 >>>>>>> Bucket successfully created. >>>>>>> root@s1:/var/log/radosgw# s3 -u create test1 >>>>>>> >>>>>>> ERROR: ErrorUnknown >>>>>>> >>>>>>> In radosgw logs: >>>>>>> >>>>>>> 2013-12-06 13:59:56.083109 7f162d7c2700 1 ====== starting new request >>>>>>> req=0xb7d480 ===== >>>>>>> 2013-12-06 13:59:56.083227 7f162d7c2700 2 req 5:0.000119::PUT >>>>>>> /test1/::initializing >>>>>>> 2013-12-06 13:59:56.083261 7f162d7c2700 10 meta>> HTTP_X_AMZ_DATE >>>>>>> 2013-12-06 13:59:56.083274 7f162d7c2700 10 x>> x-amz-date:Fri, 06 Dec >>>>>>> 2013 12:59:56 GMT >>>>>>> 2013-12-06 13:59:56.083298 7f162d7c2700 10 s->object=<NULL> s->bucket=test1 >>>>>>> 2013-12-06 13:59:56.083307 7f162d7c2700 2 req 5:0.000199:s3:PUT >>>>>>> /test1/::getting op >>>>>>> 2013-12-06 13:59:56.083315 7f162d7c2700 2 req 5:0.000207:s3:PUT >>>>>>> /test1/:create_bucket:authorizing >>>>>>> 2013-12-06 13:59:56.091724 7f162d7c2700 10 get_canon_resource(): dest= >>>>>>> 2013-12-06 13:59:56.091742 7f162d7c2700 10 auth_hdr: >>>>>>> PUT >>>>>>> >>>>>>> >>>>>>> >>>>>>> x-amz-date:Fri, 06 Dec 2013 12:59:56 GMT >>>>>>> /test1/ >>>>>>> 2013-12-06 13:59:56.091836 7f162d7c2700 2 req 5:0.008728:s3:PUT >>>>>>> /test1/:create_bucket:reading permissions >>>>>>> 2013-12-06 13:59:56.091848 7f162d7c2700 2 req 5:0.008740:s3:PUT >>>>>>> /test1/:create_bucket:verifying op mask >>>>>>> 2013-12-06 13:59:56.091852 7f162d7c2700 2 req 5:0.008744:s3:PUT >>>>>>> /test1/:create_bucket:verifying op permissions >>>>>>> 2013-12-06 13:59:56.093858 7f162d7c2700 2 req 5:0.010750:s3:PUT >>>>>>> /test1/:create_bucket:verifying op params >>>>>>> 2013-12-06 13:59:56.093882 7f162d7c2700 2 req 5:0.010773:s3:PUT >>>>>>> /test1/:create_bucket:executing >>>>>>> 2013-12-06 13:59:56.104819 7f162d7c2700 0 WARNING: couldn't find acl >>>>>>> header for object, generating default >>>>>>> 2013-12-06 13:59:56.132625 7f162d7c2700 0 get_bucket_info returned -125 >>>>>>> 2013-12-06 13:59:56.132656 7f162d7c2700 0 WARNING: set_req_state_err >>>>>>> err_no=125 resorting to 500 >>>>>>> 2013-12-06 13:59:56.132693 7f162d7c2700 2 req 5:0.049584:s3:PUT >>>>>>> /test1/:create_bucket:http status=500 >>>>>>> 2013-12-06 13:59:56.132890 7f162d7c2700 1 ====== req done >>>>>>> req=0xb7d480 http_status=500 ====== >>>>>>> >>>>>>> -- >>>>>>> Regards >>>>>>> Dominik >>>>>>> _______________________________________________ >>>>>>> ceph-users mailing list >>>>>>> ceph-users@xxxxxxxxxxxxxx >>>>>>> http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com >>>>> >>>>> >>>>> >>>>> -- >>>>> Pozdrawiam >>>>> Dominik >>> >>> >>> >>> -- >>> Pozdrawiam >>> Dominik > > > > -- > Pozdrawiam > Dominik -- Pozdrawiam Dominik _______________________________________________ ceph-users mailing list ceph-users@xxxxxxxxxxxxxx http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com
 |