Flush agent not working after AEM migratio | Adobe Higher Education
Skip to main content
2
24x7
Level 2
April 12, 2024
Beantwoord

Flush agent not working after AEM migratio

  • April 12, 2024
  • 4 reacties
  • 2012 Bekeken

Dear AEM Community,

I have migrated the operating system to RHEL9 with Java 11. Author, Publisher and Dispatcher are running on the same machine.

Since then I have not been able to run the flush agent successfully. In the Transport tab I have the following configured:

http://IP/dispatcher/invalidate.cache with user and password. An additional piece of information that might be relevant is that we are

using AEM 6.5.17 with mod_dispatcher 4.3.7.

 

2024-04-12 11:56:53 - Create new HttpClient for Dispatcher Flush 2024-04-12 11:56:53 - * Auth User: admin 2024-04-12 11:56:53 - * HTTP Version: 1.1 2024-04-12 11:56:53 - * Connect Timeout: 900000 2024-04-12 11:56:53 - * Socket Timeout: 900000 2024-04-12 11:56:53 - adding header: CQ-Action:Test 2024-04-12 11:56:53 - adding header: CQ-Handle:/content 2024-04-12 11:56:53 - adding header: CQ-Path:/content 2024-04-12 11:56:53 - adding header: Host:flush 2024-04-12 11:56:53 - deserialize content for delivery 2024-04-12 11:56:53 - No message body: Content ReplicationContent.VOID is empty 2024-04-12 11:56:53 - Sending GET request to http://IP:80/dispatcher/invalidate.cache 2024-04-12 11:56:53 - sent. Response: 404 Not Found 2024-04-12 11:56:53 - Replication (TEST) of /content not successful. Conversation follows 2024-04-12 11:56:53 - ------------------------------------------------ 2024-04-12 11:56:53 - Sending message to IP:80 2024-04-12 11:56:53 - >> GET /dispatcher/invalidate.cache HTTP/1.0 2024-04-12 11:56:53 - >> CQ-Action: Test 2024-04-12 11:56:53 - >> CQ-Handle: /content 2024-04-12 11:56:53 - >> CQ-Path: /content 2024-04-12 11:56:53 - >> Referer: about:blank 2024-04-12 11:56:53 - >> Content-Length: 0 2024-04-12 11:56:53 - >> Content-Type: application/octet-stream 2024-04-12 11:56:53 - >> Host: flush 2024-04-12 11:56:53 - -- 2024-04-12 11:56:53 - << HTTP/1.1 404 Not Found 2024-04-12 11:56:53 - << Date: Fri, 12 Apr 2024 09:56:53 GMT 2024-04-12 11:56:53 - << Server: Apache 2024-04-12 11:56:53 - << X-Content-Type-Options: nosniff 2024-04-12 11:56:53 - << Expires: Thu, 01 Jan 1970 00:00:00 GMT 2024-04-12 11:56:53 - << Content-Type: text/html;charset=utf-8 2024-04-12 11:56:53 - << Set-Cookie: cq-authoring-mode=TOUCH; Path=/; Expires=Fri, 19-Apr-2024 09:56:53 GMT; Max-Age=604800 2024-04-12 11:56:53 - << Via: 1.1 Author-FQDN 2024-04-12 11:56:53 - << Transfer-Encoding: chunked 2024-04-12 11:56:53 - << 2024-04-12 11:56:53 - << <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN"> 2024-04-12 11:56:53 - << <html> 2024-04-12 11:56:53 - << <head><title>404 Resource at '/dispatcher/invalidate.cache' not found: No resource found</title></head> 2024-04-12 11:56:53 - << <body> 2024-04-12 11:56:53 - << <h1>Resource at '/dispatcher/invalidate.cache' not found: No resource found</h1> 2024-04-12 11:56:53 - << <p>Cannot serve request to /dispatcher/invalidate.cache in /libs/sling/servlet/errorhandler/404.jsp</p> 2024-04-12 11:56:53 - << 2024-04-12 11:56:53 - << <h3>Request Progress:</h3> 2024-04-12 11:56:53 - << <pre> 2024-04-12 11:56:53 - << 0 TIMER_START{Request Processing} 2024-04-12 11:56:53 - << 1 COMMENT timer_end format is {&lt;elapsed microseconds&gt;,&lt;timer name&gt;} &lt;optional message&gt; 2024-04-12 11:56:53 - << 4 LOG Method=GET, PathInfo=null 2024-04-12 11:56:53 - << 6 TIMER_START{handleSecurity} 2024-04-12 11:56:53 - << 1198 TIMER_END{1191,handleSecurity} authenticator org.apache.sling.auth.core.impl.SlingAuthenticator@18063fae returns true 2024-04-12 11:56:53 - << 1377 TIMER_START{ResourceResolution} 2024-04-12 11:56:53 - << 1469 TIMER_END{91,ResourceResolution} URI=/dispatcher/invalidate.cache resolves to Resource=NonExistingResource, path=/dispatcher/invalidate.cache 2024-04-12 11:56:53 - << 1474 LOG Resource Path Info: SlingRequestPathInfo: path=&apos;/dispatcher/invalidate.cache&apos;, selectorString=&apos;null&apos;, extension=&apos;cache&apos;, suffix=&apos;null&apos; 2024-04-12 11:56:53 - << 1474 TIMER_START{ServletResolution} 2024-04-12 11:56:53 - << 1477 TIMER_START{resolveServlet(/dispatcher/invalidate.cache)} 2024-04-12 11:56:53 - << 2287 LOG {0}: no servlet found 2024-04-12 11:56:53 - << 2294 TIMER_END{816,resolveServlet(/dispatcher/invalidate.cache)} Using servlet org.apache.sling.servlets.get.DefaultGetServlet 2024-04-12 11:56:53 - << 2297 TIMER_END{822,ServletResolution} URI=/dispatcher/invalidate.cache handled by Servlet=org.apache.sling.servlets.get.DefaultGetServlet 2024-04-12 11:56:53 - << 2301 LOG Applying Requestfilters 2024-04-12 11:56:53 - << 2303 LOG Calling filter: com.adobe.granite.resourceresolverhelper.impl.ResourceResolverHelperImpl 2024-04-12 11:56:53 - << 2312 LOG Calling filter: org.apache.sling.security.impl.ContentDispositionFilter 2024-04-12 11:56:53 - << 2317 LOG Calling filter: com.adobe.granite.csrf.impl.CSRFFilter 2024-04-12 11:56:53 - << 2349 LOG Calling filter: org.apache.sling.i18n.impl.I18NFilter 2024-04-12 11:56:53 - << 2352 LOG Calling filter: com.adobe.granite.httpcache.impl.InnerCacheFilter 2024-04-12 11:56:53 - << 2358 LOG Calling filter: org.apache.sling.rewriter.impl.RewriterFilter 2024-04-12 11:56:53 - << 2362 LOG Calling filter: com.adobe.cq.history.impl.HistoryRequestFilter 2024-04-12 11:56:53 - << 2439 LOG Calling filter: com.day.cq.wcm.core.impl.WCMRequestFilter 2024-04-12 11:56:53 - << 2455 LOG Calling filter: com.adobe.cq.wcm.core.components.internal.servlets.CoreFormHandlingServlet 2024-04-12 11:56:53 - << 2458 LOG Calling filter: com.adobe.granite.optout.impl.OptOutFilter 2024-04-12 11:56:53 - << 2464 LOG Calling filter: com.day.cq.wcm.foundation.forms.impl.FormsHandlingServlet 2024-04-12 11:56:53 - << 2466 LOG Calling filter: com.adobe.cq.social.commons.cors.CORSAuthenticationFilter 2024-04-12 11:56:53 - << 2469 LOG Calling filter: org.apache.sling.engine.impl.debug.RequestProgressTrackerLogFilter 2024-04-12 11:56:53 - << 2472 LOG Calling filter: org.apache.sling.dynamicinclude.CacheControlFilter 2024-04-12 11:56:53 - << 2479 LOG Calling filter: com.adobe.acs.commons.wcm.notifications.impl.SystemNotificationsImpl 2024-04-12 11:56:53 - << 2637 LOG Calling filter: fbb.core.filters.LoggingFilter 2024-04-12 11:56:53 - << 2640 LOG Calling filter: com.day.cq.wcm.mobile.core.impl.redirect.RedirectFilter 2024-04-12 11:56:53 - << 2643 LOG Calling filter: com.day.cq.wcm.core.impl.AuthoringUIModeServiceImpl 2024-04-12 11:56:53 - << 2919 LOG Calling filter: com.adobe.granite.rest.assets.impl.AssetContentDispositionFilter 2024-04-12 11:56:53 - << 2924 LOG Calling filter: com.adobe.granite.requests.logging.impl.RequestLoggerImpl 2024-04-12 11:56:53 - << 2928 LOG Calling filter: com.adobe.granite.rest.impl.servlet.ApiResourceFilter 2024-04-12 11:56:53 - << 2932 LOG Calling filter: com.day.cq.dam.core.impl.servlet.ActivityRecordHandler 2024-04-12 11:56:53 - << 2942 LOG Calling filter: com.day.cq.dam.core.impl.assetlinkshare.AdhocAssetShareAuthHandler 2024-04-12 11:56:53 - << 2945 LOG Calling filter: com.adobe.cq.social.ugcbase.security.impl.SaferSlingPostServlet 2024-04-12 11:56:53 - << 2948 LOG Calling filter: com.day.cq.wcm.core.impl.warp.TimeWarpFilter 2024-04-12 11:56:53 - << 2953 LOG Calling filter: org.apache.sling.dynamicinclude.SyntheticResourceFilter 2024-04-12 11:56:53 - << 2960 LOG Applying Componentfilters 2024-04-12 11:56:53 - << 2962 LOG Calling filter: com.day.cq.personalization.impl.TargetComponentFilter 2024-04-12 11:56:53 - << 2964 LOG Calling filter: com.day.cq.wcm.core.impl.page.PageLockFilter 2024-04-12 11:56:53 - << 2967 LOG Calling filter: com.day.cq.wcm.core.impl.WCMComponentFilter 2024-04-12 11:56:53 - << 2996 LOG Calling filter: com.day.cq.wcm.core.impl.WCMDebugFilter 2024-04-12 11:56:53 - << 3015 TIMER_START{org.apache.sling.servlets.get.DefaultGetServlet#0} 2024-04-12 11:56:53 - << 3069 TIMER_END{52,org.apache.sling.servlets.get.DefaultGetServlet#0} 2024-04-12 11:56:53 - << 3097 LOG Filter timing: filter=com.day.cq.wcm.core.impl.WCMDebugFilter, inner=70, total=80, outer=10 2024-04-12 11:56:53 - << 3101 LOG Filter timing: filter=com.day.cq.wcm.core.impl.WCMComponentFilter, inner=80, total=110, outer=30 2024-04-12 11:56:53 - << 3104 LOG Filter timing: filter=com.day.cq.wcm.core.impl.page.PageLockFilter, inner=110, total=113, outer=3 2024-04-12 11:56:53 - << 3119 LOG Filter timing: filter=org.apache.sling.dynamicinclude.SyntheticResourceFilter, inner=148, total=153, outer=5 2024-04-12 11:56:53 - << 3123 LOG Filter timing: filter=com.adobe.cq.dtm.reactor.impl.servlets.ClientLibraryFilter, inner=153, total=154, outer=1 2024-04-12 11:56:53 - << 3127 LOG Filter timing: filter=com.adobe.cq.dam.webdav.impl.io.DamWebdavRequestFilter, inner=154, total=155, outer=1 2024-04-12 11:56:53 - << 3130 LOG Filter timing: filter=com.day.cq.wcm.core.impl.warp.TimeWarpFilter, inner=155, total=158, outer=3 2024-04-12 11:56:53 - << 3133 LOG Filter timing: filter=com.adobe.cq.social.ugcbase.security.impl.SaferSlingPostServlet, inner=158, total=161, outer=3 2024-04-12 11:56:53 - << 3136 LOG Filter timing: filter=com.adobe.cq.dtm.impl.servlets.DTMLibraryCompatibilityFilter, inner=161, total=162, outer=1 2024-04-12 11:56:53 - << 3140 LOG Filter timing: filter=com.day.cq.dam.core.impl.assetlinkshare.AdhocAssetShareAuthHandler, inner=162, total=166, outer=4 2024-04-12 11:56:53 - << 3143 LOG Filter timing: filter=com.day.cq.dam.core.impl.servlet.DamContentDispositionFilter, inner=166, total=168, outer=2 2024-04-12 11:56:53 - << 3146 LOG Filter timing: filter=com.day.cq.dam.core.impl.servlet.ActivityRecordHandler, inner=168, total=175, outer=7 2024-04-12 11:56:53 - << 3150 LOG Filter timing: filter=com.adobe.granite.rest.impl.servlet.ApiResourceFilter, inner=175, total=180, outer=5 2024-04-12 11:56:53 - << 3153 LOG Filter timing: filter=com.adobe.granite.requests.logging.impl.RequestLoggerImpl, inner=180, total=184, outer=4 2024-04-12 11:56:53 - << 3156 LOG Filter timing: filter=com.adobe.granite.rest.assets.impl.AssetContentDispositionFilter, inner=184, total=190, outer=6 2024-04-12 11:56:53 - << 3159 LOG Filter timing: filter=com.day.cq.wcm.core.impl.AuthoringUIModeServiceImpl, inner=190, total=465, outer=275 2024-04-12 11:56:53 - << 3162 LOG Filter timing: filter=com.day.cq.wcm.mobile.core.impl.redirect.RedirectFilter, inner=465, total=468, outer=3 2024-04-12 11:56:53 - << 3166 LOG Filter timing: filter=fbb.core.filters.LoggingFilter, inner=468, total=472, outer=4 2024-04-12 11:56:53 - << 3169 LOG Filter timing: filter=com.adobe.acs.commons.wcm.notifications.impl.SystemNotificationsImpl, inner=472, total=632, outer=160 2024-04-12 11:56:53 - << 3173 LOG Filter timing: filter=com.comwrap.fbb.core.restartweb.services.darksite.DarksiteFlightServletsFilter, inner=632, total=633, outer=1 2024-04-12 11:56:53 - << 3177 LOG Filter timing: filter=org.apache.sling.dynamicinclude.CacheControlFilter, inner=633, total=638, outer=5 2024-04-12 11:56:53 - << 3180 LOG Filter timing: filter=org.apache.sling.engine.impl.debug.RequestProgressTrackerLogFilter, inner=638, total=641, outer=3 2024-04-12 11:56:53 - << 3183 LOG Filter timing: filter=com.adobe.cq.social.commons.cors.CORSAuthenticationFilter, inner=641, total=644, outer=3 2024-04-12 11:56:53 - << 3186 LOG Filter timing: filter=com.day.cq.wcm.foundation.forms.impl.FormsHandlingServlet, inner=644, total=647, outer=3 2024-04-12 11:56:53 - << 3189 LOG Filter timing: filter=com.adobe.granite.optout.impl.OptOutFilter, inner=647, total=653, outer=6 2024-04-12 11:56:53 - << 3192 LOG Filter timing: filter=com.adobe.cq.wcm.core.components.internal.servlets.CoreFormHandlingServlet, inner=653, total=657, outer=4 2024-04-12 11:56:53 - << 3195 LOG Filter timing: filter=com.day.cq.wcm.core.impl.WCMRequestFilter, inner=657, total=674, outer=17 2024-04-12 11:56:53 - << 3199 LOG Filter timing: filter=com.adobe.cq.history.impl.HistoryRequestFilter, inner=674, total=750, outer=76 2024-04-12 11:56:53 - << 3202 LOG Filter timing: filter=org.apache.sling.rewriter.impl.RewriterFilter, inner=750, total=756, outer=6 2024-04-12 11:56:53 - << 3205 LOG Filter timing: filter=com.adobe.granite.httpcache.impl.InnerCacheFilter, inner=756, total=763, outer=7 2024-04-12 11:56:53 - << 3208 LOG Filter timing: filter=org.apache.sling.i18n.impl.I18NFilter, inner=763, total=765, outer=2 2024-04-12 11:56:53 - << 3212 LOG Filter timing: filter=com.adobe.granite.csrf.impl.CSRFFilter, inner=765, total=798, outer=33 2024-04-12 11:56:53 - << 3215 LOG Filter timing: filter=org.apache.sling.security.impl.ContentDispositionFilter, inner=798, total=803, outer=5 2024-04-12 11:56:53 - << 3219 LOG Filter timing: filter=com.comwrap.fbb.core.restartweb.filters.FormValidationFilter, inner=803, total=806, outer=3 2024-04-12 11:56:53 - << 3222 LOG Filter timing: filter=org.apache.sling.distribution.servlet.DistributionAgentCreationFilter, inner=806, total=807, outer=1 2024-04-12 11:56:53 - << 3258 LOG Applying Error filters 2024-04-12 11:56:53 - << 3260 LOG Calling filter: org.apache.sling.i18n.impl.I18NFilter 2024-04-12 11:56:53 - << 3261 LOG Calling filter: org.apache.sling.rewriter.impl.RewriterFilter 2024-04-12 11:56:53 - << 3267 TIMER_START{handleError:status=404} 2024-04-12 11:56:53 - << 3281 TIMER_END{13,handleError:status=404} Using handler /libs/sling/servlet/errorhandler/404.jsp 2024-04-12 11:56:53 - << 4427 LOG Found processor for post processing ProcessorConfiguration: {contentTypes=[text/html], order=-1, active=true, valid=true, processErrorResponse=true, pipeline=(generator=Config(type=htmlparser, config={}), transformers=(Config(type=linkchecker, config={}), Config(type=mobile, config=JcrPropertyMap [node=Node[NodeDelegate{tree=/libs/cq/config/rewriter/default/transformer-mobile: { jcr:primaryType = nt:unstructured, component-optional = true}}], values={jcr:primaryType=nt:unstructured, component-optional=true}]), Config(type=mobiledebug, config=JcrPropertyMap [node=Node[NodeDelegate{tree=/libs/cq/config/rewriter/default/transformer-mobiledebug: { jcr:primaryType = nt:unstructured, component-optional = true}}], values={jcr:primaryType=nt:unstructured, component-optional=true}]), Config(type=contentsync, config=JcrPropertyMap [node=Node[NodeDelegate{tree=/libs/cq/config/rewriter/default/transformer-contentsync: { jcr:primaryType = nt:unstructured, component-optional = true}}], values={jcr:primaryType=nt:unstructured, component-optional=true}]), serializer=Config(type=htmlwriter, config={}))} 2024-04-12 11:56:53 - << 4601 TIMER_END{4600,Request Processing} Dumping SlingRequestProgressTracker Entries 2024-04-12 11:56:53 - << </pre> 2024-04-12 11:56:53 - << 2024-04-12 11:56:53 - << <hr> 2024-04-12 11:56:53 - << <address>ApacheSling/2.7 (jetty/9.4.49.v20220914, OpenJDK 64-Bit Server VM 11.0.22, Linux 5.14.0-362.24.1.el9_3.x86_64 amd64)</address> 2024-04-12 11:56:53 - << </body> 2024-04-12 11:56:53 - << </html> 2024-04-12 11:56:53 - Message sent. 2024-04-12 11:56:53 - ------------------------------------------------ 2024-04-12 11:56:53 - Replication (TEST) of /content not successful. Replication test failed Not Found

