Dispatcher not retrieving content with Vhost but will display already cached content

Avatar

Avatar

kentt20248023

Avatar

kentt20248023

kentt20248023

10-10-2018

We have a domain resolving to our AEM environment and we are seeing this issue.

A call using the domain "http://staging.thissite.com/page" will not retrieve the content at /content/site/en/page.html however we do get the site custom error page.

If the content has been cached previously using "http://dispatcher.url.com/content/thissite/en/page.html" then it will be served correctly using the URL above.

So our virtual host redirects are working and do point to the cache location but will not make the dispatcher retrieve the content if not already cached.

Our dispatcher logs are set to debug(3) but we are not seeing any entries except for the single call and a 404.

The one difference between the two calls that I can see is that during the 404 we are getting [publishfarm/0] and during a successful return we are getting [publishfarm/-].

View Entire Topic

Avatar

Avatar

kentt20248023

Avatar

kentt20248023

kentt20248023

11-10-2018

All three phases:

http://staging.OURSITE.com/viewall/nutrition/just-eat-half  (first time, no cache)

[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] Found farm publishfarm for staging.OURSITE.com
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] checking [/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html]
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] Cache rule entry /0000 allowed '/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html'
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] cachefile does not exist: /mnt/var/www/html/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] try to create new cachefile: /mnt/var/www/html/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] cache-action for [/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html]: CREATE
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] Filter rule entry /0113 allowed 'GET /content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html HTTP/1.1'
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] Creating new socket: 172.27.58.37:4503
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] Connected to backend 0 (172.27.58.37:4503)
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.uri = "/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[X-Forwarded-Proto] = "http"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[user-agent] = "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/57.0.2987.110 Safari/537.36"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[accept-encoding] = "gzip, deflate, sdch"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[accept-language] = "en-US,en;q=0.8"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[accept] = "text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[host] = "staging.OURSITE.com"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[max-forwards] = "10"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[cookie] = "__atuvc=0%7C37%2C12%7C38%2C16%7C39%2C14%7C40%2C40%7C41; ApplicationGatewayAffinity=2bf56748f4829aa6808fec45c8c508e6cf879b9a193379f47610716cf23aecf8"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[Via] = "1.1 staging.OURSITE.com (dispatcher)"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[X-Forwarded-For] = "172.27.58.102"
[Wed Oct 10 11:30:50 2018] [T] [pid 27026 (tid 139767043823360)] request.headers[Server-Agent] = "Communique-Dispatcher"
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] response.status = 404
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] response.headers[Date] = "Wed, 10 Oct 2018 15:30:50 GMT"
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] response.headers[X-Content-Type-Options] = "nosniff"
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] response.headers[X-Frame-Options] = "SAMEORIGIN"
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] response.headers[Content-Type] = "text/html;charset=utf-8"
[Wed Oct 10 11:30:50 2018] [D] [pid 27026 (tid 139767043823360)] Storing socket for later reuse: 172.27.58.37:4503
[Wed Oct 10 11:30:50 2018] [I] [pid 27026 (tid 139767043823360)] "GET /content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html" 404 5378 30ms [publishfarm/0]

https://OURDISPATCHER.adobecqms.net/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html

