This is an automated email from the ASF dual-hosted git repository. reschke pushed a commit to branch SLING-12222 in repository https://gitbox.apache.org/repos/asf/sling-org-apache-sling-resourceresolver.git
commit 7efb37cb4e0adf08669ab4c80f6f8ed20e49c94c Author: Julian Reschke <[email protected]> AuthorDate: Tue Jan 9 17:42:56 2024 +0100 SLING-12222: INFO-level log not only query time but also # of resources (and rate) for sling:alias and sling:vanityPath --- .../apache/sling/resourceresolver/impl/mapping/MapEntries.java | 10 ++++++++-- 1 file changed, 8 insertions(+), 2 deletions(-) diff --git a/src/main/java/org/apache/sling/resourceresolver/impl/mapping/MapEntries.java b/src/main/java/org/apache/sling/resourceresolver/impl/mapping/MapEntries.java index 369b327..fdb14fc 100644 --- a/src/main/java/org/apache/sling/resourceresolver/impl/mapping/MapEntries.java +++ b/src/main/java/org/apache/sling/resourceresolver/impl/mapping/MapEntries.java @@ -352,7 +352,10 @@ public class MapEntries implements drainQueue(resourceChangeQueue); long initElapsed = System.nanoTime() - initStart; - log.info("vanity path initialization - end, elapsed {}ms", TimeUnit.NANOSECONDS.toMillis(initElapsed)); + long resourcesPerSecond = (vanityResourcesOnStartup.get() * TimeUnit.SECONDS.toNanos(1) / (initElapsed == 0 ? 1 : initElapsed)); + log.info( + "vanity path initialization - end, processed {} resources with sling:vanityPath properties in {}ms (~{} resource/s)", + vanityResourcesOnStartup.get(), TimeUnit.NANOSECONDS.toMillis(initElapsed), resourcesPerSecond); } catch (LoginException ex) { log.error("Vanity path init failed", ex); } finally { @@ -1163,6 +1166,7 @@ public class MapEntries implements it = queryUnpaged("alias", baseQueryString); } + log.debug("alias initialization - start"); long count = 0; long processStart = System.nanoTime(); while (it.hasNext()) { @@ -1170,7 +1174,9 @@ public class MapEntries implements loadAlias(it.next(), map); } long processElapsed = System.nanoTime() - processStart; - log.debug("processed {} resources with sling:alias properties in {}ms", count, TimeUnit.NANOSECONDS.toMillis(processElapsed)); + long resourcePerSecond = (count * TimeUnit.SECONDS.toNanos(1) / (processElapsed == 0 ? 1 : processElapsed)); + log.info("alias initialization - end, processed {} resources with sling:alias properties in {}ms (~{} resource/s)", count, + TimeUnit.NANOSECONDS.toMillis(processElapsed), resourcePerSecond); this.aliasResourcesOnStartup.set(count);
