Bug report logs 92bf607a0a074152adf5522850d23c10.log

logs/92bf607a0a074152adf5522850d23c10.log

@@ -0,0 +1,200 @@
+2026-10-22 21:43:35,599 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 98417 bytes
+2026-10-22 21:43:36,167 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=2 at layer=198
+2026-10-22 21:43:41,193 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:43:41,201 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:43:41,201 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:43:45,205 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 2
+2026-10-22 21:43:45,208 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1E)
+2026-10-22 21:43:49,389 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 173701 bytes
+2026-10-22 21:43:49,409 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 173701 bytes
+2026-10-22 21:44:01,971 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:44:01,973 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:44:02,229 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=1 at layer=199
+2026-10-22 21:44:04,432 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 96621 bytes
+2026-10-22 21:44:04,432 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 96621 bytes
+2026-10-22 21:44:11,237 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:44:11,243 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:44:11,243 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:44:34,565 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:44:34,566 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:44:41,290 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:44:41,297 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:44:41,297 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:44:41,326 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 2
+2026-10-22 21:44:41,327 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1E)
+2026-10-22 21:44:43,503 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 94324 bytes
+2026-10-22 21:44:43,503 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 94324 bytes
+2026-10-22 21:44:44,578 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 190082 bytes
+2026-10-22 21:44:44,578 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 190082 bytes
+2026-10-22 21:45:08,995 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:45:08,998 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:45:11,330 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:45:11,338 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:45:11,338 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:45:12,573 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 93393 bytes
+2026-10-22 21:45:12,574 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 93393 bytes
+2026-10-22 21:45:25,424 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=2 at layer=200
+2026-10-22 21:45:34,450 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 2
+2026-10-22 21:45:34,451 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1E)
+2026-10-22 21:45:37,680 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 163151 bytes
+2026-10-22 21:45:37,680 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 163151 bytes
+2026-10-22 21:45:41,398 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:45:41,412 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:45:41,412 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:45:48,475 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=1 at layer=201
+2026-10-22 21:45:50,197 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:45:50,198 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:45:58,664 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 89571 bytes
+2026-10-22 21:45:58,664 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 89571 bytes
+2026-10-22 21:46:01,823 INFO [backend.app.main] [-] Evicted 1 stale expected-print entries (TTL=7200s)
+2026-10-22 21:46:11,455 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:46:11,462 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:46:11,463 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:46:23,890 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:46:23,891 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:46:27,396 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 84283 bytes
+2026-10-22 21:46:27,397 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 84283 bytes
+2026-10-22 21:46:36,602 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 2
+2026-10-22 21:46:36,603 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1E)
+2026-10-22 21:46:40,111 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 192876 bytes
+2026-10-22 21:46:40,111 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 192876 bytes
+2026-10-22 21:46:41,493 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:46:41,499 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:46:41,500 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:47:01,074 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-10-22 21:47:09,860 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:47:09,862 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:47:11,527 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:47:11,532 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:47:11,533 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:47:24,699 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 87766 bytes
+2026-10-22 21:47:24,700 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 87766 bytes
+2026-10-22 21:47:29,718 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=2 at layer=202
+2026-10-22 21:47:37,102 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 2
+2026-10-22 21:47:37,110 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1E)
+2026-10-22 21:47:41,379 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 174458 bytes
+2026-10-22 21:47:41,379 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 174458 bytes
+2026-10-22 21:47:41,560 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:47:41,567 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:47:41,567 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:47:48,370 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:47:48,371 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:47:50,773 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=1 at layer=203
+2026-10-22 21:47:53,548 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 83864 bytes
+2026-10-22 21:47:53,548 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 83864 bytes
+2026-10-22 21:48:11,604 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:48:11,614 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:48:11,614 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:48:28,856 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:48:28,857 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:48:32,313 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 82834 bytes
+2026-10-22 21:48:32,313 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 82834 bytes
+2026-10-22 21:48:35,905 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 2
+2026-10-22 21:48:35,906 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1E)
+2026-10-22 21:48:39,294 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 186782 bytes
+2026-10-22 21:48:39,295 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 186782 bytes
+2026-10-22 21:48:41,665 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:48:41,679 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:48:41,679 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:48:44,220 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-10-22 21:48:44,222 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1C)
+2026-10-22 21:48:52,486 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 88986 bytes
+2026-10-22 21:48:52,486 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 88986 bytes
+2026-10-22 21:48:57,110 INFO [backend.app.api.routes.printers] [46d772ed] Cover: resolved plate 1 before download (subtask=u1_caste_back_front)
+2026-10-22 21:48:57,133 INFO [backend.app.api.routes.printers] [98f8ee25] Cover: resolved plate 2 before download (subtask=castle_-_window_-_2026-10-22_18-36-58_plate_2)
+2026-10-22 21:48:57,134 INFO [backend.app.api.routes.printers] [598f0300] Cover: resolved plate 1 before download (subtask=u1_caste_back_front)
+2026-10-22 21:48:57,561 INFO [backend.app.api.routes.printers] [701a617a] Cover: resolved plate 2 before download (subtask=castle_-_window_-_2026-10-22_18-36-58_plate_2)
+2026-10-22 21:48:57,585 INFO [backend.app.api.routes.cloud] [c4bc50f9] get_filament_info called with 1 IDs: ['GFA07']
+2026-10-22 21:48:57,587 INFO [backend.app.api.routes.cloud] [e8857f82] get_filament_info called with 5 IDs: ['GFA01', 'GFG00', 'GFA07', 'GFA00', 'GFU99']
+2026-10-22 21:48:57,660 INFO [uvicorn.access] [-] [IP]:43048 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-10-22 21:48:57,669 INFO [uvicorn.access] [-] [IP]:43044 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-10-22 21:49:11,732 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:49:11,744 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:49:11,744 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:49:23,027 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=2 at layer=204
+2026-10-22 21:49:30,053 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 2
+2026-10-22 21:49:30,055 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: X1E)
+2026-10-22 21:49:33,508 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 131585 bytes
+2026-10-22 21:49:33,508 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 131585 bytes
+2026-10-22 21:49:41,789 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(297, 1, 199, None)]
+2026-10-22 21:49:41,800 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:49:41,800 INFO [backend.app.services.print_scheduler] [-] Queue: printer 2 unavailable — an item is already printing on it (now: connected=True, state=RUNNING, awaiting_plate_clear=False)
+2026-10-22 21:49:44,081 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=1 at layer=205
+2026-10-22 21:49:55,957 INFO [backend.app.api.routes.support] [3dd3e107] Log level changed to DEBUG
+2026-10-22 21:49:55,957 INFO [backend.app.api.routes.bug_report] [3dd3e107] Bug report: enabled debug logging
+2026-10-22 21:49:55,957 DEBUG [backend.app.services.bambu_mqtt] [3dd3e107] [[SERIAL]] Requesting status update (pushall)
+2026-10-22 21:49:55,958 DEBUG [backend.app.services.bambu_mqtt] [3dd3e107] [[SERIAL]] Requesting status update (pushall)
+2026-10-22 21:49:55,959 INFO [uvicorn.access] [-] [IP]:43646 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-10-22 21:49:55,962 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': False, 'first_layer_inspector': False, 'halt_print_sensitivity': 'medium', 'print_halt': False, 'printing_monitor': False, 'spaghetti_detector': False}
+2026-10-22 21:49:55,963 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-10-22 21:49:55,963 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: u1_caste_back_front.gcode, subtask_name: u1_caste_back_front
+2026-10-22 21:49:55,963 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '1', 'tray_exist_bits': 'f', 'ams_status': 0, 'power_on_flag': False, 'tray_now': 1, 'tray_tar': 1, 'tray_pre': 255, 'ams_calibrate_flag': 0, 'version': 0}
+2026-10-22 21:49:55,963 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 0 (main=0, sub=0)
+2026-10-22 21:49:55,963 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 1
+2026-10-22 21:49:55,964 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-10-22 21:49:55,964 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x00000000 -> extruder 0
+2026-10-22 21:49:55,964 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-10-22 21:49:55,964 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 0 (main=0, sub=0)
+2026-10-22 21:49:55,964 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-10-22 21:49:55,964 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 36.0
+2026-10-22 21:49:55,964 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 36.0°C (heater OFF)
+2026-10-22 21:49:55,965 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=36.0, heating=False, respect_local=False
+2026-10-22 21:49:55,965 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 36.0, target: 0.0, heating: False
+2026-10-22 21:49:55,965 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'ipcam_dev': '1', 'ipcam_record': 'disable', 'timelapse': 'disable', 'resolution': '1080p', 'mode_bits': 0}
+2026-10-22 21:49:55,965 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -52dBm
+2026-10-22 21:49:55,966 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-10-22 21:49:55,966 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: u1_caste_back_front.gcode, subtask: u1_caste_back_front
+2026-10-22 21:49:56,062 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': False, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-10-22 21:49:56,063 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-10-22 21:49:56,063 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_2.gcode, subtask_name: castle_-_window_-_2026-10-22_18-36-58_plate_2
+2026-10-22 21:49:56,063 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '3', 'ams_exist_bits_raw': '3', 'cali_id': 0, 'cali_stat': 0, 'insert_flag': True, 'power_on_flag': True, 'tray_exist_bits': 'ff', 'tray_is_bbl_bits': 'fe', 'tray_now': '1', 'tray_pre': '1', 'tray_read_done_bits': 'ff', 'tray_reading_bits': '0', 'tray_tar': '1', 'unbind_ams_stat': 0, 'version': 10065}
+2026-10-22 21:49:56,063 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 1
+2026-10-22 21:49:56,063 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 2 new units, 2 total
+2026-10-22 21:49:56,064 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x1001 -> extruder 0
+2026-10-22 21:49:56,064 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 1 info=0x1001 -> extruder 0
+2026-10-22 21:49:56,064 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '1': 0}
+2026-10-22 21:49:56,064 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-10-22 21:49:56,064 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-10-22 21:49:56,064 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] device.extruder.state=1 (switch_state bits 12-14: 0)
+2026-10-22 21:49:56,065 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 34°C (heater OFF)
+2026-10-22 21:49:56,065 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ctc_info keys: ['temp']
+2026-10-22 21:49:56,065 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=34.0, heating=False, respect_local=False
+2026-10-22 21:49:56,065 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 34.0, target: 0.0, heating: False
+2026-10-22 21:49:56,065 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 201327360, 'code': 196619}]
+2026-10-22 21:49:56,066 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'agora_service': 'disable', 'brtc_service': 'enable', 'bs_state': 0, 'ipcam_dev': '1', 'ipcam_record': 'enable', 'laser_preview_res': 7, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-10-22 21:49:56,066 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -65dBm
+2026-10-22 21:49:56,066 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-10-22 21:49:56,066 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 65535, 65535, 65535, 65535, 1]
+2026-10-22 21:49:56,066 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_2.gcode, subtask: castle_-_window_-_2026-10-22_18-36-58_plate_2
+2026-10-22 21:49:56,110 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': False, 'first_layer_inspector': False, 'halt_print_sensitivity': 'medium', 'print_halt': False, 'printing_monitor': False, 'spaghetti_detector': False}
+2026-10-22 21:49:56,111 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-10-22 21:49:56,111 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: u1_caste_back_front.gcode, subtask_name: u1_caste_back_front
+2026-10-22 21:49:56,111 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-10-22 21:49:56,111 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-10-22 21:49:56,112 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 37.0
+2026-10-22 21:49:56,112 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 37.0°C (heater OFF)
+2026-10-22 21:49:56,112 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=37.0, heating=False, respect_local=False
+2026-10-22 21:49:56,112 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 37.0, target: 0.0, heating: False
+2026-10-22 21:49:56,113 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-10-22 21:49:56,113 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: u1_caste_back_front.gcode, subtask: u1_caste_back_front
+2026-10-22 21:49:56,919 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': False, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-10-22 21:49:56,919 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-10-22 21:49:56,919 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_2.gcode, subtask_name: castle_-_window_-_2026-10-22_18-36-58_plate_2
+2026-10-22 21:49:56,920 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '3', 'ams_exist_bits_raw': '3', 'cali_id': 0, 'cali_stat': 0, 'insert_flag': True, 'power_on_flag': True, 'tray_exist_bits': 'ff', 'tray_is_bbl_bits': 'fe', 'tray_now': '1', 'tray_pre': '1', 'tray_read_done_bits': 'ff', 'tray_reading_bits': '0', 'tray_tar': '1', 'unbind_ams_stat': 0, 'version': 10066}
+2026-10-22 21:49:56,920 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '1': 0}
+2026-10-22 21:49:56,921 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-10-22 21:49:56,922 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 201327360, 'code': 196619}]
+2026-10-22 21:49:56,924 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-10-22 21:49:56,925 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 65535, 65535, 65535, 65535, 1]
+2026-10-22 21:49:56,925 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_2.gcode, subtask: castle_-_window_-_2026-10-22_18-36-58_plate_2
+2026-10-22 21:49:57,112 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': False, 'first_layer_inspector': False, 'halt_print_sensitivity': 'medium', 'print_halt': False, 'printing_monitor': False, 'spaghetti_detector': False}
+2026-10-22 21:49:57,113 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-10-22 21:49:57,114 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: u1_caste_back_front.gcode, subtask_name: u1_caste_back_front
+2026-10-22 21:49:57,114 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-10-22 21:49:57,114 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-10-22 21:49:57,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 36.0
+2026-10-22 21:49:57,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 36.0°C (heater OFF)
+2026-10-22 21:49:57,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=36.0, heating=False, respect_local=False
+2026-10-22 21:49:57,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 36.0, target: 0.0, heating: False
+2026-10-22 21:49:57,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-10-22 21:49:57,116 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: u1_caste_back_front.gcode, subtask: u1_caste_back_front