Bug report logs ab77b5273a014227ad28051767c6ca6f.log

logs/ab77b5273a014227ad28051767c6ca6f.log

@@ -0,0 +1,200 @@
+2026-05-24 09:26:43,782 INFO [backend.app.api.routes.printers] [909b32c2] Cover: resolved plate 7 before download (subtask=AM4xS_-_Extension_Kit_plate_7)
+2026-05-24 09:26:43,784 INFO [backend.app.api.routes.printers] [909b32c2] Cover using cached 3MF from /app/data/archive/unassigned/20260524_092515_AM4xS - Extension Kit_plate_7/AM4xS - Extension Kit_plate_7.gcode.3mf (avoided duplicate FTP)
+2026-05-24 09:26:43,785 INFO [backend.app.api.routes.printers] [909b32c2] Downloaded file size: 2244236 bytes
+2026-05-24 09:26:43,791 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-05-24 09:26:43,792 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: P1S)
+2026-05-24 09:26:46,181 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 92484 bytes
+2026-05-24 09:26:46,182 INFO [backend.app.services.notification_service] [-] on_print_start called for printer 1 ([PRINTER])
+2026-05-24 09:26:46,186 INFO [backend.app.services.notification_service] [-] No notification providers configured for print_start event on printer 1
+2026-05-24 09:26:46,190 INFO [backend.app.main] [-] Loaded 15 printable objects for printer 1
+2026-05-24 09:26:47,044 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=P1S, prot_c=False)
+2026-05-24 09:26:57,187 INFO [backend.app.main] [-] [TIMELAPSE] Baseline at print start: 267 video files for printer 1
+2026-05-24 09:27:07,238 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-05-24 09:27:07,592 INFO [uvicorn.access] [-] [IP]:38520 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-05-24 09:27:07,614 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting...
+2026-05-24 09:27:07,615 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-05-24 09:27:07,616 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-05-24 09:27:31,277 INFO [backend.app.services.camera] [8c0199f1] Found ffmpeg at: /usr/bin/ffmpeg
+2026-05-24 09:27:41,576 INFO [backend.app.api.routes.support] [a4f2d269] Log level changed to DEBUG
+2026-05-24 09:27:41,577 INFO [uvicorn.access] [-] [IP]:59250 - "POST /api/v1/support/debug-logging HTTP/1.1" 200
+2026-05-24 09:27:43,714 DEBUG [backend.app.services.virtual_printer.ssdp_server] [-] Sent SSDP NOTIFY for Bambuddy (Location=[IP], USN=[SERIAL], bind=[IP])
+2026-05-24 09:27:44,971 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:27:44,972 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -49dBm
+2026-05-24 09:27:46,993 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:27:46,994 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -48dBm
+2026-05-24 09:27:48,932 DEBUG [backend.app.services.network_utils] [d0f34b83] Found interface eth0 ([IP]) for target [IP]
+2026-05-24 09:27:49,765 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Requesting firmware version info
+2026-05-24 09:27:49,766 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Sending K-profile priming request
+2026-05-24 09:27:49,778 WARNING [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT disconnected: rc=Normal disconnection, flags=DisconnectFlags(is_disconnect_packet_from_server=False)
+2026-05-24 09:27:49,786 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 firmware: 00.00.06.49
+2026-05-24 09:27:49,819 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: extrusion_cali_get
+2026-05-24 09:27:50,337 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: AM4xS_-_Extension_Kit_plate_7.3mf, subtask_name: AM4xS_-_Extension_Kit_plate_7
+2026-05-24 09:27:50,337 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': '255', 'tray_now': '255', 'tray_pre': '255', 'tray_read_done_bits': 'f', 'tray_reading_bits': '0', 'version': 25, 'insert_flag': True, 'power_on_flag': False}
+2026-05-24 09:27:50,337 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 255
+2026-05-24 09:27:50,337 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-05-24 09:27:50,337 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 0 (main=0, sub=0)
+2026-05-24 09:27:50,338 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:27:50,338 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 5.0
+2026-05-24 09:27:50,338 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 5.0°C (heater OFF)
+2026-05-24 09:27:50,338 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=5.0, heating=False, respect_local=False
+2026-05-24 09:27:50,338 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 5.0, target: 0.0, heating: False
+2026-05-24 09:27:50,338 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-05-24 09:27:50,339 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'ipcam_dev': '1', 'ipcam_record': 'enable', 'timelapse': 'disable', 'resolution': '', 'tutk_server': 'disable', 'mode_bits': 3}
+2026-05-24 09:27:50,339 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -48dBm
+2026-05-24 09:27:50,339 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-05-24 09:27:50,339 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: AM4xS_-_Extension_Kit_plate_7.3mf, subtask: AM4xS_-_Extension_Kit_plate_7
+2026-05-24 09:27:50,358 INFO [backend.app.api.routes.support] [d0f34b83] Generated support bundle: bambuddy-support-20260524-092749.zip
+2026-05-24 09:27:52,079 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:27:56,110 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:27:58,130 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:27:58,144 DEBUG [backend.app.main] [-] [[PRINTER]] Runtime tracking: added 30s, total=495044s (137.51h)
+2026-05-24 09:27:58,154 DEBUG [backend.app.main] [-] Updated runtime for 1 printer(s)
+2026-05-24 09:28:02,177 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:28:02,178 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -49dBm
+2026-05-24 09:28:04,198 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:28:04,199 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -48dBm
+2026-05-24 09:28:06,219 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:28:10,252 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:28:10,252 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] mc_print_sub_stage changed: 2 -> 1
+2026-05-24 09:28:10,252 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -46dBm
+2026-05-24 09:28:12,279 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:28:12,562 INFO [backend.app.api.routes.support] [e4ce8328] Log level changed to INFO
+2026-05-24 09:28:12,563 INFO [uvicorn.access] [-] [IP]:58750 - "POST /api/v1/support/debug-logging HTTP/1.1" 200
+2026-05-24 09:28:36,577 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=3 at layer=0
+2026-05-24 09:29:23,150 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 09:29:39,510 INFO [backend.app.api.routes.support] [4129ddeb] Log level changed to DEBUG
+2026-05-24 09:29:39,511 INFO [backend.app.api.routes.bug_report] [4129ddeb] Bug report: enabled debug logging
+2026-05-24 09:29:39,511 DEBUG [backend.app.services.bambu_mqtt] [4129ddeb] [[SERIAL]] Requesting status update (pushall)
+2026-05-24 09:29:39,511 INFO [uvicorn.access] [-] [IP]:56998 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-05-24 09:29:40,259 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: AM4xS_-_Extension_Kit_plate_7.3mf, subtask_name: AM4xS_-_Extension_Kit_plate_7
+2026-05-24 09:29:40,260 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': '3', 'tray_now': '3', 'tray_pre': '3', 'tray_read_done_bits': 'f', 'tray_reading_bits': '0', 'version': 26, 'insert_flag': True, 'power_on_flag': False}
+2026-05-24 09:29:40,260 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 3
+2026-05-24 09:29:40,260 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-05-24 09:29:40,260 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-05-24 09:29:40,261 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 09:29:40,261 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 5.0
+2026-05-24 09:29:40,261 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 5.0°C (heater OFF)
+2026-05-24 09:29:40,261 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=5.0, heating=False, respect_local=False
+2026-05-24 09:29:40,261 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 5.0, target: 0.0, heating: False
+2026-05-24 09:29:40,261 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-05-24 09:29:40,262 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'ipcam_dev': '1', 'ipcam_record': 'enable', 'timelapse': 'disable', 'resolution': '', 'tutk_server': 'disable', 'mode_bits': 3}
+2026-05-24 09:29:40,262 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -47dBm
+2026-05-24 09:29:40,262 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-05-24 09:29:40,262 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: AM4xS_-_Extension_Kit_plate_7.3mf, subtask: AM4xS_-_Extension_Kit_plate_7
+2026-05-24 09:29:41,281 INFO [backend.app.api.routes.support] [b2d59627] Log level changed to INFO
+2026-05-24 09:29:41,281 INFO [backend.app.api.routes.bug_report] [b2d59627] Bug report: restored normal logging
+2026-05-24 09:29:41,281 INFO [uvicorn.access] [-] [IP]:56998 - "POST /api/v1/bug-report/stop-logging?was_debug=false HTTP/1.1" 200
+2026-05-24 09:29:42,181 WARNING [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT disconnected: rc=Normal disconnection, flags=DisconnectFlags(is_disconnect_packet_from_server=False)
+2026-05-24 09:29:45,710 INFO [uvicorn.access] [-] [IP]:56998 - "POST /api/v1/bug-report/submit HTTP/1.1" 200
+2026-05-24 09:34:23,159 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 09:38:54,477 INFO [backend.app.services.bambu_mqtt] [36078632] [[SERIAL]] Requesting K-profiles for nozzle_diameter=0.4 (attempt 1/3)
+2026-05-24 09:38:54,496 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] K-profile response: nozzle=0.4, seq_id=6, 0 profiles, expected=0.4
+2026-05-24 09:38:54,496 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 0 K-profiles for nozzle=0.4
+2026-05-24 09:38:54,497 INFO [backend.app.services.bambu_mqtt] [36078632] [[SERIAL]] Got 0 K-profiles for nozzle=0.4 on attempt 1
+2026-05-24 09:39:23,181 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 09:44:23,202 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 09:49:11,399 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-05-24 09:49:11,399 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: P1S)
+2026-05-24 09:49:12,641 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 72372 bytes
+2026-05-24 09:49:23,210 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 09:54:23,229 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 09:59:23,240 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:04:23,250 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:09:23,257 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:14:23,262 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:17:08,428 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=254 at layer=9
+2026-05-24 10:17:08,431 INFO [backend.app.main] [-] [Printer 1] Broadcasting AMS change via WebSocket
+2026-05-24 10:17:08,447 INFO [backend.app.main] [-] Auto-unlink: spool 7 AMS0-T3 — fingerprint mismatch (cur=/ fp=161616FF/PLA spool=000000FF/PLA)
+2026-05-24 10:17:08,448 INFO [backend.app.main] [-] Auto-unlinked 1 stale spool assignments for printer 1
+2026-05-24 10:19:23,269 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:22:46,601 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-05-24 10:22:46,602 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: P1S)
+2026-05-24 10:22:48,982 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 79703 bytes
+2026-05-24 10:24:23,274 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:25:12,398 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-05-24 10:25:12,398 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: P1S)
+2026-05-24 10:25:14,767 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 96454 bytes
+2026-05-24 10:25:34,641 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-05-24 10:25:34,642 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: P1S)
+2026-05-24 10:25:36,602 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 96484 bytes
+2026-05-24 10:25:36,671 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-05-24 10:25:36,672 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using chamber image protocol (model: P1S)
+2026-05-24 10:25:36,845 INFO [backend.app.services.notification_service] [-] Sent notification via Notifications va ntfy
+2026-05-24 10:25:36,846 INFO [backend.app.main] [-] [HMS] Sent notification for 1 error(s) on printer 1
+2026-05-24 10:25:38,491 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 96324 bytes
+2026-05-24 10:29:23,280 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:34:23,285 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:39:23,292 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:44:23,297 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:49:23,302 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:54:23,309 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 10:59:23,330 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 11:04:23,335 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 11:09:23,355 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 11:14:05,762 INFO [backend.app.main] [-] [Printer 1] Broadcasting AMS change via WebSocket
+2026-05-24 11:14:05,769 INFO [backend.app.main] [-] Auto-unlink: spool 8 AMS0-T0 — fingerprint mismatch (cur=/ fp=000000FF/PLA spool=000000FF/PLA)
+2026-05-24 11:14:05,769 INFO [backend.app.main] [-] Auto-unlinked 1 stale spool assignments for printer 1
+2026-05-24 11:14:23,363 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 11:15:38,195 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=3 at layer=13
+2026-05-24 11:16:33,445 INFO [uvicorn.access] [-] [IP]:56612 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-05-24 11:16:58,833 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-05-24 11:16:59,299 INFO [uvicorn.access] [-] [IP]:45740 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-05-24 11:16:59,341 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting...
+2026-05-24 11:16:59,342 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-05-24 11:16:59,343 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-05-24 11:17:05,134 INFO [backend.app.api.routes.cloud] [45e4e555] get_filament_info called with 2 IDs: ['GFL96', 'GFL99']
+2026-05-24 11:17:05,145 INFO [uvicorn.access] [-] [IP]:45806 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-05-24 11:17:05,175 INFO [backend.app.api.routes.printers] [d2ed799a] Cover: resolved plate 7 before download (subtask=AM4xS_-_Extension_Kit_plate_7)
+2026-05-24 11:17:15,166 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-05-24 11:17:15,408 INFO [uvicorn.access] [-] [IP]:36240 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-05-24 11:17:15,439 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting...
+2026-05-24 11:17:15,440 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-05-24 11:17:15,441 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-05-24 11:17:15,638 INFO [backend.app.api.routes.cloud] [3a3a2964] get_filament_info called with 2 IDs: ['GFL96', 'GFL99']
+2026-05-24 11:17:15,639 INFO [uvicorn.access] [-] [IP]:36240 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-05-24 11:17:15,686 INFO [backend.app.api.routes.printers] [7e6d2e5b] Cover: resolved plate 7 before download (subtask=AM4xS_-_Extension_Kit_plate_7)
+2026-05-24 11:17:47,950 INFO [backend.app.api.routes.inventory] [f7114108] Pre-configured assignment: spool 8 → printer 1 AMS0-T3 (slot empty, will configure on insert)
+2026-05-24 11:17:47,951 INFO [uvicorn.access] [-] [IP]:56092 - "POST /api/v1/inventory/assignments HTTP/1.1" 200
+2026-05-24 11:17:47,967 INFO [uvicorn.access] [-] [IP]:58114 - "POST /api/v1/printers/1/refresh-status HTTP/1.1" 200
+2026-05-24 11:17:52,012 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-05-24 11:17:52,275 INFO [uvicorn.access] [-] [IP]:56100 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-05-24 11:17:52,298 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting...
+2026-05-24 11:17:52,298 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-05-24 11:17:52,299 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-05-24 11:17:52,435 INFO [backend.app.api.routes.cloud] [f10cefc6] get_filament_info called with 2 IDs: ['GFL96', 'GFL99']
+2026-05-24 11:17:52,437 INFO [uvicorn.access] [-] [IP]:51286 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-05-24 11:17:52,458 INFO [backend.app.api.routes.printers] [805dde42] Cover: resolved plate 7 before download (subtask=AM4xS_-_Extension_Kit_plate_7)
+2026-05-24 11:17:56,761 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-05-24 11:17:57,010 INFO [uvicorn.access] [-] [IP]:58114 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-05-24 11:17:57,037 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting...
+2026-05-24 11:17:57,037 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-05-24 11:17:57,039 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-05-24 11:17:57,190 INFO [backend.app.api.routes.cloud] [7381b928] get_filament_info called with 2 IDs: ['GFL96', 'GFL99']
+2026-05-24 11:17:57,192 INFO [uvicorn.access] [-] [IP]:51272 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-05-24 11:17:57,231 INFO [backend.app.api.routes.printers] [36fa7295] Cover: resolved plate 7 before download (subtask=AM4xS_-_Extension_Kit_plate_7)
+2026-05-24 11:19:19,053 INFO [backend.app.api.routes.support] [b56dd12f] Log level changed to DEBUG
+2026-05-24 11:19:19,053 INFO [backend.app.api.routes.bug_report] [b56dd12f] Bug report: enabled debug logging
+2026-05-24 11:19:19,053 DEBUG [backend.app.services.bambu_mqtt] [b56dd12f] [[SERIAL]] Requesting status update (pushall)
+2026-05-24 11:19:19,054 INFO [uvicorn.access] [-] [IP]:35228 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-05-24 11:19:19,097 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: AM4xS_-_Extension_Kit_plate_7.3mf, subtask_name: AM4xS_-_Extension_Kit_plate_7
+2026-05-24 11:19:19,098 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '1', 'tray_exist_bits': 'e', 'tray_is_bbl_bits': 'e', 'tray_tar': '3', 'tray_now': '3', 'tray_pre': '3', 'tray_read_done_bits': '6', 'tray_reading_bits': '0', 'version': 32, 'insert_flag': True, 'power_on_flag': False}
+2026-05-24 11:19:19,098 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 3
+2026-05-24 11:19:19,099 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-05-24 11:19:19,099 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-05-24 11:19:19,099 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 11:19:19,099 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 5.0
+2026-05-24 11:19:19,100 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 5.0°C (heater OFF)
+2026-05-24 11:19:19,100 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=5.0, heating=False, respect_local=False
+2026-05-24 11:19:19,100 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 5.0, target: 0.0, heating: False
+2026-05-24 11:19:19,101 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-05-24 11:19:19,101 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'ipcam_dev': '1', 'ipcam_record': 'enable', 'timelapse': 'disable', 'resolution': '', 'tutk_server': 'disable', 'mode_bits': 3}
+2026-05-24 11:19:19,101 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -53dBm
+2026-05-24 11:19:19,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-05-24 11:19:19,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: AM4xS_-_Extension_Kit_plate_7.3mf, subtask: AM4xS_-_Extension_Kit_plate_7
+2026-05-24 11:19:21,111 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 11:19:21,112 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -50dBm
+2026-05-24 11:19:22,807 DEBUG [backend.app.services.virtual_printer.ssdp_server] [-] Sent SSDP NOTIFY for Bambuddy (Location=[IP], USN=[SERIAL], bind=[IP])
+2026-05-24 11:19:23,132 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-05-24 11:19:23,133 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -52dBm
+2026-05-24 11:19:23,370 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-05-24 11:19:25,150 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status