It takes ages before new content shows on my 2 front ends

Vote:
 

Hi

I have a load balanced setup with one edit server and 2 front ends. When editor does changes to existing content the changes will show on front end servers at once, but if they publish new content it takes a while, maybe minutes.
The edit server has the new content at once.

Can someone tell me the missing pice of setting/code here.

I just updated to latest version of cms in my staging enionment, but no improvement.

Regards Øyvind

#201633
Feb 25, 2019 18:02
Vote:
 

Have you checked that the events system is correct events are being fired and recieved by your servers? https://world.episerver.com/documentation/developer-guides/CMS/event-management/ sometimes I've known the UDP ports to be blocked or have issues so it might be worth checking your network is sending/recieving correctly on your servers. You can switch to TCP if you have issue, I know some hosting companies block UDP.

#201638
Edited, Feb 26, 2019 9:51
Vote:
 

Initially, I was thinking the same, Scott.

But Øyvind wrote that updates to existing content are immediately re-read and shown on the frontend servers. To me that sounds like the events are properly sent and received.

Øyvind, how is the new content not available? Missing in autogenerated menus? If it is a page, can you access it directly at its URL?

#201643
Feb 26, 2019 11:04
Vote:
 

Yes I was putting this down just as a double check first off and then was going to explore more options

#201644
Feb 26, 2019 11:09
Vote:
 

Gut feel / guess: Maybe parts of the rendered page are cached for performance (good), perhaps the cache uses the standard HTTP cache and not the Episerver cache implementation which is aware of content publising events. As I said its a wild guess but it might help track down the issue.

#201646
Feb 26, 2019 12:41
Vote:
 

Hi thanks for helping me with this.

I did some more testing on this today.

I published a "test1" page on my edit server. This content gets ID 24500.

When i request this page on one of the front ends (on the server with localhost) it looks like its routed to the correct content

2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Web.Routing.Segments.Internal.NodeSegment: Url 'http://localhost/test1/' was routed to content with id '24500' and language was set to 'en'

Maybe the issue is with BVNetwork.NotFound.Core.CustomRedirects.CustomRedirectHandler?

Here is the full log on page request.

2019-03-14 11:29:30,592 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '9484e34b-b419-4e59-8fd5-3277668a7fce' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:30,592 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '59769138-7ee5-4de1-903b-b273fabee1bd' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:30,592 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '51da5053-6af8-4a10-9bd4-8417e48f38bd' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:30,592 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '1ee4c0b5-ca95-4bdb-b0d4-e5d9e91189aa' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:30,592 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: 'd464d910-68ef-402a-98c6-72a2c95dcdba' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:30,592 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '96728921-417c-4061-b278-c5621bd4f995' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:30,592 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '888b5c89-9b0f-4e67-a3b0-6e660ab9a60f' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:30,592 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '414cda81-8720-41f1-bad2-7d6155f419dc' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:32,482 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/CMS/11.17.1'
2019-03-14 11:29:32,482 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/Shell/11.17.1'
2019-03-14 11:29:32,482 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.VisitorGroupsCriteriaPack/2.0.1'
2019-03-14 11:29:32,482 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Forms.UI/4.23.0'
2019-03-14 11:29:32,482 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Forms/4.23.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Cms.TinyMce/2.7.1'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Labs.LanguageManager/3.2.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Cms.AddOns.Blocks/2.4.1'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Search.Cms'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Packaging.UI/3.4.0.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/BVNetwork.404Handler'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Social/2.3.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/episerver'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/modules'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Web.Routing.Segments.Internal.NodeSegment: Url 'http://localhost/test1/' was routed to content with id '24500' and language was set to 'en'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Web.TemplateResolver: StandardPage: Selected Customername.Controllers.StandardPageController. (tag='', channel='web', category='MvcController')
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/CMS/11.17.1'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/Shell/11.17.1'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.VisitorGroupsCriteriaPack/2.0.1'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Forms.UI/4.23.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Forms/4.23.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Cms.TinyMce/2.7.1'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Labs.LanguageManager/3.2.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Cms.AddOns.Blocks/2.4.1'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Search.Cms'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Packaging.UI/3.4.0.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/BVNetwork.404Handler'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Social/2.3.0'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/episerver'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/modules'
2019-03-14 11:29:32,498 [33] DEBUG EPiServer.Web.TemplateResolver: StandardPage: Selected Customername.Controllers.StandardPageController. (tag='', channel='web', category='Request')
2019-03-14 11:29:32,498 [33] DEBUG BVNetwork.NotFound.Core.CustomRedirects.CustomRedirectHandler: Begin: Get Current CustomRedirectHandler
2019-03-14 11:29:32,498 [33] DEBUG BVNetwork.NotFound.Core.CustomRedirects.CustomRedirectHandler: Returning cached handler.
2019-03-14 11:29:32,514 [33] DEBUG BVNetwork.NotFound.Core.CustomRedirects.CustomRedirectHandler: End: Get Current CustomRedirectHandler
2019-03-14 11:29:32,514 [33] DEBUG BVNetwork.NotFound.Core.RequestHandler: Handling 404 request.
Request URL: http://localhost/test1/
Response status code: 404
2019-03-14 11:29:32,514 [33] DEBUG BVNetwork.NotFound.Core.RequestHandler: Not handled. Current URL is ignored or no redirect found.
Request URL: http://localhost/test1/
Response status code: 404
2019-03-14 11:29:35,607 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '9484e34b-b419-4e59-8fd5-3277668a7fce' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:35,607 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '59769138-7ee5-4de1-903b-b273fabee1bd' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:35,607 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '51da5053-6af8-4a10-9bd4-8417e48f38bd' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:35,607 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '1ee4c0b5-ca95-4bdb-b0d4-e5d9e91189aa' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:35,607 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: 'd464d910-68ef-402a-98c6-72a2c95dcdba' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:35,607 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '96728921-417c-4061-b278-c5621bd4f995' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:35,607 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '888b5c89-9b0f-4e67-a3b0-6e660ab9a60f' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')
2019-03-14 11:29:35,607 [23] DEBUG EPiServer.Events.Providers.Internal.EventMessageValidator: Validated sequence of 0 events. Sequence contained no missed events. (Event: '414cda81-8720-41f1-bad2-7d6155f419dc' Raiser: '9d0990a8-906f-49af-95ff-df03391984ea')

