Bug report logs e6edca172921481f8256ddb739092ddd.log

logs/e6edca172921481f8256ddb739092ddd.log

@@ -0,0 +1,200 @@
+2026-09-03 23:25:20,683 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:25:31,291 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 107771 bytes
+2026-09-03 23:25:35,829 INFO [uvicorn.access] [-] [IP]:44676 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:25:36,994 INFO [backend.app.api.routes.camera] [1b559a5a] Using RTSP protocol for X2D
+2026-09-03 23:25:36,995 INFO [backend.app.api.routes.camera] [1b559a5a] Camera viewer attached to printer-1 (subscribers=3)
+2026-09-03 23:25:39,721 INFO [backend.app.api.routes.camera] [1b559a5a] Camera viewer detached from printer-1 (subscribers=2)
+2026-09-03 23:25:48,200 INFO [uvicorn.access] [-] [IP]:45396 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:25:49,765 INFO [uvicorn.access] [-] [IP]:45416 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:25:50,841 INFO [backend.app.services.print_scheduler] [-] Queue check: found 7 pending items: [(13, 1, None, 10), (14, 1, None, 10), (15, 1, None, 10), (6, 1, None, 5), (5, 1, None, 7), (7, 1, None, 2), (8, 1, None, 8)]
+2026-09-03 23:25:50,851 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 4}
+2026-09-03 23:25:50,852 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:25:58,721 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 110067 bytes
+2026-09-03 23:25:59,866 INFO [uvicorn.access] [-] [IP]:45502 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:26:14,216 INFO [backend.app.api.routes.camera] [04741c27] Using RTSP protocol for X2D
+2026-09-03 23:26:14,217 INFO [backend.app.api.routes.camera] [04741c27] Camera viewer attached to printer-1 (subscribers=3)
+2026-09-03 23:26:21,008 INFO [backend.app.services.print_scheduler] [-] Queue check: found 7 pending items: [(13, 1, None, 10), (14, 1, None, 10), (15, 1, None, 10), (6, 1, None, 5), (5, 1, None, 7), (7, 1, None, 2), (8, 1, None, 8)]
+2026-09-03 23:26:21,020 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 4}
+2026-09-03 23:26:21,021 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:26:24,988 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 112077 bytes
+2026-09-03 23:26:51,202 INFO [backend.app.services.print_scheduler] [-] Queue check: found 7 pending items: [(13, 1, None, 10), (14, 1, None, 10), (15, 1, None, 10), (6, 1, None, 5), (5, 1, None, 7), (7, 1, None, 2), (8, 1, None, 8)]
+2026-09-03 23:26:51,227 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 4}
+2026-09-03 23:26:51,227 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:26:52,168 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 114267 bytes
+2026-09-03 23:26:52,172 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 114267 bytes
+2026-09-03 23:26:55,655 INFO [backend.app.api.routes.camera] [04741c27] Camera viewer detached from printer-1 (subscribers=2)
+2026-09-03 23:27:01,685 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 115229 bytes
+2026-09-03 23:27:04,733 INFO [backend.app.api.routes.printers] [29b2265e] Cover: resolved plate 5 before download (subtask=E_TRON_GT_RS_3MF)
+2026-09-03 23:27:04,853 INFO [backend.app.api.routes.camera] [29de4464] Using RTSP protocol for X2D
+2026-09-03 23:27:04,854 INFO [backend.app.api.routes.camera] [29de4464] Camera viewer attached to printer-1 (subscribers=3)
+2026-09-03 23:27:20,954 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 116153 bytes
+2026-09-03 23:27:21,422 INFO [backend.app.services.print_scheduler] [-] Queue check: found 7 pending items: [(13, 1, None, 10), (14, 1, None, 10), (15, 1, None, 10), (6, 1, None, 5), (5, 1, None, 7), (7, 1, None, 2), (8, 1, None, 8)]
+2026-09-03 23:27:21,444 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 4}
+2026-09-03 23:27:21,445 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:27:46,801 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 116168 bytes
+2026-09-03 23:27:51,324 INFO [backend.app.api.routes.camera] [6854299b] Camera viewer detached from printer-1 (subscribers=2)
+2026-09-03 23:27:51,943 INFO [backend.app.api.routes.camera] [9b30e4fb] Skipping force-shutdown for printer 1: 2 subscriber(s) still attached; natural cleanup will tear down when last viewer disconnects
+2026-09-03 23:27:52,007 INFO [uvicorn.access] [-] [IP]:43498 - "POST /api/v1/printers/1/camera/stop HTTP/1.1" 200
+2026-09-03 23:27:53,112 INFO [backend.app.services.print_scheduler] [-] Queue check: found 7 pending items: [(13, 1, None, 10), (14, 1, None, 10), (15, 1, None, 10), (6, 1, None, 5), (5, 1, None, 7), (7, 1, None, 2), (8, 1, None, 8)]
+2026-09-03 23:27:53,204 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 4}
+2026-09-03 23:27:53,208 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:27:56,990 INFO [uvicorn.access] [-] [IP]:58070 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:28:00,826 INFO [uvicorn.access] [-] [IP]:57920 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:28:10,348 INFO [uvicorn.access] [-] [IP]:51988 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:28:13,670 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 117977 bytes
+2026-09-03 23:28:19,051 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-09-03 23:28:23,494 INFO [backend.app.services.print_scheduler] [-] Queue check: found 7 pending items: [(13, 1, None, 10), (14, 1, None, 10), (15, 1, None, 10), (6, 1, None, 5), (5, 1, None, 7), (7, 1, None, 2), (8, 1, None, 8)]
+2026-09-03 23:28:23,530 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 4}
+2026-09-03 23:28:23,531 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:28:34,611 INFO [uvicorn.access] [-] [IP]:58070 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:28:38,905 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 121229 bytes
+2026-09-03 23:28:53,693 INFO [backend.app.services.print_scheduler] [-] Queue check: found 7 pending items: [(13, 1, None, 10), (14, 1, None, 10), (15, 1, None, 10), (6, 1, None, 5), (5, 1, None, 7), (7, 1, None, 2), (8, 1, None, 8)]
+2026-09-03 23:28:53,706 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 4}
+2026-09-03 23:28:53,707 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:29:05,076 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 116386 bytes
+2026-09-03 23:29:05,082 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 116386 bytes
+2026-09-03 23:29:23,847 INFO [backend.app.services.print_scheduler] [-] Queue check: found 7 pending items: [(13, 1, None, 10), (14, 1, None, 10), (15, 1, None, 10), (6, 1, None, 5), (5, 1, None, 7), (7, 1, None, 2), (8, 1, None, 8)]
+2026-09-03 23:29:23,875 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 4}
+2026-09-03 23:29:23,876 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-09-03 23:29:24,882 INFO [uvicorn.access] [-] [IP]:36496 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:29:30,836 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 122046 bytes
+2026-09-03 23:29:35,839 INFO [backend.app.api.routes.support] [b634bb48] Log level changed to DEBUG
+2026-09-03 23:29:35,840 INFO [backend.app.api.routes.bug_report] [b634bb48] Bug report: enabled debug logging
+2026-09-03 23:29:35,842 DEBUG [backend.app.services.bambu_mqtt] [b634bb48] [[SERIAL]] Requesting status update (pushall)
+2026-09-03 23:29:35,852 INFO [uvicorn.access] [-] [IP]:40500 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-09-03 23:29:36,550 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:36,556 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:36,557 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:36,569 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:36,572 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '1', 'ams_exist_bits_raw': '1', 'cali_id': 255, 'cali_stat': 0, 'cfs': [2, 5, 7], 'insert_flag': True, 'power_on_flag': False, 'tray_exist_bits': 'f', 'tray_hall_out_bits': '8', 'tray_is_bbl_bits': 'f', 'tray_now': '3', 'tray_pre': '3', 'tray_read_done_bits': 'f', 'tray_reading_bits': '0', 'tray_tar': '3', 'unbind_ams_stat': 0, 'version': 5031}
+2026-09-03 23:29:36,574 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 3
+2026-09-03 23:29:36,576 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-09-03 23:29:36,576 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x11001103 -> extruder 1
+2026-09-03 23:29:36,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:36,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-09-03 23:29:36,578 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:36,579 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] device.extruder.state=33042 (switch_state bits 12-14: 0)
+2026-09-03 23:29:36,583 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 32°C (heater OFF)
+2026-09-03 23:29:36,584 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ctc_info keys: ['temp']
+2026-09-03 23:29:36,588 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=32.0, heating=False, respect_local=False
+2026-09-03 23:29:36,589 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 32.0, target: 0.0, heating: False
+2026-09-03 23:29:36,590 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:36,591 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'agora_service': 'disable', 'brtc_service': 'enable', 'bs_state': 0, 'cap_pic_enable': 'invalid', 'ipcam_dev': '1', 'ipcam_record': 'enable', 'laser_preview_res': 7, 'liveview_preview': True, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_external_free_kb': 104050048, 'tl_external_total_kb': 121353664, 'tl_internal_free_kb': 918193, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-09-03 23:29:36,592 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -40dBm
+2026-09-03 23:29:36,593 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:36,595 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:36,597 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:37,641 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:37,645 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:37,647 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:37,664 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:37,667 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:37,668 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:37,669 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:37,672 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:37,673 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:37,674 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:38,549 INFO [uvicorn.access] [-] [IP]:35564 - "POST /api/v1/auth/ws-token HTTP/1.1" 401
+2026-09-03 23:29:38,906 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:38,911 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:38,912 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:38,920 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:38,930 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:38,931 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:38,938 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:38,943 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'agora_service': 'disable', 'brtc_service': 'enable', 'bs_state': 0, 'cap_pic_enable': 'invalid', 'ipcam_dev': '1', 'ipcam_record': 'enable', 'laser_preview_res': 7, 'liveview_preview': True, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_external_free_kb': 104049024, 'tl_external_total_kb': 121353664, 'tl_internal_free_kb': 918193, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-09-03 23:29:38,944 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:38,945 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:38,945 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:39,948 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:39,949 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:39,950 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:39,950 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:39,951 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:39,952 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:39,953 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:39,954 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'agora_service': 'disable', 'brtc_service': 'enable', 'bs_state': 0, 'cap_pic_enable': 'invalid', 'ipcam_dev': '1', 'ipcam_record': 'enable', 'laser_preview_res': 7, 'liveview_preview': True, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_external_free_kb': 104048000, 'tl_external_total_kb': 121353664, 'tl_internal_free_kb': 918193, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-09-03 23:29:39,955 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:39,956 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:39,956 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:41,265 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:41,265 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:41,266 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:41,266 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:41,267 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '1', 'ams_exist_bits_raw': '1', 'cali_id': 255, 'cali_stat': 0, 'cfs': [2, 5, 7], 'insert_flag': True, 'power_on_flag': False, 'tray_exist_bits': 'f', 'tray_hall_out_bits': '8', 'tray_is_bbl_bits': 'f', 'tray_now': '3', 'tray_pre': '3', 'tray_read_done_bits': 'f', 'tray_reading_bits': '0', 'tray_tar': '3', 'unbind_ams_stat': 0, 'version': 5032}
+2026-09-03 23:29:41,269 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:41,270 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:41,270 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:41,271 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:41,272 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:41,272 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:42,421 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:42,423 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:42,424 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:42,425 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:42,426 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:42,427 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:42,430 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:42,430 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'agora_service': 'disable', 'brtc_service': 'enable', 'bs_state': 0, 'cap_pic_enable': 'invalid', 'ipcam_dev': '1', 'ipcam_record': 'enable', 'laser_preview_res': 7, 'liveview_preview': True, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_external_free_kb': 104046976, 'tl_external_total_kb': 121353664, 'tl_internal_free_kb': 918193, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-09-03 23:29:42,432 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:42,432 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:42,434 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:43,768 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:43,774 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:43,776 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:43,778 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:43,780 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:43,781 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:43,783 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:43,784 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:43,788 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:43,791 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:44,782 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:44,783 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:44,786 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:44,786 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:44,790 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:44,790 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:44,796 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:44,796 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'agora_service': 'disable', 'brtc_service': 'enable', 'bs_state': 0, 'cap_pic_enable': 'invalid', 'ipcam_dev': '1', 'ipcam_record': 'enable', 'laser_preview_res': 7, 'liveview_preview': True, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_external_free_kb': 104045952, 'tl_external_total_kb': 121353664, 'tl_internal_free_kb': 918193, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-09-03 23:29:44,797 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:44,797 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:44,798 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:45,514 INFO [backend.app.api.routes.camera] [86895a4c] Using RTSP protocol for X2D
+2026-09-03 23:29:45,515 INFO [backend.app.api.routes.camera] [86895a4c] Camera viewer attached to printer-1 (subscribers=3)
+2026-09-03 23:29:45,972 INFO [backend.app.api.routes.camera] [b7313641] Using RTSP protocol for X2D
+2026-09-03 23:29:45,973 INFO [backend.app.api.routes.camera] [b7313641] Camera viewer attached to printer-1 (subscribers=4)
+2026-09-03 23:29:46,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:46,116 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:46,117 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:46,117 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:46,117 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:46,118 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:46,121 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:46,121 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'agora_service': 'disable', 'brtc_service': 'enable', 'bs_state': 0, 'cap_pic_enable': 'invalid', 'ipcam_dev': '1', 'ipcam_record': 'enable', 'laser_preview_res': 7, 'liveview_preview': True, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_external_free_kb': 104045184, 'tl_external_total_kb': 121353664, 'tl_internal_free_kb': 918193, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-09-03 23:29:46,122 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:46,126 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:46,128 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:46,956 INFO [backend.app.api.routes.camera] [b7313641] Camera viewer detached from printer-1 (subscribers=3)
+2026-09-03 23:29:47,219 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:47,219 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:47,220 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:47,220 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:47,232 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '1', 'ams_exist_bits_raw': '1', 'cali_id': 255, 'cali_stat': 0, 'cfs': [2, 5, 7], 'insert_flag': True, 'power_on_flag': False, 'tray_exist_bits': 'f', 'tray_hall_out_bits': '8', 'tray_is_bbl_bits': 'f', 'tray_now': '3', 'tray_pre': '3', 'tray_read_done_bits': 'f', 'tray_reading_bits': '0', 'tray_tar': '3', 'unbind_ams_stat': 0, 'version': 5033}
+2026-09-03 23:29:47,254 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:47,255 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:47,259 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:47,263 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:47,266 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:47,268 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF
+2026-09-03 23:29:48,532 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 8089015, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 23:29:48,533 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['allow_skip_parts', 'buildplate_marker_detector', 'cfg', 'first_layer_inspector', 'halt_print_sensitivity', 'print_halt', 'printing_monitor', 'spaghetti_detector']
+2026-09-03 23:29:48,534 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-09-03 23:29:48,535 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_5.gcode, subtask_name: E_TRON_GT_RS_3MF
+2026-09-03 23:29:48,536 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-09-03 23:29:48,537 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 23:29:48,539 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 23:29:48,550 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'agora_service': 'disable', 'brtc_service': 'enable', 'bs_state': 0, 'cap_pic_enable': 'invalid', 'ipcam_dev': '1', 'ipcam_record': 'enable', 'laser_preview_res': 7, 'liveview_preview': True, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_external_free_kb': 104044160, 'tl_external_total_kb': 121353664, 'tl_internal_free_kb': 918193, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-09-03 23:29:48,551 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-09-03 23:29:48,553 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [65535, 65535, 65535, 3]
+2026-09-03 23:29:48,556 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_5.gcode, subtask: E_TRON_GT_RS_3MF