ASP.NET Core 源码学习之 Logging

在ASP.NET 4.X中，我们通常使用 log4net, NLog 等来记录日志，但是当我们引用的一些第三方类库使用不同的日志框架时，就比较混乱了。而在 ASP.Net Core 中内置了日志系统，并提供了一个统一的日志接口，ASP.Net Core 系统以及其它第三方类库等都使用这个日志接口来记录日志，而不关注日志的具体实现，这样便可以在我们的应用程序中进行统一的配置，并能很好的与第三方日志框架集成。

注册日志服务

ASP.NET Core 全部使用依赖注入，更好的规范我们的代码。想要使用日志系统，首先要进行注册和配置：

public void ConfigureServices(IServiceCollection services)
{
    services.AddLogging(builder =>
    {
        builder
            .AddConfiguration(loggingConfiguration.GetSection("Logging"))
            .AddFilter("Microsoft", LogLevel.Warning)
            .AddConsole();
    });
}

如上，通过 AddLogging ，将日志系统注册到了 DI 系统中，而 AddConfiguration 是对日志系统的全局配置， AddFilter 则是对日志过滤器的一些配置，最后 AddConsole 添加了一个 Console 的日志提供者（将日志输出到控制台）。

记录日志

在我们需要记录日志的时候，只需要通过构造函数注入ILogger<T>就可以了：

public class TodoController : Controller
{
    private readonly ITodoRepository _todoRepository;
    private readonly ILogger _logger;

    public TodoController(ITodoRepository todoRepository, ILogger<TodoController> logger)
    {
        _todoRepository = todoRepository;
        _logger = logger;
    }

    [HttpGet]
    public IActionResult GetById(string id)
    {
        _logger.LogInformation(LoggingEvents.GET_ITEM, "Getting item {ID}", id);
        var item = _todoRepository.Find(id);
        if (item == null)
        {
            _logger.LogWarning(LoggingEvents.GET_ITEM_NOTFOUND, "GetById({ID}) NOT FOUND", id);
            return NotFound();
        }
        return new ObjectResult(item);
    }
}

ILogger<T> 中的 T 表示日记的类别，在我们查看日志时，非常有用，在本文后面会讲。

日志输出示例

使用上面的示例代码，当我们通过控制台来运行时，访问 http://localhost:5000/api/todo/0 将会看到如下的日志输出：

info: Microsoft.AspNetCore.Hosting.Internal.WebHost[1]
      Request starting HTTP/1.1 GET http://localhost:5000/api/todo/invalidid
info: Microsoft.AspNetCore.Mvc.Internal.ControllerActionInvoker[1]
      Executing action method TodoApi.Controllers.TodoController.GetById (TodoApi) with arguments (invalidid) - ModelState is Valid
info: TodoApi.Controllers.TodoController[1002]
      Getting item invalidid
warn: TodoApi.Controllers.TodoController[4000]
      GetById(invalidid) NOT FOUND
info: Microsoft.AspNetCore.Mvc.StatusCodeResult[1]
      Executing HttpStatusCodeResult, setting HTTP status code 404
info: Microsoft.AspNetCore.Mvc.Internal.ControllerActionInvoker[2]
      Executed action TodoApi.Controllers.TodoController.GetById (TodoApi) in 243.2636ms
info: Microsoft.AspNetCore.Hosting.Internal.WebHost[2]
      Request finished in 628.9188ms 404

如果我们访问 http://localhost:55070/api/todo/0 ，将会看到：

Microsoft.AspNetCore.Hosting.Internal.WebHost:Information: Request starting HTTP/1.1 GET http://localhost:55070/api/todo/invalidid
Microsoft.AspNetCore.Mvc.Internal.ControllerActionInvoker:Information: Executing action method TodoApi.Controllers.TodoController.GetById (TodoApi) with arguments (invalidid) - ModelState is Valid
TodoApi.Controllers.TodoController:Information: Getting item invalidid
TodoApi.Controllers.TodoController:Warning: GetById(invalidid) NOT FOUND
Microsoft.AspNetCore.Mvc.StatusCodeResult:Information: Executing HttpStatusCodeResult, setting HTTP status code 404
Microsoft.AspNetCore.Mvc.Internal.ControllerActionInvoker:Information: Executed action TodoApi.Controllers.TodoController.GetById (TodoApi) in 12.5003ms
Microsoft.AspNetCore.Hosting.Internal.WebHost:Information: Request finished in 19.0913ms 404

通过这个示例，可以看到我们记录到了 ASP.NET Core 框架自身的日志，这也是统一的日志框架才能实现的功能。

日志类别

我们创建的每一个日志器都指定了一个类别。它可以是任意的字符串，但是约定使用写入类的完整限定名，如：“TodoApi.Controllers.TodoController”。如果要显式的指定日志的种类，则可以使用 ILoggerFactory 中的 CreateLogger 方法：

public class TodoController : Controller
{
    private readonly ILogger _logger;

    public TodoController(ILoggerFactory logger)
    {
        _logger = logger.CreateLogger("TodoApi.Controllers.TodoController");
    }
}

不过，大多数时候，我们还是使用 ILogger<T>，更加方便：

public class TodoController : Controller
{
    private readonly ILogger _logger;

    public TodoController(ILogger<TodoController> logger)
    {
        _logger = logger;
    }
}

这等效于使用 T 类型的完整限定名来调用 CreateLogger 方法。

日志级别

在我们记录日志时，需要指定日志的级别，这对我们过滤日志非常有用，比如在测试环境中，我们希望提供非常的详细的日志信息，包括一些敏感信息等，但是在生产环境中，我们希望只记录严重的错误，这时候只需要简单的通过 AddFilter 对日志的过滤级别配置一下就行了。

ASP.NET Core Logging 系统提供了六个日志级别，通过增加重要性或严重程度排序如下：

  • Trace 用于记录最详细的日志消息，通常仅用于开发阶段调试问题。这些消息可能包含敏感的应用程序数据，因此不应该用于生产环境。默认应禁用。
  • Debug 这种消息在开发阶段短期内比较有用。它们包含一些可能会对调试有所助益、但没有长期价值的信息。默认情况下这是最详细的日志。
  • Information 这种消息被用于跟踪应用程序的一般流程。与 Verbose 级别的消息相反，这些日志应该有一定的长期价值。
  • Warning 当应用程序出现错误或其它不会导致程序停止的流程异常或意外事件时使用警告级别，以供日后调查。在一个通用的地方处理警告级别的异常。
  • Error 当应用程序由于某些故障停止工作则需要记录错误日志。这些消息应该指明当前活动或操作（比如当前的 HTTP 请求），而不是应用程序范围的故障。
  • Critical 当应用程序或系统崩溃、遇到灾难性故障，需要立即被关注时，应当记录关键级别的日志。如数据丢失、磁盘空间不够等。

日志事件ID

每次写日志的时候，我们可以指定一个 event ID：


public class LoggingEvents
{
    public const int GET_ITEM = 1002;
    public const int GET_ITEM_NOTFOUND = 4000;
}

public IActionResult GetById(string id)
{
    _logger.LogInformation(LoggingEvents.GET_ITEM, "Getting item {ID}", id);
    var item = _todoRepository.Find(id);
    if (item == null)
    {
        _logger.LogWarning(LoggingEvents.GET_ITEM_NOTFOUND, "GetById({ID}) NOT FOUND", id);
        return NotFound();
    }
    return new ObjectResult(item);
}

event ID 是一个整数，它可以将一组日志事件关联到一起。与日志类别类似，但是更加细化。而它的输出取决于日志提供者，Console 提供者输出格式如下，在日志类别后面，并用一对中括号包裹着：

info: TodoApi.Controllers.TodoController[1002]
      Getting item invalidid
warn: TodoApi.Controllers.TodoController[4000]
      GetById(invalidid) NOT FOUND

日志格式化字符串

每次记录日志时，都会提供一条文本消息，而在这个消息字符串中，我们可以使用命名占位符：

public IActionResult GetById(string id)
{
    _logger.LogInformation(LoggingEvents.GET_ITEM, "Getting item {ID}", id);
    var item = _todoRepository.Find(id);
    if (item == null)
    {
        _logger.LogWarning(LoggingEvents.GET_ITEM_NOTFOUND, "GetById({ID}) NOT FOUND", id);
        return NotFound();
    }
    return new ObjectResult(item);
}

但是占位符的顺序决定了使用哪个参数，而不是它的名字，如下示例：

string p1 = "parm1";
string p2 = "parm2";
_logger.LogInformation("Parameter values: {p2}, {p1}", p1, p2);

输出结果为：

Parameter values: parm1, parm2

那这样做有什么意义呢？

日志框架使用这种消息格式化方式，使日志提供者能够实现 语义化日志，也称结构化日志。因为参数本身被传递到日志系统中，而不仅仅是格式化的字符串，因此日志提供者可以将参数的值作为字段存储单独的存储。比如：如果使用 Azure Table Storage，我们可以使用如下方法来记录日志：

