Umbraco application keeps restarting (hosted on Azure Cloud Service)
Hi,
I am running Umbraco on an Azure Cloud Service (A1 instance: 1 core, 1.75Gb RAM, Windows Server 2012) and the application keeps restarting - it seems to do this each time I publish something! I don't see this error on my laptop (8.1 Pro) so I'm assuming it's something to do with the hosting environment?
I am intercepting the Publish event and inserting data from the Umbraco content item into a second database, but that process all sees to be running fine.
Here's a 'debug' version of the Umbraco log file where you can see the publish happening at the top, and then 15 seconds later the "Umbraco.Core.CoreBootManager - [Thread 8] Umbraco application starting" message appears:
2015-03-26 11:19:52,964 [13] INFO Umbraco.Core.Publishing.PublishingStrategy - [Thread 24] Content 'How to read and spell' with Id '1059' has been published.
2015-03-26 11:19:53,746 [13] INFO Umbraco.Core.PluginManager - [Thread 24] Starting resolution types of umbraco.interfaces.ICacheRefresher
2015-03-26 11:19:53,746 [13] DEBUG Umbraco.Core.PluginManager - [Thread 24] Loaded plugin types umbraco.interfaces.ICacheRefresher with resolution FindAllTypes from persisted cache
2015-03-26 11:19:53,746 [13] DEBUG Umbraco.Core.PluginManager - [Thread 24] Caching of typelist for type umbraco.interfaces.ICacheRefresher and resolution FindAllTypes was successful = True
2015-03-26 11:19:53,746 [13] INFO Umbraco.Core.PluginManager - [Thread 24] Completed resolution of types of umbraco.interfaces.ICacheRefresher, found 20 (took 1ms)
2015-03-26 11:19:53,776 [13] DEBUG Umbraco.Core.Sync.DefaultServerMessenger - [Thread 24] Invoking refresher Umbraco.Web.Cache.UnpublishedPageCacheRefresher on single server instance, message type RefreshById
2015-03-26 11:19:53,807 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 24] ReIndexNode with type: content, Provider=InternalIndexer, NodeId=1059
2015-03-26 11:19:53,825 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 24] AddSingleNodeToIndex with type: content, Provider=InternalIndexer, NodeId=1059
2015-03-26 11:19:55,729 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 16] Index created for node 1059, Provider=InternalIndexer, NodeId=1059
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.KeepAlive - [Thread 31] Keep alive executing
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.Scheduler - [Thread 10] Scheduling interval executing
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner`1[[Umbraco.Web.Scheduling.ScheduledPublishing, umbraco, Version=1.0.5529.18525, Culture=neutral, PublicKeyToken=null]] - [Thread 10] Task added Umbraco.Web.Scheduling.ScheduledPublishing
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner`1[[Umbraco.Web.Scheduling.ScheduledPublishing, umbraco, Version=1.0.5529.18525, Culture=neutral, PublicKeyToken=null]] - [Thread 10] Starting
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner`1[[Umbraco.Web.Scheduling.ScheduledTasks, umbraco, Version=1.0.5529.18525, Culture=neutral, PublicKeyToken=null]] - [Thread 10] Task added Umbraco.Web.Scheduling.ScheduledTasks
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner`1[[Umbraco.Web.Scheduling.ScheduledTasks, umbraco, Version=1.0.5529.18525, Culture=neutral, PublicKeyToken=null]] - [Thread 10] Starting
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.Scheduler - [Thread 10] Scheduling interval complete (took 0ms)
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - [Thread 10] Scheduled tasks executing
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - [Thread 10] Scheduled tasks complete (took 0ms)
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner`1[[Umbraco.Web.Scheduling.ScheduledTasks, umbraco, Version=1.0.5529.18525, Culture=neutral, PublicKeyToken=null]] - [Thread 10] Shutdown
2015-03-26 11:19:57,057 [13] DEBUG Umbraco.Web.Scheduling.ScheduledPublishing - [Thread 10] Scheduled publishing executing
2015-03-26 11:19:57,089 [13] DEBUG Umbraco.Web.UmbracoModule - [Thread 20] Begin request: http://myumbracosite.cloudapp.net/umbraco/ping.aspx.
2015-03-26 11:19:57,104 [13] DEBUG Umbraco.Web.Scheduling.KeepAlive - [Thread 31] Keep alive complete (took 50ms)
2015-03-26 11:19:57,795 [13] DEBUG Umbraco.Web.UmbracoModule - [Thread 31] Begin request: http://myumbracosite.cloudapp.net/umbraco/RestServices/ScheduledPublish/Index.
2015-03-26 11:19:57,795 [13] DEBUG Umbraco.Web.Scheduling.ScheduledPublishing - [Thread 10] Scheduled publishing complete (took 746ms)
2015-03-26 11:19:57,795 [13] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner`1[[Umbraco.Web.Scheduling.ScheduledPublishing, umbraco, Version=1.0.5529.18525, Culture=neutral, PublicKeyToken=null]] - [Thread 10] Shutdown
2015-03-26 11:20:12,057 [13] DEBUG Umbraco.Web.PublishedCache.XmlPublishedCache.PublishedMediaCache - [Thread 24] Could not retrieve media 0 from Examine index, reverting to looking up media via legacy library.GetMedia method
2015-03-26 11:20:12,154 [13] DEBUG umbraco.library - [Thread 24] No media result for id 0
2015-03-26 11:20:13,322 [13] DEBUG Umbraco.Web.UmbracoModule - [Thread 16] Begin request: http://myumbracosite.cloudapp.net/umbraco/backoffice/UmbracoApi/Authentication/GetRemainingTimeoutSeconds.
2015-03-26 11:20:13,635 [13] DEBUG Umbraco.Core.Sync.DefaultServerMessenger - [Thread 24] Invoking refresher Umbraco.Web.Cache.PageCacheRefresher on single server instance, message type RefreshById
2015-03-26 11:20:13,667 [13] DEBUG umbraco.content - [Thread 24] Initializing content on thread '' (Threadpool? True)
2015-03-26 11:20:13,682 [13] INFO umbraco.content - [Thread 24] Loading content from disk cache...
2015-03-26 11:20:13,697 [13] DEBUG umbraco.content - [Thread 24] Content initialized (loaded)
2015-03-26 11:20:13,838 [13] DEBUG Umbraco.Core.Sync.DefaultServerMessenger - [Thread 24] Invoking refresher Umbraco.Web.Cache.MacroCacheRefresher on single server instance, message type RefreshAll
2015-03-26 11:20:13,856 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 24] ReIndexNode with type: content, Provider=InternalIndexer, NodeId=1059
2015-03-26 11:20:13,856 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 24] AddSingleNodeToIndex with type: content, Provider=InternalIndexer, NodeId=1059
2015-03-26 11:20:13,856 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 24] ReIndexNode with type: content, Provider=ExternalIndexer, NodeId=1059
2015-03-26 11:20:13,856 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 24] AddSingleNodeToIndex with type: content, Provider=ExternalIndexer, NodeId=1059
2015-03-26 11:20:13,869 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 10] Index created for node 1059, Provider=InternalIndexer, NodeId=1059
2015-03-26 11:20:13,964 [13] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 10] Index created for node 1059, Provider=ExternalIndexer, NodeId=1059
2015-03-26 11:20:14,431 [13] DEBUG umbraco.content - [Thread 24] Content initialized (was already in context)
2015-03-26 11:20:15,197 [13] DEBUG umbraco.content - [Thread 24] Saving content to disk on thread '' (Threadpool? True)
2015-03-26 11:20:15,213 [13] DEBUG umbraco.content - [Thread 24] Saved content on thread '' in 00:00:00.0107323 (Threadpool? True)
2015-03-26 11:20:17,275 [27] DEBUG Umbraco.Core.CoreBootManager - [Thread 8] Umbraco application starting
2015-03-26 11:20:17,403 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Determining hash of code files on disk
2015-03-26 11:20:17,432 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Hash determined (took 19ms)
2015-03-26 11:20:17,525 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Starting resolution types of umbraco.interfaces.IApplicationStartupHandler
2015-03-26 11:20:17,591 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Loaded plugin types umbraco.interfaces.IApplicationStartupHandler with resolution FindAllTypes from persisted cache
2015-03-26 11:20:17,591 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Caching of typelist for type umbraco.interfaces.IApplicationStartupHandler and resolution FindAllTypes was successful = True
2015-03-26 11:20:17,591 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Completed resolution of types of umbraco.interfaces.IApplicationStartupHandler, found 39 (took 61ms)
2015-03-26 11:20:18,027 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Starting resolution types of Umbraco.Core.PropertyEditors.IPropertyEditorValueConverter
2015-03-26 11:20:18,027 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Loaded plugin types Umbraco.Core.PropertyEditors.IPropertyEditorValueConverter with resolution FindAllTypes from persisted cache
2015-03-26 11:20:18,027 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Caching of typelist for type Umbraco.Core.PropertyEditors.IPropertyEditorValueConverter and resolution FindAllTypes was successful = True
2015-03-26 11:20:18,027 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Completed resolution of types of Umbraco.Core.PropertyEditors.IPropertyEditorValueConverter, found 0 (took 2ms)
2015-03-26 11:20:18,027 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Starting resolution types of Umbraco.Core.PropertyEditors.IPropertyValueConverter
2015-03-26 11:20:18,027 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Loaded plugin types Umbraco.Core.PropertyEditors.IPropertyValueConverter with resolution FindAllTypes from persisted cache
2015-03-26 11:20:18,027 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Caching of typelist for type Umbraco.Core.PropertyEditors.IPropertyValueConverter and resolution FindAllTypes was successful = True
2015-03-26 11:20:18,027 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Completed resolution of types of Umbraco.Core.PropertyEditors.IPropertyValueConverter, found 17 (took 5ms)
2015-03-26 11:20:18,275 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Starting resolution types of Umbraco.Web.Mvc.SurfaceController
2015-03-26 11:20:18,275 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Loaded plugin types Umbraco.Web.Mvc.SurfaceController with resolution FindAllTypes from persisted cache
2015-03-26 11:20:18,275 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Caching of typelist for type Umbraco.Web.Mvc.SurfaceController and resolution FindAllTypes was successful = True
2015-03-26 11:20:18,275 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Completed resolution of types of Umbraco.Web.Mvc.SurfaceController, found 4 (took 1ms)
2015-03-26 11:20:18,275 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Starting resolution types of Umbraco.Web.WebApi.UmbracoApiController
2015-03-26 11:20:18,291 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Loaded plugin types Umbraco.Web.WebApi.UmbracoApiController with resolution FindAllTypes from persisted cache
2015-03-26 11:20:18,291 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Caching of typelist for type Umbraco.Web.WebApi.UmbracoApiController and resolution FindAllTypes was successful = True
2015-03-26 11:20:18,291 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Completed resolution of types of Umbraco.Web.WebApi.UmbracoApiController, found 36 (took 7ms)
2015-03-26 11:20:18,416 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Starting resolution types of Umbraco.Core.Media.IThumbnailProvider
2015-03-26 11:20:18,416 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Loaded plugin types Umbraco.Core.Media.IThumbnailProvider with resolution FindAllTypes from persisted cache
2015-03-26 11:20:18,416 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Caching of typelist for type Umbraco.Core.Media.IThumbnailProvider and resolution FindAllTypes was successful = True
2015-03-26 11:20:18,416 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Completed resolution of types of Umbraco.Core.Media.IThumbnailProvider, found 3 (took 0ms)
2015-03-26 11:20:18,416 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Starting resolution types of Umbraco.Core.Media.IImageUrlProvider
2015-03-26 11:20:18,416 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Loaded plugin types Umbraco.Core.Media.IImageUrlProvider with resolution FindAllTypes from persisted cache
2015-03-26 11:20:18,416 [27] DEBUG Umbraco.Core.PluginManager - [Thread 8] Caching of typelist for type Umbraco.Core.Media.IImageUrlProvider and resolution FindAllTypes was successful = True
2015-03-26 11:20:18,416 [27] INFO Umbraco.Core.PluginManager - [Thread 8] Completed resolution of types of Umbraco.Core.Media.IImageUrlProvider, found 1 (took 0ms)
2015-03-26 11:20:21,714 [27] DEBUG Umbraco.Core.ObjectResolution.Resolution - [Thread 8] Freezing resolution
2015-03-26 11:20:21,759 [27] DEBUG jumps.umbraco.usync.uSync - [Thread 8] Get Settings
2015-03-26 11:20:21,775 [27] DEBUG jumps.umbraco.usync.uSync - [Thread 8] Settings : Read = True
2015-03-26 11:20:21,775 [27] DEBUG jumps.umbraco.usync.uSync - [Thread 8] Settings : Write = False
2015-03-26 11:20:21,775 [27] DEBUG jumps.umbraco.usync.uSync - [Thread 8] Settings : Attach = True
2015-03-26 11:20:21,793 [27] INFO jumps.umbraco.usync.uSync - [Thread 8] uSync Starting - for detailed debug info. set priority to 'Debug' in log4net.config file
2015-03-26 11:20:21,793 [27] INFO jumps.umbraco.usync.uSync - [Thread 8] Read stopped by usync.stop
2015-03-26 11:20:21,806 [27] DEBUG jumps.umbraco.usync.uSync - [Thread 8] Attaching to Events - Start
2015-03-26 11:20:21,853 [27] DEBUG jumps.umbraco.usync.uSync - [Thread 8] Attaching to Events - End
2015-03-26 11:20:21,853 [27] INFO jumps.umbraco.usync.uSync - [Thread 8] uSync Initialized (70ms)
2015-03-26 11:20:21,962 [27] INFO Umbraco.Web.Search.ExamineEvents - [Thread 8] Initializing Examine and binding to business logic events
2015-03-26 11:20:22,011 [27] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 8] InternalIndexer indexer initializing, Provider=InternalIndexer, NodeId=-1
2015-03-26 11:20:22,322 [27] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 8] InternalMemberIndexer indexer initializing, Provider=InternalMemberIndexer, NodeId=-1
2015-03-26 11:20:22,356 [27] DEBUG UmbracoExamine.DataServices.UmbracoLogService - [Thread 8] ExternalIndexer indexer initializing, Provider=ExternalIndexer, NodeId=-1
2015-03-26 11:20:22,447 [27] INFO Umbraco.Web.Search.ExamineEvents - [Thread 8] Adding examine event handlers for index providers: 3
2015-03-26 11:20:22,495 [27] DEBUG Umbraco.Core.CoreBootManager - [Thread 8] Umbraco application startup complete (took 5177ms)
As you can see from the log file, there are no errors being logged that seem to be causing this...
The only Umbraco package I have installed is uSync, but I've tried uninstalling that and it makes no difference...
It will make 0 difference, it's not a performance problem it's a problem in their code which they have identified. There's nothing you can do if you cannot uninstall the update. I have my fingers crossed for a fix in the next windows update (second Tuesday of the month), though that doesn't help you now unfortunately.
I've tried a whole bunch of things, and the site seems to be running ok, then occasionally it will appear to restart, where it reloads in views, etc. to the cache.
Umbraco application keeps restarting (hosted on Azure Cloud Service)
Hi,
I am running Umbraco on an Azure Cloud Service (A1 instance: 1 core, 1.75Gb RAM, Windows Server 2012) and the application keeps restarting - it seems to do this each time I publish something! I don't see this error on my laptop (8.1 Pro) so I'm assuming it's something to do with the hosting environment?
I am intercepting the Publish event and inserting data from the Umbraco content item into a second database, but that process all sees to be running fine.
Here's a 'debug' version of the Umbraco log file where you can see the publish happening at the top, and then 15 seconds later the "Umbraco.Core.CoreBootManager - [Thread 8] Umbraco application starting" message appears:
As you can see from the log file, there are no errors being logged that seem to be causing this...
The only Umbraco package I have installed is uSync, but I've tried uninstalling that and it makes no difference...
Any ideas or solutions much appreciated!
Thanks,
Maff
Unfortunately this is due to a update from Microsoft that's installed on your server, they're working on it. More info and a workaround here:
http://issues.umbraco.org/issue/U4-6338
Thanks Sebastiaan - I'm going to deploy to a bigger instance (the A2 with 3.5Gb RAM) to see if this gets around the problem for now...
It will make 0 difference, it's not a performance problem it's a problem in their code which they have identified. There's nothing you can do if you cannot uninstall the update. I have my fingers crossed for a fix in the next windows update (second Tuesday of the month), though that doesn't help you now unfortunately.
Ah ok - you're right it didn't make any difference, just slightly improves the application start-up time... :)
Will keep my fingers crossed for that Windows Update then!
Thanks for the info :)
Maff
Do you know if this issue was ever resolved?
I've tried a whole bunch of things, and the site seems to be running ok, then occasionally it will appear to restart, where it reloads in views, etc. to the cache.
I have more detail here if you get a chance to look at what I've been battling: https://stackoverflow.com/questions/44099297/performance-issue-umbraco-on-azure
Thanks
is working on a reply...