[OAK-4108] Reduce logging from JournalGarbageCollector

• Type: Improvement
• Status: Closed
• Priority: Minor
• Resolution: Fixed
• Fix Version/s: 1.5.1, 1.6.0
• Component/s: documentmk
• Assignee: Chetan Mehrotra
• Reporter: Chetan Mehrotra
• Created: 09/Mar/16 05:31
• Updated: 26/Sep/19 11:42
• Resolved: 31/Mar/16 10:40

Description

The Journal GC is by default scheduled for every 5 mins. On a normal setup following logs can be seen

08.03.2016 00:43:53.869 *INFO* [sling-default-775-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 874.2 μs, deleted 0 entries that were older than 360 min.
08.03.2016 00:48:53.870 *INFO* [sling-default-781-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 1.909 ms, deleted 0 entries that were older than 360 min.
08.03.2016 00:53:53.870 *INFO* [sling-default-784-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 2.195 ms, deleted 0 entries that were older than 360 min.
08.03.2016 00:58:53.870 *INFO* [sling-default-786-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 1.586 ms, deleted 0 entries that were older than 360 min.
08.03.2016 01:00:30.000 *INFO* [sling-default-790-com.adobe.granite.taskmanagement.impl.jcr.TaskArchiveService.639] com.adobe.granite.taskmanagement.impl.jcr.TaskArchiveService archiving tasks at: 'Tue Mar 08 01:00:30 EST 2016'
08.03.2016 01:03:53.870 *INFO* [sling-default-791-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 1.218 ms, deleted 0 entries that were older than 360 min.
08.03.2016 01:07:23.003 *INFO* [sling-default-797-com.adobe.granite.oauth.server.impl.AccessTokenCleanupTask.552] com.adobe.granite.oauth.server.impl.AccessTokenCleanupTask AccessTokenCleanupTask: Removed 0 token(s) in 3ms (0 token(s) still active)
08.03.2016 01:08:53.868 *INFO* [sling-default-796-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 1.843 ms, deleted 0 entries that were older than 360 min.
08.03.2016 01:13:53.870 *INFO* [sling-default-800-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 1.828 ms, deleted 0 entries that were older than 360 min.
08.03.2016 01:17:23.002 *INFO* [sling-default-804-com.day.crx.security.token.impl.TokenCleanupTask.995] com.day.crx.security.token.impl.TokenCleanupTask TokenCleanupTask: Removed 0 token(s) in 2ms (0 token(s) still active)
08.03.2016 01:18:53.870 *INFO* [sling-default-805-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 4.046 ms, deleted 3 entries that were older than 360 min.
08.03.2016 01:23:53.870 *INFO* [sling-default-806-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 1.972 ms, deleted 0 entries that were older than 360 min.
08.03.2016 01:28:53.870 *INFO* [sling-default-813-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 1.348 ms, deleted 0 entries that were older than 360 min.
08.03.2016 01:33:53.871 *INFO* [sling-default-814-Registered Service.267] org.apache.jackrabbit.oak.plugins.document.JournalGarbageCollector gc: Journal garbage collection took 1.733 ms, deleted 0 entries that were older than 360 min.

It would be better to log at debug level if the delete count is 0 so as to reduce the noise.