U4-6802 - Blank screen / Failed to load Xml from file error after upgrade to 7.2.6

Created by Arjan Hoogervorst 08 Jul 2015, 08:43:11 Updated by Matthew 12 Oct 2015, 17:51:08

I'm hosting on a Windows 2008 R2 server, and I started seeing the following issue after upgrading from Umbraco version 7.2.4 to 7.2.6 yesterday. We run a scheduled apppool recycle every day at 5:00am, and this morning a "Failed to load Xml from file" error showed up after the schedule recycle @ 05:02:20,600, which caused the site to display a blank screen until I performed a manual apppool recycle.

2015-07-08 05:00:14,176 [6] INFO Umbraco.Core.CoreBootManager - [T1/D2] Umbraco application starting 2015-07-08 05:00:14,816 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Determining hash of code files on disk 2015-07-08 05:00:14,940 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Hash determined (took 125ms) 2015-07-08 05:00:14,956 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Starting resolution types of umbraco.interfaces.IApplicationStartupHandler 2015-07-08 05:00:15,861 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Completed resolution of types of umbraco.interfaces.IApplicationStartupHandler, found 39 (took 901ms) 2015-07-08 05:00:16,376 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Starting resolution types of Umbraco.Core.PropertyEditors.IPropertyEditorValueConverter 2015-07-08 05:00:16,376 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Completed resolution of types of Umbraco.Core.PropertyEditors.IPropertyEditorValueConverter, found 0 (took 2ms) 2015-07-08 05:00:16,376 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Starting resolution types of Umbraco.Core.PropertyEditors.IPropertyValueConverter 2015-07-08 05:00:16,376 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Completed resolution of types of Umbraco.Core.PropertyEditors.IPropertyValueConverter, found 16 (took 1ms) 2015-07-08 05:00:16,422 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Starting resolution types of Umbraco.Web.Mvc.SurfaceController 2015-07-08 05:00:16,656 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Completed resolution of types of Umbraco.Web.Mvc.SurfaceController, found 6 (took 239ms) 2015-07-08 05:00:16,656 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Starting resolution types of Umbraco.Web.WebApi.UmbracoApiController 2015-07-08 05:00:16,781 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Completed resolution of types of Umbraco.Web.WebApi.UmbracoApiController, found 48 (took 131ms) 2015-07-08 05:00:17,046 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Starting resolution types of Umbraco.Core.Media.IThumbnailProvider 2015-07-08 05:00:17,046 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Completed resolution of types of Umbraco.Core.Media.IThumbnailProvider, found 3 (took 0ms) 2015-07-08 05:00:17,046 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Starting resolution types of Umbraco.Core.Media.IImageUrlProvider 2015-07-08 05:00:17,046 [6] INFO Umbraco.Core.PluginManager - [T1/D2] Completed resolution of types of Umbraco.Core.Media.IImageUrlProvider, found 1 (took 0ms) 2015-07-08 05:00:19,246 [6] INFO Umbraco.Web.Search.ExamineEvents - [T1/D2] Initializing Examine and binding to business logic events 2015-07-08 05:00:19,667 [6] INFO Umbraco.Web.Search.ExamineEvents - [T1/D2] Adding examine event handlers for index providers: 3 2015-07-08 05:00:19,714 [6] INFO Umbraco.Core.CoreBootManager - [T1/D2] Umbraco application startup complete (took 5182ms) 2015-07-08 05:00:22,943 [6] INFO Umbraco.Web.UmbracoModule - [T9/D2] Setting OriginalRequestUrl: localhost:80/umbraco 2015-07-08 05:00:23,052 [6] INFO umbraco.content - [T9/D2] Load Xml from file... 2015-07-08 05:02:20,600 [6] ERROR umbraco.content - [T9/D2] Failed to load Xml from file. System.Threading.ThreadAbortException: Thread was being aborted. at System.Threading.WaitHandle.WaitOneNative(SafeHandle waitableSafeHandle, UInt32 millisecondsTimeout, Boolean hasThreadAffinity, Boolean exitContext) at System.Threading.WaitHandle.InternalWaitOne(SafeHandle waitableSafeHandle, Int64 millisecondsTimeout, Boolean hasThreadAffinity, Boolean exitContext) at Umbraco.Core.AsyncLock.Lock(Int32 millisecondsTimeout) at umbraco.content.EnsureFileLock() at umbraco.content.LoadXmlFromFile() 2015-07-08 05:02:24,671 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [XmlCacheFilePersister] Terminating 2015-07-08 05:02:24,671 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [XmlCacheFilePersister] Waiting for tasks to complete 2015-07-08 05:02:24,671 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [LogScrubber] Terminating 2015-07-08 05:02:24,671 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [LogScrubber] Waiting for tasks to complete 2015-07-08 05:02:24,671 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [LogScrubber] Tasks completed, terminated 2015-07-08 05:02:24,671 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [ScheduledPublishing] Terminating 2015-07-08 05:02:24,671 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [ScheduledPublishing] Waiting for tasks to complete 2015-07-08 05:02:24,671 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [ScheduledPublishing] Tasks completed, terminated 2015-07-08 05:02:24,687 [15] INFO umbraco.content - [T268/D8] Save Xml to file... 2015-07-08 05:02:24,702 [15] INFO umbraco.content - [T212/D8] Saved Xml to file. 2015-07-08 05:02:24,702 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T215/D8] [XmlCacheFilePersister] Tasks completed, terminated 2015-07-08 05:02:24,827 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [ScheduledTasks] Terminating 2015-07-08 05:02:24,827 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [ScheduledTasks] Waiting for tasks to complete 2015-07-08 05:02:24,827 [15] INFO Umbraco.Web.Scheduling.BackgroundTaskRunner - [T228/D8] [ScheduledTasks] Tasks completed, terminated 2015-07-08 05:02:24,827 [15] INFO Umbraco.Core.UmbracoApplicationBase - [T228/D8] Application shutdown. Reason: HostingEnvironment

Looks like others are dealing with the "blank screen" issue related to the error as well: https://our.umbraco.org/forum/umbraco-7/using-umbraco-7/66890-random-white-screen-on-website-but-umbraco-is-responsive

This seems to be unrelated to issues http://issues.umbraco.org/issue/U4-6338 and http://issues.umbraco.org/issue/U4-6669. I ran the test project attached to those issues on our server, and it worked fine, without any errors.

Comments

schlubadub 13 Jul 2015, 13:41:40

FYI I'm having the same issue on 7.2.6... my logfile looks the same as yours (http://issues.umbraco.org/issue/U4-5728#comment=67-21688). I've posted my problems in these issues http://issues.umbraco.org/issue/U4-6626 and http://issues.umbraco.org/issue/U4-5728.

Stephan just posted that our issue should, hopefully, be fixed in 7.2.7 http://issues.umbraco.org/issue/U4-6626#comment=67-21714


Matthew 12 Oct 2015, 17:51:08

I'm still having this issue multiple times per day on our 7.2.8 install. I'm forced to restart the site every time a user complains about getting a blank page in the backoffice. I noticed that this only happens with FireFox, as Chrome loads the backoffice after an initial delay.

I wish there were more I could do about this but I haven't seen any relevant errors in the server's Event Viewer and the Umbraco log is only showing Info level reports with multiple application shutdowns and calls to BackgroundTaskRunner and PluginManager.


Priority: Major

Type: Bug

State: Fixed

Assignee: Stephan

Difficulty: Normal

Category:

Backwards Compatible: True

Fix Submitted:

Affected versions: 7.2.6

Due in version: 7.2.7

Sprint:

Story Points:

Cycle: