Umbraco 7.6.0 - каждое утро сайт перестает отвечать на запросы, а затем снова включается

У нас есть производственная площадка, на которой работает Umbraco 7.6.0. Каждое утро мы получаем оповещение о мониторинге из-за времени отклика на главной странице более 30 секунд, и примерно через 10-15 минут проблема решается сама собой. Прошлой ночью мы изменили уровень детализации журнала на DEBUG, и сегодня утром мы все еще не можем точно определить причину проблемы. Каждый раз, когда возникает эта проблема, мы видим то, что я считаю некоторыми последствиями проблемы — сбой задач Keep Alive и Scheduled Publish (вероятно, время ожидания истекло):

ERROR Umbraco.Web.Scheduling.ScheduledPublishing - Failed (at "https://www.[domainredacted].com:443/umbraco").
System.Threading.Tasks.TaskCanceledException: A task was canceled.
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Umbraco.Web.Scheduling.ScheduledPublishing.<PerformRunAsync>d__7.MoveNext()

введите здесь описание изображения

Поскольку использование ЦП и памяти в плане службы приложений Azure во время сбоя было стабильным, это наводит меня на мысль, что существует своего рода взаимоблокировка, и это происходит только один раз в день. К сожалению, журналы DEBUG также бесполезны. Я пытаюсь найти задачу/процедуру, которая начинается, но никогда не заканчивается. Я вижу запланированную публикацию, которая заняла 100 секунд (что довольно долго), но опять же это может быть просто эффект, вызванный проблемой, а не обязательно сама причина. У кого-нибудь есть идеи, в чем может быть проблема и / или как лучше исследовать?

Вот журнал с отредактированной конфиденциальной информацией о проекте:

2017-08-17 13:14:47,066 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: Begin finders
 2017-08-17 13:14:47,066 [P4824/D11/T56] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,066 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - [route redacted]
 2017-08-17 13:14:47,082 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - Got content, id=39911
 2017-08-17 13:14:47,082 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: End finders, no document was found (took 8ms)
 2017-08-17 13:14:47,082 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandlePublishedContent: Begin
 2017-08-17 13:14:47,082 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - EnsurePublishedContentAccess: Page is not protected
 2017-08-17 13:14:47,082 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandlePublishedContent: End
 2017-08-17 13:14:47,082 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Look for template id=1191
 2017-08-17 13:14:47,098 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Got template id=1191 alias="Property"
 2017-08-17 13:14:47,098 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Running with template id=1191 alias="Property"
 2017-08-17 13:14:47,098 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandleWildcardDomains: Path="-1,1054,1194,39911"
 2017-08-17 13:14:47,098 [P4824/D11/T71] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandleWildcardDomains: No match.
 2017-08-17 13:14:47,098 [P4824/D11/T71] DEBUG Umbraco.Web.UmbracoModule - Response status: Redirect=none, Is404=false, StatusCode=0
 2017-08-17 13:14:47,238 [P4824/D11/T30] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,285 [P4824/D11/T60] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,301 [P4824/D11/T81] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,332 [P4824/D11/T56] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,332 [P4824/D11/T10] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,379 [P4824/D11/T71] DEBUG Umbraco.Web.UmbracoModule - Total milliseconds for umbraco request to process: 312.4882
 2017-08-17 13:14:47,394 [P4824/D11/T23] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,551 [P4824/D11/T81] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,598 [P4824/D11/T58] DEBUG Umbraco.Core.Sync.ServerMessengerBase - Invoking refresher Umbraco.Web.Cache.UnpublishedPageCacheRefresher on local server for message type RefreshByInstance
 2017-08-17 13:14:47,629 [P4824/D11/T58] DEBUG UmbracoExamine.DataServices.UmbracoLogService - ReIndexNode with type: content, Provider=InternalIndexer, NodeId=40744
 2017-08-17 13:14:47,629 [P4824/D11/T58] DEBUG UmbracoExamine.DataServices.UmbracoLogService - AddSingleNodeToIndex with type: content, Provider=InternalIndexer, NodeId=40744
 2017-08-17 13:14:47,738 [P4824/D11/T10] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,738 [P4824/D11/T10] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:47,816 [P4824/D11/T58] DEBUG Umbraco.Core.Sync.ServerMessengerBase - Invoking refresher Umbraco.Web.Cache.PageCacheRefresher on local server for message type RefreshByInstance
 2017-08-17 13:14:47,879 [P4824/D11/T71] DEBUG UmbracoExamine.DataServices.UmbracoLogService - Index created for node 40744, Provider=InternalIndexer, NodeId=40744
 2017-08-17 13:14:48,160 [P4824/D11/T58] DEBUG UmbracoExamine.DataServices.UmbracoLogService - ReIndexNode with type: content, Provider=InternalIndexer, NodeId=40744
 2017-08-17 13:14:48,160 [P4824/D11/T58] DEBUG UmbracoExamine.DataServices.UmbracoLogService - AddSingleNodeToIndex with type: content, Provider=InternalIndexer, NodeId=40744
 2017-08-17 13:14:48,160 [P4824/D11/T58] DEBUG UmbracoExamine.DataServices.UmbracoLogService - ReIndexNode with type: content, Provider=ExternalIndexer, NodeId=40744
 2017-08-17 13:14:48,160 [P4824/D11/T58] DEBUG UmbracoExamine.DataServices.UmbracoLogService - AddSingleNodeToIndex with type: content, Provider=ExternalIndexer, NodeId=40744
 2017-08-17 13:14:48,160 [P4824/D11/T23] DEBUG UmbracoExamine.DataServices.UmbracoLogService - Index created for node 40744, Provider=InternalIndexer, NodeId=40744
 2017-08-17 13:14:48,207 [P4824/D11/T58] DEBUG Umbraco.Web.PublishedCache.XmlPublishedCache.XmlCacheFilePersister - Touched, was idle, start and save in 4000ms.
 2017-08-17 13:14:48,223 [P4824/D11/T58] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Uri="[url redacted]"
 2017-08-17 13:14:48,223 [P4824/D11/T58] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Matches no domain
 2017-08-17 13:14:48,223 [P4824/D11/T58] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Culture="en-US"
 2017-08-17 13:14:48,223 [P4824/D11/T58] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: Begin finders
 2017-08-17 13:14:48,223 [P4824/D11/T58] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - [route redacted]
 2017-08-17 13:14:48,238 [P4824/D11/T58] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - Got content, id=40744
 2017-08-17 13:14:48,238 [P4824/D11/T58] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: End finders, no document was found (took 9ms)
 2017-08-17 13:14:48,285 [P4824/D11/T61] DEBUG UmbracoExamine.DataServices.UmbracoLogService - Index created for node 40744, Provider=ExternalIndexer, NodeId=40744
 2017-08-17 13:14:48,363 [P4824/D11/T15] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:48,379 [P4824/D11/T60] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:48,816 [P4824/D11/T71] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:49,332 [P4824/D11/T81] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:49,504 [P4824/D11/T81] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:49,848 [P4824/D11/T23] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:49,894 [P4824/D11/T10] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:51,316 [P4824/D11/T68] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:51,316 [P4824/D11/T68] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Syncing from database...
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Complete (took 29ms)
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Uri="[url redacted]"
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Matches no domain
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Culture="en-US"
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContentAndTemplate: Path="/"
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: Begin finders
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - [route redacted]
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - Got content, id=1054
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: End finders, no document was found (took 3ms)
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandlePublishedContent: Begin
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - EnsurePublishedContentAccess: Page is not protected
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandlePublishedContent: End
 2017-08-17 13:14:51,348 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Look for template id=1048
 2017-08-17 13:14:51,363 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Got template id=1048 alias="Homepage"
 2017-08-17 13:14:51,363 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Running with template id=1048 alias="Homepage"
 2017-08-17 13:14:51,363 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandleWildcardDomains: Path="-1,1054"
 2017-08-17 13:14:51,363 [P4824/D11/T68] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandleWildcardDomains: No match.
 2017-08-17 13:14:51,363 [P4824/D11/T68] DEBUG Umbraco.Web.UmbracoModule - Response status: Redirect=none, Is404=false, StatusCode=0
 2017-08-17 13:14:51,426 [P4824/D11/T68] DEBUG Umbraco.Web.UmbracoModule - Total milliseconds for umbraco request to process: 109.3749
 2017-08-17 13:14:52,223 [P4824/D11/T65] DEBUG Umbraco.Web.PublishedCache.XmlPublishedCache.XmlCacheFilePersister - Timer: release.
 2017-08-17 13:14:52,223 [P4824/D11/T65] DEBUG Umbraco.Web.PublishedCache.XmlPublishedCache.XmlCacheFilePersister - Run now (sync).
 2017-08-17 13:14:52,223 [P4824/D11/T65] INFO  umbraco.content - Save Xml to file...
 2017-08-17 13:14:53,100 [P4824/D11/T10] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:53,631 [P4824/D11/T65] INFO  umbraco.content - Saved Xml to file.
 2017-08-17 13:14:54,563 [P4824/D11/T60] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:56,003 [P4824/D11/T71] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:14:59,953 [P4824/D11/T61] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks executing
 2017-08-17 13:14:59,953 [P4824/D11/T61] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks complete (took 0ms)
 2017-08-17 13:14:59,953 [P4824/D11/T61] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledTasks] Task added Umbraco.Web.Scheduling.ScheduledTasks
 2017-08-17 13:15:01,940 [P4824/D11/T60] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:01,940 [P4824/D11/T60] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Syncing from database...
 2017-08-17 13:15:01,940 [P4824/D11/T60] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Complete (took 12ms)
 2017-08-17 13:15:11,904 [P4824/D11/T56] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:12,007 [P4824/D11/T30] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:12,069 [P4824/D11/T61] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:12,413 [P4824/D11/T23] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:13,317 [P4824/D11/T15] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:15,133 [P4824/D11/T68] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:24,516 [P4824/D11/T15] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:24,532 [P4824/D11/T15] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Syncing from database...
 2017-08-17 13:15:24,532 [P4824/D11/T15] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Complete (took 11ms)
 2017-08-17 13:15:24,532 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Uri="[url redacted]"
 2017-08-17 13:15:24,532 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Matches no domain
 2017-08-17 13:15:24,532 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Culture="en-US"
 2017-08-17 13:15:24,532 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContentAndTemplate: Path="[path redacted]"
 2017-08-17 13:15:24,532 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: Begin finders
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - [route redacted]
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - Got content, id=39911
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: End finders, no document was found (took 7ms)
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandlePublishedContent: Begin
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - EnsurePublishedContentAccess: Page is not protected
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandlePublishedContent: End
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Look for template id=1191
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Got template id=1191 alias="Property"
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Running with template id=1191 alias="Property"
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandleWildcardDomains: Path="-1,1054,1194,39911"
 2017-08-17 13:15:24,547 [P4824/D11/T15] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandleWildcardDomains: No match.
 2017-08-17 13:15:24,563 [P4824/D11/T15] DEBUG Umbraco.Web.UmbracoModule - Response status: Redirect=none, Is404=false, StatusCode=0
 2017-08-17 13:15:24,625 [P4824/D11/T15] DEBUG Umbraco.Web.UmbracoModule - Total milliseconds for umbraco request to process: 93.7463
 2017-08-17 13:15:24,672 [P4824/D11/T56] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:24,844 [P4824/D11/T81] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:24,860 [P4824/D11/T65] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [Umbraco.Web.Scheduling.IBackgroundTask] Task added Umbraco.Web.Strategies.ServerRegistrationEventHandler+TouchServerTask
 2017-08-17 13:15:27,042 [P4824/D11/T30] DEBUG Umbraco.Web.Scheduling.ScheduledPublishing - Scheduled publishing executing @ [url redacted]
 2017-08-17 13:15:27,542 [P4824/D11/T15] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:27,542 [P4824/D11/T58] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:30,562 [P4824/D11/T56] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:31,570 [P4824/D11/T68] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:31,570 [P4824/D11/T30] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:31,570 [P4824/D11/T68] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Syncing from database...
 2017-08-17 13:15:31,586 [P4824/D11/T68] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Complete (took 10ms)
 2017-08-17 13:15:44,659 [P4824/D11/T23] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:44,659 [P4824/D11/T23] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Syncing from database...
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Core.Sync.DatabaseServerMessenger - Complete (took 10ms)
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Uri="[url redacted]"
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Matches no domain
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindDomain: Culture="en-US"
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContentAndTemplate: Path="[path redacted]"
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: Begin finders
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - [route redacted]
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.ContentFinderByNiceUrl - Got content, id=39911
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindPublishedContent: End finders, no document was found (took 3ms)
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandlePublishedContent: Begin
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - EnsurePublishedContentAccess: Page is not protected
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandlePublishedContent: End
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Look for template id=1191
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Got template id=1191 alias="Property"
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - FindTemplate: Running with template id=1191 alias="Property"
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandleWildcardDomains: Path="-1,1054,1194,39911"
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.Routing.PublishedContentRequestEngine - HandleWildcardDomains: No match.
 2017-08-17 13:15:44,674 [P4824/D11/T23] DEBUG Umbraco.Web.UmbracoModule - Response status: Redirect=none, Is404=false, StatusCode=0
 2017-08-17 13:15:44,754 [P4824/D11/T23] DEBUG Umbraco.Web.UmbracoModule - Total milliseconds for umbraco request to process: 95.139
 2017-08-17 13:15:47,585 [P4824/D11/T60] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:47,960 [P4824/D11/T30] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:48,006 [P4824/D11/T15] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:48,073 [P4824/D11/T56] DEBUG Umbraco.Web.UmbracoModule - Begin request: [url redacted]
 2017-08-17 13:15:59,958 [P4824/D11/T60] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks executing
 2017-08-17 13:15:59,958 [P4824/D11/T60] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks complete (took 0ms)
 2017-08-17 13:15:59,958 [P4824/D11/T60] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledTasks] Task added Umbraco.Web.Scheduling.ScheduledTasks
 2017-08-17 13:16:24,966 [P4824/D11/T56] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [Umbraco.Web.Scheduling.IBackgroundTask] Task added Umbraco.Web.Strategies.ServerRegistrationEventHandler+TouchServerTask
 2017-08-17 13:16:59,968 [P4824/D11/T56] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks executing
 2017-08-17 13:16:59,968 [P4824/D11/T56] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks complete (took 0ms)
 2017-08-17 13:16:59,968 [P4824/D11/T56] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledTasks] Task added Umbraco.Web.Scheduling.ScheduledTasks
 2017-08-17 13:17:07,056 [P4824/D11/T15] ERROR Umbraco.Web.Scheduling.ScheduledPublishing - Failed (at "[url redacted]").
