Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

chore(Orleans.Runtime): Use LoggerMessageGenerator for core logging messages #9316

Open
wants to merge 41 commits into
base: main
Choose a base branch
from
Open
Show file tree
Hide file tree
Changes from 10 commits
Commits
Show all changes
41 commits
Select commit Hold shift + click to select a range
439ff17
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 6, 2025
1d170bc
Merge remote-tracking branch 'origin/main' into chore/logging
Meir017 Feb 6, 2025
f7c0f85
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 6, 2025
6ce7e05
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 7, 2025
d3ed39f
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 7, 2025
edff556
Merge branch 'main' into chore/logging
Meir017 Feb 7, 2025
e022123
Apply suggestions from code review
ReubenBond Feb 7, 2025
2a79c7c
Update src/Orleans.Runtime/Cancellation/CancellationSourcesExtension.cs
ReubenBond Feb 7, 2025
fab2b6a
Merge branch 'main' into chore/logging
Meir017 Feb 8, 2025
606f035
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 8, 2025
7a011e5
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 12, 2025
a20eaab
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 12, 2025
cf364f4
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 12, 2025
dbe98f2
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 12, 2025
d0b8c1c
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 12, 2025
8715740
Merge branch 'main' into chore/logging
Meir017 Feb 12, 2025
736adf1
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 12, 2025
474c6b5
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 12, 2025
05150bd
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
0964897
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
fcebb23
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
af4a727
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
c2cfaf9
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
f0e2e63
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
2072528
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
63b8113
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
239e040
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
1e2681a
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 13, 2025
657d733
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
b18a879
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
ac9de98
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
2222cd5
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
b1734c5
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
d200950
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
71ade67
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
bbc658c
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
b108e14
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
248ca66
Merge branch 'main' into chore/logging
Meir017 Feb 14, 2025
7b78cc6
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
03a49c2
Merge branch 'chore/logging' of https://github.com/Meir017/orleans in…
Meir017 Feb 14, 2025
4c6bab5
chore: use Use LoggerMessageGenerator for core Orleans.Runtime
Meir017 Feb 14, 2025
File filter

Filter by extension

Filter by extension

