From d632b46f9ab477ded725422b39be3cd6bc8de36d Mon Sep 17 00:00:00 2001
From: Warren <warren@umbraco.com>
Date: Wed, 15 Aug 2018 17:44:22 +0100
Subject: [PATCH] For each & every request give it a GUID so we can log the
 begin & end request with timings along with making this log entry VERBOSE

---
 src/Umbraco.Web/UmbracoModule.cs | 17 ++++++++++++++---
 1 file changed, 14 insertions(+), 3 deletions(-)

diff --git a/src/Umbraco.Web/UmbracoModule.cs b/src/Umbraco.Web/UmbracoModule.cs
index 61c3d80b81..14cebabdac 100644
--- a/src/Umbraco.Web/UmbracoModule.cs
+++ b/src/Umbraco.Web/UmbracoModule.cs
@@ -551,10 +551,17 @@ namespace Umbraco.Web
             // get our dependencies injected manually, through properties.
             Core.Composing.Current.Container.InjectProperties(this);
 
+            //Create a GUID to use as some form of request ID
+            var requestId = Guid.Empty;
+
             app.BeginRequest += (sender, e) =>
             {
                 var httpContext = ((HttpApplication) sender).Context;
-                Logger.Debug<UmbracoModule>("Begin request: {RequestUrl}", httpContext.Request.Url);
+
+                //Create a new Request ID/GUID
+                requestId = Guid.NewGuid();
+
+                Logger.Verbose<UmbracoModule>("Begin request [{RequestId}]: {RequestUrl}", requestId, httpContext.Request.Url);
                 BeginRequest(new HttpContextWrapper(httpContext));
             };
 
@@ -595,9 +602,13 @@ namespace Umbraco.Web
             {
                 var httpContext = ((HttpApplication) sender).Context;
 
-                if (UmbracoContext.Current != null && UmbracoContext.Current.IsFrontEndUmbracoRequest)
+                if (UmbracoContext.Current != null)
                 {
-                    Logger.Debug<UmbracoModule>("End Request: {RequestUrl} ({RequestTotalMilliseconds}ms)", httpContext.Request.Url, DateTime.Now.Subtract(UmbracoContext.Current.ObjectCreated).TotalMilliseconds);
+                    Logger.Verbose<UmbracoModule>(
+                        "End Request [{RequestId}]: {RequestUrl} ({RequestTotalMilliseconds}ms)",
+                        requestId,
+                        httpContext.Request.Url,
+                        DateTime.Now.Subtract(UmbracoContext.Current.ObjectCreated).TotalMilliseconds);
                 }
 
                 OnEndRequest(new UmbracoRequestEventArgs(UmbracoContext.Current, new HttpContextWrapper(httpContext)));