System.Threading.Tasks.TaskCanceledException: A task was canceled.
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Umbraco.Web.Scheduling.ScheduledPublishing.<PerformRunAsync>d__7.MoveNext()
 2017-08-17 13:17:07,056 [P4824/D11/T15] DEBUG Umbraco.Web.Scheduling.ScheduledPublishing - Scheduled publishing complete (took 100027ms)
 2017-08-17 13:17:07,056 [P4824/D11/T15] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledPublishing] Task added Umbraco.Web.Scheduling.ScheduledPublishing
 2017-08-17 13:17:25,037 [P4824/D11/T56] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [Umbraco.Web.Scheduling.IBackgroundTask] Task added Umbraco.Web.Strategies.ServerRegistrationEventHandler+TouchServerTask
 2017-08-17 13:17:59,197 [P4824/D11/T15] DEBUG Umbraco.Web.Scheduling.KeepAlive - Keep alive executing
 2017-08-17 13:17:59,973 [P4824/D11/T61] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks executing
 2017-08-17 13:17:59,973 [P4824/D11/T61] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks complete (took 0ms)
 2017-08-17 13:17:59,973 [P4824/D11/T61] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledTasks] Task added Umbraco.Web.Scheduling.ScheduledTasks
 2017-08-17 13:18:07,064 [P4824/D11/T23] DEBUG Umbraco.Web.Scheduling.ScheduledPublishing - Scheduled publishing executing @ [url redacted]
 2017-08-17 13:18:25,077 [P4824/D11/T60] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [Umbraco.Web.Scheduling.IBackgroundTask] Task added Umbraco.Web.Strategies.ServerRegistrationEventHandler+TouchServerTask
 2017-08-17 13:18:59,982 [P4824/D11/T56] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks executing
 2017-08-17 13:18:59,982 [P4824/D11/T56] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks complete (took 0ms)
 2017-08-17 13:18:59,982 [P4824/D11/T56] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledTasks] Task added Umbraco.Web.Scheduling.ScheduledTasks
 2017-08-17 13:19:25,184 [P4824/D11/T60] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [Umbraco.Web.Scheduling.IBackgroundTask] Task added Umbraco.Web.Strategies.ServerRegistrationEventHandler+TouchServerTask
 2017-08-17 13:19:39,208 [P4824/D11/T65] ERROR Umbraco.Web.Scheduling.KeepAlive - Failed (at "[url redacted]").
System.Threading.Tasks.TaskCanceledException: A task was canceled.
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Umbraco.Web.Scheduling.KeepAlive.<PerformRunAsync>d__4.MoveNext()
 2017-08-17 13:19:39,208 [P4824/D11/T65] DEBUG Umbraco.Web.Scheduling.KeepAlive - Keep alive complete (took 100016ms)
 2017-08-17 13:19:39,208 [P4824/D11/T65] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [KeepAlive] Task added Umbraco.Web.Scheduling.KeepAlive
 2017-08-17 13:19:47,085 [P4824/D11/T61] ERROR Umbraco.Web.Scheduling.ScheduledPublishing - Failed (at "[url redacted]").
System.Threading.Tasks.TaskCanceledException: A task was canceled.
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Umbraco.Web.Scheduling.ScheduledPublishing.<PerformRunAsync>d__7.MoveNext()
 2017-08-17 13:19:47,085 [P4824/D11/T61] DEBUG Umbraco.Web.Scheduling.ScheduledPublishing - Scheduled publishing complete (took 100023ms)
 2017-08-17 13:19:47,085 [P4824/D11/T61] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledPublishing] Task added Umbraco.Web.Scheduling.ScheduledPublishing
 2017-08-17 13:19:59,992 [P4824/D11/T15] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks executing
 2017-08-17 13:19:59,992 [P4824/D11/T15] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks complete (took 0ms)
 2017-08-17 13:19:59,992 [P4824/D11/T15] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledTasks] Task added Umbraco.Web.Scheduling.ScheduledTasks
 2017-08-17 13:20:25,261 [P4824/D11/T60] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [Umbraco.Web.Scheduling.IBackgroundTask] Task added Umbraco.Web.Strategies.ServerRegistrationEventHandler+TouchServerTask
 2017-08-17 13:20:47,105 [P4824/D11/T23] DEBUG Umbraco.Web.Scheduling.ScheduledPublishing - Scheduled publishing executing @ [url redacted]
 2017-08-17 13:20:54,710 [P4824/D11/T75] DEBUG Umbraco.Web.Scheduling.ScheduledPublishing - Scheduled publishing complete (took 7608ms)
 2017-08-17 13:20:54,710 [P4824/D11/T75] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledPublishing] Task added Umbraco.Web.Scheduling.ScheduledPublishing
 2017-08-17 13:21:00,012 [P4824/D11/T23] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks executing
 2017-08-17 13:21:00,012 [P4824/D11/T23] DEBUG Umbraco.Web.Scheduling.ScheduledTasks - Scheduled tasks complete (took 0ms)
 2017-08-17 13:21:00,012 [P4824/D11/T23] DEBUG Umbraco.Web.Scheduling.BackgroundTaskRunner - [ScheduledTasks] Task added Umbraco.Web.Scheduling.ScheduledTasks

