最近写了一个模拟器程序,由于模拟器中要在日志中记录对象实时变化时的完整状态,导致日志数量猛增,跑了一分钟程序就有3000多条日志。
日志多没关系,但是我发现在增加日志内容后程序在很多运行周期中常常超时。难道这个.Net Core程序用的NLog还要占用主线程的CPU资源去记录日志吗?仔细研读了NLog的文档后,发现NLog在默认状态下确实是使用记录日志时所用的线程来保存日志的。
但是你可以打开异步开关来实现在日志自己的线程中保存日志,具体做法是在配置文件的 targets 增加 async = “true” 属性。下面是示例配置:
<?xml version="1.0" encoding="utf-8" ?>
<nlog xmlns="http://www.nlog-project.org/schemas/NLog.xsd"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
autoReload="true"
internalLogLevel="Info"
internalLogFile="${basedir}/logs/internal-nlog.txt">
<!-- 在这里把 async="true" 就可以实现异步保存日志了 -->
<targets async="true">
<!-- write logs to file -->
<target xsi:type="File" name="allfile" fileName="${basedir}/logs/nlog-all-${shortdate}.log"
keepFileOpen="true"
archiveFileName="${basedir}/logs/archives/nlog-all-${longdate}.log"
archiveAboveSize="4096000"
archiveEvery="Day"
maxArchiveFiles="35"
layout="${longdate}|${event-properties:item=EventId_Id}|${uppercase:${level}}|${logger}|${message} ${exception:format=tostring}" />
<target name="logconsole" xsi:type="Console" />
</targets>
<!-- rules to map from logger name to target -->
<rules>
<logger name="*" minlevel="Info" writeTo="logconsole" />
<!--All logs, including from Microsoft-->
<logger name="*" minlevel="Info" writeTo="allfile" />
<!--Skip non-critical Microsoft logs and so log only own logs-->
<!--logger name="Microsoft.*" maxlevel="Info" final="true" /-->
</rules>
</nlog>
实际运行以后,即使是在压力极大的情况下也没有再出现过运行周期超时的问题,成功地解决了写日志耗时过高的问题。
由于是异步写日志,NLog默认设置了一个缓存值,这个值默认是10000条日志,如果 10000 条以上了待写入的时候又来了其他日志,NLog 会直接忽略。当然你可以选择扩大缓存,或者让发起记日志的线程等待缓存被清空。
最后,记得在程序运行结束时执行以下语句,这样才能保证日志被完整保存。
// Flush logs
NLog.LogManager.Shutdown();