c# - MDLC 和 NDLC 跨线程的奇怪 NLog 行为

Question

我正在尝试跨线程设置 CorrelationID，以在对我的服务器的调用与对外部 Web 服务进行的相应调用之间建立链接。相关 ID 是一个 GUID，我将其保存在 NLog 的逻辑上下文结构中(逻辑上下文可以在线程之间正常工作，据说)。

这个想法是在对我的服务器的任何请求以及由于该请求而向各种 Web 服务发出的相应请求之间共享一个 GUID。我尝试同时使用 MDLC 和 NDLC。

问题在于，仅针对第一个请求正确存储该值，并且为所有后续请求保存空白值，即使为我的服务器的每个新请求正确生成了 GUID。

我尝试记录到数据库或文件中。如果我在代码中添加断点，或者在日志记录方法周围的任何位置添加 System.Threading.Sleep，问题似乎会自行解决。同样奇怪的是，我可以在逻辑上下文中设置值的方法之前或之后添加 Sleep，并且无论哪种方式它仍然有效。删除 sleep /断点会导致它再次中断。

我正在使用 NLog v4.5.2。

日志模块:

using System;
using System.Web;
using NLog;

namespace Shift.Stardust.Engine.Modules
{
    public class LoggingHttpModule : IHttpModule
    {
        public void Init(HttpApplication context)
        {
            context.BeginRequest += HandleBeginRequest;
        }

        public void Dispose()
        {
        }

        private void HandleBeginRequest(object sender, EventArgs e)
        {
            System.Threading.Thread.Sleep(500);
            var guid = Guid.NewGuid().ToString();
            NestedDiagnosticsLogicalContext.Push(guid);
        }
    }
}

在 HandleBeginRequest 中的任意位置放置断点会产生正确的输出。类似地，添加 System.Threading.Thread.Sleep(500)。当然，我不想只是为了解决这个问题而在代码中添加这样一行。

NLog 配置:

<nlog xmlns="http://www.nlog-project.org/schemas/NLog.xsd" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" internalLogFile="c:\temp\nlog-internal.txt" internalLogLevel="Trace">
  <variable name="logDirectory" value="${basedir}/logs"/>
  <targets>
    <target name="asyncdatabase"
            xsi:type="AsyncWrapper"
            queueLimit="5000"
            overflowAction="Block">
        <target xsi:type="Database"
                connectionStringName="ConnectionStringHere"
                keepConnection="true">
            <commandText>[db].[P_Log_Insert] @CreateDate, @ApplicationName, @MachineName, @LoggerName, @LogLevel, @Message, @Exception, NULL, @EngineSessionId, @CorrelationId</commandText>
            <parameter name="@CreateDate" layout="${date}"/>
            <parameter name="@ApplicationName" layout="${appsetting:name=Shift.Stardust.ApplicationName}"/>
            <parameter name="@MachineName" layout="${machinename}"/>
            <parameter name="@LoggerName" layout="${logger}"/>
            <parameter name="@LogLevel" layout="${level}"/>
            <parameter name="@Message" layout="${message}"/>
            <parameter name="@Exception" layout="${exception:format=tostring}"/>
            <parameter name="@EngineSessionId" layout="${aspnet-sessionid}"/>
            <parameter name="@CorrelationId" layout="${ndlc}"/>
        </target>
    </target>
  </targets>
  <rules>
    <logger name="Http.*" minlevel="Info" writeTo="asyncdatabase" final="true" />
  </rules>
</nlog>

我希望每个传入请求都有不同的 CorrelationID，但这仅适用于第一个请求。所有后续的值都有一个空字符串。

Answer 1

我认为对于这种情况最好写入 HTTP 上下文。

例如

HttpContext.Current.Items["myvariable"] = 123;

和用法:

${aspnet-item:variable=myvariable} - produces "123"

参见docs

您需要软件包NLog.Web (ASP.NET 非核心)为此。

注意:ASP.NET Core使用应该使用NLog.Web.AspNetCore而不是 NLog.Web