person Keith    schedule 17.08.2017    source источник
comment
Просто предположение, но, возможно, IIS перерабатывает веб-сайт, и для повторного запуска требуется некоторое время? Ознакомьтесь с этим вопросом, чтобы узнать об отключении автоматических перезапусков: stackoverflow.com/q/3156925/2963111   -  person harvzor    schedule 30.08.2017
comment
Вы также можете включить монитор запросов в IIS. Это позволяет вам видеть текущие веб-запросы, сделанные к вашему приложению, с количеством времени, в течение которого запрос обрабатывается. Это может быть весьма полезно: stackoverflow.com/q/15621509/2963111   -  person harvzor    schedule 30.08.2017
comment
@Harvey Это довольно стандартная причина, по которой вы это увидите, но в приведенном выше сценарии это не так.   -  person Keith    schedule 31.08.2017
comment
@Harvey Существует ли служба приложений Azure, эквивалентная монитору запросов?   -  person Keith    schedule 31.08.2017


Ответы (1)


Если он размещен в приложении Azure, скорее всего, это будет запуск приложения или перемещение приложения. Если ваш сайт не оптимизирован достаточно хорошо, иногда вы можете наблюдать довольно медленное время запуска в Azure Apps, я наблюдал более 20 минут на особенно плохо оптимизированных сайтах.

Я думаю, что главный виновник — файловый ввод-вывод во время запуска приложения. Сайт достаточно большой? Также есть ли у него много пользовательских индексов Examine?

Вот несколько полезных советов по повышению производительности в Azure: https://cultiv.nl/blog/making-sure-your-umbraco-site-performs-on-azure/

person Tim    schedule 07.09.2017