Log4Net 性能

Question

我编写了一个 C# 应用程序，该应用程序在循环中不断运行，并且多个线程写入 log4net 文件。

问题是应用程序运行的时间越长，完成循环所需的时间就越多。我运行了 ANTS 性能分析器，并注意到大部分 CPU 时间都花在了使用 log4.net 进行日志记录上。

日志越详细，使用的 CPU 就越多，30 分钟后，CPU 使用率达到 100%。如果我禁用记录，循环所花费的时间将随着时间的推移保持不变。我查看了 Windows 性能监视器，发现物理磁盘大部分时间处于空闲状态。

我试图将日志记录保持在最低限度，但即使日志记录量相对较少，我仍然遇到这个问题。

以下是我的 Log4net.xml 配置文件的示例：

<log4net>
  <root>
    <!-- Levels: OFF, DEBUG, INFO, WARN, ERROR, FATAL-->
    <level value="INFO" />
    <appender-ref ref="RollingLogFileAppender" />
  </root>

  <!--Logs to a file-->
  <appender name="RollingLogFileAppender" type="log4net.Appender.RollingFileAppender">
    <file value="c:\\logs\\log-file.txt" />
    <appendToFile value="true" />
    <lockingModel type="log4net.Appender.FileAppender+ExclusiveLock" />
    <rollingStyle value="Composite" />
    <datePattern value="yyyyMMdd" />
    <maxSizeRollBackups value="20" />
    <maximumFileSize value="1MB" />
    <layout type="log4net.Layout.PatternLayout">
      <conversionPattern value="%date [%thread] %-5level %logger %L %M Schdl:[%property{scheduleContext}] Job:[%property{jobContext}] - %message%newline" />
    </layout>
  </appender>
</log4net>

我在每个记录对象中使用相同的记录器。

  log4net.ILog logger;
  log4net.Config.BasicConfigurator.Configure();
  logger = log4net.LogManager.GetLogger(typeof(myProject));

为什么它运行的时间越长，使用的CPU就越多？

任何有关如何改进这一点的建议将不胜感激。

Answer 1

您是否在每个记录对象中配置 log4net？从你的代码来看是这样的。配置应该每个进程完成一次，例如在启动时，然后您的对象应该只需要获取记录器。

对于每个需要记录的类，我通常具有以下模式：

class SomeClass
{
    private static readonly ILog log = LogManager.GetLogger(typeof(SomeClass));
    ...
}

使用此模式，您将自动获取根据类的名称空间和名称分层的记录器。

Answer 2

你用这些上下文属性做什么？ Schdl:[%property{scheduleContext}] Job:[%property{jobContext}] 如果您在此处分配的不仅仅是一个值（或具有简单 ToString() 方法的对象），它还可以性能陷入困境。请参阅活动属性值下的 log4net 手册，了解以下内容：我在说什么。

Peter Lillevold 有一个关于仅配置 log4net 一次的好建议。您还可以将以下行放入 AssemblyInfo.cs 文件中：

[assembly: log4net.Config.XmlConfigurator()]

以下行很容易剪切并粘贴到任何执行日志记录的类中：

private static readonly log4net.ILog _log = log4net.LogManager.GetLogger(System.Reflection.MethodBase.GetCurrentMethod().DeclaringType);

您还可以尝试打开 log4net 内部日志记录以查看发生了什么：

<appSettings>
  <add key="log4net.Internal.Debug" value="true"/>
</appSettings>

Answer 3

（我以前从未使用过 log4net，因此请谨慎使用我推荐的内容）

您可以为 log4Net 组件编写一个异步代理并登录专用线程。您将不再尝试写入日志文件而阻塞主线程。

如果 log4net 已经有异步代理，那么这一切都是无用的。