Conversations
Failed to load comments.
Loading
Jump to
Jump to file
Failed to load files.
Loading
Diff view
Diff view
11 changes: 9 additions & 2 deletions src/Orleans.Runtime/Cancellation/CancellationSourcesExtension.cs
Original file line number Diff line number Diff line change
Expand Up @@ -12,7 +12,7 @@ namespace Orleans.Runtime
/// Contains list of cancellation token source corresponding to the tokens
/// passed to the related grain activation.
/// </summary>
internal class CancellationSourcesExtension : ICancellationSourcesExtension, IDisposable
internal partial class CancellationSourcesExtension : ICancellationSourcesExtension, IDisposable
{
private readonly ConcurrentDictionary<Guid, Entry> _cancellationTokens = new ConcurrentDictionary<Guid, Entry>();
private readonly ILogger _logger;
Expand All @@ -39,7 +39,7 @@ public Task CancelRemoteToken(Guid tokenId)
{
if (!_cancellationTokens.TryGetValue(tokenId, out var entry))
{
_logger.LogWarning((int)ErrorCode.CancellationTokenCancelFailed, "Received a cancel call for token with id {TokenId}, but the token was not found", tokenId);
LogCancellationFailed(tokenId);

// Record the cancellation anyway, in case the call which would have registered the cancellation is still pending.
this.RecordCancellationToken(tokenId, isCancellationRequested: true);
Expand Down Expand Up @@ -132,5 +132,12 @@ public bool IsExpired(TimeSpan expiry, long nowTimestamp)
return untouchedTime >= expiry;
}
}

[LoggerMessage(
EventId = (int)ErrorCode.CancellationTokenCancelFailed,
Level = LogLevel.Warning,
Message = "Received a cancel call for token with id '{TokenId}', but the token was not found."
)]
ReubenBond marked this conversation as resolved.
Show resolved Hide resolved
private partial void LogCancellationFailed(Guid tokenId);
}
}
57 changes: 32 additions & 25 deletions src/Orleans.Runtime/Catalog/ActivationCollector.cs
Original file line number Diff line number Diff line change
Expand Up @@ -14,7 +14,7 @@ namespace Orleans.Runtime
/// <summary>
/// Identifies activations that have been idle long enough to be deactivated.
/// </summary>
internal class ActivationCollector : IActivationWorkingSetObserver, ILifecycleParticipant<ISiloLifecycle>, IDisposable
internal partial class ActivationCollector : IActivationWorkingSetObserver, ILifecycleParticipant<ISiloLifecycle>, IDisposable
{
private readonly TimeSpan quantum;
private readonly TimeSpan shortestAgeLimit;
Expand Down Expand Up @@ -441,7 +441,7 @@ private async Task RunActivationCollectionLoop()
}
catch (Exception exception)
{
this.logger.LogError(exception, "Error while collecting activations.");
LogErrorWhileCollectingActivations(exception);
}
}
}
Expand All @@ -452,16 +452,7 @@ private async Task CollectActivationsImpl(bool scanStale, TimeSpan ageLimit, Can
var number = Interlocked.Increment(ref collectionNumber);
long memBefore = GC.GetTotalMemory(false) / (1024 * 1024);

if (logger.IsEnabled(LogLevel.Debug))
{
logger.LogDebug(
(int)ErrorCode.Catalog_BeforeCollection,
"Before collection #{CollectionNumber}: memory: {MemoryBefore}MB, #activations: {ActivationCount}, collector: {CollectorStatus}",
number,
memBefore,
_activationCount,
ToString());
}
LogBeforeCollection(number, memBefore, _activationCount, this);

List<ICollectibleGrainContext> list = scanStale ? ScanStale() : ScanAll(ageLimit);
CatalogInstruments.ActivationCollections.Add(1);
Meir017 marked this conversation as resolved.
Show resolved Hide resolved
Expand All @@ -474,23 +465,12 @@ private async Task CollectActivationsImpl(bool scanStale, TimeSpan ageLimit, Can
long memAfter = GC.GetTotalMemory(false) / (1024 * 1024);
watch.Stop();

if (logger.IsEnabled(LogLevel.Debug))
{
logger.LogDebug(
(int)ErrorCode.Catalog_AfterCollection,
"After collection #{CollectionNumber} memory: {MemoryAfter}MB, #activations: {ActivationCount}, collected {CollectedCount} activations, collector: {CollectorStatus}, collection time: {CollectionTime}",
number,
memAfter,
_activationCount,
list?.Count ?? 0,
ToString(),
watch.Elapsed);
}
LogAfterCollection(number, memAfter, _activationCount, list?.Count ?? 0, this, watch.Elapsed);
}

private async Task DeactivateActivationsFromCollector(List<ICollectibleGrainContext> list, CancellationToken cancellationToken)
{
logger.LogInformation((int)ErrorCode.Catalog_ShutdownActivations_1, "Deactivating '{Count}' idle activations.", list.Count);
LogDeactivateActivationsFromCollector(list.Count);
CatalogInstruments.ActivationShutdownViaCollection();

var reason = GetDeactivationReason();
Expand Down Expand Up @@ -567,5 +547,32 @@ public List<ICollectibleGrainContext> CancelAll()
return result ?? nothing;
}
}

[LoggerMessage(
Level = LogLevel.Error,
Message = "Error while collecting activations."
)]
private partial void LogErrorWhileCollectingActivations(Exception exception);

[LoggerMessage(
EventId = (int)ErrorCode.Catalog_BeforeCollection,
Level = LogLevel.Debug,
Message = "Before collection #{CollectionNumber}: memory: {MemoryBefore}MB, #activations: {ActivationCount}, collector: {CollectorStatus}"
)]
private partial void LogBeforeCollection(int collectionNumber, long memoryBefore, int activationCount, ActivationCollector collectorStatus);

[LoggerMessage(
EventId = (int)ErrorCode.Catalog_AfterCollection,
Level = LogLevel.Debug,
Message = "After collection #{CollectionNumber} memory: {MemoryAfter}MB, #activations: {ActivationCount}, collected {CollectedCount} activations, collector: {CollectorStatus}, collection time: {CollectionTime}"
)]
private partial void LogAfterCollection(int collectionNumber, long memoryAfter, int activationCount, int collectedCount, ActivationCollector collectorStatus, TimeSpan collectionTime);

[LoggerMessage(
EventId = (int)ErrorCode.Catalog_ShutdownActivations_1,
Level = LogLevel.Information,
Message = "Deactivating '{Count}' idle activations."
)]
private partial void LogDeactivateActivationsFromCollector(int count);
}
}
Loading