_logger.LogInformation("Getting item {ID} at {RequestTime}", id, DateTime.Now);

每一个 Azure Table 都可以有 ID 和 RequestTime 属性，这将简化对日志数据的查询，你可以查找指定 RequestTime 范围内的所有日志，而不必花费解析文本消息的开销。

过滤器

过滤器可以让你根据日志的级别和类别来选择是输出，还是忽略。我们可以为不同的日志提供者指定不同的过滤器，如下代码所示，让 Console 提供者忽略低于 warning 级别的日志，而 Debug 提供者则忽略 TodoApi 类别的日志。

public void Configure(IApplicationBuilder app, ILoggerFactory loggerFactory)
{
    loggerFactory
        .AddConsole(LogLevel.Warning)
        .AddDebug((category, logLevel) => (category.Contains("TodoApi") && logLevel >= LogLevel.Trace));
}

而我们还可以指定全局过滤器，作用于所有的日志提供者，如下示例，我们对于以 "Microsoft" 和 "System" 开头的日志类别忽略掉低于 Warning 级别的日志：

public void Configure(IApplicationBuilder app, ILoggerFactory loggerFactory)
{
    loggerFactory
        .AddFilter("Microsoft", LogLevel.Warning)
        .AddFilter("System", LogLevel.Warning)
        .AddFilter("SampleApp.Program", LogLevel.Debug)
        .AddDebug();
}

作用域

我们可以将一组逻辑操作放在一个有序的 Scope 中，将 Scope 的标识附加到范围内的所有日志中。例如，我们可以在处理事务的时候，使事务内的每一个操作日志都包含这个事务的ID。

使用ILgger.BeginScope<TState> 方法创建一个 Scope，并返回一个 IDisposable 类型，当我们 Dispose的时候，这个 Scope 也就结束了，非常适合于使用 using 的方式：

public IActionResult GetById(string id)
{
    TodoItem item;
    using (_logger.BeginScope("Message attached to logs created in the using block"))
    {
        _logger.LogInformation(LoggingEvents.GET_ITEM, "Getting item {ID}", id);
        item = _todoRepository.Find(id);
        if (item == null)
        {
            _logger.LogWarning(LoggingEvents.GET_ITEM_NOTFOUND, "GetById({ID}) NOT FOUND", id);
            return NotFound();
        }
    }
    return new ObjectResult(item);
}

每一个日志将包括 Scope 的信息：

info: TodoApi.Controllers.TodoController[1002]
      => RequestId:0HKV9C49II9CK RequestPath:/api/todo/0 => TodoApi.Controllers.TodoController.GetById (TodoApi) => Message attached to logs created in the using block
      Getting item 0
warn: TodoApi.Controllers.TodoController[4000]
      => RequestId:0HKV9C49II9CK RequestPath:/api/todo/0 => TodoApi.Controllers.TodoController.GetById (TodoApi) => Message attached to logs created in the using block
      GetById(0) NOT FOUND

总结

ASP.NET Core 提供了统一的日志框架，能方便地通过 Startup 类进行配置，灵活的集成第三方日志框架，并使用依赖注入的方式在应用程序中使用。本文整体的概述了一下 Logging 系统，在下一章中，会来分析一下 Logging 中配置的源码。

参考微软官方文档：Introduction to Logging in ASP.NET Core

作者：雨夜朦胧

原文链接：https://www.cnblogs.com/RainingNight/p/asp-net-core-logging-introduction.html

原文地址：https://www.cnblogs.com/gongap/p/9308588.html

时间： 2024-10-01 03:42:27

ASP.NET Core 源码学习之 Logging的相关文章

asp.net core源码飘香：Logging组件（转）

简介: 作为基础组件,日志组件被其他组件和中间件所使用,它提供了一个统一的编程模型,即不需要知道日志最终记录到哪里去,只需要调用它即可. 使用方法很简单,通过依赖注入ILogFactory(CreateLogger方法)或ILogger<T>对象,获取一个ILogger对象,然后通过ILogger的各种扩展方法(都是调用Log方法)记录不同级别的日志. 源码剖析: 总结: 日志组件其实就是工厂模式的应用,但进行了改进,LoggerFactory每次都返回一个Logger对象,而Logger对象

asp.net core源码飘香：Logging组件

