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.

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?

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

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.

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')

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

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.

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.

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.

Hi Johan

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

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.