added debugging lines

Author: devhl-labs

src/CocApi.Cache/CocApi.Cache.csproj

@@ -6,7 +6,7 @@
     <Authors>devhl</Authors>
     <Product />
     <Description>Caches response from the Clash of Clans API.</Description>
-    <Version>2.11.5</Version>
+    <Version>2.11.6-debug</Version>
     <AssemblyVersion>2.0.0.0</AssemblyVersion>
     <FileVersion>2.0.0.0</FileVersion>
     <GeneratePackageOnBuild>true</GeneratePackageOnBuild>

src/CocApi.Cache/Services/WarService.cs

@@ -53,14 +53,20 @@ public WarService(
 
     protected override async Task ExecuteScheduledTaskAsync(CancellationToken cancellationToken)
     {
+        Logger.LogInformation("Executing war service");
+
         SetDateVariables();
 
+        Logger.LogInformation("Setting variables");
+
         WarServiceOptions options = Options.Value.Wars;
 
         using var scope = ScopeFactory.CreateScope();
 
         CacheDbContext dbContext = scope.ServiceProvider.GetRequiredService<CacheDbContext>();
 
+        Logger.LogInformation("Querying wars");
+
         List<CachedWar> cachedWars = await dbContext.Wars
             .Where(w =>
                 string.IsNullOrWhiteSpace(w.WarTag) &&
@@ -85,6 +91,8 @@ protected override async Task ExecuteScheduledTaskAsync(CancellationToken cancel
             tags.Add(cachedWar.OpponentTag);
         }
 
+        Logger.LogInformation("Querying clans");
+
         List<CachedClan> allCachedClans = await dbContext.Clans
             .Where(c => tags.Contains(c.Tag))
             .AsNoTracking()
@@ -97,6 +105,8 @@ protected override async Task ExecuteScheduledTaskAsync(CancellationToken cancel
 
         IClansApi clansApi = ApiFactory.Create<IClansApi>();
 
+        Logger.LogInformation("Iterating wars");
+
         try
         {
             foreach (CachedWar cachedWar in cachedWars)
@@ -117,8 +127,12 @@ protected override async Task ExecuteScheduledTaskAsync(CancellationToken cancel
                 tasks.Add(SendWarAnnouncementsAsync(cachedWar, cachedClans.Select(c => c.CurrentWar).ToArray(), cancellationToken));
             }
 
+            Logger.LogInformation("Waiting for tasks to complete");
+
             await Task.WhenAll(tasks).WaitAsync(cancellationToken).ConfigureAwait(false);
 
+            Logger.LogInformation("Tasks are complete");
+
             await dbContext.SaveChangesAsync(CancellationToken.None).ConfigureAwait(false);
         }
         finally
@@ -139,6 +153,8 @@ private async Task UpdateWarAsync(
         CachedClan? cachedOpponent,
         CancellationToken cancellationToken)
     {
+        Logger.LogInformation("Creating cached clans");
+
         if (cachedClan == null && cachedOpponent?.CurrentWar.IsExpired == true)
             cachedClan = await CreateCachedClan(clansApi, cachedWar.ClanTag, dbContext, cancellationToken).ConfigureAwait(false);
 
@@ -158,17 +174,21 @@ private async Task UpdateWarAsync(
             .OrderByDescending(c => c?.CurrentWar.ExpiresAt)
             .FirstOrDefault(c => c?.CurrentWar.PreparationStartTime == cachedWar.PreparationStartTime);
 
-        if (cachedWar.Content != null && 
-            clan?.CurrentWar.Content != null && 
+        if (cachedWar.Content != null &&
+            clan?.CurrentWar.Content != null &&
             CachedWar.HasUpdated(cachedWar, clan.CurrentWar) &&
             ClanWarUpdated != null)
+        {
+            Logger.LogInformation("sending the war updated event");
+
             await ClanWarUpdated.Invoke(this, new ClanWarUpdatedEventArgs(
                     cachedWar.Content,
                     clan.CurrentWar.Content,
                     cachedClan?.Content,
                     cachedOpponent?.Content,
                     cancellationToken))
                 .ConfigureAwait(false);
+        }
 
         if (clan != null)
         {
@@ -183,6 +203,9 @@ private async Task UpdateWarAsync(
                     c.CurrentWar.PreparationStartTime > cachedWar.PreparationStartTime
                 )))
             cachedWar.IsFinal = true;
+
+
+        Logger.LogInformation("Done sending war updated event");
     }
 
     private async Task<CachedClan?> CreateCachedClan(IClansApi clansApi, string tag, CacheDbContext dbContext, CancellationToken cancellationToken)
@@ -194,6 +217,8 @@ private async Task UpdateWarAsync(
         {
             _unmonitoredClans.Add(tag);
 
+            Logger.LogInformation("Creating cached clan war from current war");
+
             CachedClanWar cachedClanWar = await CachedClanWar
                 .FromCurrentWarResponseAsync(tag, Options.Value.RealTime == null ? default : new(Options.Value.RealTime.Value), TimeToLiveProvider, clansApi, cancellationToken)
                 .ConfigureAwait(false);
@@ -240,7 +265,11 @@ private async Task SendWarAnnouncementsAsync(CachedWar cachedWar, CachedClanWar[
                 cachedWar.Announcements |= Announcements.WarStartingSoon;
 
                 if (ClanWarStartingSoon != null)
+                {
+                    Logger.LogInformation("Clan war starting soon");
+
                     await ClanWarStartingSoon.Invoke(this, new WarEventArgs(cachedWar.Content, cancellationToken)).ConfigureAwait(false);
+                }
             }
 
             if (cachedWar.Announcements.HasFlag(Announcements.WarEndingSoon) == false &&
@@ -250,7 +279,10 @@ private async Task SendWarAnnouncementsAsync(CachedWar cachedWar, CachedClanWar[
                 cachedWar.Announcements |= Announcements.WarEndingSoon;
 
                 if (ClanWarEndingSoon != null)
+                {
+                    Logger.LogInformation("Clan war ending soon");
                     await ClanWarEndingSoon.Invoke(this, new WarEventArgs(cachedWar.Content, cancellationToken)).ConfigureAwait(false);
+                }
             }
 
             if (cachedWar.Announcements.HasFlag(Announcements.WarEndNotSeen) == false &&
@@ -264,7 +296,11 @@ private async Task SendWarAnnouncementsAsync(CachedWar cachedWar, CachedClanWar[
                 cachedWar.Announcements |= Announcements.WarEndNotSeen;
 
                 if (ClanWarEndNotSeen != null)
+                {
+                    Logger.LogInformation("Clan war end not seen");
+
                     await ClanWarEndNotSeen.Invoke(this, new WarEventArgs(cachedWar.Content, cancellationToken)).ConfigureAwait(false);
+                }
             }
 
             if (cachedWar.Announcements.HasFlag(Announcements.WarEnded) == false &&
@@ -274,8 +310,14 @@ private async Task SendWarAnnouncementsAsync(CachedWar cachedWar, CachedClanWar[
                 cachedWar.Announcements |= Announcements.WarEnded;
 
                 if (ClanWarEnded != null)
+                {
+                    Logger.LogInformation("Clan war ended");
+
                     await ClanWarEnded.Invoke(this, new WarEventArgs(cachedWar.Content, cancellationToken)).ConfigureAwait(false);
+                }
             }
+
+            Logger.LogInformation("done sending war announcement");
         }
         catch (Exception e)
         {