Bug report logs 597af6e750b04387a3f62586d8352490.log

logs/597af6e750b04387a3f62586d8352490.log

@@ -0,0 +1,200 @@
+2026-07-12 20:08:31,882 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-07-12 20:08:31,885 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 2 printers
+2026-07-12 20:13:33,829 INFO [backend.app.api.routes.cloud] [9e380752] get_filament_info called with 1 IDs: ['GFG99']
+2026-07-12 20:13:34,115 INFO [uvicorn.access] [-] [IP]:36316 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-07-12 20:19:03,061 INFO [backend.app.api.routes.cloud] [f36b7393] get_filament_info called with 1 IDs: ['GFG99']
+2026-07-12 20:19:03,148 INFO [backend.app.services.bambu_mqtt] [32ae0cb8] [[SERIAL]] Set chamber lights on (seq=41)
+2026-07-12 20:19:03,182 INFO [uvicorn.access] [-] [IP]:59128 - "POST /api/v1/printers/1/chamber-light?on=true HTTP/1.1" 200
+2026-07-12 20:19:03,451 INFO [uvicorn.access] [-] [IP]:58972 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-07-12 20:19:06,479 INFO [backend.app.services.bambu_mqtt] [df153eec] [[SERIAL]] Set chamber lights off (seq=43)
+2026-07-12 20:19:06,481 INFO [uvicorn.access] [-] [IP]:58972 - "POST /api/v1/printers/1/chamber-light?on=false HTTP/1.1" 200
+2026-07-12 20:21:02,496 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-07-12 20:21:02,597 INFO [backend.app.services.print_scheduler] [-] Print scheduler stopped
+2026-07-12 20:21:02,598 INFO [backend.app.services.smart_plug_manager] [-] Smart plug scheduler stopped
+2026-07-12 20:21:02,598 INFO [backend.app.services.smart_plug_manager] [-] Smart plug energy snapshot loop stopped
+2026-07-12 20:21:02,599 INFO [backend.app.services.notification_service] [-] Notification digest scheduler stopped
+2026-07-12 20:21:02,599 INFO [backend.app.services.github_backup] [-] Stopped GitHub backup scheduler
+2026-07-12 20:21:02,599 INFO [backend.app.services.local_backup] [-] Stopped local backup scheduler
+2026-07-12 20:21:02,600 INFO [backend.app.services.library_trash] [-] Stopped library trash sweeper
+2026-07-12 20:21:02,601 INFO [backend.app.services.archive_purge] [-] Stopped archive auto-purge sweeper
+2026-07-12 20:21:02,602 INFO [backend.app.services.obico_detection] [-] Stopped Obico detection service
+2026-07-12 20:21:02,602 INFO [backend.app.main] [-] AMS history recording stopped
+2026-07-12 20:21:02,603 INFO [backend.app.main] [-] Printer sensor history recording stopped
+2026-07-12 20:21:02,603 INFO [backend.app.main] [-] Printer runtime tracking stopped
+2026-07-12 20:21:02,604 INFO [backend.app.main] [-] SpoolBuddy watchdog stopped
+2026-07-12 20:21:02,605 INFO [backend.app.main] [-] Camera stream cleanup stopped
+2026-07-12 20:21:02,605 INFO [backend.app.services.loop_watchdog] [-] Event-loop stall watchdog stopped
+2026-07-12 20:21:02,607 INFO [backend.app.main] [-] Expected prints cleanup stopped
+2026-07-12 20:21:02,607 INFO [backend.app.main] [-] Auth periodic cleanup stopped
+2026-07-12 20:21:02,809 INFO [backend.app.services.virtual_printer.manager] [-] Stopping all virtual printer services...
+2026-07-12 20:21:02,810 INFO [backend.app.services.virtual_printer.manager] [-] All virtual printer services stopped
+2026-07-12 20:21:03,363 INFO [root] [-] WAL checkpoint completed
+2026-07-12 20:21:29,685 INFO [root] [-] Logging to file: /app/logs/bambuddy.log
+2026-07-12 20:21:29,687 INFO [root] [-] Bambuddy starting - debug=False, log_level=INFO
+2026-07-12 20:21:34,034 INFO [backend.app.services.printer_manager] [-] Loaded 1 printer(s) awaiting plate-clear acknowledgment: [2]
+2026-07-12 20:21:34,049 INFO [backend.app.services.mqtt_relay] [-] MQTT relay disabled
+2026-07-12 20:21:35,274 WARNING [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT disconnected: rc=Unspecified error, flags=DisconnectFlags(is_disconnect_packet_from_server=False)
+2026-07-12 20:21:35,274 WARNING [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Disconnected shortly after request topic subscription. Disabling request topic for this printer.
+2026-07-12 20:21:35,275 INFO [backend.app.main] [-] [#1752] Printer 1 connected→disconnected edge; scheduling offline notification in 60s
+2026-07-12 20:21:36,137 INFO [backend.app.services.smart_plug_manager] [-] Smart plug scheduler started
+2026-07-12 20:21:36,137 INFO [backend.app.services.smart_plug_manager] [-] Smart plug energy snapshot loop started
+2026-07-12 20:21:36,147 INFO [backend.app.services.print_scheduler] [-] Print scheduler started
+2026-07-12 20:21:36,162 INFO [backend.app.services.notification_service] [-] Notification digest scheduler started
+2026-07-12 20:21:36,164 INFO [backend.app.services.github_backup] [-] Starting GitHub backup scheduler
+2026-07-12 20:21:36,165 INFO [backend.app.services.local_backup] [-] Starting local backup scheduler
+2026-07-12 20:21:36,178 INFO [backend.app.services.obico_detection] [-] Starting Obico detection service
+2026-07-12 20:21:36,179 INFO [backend.app.services.library_trash] [-] Starting library trash sweeper
+2026-07-12 20:21:36,179 INFO [backend.app.services.archive_purge] [-] Starting archive auto-purge sweeper
+2026-07-12 20:21:36,180 INFO [backend.app.main] [-] AMS history recording started
+2026-07-12 20:21:36,181 INFO [backend.app.main] [-] Printer sensor history recording started
+2026-07-12 20:21:36,182 INFO [backend.app.main] [-] Printer runtime tracking started
+2026-07-12 20:21:36,183 INFO [backend.app.main] [-] SpoolBuddy watchdog started
+2026-07-12 20:21:36,184 INFO [backend.app.main] [-] Camera stream cleanup started
+2026-07-12 20:21:36,184 INFO [backend.app.main] [-] Expected prints cleanup started
+2026-07-12 20:21:36,185 INFO [backend.app.main] [-] Auth periodic cleanup started
+2026-07-12 20:21:36,186 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-07-12 20:21:36,213 INFO [root] [-] Virtual printer manager synced from database
+2026-07-12 20:21:37,335 INFO [backend.app.main] [-] [#1752] Printer 1 reconnected before debounce; cancelling pending offline notification
+2026-07-12 20:21:37,461 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Firmware version: 01.08.01.00
+2026-07-12 20:21:37,730 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] PRINT START detected - file: openGrid_one_Plate_1.3mf, subtask: openGrid_one_Plate_1, is_new: True, is_file_change: False
+2026-07-12 20:21:37,734 INFO [backend.app.main] [-] [Printer 1] Broadcasting AMS change via WebSocket
+2026-07-12 20:21:38,377 INFO [backend.app.main] [-] [CALLBACK] on_print_start called for printer 1, data keys: ['filename', 'subtask_name', 'remaining_time', 'raw_data', 'ams_mapping']
+2026-07-12 20:21:38,444 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] PRINT START printer 1: mapping=None, tray_now=254, last_loaded_tray=254
+2026-07-12 20:21:38,473 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-07-12 20:21:38,475 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: A1 Mini)
+2026-07-12 20:21:38,477 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] Snapshotted 1 spool assignments for printer 1: {'255-0': 6}
+2026-07-12 20:21:38,490 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-07-12 20:21:38,490 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: A1 Mini)
+2026-07-12 20:21:38,502 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] Captured start remain% for printer 1 (1 trays): {'255-0': 0}
+2026-07-12 20:21:38,503 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Publishing ams_filament_setting: AMS 255, tray 0, tray_info_idx=GFG99, setting_id=PFUScbe419d14152fc
+2026-07-12 20:21:38,504 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Publishing extrusion_cali_sel: AMS 255, tray 0, cali_idx=-1
+2026-07-12 20:21:38,505 INFO [backend.app.api.routes.inventory] [-] No stored K-profile for spool 6 — reset slot to Default K (cali_idx=-1)
+2026-07-12 20:21:38,528 INFO [backend.app.api.routes.inventory] [-] Auto-configured AMS slot ams=255 tray=0 for spool 6 on printer 1
+2026-07-12 20:21:38,529 INFO [backend.app.main] [-] SpoolBuddy pre-config applied on insert: spool 6 → printer 1 AMS255-T0
+2026-07-12 20:21:38,545 INFO [backend.app.main] [-] [PLATE CHECK] printer_id=1, plate_detection_enabled=False
+2026-07-12 20:21:38,545 INFO [backend.app.main] [-] [CALLBACK] Print start detected - filename: openGrid_one_Plate_1.3mf, subtask: openGrid_one_Plate_1
+2026-07-12 20:21:38,555 INFO [backend.app.main] [-] Resuming archive 11 on subtask_id match (1466443519)
+2026-07-12 20:21:38,559 INFO [backend.app.main] [-] [ENERGY] No smart plug for printer 1 (archive 11)
+2026-07-12 20:21:38,570 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-07-12 20:21:38,570 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: A1 Mini)
+2026-07-12 20:21:38,916 INFO [uvicorn.access] [-] [IP]:39616 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-12 20:21:39,162 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-07-12 20:21:39,170 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-07-12 20:21:39,173 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 2 printers
+2026-07-12 20:21:41,428 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 181908 bytes
+2026-07-12 20:21:42,414 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 181908 bytes
+2026-07-12 20:21:42,415 INFO [backend.app.services.notification_service] [-] on_print_start called for printer 1 ([PRINTER])
+2026-07-12 20:21:42,422 INFO [backend.app.services.notification_service] [-] No notification providers configured for print_start event on printer 1
+2026-07-12 20:21:42,459 INFO [backend.app.main] [-] Loaded 37 printable objects for printer 1
+2026-07-12 20:21:43,831 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Probing developer mode via ams_filament_setting (seq=5)
+2026-07-12 20:21:43,964 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Developer mode probe: ENABLED (result='success')
+2026-07-12 20:21:44,075 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 187911 bytes
+2026-07-12 20:24:49,797 INFO [uvicorn.access] [-] [IP]:50070 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-07-12 20:24:50,129 INFO [backend.app.api.routes.cloud] [e3bb7357] get_filament_info called with 1 IDs: ['GFG99']
+2026-07-12 20:24:50,266 INFO [backend.app.api.routes.printers] [c344ebb8] Trying to download cover for 'openGrid_one_Plate_1' from [IP] (trying 8 paths)
+2026-07-12 20:24:50,466 INFO [uvicorn.access] [-] [IP]:50196 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-07-12 20:24:51,063 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-07-12 20:24:51,551 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=A1 Mini, prot_c=False)
+2026-07-12 20:24:51,850 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:24:52,042 INFO [uvicorn.access] [-] [IP]:50204 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-07-12 20:24:52,120 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /cache/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:24:52,304 INFO [uvicorn.access] [-] [IP]:50304 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-12 20:24:52,406 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /model/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:24:52,678 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-07-12 20:24:52,714 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /data/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:24:52,771 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-07-12 20:24:52,774 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 2 printers
+2026-07-12 20:24:53,187 INFO [backend.app.api.routes.printers] [27bd75c1] Trying to download cover for 'openGrid_one_Plate_1' from [IP] (trying 8 paths)
+2026-07-12 20:24:53,358 INFO [backend.app.api.routes.cloud] [a3067e94] get_filament_info called with 1 IDs: ['GFG99']
+2026-07-12 20:24:53,368 INFO [uvicorn.access] [-] [IP]:50078 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-07-12 20:24:54,813 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=A1 Mini, prot_c=False)
+2026-07-12 20:24:55,080 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:24:55,558 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /cache/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:24:56,120 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /model/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:24:56,721 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /data/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:25:06,096 INFO [backend.app.services.bambu_ftp] [-] Successfully downloaded /openGrid_one_Plate_1.3mf to /app/data/archive/temp/cover_1_openGrid_one_Plate_1.gcode.3mf (643072 bytes)
+2026-07-12 20:25:06,207 INFO [backend.app.api.routes.printers] [c344ebb8] Downloaded file size: 659456 bytes
+2026-07-12 20:25:09,207 WARNING [backend.app.services.bambu_ftp] [-] FTP download returned 0 bytes for /openGrid_one_Plate_1.3mf
+2026-07-12 20:25:09,454 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /cache/openGrid_one_Plate_1.3mf: 550  (not on printer)
+2026-07-12 20:25:09,654 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /model/openGrid_one_Plate_1.3mf: 550  (not on printer)
+2026-07-12 20:25:09,901 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /data/openGrid_one_Plate_1.3mf: 550  (not on printer)
+2026-07-12 20:25:11,174 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=A1 Mini, prot_c=False)
+2026-07-12 20:25:11,406 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:25:11,654 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /cache/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:25:11,913 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /model/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:25:12,174 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /data/openGrid_one_Plate_1.gcode.3mf: 550  (not on printer)
+2026-07-12 20:25:16,721 INFO [backend.app.services.bambu_mqtt] [5b90cbaf] [[SERIAL]] Set chamber lights on (seq=7)
+2026-07-12 20:25:16,723 INFO [uvicorn.access] [-] [IP]:46226 - "POST /api/v1/printers/1/chamber-light?on=true HTTP/1.1" 200
+2026-07-12 20:25:19,348 INFO [backend.app.services.plate_detection] [2eea0d71] OpenCV not available - plate detection feature disabled
+2026-07-12 20:25:19,443 INFO [backend.app.services.bambu_mqtt] [a0684077] [[SERIAL]] Set chamber lights off (seq=9)
+2026-07-12 20:25:19,445 INFO [uvicorn.access] [-] [IP]:46226 - "POST /api/v1/printers/1/chamber-light?on=false HTTP/1.1" 200
+2026-07-12 20:25:21,239 INFO [backend.app.services.bambu_ftp] [-] Successfully downloaded /openGrid_one_Plate_1.3mf to /app/data/archive/temp/cover_1_openGrid_one_Plate_1.gcode.3mf (987653 bytes)
+2026-07-12 20:25:21,297 INFO [backend.app.api.routes.printers] [27bd75c1] Downloaded file size: 987653 bytes
+2026-07-12 20:25:21,299 INFO [backend.app.api.routes.printers] [27bd75c1] Cover: detected plate 1 from 3MF contents
+2026-07-12 20:25:52,530 INFO [backend.app.services.bambu_mqtt] [c40f2898] [[SERIAL]] Set chamber lights on (seq=11)
+2026-07-12 20:25:52,534 INFO [uvicorn.access] [-] [IP]:35266 - "POST /api/v1/printers/1/chamber-light?on=true HTTP/1.1" 200
+2026-07-12 20:25:55,259 INFO [backend.app.services.bambu_mqtt] [bbd912c7] [[SERIAL]] Set chamber lights off (seq=13)
+2026-07-12 20:25:55,261 INFO [uvicorn.access] [-] [IP]:35270 - "POST /api/v1/printers/1/chamber-light?on=false HTTP/1.1" 200
+2026-07-12 20:27:16,062 INFO [backend.app.api.routes.support] [63016c46] Log level changed to DEBUG
+2026-07-12 20:27:16,062 INFO [backend.app.api.routes.bug_report] [63016c46] Bug report: enabled debug logging
+2026-07-12 20:27:16,062 DEBUG [backend.app.services.bambu_mqtt] [63016c46] [[SERIAL]] Requesting status update (pushall)
+2026-07-12 20:27:16,063 WARNING [backend.app.services.bambu_mqtt] [63016c46] [[SERIAL]] request_status_update: not connected
+2026-07-12 20:27:16,064 INFO [uvicorn.access] [-] [IP]:38124 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-07-12 20:27:16,202 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'buildplate_marker_detector': True}
+2026-07-12 20:27:16,202 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['buildplate_marker_detector']
+2026-07-12 20:27:16,202 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: openGrid_one_Plate_1.3mf, subtask_name: openGrid_one_Plate_1
+2026-07-12 20:27:16,203 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '0', 'tray_exist_bits': '0', 'tray_is_bbl_bits': '0', 'tray_tar': '254', 'tray_now': '254', 'tray_pre': '254', 'tray_read_done_bits': '0', 'tray_reading_bits': '0', 'version': 2, 'insert_flag': True, 'power_on_flag': False}
+2026-07-12 20:27:16,203 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 254
+2026-07-12 20:27:16,203 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 0 new units, 0 total
+2026-07-12 20:27:16,203 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-07-12 20:27:16,204 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-07-12 20:27:16,204 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 5.0
+2026-07-12 20:27:16,204 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 5.0°C (heater OFF)
+2026-07-12 20:27:16,205 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=5.0, heating=False, respect_local=False
+2026-07-12 20:27:16,205 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 5.0, target: 0.0, heating: False
+2026-07-12 20:27:16,205 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-07-12 20:27:16,205 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'ipcam_dev': '1', 'ipcam_record': 'enable', 'timelapse': 'disable', 'resolution': '1080p', 'tutk_server': 'disable', 'mode_bits': 3}
+2026-07-12 20:27:16,205 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -71dBm
+2026-07-12 20:27:16,206 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'off'}]
+2026-07-12 20:27:16,206 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: openGrid_one_Plate_1.3mf, subtask: openGrid_one_Plate_1
+2026-07-12 20:27:18,033 DEBUG [backend.app.api.routes.printers] [c327bb32] API returning ams_mapping: [], ams_extruder_map: {}
+2026-07-12 20:27:18,034 DEBUG [backend.app.api.routes.printers] [c327bb32] Using tray_now directly as global ID: 254
+2026-07-12 20:27:18,249 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-07-12 20:27:18,251 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -72dBm
+2026-07-12 20:27:19,312 INFO [backend.app.services.bambu_mqtt] [c68ff49d] [[SERIAL]] Set chamber lights on (seq=15)
+2026-07-12 20:27:19,317 INFO [uvicorn.access] [-] [IP]:38126 - "POST /api/v1/printers/1/chamber-light?on=true HTTP/1.1" 200
+2026-07-12 20:27:19,381 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received system data: {'command': 'ledctrl', 'led_node': 'chamber_light', 'led_mode': 'on', 'led_on_time': 500, 'led_off_time': 500, 'loop_times': 0, 'interval_time': 0, 'sequence_id': '14', 'reason': 'success', 'result': 'success'}
+2026-07-12 20:27:19,442 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received system data: {'command': 'ledctrl', 'led_node': 'chamber_light2', 'led_mode': 'on', 'led_on_time': 500, 'led_off_time': 500, 'loop_times': 0, 'interval_time': 0, 'sequence_id': '15', 'reason': 'success', 'result': 'success'}
+2026-07-12 20:27:20,262 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-07-12 20:27:20,262 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-07-12 20:27:20,262 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_light changed: False -> True
+2026-07-12 20:27:21,328 DEBUG [backend.app.main] [-] [[PRINTER]] Runtime tracking: added 30s, total=14680s (4.08h)
+2026-07-12 20:27:21,334 DEBUG [backend.app.main] [-] [A1] Runtime tracking: not connected
+2026-07-12 20:27:21,334 DEBUG [backend.app.main] [-] Updated runtime for 1 printer(s)
+2026-07-12 20:27:22,030 INFO [backend.app.services.bambu_mqtt] [0a36350b] [[SERIAL]] Set chamber lights off (seq=17)
+2026-07-12 20:27:22,032 INFO [uvicorn.access] [-] [IP]:38126 - "POST /api/v1/printers/1/chamber-light?on=false HTTP/1.1" 200
+2026-07-12 20:27:22,082 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received system data: {'command': 'ledctrl', 'led_node': 'chamber_light', 'led_mode': 'off', 'led_on_time': 500, 'led_off_time': 500, 'loop_times': 0, 'interval_time': 0, 'sequence_id': '16', 'reason': 'success', 'result': 'success'}
+2026-07-12 20:27:22,140 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received system data: {'command': 'ledctrl', 'led_node': 'chamber_light2', 'led_mode': 'off', 'led_on_time': 500, 'led_off_time': 500, 'loop_times': 0, 'interval_time': 0, 'sequence_id': '17', 'reason': 'success', 'result': 'success'}
+2026-07-12 20:27:23,771 DEBUG [backend.app.api.routes.printers] [069575d4] API returning ams_mapping: [], ams_extruder_map: {}
+2026-07-12 20:27:23,771 DEBUG [backend.app.api.routes.printers] [069575d4] Using tray_now directly as global ID: 255
+2026-07-12 20:27:26,342 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-07-12 20:27:26,342 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -73dBm
+2026-07-12 20:27:26,343 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'off'}]
+2026-07-12 20:27:26,343 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_light changed: True -> False
+2026-07-12 20:27:26,862 INFO [backend.app.services.bambu_mqtt] [bbcddca5] [[SERIAL]] Set chamber lights on (seq=19)
+2026-07-12 20:27:26,865 INFO [uvicorn.access] [-] [IP]:38126 - "POST /api/v1/printers/1/chamber-light?on=true HTTP/1.1" 200
+2026-07-12 20:27:26,929 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received system data: {'command': 'ledctrl', 'led_node': 'chamber_light', 'led_mode': 'on', 'led_on_time': 500, 'led_off_time': 500, 'loop_times': 0, 'interval_time': 0, 'sequence_id': '18', 'reason': 'success', 'result': 'success'}
+2026-07-12 20:27:27,001 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received system data: {'command': 'ledctrl', 'led_node': 'chamber_light2', 'led_mode': 'on', 'led_on_time': 500, 'led_off_time': 500, 'loop_times': 0, 'interval_time': 0, 'sequence_id': '19', 'reason': 'success', 'result': 'success'}
+2026-07-12 20:27:28,442 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-07-12 20:27:28,443 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-07-12 20:27:28,444 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_light changed: False -> True
+2026-07-12 20:27:29,588 INFO [backend.app.services.bambu_mqtt] [47202753] [[SERIAL]] Set chamber lights off (seq=21)
+2026-07-12 20:27:29,589 INFO [uvicorn.access] [-] [IP]:48720 - "POST /api/v1/printers/1/chamber-light?on=false HTTP/1.1" 200
+2026-07-12 20:27:29,661 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received system data: {'command': 'ledctrl', 'led_node': 'chamber_light', 'led_mode': 'off', 'led_on_time': 500, 'led_off_time': 500, 'loop_times': 0, 'interval_time': 0, 'sequence_id': '20', 'reason': 'success', 'result': 'success'}
+2026-07-12 20:27:29,730 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received system data: {'command': 'ledctrl', 'led_node': 'chamber_light2', 'led_mode': 'off', 'led_on_time': 500, 'led_off_time': 500, 'loop_times': 0, 'interval_time': 0, 'sequence_id': '21', 'reason': 'success', 'result': 'success'}
+2026-07-12 20:27:30,534 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-07-12 20:27:30,534 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -74dBm
+2026-07-12 20:27:32,561 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-07-12 20:27:32,561 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -71dBm
+2026-07-12 20:27:32,562 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'off'}]
+2026-07-12 20:27:32,562 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_light changed: True -> False