0
我們有生產現場運行的一把umbraco 7.6.0。每天早上，由於主頁上的響應時間超過30s，我們會收到監控警報，大約10-15分鐘後，問題會自行解決。昨天晚上，我們將日誌冗長度改爲DEBUG，今天早上我們仍然無法確定問題的原因。每次發生這個問題的時候，我們看到了什麼，我認爲是這個問題的一些影響 - 保活和計劃發佈任務失敗（可能是超時）：一把umbraco 7.6.0 - 網站每天早晨變得沒有響應，然後回來了
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()
由於CPU和在停電期間，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
只是一個猜測，但也許IIS是回收的網站，它需要一些時間來重新啓動？看看這個問題，看看有關禁用自動重啓：https://stackoverflow.com/q/3156925/2963111 – Harvey
您還可以在IIS打開請求監視器。它允許您查看當前對您的應用程序進行的網絡請求，並請求處理該請求的時間。它可以是非常有用：https://stackoverflow.com/q/15621509/2963111 – Harvey
@Harvey這就是你會看到這樣一個非常標準的原因，但不是在上述情況下的情況。 – Keith