Bug report logs 041ebf0e0f8545b0a59a97658830486c.log

logs/041ebf0e0f8545b0a59a97658830486c.log

@@ -0,0 +1,200 @@
+2026-08-18 17:18:36,612 INFO [backend.app.services.bambu_mqtt] [d8e16e7f] [[SERIAL]] Got 1 K-profiles for nozzle=0.4 on attempt 1
+2026-08-18 17:18:37,412 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 190328 bytes
+2026-08-18 17:18:37,412 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 190328 bytes
+2026-08-18 17:18:37,413 INFO [backend.app.services.camera] [-] Reusing in-flight camera capture for [IP]: 190328 bytes (no second connection opened)
+2026-08-18 17:18:37,413 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 190328 bytes
+2026-08-18 17:18:39,471 INFO [uvicorn.access] [-] [IP]:59897 - "PATCH /api/v1/inventory/spools/8 HTTP/1.1" 200
+2026-08-18 17:18:39,487 INFO [uvicorn.access] [-] [IP]:59897 - "PUT /api/v1/inventory/spools/8/k-profiles HTTP/1.1" 200
+2026-08-18 17:18:41,209 INFO [backend.app.services.bambu_mqtt] [ad70a1b9] [[SERIAL]] Requesting K-profiles for nozzle_diameter=0.4 (attempt 1/3)
+2026-08-18 17:18:41,300 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] K-profile response: nozzle=0.4, seq_id=45, 1 profiles, expected=0.4
+2026-08-18 17:18:41,301 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 1 K-profiles for nozzle=0.4
+2026-08-18 17:18:41,301 INFO [backend.app.services.bambu_mqtt] [ad70a1b9] [[SERIAL]] Got 1 K-profiles for nozzle=0.4 on attempt 1
+2026-08-18 17:18:44,996 INFO [uvicorn.access] [-] [IP]:59897 - "PATCH /api/v1/inventory/spools/42 HTTP/1.1" 200
+2026-08-18 17:18:45,012 INFO [uvicorn.access] [-] [IP]:59897 - "PUT /api/v1/inventory/spools/42/k-profiles HTTP/1.1" 200
+2026-08-18 17:18:46,586 INFO [backend.app.services.bambu_mqtt] [c7d33e22] [[SERIAL]] Requesting K-profiles for nozzle_diameter=0.4 (attempt 1/3)
+2026-08-18 17:18:46,757 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] K-profile response: nozzle=0.4, seq_id=46, 1 profiles, expected=0.4
+2026-08-18 17:18:46,757 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 1 K-profiles for nozzle=0.4
+2026-08-18 17:18:46,757 INFO [backend.app.services.bambu_mqtt] [c7d33e22] [[SERIAL]] Got 1 K-profiles for nozzle=0.4 on attempt 1
+2026-08-18 17:18:50,570 INFO [uvicorn.access] [-] [IP]:55290 - "PATCH /api/v1/inventory/spools/9 HTTP/1.1" 200
+2026-08-18 17:18:50,589 INFO [uvicorn.access] [-] [IP]:55290 - "PUT /api/v1/inventory/spools/9/k-profiles HTTP/1.1" 200
+2026-08-18 17:18:51,978 INFO [backend.app.services.bambu_mqtt] [f16e479e] [[SERIAL]] Requesting K-profiles for nozzle_diameter=0.4 (attempt 1/3)
+2026-08-18 17:18:52,132 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] K-profile response: nozzle=0.4, seq_id=47, 1 profiles, expected=0.4
+2026-08-18 17:18:52,132 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 1 K-profiles for nozzle=0.4
+2026-08-18 17:18:52,132 INFO [backend.app.services.bambu_mqtt] [f16e479e] [[SERIAL]] Got 1 K-profiles for nozzle=0.4 on attempt 1
+2026-08-18 17:18:57,029 INFO [uvicorn.access] [-] [IP]:58892 - "PATCH /api/v1/inventory/spools/10 HTTP/1.1" 200
+2026-08-18 17:18:57,046 INFO [uvicorn.access] [-] [IP]:58892 - "PUT /api/v1/inventory/spools/10/k-profiles HTTP/1.1" 200
+2026-08-18 17:18:59,177 INFO [backend.app.services.bambu_mqtt] [ddfe3a43] [[SERIAL]] Requesting K-profiles for nozzle_diameter=0.4 (attempt 1/3)
+2026-08-18 17:18:59,267 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] K-profile response: nozzle=0.4, seq_id=48, 1 profiles, expected=0.4
+2026-08-18 17:18:59,267 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 1 K-profiles for nozzle=0.4
+2026-08-18 17:18:59,267 INFO [backend.app.services.bambu_mqtt] [ddfe3a43] [[SERIAL]] Got 1 K-profiles for nozzle=0.4 on attempt 1
+2026-08-18 17:19:03,495 INFO [uvicorn.access] [-] [IP]:63308 - "PATCH /api/v1/inventory/spools/11 HTTP/1.1" 200
+2026-08-18 17:19:03,509 INFO [uvicorn.access] [-] [IP]:63308 - "PUT /api/v1/inventory/spools/11/k-profiles HTTP/1.1" 200
+2026-08-18 17:19:09,532 INFO [uvicorn.access] [-] [IP]:60274 - "DELETE /api/v1/inventory/locations/1 HTTP/1.1" 200
+2026-08-18 17:19:16,171 INFO [uvicorn.access] [-] [IP]:60274 - "POST /api/v1/inventory/locations HTTP/1.1" 201
+2026-08-18 17:19:21,242 INFO [uvicorn.access] [-] [IP]:60274 - "PATCH /api/v1/inventory/locations/1 HTTP/1.1" 200
+2026-08-18 17:19:27,088 INFO [backend.app.services.bambu_mqtt] [fee4be45] [[SERIAL]] Requesting K-profiles for nozzle_diameter=0.4 (attempt 1/3)
+2026-08-18 17:19:27,169 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] K-profile response: nozzle=0.4, seq_id=49, 1 profiles, expected=0.4
+2026-08-18 17:19:27,169 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 1 K-profiles for nozzle=0.4
+2026-08-18 17:19:27,169 INFO [backend.app.services.bambu_mqtt] [fee4be45] [[SERIAL]] Got 1 K-profiles for nozzle=0.4 on attempt 1
+2026-08-18 17:19:33,339 INFO [uvicorn.access] [-] [IP]:59364 - "PATCH /api/v1/inventory/spools/22 HTTP/1.1" 200
+2026-08-18 17:19:33,359 INFO [uvicorn.access] [-] [IP]:59364 - "PUT /api/v1/inventory/spools/22/k-profiles HTTP/1.1" 200
+2026-08-18 17:19:35,372 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-08-18 17:19:35,642 INFO [uvicorn.access] [-] [IP]:59364 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-08-18 17:19:35,813 INFO [uvicorn.access] [-] [IP]:59364 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-08-18 17:19:35,855 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-08-18 17:19:35,857 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-08-18 17:19:35,857 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-08-18 17:20:00,883 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-18 17:20:00,884 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: P2S)
+2026-08-18 17:20:04,130 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 185652 bytes
+2026-08-18 17:20:04,130 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 185652 bytes
+2026-08-18 17:21:15,424 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-18 17:21:15,425 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: P2S)
+2026-08-18 17:21:18,878 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 167995 bytes
+2026-08-18 17:21:18,878 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 167995 bytes
+2026-08-18 17:22:24,795 INFO [backend.app.main] [-] Recorded 1 AMS sensor history entries
+2026-08-18 17:22:58,775 INFO [backend.app.main] [-] [Printer 1] Broadcasting AMS change via WebSocket
+2026-08-18 17:23:02,406 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-08-18 17:23:02,406 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: P2S)
+2026-08-18 17:23:05,622 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 180217 bytes
+2026-08-18 17:23:05,622 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 180217 bytes
+2026-08-18 17:23:41,692 INFO [backend.app.api.routes.support] [34acae0d] Log level changed to DEBUG
+2026-08-18 17:23:41,692 INFO [backend.app.api.routes.bug_report] [34acae0d] Bug report: enabled debug logging
+2026-08-18 17:23:41,692 DEBUG [backend.app.services.bambu_mqtt] [34acae0d] [[SERIAL]] Requesting status update (pushall)
+2026-08-18 17:23:41,693 INFO [uvicorn.access] [-] [IP]:64804 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-08-18 17:23:42,410 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-08-18 17:23:42,410 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-18 17:23:42,410 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:42,410 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:42,410 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': [], 'insert_flag': True, 'power_on_flag': False, 'tray_exist_bits': 'f', 'tray_hall_out_bits': '2', 'tray_is_bbl_bits': 'f', 'tray_now': '1', 'tray_pre': '1', 'tray_read_done_bits': 'f', 'tray_reading_bits': '0', 'tray_tar': '1', 'unbind_ams_stat': 0, 'version': 3316}
+2026-08-18 17:23:42,410 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 1
+2026-08-18 17:23:42,410 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x10002003 -> extruder 0
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] device.extruder.state=1 (switch_state bits 12-14: 0)
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 41°C (heater OFF)
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ctc_info keys: ['temp']
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=41.0, heating=False, respect_local=False
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 41.0, target: 0.0, heating: False
+2026-08-18 17:23:42,411 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:42,412 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': 0, 'tl_external_total_kb': 0, 'tl_internal_free_kb': 891708, 'tl_internal_total_kb': 962560, 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-08-18 17:23:42,412 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -45dBm
+2026-08-18 17:23:42,412 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:42,412 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:42,412 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:43,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-08-18 17:23:43,532 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-18 17:23:43,532 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:43,532 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:43,532 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:43,532 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:43,533 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:43,533 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:43,533 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:43,533 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:44,665 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-08-18 17:23:44,665 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-18 17:23:44,665 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:44,665 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:44,665 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:44,665 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:44,666 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:44,666 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:44,666 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:44,666 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:45,315 DEBUG [backend.app.main] [-] [[PRINTER]] Runtime tracking: added 30s, total=2038403s (566.22h)
+2026-08-18 17:23:45,330 DEBUG [backend.app.main] [-] Updated runtime for 1 printer(s)
+2026-08-18 17:23:45,788 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-08-18 17:23:45,788 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-18 17:23:45,788 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:45,788 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:45,788 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:45,788 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:45,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:45,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:45,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:45,789 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:46,224 DEBUG [backend.app.api.routes.printers] [4ffb657d] API returning ams_mapping: [], ams_extruder_map: {'0': 0}
+2026-08-18 17:23:46,224 DEBUG [backend.app.api.routes.printers] [4ffb657d] Using tray_now directly as global ID: 1
+2026-08-18 17:23:46,245 INFO [backend.app.services.bambu_mqtt] [d336c3ce] [[SERIAL]] Requesting K-profiles for nozzle_diameter=0.4 (attempt 1/3)
+2026-08-18 17:23:46,246 DEBUG [backend.app.services.bambu_mqtt] [d336c3ce] [[SERIAL]] K-profile request JSON: {"print": {"command": "extrusion_cali_get", "filament_id": "", "nozzle_diameter": "0.4", "sequence_id": "50"}}
+2026-08-18 17:23:46,324 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: extrusion_cali_get
+2026-08-18 17:23:46,324 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] K-profile response: nozzle=0.4, seq_id=50, 1 profiles, expected=0.4
+2026-08-18 17:23:46,324 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 1 K-profiles for nozzle=0.4
+2026-08-18 17:23:46,324 INFO [backend.app.services.bambu_mqtt] [d336c3ce] [[SERIAL]] Got 1 K-profiles for nozzle=0.4 on attempt 1
+2026-08-18 17:23:47,049 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-08-18 17:23:47,049 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-18 17:23:47,049 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:47,049 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:47,049 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:47,050 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:47,050 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:47,050 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:47,050 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:47,050 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:47,758 DEBUG [backend.app.services.print_scheduler] [-] Printer 1: not idle — state=RUNNING
+2026-08-18 17:23:47,758 DEBUG [backend.app.services.print_scheduler] [-] Auto-drying: printer 1 skipped — not idle
+2026-08-18 17:23:48,154 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-08-18 17:23:48,154 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-18 17:23:48,154 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:48,154 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:48,154 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:48,154 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:48,155 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:48,155 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:48,155 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:48,155 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:49,398 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-08-18 17:23:49,398 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-18 17:23:49,398 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:49,398 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:49,398 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:49,398 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:49,399 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:49,399 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:49,399 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:49,399 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:50,406 INFO [uvicorn.access] [-] [IP]:64804 - "PATCH /api/v1/inventory/spools/21 HTTP/1.1" 200
+2026-08-18 17:23:50,425 INFO [uvicorn.access] [-] [IP]:64804 - "PUT /api/v1/inventory/spools/21/k-profiles HTTP/1.1" 200
+2026-08-18 17:23:50,467 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-08-18 17:23:50,467 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-18 17:23:50,467 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:50,467 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:50,467 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:50,467 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:50,467 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:50,468 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:50,468 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:50,468 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:51,617 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-08-18 17:23:51,618 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-18 17:23:51,618 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:51,618 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:51,618 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:51,618 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:51,618 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:51,618 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:51,619 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:51,619 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:52,728 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-08-18 17:23:52,728 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-18 17:23:52,728 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:52,728 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:52,729 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:52,729 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:52,729 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:52,729 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:52,730 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:52,730 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:54,114 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-08-18 17:23:54,114 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-18 17:23:54,114 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 8089015 (binary: 0b11110110110110110110111)
+2026-08-18 17:23:54,114 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_20.gcode, subtask_name: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100
+2026-08-18 17:23:54,114 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-08-18 17:23:54,114 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-08-18 17:23:54,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83887616, 'code': 131184, 'ts_boot': 34110, 'ts_unix': '20260802055857'}]
+2026-08-18 17:23:54,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}]
+2026-08-18 17:23:54,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [1]
+2026-08-18 17:23:54,115 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_20.gcode, subtask: Spacex_Launch_Tower_OLM_Pad_B_Starship_1_100