[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] checking [/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html]
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] Cache rule entry /0000 allowed '/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html'
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] cachefile does not exist: /mnt/var/www/html/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] try to create new cachefile: /mnt/var/www/html/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] cache-action for [/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html]: CREATE
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] Filter rule entry /0113 allowed 'GET /content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html HTTP/1.1'
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Creating new socket: 172.27.58.37:4503
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Connected to backend 0 (172.27.58.37:4503)
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.uri = "/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[X-Forwarded-Proto] = "https"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[user-agent] = "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/57.0.2987.110 Safari/537.36"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[accept-encoding] = "gzip, deflate, sdch, br"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[accept-language] = "en-US,en;q=0.8"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[accept] = "text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[host] = "OURDISPATCHER.adobecqms.net"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[max-forwards] = "10"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[cookie] = "_vwo_uuid_v2=D2438EDC8B2F690CA524AAB4AD34DEB5B|206318b3c740c5447e4d5e3745aa6246; AMCVS_8F99160E571FC0427F000101%40AdobeOrg=1; lang=en; __atuvc=5%7C37%2C0%7C38%2C3%7C39%2C5%7C40%2C7%7C41; AMCV_8F99160E571FC0427F000101%40AdobeOrg=1687686476%7CMCIDTS%7C17815%7CMCMID%7C06190926912394866332201068225881085915%7CMCAID%7CNONE%7CMCOPTOUT-1539184576s%7CNONE%7CMCAAMLH-1539782226%7C7%7CMCAAMB-1539782226%7Cj8Odv6LonN4r3an7LhD3WZrU1bUpAkFkkiY1ncBR96t2PTI%7CMCSYNCSOP%7C411-17788%7CvVersion%7C3.0.0%7CMCCIDH%7C-1534066966; s_cc=true; s_sq=%5B%5BB%5D%5D; ApplicationGatewayAffinity=fb3fe7cd787cdeddaccfc03ba607cff4584d634a2cf8577d8706274c05ffc0db"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[Via] = "1.1 OURDISPATCHER.adobecqms.net (dispatcher)"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[X-Forwarded-For] = "172.27.58.100"
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] request.headers[Server-Agent] = "Communique-Dispatcher"
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Cache file successfully created: /mnt/var/www/html/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Storing socket for later reuse: 172.27.58.37:4503
[Wed Oct 10 11:34:02 2018] [I] [pid 27028 (tid 139767018645248)] "GET /content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html" - + 43ms [publishfarm/0]
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] checking [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-plugins.min.css]
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] Cache rule entry /0000 allowed '/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-plugins.min.css'
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] cache-action for [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-plugins.min.css]: DELIVER
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] request declined
[Wed Oct 10 11:34:02 2018] [I] [pid 27028 (tid 139767018645248)] "GET /etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-plugins.min.css" - - 0ms [publishfarm/-]
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] checking [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs.min.css]
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] Cache rule entry /0000 allowed '/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs.min.css'
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] cache-action for [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs.min.css]: DELIVER
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] request declined
[Wed Oct 10 11:34:02 2018] [I] [pid 27028 (tid 139767018645248)] "GET /etc.clientlibs/bcbs/clientlibs/ecards/clientlibs.min.css" - - 0ms [publishfarm/-]
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] checking [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-footer.min.css]
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] Cache rule entry /0000 allowed '/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-footer.min.css'
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] cache-action for [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-footer.min.css]: DELIVER
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] request declined
[Wed Oct 10 11:34:02 2018] [I] [pid 27028 (tid 139767018645248)] "GET /etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-footer.min.css" - - 0ms [publishfarm/-]
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] checking [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-plugins.min.js]
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] Cache rule entry /0000 allowed '/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-plugins.min.js'
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] cache-action for [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-plugins.min.js]: DELIVER
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] request declined
[Wed Oct 10 11:34:02 2018] [I] [pid 27028 (tid 139767018645248)] "GET /etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-plugins.min.js" - - 0ms [publishfarm/-]
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] checking [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs.min.js]
[Wed Oct 10 11:34:02 2018] [T] [pid 27028 (tid 139767018645248)] Cache rule entry /0000 allowed '/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs.min.js'
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] cache-action for [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs.min.js]: DELIVER
[Wed Oct 10 11:34:02 2018] [D] [pid 27028 (tid 139767018645248)] request declined
[Wed Oct 10 11:34:02 2018] [I] [pid 27028 (tid 139767018645248)] "GET /etc.clientlibs/bcbs/clientlibs/ecards/clientlibs.min.js" - - 0ms [publishfarm/-]
[Wed Oct 10 11:34:03 2018] [D] [pid 27028 (tid 139767018645248)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:03 2018] [D] [pid 27028 (tid 139767018645248)] checking [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-footer.min.js]
[Wed Oct 10 11:34:03 2018] [T] [pid 27028 (tid 139767018645248)] Cache rule entry /0000 allowed '/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-footer.min.js'
[Wed Oct 10 11:34:03 2018] [D] [pid 27028 (tid 139767018645248)] cache-action for [/etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-footer.min.js]: DELIVER
[Wed Oct 10 11:34:03 2018] [D] [pid 27028 (tid 139767018645248)] request declined
[Wed Oct 10 11:34:03 2018] [I] [pid 27028 (tid 139767018645248)] "GET /etc.clientlibs/bcbs/clientlibs/ecards/clientlibs-footer.min.js" - - 0ms [publishfarm/-]
[Wed Oct 10 11:34:03 2018] [D] [pid 27026 (tid 139766926325504)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:03 2018] [D] [pid 27026 (tid 139766926325504)] checking [/]
[Wed Oct 10 11:34:03 2018] [D] [pid 27026 (tid 139766926325504)] request URL has no extension: /
[Wed Oct 10 11:34:03 2018] [D] [pid 27026 (tid 139766926325504)] cache-action for [/]: NONE
[Wed Oct 10 11:34:03 2018] [T] [pid 27026 (tid 139766926325504)] Filter rule entry /0001 blocked 'GET / HTTP/1.1'
[Wed Oct 10 11:34:03 2018] [D] [pid 27026 (tid 139766926325504)] Filter rejects: GET / HTTP/1.1
[Wed Oct 10 11:34:03 2018] [I] [pid 27026 (tid 139766926325504)] "GET /" ! - 0ms [publishfarm/-]
[Wed Oct 10 11:34:03 2018] [D] [pid 27027 (tid 139766917932800)] Found farm publishfarm for OURDISPATCHER.adobecqms.net
[Wed Oct 10 11:34:03 2018] [D] [pid 27027 (tid 139766917932800)] checking [/]
[Wed Oct 10 11:34:03 2018] [D] [pid 27027 (tid 139766917932800)] request URL has no extension: /
[Wed Oct 10 11:34:03 2018] [D] [pid 27027 (tid 139766917932800)] cache-action for [/]: NONE
[Wed Oct 10 11:34:03 2018] [T] [pid 27027 (tid 139766917932800)] Filter rule entry /0001 blocked 'GET / HTTP/1.1'
[Wed Oct 10 11:34:03 2018] [D] [pid 27027 (tid 139766917932800)] Filter rejects: GET / HTTP/1.1
[Wed Oct 10 11:34:03 2018] [I] [pid 27027 (tid 139766917932800)] "GET /" ! - 0ms [publishfarm/-]

http://staging.OURSITE.com/viewall/nutrition/just-eat-half (after above caching)

[Wed Oct 10 11:40:57 2018] [D] [pid 24219 (tid 140281877882624)] Found farm publishfarm for staging.OURSITE.com
[Wed Oct 10 11:40:57 2018] [D] [pid 24219 (tid 140281877882624)] checking [/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html]
[Wed Oct 10 11:40:57 2018] [T] [pid 24219 (tid 140281877882624)] Cache rule entry /0000 allowed '/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html'
[Wed Oct 10 11:40:57 2018] [D] [pid 24219 (tid 140281877882624)] cache file is newer than lastflush -> use cache [/mnt/var/www/html/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html]
[Wed Oct 10 11:40:57 2018] [D] [pid 24219 (tid 140281877882624)] cache-action for [/content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html]: DELIVER
[Wed Oct 10 11:40:57 2018] [D] [pid 24219 (tid 140281877882624)] request declined
[Wed Oct 10 11:40:57 2018] [I] [pid 24219 (tid 140281877882624)] "GET /content/bcbs/ecards/en/viewall/nutrition/just-eat-half.html" - - 0ms [publishfarm/-]