Looks like there is one already. http://tracker.ceph.com/issues/21259 On Tue, Oct 3, 2017 at 1:15 AM, Gregory Farnum <gfarnum@xxxxxxxxxx> wrote: > Please file a tracker ticket with all the info you have for stuff like this. > They’re a lot harder to lose than emails are. ;) > > On Sat, Sep 30, 2017 at 8:31 AM Marc Roos <M.Roos@xxxxxxxxxxxxxxxxx> wrote: >> >> Is this useful for someone? >> >> >> >> [Sat Sep 30 15:51:11 2017] libceph: osd5 192.168.10.113:6809 socket >> closed (con state OPEN) >> [Sat Sep 30 15:51:11 2017] libceph: osd5 192.168.10.113:6809 socket >> closed (con state CONNECTING) >> [Sat Sep 30 15:51:11 2017] libceph: osd5 down >> [Sat Sep 30 15:51:11 2017] libceph: osd5 down >> [Sat Sep 30 15:52:52 2017] libceph: osd5 up >> [Sat Sep 30 15:52:52 2017] libceph: osd5 up >> >> >> >> 2017-09-30 15:48:08.542202 7f7623ce9700 0 log_channel(cluster) log >> [WRN] : slow request 31.456482 seconds old, received at 2017-09-30 >> 15:47:37.085589: osd_op(mds.0.9227:1289186 20.2b 20.9af42b6b (undecoded) >> ondisk+write+known_if_redirected+full_force e15675) currently >> queued_for_pg >> 2017-09-30 15:48:08.542207 7f7623ce9700 0 log_channel(cluster) log >> [WRN] : slow request 31.456086 seconds old, received at 2017-09-30 >> 15:47:37.085984: osd_op(mds.0.9227:1289190 20.13 20.e44f3f53 (undecoded) >> ondisk+write+known_if_redirected+full_force e15675) currently >> queued_for_pg >> 2017-09-30 15:48:08.542212 7f7623ce9700 0 log_channel(cluster) log >> [WRN] : slow request 31.456005 seconds old, received at 2017-09-30 >> 15:47:37.086065: osd_op(mds.0.9227:1289194 20.2b 20.6733bdeb (undecoded) >> ondisk+write+known_if_redirected+full_force e15675) currently >> queued_for_pg >> 2017-09-30 15:51:12.592490 7f7611cc5700 0 log_channel(cluster) log >> [DBG] : 20.3f scrub starts >> 2017-09-30 15:51:24.514602 7f76214e4700 -1 *** Caught signal >> (Segmentation fault) ** >> in thread 7f76214e4700 thread_name:bstore_mempool >> >> ceph version 12.2.1 (3e7492b9ada8bdc9a5cd0feafd42fbca27f9c38e) luminous >> (stable) >> 1: (()+0xa29511) [0x7f762e5b2511] >> 2: (()+0xf370) [0x7f762afa5370] >> 3: (BlueStore::TwoQCache::_trim(unsigned long, unsigned long)+0x2df) >> [0x7f762e481a2f] >> 4: (BlueStore::Cache::trim(unsigned long, float, float, float)+0x1d1) >> [0x7f762e4543e1] >> 5: (BlueStore::MempoolThread::entry()+0x14d) [0x7f762e45a71d] >> 6: (()+0x7dc5) [0x7f762af9ddc5] >> 7: (clone()+0x6d) [0x7f762a09176d] >> NOTE: a copy of the executable, or `objdump -rdS <executable>` is >> needed to interpret this. >> >> --- begin dump of recent events --- >> -10000> 2017-09-30 15:51:05.105915 7f76284ac700 5 -- >> 192.168.10.113:0/27661 >> 192.168.10.111:6810/6617 conn(0x7f766b736000 >> :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=19 cs=1 l=1). rx >> osd.0 seq 19546 0x7f76a2daf000 osd_ping(ping_reply e15675 stamp >> 2017-09-30 15:51:05.105439) v4 >> -9999> 2017-09-30 15:51:05.105963 7f760fcc1700 1 -- 10.0.0.13:0/27661 >> --> 10.0.0.11:6805/6491 -- osd_ping(ping e15675 stamp 2017-09-30 >> 15:51:05.105439) v4 -- 0x7f7683e98a00 con 0 >> -9998> 2017-09-30 15:51:05.105960 7f76284ac700 1 -- >> 192.168.10.113:0/27661 <== osd.0 192.168.10.111:6810/6617 19546 ==== >> osd_ping(ping_reply e15675 stamp 2017-09-30 15:51:05.105439) v4 ==== >> 2004+0+0 (1212154800 0 0) 0x7f76a2daf000 con 0x7f766b736000 >> -9997> 2017-09-30 15:51:05.105961 7f76274aa700 5 -- 10.0.0.13:0/27661 >> >> 10.0.0.11:6808/6646 conn(0x7f766b745800 :-1 >> s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=24 cs=1 l=1). rx osd.3 >> seq 19546 0x7f769b95f200 osd_ping(ping_reply e15675 stamp 2017-09-30 >> 15:51:05.105439) v4 >> -9996> 2017-09-30 15:51:05.105983 7f760fcc1700 1 -- >> 192.168.10.113:0/27661 --> 192.168.10.111:6805/6491 -- osd_ping(ping >> e15675 stamp 2017-09-30 15:51:05.105439) v4 -- 0x7f7683e97600 con 0 >> -9995> 2017-09-30 15:51:05.106001 7f76274aa700 1 -- 10.0.0.13:0/27661 >> <== osd.3 10.0.0.11:6808/6646 19546 ==== osd_ping(ping_reply e15675 >> stamp 2017-09-30 15:51:05.105439) v4 ==== 2004+0+0 (1212154800 0 0) >> 0x7f769b95f200 con 0x7f766b745800 >> -9994> 2017-09-30 15:51:05.106015 7f760fcc1700 1 -- 10.0.0.13:0/27661 >> --> 10.0.0.11:6807/6470 -- osd_ping(ping e15675 stamp 2017-09-30 >> 15:51:05.105439) v4 -- 0x7f7683e99800 con 0 >> -9993> 2017-09-30 15:51:05.106035 7f760fcc1700 1 -- >> 192.168.10.113:0/27661 --> 192.168.10.111:6808/6470 -- osd_ping(ping >> e15675 stamp 2017-09-30 15:51:05.105439) v4 -- 0x7f763b72a200 con 0 >> -9992> 2017-09-30 15:51:05.106072 7f760fcc1700 1 -- 10.0.0.13:0/27661 >> --> 10.0.0.11:6809/6710 -- osd_ping(ping e15675 stamp 2017-09-30 >> 15:51:05.105439) v4 -- 0x7f768633dc00 con 0 >> -9991> 2017-09-30 15:51:05.106093 7f760fcc1700 1 -- >> 192.168.10.113:0/27661 --> 192.168.10.111:6804/6710 -- osd_ping(ping >> e15675 stamp 2017-09-30 15:51:05.105439) v4 -- 0x7f76667d3600 con 0 >> -9990> 2017-09-30 15:51:05.106114 7f760fcc1700 1 -- 10.0.0.13:0/27661 >> --> 10.0.0.12:6805/1949 -- osd_ping(ping e15675 stamp 2017-09-30 >> 15:51:05.105439) v4 -- 0x7f768fcd6200 con 0 >> -9989> 2017-09-30 15:51:05.106134 7f760fcc1700 1 -- >> 192.168.10.113:0/27661 --> 192.168.10.112:6805/1949 -- osd_ping(ping >> e15675 stamp 2017-09-30 15:51:05.105439) v4 -- 0x7f765f27a800 con 0 >> >> >> ... >> >> >> -29> 2017-09-30 15:51:24.469620 7f7611cc5700 1 -- >> 10.0.0.13:6808/27661 --> 10.0.0.12:6800/1947 -- replica scrub(pg: >> 20.3f,from:0'0,to:0'0,epoch:15675/15644,start:20:fc9e27a6:::100008d7d4e. >> 000000cd:0,end:20:fc9e74a2:::100006a91fc.00000000:0,chunky:1,deep:0,seed >> :4294967295,version:7) v7 -- 0x7f76514c1600 con 0 >> -28> 2017-09-30 15:51:24.474230 7f76284ac700 5 -- >> 10.0.0.13:6808/27661 >> 10.0.0.12:6800/1947 conn(0x7f76831ac000 :6808 >> s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=4 cs=1 l=0). rx osd.10 >> seq 28454 0x7f77072a1480 rep_scrubmap(20.3f e15675 from shard 10) v1 >> -27> 2017-09-30 15:51:24.474277 7f76284ac700 1 -- >> 10.0.0.13:6808/27661 <== osd.10 10.0.0.12:6800/1947 28454 ==== >> rep_scrubmap(20.3f e15675 from shard 10) v1 ==== 39+0+18285 (2345442504 >> 0 4245554900) 0x7f77072a1480 con 0x7f76831ac000 >> -26> 2017-09-30 15:51:24.476534 7f7627cab700 5 -- >> 10.0.0.13:6808/27661 >> 10.0.0.11:6800/6617 conn(0x7f766bbbe000 :-1 >> s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=23 cs=1 l=0). rx osd.0 >> seq 153542 0x7f76b1689180 rep_scrubmap(20.3f e15675 from shard 0) v1 >> -25> 2017-09-30 15:51:24.476573 7f7627cab700 1 -- >> 10.0.0.13:6808/27661 <== osd.0 10.0.0.11:6800/6617 153542 ==== >> rep_scrubmap(20.3f e15675 from shard 0) v1 ==== 39+0+18285 (1049184257 0 >> 4245554900) 0x7f76b1689180 con 0x7f766bbbe000 >> -24> 2017-09-30 15:51:24.477065 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps osd.5 has 25 items >> -23> 2017-09-30 15:51:24.477097 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps replica 0 has 25 items >> -22> 2017-09-30 15:51:24.477113 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps replica 10 has 25 items >> -21> 2017-09-30 15:51:24.478038 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] >> -20> 2017-09-30 15:51:24.483045 7f7611cc5700 1 -- >> 10.0.0.13:6808/27661 --> 10.0.0.11:6800/6617 -- replica scrub(pg: >> 20.3f,from:0'0,to:15675'635985,epoch:15675/15644,start:20:fc9e74a2:::100 >> 006a91fc.00000000:0,end:20:fc9ea723:::100000f77c1.00000000:0,chunky:1,de >> ep:0,seed:4294967295,version:7) v7 -- 0x7f76514c18c0 con 0 >> -19> 2017-09-30 15:51:24.483103 7f7611cc5700 1 -- >> 10.0.0.13:6808/27661 --> 10.0.0.12:6800/1947 -- replica scrub(pg: >> 20.3f,from:0'0,to:15675'635985,epoch:15675/15644,start:20:fc9e74a2:::100 >> 006a91fc.00000000:0,end:20:fc9ea723:::100000f77c1.00000000:0,chunky:1,de >> ep:0,seed:4294967295,version:7) v7 -- 0x7f76514c1b80 con 0 >> -18> 2017-09-30 15:51:24.487785 7f7627cab700 5 -- >> 10.0.0.13:6808/27661 >> 10.0.0.11:6800/6617 conn(0x7f766bbbe000 :-1 >> s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=23 cs=1 l=0). rx osd.0 >> seq 153543 0x7f76b1689900 rep_scrubmap(20.3f e15675 from shard 0) v1 >> -17> 2017-09-30 15:51:24.487816 7f7627cab700 1 -- >> 10.0.0.13:6808/27661 <== osd.0 10.0.0.11:6800/6617 153543 ==== >> rep_scrubmap(20.3f e15675 from shard 0) v1 ==== 39+0+17701 (1049184257 0 >> 1206823045) 0x7f76b1689900 con 0x7f766bbbe000 >> -16> 2017-09-30 15:51:24.487832 7f76284ac700 5 -- >> 10.0.0.13:6808/27661 >> 10.0.0.12:6800/1947 conn(0x7f76831ac000 :6808 >> s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=4 cs=1 l=0). rx osd.10 >> seq 28455 0x7f77072a1200 rep_scrubmap(20.3f e15675 from shard 10) v1 >> -15> 2017-09-30 15:51:24.487869 7f76284ac700 1 -- >> 10.0.0.13:6808/27661 <== osd.10 10.0.0.12:6800/1947 28455 ==== >> rep_scrubmap(20.3f e15675 from shard 10) v1 ==== 39+0+17701 (2345442504 >> 0 1206823045) 0x7f77072a1200 con 0x7f76831ac000 >> -14> 2017-09-30 15:51:24.488423 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps osd.5 has 25 items >> -13> 2017-09-30 15:51:24.488451 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps replica 0 has 25 items >> -12> 2017-09-30 15:51:24.488466 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps replica 10 has 25 items >> -11> 2017-09-30 15:51:24.489370 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] >> -10> 2017-09-30 15:51:24.493346 7f7611cc5700 1 -- >> 10.0.0.13:6808/27661 --> 10.0.0.11:6800/6617 -- replica scrub(pg: >> 20.3f,from:0'0,to:15675'635865,epoch:15675/15644,start:20:fc9ea723:::100 >> 000f77c1.00000000:0,end:20:fc9edf2c:::100008c400a.000005af:0,chunky:1,de >> ep:0,seed:4294967295,version:7) v7 -- 0x7f76514c1e40 con 0 >> -9> 2017-09-30 15:51:24.493400 7f7611cc5700 1 -- >> 10.0.0.13:6808/27661 --> 10.0.0.12:6800/1947 -- replica scrub(pg: >> 20.3f,from:0'0,to:15675'635865,epoch:15675/15644,start:20:fc9ea723:::100 >> 000f77c1.00000000:0,end:20:fc9edf2c:::100008c400a.000005af:0,chunky:1,de >> ep:0,seed:4294967295,version:7) v7 -- 0x7f76514c2100 con 0 >> -8> 2017-09-30 15:51:24.497410 7f76284ac700 5 -- >> 10.0.0.13:6808/27661 >> 10.0.0.12:6800/1947 conn(0x7f76831ac000 :6808 >> s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=4 cs=1 l=0). rx osd.10 >> seq 28456 0x7f77072a0f80 rep_scrubmap(20.3f e15675 from shard 10) v1 >> -7> 2017-09-30 15:51:24.497456 7f76284ac700 1 -- >> 10.0.0.13:6808/27661 <== osd.10 10.0.0.12:6800/1947 28456 ==== >> rep_scrubmap(20.3f e15675 from shard 10) v1 ==== 39+0+20357 (2345442504 >> 0 574503752) 0x7f77072a0f80 con 0x7f76831ac000 >> -6> 2017-09-30 15:51:24.499268 7f7627cab700 5 -- >> 10.0.0.13:6808/27661 >> 10.0.0.11:6800/6617 conn(0x7f766bbbe000 :-1 >> s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=23 cs=1 l=0). rx osd.0 >> seq 153544 0x7f767d915700 rep_scrubmap(20.3f e15675 from shard 0) v1 >> -5> 2017-09-30 15:51:24.499306 7f7627cab700 1 -- >> 10.0.0.13:6808/27661 <== osd.0 10.0.0.11:6800/6617 153544 ==== >> rep_scrubmap(20.3f e15675 from shard 0) v1 ==== 39+0+20357 (1049184257 0 >> 574503752) 0x7f767d915700 con 0x7f766bbbe000 >> -4> 2017-09-30 15:51:24.499581 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps osd.5 has 25 items >> -3> 2017-09-30 15:51:24.499613 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps replica 0 has 25 items >> -2> 2017-09-30 15:51:24.499629 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] scrub_compare_maps replica 10 has 25 items >> -1> 2017-09-30 15:51:24.500587 7f7611cc5700 2 osd.5 pg_epoch: 15675 >> pg[20.3f( v 15675'636579 (15675'635005,15675'636579] >> local-lis/les=15644/15645 n=120537 ec=3902/3902 lis/c 15644/15644 >> les/c/f 15645/15669/0 15644/15644/14120) [5,0,10] r=0 lpr=15644 >> crt=15675'636579 lcod 15675'636578 mlcod 15675'636578 >> active+clean+scrubbing] >> 0> 2017-09-30 15:51:24.514602 7f76214e4700 -1 *** Caught signal >> (Segmentation fault) ** >> in thread 7f76214e4700 thread_name:bstore_mempool >> >> ceph version 12.2.1 (3e7492b9ada8bdc9a5cd0feafd42fbca27f9c38e) luminous >> (stable) >> 1: (()+0xa29511) [0x7f762e5b2511] >> 2: (()+0xf370) [0x7f762afa5370] >> 3: (BlueStore::TwoQCache::_trim(unsigned long, unsigned long)+0x2df) >> [0x7f762e481a2f] >> 4: (BlueStore::Cache::trim(unsigned long, float, float, float)+0x1d1) >> [0x7f762e4543e1] >> 5: (BlueStore::MempoolThread::entry()+0x14d) [0x7f762e45a71d] >> 6: (()+0x7dc5) [0x7f762af9ddc5] >> 7: (clone()+0x6d) [0x7f762a09176d] >> NOTE: a copy of the executable, or `objdump -rdS <executable>` is >> needed to interpret this. >> >> --- logging levels --- >> 0/ 5 rados >> 0/ 5 rbd >> 0/ 5 rbd_mirror >> 0/ 5 rbd_replay >> 0/ 5 journaler >> 0/ 5 objectcacher >> >> 4/ 5 leveldb >> 4/ 5 memdb >> 1/ 5 kinetic >> 1/ 5 fuse >> 1/ 5 mgr >> 1/ 5 mgrc >> 1/ 5 dpdk >> 1/ 5 eventtrace >> -2/-2 (syslog threshold) >> -1/-1 (stderr threshold) >> max_recent 10000 >> max_new 1000 >> log_file /var/log/ceph/ceph-osd.5.log >> --- end dump of recent events --- >> 2017-09-30 15:51:44.957962 7f045fa43d00 -1 WARNING: the following >> dangerous and experimental features are enabled: bluestore >> 2017-09-30 15:51:44.957982 7f045fa43d00 0 set uid:gid to 167:167 >> (ceph:ceph) >> 2017-09-30 15:51:44.958002 7f045fa43d00 0 ceph version 12.2.1 >> (3e7492b9ada8bdc9a5cd0feafd42fbca27f9c38e) luminous (stable), process >> (unknown), pid 11398 >> 2017-09-30 15:51:44.962184 7f045fa43d00 0 pidfile_write: ignore empty >> --pid-file >> 2017-09-30 15:51:44.997185 7f045fa43d00 -1 WARNING: the following >> dangerous and experimental features are enabled: bluestore >> >> _______________________________________________ >> ceph-users mailing list >> ceph-users@xxxxxxxxxxxxxx >> http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com > > > _______________________________________________ > ceph-users mailing list > ceph-users@xxxxxxxxxxxxxx > http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com > -- Cheers, Brad _______________________________________________ ceph-users mailing list ceph-users@xxxxxxxxxxxxxx http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com
 |