Re: Ceph Giant not fixed RepllicatedPG:NotStrimming?

[Ta Ba Tuan <tuantb-QlevPasa8l681eZEIcUDRw@public.gmane.org>]

[-- Attachment #1.1: Type: text/plain, Size: 16406 bytes --]

Hi David,

I re-uploaded entire log at http://123.30.41.138/ceph-osd.21.log

appear many many logs :|
2014-11-04 18:24:38.641529 7f0fda7ac780 15 read_log missing 
106395'4837671 (106395'4837670) modify 
5479e128/rbd_data.74ae9c3be03aff.0000000000000b01/head//6 by 
client.7912580.0:19413835 2014-11-04 15:06:55.874814 (have 106384'4836070)
2014-11-04 18:24:38.641581 7f0fda7ac780 15 
filestore(/var/lib/ceph/osd/cloud-21) getattr 
6.128_head/573c4128/rbd_data.75551c509c613c.0000000000000780/head//6 '_'
...

Thank you
--
Tuan
HaNoi-VietNam

On 11/05/2014 11:36 AM, David Zafman wrote:
>
> Can you upload the entire log file?
>
> David
>
>> On Nov 4, 2014, at 1:03 AM, Ta Ba Tuan <tuantb-QlevPasa8l681eZEIcUDRw@public.gmane.org 
>> <mailto:tuantb-QlevPasa8l681eZEIcUDRw@public.gmane.org>> wrote:
>>
>> Hi Sam,
>> I resend logs with debug options http://123.30.41.138/ceph-osd.21.log
>> (Sorry about my spam :D)
>>
>> I saw many missing objects :|
>>
>> 2014-11-04 15:26:02.205607 7f3ab11a8700 10 osd.21 pg_epoch: 106407 
>> pg[24.7d7( v 106407'491583 lc 106401'491579 
>> (105805'487042,106407'491583] loca
>> l-les=106403 n=179 ec=25000 les/c 106403/106390 106402/106402/106402) 
>> [21,28,4] r=0 lpr=106402 pi=106377-106401/4 rops=1 crt=106401'491581 
>> mlcod
>> 106393'491097 active+recovering+degraded m=2 snaptrimq=[306~1,312~1]] 
>> recover_primary 
>> 675ea7d7/*rbd_data.4930222ae8944a.0000000000000001/head//24 
>> 106401'491580 (missing) (missing head) (recovering) (recovering head)*
>> 2014-11-04 15:26:02.205642 7f3ab11a8700 10 osd.21 pg_epoch: 106407 
>> pg[24.7d7( v 106407'491583 lc 106401'491579 
>> (105805'487042,106407'491583] local-les=106403 n=179 ec=25000 les/c 
>> 106403/106390 106402/106402/106402) [21,28,4] r=0 lpr=106402 
>> pi=106377-106401/4 rops=1 crt=106401'491581 mlcod 106393'491097 
>> active+recovering+degraded m=2 snaptrimq=[306~1,312~1]] 
>> recover_primary 
>> d4d4bfd7/rbd_data.c6964d30a28220.000000000000035f/head//24 
>> 106401'491581 (missing) (missing head)
>> 2014-11-04 15:26:02.237994 7f3ab29ab700 10 osd.21 pg_epoch: 106407 
>> pg[24.7d7( v 106407'491583 lc 106401'491579 
>> (105805'487042,106407'491583] local-les=106403 n=179 ec=25000 les/c 
>> 106403/106390 106402/106402/106402) [21,28,4] r=0 lpr=106402 
>> pi=106377-106401/4 rops=2 crt=106401'491581 mlcod 106393'491097 
>> active+recovering+degraded m=2 snaptrimq=[306~1,312~1]] *got missing 
>> d4d4bfd7/rbd_data.c6964d30a28220.000000000000035f/head//24 v 
>> 106401'491581*
>>
>> Thanks Sam and All,
>> --
>> Tuan
>> HaNoi-Vietnam
>>
>> On 11/04/2014 04:54 AM, Samuel Just wrote:
>>> Can you reproduce with
>>>
>>> debug osd = 20
>>> debug filestore = 20
>>> debug ms = 1
>>>
>>> In the [osd] section of that osd's ceph.conf?
>>> -Sam
>>>
>>> On Sun, Nov 2, 2014 at 9:10 PM, Ta Ba Tuan<tuantb-QlevPasa8l681eZEIcUDRw@public.gmane.org>  wrote:
>>>> Hi Sage, Samuel & All,
>>>>
>>>> I upgraded to GAINT, but still appearing that errors |:
>>>> I'm trying on deleting  related objects/volumes, but very hard to verify
>>>> missing objects :(.
>>>>
>>>> Guide me to resolve it, please! (I send attached detail log).
>>>>
>>>> 2014-11-03 11:37:57.730820 7f28fb812700  0 osd.21 105950 do_command r=0
>>>> 2014-11-03 11:37:57.856578 7f28fc013700 -1 *** Caught signal (Segmentation
>>>> fault) **
>>>>   in thread 7f28fc013700
>>>>
>>>>   ceph version 0.87-6-gdba7def (dba7defc623474ad17263c9fccfec60fe7a439f0)
>>>>   1: /usr/bin/ceph-osd() [0x9b6725]
>>>>   2: (()+0xfcb0) [0x7f291fc2acb0]
>>>>   3: (ReplicatedPG::trim_object(hobject_t const&)+0x395) [0x811b55]
>>>>   4: (ReplicatedPG::TrimmingObjects::react(ReplicatedPG::SnapTrim
>>>> const&)+0x43e) [0x82b9be]
>>>>   5: (boost::statechart::simple_state<ReplicatedPG::TrimmingObjects,
>>>> ReplicatedPG::SnapTrimmer, boost::mpl::list<mpl_::na, mpl_::na, mpl_::na,
>>>> mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na,
>>>> mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na,
>>>> mpl_::na, mpl_::na, mpl_::na>,
>>>> (boost::statechart::history_mode)0>::react_impl(boost::statechart::event_base
>>>> const&, void const*)+0xc0) [0x870ce0]
>>>>   6: (boost::statechart::state_machine<ReplicatedPG::SnapTrimmer,
>>>> ReplicatedPG::NotTrimming, std::allocator<void>,
>>>> boost::statechart::null_exception_translator>::process_queued_events()+0xfb)
>>>> [0x85618b]
>>>>   7: (boost::statechart::state_machine<ReplicatedPG::SnapTrimmer,
>>>> ReplicatedPG::NotTrimming, std::allocator<void>,
>>>> boost::statechart::null_exception_translator>::process_event(boost::statechart::event_base
>>>> const&)+0x1e) [0x85633e]
>>>>   8: (ReplicatedPG::snap_trimmer()+0x4f8) [0x7d5ef8]
>>>>   9: (OSD::SnapTrimWQ::_process(PG*)+0x14) [0x673ab4]
>>>>   10: (ThreadPool::worker(ThreadPool::WorkThread*)+0x48e) [0xa8fade]
>>>>   11: (ThreadPool::WorkThread::entry()+0x10) [0xa92870]
>>>>   12: (()+0x7e9a) [0x7f291fc22e9a]
>>>>   13: (clone()+0x6d) [0x7f291e5ed31d]
>>>>   NOTE: a copy of the executable, or `objdump -rdS <executable>` is needed to
>>>> interpret this.
>>>>
>>>>   -9993> 2014-11-03 11:37:47.689335 7f28fc814700  1 -- 172.30.5.2:6803/7606
>>>> --> 172.30.5.1:6886/3511 -- MOSDPGPull(6.58e 105950
>>>> [PullOp(87f82d8e/rbd_data.45e62779c99cf1.00000000000022b5/head//6,
>>>> recovery_info:
>>>> ObjectRecoveryInfo(87f82d8e/rbd_data.45e62779c99cf1.00000000000022b5/head//6@105938'11622009,
>>>> copy_subset: [0~18446744073709551615], clone_subset: {}), recovery_progress:
>>>> ObjectRecoveryProgress(first, data_recovered_to:0, data_complete:false,
>>>> omap_recovered_to:, omap_complete:false))]) v2 -- ?+0 0x26c59000 con
>>>> 0x22fbc420
>>>> ....
>>>>      -2> 2014-11-03 11:37:57.853585 7f2902820700  5 osd.21 pg_epoch: 105950
>>>> pg[24.9e4( v 105946'113392 lc 105946'113391 (103622'109598,105946'113392]
>>>> local-les=1
>>>> 05948 n=88 ec=25000 les/c 105948/105943 105947/105947/105947) [21,112,33]
>>>> r=0 lpr=105947 pi=105933-105946/4 crt=105946'113392 lcod 0'0 mlcod 0'0
>>>> active+recovery
>>>> _wait+degraded m=1 snaptrimq=[303~3,307~1]] enter
>>>> Started/Primary/Active/Recovering
>>>>      -1> 2014-11-03 11:37:57.853735 7f28fc814700  1 -- 172.30.5.2:6803/7606
>>>> --> 172.30.5.9:6806/24552 -- MOSDPGPull(24.9e4 105950
>>>> [PullOp(5abb99e4/rbd_data.5dd32
>>>> f2ae8944a.0000000000000165/head//24, recovery_info:
>>>> ObjectRecoveryInfo(5abb99e4/rbd_data.5dd32f2ae8944a.0000000000000165/head//24@105946'113392,
>>>> copy_subset: [0
>>>> ~18446744073709551615], clone_subset: {}), recovery_progress:
>>>> ObjectRecoveryProgress(first, data_recovered_to:0, data_complete:false,
>>>> omap_recovered_to:, omap_c
>>>> omplete:false))]) v2 -- ?+0 0x229e7e00 con 0x22fb7000
>>>>       0> 2014-11-03 11:37:57.856578 7f28fc013700 -1 *** Caught signal
>>>> (Segmentation fault) **
>>>>
>>>> Thanks!
>>>> --
>>>> Tuan
>>>> HaNoi-VietNam
>>>>
>>>>
>>>>
>>>>
>>>> On 11/01/2014 09:21 AM, Ta Ba Tuan wrote:
>>>>
>>>> Hi Samuel and Sage,
>>>>
>>>> I will upgrde to Giant soon, Thank you so much.
>>>>
>>>> --
>>>> Tuan
>>>> HaNoi-VietNam
>>>>
>>>> On 11/01/2014 01:10 AM, Samuel Just wrote:
>>>>
>>>> You should start by upgrading to giant, many many bug fixes went in
>>>> between .86 and giant.
>>>> -Sam
>>>>
>>>> On Fri, Oct 31, 2014 at 8:54 AM, Ta Ba Tuan<tuantb-QlevPasa8l681eZEIcUDRw@public.gmane.org>  wrote:
>>>>
>>>> Hi Sage Weil
>>>>
>>>> Thank for your repling. Yes, I'm using Ceph v.0.86,
>>>> I report some related bugs, Hope you help me,
>>>>
>>>> 2014-10-31 15:34:52.927965 7f85efb6b700  0 osd.21 104744 do_command r=0
>>>> 2014-10-31 15:34:53.105533 7f85f036c700 -1 *** Caught signal (Segmentation
>>>> fault) **
>>>>    in thread 7f85f036c700
>>>>    ceph version 0.86-106-g6f8524e (6f8524ef7673ab4448de2e0ff76638deaf03cae8)
>>>>    1: /usr/bin/ceph-osd() [0x9b6655]
>>>>    2: (()+0xfcb0) [0x7f8615726cb0]
>>>>    3: (ReplicatedPG::trim_object(hobject_t const&)+0x395) [0x811c25]
>>>>    4: (ReplicatedPG::TrimmingObjects::react(ReplicatedPG::SnapTrim
>>>> const&)+0x43e) [0x82baae]
>>>>    5: (boost::statechart::simple_state<ReplicatedPG::TrimmingObjects,
>>>> ReplicatedPG::SnapTrimmer, boost::mpl::list<mpl_::na, mpl_::na, mpl_::na,
>>>> mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na,
>>>> mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na,
>>>> mpl_::na, mpl_::na, mpl_::na>,
>>>> (boost::statechart::history_mode)0>::react_impl(boost::statechart::event_base
>>>> const&, void const*)+0xc0) [0x870c30]
>>>>    6: (boost::statechart::state_machine<ReplicatedPG::SnapTrimmer,
>>>> ReplicatedPG::NotTrimming, std::allocator<void>,
>>>> boost::statechart::null_exception_translator>::process_queued_events()+0xfb)
>>>> [0x8560db]
>>>>    7: (boost::statechart::state_machine<ReplicatedPG::SnapTrimmer,
>>>> ReplicatedPG::NotTrimming, std::allocator<void>,
>>>> boost::statechart::null_exception_translator>::process_event(boost::statechart::event_base
>>>> const&)+0x1e) [0x8562ae]
>>>>    8: (ReplicatedPG::snap_trimmer()+0x4f8) [0x7d5f48]
>>>>    9: (OSD::SnapTrimWQ::_process(PG*)+0x14) [0x6739b4]
>>>>    10: (ThreadPool::worker(ThreadPool::WorkThread*)+0x48e) [0xa8fa0e]
>>>>    11: (ThreadPool::WorkThread::entry()+0x10) [0xa927a0]
>>>>    12: (()+0x7e9a) [0x7f861571ee9a]
>>>>    13: (clone()+0x6d) [0x7f86140e931d]
>>>>    NOTE: a copy of the executable, or `objdump -rdS <executable>` is needed
>>>> to
>>>> interpret this.
>>>>
>>>>    -9523> 2014-10-31 15:34:45.571962 7f85e3ee0700  5 -- op tracker -- seq:
>>>> 6937, time: 2014-10-31 15:34:45.531887, event: header_read, op: MOSDPGPus
>>>> h(6.749 104744
>>>> [PushOp(d2106749/rbd_data.a2e6185b9a8ef8.0000000000000803/head//6, version:
>>>> 104736'7736506, data_included: [0~4194304], data_size:
>>>> 4194304, omap_header_size: 0, omap_entries_size: 0, attrset_size: 2,
>>>> recovery_info:
>>>> ObjectRecoveryInfo(d2106749/rbd_data.a2e6185b9a8ef8.0000000000
>>>> 000803/head//6@104736'7736506, copy_subset: [0~4194304], clone_subset: {}),
>>>> after_progress: ObjectRecoveryProgress(!first, data_recovered_to:41943
>>>> 04, data_complete:true, omap_recovered_to:, omap_complete:true),
>>>> before_progress: ObjectRecoveryProgress(first, data_recovered_to:0,
>>>> data_complete
>>>> :false, omap_recovered_to:,
>>>> omap_complete:false)),PushOp(60940749/rbd_data.3435875ff78f67.0000000000001408/head//6,
>>>> version: 104736'7736579, data_
>>>> included: [0~335360], data_size: 335360, omap_header_size: 0,
>>>> omap_entries_size: 0, attrset_size: 2, recovery_info:
>>>> ObjectRecoveryInfo(60940749/rb
>>>> d_data.3435875ff78f67.0000000000001408/head//6@104736'7736579, copy_subset:
>>>> [0~335360], clone_subset: {}), after_progress: ObjectRecoveryProgress(
>>>> !first, data_recovered_to:335360, data_complete:true, omap_recovered_to:,
>>>> omap_complete:true), before_progress: ObjectRecoveryProgress(first, data
>>>> _recovered_to:0, data_complete:false, omap_recovered_to:,
>>>> omap_complete:false)),PushOp(922b1749/rbd_data.1c3dade6cdc10.00000000000014c5/head//6,
>>>> v
>>>> ersion: 104736'7736866, data_included: [0~4194304], data_size: 4194304,
>>>> omap_header_size: 0, omap_entries_size: 0, attrset_size: 2, recovery_info:
>>>>
>>>> ObjectRecoveryInfo(922b1749/rbd_data.1c3dade6cdc10.00000000000014c5/head//6@104736'7736866,
>>>> copy_subset: [0~4194304], clone_subset: {}), after_pr
>>>> ogress: ObjectRecoveryProgress(!first, data_recovered_to:4194304,
>>>> data_complete:true, omap_recovered_to:, omap_complete:true),
>>>> before_progress: Ob
>>>> jectRecoveryProgress(first, data_recovered_to:0, data_complete:false,
>>>> omap_recovered_to:, omap_complete:false))])
>>>>
>>>>    -6933> 2014-10-31 15:34tha7.611229 7f85f737a700  5 osd.21 pg_epoch: 104744
>>>> pg[6.749( v 104744'7741801 (104665'7732106,104744'7741801] lb
>>>> 14886749/rbd_data.3955b9640616f2.000000000000f5e2/head//6 local-les=104661
>>>> n=1780 ec=164 les/c 104742/104735 104740/104741/103210) [74,112,21]/[74,112]
>>>> r=-1 lpr=104741 pi=64005-104740/278 luod=0'0 crt=104744'7741798
>>>> active+remapped] enter Started/ReplicaActive/RepNotRecovering
>>>>
>>>> I think having some missing objects, I can't start one osd  that above
>>>> objects be pushed to that osd. Ceph'versions are slower 0.86 then appear
>>>> this bug?
>>>> Should I upgrade to Giant o resolve this bug?,
>>>>
>>>>
>>>> Thank you,
>>>> --
>>>> Tuan
>>>> HaNoi-VietNam
>>>>
>>>>
>>>> On 10/30/2014 10:02 PM, Sage Weil wrote:
>>>>
>>>> On Thu, 30 Oct 2014, Ta Ba Tuan wrote:
>>>>
>>>> Hi Everyone,
>>>>
>>>> I upgraded Ceph to Giant by installing *tar.gz package, but appeared some
>>>> errors related Object Trimming or Snap Trimming:
>>>> I think having some missing objects and be not recovered.
>>>>
>>>> Note that this isn't giant, which is 0.87, but something a few weeks
>>>> older.  There were a few bugs fixed in this code, but we can't tell if
>>>> this was one of them without the log leading up to this message, which
>>>> should include either a failed assertion message or segmentation fault or
>>>> similar.
>>>>
>>>> Thanks!
>>>> sage
>>>>
>>>>
>>>>    ceph version 0.86-106-g6f8524e (6f8524ef7673ab4448de2e0ff76638deaf03cae8)
>>>>    1: /usr/bin/ceph-osd() [0x9b6655]
>>>>    2: (()+0xfcb0) [0x7fa52c471cb0]
>>>>    3: (ReplicatedPG::trim_object(hobject_t const&)+0x395) [0x811c25]
>>>>    4: (ReplicatedPG::TrimmingObjects::react(ReplicatedPG::SnapTrim
>>>> const&)+0x43e) [0x82baae]
>>>>    5: (boost::statechart::simple_state<ReplicatedPG::TrimmingObjects,
>>>> ReplicatedPG::SnapTrimmer, boost::mpl::list<mpl_::na, mpl_::na, mpl_::na,
>>>> mpl
>>>> _::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na,
>>>> mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na, mpl_::na
>>>> , mpl_::na,
>>>> mpl_::na>,(boost::statechart::history_mode)0>::react_impl(boost::statechart::event_ba
>>>> se const&, void const*)+0xc0) [0x870c30]
>>>>    6: (boost::statechart::state_machine<ReplicatedPG::SnapTrimmer,
>>>> ReplicatedPG::NotTrimming, std::allocator<void>,
>>>> boost::statechart::null_excepti
>>>> on_translator>::process_queued_events()+0xfb) [0x8560db]
>>>>    7: (boost::statechart::state_machine<ReplicatedPG::SnapTrimmer,
>>>> ReplicatedPG::NotTrimming, std::allocator<void>,
>>>> boost::statechart::null_excepti
>>>> on_translator>::process_event(boost::statechart::event_base const&)+0x1e)
>>>> [0x8562ae]
>>>>    8: (ReplicatedPG::snap_trimmer()+0x4f8) [0x7d5f48]
>>>>    9: (OSD::SnapTrimWQ::_process(PG*)+0x14) [0x6739b4]
>>>>    10: (ThreadPool::worker(ThreadPool::WorkThread*)+0x48e) [0xa8fa0e]
>>>>    11: (ThreadPool::WorkThread::entry()+0x10) [0xa927a0]
>>>>    12: (()+0x7e9a) [0x7fa52c469e9a]
>>>>    13: (clone()+0x6d) [0x7fa52ae3431d]
>>>>    NOTE: a copy of the executable, or `objdump -rdS <executable>` is needed
>>>> to
>>>> interpret this.
>>>>
>>>>
>>>>     -128> 2014-10-29 13:51:23.049357 7fa50ed9d700  5 osd.21 pg_epoch: 104445
>>>> pg[6.9d8( v 104445'7857889 (103730'7852406,104445'7857889] local-les=104444
>>>> n=4345 ec=164 les/c 104444/104272 104443/104443/104443) [21,93,49] r=0
>>>> lpr=104443 pi=103787-104442/16 crt=104442'7857887 mlcod 104445'7857888
>>>> active snaptrimq=[1907~1,1941~4,1946~1,19ef~2,19f2~1,19f4~3,19fa~5]] exit
>>>> Started/Primary/Active/Recovered 0.000084 0 0.000000
>>>>     -127> 2014-10-29 13:51:23.049392 7fa50ed9d700  5 osd.21 pg_epoch: 104445
>>>> pg[6.9d8( v 104445'7857889 (103730'7852406,104445'7857889] local-les=104444
>>>> n=4345 ec=164 les/c 104444/104272 104443/104443/104443) [21,93,49] r=0
>>>> lpr=104443 pi=103787-104442/16 crt=104442'7857887 mlcod 104445'7857888
>>>> active snaptrimq=[1907~1,1941~4,1946~1,19ef~2,19f2~1,19f4~3,19fa~5]] enter
>>>> Started/Primary/Active/Clean
>>>>     -126> 2014-10-29 13:51:23.049582 7fa50ed9d700  1 -- 172.30.5.2:6838/22980
>>>> --> 172.30.5.4:6859/8884 -- pg_info(1 pgs e104445:6.9d8) v4 -- ?+0
>>>> 0x30d41c00 con 0x26c6ac60
>>>>
>>>>
>>>> Thank you!
>>>> --
>>>> Tuan
>>>> HaNoi-VietNam
>>>>
>>>>
>>>>
>>>>
>>>> _______________________________________________
>>>> ceph-users mailing list
>>>> ceph-users-idqoXFIVOFJgJs9I8MT0rw@public.gmane.org
>>>> http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com
>>>>
>>>>
>>>>
>>
>> _______________________________________________
>> ceph-users mailing list
>> ceph-users-idqoXFIVOFJgJs9I8MT0rw@public.gmane.org <mailto:ceph-users-idqoXFIVOFJgJs9I8MT0rw@public.gmane.org>
>> http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com
>


[-- Attachment #1.2: Type: text/html, Size: 18981 bytes --]

[-- Attachment #2: Type: text/plain, Size: 178 bytes --]

_______________________________________________
ceph-users mailing list
ceph-users-idqoXFIVOFJgJs9I8MT0rw@public.gmane.org
http://lists.ceph.com/listinfo.cgi/ceph-users-ceph.com