feat: add duplicate log prevention and improve timezone handling with fallbacks

Author: Admin9705

frontend/static/js/logs.js

@@ -27,6 +27,9 @@ window.LogsModule = {
 
     // Load user's timezone setting from the backend
     loadUserTimezone: function() {
+        // Set immediate fallback to prevent warnings during loading
+        this.userTimezone = this.userTimezone || 'UTC';
+        
         fetch('/api/settings')
             .then(response => response.json())
             .then(settings => {
@@ -91,17 +94,23 @@ window.LogsModule = {
 
     // Convert UTC timestamp to user's timezone
     convertToUserTimezone: function(utcTimestamp) {
-        if (!utcTimestamp || !this.userTimezone) {
-            console.warn('[LogsModule] Missing timestamp or timezone:', {utcTimestamp, userTimezone: this.userTimezone});
-            return { date: utcTimestamp?.split(' ')[0] || '', time: utcTimestamp?.split(' ')[1] || '' };
+        if (!utcTimestamp) {
+            console.debug('[LogsModule] No timestamp provided for conversion');
+            return { date: '', time: '' };
+        }
+        
+        if (!this.userTimezone) {
+            // Set fallback if timezone not loaded yet
+            this.userTimezone = 'UTC';
+            console.debug('[LogsModule] Timezone not loaded yet, using UTC fallback');
         }
 
         try {
-            console.log('[LogsModule] Converting timestamp:', utcTimestamp, 'from UTC to', this.userTimezone);
+            console.debug('[LogsModule] Converting timestamp:', utcTimestamp, 'from UTC to', this.userTimezone);
 
             // Parse UTC timestamp - ensure it's treated as UTC
             const utcDate = new Date(utcTimestamp + ' UTC');
-            console.log('[LogsModule] Parsed UTC date:', utcDate.toISOString());
+            console.debug('[LogsModule] Parsed UTC date:', utcDate.toISOString());
 
             // Convert to user's timezone using toLocaleString
             const userDateString = utcDate.toLocaleString("en-CA", {
@@ -115,7 +124,7 @@ window.LogsModule = {
                 hour12: false
             });
 
-            console.log('[LogsModule] Converted to user timezone:', userDateString);
+            console.debug('[LogsModule] Converted to user timezone:', userDateString);
 
             // Parse the formatted string "2025-06-05, 14:09:54"
             const [datePart, timePart] = userDateString.split(', ');
@@ -125,7 +134,7 @@ window.LogsModule = {
                 time: timePart
             };
 
-            console.log('[LogsModule] Final result:', result);
+            console.debug('[LogsModule] Final result:', result);
             return result;
 
         } catch (error) {

src/primary/background.py

@@ -454,7 +454,7 @@ def app_specific_loop(app_type: str) -> None:
         # Calculate next cycle time in user's timezone without microseconds
         next_cycle_time = now_user_tz + datetime.timedelta(seconds=sleep_seconds)
 
-        app_logger.info(f"Current time ({user_tz}): {now_user_tz.strftime('%Y-%m-%d %H:%M:%S')}")
+        app_logger.debug(f"Current time ({user_tz}): {now_user_tz.strftime('%Y-%m-%d %H:%M:%S')}")
         app_logger.info(f"Next cycle will begin at {next_cycle_time.strftime('%Y-%m-%d %H:%M:%S')} ({user_tz})")
         app_logger.info(f"Sleep duration: {sleep_seconds} seconds")
 

src/primary/utils/history_utils.py

@@ -1,10 +1,15 @@
 #!/usr/bin/env python3
 
+import time
 from src.primary.history_manager import add_history_entry
 from src.primary.utils.logger import get_logger
 
 logger = get_logger("history")
 
+# Cache to prevent duplicate log entries within a short time window
+_recent_log_entries = {}
+_DUPLICATE_WINDOW_SECONDS = 30
+
 def log_processed_media(app_type, media_name, media_id, instance_name, operation_type="missing"):
     """
     Log when media is processed by an app instance
@@ -20,6 +25,22 @@ def log_processed_media(app_type, media_name, media_id, instance_name, operation
     - bool - Success or failure
     """
     try:
+        # Create a unique key for this log entry
+        entry_key = f"{app_type}|{instance_name}|{media_name}|{operation_type}"
+        current_time = time.time()
+        
+        # Check if this exact entry was logged recently
+        if entry_key in _recent_log_entries:
+            last_logged = _recent_log_entries[entry_key]
+            if current_time - last_logged < _DUPLICATE_WINDOW_SECONDS:
+                logger.debug(f"Skipping duplicate history entry for {app_type} - {instance_name}: {media_name} (last logged {current_time - last_logged:.1f}s ago)")
+                return True
+        
+        # Clean up old entries from cache
+        expired_keys = [k for k, v in _recent_log_entries.items() if current_time - v > _DUPLICATE_WINDOW_SECONDS]
+        for key in expired_keys:
+            del _recent_log_entries[key]
+        
         logger.debug(f"Logging history entry for {app_type} - {instance_name}: '{media_name}' (ID: {media_id})")
 
         entry_data = {
@@ -31,6 +52,8 @@ def log_processed_media(app_type, media_name, media_id, instance_name, operation
 
         result = add_history_entry(app_type, entry_data)
         if result:
+            # Record this entry in our cache
+            _recent_log_entries[entry_key] = current_time
             logger.info(f"Logged history entry for {app_type} - {instance_name}: {media_name} ({operation_type})")
             return True
         else: