Thanks, guys I can't add more RAM right now or have access to a server that does, I'd fear it wouldn't be enough anyway. I'll give the swap idea a go and try and track down the thread you mentioned, Frank.
'cephfs-journal-tool journal inspect' tells me the journal is fine. I was able to back it up cleanly, however the apparent size of the file reported by du is 53TB, does that sound right to you? The actual size is 3.7GB. 'cephfs-journal-tool event get list' starts listing events but eventually gets killed as expected. 'cephfs-journal-tool event get summary' Events by type: OPEN: 314260 SUBTREEMAP: 1134 UPDATE: 547973 Errors: 0 Those numbers seem really high to me - for reference this is an approx 128TB (usable space) cluster, 5050000 objects in metadata pool. On Thu, Oct 22, 2020 at 5:23 PM Frank Schilder <fr...@dtu.dk> wrote: > > If you can't add RAM, you could try provisioning SWAP on a reasonably fast > drive. There is a thread from this year where someone had a similar problem, > the MDS running out of memory during replay. He could quickly add sufficient > swap and the MDS managed to come up. Took a long time though, but might be > faster than getting more RAM and will not loose data. > > Your clients will not be able to do much, if anything during recovery though. > > Best regards, > ================= > Frank Schilder > AIT Risø Campus > Bygning 109, rum S14 > > ________________________________________ > From: Dan van der Ster <d...@vanderster.com> > Sent: 22 October 2020 18:11:57 > To: David C > Cc: ceph-devel; ceph-users > Subject: [ceph-users] Re: Urgent help needed please - MDS offline > > I assume you aren't able to quickly double the RAM on this MDS ? or > failover to a new MDS with more ram? > > Failing that, you shouldn't reset the journal without recovering > dentries, otherwise the cephfs_data objects won't be consistent with > the metadata. > The full procedure to be used is here: > https://docs.ceph.com/en/latest/cephfs/disaster-recovery-experts/#disaster-recovery-experts > > backup the journal, recover dentires, then reset the journal. > (the steps after might not be needed) > > That said -- maybe there is a more elegant procedure than using > cephfs-journal-tool. A cephfs dev might have better advice. > > -- dan > > > On Thu, Oct 22, 2020 at 6:03 PM David C <dcsysengin...@gmail.com> wrote: > > > > I'm pretty sure it's replaying the same ops every time, the last > > "EMetaBlob.replay updated dir" before it dies is always referring to > > the same directory. Although interestingly that particular dir shows > > up in the log thousands of times - the dir appears to be where a > > desktop app is doing some analytics collecting - I don't know if > > that's likely to be a red herring or the reason why the journal > > appears to be so long. It's a dir I'd be quite happy to lose changes > > to or remove from the file system altogether. > > > > I'm loath to update during an outage although I have seen people > > update the MDS code independently to get out of a scrape - I suspect > > you wouldn't recommend that. > > > > I feel like this leaves me with having to manipulate the journal in > > some way, is there a nuclear option where I can choose to disregard > > the uncommitted events? I assume that would be a journal reset with > > the cephfs-journal-tool but I'm unclear on the impact of that, I'd > > expect to lose any metadata changes that were made since my cluster > > filled up but are there further implications? I also wonder what's the > > riskier option, resetting the journal or attempting an update. > > > > I'm very grateful for your help so far > > > > Below is more of the debug 10 log with ops relating to the > > aforementioned dir (name changed but inode is accurate): > > > > 2020-10-22 16:44:00.488850 7f424659e700 10 mds.0.journal > > EMetaBlob.replay updated dir [dir 0x10009e1ec8d /path/to/desktop/app/ > > [2,head] auth v=911968 cv=0/0 state=1610612736 f(v0 m2020-10-14 > > 16:32:42.596652 1=0+1) n(v6164 rc2020-10-22 08:46:44.932805 b133337592 > > 89216=89215+1)/n(v6164 rc2020-10-22 08:46:43.950805 b133337592 > > 89214=89213+1) hs=1+0,ss=0+0 dirty=1 | child=1 dirty=1 0x5654f8288300] > > 2020-10-22 16:44:00.488864 7f424659e700 10 mds.0.journal > > EMetaBlob.replay for [2,head] had [dentry > > #0x1/path/to/desktop/app/Upload [2,head] auth (dversion lock) v=911967 > > inode=0x5654f8288a00 state=1610612736 | inodepin=1 dirty=1 > > 0x5654f82794a0] > > 2020-10-22 16:44:00.488873 7f424659e700 10 mds.0.journal > > EMetaBlob.replay for [2,head] had [inode 0x10009e1ec8e [...2,head] > > /path/to/desktop/app/Upload/ auth v911967 f(v0 m2020-10-22 > > 08:46:44.932805 89215=89215+0) n(v2 rc2020-10-22 08:46:44.932805 > > b133337592 89216=89215+1) (iversion lock) | dirfrag=1 dirty=1 > > 0x5654f8288a00] > > 2020-10-22 16:44:00.488884 7f424659e700 10 mds.0.journal > > EMetaBlob.replay dir 0x10009e1ec8e > > 2020-10-22 16:44:00.488885 7f424659e700 10 mds.0.journal > > EMetaBlob.replay updated dir [dir 0x10009e1ec8e > > /path/to/desktop/app/Upload/ [2,head] auth v=904150 cv=0/0 > > state=1073741824 f(v0 m2020-10-22 08:46:44.932805 89215=89215+0) n(v2 > > rc2020-10-22 08:46:44.932805 b133337592 89215=89215+0) > > hs=42926+1178,ss=0+0 dirty=2375 | child=1 0x5654f8289100] > > 2020-10-22 16:44:00.488898 7f424659e700 10 mds.0.journal > > EMetaBlob.replay added (full) [dentry > > #0x1/path/to/desktop/app/Upload/{dc97bb9c-4600-48bb-b232-23f9e45caa6e}.tmp > > [2,head] auth NULL (dversion lock) v=904149 inode=0 > > state=1610612800|bottomlru | dirty=1 0x56586df52f00] > > 2020-10-22 16:44:00.488911 7f424659e700 10 mds.0.journal > > EMetaBlob.replay added [inode 0x1000e4c0ff4 [2,head] > > /path/to/desktop/app/Upload/{dc97bb9c-4600-48bb-b232-23f9e45caa6e}.tmp > > auth v904149 s=0 n(v0 1=1+0) (iversion lock) 0x566ce168ce00] > > 2020-10-22 16:44:00.488918 7f424659e700 10 > > mds.0.cache.ino(0x1000e4c0ff4) mark_dirty_parent > > 2020-10-22 16:44:00.488920 7f424659e700 10 mds.0.journal > > EMetaBlob.replay noting opened inode [inode 0x1000e4c0ff4 [2,head] > > /path/to/desktop/app/Upload/{dc97bb9c-4600-48bb-b232-23f9e45caa6e}.tmp > > auth v904149 dirtyparent s=0 n(v0 1=1+0) (iversion lock) | > > dirtyparent=1 dirty=1 0x566ce168ce00] > > 2020-10-22 16:44:00.488924 7f424659e700 10 mds.0.journal > > EMetaBlob.replay inotable tablev 481253 <= table 481328 > > 2020-10-22 16:44:00.488926 7f424659e700 10 mds.0.journal > > EMetaBlob.replay sessionmap v 240341131 <= table 240378576 > > 2020-10-22 16:44:00.488927 7f424659e700 10 mds.0.journal > > EMetaBlob.replay request client.16250824:1416595263 trim_to 1416595263 > > 2020-10-22 16:44:00.491462 7f424659e700 10 mds.0.log _replay > > 57437755528637~11764673 / 57441334490146 2020-10-22 09:08:56.198798: > > EOpen [metablob 0x10009e1ec8e, 1881 dirs], 16748 open files > > 2020-10-22 16:44:00.491471 7f424659e700 10 mds.0.journal EOpen.replay > > 2020-10-22 16:44:00.491472 7f424659e700 10 mds.0.journal > > EMetaBlob.replay 1881 dirlumps by unknown.0 > > 2020-10-22 16:44:00.491475 7f424659e700 10 mds.0.journal > > EMetaBlob.replay dir 0x10009e1ec8e > > 2020-10-22 16:44:00.491478 7f424659e700 10 mds.0.journal > > EMetaBlob.replay updated dir [dir 0x10009e1ec8e > > /path/to/desktop/app/Upload/ [2,head] auth v=904150 cv=0/0 > > state=1073741824 f(v0 m2020-10-22 08:46:44.932805 89215=89215+0) n(v2 > > rc2020-10-22 08:46:44.932805 b133337592 89215=89215+0) > > hs=42927+1178,ss=0+0 dirty=2376 | child=1 0x5654f8289100] > > 2020-10-22 16:44:03.783487 7f424ada7700 5 > > mds.beacon.hostnamecephssd01 Sending beacon up:replay seq 14 > > 2020-10-22 16:44:03.784082 7f424fd2c700 5 > > mds.beacon.hostnamecephssd01 received beacon reply up:replay seq 14 > > rtt 0.00100003 > > 2020-10-22 16:44:07.783586 7f424ada7700 5 > > mds.beacon.hostnamecephssd01 Sending beacon up:replay seq 15 > > 2020-10-22 16:44:07.784097 7f424fd2c700 5 > > mds.beacon.hostnamecephssd01 received beacon reply up:replay seq 15 > > rtt 0.00100003 > > 2020-10-22 16:44:11.783678 7f424ada7700 5 > > mds.beacon.hostnamecephssd01 Sending beacon up:replay seq 16 > > 2020-10-22 16:44:11.784223 7f424fd2c700 5 > > mds.beacon.hostnamecephssd01 received beacon reply up:replay seq 16 > > rtt 0.00100003 > > 2020-10-22 16:44:15.783788 7f424ada7700 1 heartbeat_map is_healthy > > 'MDSRank' had timed out after 15 > > 2020-10-22 16:44:15.783814 7f424ada7700 0 > > mds.beacon.hostnamecephssd01 Skipping beacon heartbeat to monitors > > (last acked 4.00013s ago); MDS internal heartbeat is not healthy! > > > > On Thu, Oct 22, 2020 at 3:30 PM Dan van der Ster <d...@vanderster.com> > > wrote: > > > > > > I wouldn't adjust it. > > > Do you have the impression that the mds is replaying the exact same ops > > > every > > > time the mds is restarting? or is it progressing and trimming the > > > journal over time? > > > > > > The only other advice I have is that 12.2.10 is quite old, and might > > > miss some important replay/mem fixes. > > > I'm thinking of one particular memory bloat issue we suffered (it > > > manifested on a multi-mds cluster, so I am not sure if it is the root > > > cause here https://tracker.ceph.com/issues/45090 ) > > > I don't know enough about the changelog diffs to suggest upgrading > > > right now in the middle of this outage. > > > > > > > > > -- dan > > > > > > On Thu, Oct 22, 2020 at 4:14 PM David C <dcsysengin...@gmail.com> wrote: > > > > > > > > I've not touched the journal segments, current value of > > > > mds_log_max_segments is 128. Would you recommend I increase (or > > > > decrease) that value? And do you think I should change > > > > mds_log_max_expiring to match that value? > > > > > > > > On Thu, Oct 22, 2020 at 3:06 PM Dan van der Ster <d...@vanderster.com> > > > > wrote: > > > > > > > > > > You could decrease the mds_cache_memory_limit but I don't think this > > > > > will help here during replay. > > > > > > > > > > You can see a related tracker here: > > > > > https://tracker.ceph.com/issues/47582 > > > > > This is possibly caused by replaying a very large journal. Did you > > > > > increase the journal segments? > > > > > > > > > > -- dan > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > > -- dan > > > > > > > > > > On Thu, Oct 22, 2020 at 3:35 PM David C <dcsysengin...@gmail.com> > > > > > wrote: > > > > > > > > > > > > Dan, many thanks for the response. > > > > > > > > > > > > I was going down the route of looking at mds_beacon_grace but I now > > > > > > realise when I start my MDS, it's swallowing up memory rapidly and > > > > > > looks like the oom-killer is eventually killing the mds. With debug > > > > > > upped to 10, I can see it's doing EMetaBlob.replays on various dirs > > > > > > in > > > > > > the filesystem and I can't see any obvious issues. > > > > > > > > > > > > This server has 128GB ram with 111GB free with the MDS stopped > > > > > > > > > > > > The mds_cache_memory_limit is currently set to 32GB > > > > > > > > > > > > Could this be a case of simply reducing the mds cache until I can > > > > > > get > > > > > > this started again or is there another setting I should be looking > > > > > > at? > > > > > > Is it safe to reduce the cache memory limit at this point? > > > > > > > > > > > > The standby is currently down and has been deliberately down for a > > > > > > while now. > > > > > > > > > > > > Log excerpt from debug 10 just before MDS is killed (path/to/dir > > > > > > refers to a real path in my FS) > > > > > > > > > > > > 2020-10-22 13:29:49.527372 7fc72d39f700 10 > > > > > > mds.0.cache.ino(0x1000e4c0ff4) mark_dirty_parent > > > > > > 2020-10-22 13:29:49.527374 7fc72d39f700 10 mds.0.journal > > > > > > EMetaBlob.replay noting opened inode [inode 0x1000e4c0ff4 [2,head] > > > > > > /path/to/dir/{dc97bb9c-4600-48bb-b232-23f9e45caa6e}.tmp auth v904149 > > > > > > dirtyparent s > > > > > > =0 n(v0 1=1+0) (iversion lock) | dirtyparent=1 dirty=1 > > > > > > 0x561c23d66e00] > > > > > > 2020-10-22 13:29:49.527378 7fc72d39f700 10 mds.0.journal > > > > > > EMetaBlob.replay inotable tablev 481253 <= table 481328 > > > > > > 2020-10-22 13:29:49.527380 7fc72d39f700 10 mds.0.journal > > > > > > EMetaBlob.replay sessionmap v 240341131 <= table 240378576 > > > > > > 2020-10-22 13:29:49.527383 7fc72d39f700 10 mds.0.journal > > > > > > EMetaBlob.replay request client.16250824:1416595263 trim_to > > > > > > 1416595263 > > > > > > 2020-10-22 13:29:49.530097 7fc72d39f700 10 mds.0.log _replay > > > > > > 57437755528637~11764673 / 57441334490146 2020-10-22 09:08:56.198798: > > > > > > EOpen [metab > > > > > > lob 0x10009e1ec8e, 1881 dirs], 16748 open files > > > > > > 2020-10-22 13:29:49.530106 7fc72d39f700 10 mds.0.journal > > > > > > EOpen.replay > > > > > > 2020-10-22 13:29:49.530107 7fc72d39f700 10 mds.0.journal > > > > > > EMetaBlob.replay 1881 dirlumps by unknown.0 > > > > > > 2020-10-22 13:29:49.530109 7fc72d39f700 10 mds.0.journal > > > > > > EMetaBlob.replay dir 0x10009e1ec8e > > > > > > 2020-10-22 13:29:49.530111 7fc72d39f700 10 mds.0.journal > > > > > > EMetaBlob.replay updated dir [dir 0x10009e1ec8e /path/to/dir/ > > > > > > [2,head] > > > > > > auth v=904150 cv=0/0 state=1073741824 f(v0 m2020-10-22 > > > > > > 08:46:44.932805 > > > > > > 89215=89215+0) n(v2 rc2020-10-22 08:46:44.932805 b133337592 > > > > > > 89215=89215+0) hs=42927+1178,ss=0+0 dirty=2376 | child=1 > > > > > > 0x56043c4bd100] > > > > > > 2020-10-22 13:29:50.275864 7fc731ba8700 5 > > > > > > mds.beacon.hostnamecephssd01 Sending beacon up:replay seq 13 > > > > > > 2020-10-22 13:29:51.026368 7fc73732e700 5 > > > > > > mds.beacon.hostnamecephssd01 received beacon reply up:replay seq 13 > > > > > > rtt 0.750024 > > > > > > 2020-10-22 13:29:51.026377 7fc73732e700 0 > > > > > > mds.beacon.hostnamecephssd01 MDS is no longer laggy > > > > > > 2020-10-22 13:29:54.275993 7fc731ba8700 5 > > > > > > mds.beacon.hostnamecephssd01 Sending beacon up:replay seq 14 > > > > > > 2020-10-22 13:29:54.277360 7fc73732e700 5 > > > > > > mds.beacon.hostnamecephssd01 received beacon reply up:replay seq 14 > > > > > > rtt 0.00100003 > > > > > > 2020-10-22 13:29:58.276117 7fc731ba8700 5 > > > > > > mds.beacon.hostnamecephssd01 Sending beacon up:replay seq 15 > > > > > > 2020-10-22 13:29:58.277322 7fc73732e700 5 > > > > > > mds.beacon.hostnamecephssd01 received beacon reply up:replay seq 15 > > > > > > rtt 0.00100003 > > > > > > 2020-10-22 13:30:02.276313 7fc731ba8700 5 > > > > > > mds.beacon.hostnamecephssd01 Sending beacon up:replay seq 16 > > > > > > 2020-10-22 13:30:02.477973 7fc73732e700 5 > > > > > > mds.beacon.hostnamecephssd01 received beacon reply up:replay seq 16 > > > > > > rtt 0.202007 > > > > > > > > > > > > Thanks, > > > > > > David > > > > > > > > > > > > On Thu, Oct 22, 2020 at 1:41 PM Dan van der Ster > > > > > > <d...@vanderster.com> wrote: > > > > > > > > > > > > > > You can disable that beacon by increasing mds_beacon_grace to 300 > > > > > > > or > > > > > > > 600. This will stop the mon from failing that mds over to a > > > > > > > standby. > > > > > > > I don't know if that is set on the mon or mgr, so I usually set > > > > > > > it on both. > > > > > > > (You might as well disable the standby too -- no sense in > > > > > > > something > > > > > > > failing back and forth between two mdses). > > > > > > > > > > > > > > Next -- looks like your mds is in active:replay. Is it doing > > > > > > > anything? > > > > > > > Is it using lots of CPU/RAM? If you increase debug_mds do you see > > > > > > > some > > > > > > > progress? > > > > > > > > > > > > > > -- dan > > > > > > > > > > > > > > > > > > > > > On Thu, Oct 22, 2020 at 2:01 PM David C <dcsysengin...@gmail.com> > > > > > > > wrote: > > > > > > > > > > > > > > > > Hi All > > > > > > > > > > > > > > > > My main CephFS data pool on a Luminous 12.2.10 cluster hit > > > > > > > > capacity > > > > > > > > overnight, metadata is on a separate pool which didn't hit > > > > > > > > capacity but the > > > > > > > > filesystem stopped working which I'd expect. I increased the > > > > > > > > osd full-ratio > > > > > > > > to give me some breathing room to get some data deleted once > > > > > > > > the filesystem > > > > > > > > is back online. When I attempt to restart the MDS service, I > > > > > > > > see the usual > > > > > > > > stuff I'd expect in the log but then: > > > > > > > > > > > > > > > > heartbeat_map is_healthy 'MDSRank' had timed out after 15 > > > > > > > > > > > > > > > > > > > > > > > > Followed by: > > > > > > > > > > > > > > > > mds.beacon.hostnamecephssd01 Skipping beacon heartbeat to > > > > > > > > monitors (last > > > > > > > > > acked 4.00013s ago); MDS internal heartbeat is not healthy! > > > > > > > > > > > > > > > > > > > > > > > > Eventually I get: > > > > > > > > > > > > > > > > > > > > > > > > > > mds.beacon.hostnamecephssd01 is_laggy 29.372 > 15 since last > > > > > > > > > acked beacon > > > > > > > > > mds.0.90884 skipping upkeep work because connection to > > > > > > > > > Monitors appears > > > > > > > > > laggy > > > > > > > > > mds.hostnamecephssd01 Updating MDS map to version 90885 from > > > > > > > > > mon.0 > > > > > > > > > mds.beacon.hostnamecephssd01 MDS is no longer laggy > > > > > > > > > > > > > > > > > > > > > > > > The "MDS is no longer laggy" appears to be where the service > > > > > > > > fails > > > > > > > > > > > > > > > > Meanwhile a ceph -s is showing: > > > > > > > > > > > > > > > > > > > > > > > > > > cluster: > > > > > > > > > id: 5c5998fd-dc9b-47ec-825e-beaba66aad11 > > > > > > > > > health: HEALTH_ERR > > > > > > > > > 1 filesystem is degraded > > > > > > > > > insufficient standby MDS daemons available > > > > > > > > > 67 backfillfull osd(s) > > > > > > > > > 11 nearfull osd(s) > > > > > > > > > full ratio(s) out of order > > > > > > > > > 2 pool(s) backfillfull > > > > > > > > > 2 pool(s) nearfull > > > > > > > > > 6 scrub errors > > > > > > > > > Possible data damage: 5 pgs inconsistent > > > > > > > > > services: > > > > > > > > > mon: 3 daemons, quorum > > > > > > > > > hostnameceph01,hostnameceph02,hostnameceph03 > > > > > > > > > mgr: hostnameceph03(active), standbys: hostnameceph02, > > > > > > > > > hostnameceph01 > > > > > > > > > mds: cephfs-1/1/1 up {0=hostnamecephssd01=up:replay} > > > > > > > > > osd: 172 osds: 161 up, 161 in > > > > > > > > > data: > > > > > > > > > pools: 5 pools, 8384 pgs > > > > > > > > > objects: 76.25M objects, 124TiB > > > > > > > > > usage: 373TiB used, 125TiB / 498TiB avail > > > > > > > > > pgs: 8379 active+clean > > > > > > > > > 5 active+clean+inconsistent > > > > > > > > > io: > > > > > > > > > client: 676KiB/s rd, 0op/s rd, 0op/s w > > > > > > > > > > > > > > > > > > > > > > > > The 5 pgs inconsistent is not a new issue, that is from past > > > > > > > > scrubs, just > > > > > > > > haven't gotten around to manually clearing them although I > > > > > > > > suppose they > > > > > > > > could be related to my issue > > > > > > > > > > > > > > > > The cluster has no clients connected > > > > > > > > > > > > > > > > I did notice in the ceph.log, some OSDs that are in the same > > > > > > > > host as the > > > > > > > > MDS service briefly went down when trying to restart the MDS > > > > > > > > but examining > > > > > > > > the logs of those particular OSDs isn't showing any glaring > > > > > > > > issues. > > > > > > > > > > > > > > > > Full MDS log at debug 5 (can go higher if needed): > > > > > > > > > > > > > > > > 2020-10-22 11:27:10.987652 7f6f696f5240 0 set uid:gid to > > > > > > > > 167:167 > > > > > > > > (ceph:ceph) > > > > > > > > 2020-10-22 11:27:10.987669 7f6f696f5240 0 ceph version 12.2.10 > > > > > > > > (177915764b752804194937482a39e95e0ca3de94) luminous (stable), > > > > > > > > process > > > > > > > > ceph-mds, pid 2022582 > > > > > > > > 2020-10-22 11:27:10.990567 7f6f696f5240 0 pidfile_write: > > > > > > > > ignore empty > > > > > > > > --pid-file > > > > > > > > 2020-10-22 11:27:11.027981 7f6f62616700 1 > > > > > > > > mds.hostnamecephssd01 Updating > > > > > > > > MDS map to version 90882 from mon.0 > > > > > > > > 2020-10-22 11:27:15.097957 7f6f62616700 1 > > > > > > > > mds.hostnamecephssd01 Updating > > > > > > > > MDS map to version 90883 from mon.0 > > > > > > > > 2020-10-22 11:27:15.097989 7f6f62616700 1 > > > > > > > > mds.hostnamecephssd01 Map has > > > > > > > > assigned me to become a standby > > > > > > > > 2020-10-22 11:27:15.101071 7f6f62616700 1 > > > > > > > > mds.hostnamecephssd01 Updating > > > > > > > > MDS map to version 90884 from mon.0 > > > > > > > > 2020-10-22 11:27:15.105310 7f6f62616700 1 mds.0.90884 > > > > > > > > handle_mds_map i am > > > > > > > > now mds.0.90884 > > > > > > > > 2020-10-22 11:27:15.105316 7f6f62616700 1 mds.0.90884 > > > > > > > > handle_mds_map state > > > > > > > > change up:boot --> up:replay > > > > > > > > 2020-10-22 11:27:15.105325 7f6f62616700 1 mds.0.90884 > > > > > > > > replay_start > > > > > > > > 2020-10-22 11:27:15.105333 7f6f62616700 1 mds.0.90884 > > > > > > > > recovery set is > > > > > > > > 2020-10-22 11:27:15.105344 7f6f62616700 1 mds.0.90884 waiting > > > > > > > > for osdmap > > > > > > > > 73745 (which blacklists prior instance) > > > > > > > > 2020-10-22 11:27:15.149092 7f6f5be09700 0 mds.0.cache creating > > > > > > > > system > > > > > > > > inode with ino:0x100 > > > > > > > > 2020-10-22 11:27:15.149693 7f6f5be09700 0 mds.0.cache creating > > > > > > > > system > > > > > > > > inode with ino:0x1 > > > > > > > > 2020-10-22 11:27:41.021708 7f6f63618700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:27:43.029290 7f6f5f610700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:27:43.029297 7f6f5f610700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 4.00013s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:27:45.866711 7f6f5fe11700 1 heartbeat_map > > > > > > > > reset_timeout > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:01.021965 7f6f63618700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:03.029862 7f6f5f610700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:03.029885 7f6f5f610700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 4.00113s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:28:06.022033 7f6f63618700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:07.029955 7f6f5f610700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:07.029961 7f6f5f610700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 8.00126s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:28:11.022099 7f6f63618700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:11.030024 7f6f5f610700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:11.030028 7f6f5f610700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 12.0014s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:28:15.030092 7f6f5f610700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:15.030099 7f6f5f610700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 16.0015s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:28:16.022165 7f6f63618700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:19.030163 7f6f5f610700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:19.030169 7f6f5f610700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 20.0016s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:28:21.022231 7f6f63618700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:23.030233 7f6f5f610700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:23.030241 7f6f5f610700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 24.0008s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:28:26.022295 7f6f63618700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:27.030305 7f6f5f610700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:27.030311 7f6f5f610700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 28.0009s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:28:28.401161 7f6f5fe11700 1 heartbeat_map > > > > > > > > reset_timeout > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:28:28.401168 7f6f5fe11700 1 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > is_laggy 29.372 > 15 since last acked beacon > > > > > > > > 2020-10-22 11:28:28.401177 7f6f5fe11700 1 mds.0.90884 skipping > > > > > > > > upkeep work > > > > > > > > because connection to Monitors appears laggy > > > > > > > > 2020-10-22 11:28:28.401187 7f6f62616700 1 > > > > > > > > mds.hostnamecephssd01 Updating > > > > > > > > MDS map to version 90885 from mon.0 > > > > > > > > 2020-10-22 11:28:31.659817 7f6f64595700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > MDS is no longer laggy > > > > > > > > 2020-10-22 11:36:15.880009 7f88ee4ac240 0 set uid:gid to > > > > > > > > 167:167 > > > > > > > > (ceph:ceph) > > > > > > > > 2020-10-22 11:36:15.880026 7f88ee4ac240 0 ceph version 12.2.10 > > > > > > > > (177915764b752804194937482a39e95e0ca3de94) luminous (stable), > > > > > > > > process > > > > > > > > ceph-mds, pid 2022663 > > > > > > > > 2020-10-22 11:36:15.883118 7f88ee4ac240 0 pidfile_write: > > > > > > > > ignore empty > > > > > > > > --pid-file > > > > > > > > 2020-10-22 11:36:15.921200 7f88e73cd700 1 > > > > > > > > mds.hostnamecephssd01 Updating > > > > > > > > MDS map to version 90887 from mon.2 > > > > > > > > 2020-10-22 11:36:20.270298 7f88e73cd700 1 > > > > > > > > mds.hostnamecephssd01 Updating > > > > > > > > MDS map to version 90888 from mon.2 > > > > > > > > 2020-10-22 11:36:20.270329 7f88e73cd700 1 > > > > > > > > mds.hostnamecephssd01 Map has > > > > > > > > assigned me to become a standby > > > > > > > > 2020-10-22 11:36:20.272917 7f88e73cd700 1 > > > > > > > > mds.hostnamecephssd01 Updating > > > > > > > > MDS map to version 90889 from mon.2 > > > > > > > > 2020-10-22 11:36:20.277063 7f88e73cd700 1 mds.0.90889 > > > > > > > > handle_mds_map i am > > > > > > > > now mds.0.90889 > > > > > > > > 2020-10-22 11:36:20.277069 7f88e73cd700 1 mds.0.90889 > > > > > > > > handle_mds_map state > > > > > > > > change up:boot --> up:replay > > > > > > > > 2020-10-22 11:36:20.277079 7f88e73cd700 1 mds.0.90889 > > > > > > > > replay_start > > > > > > > > 2020-10-22 11:36:20.277086 7f88e73cd700 1 mds.0.90889 > > > > > > > > recovery set is > > > > > > > > 2020-10-22 11:36:20.277096 7f88e73cd700 1 mds.0.90889 waiting > > > > > > > > for osdmap > > > > > > > > 73746 (which blacklists prior instance) > > > > > > > > 2020-10-22 11:36:20.322318 7f88e0bc0700 0 mds.0.cache creating > > > > > > > > system > > > > > > > > inode with ino:0x100 > > > > > > > > 2020-10-22 11:36:20.322918 7f88e0bc0700 0 mds.0.cache creating > > > > > > > > system > > > > > > > > inode with ino:0x1 > > > > > > > > 2020-10-22 11:36:47.922531 7f88e43c7700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:36:47.922549 7f88e43c7700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 4.00013s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:36:50.914516 7f88e83cf700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:36:51.351457 7f88e4bc8700 1 heartbeat_map > > > > > > > > reset_timeout > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:07.923089 7f88e43c7700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:07.923126 7f88e43c7700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 3.99913s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:37:10.914767 7f88e83cf700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:11.923216 7f88e43c7700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:11.923223 7f88e43c7700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 7.99926s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:37:15.914831 7f88e83cf700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:15.923286 7f88e43c7700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:15.923294 7f88e43c7700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 11.9994s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:37:19.923359 7f88e43c7700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:19.923366 7f88e43c7700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 15.9995s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:37:20.914917 7f88e83cf700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:23.923430 7f88e43c7700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:23.923437 7f88e43c7700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 19.9996s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:37:25.914981 7f88e83cf700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:27.923501 7f88e43c7700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:27.923508 7f88e43c7700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 23.9998s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:37:30.915046 7f88e83cf700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:31.923572 7f88e43c7700 1 heartbeat_map > > > > > > > > is_healthy > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:31.923579 7f88e43c7700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > Skipping beacon heartbeat to monitors (last acked 27.9999s > > > > > > > > ago); MDS > > > > > > > > internal heartbeat is not healthy! > > > > > > > > 2020-10-22 11:37:32.412628 7f88e4bc8700 1 heartbeat_map > > > > > > > > reset_timeout > > > > > > > > 'MDSRank' had timed out after 15 > > > > > > > > 2020-10-22 11:37:32.412635 7f88e4bc8700 1 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > is_laggy 28.4889 > 15 since last acked beacon > > > > > > > > 2020-10-22 11:37:32.412643 7f88e4bc8700 1 mds.0.90889 skipping > > > > > > > > upkeep work > > > > > > > > because connection to Monitors appears laggy > > > > > > > > 2020-10-22 11:37:32.412657 7f88e73cd700 1 > > > > > > > > mds.hostnamecephssd01 Updating > > > > > > > > MDS map to version 90890 from mon.2 > > > > > > > > 2020-10-22 11:37:35.978858 7f88e934c700 0 > > > > > > > > mds.beacon.hostnamecephssd01 > > > > > > > > MDS is no longer laggy > > > > > > > > > > > > > > > > > > > > > > > > Thanks in advance for any assistance you can provide! > > > > > > > > David > > > > > > > > _______________________________________________ > > > > > > > > ceph-users mailing list -- ceph-users@ceph.io > > > > > > > > To unsubscribe send an email to ceph-users-le...@ceph.io > _______________________________________________ > ceph-users mailing list -- ceph-users@ceph.io > To unsubscribe send an email to ceph-users-le...@ceph.io _______________________________________________ ceph-users mailing list -- ceph-users@ceph.io To unsubscribe send an email to ceph-users-le...@ceph.io
