Bug report logs 9db6c252b9a24c36938eb13ac58ad987.log

logs/9db6c252b9a24c36938eb13ac58ad987.log

@@ -0,0 +1,200 @@
+2026-08-31 05:05:42,236 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-08-31 05:05:42,237 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 2 printers
+2026-08-31 05:05:47,156 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:05:47,163 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:05:50,385 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 203173 bytes
+2026-08-31 05:05:50,386 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 203173 bytes
+2026-08-31 05:06:14,681 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:06:14,683 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:06:18,040 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 202210 bytes
+2026-08-31 05:06:18,040 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 202210 bytes
+2026-08-31 05:06:34,901 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-08-31 05:06:41,936 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:06:41,937 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:06:45,210 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 219616 bytes
+2026-08-31 05:06:45,211 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 219616 bytes
+2026-08-31 05:06:51,693 INFO [backend.app.api.routes.printers] [08fc71f9] Cover: resolved plate 5 before download (subtask=g4_doorbell_pro_mount_right_30_plate_5)
+2026-08-31 05:06:51,693 INFO [backend.app.api.routes.printers] [08fc71f9] Cover using cached 3MF from /app/data/archive/2/20260818_023415_g4_doorbell_pro_mount_right_30_plate_5/g4_doorbell_pro_mount_right_30_plate_5.gcode.3mf (avoided duplicate FTP)
+2026-08-31 05:06:51,693 INFO [backend.app.api.routes.printers] [08fc71f9] Downloaded file size: 4034154 bytes
+2026-08-31 05:06:51,831 INFO [backend.app.api.routes.cloud] [dcbb3756] get_filament_info called with 2 IDs: ['GFG00', 'GFU01']
+2026-08-31 05:06:51,920 INFO [backend.app.api.routes.cloud] [76b561a9] get_filament_info called with 2 IDs: ['GFG00', 'GFA00']
+2026-08-31 05:06:52,379 INFO [uvicorn.access] [-] [IP]:64211 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-08-31 05:06:52,531 INFO [uvicorn.access] [-] [IP]:64170 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-08-31 05:06:52,651 INFO [backend.app.api.routes.printers] [ec2a9cc4] Cover: resolved plate 3 before download (subtask=T03)
+2026-08-31 05:06:52,651 INFO [backend.app.api.routes.printers] [ec2a9cc4] Cover using cached 3MF from /app/data/archive/1/20260505_101748_T03.gcode/d2deab04eaf443d18c50c61fe9a35510.3mf (avoided duplicate FTP)
+2026-08-31 05:06:52,651 INFO [backend.app.api.routes.printers] [ec2a9cc4] Downloaded file size: 370448 bytes
+2026-08-31 05:07:12,213 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:07:12,214 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:07:15,550 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 204906 bytes
+2026-08-31 05:07:15,550 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 204906 bytes
+2026-08-31 05:07:53,683 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:07:53,685 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:07:57,030 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 209408 bytes
+2026-08-31 05:07:57,031 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 209408 bytes
+2026-08-31 05:08:26,862 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:08:26,864 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:08:30,156 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 194663 bytes
+2026-08-31 05:08:30,159 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 194663 bytes
+2026-08-31 05:08:56,636 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-08-31 05:08:57,882 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:08:57,883 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:09:00,087 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:09:01,099 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 249092 bytes
+2026-08-31 05:09:01,100 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 249092 bytes
+2026-08-31 05:09:01,100 INFO [backend.app.services.camera] [-] Reusing in-flight camera capture for [IP]: 249092 bytes (no second connection opened)
+2026-08-31 05:09:01,100 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 249092 bytes
+2026-08-31 05:09:25,229 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:09:25,231 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:09:28,741 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 214646 bytes
+2026-08-31 05:09:28,744 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 214646 bytes
+2026-08-31 05:09:52,496 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:09:52,497 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:09:55,868 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 210574 bytes
+2026-08-31 05:09:55,869 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 210574 bytes
+2026-08-31 05:10:19,950 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:10:19,951 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:10:23,239 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 203775 bytes
+2026-08-31 05:10:23,240 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 203775 bytes
+2026-08-31 05:10:47,021 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:10:47,022 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:10:47,023 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:10:50,430 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 223706 bytes
+2026-08-31 05:10:50,431 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 223706 bytes
+2026-08-31 05:10:50,431 INFO [backend.app.services.camera] [-] Reusing in-flight camera capture for [IP]: 223706 bytes (no second connection opened)
+2026-08-31 05:10:50,431 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 223706 bytes
+2026-08-31 05:10:54,454 INFO [backend.app.services.notification_service] [-] Sent notification via Telegram thông báo
+2026-08-31 05:11:12,920 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:11:12,922 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:11:16,129 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 225380 bytes
+2026-08-31 05:11:16,129 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 225380 bytes
+2026-08-31 05:11:39,510 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:11:39,511 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:11:41,700 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:11:42,712 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 195511 bytes
+2026-08-31 05:11:42,712 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 195511 bytes
+2026-08-31 05:11:42,712 INFO [backend.app.services.camera] [-] Reusing in-flight camera capture for [IP]: 195511 bytes (no second connection opened)
+2026-08-31 05:11:42,713 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 195511 bytes
+2026-08-31 05:12:07,385 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:12:07,387 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:12:09,821 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:12:10,623 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 216774 bytes
+2026-08-31 05:12:10,624 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 216774 bytes
+2026-08-31 05:12:10,624 INFO [backend.app.services.camera] [-] Reusing in-flight camera capture for [IP]: 216774 bytes (no second connection opened)
+2026-08-31 05:12:10,624 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 216774 bytes
+2026-08-31 05:12:37,951 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:12:37,952 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:12:41,198 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 217798 bytes
+2026-08-31 05:12:41,199 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 217798 bytes
+2026-08-31 05:13:07,498 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:13:07,499 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:13:10,789 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 223036 bytes
+2026-08-31 05:13:10,789 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 223036 bytes
+2026-08-31 05:13:36,091 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:13:36,092 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:13:39,361 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 218501 bytes
+2026-08-31 05:13:39,361 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 218501 bytes
+2026-08-31 05:13:56,654 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-08-31 05:14:05,385 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:14:05,387 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:14:08,596 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 224418 bytes
+2026-08-31 05:14:08,597 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 224418 bytes
+2026-08-31 05:14:34,704 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:14:34,705 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:14:37,911 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 230335 bytes
+2026-08-31 05:14:37,911 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 230335 bytes
+2026-08-31 05:15:03,539 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-31 05:15:03,541 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-08-31 05:15:06,737 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 234294 bytes
+2026-08-31 05:15:06,738 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 234294 bytes
+2026-08-31 05:15:12,661 INFO [backend.app.api.routes.support] [c77d8a15] Log level changed to DEBUG
+2026-08-31 05:15:12,661 INFO [backend.app.api.routes.bug_report] [c77d8a15] Bug report: enabled debug logging
+2026-08-31 05:15:12,661 DEBUG [backend.app.services.bambu_mqtt] [c77d8a15] [[SERIAL]] Requesting status update (pushall)
+2026-08-31 05:15:12,662 DEBUG [backend.app.services.bambu_mqtt] [c77d8a15] [[SERIAL]] Requesting status update (pushall)
+2026-08-31 05:15:12,662 INFO [uvicorn.access] [-] [IP]:65072 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-08-31 05:15:12,966 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 3894711, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-08-31 05:15:12,967 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-08-31 05:15:12,967 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 3894711 (binary: 0b1110110110110110110111)
+2026-08-31 05:15:12,967 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_3.gcode, subtask_name: T03
+2026-08-31 05:15:12,967 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, 9, 5, 7], 'insert_flag': True, 'power_on_flag': False, 'tray_exist_bits': 'b', 'tray_hall_out_bits': '8', 'tray_is_bbl_bits': 'b', 'tray_now': '3', 'tray_pre': '3', 'tray_read_done_bits': 'b', 'tray_reading_bits': '0', 'tray_tar': '3', 'unbind_ams_stat': 0, 'version': 14267}
+2026-08-31 05:15:12,967 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 3
+2026-08-31 05:15:12,968 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-08-31 05:15:12,968 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x11001103 -> extruder 1
+2026-08-31 05:15:12,968 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-08-31 05:15:12,968 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-08-31 05:15:12,968 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-31 05:15:12,969 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] device.extruder.state=33042 (switch_state bits 12-14: 0)
+2026-08-31 05:15:12,969 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 41°C (heater OFF)
+2026-08-31 05:15:12,969 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ctc_info keys: ['temp']
+2026-08-31 05:15:12,969 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=41.0, heating=False, respect_local=False
+2026-08-31 05:15:12,970 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 41.0, target: 0.0, heating: False
+2026-08-31 05:15:12,970 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 29923, 'ts_unix': '20260821054224'}]
+2026-08-31 05:15:12,970 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': 'disable', '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': 4799680, 'tl_external_total_kb': 7986176, 'tl_internal_free_kb': 948795, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-08-31 05:15:12,970 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -40dBm
+2026-08-31 05:15:12,971 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-31 05:15:12,971 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [3]
+2026-08-31 05:15:12,971 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_3.gcode, subtask: T03
+2026-08-31 05:15:13,576 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'buildplate_marker_detector': True}
+2026-08-31 05:15:13,581 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Parsing xcam data - all fields: ['buildplate_marker_detector']
+2026-08-31 05:15:13,585 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: g4_doorbell_pro_mount_right_30_plate_5.3mf, subtask_name: g4_doorbell_pro_mount_right_30_plate_5
+2026-08-31 05:15:13,586 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': '0', 'tray_now': '0', 'tray_pre': '0', 'tray_read_done_bits': 'f', 'tray_reading_bits': '0', 'version': 26, 'insert_flag': True, 'power_on_flag': True}
+2026-08-31 05:15:13,586 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 0
+2026-08-31 05:15:13,586 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-08-31 05:15:13,587 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x1002 -> extruder 0
+2026-08-31 05:15:13,588 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-31 05:15:13,589 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-08-31 05:15:13,594 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-31 05:15:13,597 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 5.0
+2026-08-31 05:15:13,600 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 5.0°C (heater OFF)
+2026-08-31 05:15:13,602 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=5.0, heating=False, respect_local=False
+2026-08-31 05:15:13,605 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 5.0, target: 0.0, heating: False
+2026-08-31 05:15:13,606 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-08-31 05:15:13,606 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'ipcam_dev': '1', 'ipcam_record': 'disable', 'timelapse': 'disable', 'resolution': '1080p', 'tutk_server': 'disable', 'mode_bits': 3}
+2026-08-31 05:15:13,611 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -25dBm
+2026-08-31 05:15:13,614 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-08-31 05:15:13,617 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: g4_doorbell_pro_mount_right_30_plate_5.3mf, subtask: g4_doorbell_pro_mount_right_30_plate_5
+2026-08-31 05:15:14,129 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 3894711, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-08-31 05:15:14,130 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-08-31 05:15:14,130 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 3894711 (binary: 0b1110110110110110110111)
+2026-08-31 05:15:14,130 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_3.gcode, subtask_name: T03
+2026-08-31 05:15:14,130 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-08-31 05:15:14,131 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-31 05:15:14,131 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 29923, 'ts_unix': '20260821054224'}]
+2026-08-31 05:15:14,131 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-31 05:15:14,132 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [3]
+2026-08-31 05:15:14,132 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_3.gcode, subtask: T03
+2026-08-31 05:15:14,976 DEBUG [backend.app.api.routes.printers] [f84665ac] API returning ams_mapping: [], ams_extruder_map: {'0': 0}
+2026-08-31 05:15:14,976 DEBUG [backend.app.api.routes.printers] [f84665ac] Using tray_now directly as global ID: 0
+2026-08-31 05:15:15,182 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 3894711, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-08-31 05:15:15,184 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-08-31 05:15:15,185 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 3894711 (binary: 0b1110110110110110110111)
+2026-08-31 05:15:15,187 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_3.gcode, subtask_name: T03
+2026-08-31 05:15:15,188 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-08-31 05:15:15,190 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-31 05:15:15,194 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 29923, 'ts_unix': '20260821054224'}]
+2026-08-31 05:15:15,195 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-31 05:15:15,198 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [3]
+2026-08-31 05:15:15,198 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_3.gcode, subtask: T03
+2026-08-31 05:15:15,684 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-31 05:15:15,684 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -30dBm
+2026-08-31 05:15:16,509 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 3894711, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-08-31 05:15:16,509 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-08-31 05:15:16,509 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 3894711 (binary: 0b1110110110110110110111)
+2026-08-31 05:15:16,510 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_3.gcode, subtask_name: T03
+2026-08-31 05:15:16,510 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-08-31 05:15:16,510 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-31 05:15:16,511 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 29923, 'ts_unix': '20260821054224'}]
+2026-08-31 05:15:16,511 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-31 05:15:16,512 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [3]
+2026-08-31 05:15:16,512 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_3.gcode, subtask: T03
+2026-08-31 05:15:17,578 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 3894711, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-08-31 05:15:17,579 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-08-31 05:15:17,579 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 3894711 (binary: 0b1110110110110110110111)
+2026-08-31 05:15:17,579 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_3.gcode, subtask_name: T03
+2026-08-31 05:15:17,581 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 1}
+2026-08-31 05:15:17,581 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-31 05:15:17,587 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 29923, 'ts_unix': '20260821054224'}]
+2026-08-31 05:15:17,587 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-31 05:15:17,587 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [3]
+2026-08-31 05:15:17,588 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_3.gcode, subtask: T03
+2026-08-31 05:15:17,785 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-31 05:15:17,791 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -31dBm