简介: 作为基础组件,日志组件被其他组件和中间件所使用,它提供了一个统一的编程模型,即不需要知道日志最终记录到哪里去,只需要调用它即可. 使用方法很简单,通过依赖注入ILogFactory(CreateLogger方法)或ILogger<T>对象,获取一个ILogger对象,然后通过ILogger的各种扩展方法(都是调用Log方法)记录不同级别的日志. 源码剖析: 总结: 日志组件其实就是工厂模式的应用,但进行了改进,LoggerFactory每次都返回一个Logger对象,而Logger对象

asp.net core源码飘香：Configuration组件（转）

简介: 这是一个基础组件,是一个统一的配置模型,配置可以来源于配置文件(json文件,xml文件,ini文件),内存对象,命令行参数,系统的环境变量又或者是你自己扩展的配置源,该组件将各个配置源的数据按统一的格式(IDictionary<string, string> Data)进行加载,进而对外提供调用接口. 不仅如此,有些配置源(如文件配置源)还可以在配置源的数据发生变化时进行重新加载(IDictionary<string, string> Data),而程序员随时可以判断是否

asp.net core源码地址

https://github.com/dotnet/corefx 这个是.net core的 开源项目地址 https://github.com/aspnet 这个下面是asp.net core 框架的地址,里面有很多仓库. https://github.com/aspnet/EntityFrameworkCore  EF Core源码 https://github.com/aspnet/Configuration 配置模块源码 https://github.com/aspnet/Routing

asp.net core源码飘香：从Hosting开始

知识点: 1.Kestrel服务器启动并处理Http请求的过程. 2.Startup的作用. 源码飘香: 总结: asp.net core将web开发拆分为多个独立的组件,大多以http中间件的形式添加到请求管线,也有一下作为基础组件(configution,options,logging等)提供. 零零散散的二三十个框架吧,除了mvc(还有razor)和ef core是比较大型的框架,其他小框架都可以一遍博客讲完一个.后面三个大框架准备开单独的系列来细说. 今晚就到此为止吧,如果大家有兴趣,请

ASP.NET Core 源码阅读笔记(5) ---Microsoft.AspNetCore.Routing路由

这篇随笔讲讲路由功能,主要内容在项目Microsoft.AspNetCore.Routing中,可以在GitHub上找到,Routing项目地址. 路由功能是大家都很熟悉的功能,使用起来也十分简单,从使用的角度来说可讲的东西不多.不过阅读源码的过程的是个学习的过程,看看顶尖Coder怎么组织代码也是在提升自己. 我们知道现在ASP.NET Core中所有用到的功能都是服务,那么Routing服务是什么时候被添加到依赖注入容器的呢?答案是在StartUp类的ConfigureServices方法中

Visual Studio 2017中使用SourceLink调试ASP.NET Core源码

背景 当我们在学习ASP.NET Core或者调试ASP.NET Core程序的时候,有时候需要调试底层代码,但是当我们在Visual Studio中调试程序的时候,由于一些基础库或者第三方库缺少pdb文件,所以调试这些库的代码的时候,会出现断点不能进去的情况. 例如,在如下代码中,我们希望调试一下View()方法,看看其中的内部实现 但是当我们点击F11之后,程序会直接跳到下一步 Source Link SourceLink是一组用于描述可嵌入到符号.二进制文件和包中的源代码控制元数据的包和规

asp.net core源码飘香：Configuration组件

简介: 这是一个基础组件,是一个统一的配置模型,配置可以来源于配置文件(json文件,xml文件,ini文件),内存对象,命令行参数,系统的环境变量又或者是你自己扩展的配置源,该组件将各个配置源的数据按统一的格式(IDictionary<string, string> Data)进行加载,进而对外提供调用接口. 不仅如此,有些配置源(如文件配置源)还可以在配置源的数据发生变化时进行重新加载(IDictionary<string, string> Data),而程序员随时可以判断是否

ASP.NET Core 源码阅读笔记(3) ---Microsoft.AspNetCore.Hosting

有关Hosting的基础知识 Hosting是一个非常重要,但又很难翻译成中文的概念.翻译成:寄宿,大概能勉强地传达它的意思.我们知道,有一些病毒离开了活体之后就会死亡,我们把那些活体称为病毒的宿主.把这种概念应用到托管程序上来,CLR不能单独存在,它必须依赖于某一个进程,我们把这种状况称之为:CLR必须寄宿于某一个进程中,而那个进程就是宿主. ASP.NET Core的一个大的改变就是就是将Web应用程序改成了自寄宿(当然在Windows上也还支持寄宿在IIS中).什么意思呢?我们知道,在之前