Highlighted

AEM process spiking after running version comparison

bkowalk11

06-12-2019

Hoping someone has seen this before.If we try to explore doing a revert by doing a past version preview on any page in our system, we see a massive server spike for several hours as the logs fill with messages like this, for every single page on the server. Not even just child pages - just seems that clicking "preview" on any version of anything causes the server to suddenly rethink its entire life Makes the version preview feature entirely fail for us, obviously... even if we let it run through all 20k pages, it still doesn't display a preview at the end, and will still show the same activity in the logs the next time we try it. Hoping there's just something we have configured wrong. Thanks in advance!

06.12.2019 14:56:34.669 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/10xmanagement

06.12.2019 14:56:34.839 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/bill-gates-60-minutes

06.12.2019 14:56:35.007 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/cisco-home-lab-routers

06.12.2019 14:56:35.142 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/teaching-kids-programming-in-utah-schools

06.12.2019 14:56:35.266 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/ccna-video-study-methods

06.12.2019 14:56:35.405 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/vmware-vcp5-dv-versus-vsphere-5-1

06.12.2019 14:56:35.522 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/more-microsoft-build-2013-tickets-available-if-you-hurry

06.12.2019 14:56:35.657 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/unified-messaging-features

06.12.2019 14:56:35.859 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/google-io-is-here-what-does-it-mean-for-developers

06.12.2019 14:56:35.979 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/vmware-hybrid-cloud-evaluation

06.12.2019 14:56:36.070 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/teach-kids-programming-learning-portal

06.12.2019 14:56:36.174 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/government-it-dod-jobs

06.12.2019 14:56:36.292 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/android-player-v1-1-151-released

06.12.2019 14:56:36.390 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/healthcare-it-inside-story

06.12.2019 14:56:36.499 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/modern-tech-job-interview-tips

06.12.2019 14:56:36.613 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/cisco-ccna-rs-exams

06.12.2019 14:56:36.720 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/vcloud-director

06.12.2019 14:56:36.826 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/unitys-mobile-tools-go-free-for-indie-developers

06.12.2019 14:56:36.936 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/vmware-hybrid-cloud-service-announced

06.12.2019 14:56:37.041 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/teaching-developers

06.12.2019 14:56:37.136 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/top-5-windows-azure-annoyances

06.12.2019 14:56:37.272 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/resize-vmware-windows-server-drives

06.12.2019 14:56:37.386 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/two-factor-authentication

06.12.2019 14:56:37.501 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/cert-expiration-2013-microsoft-cisco-oracle

06.12.2019 14:56:37.600 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/pluralsight-library-reaches-500-courses

06.12.2019 14:56:37.712 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/is-apple-following-microsofts-live-tile-footsteps

06.12.2019 14:56:37.816 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/emc-vs-netapp-storage-wars

06.12.2019 14:56:37.917 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/afraid-of-cloud

06.12.2019 14:56:38.018 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/blog_archive/2013/05/vm-sprawl

Replies

Highlighted

sunjot16

Employee

06-12-2019

It's all INFO...Do you see any errors related to it? Any traversal queries in error.log? Any Console errors in Console tab or 4xx/5xx status codes in Network tab in Dev tools?

Which AEM Version are you having issues on?

Highlighted

bkowalk11

06-12-2019

We're on AEM 6.4. And correct, they all seem to be INFO or unrelated WARN level messages. Noticed a traversal warning on line 20 here (this is the log at the very start of the error happening). Does that help with any clues? Here's that line separated out so you don't have to hunt:

06.12.2019 14:41:00.258 *WARN* [Thread-108] org.apache.jackrabbit.oak.plugins.index.Cursors$TraversingCursor Traversed 1000 nodes with filter Filter(query=select e.[jcr:path] as [jcr:path], e.[jcr:score] as [jcr:score], e.* from [nt:base] as a inner join [nt:base] as b on ischildnode(b, a) inner join [nt:base] as c on ischildnode(c, b) inner join [nt:base] as d on ischildnode(d, c) inner join [nt:base] as e on ischildnode(e, d) where name(a) = 'wcm' and isdescendantnode(a, '/home') and name(b) = 'notification' and name(c) = 'config' and name(d) = 'subscriptions' and e.[type] = 'page' option(traversal OK) /* xpath: /jcr:root/home//wcm/notification/config/subscriptions/* [@type='page'] option(traversal ok) */, path=/home//*, property=[:localname=[wcm]]); consider creating an index or changing the query

And the rest of the logs as the issue starts:

06.12.2019 14:40:02.058 *INFO* [10.107.139.127 [1575643202034] GET /siteadmin HTTP/1.1] org.apache.sling.xss.impl.HtmlToHtmlContentContext AntiSamy warning: The link tag contained an attribute that we could not process. The href attribute had a value of "/libs/wcm/core/content/siteadmin.ico". This value could not be accepted for security reasons. We have chosen to remove the entire link tag in order to continue processing the input.

06.12.2019 14:40:02.058 *INFO* [10.107.139.127 [1575643202034] GET /siteadmin HTTP/1.1] org.apache.sling.xss.impl.HtmlToHtmlContentContext AntiSamy warning: The link tag contained an attribute that we could not process. The type attribute had a value of "image/vnd.microsoft.icon". This value could not be accepted for security reasons. We have chosen to remove the entire link tag in order to continue processing the input.

06.12.2019 14:40:02.058 *INFO* [10.107.139.127 [1575643202034] GET /siteadmin HTTP/1.1] org.apache.sling.xss.impl.HtmlToHtmlContentContext AntiSamy warning: The link tag contained an attribute that we could not process. The rel attribute had a value of "icon". This value could not be accepted for security reasons. We have chosen to remove this attribute from the tag and leave everything else in place so that we could process the input.

06.12.2019 14:40:02.058 *INFO* [10.107.139.127 [1575643202034] GET /siteadmin HTTP/1.1] org.apache.sling.xss.impl.HtmlToHtmlContentContext AntiSamy warning: The link tag contained an attribute that we could not process. The href attribute had a value of "/libs/wcm/core/content/siteadmin.ico". This value could not be accepted for security reasons. We have chosen to remove the entire link tag in order to continue processing the input.

06.12.2019 14:40:02.058 *INFO* [10.107.139.127 [1575643202034] GET /siteadmin HTTP/1.1] org.apache.sling.xss.impl.HtmlToHtmlContentContext AntiSamy warning: The link tag contained an attribute that we could not process. The type attribute had a value of "image/vnd.microsoft.icon". This value could not be accepted for security reasons. We have chosen to remove the entire link tag in order to continue processing the input.

06.12.2019 14:40:02.773 *INFO* [ReplicateOnModification Processor] com.day.cq.wcm.core.impl.event.ReplicateOnModification Auto-Replicated ACTIVATE of /content/en/skill-iq/it-risk-management

06.12.2019 14:40:06.924 *INFO* [10.107.139.127 [1575643206917] GET /content/cq:tags/marketing.infinity.json HTTP/1.1] org.apache.sling.engine.impl.SlingRequestProcessorImpl service: Resource /content/cq:tags/marketing.infinity.json not found

06.12.2019 14:40:07.137 *WARN* [10.107.139.127 [1575643207051] GET /content.pages.json HTTP/1.1] com.day.cq.wcm.core.impl.PageImpl Repository error while checking lock status of /content/versionhistory

06.12.2019 14:40:24.775 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552463, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:40:24.777 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552464, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:40:24.778 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552465, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:40:24.800 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552466, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:40:42.134 *WARN* [10.107.139.127 [1575643242124] GET /libs/granite/core/content/login.html HTTP/1.1] libs.granite.core.components.login.login$jsp j_reason param value 'unknown' cannot be mapped to a valid reason message: ignoring

06.12.2019 14:40:56.701 *INFO* [qtp198891862-1731303] org.apache.sling.auth.core.impl.SlingAuthenticator getAnonymousResolver: Anonymous access not allowed by configuration - requesting credentials

06.12.2019 14:40:56.755 *INFO* [HealthCheck Synchronized Clocks] org.apache.sling.discovery.oak.SynchronizedClocksHealthCheck execute: no topology connectors connected to local instance.

06.12.2019 14:40:56.770 *INFO* [HealthCheck Default Login Accounts] com.adobe.granite.repository.hc.impl.DefaultLoginsHealthCheck Activated, accountLogins=[admin:admin, author:author], consoleLogins=[admin:admin]

06.12.2019 14:40:56.779 *INFO* [HealthCheck SSL Configuration] com.adobe.granite.security.user.ui.internal.servlets.SSLConfigurationServlet The key store for user ssl-service is not initialized (may not exist):

06.12.2019 14:40:56.826 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552467, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:40:56.832 *WARN* [10.107.139.127 [1575643256821] HEAD /libs/granite/core/content/login.html HTTP/1.1] libs.granite.core.components.login.login$jsp j_reason param value 'unknown' cannot be mapped to a valid reason message: ignoring

06.12.2019 14:41:00.258 *WARN* [Thread-108] org.apache.jackrabbit.oak.plugins.index.Cursors$TraversingCursor Traversed 1000 nodes with filter Filter(query=select e.[jcr:path] as [jcr:path], e.[jcr:score] as [jcr:score], e.* from [nt:base] as a inner join [nt:base] as b on ischildnode(b, a) inner join [nt:base] as c on ischildnode(c, b) inner join [nt:base] as d on ischildnode(d, c) inner join [nt:base] as e on ischildnode(e, d) where name(a) = 'wcm' and isdescendantnode(a, '/home') and name(b) = 'notification' and name(c) = 'config' and name(d) = 'subscriptions' and e.[type] = 'page' option(traversal OK) /* xpath: /jcr:root/home//wcm/notification/config/subscriptions/* [@type='page'] option(traversal ok) */, path=/home//*, property=[:localname=[wcm]]); consider creating an index or changing the query

06.12.2019 14:41:01.192 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552468, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:41:02.069 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552469, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:41:02.825 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552470, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:41:28.237 *INFO* [qtp198891862-1725581] org.apache.sling.auth.core.impl.SlingAuthenticator getAnonymousResolver: Anonymous access not allowed by configuration - requesting credentials

06.12.2019 14:41:28.662 *WARN* [10.107.111.251 [1575643288653] HEAD /libs/granite/core/content/login.html HTTP/1.1] libs.granite.core.components.login.login$jsp j_reason param value 'unknown' cannot be mapped to a valid reason message: ignoring

06.12.2019 14:41:42.377 *WARN* [10.107.139.127 [1575643302369] GET /libs/granite/core/content/login.html HTTP/1.1] libs.granite.core.components.login.login$jsp j_reason param value 'unknown' cannot be mapped to a valid reason message: ignoring

06.12.2019 14:41:56.754 *INFO* [HealthCheck Synchronized Clocks] org.apache.sling.discovery.oak.SynchronizedClocksHealthCheck execute: no topology connectors connected to local instance.

06.12.2019 14:41:56.770 *INFO* [HealthCheck Default Login Accounts] com.adobe.granite.repository.hc.impl.DefaultLoginsHealthCheck Activated, accountLogins=[admin:admin, author:author], consoleLogins=[admin:admin]

06.12.2019 14:41:56.781 *INFO* [HealthCheck SSL Configuration] com.adobe.granite.security.user.ui.internal.servlets.SSLConfigurationServlet The key store for user ssl-service is not initialized (may not exist):

06.12.2019 14:41:57.134 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552471, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:42:03.513 *INFO* [10.107.139.127 [1575643323504] GET /content/en/sandbox/hoodoo-authoring/social-tables/jcr:content/main/generic_block_2105624874/parsys/columns/column-parsys-1/column_control/column-parsys-1/spacer.html HTTP/1.1] org.apache.sling.engine.impl.SlingRequestProcessorImpl service: Resource /content/en/sandbox/hoodoo-authoring/social-tables/jcr:content/main/generic_block_2105624874/parsys/columns/column-parsys-1/column_control/column-parsys-1/spacer.html not found

06.12.2019 14:42:33.529 *INFO* [qtp198891862-1731303] org.apache.sling.auth.core.impl.SlingAuthenticator getAnonymousResolver: Anonymous access not allowed by configuration - requesting credentials

06.12.2019 14:42:33.851 *WARN* [10.107.139.127 [1575643353840] HEAD /libs/granite/core/content/login.html HTTP/1.1] libs.granite.core.components.login.login$jsp j_reason param value 'unknown' cannot be mapped to a valid reason message: ignoring

06.12.2019 14:42:42.332 *WARN* [10.107.139.127 [1575643362317] GET /libs/granite/core/content/login.html HTTP/1.1] libs.granite.core.components.login.login$jsp j_reason param value 'unknown' cannot be mapped to a valid reason message: ignoring

06.12.2019 14:42:56.367 *WARN* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] org.apache.jackrabbit.oak.jcr.observation.ChangeProcessor Revision queue is full. Further revisions will be compacted. Suppressing further such cases for 30 minutes.

06.12.2019 14:42:56.827 *INFO* [HealthCheck Synchronized Clocks] org.apache.sling.discovery.oak.SynchronizedClocksHealthCheck execute: no topology connectors connected to local instance.

06.12.2019 14:42:56.849 *INFO* [HealthCheck Default Login Accounts] com.adobe.granite.repository.hc.impl.DefaultLoginsHealthCheck Activated, accountLogins=[admin:admin, author:author], consoleLogins=[admin:admin]

06.12.2019 14:42:56.854 *INFO* [HealthCheck SSL Configuration] com.adobe.granite.security.user.ui.internal.servlets.SSLConfigurationServlet The key store for user ssl-service is not initialized (may not exist):

06.12.2019 14:42:57.122 *INFO* [oak-repository-executor-1] com.adobe.granite.repository Service [552472, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent REGISTERED

06.12.2019 14:43:41.737 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.adobe.granite.repository Service [552466, [org.apache.jackrabbit.oak.api.jmx.SessionMBean]] ServiceEvent UNREGISTERING

06.12.2019 14:43:41.739 *WARN* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] org.apache.jackrabbit.oak.jcr.session.RefreshStrategy This session has been idle for 3 minutes and might be out of date. Consider using a fresh session or explicitly refresh the session.

06.12.2019 14:43:42.307 *WARN* [10.107.111.251 [1575643422300] GET /libs/granite/core/content/login.html HTTP/1.1] libs.granite.core.components.login.login$jsp j_reason param value 'unknown' cannot be mapped to a valid reason message: ignoring

06.12.2019 14:43:42.423 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en

06.12.2019 14:43:44.273 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about

06.12.2019 14:43:44.699 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/offices

06.12.2019 14:43:45.233 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/offices/dublin

06.12.2019 14:43:45.672 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/offices/utah

06.12.2019 14:43:46.037 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/offices/boston

06.12.2019 14:43:46.340 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/offices/boston/GTM

06.12.2019 14:43:46.695 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/visit

06.12.2019 14:43:47.341 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/board

06.12.2019 14:43:47.629 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/ipo

06.12.2019 14:43:47.957 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/about/acquisitions

06.12.2019 14:43:48.250 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/affiliate

06.12.2019 14:43:48.604 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors

06.12.2019 14:43:48.680 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar

06.12.2019 14:43:48.741 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-frost

06.12.2019 14:43:48.806 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-patterson

06.12.2019 14:43:48.864 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-powell

06.12.2019 14:43:48.931 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-paxson

06.12.2019 14:43:48.989 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-rosenmund

06.12.2019 14:43:49.058 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-medacco

06.12.2019 14:43:49.132 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-stewart

06.12.2019 14:43:49.200 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-sampson

06.12.2019 14:43:49.259 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-willis

06.12.2019 14:43:49.310 *INFO* [qtp198891862-1727519] org.apache.sling.auth.core.impl.SlingAuthenticator getAnonymousResolver: Anonymous access not allowed by configuration - requesting credentials

06.12.2019 14:43:49.318 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-skonnard

06.12.2019 14:43:49.383 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/aar/aaron-staines-9

06.12.2019 14:43:49.453 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/abh

06.12.2019 14:43:49.520 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/abh/abhimanyu-tanwar

06.12.2019 14:43:49.600 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/abh/abhishek-kant

06.12.2019 14:43:49.712 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/abh/abhishek-kumar

06.12.2019 14:43:49.794 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/abh/abhaya-chauhan

06.12.2019 14:43:49.863 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/ada

06.12.2019 14:43:49.923 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/ada/adam-bertram

06.12.2019 14:43:49.999 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/ada/adam-chase

06.12.2019 14:43:50.024 *WARN* [10.107.119.6 [1575643430011] HEAD /libs/granite/core/content/login.html HTTP/1.1] libs.granite.core.components.login.login$jsp j_reason param value 'unknown' cannot be mapped to a valid reason message: ignoring

06.12.2019 14:43:50.070 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/ada/adam-churvis

06.12.2019 14:43:50.147 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/ada/adam-fairless

06.12.2019 14:43:50.226 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/ada/adam-grocholski

06.12.2019 14:43:50.303 *INFO* [10.107.139.127 [1575643164773] GET /mnt/overlay/wcm/core/content/sites/versionhistory.html HTTP/1.1] com.day.cq.wcm.core.impl.PageManagerImpl Created new version 1.0 for /content/versionhistory/nfielding/en/authors/ada/adam-kuczek

Highlighted

bkowalk2

23-01-2020

Huge apologies - I never got the notification you had responded! Will keep an eye on this thread now as this issue is still going on. Also... no idea why my username is suddenly different. That's fun 🙂

Highlighted

bkowalk2

23-01-2020

No errors are appearing, no traversal queries. Just a million edits suddenly as it seems to run through the whole system creating versions before doing the preview. And this is on AEM 6.4.0. Really baffled what's going on - does a preview always require the whole system to have a current version or something? Have tried leaving this running overnight to see if it could catch up on whatever this process is doing, but in the morning the logs are still running, and the company needs to get back to work on a virtually frozen server, so I end up just rebooting.

Highlighted

bkowalk2

23-01-2020

One more piece of info - I just tried this with a local instance with a small portion of our site on it. The same behavior happened, but it was able to finish and finally display a preview after a half hour. I hoped that once this occurred, the process wouldn't need to repeat. But the next time I hit preview, it took the same amount of time to display. No matter where I run it (a page with many children, or one that's on its own as a child), it still seems to run through nearly our entire /content/ directory and touch every node before finally displaying.