#202104
Mar 14, 2019 11:42
Vote:
 

After a while when it works i can see my controller debug messsage

2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Web.Routing.Segments.Internal.NodeSegment: Url 'http://localhost/test1/' was routed to content with id '24501' and language was set to 'en'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Web.TemplateResolver: StandardPage: Selected Customername.Controllers.StandardPageController. (tag='', channel='web', category='MvcController')
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/CMS/11.17.1'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/Shell/11.17.1'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.VisitorGroupsCriteriaPack/2.0.1'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Forms.UI/4.23.0'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Forms/4.23.0'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Cms.TinyMce/2.7.1'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Labs.LanguageManager/3.2.0'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Cms.AddOns.Blocks/2.4.1'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Search.Cms'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Packaging.UI/3.4.0.0'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/EPiServer.Social/2.3.0'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/EPiServer/BVNetwork.404Handler'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/episerver'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Shell.Web.Routing.ModuleRouteCollection: Not routing '~/test1/' since it doesn't start with '~/modules'
2019-03-14 12:57:19,904 [60] DEBUG EPiServer.Web.TemplateResolver: StandardPage: Selected Customername.Controllers.StandardPageController. (tag='', channel='web', category='Request')
2019-03-14 12:57:19,904 [60] DEBUG Customername.Controllers.StandardPageController: Message from controller, CurrentPage is not null

#202110
Mar 14, 2019 13:14
Vote:
 

Back with new questions, Øyvind. 😉

I think it’s interesting that it routes to content 24500 in your first dump and to content 24501 in your second. Are you requesting the same page both times?

In the second dump, when the request hit the action, you logged that “currentPage is not null”. Does it even hit the action at all in the first dump (and is currentPage then null)?

I don’t think the BVN handler is at fault here. It is merely trying to recover the 404 response.

#202157
Mar 17, 2019 15:51
Vote:
 

An easy test is to turn off Epi's object cache (pageCacheSlidingExpiration) on the fronts and see if the issue is still there.
https://world.episerver.com/documentation/developer-guides/CMS/configuration/Configuring-episerver/

If it's still there then the issue is with caching in the solution's custom code. Otherwise it's most likely something not working with the UDP communication.

#202172
Mar 18, 2019 13:34
Vote:
 

Hi Stefan. I deleted page 24500 and created page 21501. The second dump is to another page. (nice catch :) )
Its correct that it does does not hit the controller action at all.
BVN handler is not the issue with this is also correct.

#202176
Mar 18, 2019 15:08
Vote:
 

Hi Johan

i tried with  pageCacheSlidingExpiration="0:0:0" and it did not help. No page and after some mins the url works

#202177
Mar 18, 2019 15:17
Vote:
 

This have worked for a long time, but i guess it stopped working after an upgrade. The event works fine for Update and delete, But new content is not so smoot.

#202178
Mar 18, 2019 15:40
* You are NOT allowed to include any hyperlinks in the post because your account hasn't associated to your company. User profile should be updated.