Any idea what might be going on here? I would appreciate any suggestions.

Regards

Angel

Er kunnen geen reacties meer worden geplaatst op dit onderwerp.
Beste antwoord door joerghoh

Your invalidation requests shows the Host Header with that value; if you never had such a vhost configured, some default vhost might have taken over this role, and potentially that has changed.

 

You should enable the logging on the httpd to get the requests logged in the context of the virtual host which is handling it. And then compare the old and the new setup.

4 reacties

arunpatidar
Community Advisor
arunpatidarCommunity Advisor
Community Advisor
April 12, 2024

Hi @24x7 
We also did the migration for RHEL9 with Java 11.

we have not seen any such issues.

However for us Adobe CSE took care of everything.

Arun Patidar
    EstebanBustamante
    Community Advisor and Adobe Champion
    EstebanBustamanteCommunity Advisor and Adobe Champion
    Community Advisor and Adobe Champion
    April 12, 2024

    Can you try disabling SELinux? https://blogs.perficient.com/2019/04/09/selinux-configuration-for-aem-dispatcher-2/

     

    Hope this helps

    Esteban Bustamante
      2
      24x7Auteur
      Level 2
      April 15, 2024

      @estebanbustamante SELinux was already permissive and the only thing I did was to enable httpd_can_network_connect. I even made the changes explained here: https://experienceleague.adobe.com/en/docs/experience-manager-dispatcher/using/getting-started/dispatcher-install#apache-web-server-configure-selinux-properties. But so far I still have the same result. Thank you for the advice.

        partyush
        Community Advisor
        partyushCommunity Advisor
        Community Advisor
        April 13, 2024

        Hi @24x7 

         

        It seems like the flush agent is attempting to send a GET request to invalidate the cache using the URL http://IP:80/dispatcher/invalidate.cache, but it's receiving a 404 Not Found response. Based on the logs, the request is being processed by Apache Sling, but it fails to find the resource at the specified path /dispatcher/invalidate.cache. This could indicate that either the path is incorrect or the resource does not exist in the expected location.

         

        Here are a few things you could check:

        1. Dispatcher Configuration: Ensure that the invalidate.cache handler is configured correctly in your Apache Dispatcher configuration (dispatcher.any). Verify that the invalidate.any file contains the appropriate rules for handling cache invalidation requests.

        2. Dispatcher Module Version Compatibility: Confirm that the version of the Apache Dispatcher module (mod_dispatcher) you're using (4.3.7) is compatible with AEM 6.5.17 and RHEL 9. There might be compatibility issues that are causing the 404 error.

        3. Check Dispatcher Logs: Look into the logs of the Apache HTTP Server where the Dispatcher module is running. It might provide more detailed information about why the request is resulting in a 404 error.

        4. Verify Dispatcher Configuration with AEM Documentation: Cross-reference your Apache Dispatcher configuration with the official AEM documentation for configuring the Dispatcher module. Ensure that you haven't missed any required configurations or made any mistakes in the setup.

        5. Access Permissions: Double-check the permissions and access settings for the invalidate.cache resource. Ensure that the user specified in the flush agent configuration has sufficient permissions to access and invalidate the cache.

        6. Path Correction: If you're sure that the configuration is correct, try accessing the invalidate.cache resource directly from a web browser or using a tool like cURL to ensure that the path is reachable and returns the expected response

         

        Thanks

          2
          24x7Auteur
          Level 2
          April 15, 2024

          Hi @partyush , invalidate.cache and dispatcher.any are configured correctly. The flush agent was working perfectly on CentOS 8 Stream. Regarding the dispatcher logs, I don't see anywhere why I am getting a 404, but this doesn't mean anything as I may not be able to see anything anymore due to going over and over the logs. If I try to access invalidate.cache through a browser I get the same result "Not found". Regarding the permissions and compatibility of AEM 6.5.17 to mod_dispatcher 4.3.7 I have to investigate a little more before giving an answer. 

          Regards

          Angel.

            joerghoh
            Adobe Employee
            joerghohAdobe Employee
            Adobe Employee
            April 14, 2024

            I would assume that the vhost with the hostname "flush" does either not exist or has not configured the invalidation correctly.

              2
              24x7Auteur
              Level 2
              April 15, 2024

              Hi @joerghoh ,
              taking a look at the whole configuration, we never had any vhost with the hostname "flush".

              Regards

              Angel

                joerghoh
                Adobe Employee
                joerghohAdobe EmployeeAntwoord
                Adobe Employee
                April 15, 2024

                Your invalidation requests shows the Host Header with that value; if you never had such a vhost configured, some default vhost might have taken over this role, and potentially that has changed.

                 

                You should enable the logging on the httpd to get the requests logged in the context of the virtual host which is handling it. And then compare the old and the new setup.

                  ForumvoorwaardenAccessibility statement

                  Loading footer...