[OAK-4965] Cold standby logs SNFE ERROR

• Type: Bug
• Status: Closed
• Priority: Major
• Resolution: Fixed
• Affects Version/s: Segment Tar 0.0.14
• Fix Version/s: 1.5.13, 1.6.0
• Component/s: segment-tar
• Assignee: Andrei Dulceanu
• Reporter: Andrei Dulceanu
• Created: 20/Oct/16 13:17
• Updated: 04/Oct/19 17:51
• Resolved: 02/Nov/16 11:01

Description

On coldstandby, there are a lot of occurences of SNFE:

200766 04.10.2016 14:29:52.657 *ERROR* [sling-default-16-Registered Service.577] org.apache.jackrabbit.oak.segment.SegmentId Segment not found: 19d493e3-8bad-4124-a962-5388d91f560e. SegmentId age=0ms
200767 org.apache.jackrabbit.oak.segment.SegmentNotFoundException: Segment 19d493e3-8bad-4124-a962-5388d91f560e not found
200768         at org.apache.jackrabbit.oak.segment.file.FileStore$14.call(FileStore.java:1345)
200769         at org.apache.jackrabbit.oak.segment.file.FileStore$14.call(FileStore.java:1285)
200770         at org.apache.jackrabbit.oak.cache.CacheLIRS$Segment.load(CacheLIRS.java:1013)
200771         at org.apache.jackrabbit.oak.cache.CacheLIRS$Segment.get(CacheLIRS.java:974)
200772         at org.apache.jackrabbit.oak.cache.CacheLIRS.get(CacheLIRS.java:285)
200773         at org.apache.jackrabbit.oak.segment.SegmentCache.getSegment(SegmentCache.java:92)
200774         at org.apache.jackrabbit.oak.segment.file.FileStore.readSegment(FileStore.java:1285)
200775         at org.apache.jackrabbit.oak.segment.SegmentId.getSegment(SegmentId.java:123)
200776         at org.apache.jackrabbit.oak.segment.Record.getSegment(Record.java:70)
200777         at org.apache.jackrabbit.oak.segment.SegmentNodeState.getStableIdBytes(SegmentNodeState.java:139)
200778         at org.apache.jackrabbit.oak.segment.SegmentNodeState.getStableId(SegmentNodeState.java:122)
200779         at org.apache.jackrabbit.oak.segment.SegmentNodeState.fastEquals(SegmentNodeState.java:633)
200780         at org.apache.jackrabbit.oak.segment.SegmentNodeState.compareAgainstBaseState(SegmentNodeState.java:459)
200781         at org.apache.jackrabbit.oak.segment.standby.client.StandbyClientSyncExecution.compareAgainstBaseState(StandbyClientSyncExecution.java:100)
200782         at org.apache.jackrabbit.oak.segment.standby.client.StandbyClientSyncExecution.execute(StandbyClientSyncExecution.java:80)
200783         at org.apache.jackrabbit.oak.segment.standby.client.StandbyClientSync.run(StandbyClientSync.java:143)
200784         at org.apache.sling.commons.scheduler.impl.QuartzJobExecutor.execute(QuartzJobExecutor.java:118)
200785         at org.quartz.core.JobRunShell.run(JobRunShell.java:202)
200786         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
200787         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
200788         at java.lang.Thread.run(Thread.java:745)
200789 04.10.2016 14:29:52.657 *INFO* [sling-default-16-Registered Service.577] org.apache.jackrabbit.oak.segment.standby.client.StandbyClientSyncExecution Found missing segment 19d493e3-8bad-4124-a962-5388d91f560e
200790 04.10.2016 14:29:52.657 *INFO* [sling-default-16-Registered Service.577] org.apache.jackrabbit.oak.segment.standby.client.StandbyClientSyncExecution Loading segment 19d493e3-8bad-4124-a962-5388d91f560e
200791 04.10.2016 14:29:52.657 *DEBUG* [nioEventLoopGroup-208-1] org.apache.jackrabbit.oak.segment.standby.codec.GetSegmentRequestEncoder Sending request from client qastandby1 for segment 19d493e3-8bad-4124-a962-5388d91f560e

While these are false positives (the segment is found later), we need to find a way to avoid logging the errors.

Issue Links

• is related to: OAK-5083 SegmentNotFoundExceptionListener logs Listener ID instead of SegmentId