{"time":1739517335662,"blocks":[{"id":"HAKKdSzZWQ","type":"paragraph","data":{"text":"Это продолжение нашей серии о логировании в .NET. В первой части мы разобрали, как стандартные методы могут замедлять приложение, и познакомились с LoggerMessage. Теперь пора перейти к практике: мы покажем реальные тесты производительности и расскажем, как автоматизировать переход на эффективное логирование."}},{"id":"8REjl8K4Ek","type":"embed","data":{"link":"https://tproger.ru/articles/pochemu-standartnoe-logirovanie-mozhet-tormozit--net-prilozheniya"}},{"id":"XwLNqycDlw","type":"header2","data":{"text":"Сравнение производительности: стандартное логирование против LoggerMessage","level":2},"tunes":{}},{"id":"dEVj_wiVnd","type":"paragraph","data":{"text":"Чтобы сравнить производительность LoggerMessage и методов расширения интерфейса, мы решили написать программу, подсчитывающую сумму четных элементов массива. Предварительно добавили несколько новых методов для записи дополнительных событий. Вот объявления для новых событий:"}},{"id":"N5PX_hQqcB","type":"code","data":{"code":"[LoggerMessage(EventId = 10, EventName = nameof(LogIterationStart), Level = LogLevel.Information, Message = \"Iteration start [{Iteration}]\")]\npublic static partial void LogIterationStart(this ILogger logger, int iteration);\n \n[LoggerMessage(EventId = 20, EventName = nameof(LogIterationComplete), Level = LogLevel.Information, Message = \"Iteration complete [{Iteration}] Current summ: [{Summ}]\")]\npublic static partial void LogIterationComplete(this ILogger logger, int iteration, int summ);\n \n[LoggerMessage(EventId=30, EventName =nameof(LogOddItem), Level = LogLevel.Information, Message = \"Iteration [{Iteration}] An odd item detected: [{OddItem}]\")]\npublic static partial void LogOddItem(this ILogger logger, int iteration, int oddItem);\n \n[LoggerMessage(EventId = 999, EventName = nameof(LogException), Level = LogLevel.Error, Message = \"An unexpected exception has occurred\")]\npublic static partial void LogException(this ILogger logger, Exception exception);","language":"csharp","lineNumbers":false,"startLineNumber":1,"stretched":false}},{"id":"WL0-f7mnWq","type":"paragraph","data":{"text":"Обновленный код приложения:"}},{"id":"ZFPoG4HNAV","type":"code","data":{"code":"using AutoLoggingNet9;\nusing Microsoft.Extensions.Logging;\nusing System.Diagnostics;\nusing System.Net;\n \nusing ILoggerFactory loggerFactory = LoggerFactory.Create(builder => builder.AddConsole());\n \nILogger logger = loggerFactory.CreateLogger<Program>();\n \nlogger.LogApplicationStart(Dns.GetHostName());\n \nvar sw = Stopwatch.StartNew();\ntry\n{\n // Имитация выполнения работы приложения\n const int N = 100000;\n var random = new Random();\n var numbers = Enumerable.Range(0, N).Select(x => random.Next(N / 100)).ToArray();\n var summ = 0;\n for (int i = 0; i < N; i++)\n {\n logger.LogIterationStart(i);\n //logger.LogInformation(\"Iteration start [{Iteration}]\", i);\n \n if (numbers[i] % 2 == 0) // четный элемент\n {\n if ((long)summ + numbers[i] >= int.MaxValue)\n {\n throw new InvalidOperationException(\"The uint.MaxValue is exceeded!\");\n }\n \n summ += numbers[i];\n }\n else // нечетный элемент\n {\n logger.LogOddItem(i, numbers[i]);\n //logger.LogInformation(\"Iteration [{Iteration}] An odd item detected: [{OddItem}]\", i, numbers[i]);\n }\n \n logger.LogIterationComplete(i, summ);\n //logger.LogInformation(\"Iteration complete [{Iteration}] Current summ: [{Summ}]\", i, summ);\n }\n}\ncatch (Exception ex)\n{\n logger.LogException(ex);\n //logger.LogError(ex, \"An unexpected exception has occurred\");\n}\nfinally\n{\n sw.Stop();\n logger.LogApplicationFinish(sw.Elapsed);\n}\n","language":"csharp","lineNumbers":false,"startLineNumber":1,"stretched":false}},{"id":"aUiuMHTUnT","type":"paragraph","data":{"text":"Здесь строки вызова методов расширения ILogger закомментированы. А вызовы наших методов, построенных через механизм LoggerMessage, раскомментированы. Таким образом, можно прогнать программу, используя ILogger, либо, наоборот, LoggerMessage"}},{"id":"2L7Ape_Wzu","type":"paragraph","data":{"text":"При использовании механизма LoggerMessage затраты на выполнение методов логирования, которые мы вызывали в цикле (LogIterationStart и LogIterationComplete), составили 0,58% и 0,52% процессорного времени от общих затрат."}},{"id":"57kKAPeqpN","type":"image","data":{"alt":"","title":"","caption":"","file":{"id":45617,"url":"https://media.tproger.ru/user-uploads/101078/2025-01-30/e1164e87-c1a9-4e42-9847-a5d620953363.png"},"stretched":false,"withBackground":false,"withBorder":false,"width":904,"height":160,"optimizedFile":{"original":"https://media.tproger.ru/user-uploads/101078/2025-01-30/e1164e87-c1a9-4e42-9847-a5d620953363.png","alt":"Часть 2: Как оптимизировать логирование в .NET: советы и примеры 1","dimensions":{"width":904,"height":160},"additionalSizes":{"srcSet":[{"url":"https://tproger.ru/signed_image/CTViwLJY_gwhUhGUnAKkU0R1EbArQgPs8ItuPAcHSzU/rs:fill:766:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9lMTE2NGU4Ny1jMWE5LTRlNDItOTg0Ny1hNWQ2MjA5NTMzNjMucG5n","dpr":1,"width":766},{"url":"https://tproger.ru/signed_image/AwdkvjxZSbVqJjXHDO1AzsJUYSc5GXfceCmUcK2use4/rs:fill:1532:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9lMTE2NGU4Ny1jMWE5LTRlNDItOTg0Ny1hNWQ2MjA5NTMzNjMucG5n","dpr":1,"width":1532},{"url":"https://tproger.ru/signed_image/DB9r4sQk0FJCdBHAAuVmRnSstizzEN8ccHUGF-K4YY4/rs:fill:686:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9lMTE2NGU4Ny1jMWE5LTRlNDItOTg0Ny1hNWQ2MjA5NTMzNjMucG5n","dpr":1,"width":686},{"url":"https://tproger.ru/signed_image/L1h9CPAk9zocZ71HIiJJbAe9hnfV65Rvovq-4gVoHlE/rs:fill:1372:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9lMTE2NGU4Ny1jMWE5LTRlNDItOTg0Ny1hNWQ2MjA5NTMzNjMucG5n","dpr":1,"width":1372},{"url":"https://tproger.ru/signed_image/mIR-UES8y9BHT_MG6aqBD36nY8tBZ6hlez_q_0YXv9E/rs:fill:636:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9lMTE2NGU4Ny1jMWE5LTRlNDItOTg0Ny1hNWQ2MjA5NTMzNjMucG5n","dpr":1,"width":636},{"url":"https://tproger.ru/signed_image/EySN_od7egpaYMhnHM3AILoZ1XIqqrjzl5-skl7W_yE/rs:fill:1272:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9lMTE2NGU4Ny1jMWE5LTRlNDItOTg0Ny1hNWQ2MjA5NTMzNjMucG5n","dpr":1,"width":1272},{"url":"https://tproger.ru/signed_image/F9A8Kll7zZBbOSF7TTVoBJbvsJaaRJq41HoH3g8Mjms/rs:fill:466:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9lMTE2NGU4Ny1jMWE5LTRlNDItOTg0Ny1hNWQ2MjA5NTMzNjMucG5n","dpr":1,"width":466},{"url":"https://tproger.ru/signed_image/OgkbxRxSLqqAPXqWyJNNl4sjv9aGFxJg8MdL6C3Dtks/rs:fill:932:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9lMTE2NGU4Ny1jMWE5LTRlNDItOTg0Ny1hNWQ2MjA5NTMzNjMucG5n","dpr":1,"width":932}],"sizes":[{"media":"(min-width: 1441px)","size":"766px"},{"media":"(min-width: 1281px)","size":"686px"},{"media":"(min-width: 1281px)","size":"766px"},{"media":"(min-width: 961px)","size":"766px"},{"media":"(min-width: 671px)","size":"636px"},{"media":"(min-width: 500px)","size":"466px"}]}}}},{"id":"6s1SmY29e3","type":"paragraph","data":{"text":"При использовании методов расширения ILogger затраты на выполнение метода ILogger.LogInformation составили 35,12% от общих затрат."}},{"id":"kT7kW6BAUs","type":"image","data":{"alt":"","title":"","caption":"","file":{"id":45618,"url":"https://media.tproger.ru/user-uploads/101078/2025-01-30/a3f07e4f-0bf9-4df3-bf56-6f52b9d68277.png"},"stretched":false,"withBackground":false,"withBorder":false,"width":904,"height":140,"optimizedFile":{"original":"https://media.tproger.ru/user-uploads/101078/2025-01-30/a3f07e4f-0bf9-4df3-bf56-6f52b9d68277.png","alt":"Часть 2: Как оптимизировать логирование в .NET: советы и примеры 2","dimensions":{"width":904,"height":140},"additionalSizes":{"srcSet":[{"url":"https://tproger.ru/signed_image/PYvBZew12MdWZlUsTR9Lvnw_WQT6PzZOxAWIL7OQiHw/rs:fill:766:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9hM2YwN2U0Zi0wYmY5LTRkZjMtYmY1Ni02ZjUyYjlkNjgyNzcucG5n","dpr":1,"width":766},{"url":"https://tproger.ru/signed_image/tVB8rMgr5AWijyEdDV0QXBj10r7vcpFrRxyRQMm2aVg/rs:fill:1532:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9hM2YwN2U0Zi0wYmY5LTRkZjMtYmY1Ni02ZjUyYjlkNjgyNzcucG5n","dpr":1,"width":1532},{"url":"https://tproger.ru/signed_image/TW1fqDFfQR74YEThSIOK7iqmgtnAZ8K2WRzth3XzhOk/rs:fill:686:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9hM2YwN2U0Zi0wYmY5LTRkZjMtYmY1Ni02ZjUyYjlkNjgyNzcucG5n","dpr":1,"width":686},{"url":"https://tproger.ru/signed_image/vK6foSeAhuJ6RUhzIaGruadHfhRMOzSvh5HgOI9VthA/rs:fill:1372:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9hM2YwN2U0Zi0wYmY5LTRkZjMtYmY1Ni02ZjUyYjlkNjgyNzcucG5n","dpr":1,"width":1372},{"url":"https://tproger.ru/signed_image/kR1WDdr9_6nH3fRSimcRXERmb-HPQIJw-DDdnklvTH4/rs:fill:636:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9hM2YwN2U0Zi0wYmY5LTRkZjMtYmY1Ni02ZjUyYjlkNjgyNzcucG5n","dpr":1,"width":636},{"url":"https://tproger.ru/signed_image/vKJts27-VIK7zoTGgJ35u7pf01mdpBVOKto1-v10lD0/rs:fill:1272:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9hM2YwN2U0Zi0wYmY5LTRkZjMtYmY1Ni02ZjUyYjlkNjgyNzcucG5n","dpr":1,"width":1272},{"url":"https://tproger.ru/signed_image/39mMPl8RrB1rRNibz3ZJ6SpSQqdx9sJzOwmeienxqzE/rs:fill:466:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9hM2YwN2U0Zi0wYmY5LTRkZjMtYmY1Ni02ZjUyYjlkNjgyNzcucG5n","dpr":1,"width":466},{"url":"https://tproger.ru/signed_image/E3BYbuelnlaV3Vgc2HSAF-V5r9USTn11oKK8RijGUCk/rs:fill:932:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9hM2YwN2U0Zi0wYmY5LTRkZjMtYmY1Ni02ZjUyYjlkNjgyNzcucG5n","dpr":1,"width":932}],"sizes":[{"media":"(min-width: 1441px)","size":"766px"},{"media":"(min-width: 1281px)","size":"686px"},{"media":"(min-width: 1281px)","size":"766px"},{"media":"(min-width: 961px)","size":"766px"},{"media":"(min-width: 671px)","size":"636px"},{"media":"(min-width: 500px)","size":"466px"}]}}}},{"id":"KNB1Yh6qxD","type":"paragraph","data":{"text":"Да, можно заметить, что в затраты попали вызовы метода LoggerMessage.Define. Но заметим, что эти методы выполняются один раз на старте приложения. И если наше приложение серверное (долгоживущее), то затраты станут ничтожными по отношению к общим затратам за всё время жизни приложения. "}},{"id":"aec75fc4-cc58-4584-b6f7-2f4ec1822392","type":"banner-blank","data":{}},{"id":"iOvbsAcDa8","type":"paragraph","data":{"text":"При использовании же методов расширения ILogger затраты на выполнение будут расти, создавая нагрузку как на процессор, так и на память."}},{"id":"WvhU5zNs5M","type":"header2","data":{"text":"AutoLoggerMessage: как легко перейти на высокопроизводительное логирование","level":2},"tunes":{}},{"id":"PvMQkcxun6","type":"paragraph","data":{"text":"Мы рассмотрели два способа типизированных методов для ILogger. LoggerMessageAttribute упрощает процесс, но перевод готового проекта всё равно сложный."}},{"id":"lRjDQ7_LlC","type":"paragraph","data":{"text":"К счастью, есть nuget-пакет, который умеет преобразовывать методы расширения ILogger.Log* из пространства имен Microsoft.Extensions.Logging в строго типизированные методы LoggerMessage."}},{"id":"4k5m9zu_gd","type":"paragraph","data":{"text":"Чтобы воспользоваться этим пакетом, нужно выполнить два простых шага:"}},{"id":"AatXGY9qQX","type":"paragraph","data":{"text":"Добавить в проект nuget-пакет stbychkov.AutoLoggerMessage"}},{"id":"kkc6bRORQU","type":"paragraph","data":{"text":"Это можно сделать либо через консоль с помощью инструмента Nuget package Manager, либо добавив ссылку на пакет в файл проекта."}},{"id":"7erKqBtFa6","type":"paragraph","data":{"text":"Команда консоли Nuget Package Manager: "}},{"id":"PHQIX523iW","type":"paragraph","data":{"text":"Install-Package stbychkov.AutoLoggerMessage"}},{"id":"b-4BRLeeYY","type":"paragraph","data":{"text":"Добавление пакета с помощью утилиты dotnet:"}},{"id":"exgh5twiPn","type":"paragraph","data":{"text":"dotnet add package stbychkov.AutoLoggerMessage"}},{"id":"_lP0Q4V9hC","type":"paragraph","data":{"text":"Разрешить перехватчики для генерации исходного кода"}},{"id":"6nNBY_pcvj","type":"code","data":{"code":"<PropertyGroup>\n <InterceptorsPreviewNamespaces>$(InterceptorsPreviewNamespaces);\nMicrosoft.Extensions.Logging.AutoLoggerMessage</InterceptorsPreviewNamespaces>\n</PropertyGroup>\n","language":"clike","lineNumbers":false,"startLineNumber":1,"stretched":false}},{"id":"_VfP8Ug6pF","type":"paragraph","data":{"text":"Для тестирования этого пакета мы создали отдельный проект консольного приложения и подключили в него указанный nuget-пакет. Далее добавили в модуль Program.cs пару строк с вызовами ILogger.LogInformation: "}},{"id":"oCiJ5l01rq","type":"code","data":{"code":"using Microsoft.Extensions.Logging;\nusing System.Diagnostics;\n \nusing ILoggerFactory loggerFactory = LoggerFactory.Create(builder => builder.AddConsole());\n \nILogger logger = loggerFactory.CreateLogger<Program>();\n \nlogger.LogInformation(\"Hello, World\");\n \nvar sw = Stopwatch.StartNew();\ntry\n{\n \n}\nfinally\n{\n sw.Stop();\n logger.LogInformation(\"Application is done.Elapsed time: {Elapsed:g}\", sw.Elapsed);","language":"csharp","lineNumbers":false,"startLineNumber":1,"stretched":false}},{"id":"aS1F9pxPJh","type":"paragraph","data":{"text":"Если попытаться перейти к реализации метода LogInformation с помощью команды Go To Implementation, мы увидим, что попали не в метод расширения класса LoggerExtensions, а в строго типизированный метод в классе GenericLoggerExtensions:"}},{"id":"Qk9YnVQuHP","type":"image","data":{"alt":"","title":"","caption":"","file":{"id":45619,"url":"https://media.tproger.ru/user-uploads/101078/2025-01-30/6f64462b-bed9-4e46-83cd-489887e518f7.png"},"stretched":false,"withBackground":false,"withBorder":false,"width":904,"height":294,"optimizedFile":{"original":"https://media.tproger.ru/user-uploads/101078/2025-01-30/6f64462b-bed9-4e46-83cd-489887e518f7.png","alt":"Часть 2: Как оптимизировать логирование в .NET: советы и примеры 3","dimensions":{"width":904,"height":294},"additionalSizes":{"srcSet":[{"url":"https://tproger.ru/signed_image/ewERJKNTVK9YYEq50bgdySNnxjC_y2ismhK1sFqRNxY/rs:fill:766:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC82ZjY0NDYyYi1iZWQ5LTRlNDYtODNjZC00ODk4ODdlNTE4ZjcucG5n","dpr":1,"width":766},{"url":"https://tproger.ru/signed_image/maLUCsXfilaHsEVKaHz1s6DsqdhMiqD0zYytrHKF_U8/rs:fill:1532:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC82ZjY0NDYyYi1iZWQ5LTRlNDYtODNjZC00ODk4ODdlNTE4ZjcucG5n","dpr":1,"width":1532},{"url":"https://tproger.ru/signed_image/emYOiZvvX26Rv11XYTdSwz_k7GUK8VqsftXe_vUyTpI/rs:fill:686:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC82ZjY0NDYyYi1iZWQ5LTRlNDYtODNjZC00ODk4ODdlNTE4ZjcucG5n","dpr":1,"width":686},{"url":"https://tproger.ru/signed_image/ZsOGnJFobutYPEVGdtuohx2vhDRkhclCSgsTTR4Ul8k/rs:fill:1372:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC82ZjY0NDYyYi1iZWQ5LTRlNDYtODNjZC00ODk4ODdlNTE4ZjcucG5n","dpr":1,"width":1372},{"url":"https://tproger.ru/signed_image/-DKWrESBNbXk8D8WA0GEo6xnwR676JJBdPbQ17yl8VM/rs:fill:636:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC82ZjY0NDYyYi1iZWQ5LTRlNDYtODNjZC00ODk4ODdlNTE4ZjcucG5n","dpr":1,"width":636},{"url":"https://tproger.ru/signed_image/U1MFFDmIaqLZtxjY1GWleWsGB-AO9sGHYnO0-gR1j0A/rs:fill:1272:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC82ZjY0NDYyYi1iZWQ5LTRlNDYtODNjZC00ODk4ODdlNTE4ZjcucG5n","dpr":1,"width":1272},{"url":"https://tproger.ru/signed_image/ZxpBwk85VN5coodvQ2dVPMdOGwcJDJ8tHVaRS3lGJXk/rs:fill:466:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC82ZjY0NDYyYi1iZWQ5LTRlNDYtODNjZC00ODk4ODdlNTE4ZjcucG5n","dpr":1,"width":466},{"url":"https://tproger.ru/signed_image/vCsAHkI5aNeWYGguM3x2lUFmncqD1ks60BC-Z_qjjSE/rs:fill:932:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC82ZjY0NDYyYi1iZWQ5LTRlNDYtODNjZC00ODk4ODdlNTE4ZjcucG5n","dpr":1,"width":932}],"sizes":[{"media":"(min-width: 1441px)","size":"766px"},{"media":"(min-width: 1281px)","size":"686px"},{"media":"(min-width: 1281px)","size":"766px"},{"media":"(min-width: 961px)","size":"766px"},{"media":"(min-width: 671px)","size":"636px"},{"media":"(min-width: 500px)","size":"466px"}]}}}},{"id":"G2WBYU3ir-","type":"paragraph","data":{"text":"Для первого вызова, где передана только строка сообщения, будет вызван этот метод:"}},{"id":"_n7xSOdA-z","type":"code","data":{"code":"[GeneratedCode(\"AutoLoggerMessageGenerator\", \"1.0.0.0\")]\n[EditorBrowsable(EditorBrowsableState.Never)]\n[DebuggerStepThrough]\n[ExcludeFromCodeCoverage]\npublic static class GenericLoggerExtensions\n{\n . . .\n public static void LogInformation(this ILogger logger, string message)\n {\n LoggerExtensions.LogInformation(logger, message);\n }\n . . .\n}\n","language":"csharp","lineNumbers":false,"startLineNumber":1,"stretched":false}},{"id":"p3fIFitOgv","type":"paragraph","data":{"text":"Для второго вызова, где передан параметр типа TimeSpan, будет вызван следующий метод:"}},{"id":"oBvR96VnNu","type":"code","data":{"code":"public static void LogInformation<T0>(this ILogger logger, string message, T0 arg0)\n{\n LoggerExtensions.LogInformation(logger, message, arg0);\n}\n","language":"csharp","lineNumbers":false,"startLineNumber":1,"stretched":false}},{"id":"9IIlt-YtmW","type":"paragraph","data":{"text":"Как видите, второй метод имеет обобщенный параметр."}},{"id":"flUfyQvuuo","type":"code","data":{"code":"public static void LogInformation(this ILogger logger, string? message, params object?[] args)\n{\n logger.Log(LogLevel.Information, message, args);\n}\n","language":"clike","lineNumbers":false,"startLineNumber":1,"stretched":false}},{"id":"QXwV2zCcd0","type":"paragraph","data":{"text":"Можно заметить, что внутри есть вызовы LoggerExtensions, которые не строго типизированы. Это нужно, поскольку во время компиляции вложенный вызов logger.Log должен быть заменен сгенерированным кодом, который в итоге будет вызывать подходящий строго типизированный метод расширения класса LoggerMessageExtensions."}},{"id":"1f3f2094-c602-4a7a-96be-31e0522a3a00","type":"banner-blank","data":{}},{"id":"grwJaMwQhh","type":"paragraph","data":{"text":"Сборка проекта как в MS Visual Studio 2022, так и в JetBrains Rider, прошла без проблем. Так, если в IDE Rider запустить отладку проекта, предварительно поставив точку останова на вызове одного из методов logger.LogInformation(), то можно увидеть код, который будет вызван. Для этого выберите команду Step Into:"}},{"id":"dbEUhOj1o3","type":"image","data":{"alt":"","title":"","caption":"","file":{"id":45620,"url":"https://media.tproger.ru/user-uploads/101078/2025-01-30/b381b126-2726-4f1c-b771-6d1f9874372f.png"},"stretched":false,"withBackground":false,"withBorder":false,"width":300,"height":198,"optimizedFile":{"original":"https://media.tproger.ru/user-uploads/101078/2025-01-30/b381b126-2726-4f1c-b771-6d1f9874372f.png","alt":"Часть 2: Как оптимизировать логирование в .NET: советы и примеры 4","dimensions":{"width":300,"height":198},"additionalSizes":{"srcSet":[{"url":"https://tproger.ru/signed_image/rCfZlhS5hZ3j8p2OGp5baRD7EyiBMlYjqF2jzbKOIeQ/rs:fill:300:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9iMzgxYjEyNi0yNzI2LTRmMWMtYjc3MS02ZDFmOTg3NDM3MmYucG5n","dpr":1,"width":300},{"url":"https://tproger.ru/signed_image/SoIAHKJ0lqQN5EVkx_pQwYxybK00N_5JQHBSMwTKwh4/rs:fill:600:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9iMzgxYjEyNi0yNzI2LTRmMWMtYjc3MS02ZDFmOTg3NDM3MmYucG5n","dpr":1,"width":600},{"url":"https://tproger.ru/signed_image/rCfZlhS5hZ3j8p2OGp5baRD7EyiBMlYjqF2jzbKOIeQ/rs:fill:300:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9iMzgxYjEyNi0yNzI2LTRmMWMtYjc3MS02ZDFmOTg3NDM3MmYucG5n","dpr":1,"width":300},{"url":"https://tproger.ru/signed_image/SoIAHKJ0lqQN5EVkx_pQwYxybK00N_5JQHBSMwTKwh4/rs:fill:600:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9iMzgxYjEyNi0yNzI2LTRmMWMtYjc3MS02ZDFmOTg3NDM3MmYucG5n","dpr":1,"width":600},{"url":"https://tproger.ru/signed_image/rCfZlhS5hZ3j8p2OGp5baRD7EyiBMlYjqF2jzbKOIeQ/rs:fill:300:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9iMzgxYjEyNi0yNzI2LTRmMWMtYjc3MS02ZDFmOTg3NDM3MmYucG5n","dpr":1,"width":300},{"url":"https://tproger.ru/signed_image/SoIAHKJ0lqQN5EVkx_pQwYxybK00N_5JQHBSMwTKwh4/rs:fill:600:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9iMzgxYjEyNi0yNzI2LTRmMWMtYjc3MS02ZDFmOTg3NDM3MmYucG5n","dpr":1,"width":600},{"url":"https://tproger.ru/signed_image/rCfZlhS5hZ3j8p2OGp5baRD7EyiBMlYjqF2jzbKOIeQ/rs:fill:300:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9iMzgxYjEyNi0yNzI2LTRmMWMtYjc3MS02ZDFmOTg3NDM3MmYucG5n","dpr":1,"width":300},{"url":"https://tproger.ru/signed_image/SoIAHKJ0lqQN5EVkx_pQwYxybK00N_5JQHBSMwTKwh4/rs:fill:600:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC9iMzgxYjEyNi0yNzI2LTRmMWMtYjc3MS02ZDFmOTg3NDM3MmYucG5n","dpr":1,"width":600}],"sizes":[{"media":"(min-width: 1441px)","size":"300px"},{"media":"(min-width: 1281px)","size":"300px"},{"media":"(min-width: 1281px)","size":"300px"},{"media":"(min-width: 961px)","size":"300px"},{"media":"(min-width: 671px)","size":"300px"},{"media":"(min-width: 500px)","size":"300px"}]}}}},{"id":"Toa7aNu_lD","type":"paragraph","data":{"text":"Мы можем видеть, что дальше управление передается сгенерированному методу с типизированными параметрами:"}},{"id":"RN8UnSBsx4","type":"image","data":{"alt":"","title":"","caption":"","file":{"id":45621,"url":"https://media.tproger.ru/user-uploads/101078/2025-01-30/02872f1a-5598-4484-bbc9-dcb73c098fc0.png"},"stretched":false,"withBackground":false,"withBorder":false,"width":904,"height":82,"optimizedFile":{"original":"https://media.tproger.ru/user-uploads/101078/2025-01-30/02872f1a-5598-4484-bbc9-dcb73c098fc0.png","alt":"Часть 2: Как оптимизировать логирование в .NET: советы и примеры 5","dimensions":{"width":904,"height":82},"additionalSizes":{"srcSet":[{"url":"https://tproger.ru/signed_image/HYHGRXu5AGc8vfifqFh_uYCYopHO8Fw5v_T1NxIIehY/rs:fill:766:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wMjg3MmYxYS01NTk4LTQ0ODQtYmJjOS1kY2I3M2MwOThmYzAucG5n","dpr":1,"width":766},{"url":"https://tproger.ru/signed_image/nEsnffZBa1yJszqyvC_RHFfqyqd2UKpY3BrfkLbKrps/rs:fill:1532:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wMjg3MmYxYS01NTk4LTQ0ODQtYmJjOS1kY2I3M2MwOThmYzAucG5n","dpr":1,"width":1532},{"url":"https://tproger.ru/signed_image/zJsP_Z_YUcgg-8_ooSglwhjSyH1dU1Fk4MejiO7RUpA/rs:fill:686:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wMjg3MmYxYS01NTk4LTQ0ODQtYmJjOS1kY2I3M2MwOThmYzAucG5n","dpr":1,"width":686},{"url":"https://tproger.ru/signed_image/Yfr7XIkibgOptuMTN0FMOESxF-z3jjt2cfgWFNw2BD8/rs:fill:1372:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wMjg3MmYxYS01NTk4LTQ0ODQtYmJjOS1kY2I3M2MwOThmYzAucG5n","dpr":1,"width":1372},{"url":"https://tproger.ru/signed_image/E7lhWxMbMzgcRSqeygRr3vVQUNHnFypoGkAc_lkK7DI/rs:fill:636:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wMjg3MmYxYS01NTk4LTQ0ODQtYmJjOS1kY2I3M2MwOThmYzAucG5n","dpr":1,"width":636},{"url":"https://tproger.ru/signed_image/BgaAKqfFGYkxAaQc5Tdu5KfX0l6F5r1b7kKY8-Yu8Vw/rs:fill:1272:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wMjg3MmYxYS01NTk4LTQ0ODQtYmJjOS1kY2I3M2MwOThmYzAucG5n","dpr":1,"width":1272},{"url":"https://tproger.ru/signed_image/DaqQj26tonFKg8nuJGIFe1OAqgZ6liM1BEyGbhLs2hk/rs:fill:466:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wMjg3MmYxYS01NTk4LTQ0ODQtYmJjOS1kY2I3M2MwOThmYzAucG5n","dpr":1,"width":466},{"url":"https://tproger.ru/signed_image/q_kjAie875J4-DVIAC29pRsdyk6p3_DWeQzObUPB04o/rs:fill:932:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wMjg3MmYxYS01NTk4LTQ0ODQtYmJjOS1kY2I3M2MwOThmYzAucG5n","dpr":1,"width":932}],"sizes":[{"media":"(min-width: 1441px)","size":"766px"},{"media":"(min-width: 1281px)","size":"686px"},{"media":"(min-width: 1281px)","size":"766px"},{"media":"(min-width: 961px)","size":"766px"},{"media":"(min-width: 671px)","size":"636px"},{"media":"(min-width: 500px)","size":"466px"}]}}}},{"id":"DumBBJjRon","type":"paragraph","data":{"text":"Этот код построен на LoggerMessage.Define – следующим шагом мы попадаем в один из методов расширения класса LoggerMessage:"}},{"id":"ikv6f7yh9s","type":"image","data":{"alt":"","title":"","caption":"","file":{"id":45622,"url":"https://media.tproger.ru/user-uploads/101078/2025-01-30/0f6d1cc7-39b0-4003-9c38-89b01a9a9cc3.png"},"stretched":false,"withBackground":false,"withBorder":false,"width":904,"height":340,"optimizedFile":{"original":"https://media.tproger.ru/user-uploads/101078/2025-01-30/0f6d1cc7-39b0-4003-9c38-89b01a9a9cc3.png","alt":"Часть 2: Как оптимизировать логирование в .NET: советы и примеры 6","dimensions":{"width":904,"height":340},"additionalSizes":{"srcSet":[{"url":"https://tproger.ru/signed_image/N_22B3kufUAwolUCrefWFaf7zcLw1QTHTmnjZjvH51Q/rs:fill:766:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wZjZkMWNjNy0zOWIwLTQwMDMtOWMzOC04OWIwMWE5YTljYzMucG5n","dpr":1,"width":766},{"url":"https://tproger.ru/signed_image/U40gGIKfZvpPGqejoI1nAH0ghKP9eQ8l21IGWhciy2A/rs:fill:1532:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wZjZkMWNjNy0zOWIwLTQwMDMtOWMzOC04OWIwMWE5YTljYzMucG5n","dpr":1,"width":1532},{"url":"https://tproger.ru/signed_image/Yq_jHDoPeNbHgul-IxSRKWXrfQx7A9pwQb7S3TofZtw/rs:fill:686:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wZjZkMWNjNy0zOWIwLTQwMDMtOWMzOC04OWIwMWE5YTljYzMucG5n","dpr":1,"width":686},{"url":"https://tproger.ru/signed_image/BGYHFCUe8_r6j6tgMLEsvxTV3cwZBqn9XjXVTZwFeUc/rs:fill:1372:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wZjZkMWNjNy0zOWIwLTQwMDMtOWMzOC04OWIwMWE5YTljYzMucG5n","dpr":1,"width":1372},{"url":"https://tproger.ru/signed_image/sJOdrmsZodofTmCnQZk76ASq6-DvrjA7Fuooq8QcduU/rs:fill:636:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wZjZkMWNjNy0zOWIwLTQwMDMtOWMzOC04OWIwMWE5YTljYzMucG5n","dpr":1,"width":636},{"url":"https://tproger.ru/signed_image/0--tVTkaQnH-CRS8PCtUBem160yfug-5vspvC9QqQq8/rs:fill:1272:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wZjZkMWNjNy0zOWIwLTQwMDMtOWMzOC04OWIwMWE5YTljYzMucG5n","dpr":1,"width":1272},{"url":"https://tproger.ru/signed_image/0mgvLmMdtWj1v2eNKZ8XSFgXj6RzYhSPso_me8sY1-o/rs:fill:466:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wZjZkMWNjNy0zOWIwLTQwMDMtOWMzOC04OWIwMWE5YTljYzMucG5n","dpr":1,"width":466},{"url":"https://tproger.ru/signed_image/wd4KxIkYHCMTwotRP1-gh7ywJUuhMe7RyzvqGtnem54/rs:fill:932:0:true/cb:vimg_2/f:webp/aHR0cHM6Ly9tZWRpYS50cHJvZ2VyLnJ1L3VzZXItdXBsb2Fkcy8xMDEwNzgvMjAyNS0wMS0zMC8wZjZkMWNjNy0zOWIwLTQwMDMtOWMzOC04OWIwMWE5YTljYzMucG5n","dpr":1,"width":932}],"sizes":[{"media":"(min-width: 1441px)","size":"766px"},{"media":"(min-width: 1281px)","size":"686px"},{"media":"(min-width: 1281px)","size":"766px"},{"media":"(min-width: 961px)","size":"766px"},{"media":"(min-width: 671px)","size":"636px"},{"media":"(min-width: 500px)","size":"466px"}]}}}},{"id":"whvCG3QwgE","type":"paragraph","data":{"text":"Таким образом, пакет AutoLoggerMessage позволяет перейти на высокопроизводительное логирование без ручного изменения исходного кода."}},{"id":"qafheW5kCT","type":"paragraph","data":{"text":"Правда, у AutoLoggerMessage есть ограничения:"}},{"id":"osOYxANWFk","type":"list","data":{"style":"ordered","items":["Поддерживается с .NET 8.0.8 и выше;","В ILogger.Log*() может быть не более 6 параметров, иначе параметры передаются как массив object, то есть вызывается упаковка значимых типов."]}},{"id":"gqkKGhsmtz","type":"header2","data":{"text":"Какой подход к логированию выбрать","level":2},"tunes":{}},{"id":"XYgyXM9VMF","type":"paragraph","data":{"text":"Подводя итоги, приведем плюсы и минусы каждого подхода."}},{"id":"Ii-7SDWd1E","type":"header3","data":{"text":"ILogger и методы расширения Log*() класса LoggerExtensions","level":3},"tunes":{}},{"id":"AOEHyAmjS5","type":"paragraph","data":{"text":"+ Если используете ILogger из коробки, вам не нужно добавлять статический класс с объявлениями методов расширения."}},{"id":"MeQH0kjU59","type":"paragraph","data":{"text":"- Так как дополнительные параметры передаются как object, то выполняется упаковка значимых типов."}},{"id":"V7CUciDl6X","type":"paragraph","data":{"text":"- Разбор шаблонов сообщений выполняется при каждом вызове, что может увеличить нагрузку."}},{"id":"SJKuuhgAKE","type":"paragraph","data":{"text":"- Код загрязняется текстом шаблонов сообщений."}},{"id":"08fsCrabtn","type":"paragraph","data":{"text":"- Поскольку сообщения добавляются по мере необходимости прямо в код классов, то возможно дублирование сообщений."}},{"id":"ocfnh0p_X9","type":"header3","data":{"text":"LoggerMessage и строго типизированные методы класса LoggerMessageExtensions","level":3},"tunes":{}},{"id":"95MtckRcwm","type":"paragraph","data":{"text":"+ Не загрязняют код шаблонами сообщений."}},{"id":"0625a2ff-de3d-4be6-8e55-a09a18c67063","type":"banner-blank","data":{}},{"id":"U7v1Rbh2Xg","type":"paragraph","data":{"text":"+ Методы расширений имеют строго типизированные параметры — это исключает упаковку значимых типов."}},{"id":"aCU89SEnTy","type":"paragraph","data":{"text":"+ Шаблоны сообщений разбираются один раз во время старта приложения."}},{"id":"fEYjYXO2e1","type":"paragraph","data":{"text":"+ Все сообщения хранятся в одном статическом классе, которым проще управлять."}},{"id":"7Q7olbS9wk","type":"paragraph","data":{"text":"- Придется добавить статический класс и статический метод для каждого вновь добавляемого сообщения."}},{"id":"qmepyejdQp","type":"hint","data":{"text":"В целом, подход с LoggerMessage имеет больше преимуществ. А с пакетом AutoLoggerMessage можно легко перевести проекты на более производительное логирование.","fullWidth":true}},{"id":"Jq0zzLV0ch","type":"paragraph","data":{"text":"Исходный код можно посмотреть на bitbucket. А узнать больше информации про .NET — в нашем канале."}},{"id":"U2IR8pLCAD","type":"header2","data":{"text":"Полезные ссылки","level":2},"tunes":{}},{"id":"Yk45LVPF8N","type":"paragraph","data":{"text":"Logging in C# - .NET | Microsoft Learn"}},{"id":"P3nE1UCCmW","type":"paragraph","data":{"text":"High-performance logging - .NET | Microsoft Learn"}},{"id":"nFo5qT2Yfj","type":"paragraph","data":{"text":"Compile-time logging source generation - .NET | Microsoft Learn"}},{"id":"JB7tvLoaGS","type":"paragraph","data":{"text":"AutoLoggerMessage"}}],"version":"2.25.0"}

Ошибка в настройках сайта