Bug report logs ddb4020eeb2441b68d027b47edf41d7a.log

logs/ddb4020eeb2441b68d027b47edf41d7a.log

@@ -0,0 +1,200 @@
+2026-06-29 10:12:58,898 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:17:58,908 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:22:58,920 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:27:58,929 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:32:58,940 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:37:58,948 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:42:58,979 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:47:58,989 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:52:58,998 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 10:57:59,006 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:02:59,015 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:07:59,025 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:12:59,036 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:17:59,046 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:22:59,057 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:27:59,066 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:32:59,077 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:37:59,086 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:42:59,098 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:47:59,109 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:52:59,119 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 11:57:59,130 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:02:59,139 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:07:59,148 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:12:59,156 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:17:59,166 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:22:59,174 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:27:59,183 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:32:59,192 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:37:09,563 INFO [uvicorn.access] [-] [IP]:42370 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-06-29 12:37:09,783 INFO [uvicorn.access] [-] [IP]:42558 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-06-29 12:37:09,851 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-06-29 12:37:09,852 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-06-29 12:37:09,854 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-06-29 12:37:10,023 INFO [backend.app.api.routes.cloud] [7a67eaff] get_filament_info called with 3 IDs: ['GFA00', 'P3507b3f', 'P8b667ee']
+2026-06-29 12:37:10,497 WARNING [backend.app.api.routes.cloud] [7a67eaff] Failed to get cloud preset P3507b3f (API ID: P3507b3f): Failed to get setting detail: 400
+2026-06-29 12:37:10,713 WARNING [backend.app.api.routes.cloud] [7a67eaff] Failed to get cloud preset P8b667ee (API ID: P8b667ee): Failed to get setting detail: 400
+2026-06-29 12:37:10,716 INFO [uvicorn.access] [-] [IP]:42648 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-06-29 12:37:10,790 INFO [backend.app.services.firmware_check] [9c7642dc] Got Bambu Lab build ID: MhW5YbaMR04SYuNYVMTH4
+2026-06-29 12:37:19,324 INFO [uvicorn.access] [-] [IP]:33970 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-06-29 12:37:19,374 INFO [backend.app.api.routes.camera] [686a50a7] Using chamber image protocol for P1S
+2026-06-29 12:37:19,374 INFO [backend.app.api.routes.camera] [686a50a7] Camera viewer attached to printer-1 (subscribers=1)
+2026-06-29 12:37:19,375 INFO [backend.app.api.routes.camera] [686a50a7] Starting chamber image stream for [IP] (stream_id=1-fanout, model=P1S)
+2026-06-29 12:37:19,416 INFO [uvicorn.access] [-] [IP]:34034 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-06-29 12:37:19,436 INFO [backend.app.api.routes.camera] [e07a7f98] Using chamber image protocol for P1S
+2026-06-29 12:37:19,436 INFO [backend.app.api.routes.camera] [e07a7f98] Camera viewer attached to printer-1 (subscribers=2)
+2026-06-29 12:37:20,132 INFO [backend.app.services.camera] [686a50a7] Chamber image: connected to [IP]:6000
+2026-06-29 12:37:21,312 INFO [backend.app.api.routes.camera] [e07a7f98] Camera viewer detached from printer-1 (subscribers=1)
+2026-06-29 12:37:21,312 INFO [backend.app.api.routes.camera] [686a50a7] Camera viewer detached from printer-1 (subscribers=0)
+2026-06-29 12:37:21,323 INFO [backend.app.api.routes.camera] [249f4435] Using chamber image protocol for P1S
+2026-06-29 12:37:21,323 INFO [backend.app.api.routes.camera] [249f4435] Camera viewer attached to printer-1 (subscribers=1)
+2026-06-29 12:37:37,427 INFO [backend.app.api.routes.camera] [249f4435] Camera viewer detached from printer-1 (subscribers=0)
+2026-06-29 12:37:37,465 INFO [backend.app.api.routes.camera] [686a50a7] Chamber image stream cancelled (stream_id=1-fanout)
+2026-06-29 12:37:37,470 INFO [backend.app.api.routes.camera] [686a50a7] Chamber image stream stopped for [IP] (stream_id=1-fanout)
+2026-06-29 12:37:37,471 INFO [backend.app.api.routes.camera] [acad7d60] Shut down camera fan-out broadcaster for printer 1
+2026-06-29 12:37:37,471 INFO [backend.app.api.routes.camera] [acad7d60] Stopped 0 camera stream(s) for printer 1
+2026-06-29 12:37:37,472 INFO [uvicorn.access] [-] [IP]:40300 - "POST /api/v1/printers/1/camera/stop HTTP/1.1" 200
+2026-06-29 12:37:59,202 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:38:33,483 INFO [uvicorn.access] [-] [IP]:60964 - "DELETE /api/v1/archives/110?purge_stats=true HTTP/1.1" 200
+2026-06-29 12:38:33,913 INFO [uvicorn.access] [-] [IP]:32946 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-06-29 12:42:59,218 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:47:01,451 INFO [backend.app.api.routes.cloud] [59951d2d] get_filament_info called with 3 IDs: ['GFA00', 'P3507b3f', 'P8b667ee']
+2026-06-29 12:47:02,025 WARNING [backend.app.api.routes.cloud] [59951d2d] Failed to get cloud preset P3507b3f (API ID: P3507b3f): Failed to get setting detail: 400
+2026-06-29 12:47:02,232 WARNING [backend.app.api.routes.cloud] [59951d2d] Failed to get cloud preset P8b667ee (API ID: P8b667ee): Failed to get setting detail: 400
+2026-06-29 12:47:02,234 INFO [uvicorn.access] [-] [IP]:51428 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-06-29 12:47:18,582 INFO [backend.app.api.routes.print_queue] [3c3f8fd3] Added archive 109 to queue for 1
+2026-06-29 12:47:18,586 INFO [uvicorn.access] [-] [IP]:36356 - "POST /api/v1/queue/ HTTP/1.1" 200
+2026-06-29 12:47:35,564 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(7, 1, 109, None)]
+2026-06-29 12:47:35,566 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 12:47:43,284 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-06-29 12:47:44,166 INFO [uvicorn.access] [-] [IP]:39646 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-06-29 12:47:44,252 INFO [uvicorn.access] [-] [IP]:39800 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-06-29 12:47:44,367 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-06-29 12:47:44,368 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-06-29 12:47:44,369 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-06-29 12:47:44,571 INFO [backend.app.api.routes.cloud] [188a27ac] get_filament_info called with 3 IDs: ['GFA00', 'P3507b3f', 'P8b667ee']
+2026-06-29 12:47:44,572 INFO [uvicorn.access] [-] [IP]:39924 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-06-29 12:47:59,228 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:48:05,578 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(7, 1, 109, None)]
+2026-06-29 12:48:05,579 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 12:48:15,679 INFO [backend.app.api.routes.print_queue] [10a5e797] Cancelled queue item 7
+2026-06-29 12:48:15,680 INFO [uvicorn.access] [-] [IP]:49422 - "POST /api/v1/queue/7/cancel HTTP/1.1" 200
+2026-06-29 12:49:07,439 INFO [root] [-] Logging to file: /app/logs/bambuddy.log
+2026-06-29 12:49:07,440 INFO [root] [-] Bambuddy starting - debug=False, log_level=INFO
+2026-06-29 12:49:09,942 INFO [backend.app.services.printer_manager] [-] Loaded 1 printer(s) awaiting plate-clear acknowledgment: [1]
+2026-06-29 12:49:09,950 INFO [backend.app.services.mqtt_relay] [-] MQTT relay disabled
+2026-06-29 12:49:10,743 WARNING [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT disconnected: rc=Unspecified error, flags=DisconnectFlags(is_disconnect_packet_from_server=False)
+2026-06-29 12:49:10,743 WARNING [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Disconnected shortly after request topic subscription. Disabling request topic for this printer.
+2026-06-29 12:49:10,744 INFO [backend.app.main] [-] [#1752] Printer 1 connected→disconnected edge; scheduling offline notification in 60s
+2026-06-29 12:49:10,977 INFO [backend.app.services.smart_plug_manager] [-] Smart plug scheduler started
+2026-06-29 12:49:10,977 INFO [backend.app.services.smart_plug_manager] [-] Smart plug energy snapshot loop started
+2026-06-29 12:49:10,984 INFO [backend.app.services.print_scheduler] [-] Print scheduler started
+2026-06-29 12:49:10,989 INFO [backend.app.services.notification_service] [-] Notification digest scheduler started
+2026-06-29 12:49:10,989 INFO [backend.app.services.github_backup] [-] Starting GitHub backup scheduler
+2026-06-29 12:49:10,989 INFO [backend.app.services.local_backup] [-] Starting local backup scheduler
+2026-06-29 12:49:10,994 INFO [backend.app.services.obico_detection] [-] Starting Obico detection service
+2026-06-29 12:49:10,994 INFO [backend.app.services.library_trash] [-] Starting library trash sweeper
+2026-06-29 12:49:10,994 INFO [backend.app.services.archive_purge] [-] Starting archive auto-purge sweeper
+2026-06-29 12:49:10,994 INFO [backend.app.main] [-] AMS history recording started
+2026-06-29 12:49:10,994 INFO [backend.app.main] [-] Printer sensor history recording started
+2026-06-29 12:49:10,995 INFO [backend.app.main] [-] Printer runtime tracking started
+2026-06-29 12:49:10,995 INFO [backend.app.main] [-] SpoolBuddy watchdog started
+2026-06-29 12:49:10,995 INFO [backend.app.main] [-] Camera stream cleanup started
+2026-06-29 12:49:10,995 INFO [backend.app.main] [-] Expected prints cleanup started
+2026-06-29 12:49:10,995 INFO [backend.app.main] [-] Auth periodic cleanup started
+2026-06-29 12:49:10,996 INFO [backend.app.services.loop_watchdog] [-] Event-loop stall watchdog started — dumps all thread stacks to stderr if the loop stalls for more than 30s
+2026-06-29 12:49:11,006 INFO [root] [-] Virtual printer manager synced from database
+2026-06-29 12:49:12,438 INFO [uvicorn.access] [-] [IP]:51246 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-06-29 12:49:12,455 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-06-29 12:49:12,455 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-06-29 12:49:12,457 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-06-29 12:49:12,500 INFO [backend.app.main] [-] [#1752] Printer 1 reconnected before debounce; cancelling pending offline notification
+2026-06-29 12:49:12,558 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] State is FAILED but completion NOT triggered: prev=None, was_running=False, already_triggered=False, has_callback=True
+2026-06-29 12:49:12,560 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Firmware version: 01.10.00.00
+2026-06-29 12:49:12,564 INFO [backend.app.main] [-] [Printer 1] Broadcasting AMS change via WebSocket
+2026-06-29 12:49:17,628 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Probing developer mode via ams_filament_setting (seq=5)
+2026-06-29 12:49:17,641 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Developer mode probe: ENABLED (result='success')
+2026-06-29 12:49:21,019 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:49:31,382 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-06-29 12:49:32,031 INFO [uvicorn.access] [-] [IP]:42586 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-06-29 12:49:32,124 INFO [uvicorn.access] [-] [IP]:42684 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-06-29 12:49:32,217 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-06-29 12:49:32,217 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-06-29 12:49:32,220 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-06-29 12:49:32,433 INFO [backend.app.api.routes.cloud] [b8a75ed7] get_filament_info called with 3 IDs: ['GFA00', 'P3507b3f', 'P8b667ee']
+2026-06-29 12:49:32,897 WARNING [backend.app.api.routes.cloud] [b8a75ed7] Failed to get cloud preset P3507b3f (API ID: P3507b3f): Failed to get setting detail: 400
+2026-06-29 12:49:33,102 WARNING [backend.app.api.routes.cloud] [b8a75ed7] Failed to get cloud preset P8b667ee (API ID: P8b667ee): Failed to get setting detail: 400
+2026-06-29 12:49:33,107 INFO [uvicorn.access] [-] [IP]:42876 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-06-29 12:49:40,992 INFO [backend.app.api.routes.print_queue] [4cd0a9bd] Added archive 109 to queue for 1
+2026-06-29 12:49:41,000 INFO [uvicorn.access] [-] [IP]:54238 - "POST /api/v1/queue/ HTTP/1.1" 200
+2026-06-29 12:49:41,056 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(8, 1, 109, None)]
+2026-06-29 12:49:41,064 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 12:50:11,128 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(8, 1, 109, None)]
+2026-06-29 12:50:11,129 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 12:50:41,139 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(8, 1, 109, None)]
+2026-06-29 12:50:41,140 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 12:51:11,151 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(8, 1, 109, None)]
+2026-06-29 12:51:11,152 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 12:51:41,163 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(8, 1, 109, None)]
+2026-06-29 12:51:41,165 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 12:52:11,175 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(8, 1, 109, None)]
+2026-06-29 12:52:11,176 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 12:52:19,403 INFO [backend.app.api.routes.print_queue] [31b43a54] Cancelled queue item 8
+2026-06-29 12:52:19,403 INFO [uvicorn.access] [-] [IP]:56818 - "POST /api/v1/queue/8/cancel HTTP/1.1" 200
+2026-06-29 12:54:21,031 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 12:59:21,041 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 13:03:32,676 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-06-29 13:03:32,712 INFO [uvicorn.access] [-] [IP]:60912 - "POST /api/v1/cloud/filament-info HTTP/1.1" 401
+2026-06-29 13:03:35,328 INFO [uvicorn.access] [-] [IP]:60938 - "POST /api/v1/auth/login HTTP/1.1" 200
+2026-06-29 13:03:35,796 INFO [backend.app.api.routes.cloud] [7c641f8a] get_filament_info called with 3 IDs: ['GFA00', 'P3507b3f', 'P8b667ee']
+2026-06-29 13:03:35,802 INFO [uvicorn.access] [-] [IP]:32874 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-06-29 13:03:35,874 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-06-29 13:03:35,874 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-06-29 13:03:35,875 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-06-29 13:03:35,887 INFO [uvicorn.access] [-] [IP]:32962 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-06-29 13:03:35,906 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-06-29 13:03:35,906 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-06-29 13:03:35,907 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-06-29 13:03:36,309 WARNING [backend.app.api.routes.cloud] [7c641f8a] Failed to get cloud preset P3507b3f (API ID: P3507b3f): Failed to get setting detail: 400
+2026-06-29 13:03:36,518 WARNING [backend.app.api.routes.cloud] [7c641f8a] Failed to get cloud preset P8b667ee (API ID: P8b667ee): Failed to get setting detail: 400
+2026-06-29 13:03:36,521 INFO [uvicorn.access] [-] [IP]:60992 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-06-29 13:03:50,029 INFO [backend.app.api.routes.print_queue] [7065fed0] Added library file 10 to queue for 1
+2026-06-29 13:03:50,033 INFO [uvicorn.access] [-] [IP]:45180 - "POST /api/v1/queue/ HTTP/1.1" 200
+2026-06-29 13:04:11,387 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(9, 1, None, 10)]
+2026-06-29 13:04:11,389 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 13:04:21,051 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-06-29 13:04:41,398 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(9, 1, None, 10)]
+2026-06-29 13:04:41,400 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 13:05:11,410 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(9, 1, None, 10)]
+2026-06-29 13:05:11,412 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 13:05:41,421 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(9, 1, None, 10)]
+2026-06-29 13:05:41,423 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 13:06:11,432 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(9, 1, None, 10)]
+2026-06-29 13:06:11,433 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 13:06:41,241 INFO [backend.app.api.routes.support] [9257b60c] Log level changed to DEBUG
+2026-06-29 13:06:41,241 INFO [backend.app.api.routes.bug_report] [9257b60c] Bug report: enabled debug logging
+2026-06-29 13:06:41,241 DEBUG [backend.app.services.bambu_mqtt] [9257b60c] [[SERIAL]] Requesting status update (pushall)
+2026-06-29 13:06:41,242 INFO [uvicorn.access] [-] [IP]:52682 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-06-29 13:06:41,441 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(9, 1, None, 10)]
+2026-06-29 13:06:41,442 DEBUG [backend.app.services.print_scheduler] [-] Printer 1: not idle — awaiting plate-clear acknowledgment (state=FAILED)
+2026-06-29 13:06:41,442 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FAILED, awaiting_plate_clear=True
+2026-06-29 13:06:41,788 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: FAILED, gcode_file: , subtask_name: Self-Filling_Pet_Water_Bowl_(cats_and_small_dogs)
+2026-06-29 13:06:41,788 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '1', 'tray_exist_bits': 'f', 'tray_is_bbl_bits': 'f', 'tray_tar': '1', 'tray_now': '1', 'tray_pre': '1', 'tray_read_done_bits': 'f', 'tray_reading_bits': '0', 'version': 21, 'insert_flag': True, 'power_on_flag': True}
+2026-06-29 13:06:41,788 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 1
+2026-06-29 13:06:41,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-06-29 13:06:41,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x1001 -> extruder 0
+2026-06-29 13:06:41,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-06-29 13:06:41,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-06-29 13:06:41,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-06-29 13:06:41,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 5.0
+2026-06-29 13:06:41,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 5.0°C (heater OFF)
+2026-06-29 13:06:41,790 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=5.0, heating=False, respect_local=False
+2026-06-29 13:06:41,790 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 5.0, target: 0.0, heating: False
+2026-06-29 13:06:41,790 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-06-29 13:06:41,790 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'ipcam_dev': '1', 'ipcam_record': 'disable', 'timelapse': 'disable', 'resolution': '', 'tutk_server': 'disable', 'mode_bits': 3}
+2026-06-29 13:06:41,790 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -34dBm
+2026-06-29 13:06:41,791 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-06-29 13:06:41,791 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: FAILED -> FAILED, file: , subtask: Self-Filling_Pet_Water_Bowl_(cats_and_small_dogs)
+2026-06-29 13:06:47,831 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-06-29 13:06:47,831 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -39dBm