Bug report logs 881fec70eaf248f5bcc5242a2085b9cf.log

logs/881fec70eaf248f5bcc5242a2085b9cf.log

@@ -0,0 +1,200 @@
+2026-09-03 11:07:39,036 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:12:39,042 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:17:39,048 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:22:39,055 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:27:39,062 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:32:39,068 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:37:39,077 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:42:39,084 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:47:39,091 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:52:39,098 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 11:57:39,105 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 12:02:39,112 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 12:07:39,119 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 12:12:39,127 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 12:17:39,133 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 12:20:41,784 INFO [uvicorn.access] [-] [IP]:0 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-09-03 12:20:41,867 INFO [backend.app.api.routes.cloud] [eb1f1a82] get_filament_info called with 3 IDs: ['GFA12', 'GFL01', 'GFG02']
+2026-09-03 12:20:42,138 INFO [uvicorn.access] [-] [IP]:0 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-09-03 12:20:44,048 INFO [uvicorn.access] [-] [IP]:0 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-09-03 12:20:44,639 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=[USER])
+2026-09-03 12:20:44,640 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-09-03 12:20:44,641 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-09-03 12:20:53,567 INFO [backend.app.api.routes.print_queue] [bedfd4fd] Added library file 5 to queue for 1
+2026-09-03 12:20:53,568 INFO [uvicorn.access] [-] [IP]:0 - "POST /api/v1/queue/ HTTP/1.1" 200
+2026-09-03 12:21:05,213 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(86, 1, None, 5)]
+2026-09-03 12:21:05,221 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=FINISH, awaiting_plate_clear=True
+2026-09-03 12:21:05,223 INFO [backend.app.services.print_scheduler] [-] Launching 1 upload(s) (pool 0/4 in flight)
+2026-09-03 12:21:05,226 INFO [backend.app.services.print_scheduler] [-] Starting queue item 86
+2026-09-03 12:21:05,249 INFO [backend.app.services.print_scheduler] [-] Queue item 86: Created archive 79 from library file 5
+2026-09-03 12:21:05,252 INFO [backend.app.services.print_scheduler] [-] Queue item 86: FTP upload starting - printer=[PRINTER] (H2D), ip=[IP], file=BirdBuddy_-_Anti_Squirrel_Beta.3mf, local_path=/opt/[user]/data/archive/library/files/60f542aadf3040338530cf4d390d1ec3.3mf, retry_enabled=True, retry_count=3, timeout=30.0
+2026-09-03 12:21:05,525 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=H2D, prot_c=False)
+2026-09-03 12:21:05,537 INFO [backend.app.services.bambu_ftp] [-] FTP connecting to [IP] for upload (model=H2D, mode=prot_p, socket_timeout=30.0s, deadline=600s)...
+2026-09-03 12:21:05,648 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=H2D, prot_c=False)
+2026-09-03 12:21:05,648 INFO [backend.app.services.bambu_ftp] [-] FTP connected to [IP]
+2026-09-03 12:21:05,648 INFO [backend.app.services.bambu_ftp] [-] FTP uploading /opt/[user]/data/archive/library/files/60f542aadf3040338530cf4d390d1ec3.3mf (1587232 bytes) to /BirdBuddy_-_Anti_Squirrel_Beta.3mf
+2026-09-03 12:21:05,678 INFO [backend.app.services.bambu_ftp] [-] FTP data channel ready in 0.0s (PASV + TLS handshake)
+2026-09-03 12:21:05,904 INFO [backend.app.services.bambu_ftp] [-] FTP STOR returned error_temp for /BirdBuddy_-_Anti_Squirrel_Beta.3mf but file is intact on the printer (1587232 bytes match) — proceeding: 426 Failure reading network stream.
+2026-09-03 12:21:05,904 INFO [backend.app.services.bambu_ftp] [-] FTP upload complete: /BirdBuddy_-_Anti_Squirrel_Beta.3mf (1587232 bytes in 0.3s, 6048 KB/s)
+2026-09-03 12:21:05,904 INFO [backend.app.services.bambu_ftp] [-] FTP mode cached for [IP]: prot_p
+2026-09-03 12:21:05,911 INFO [backend.app.main] [-] Registered expected print: printer=1, file=BirdBuddy_-_Anti_Squirrel_Beta.3mf, archive=79, ams_mapping=[128], plate_id=1
+2026-09-03 12:21:05,914 INFO [backend.app.services.print_scheduler] [-] Queue item 86: Status set to 'printing', sending print command...
+2026-09-03 12:21:05,914 INFO [backend.app.services.printer_manager] [-] PRINT COMMAND: printer=1, file=BirdBuddy_-_Anti_Squirrel_Beta.3mf, caller=print_scheduler.py:1874:_dispatch_one
+2026-09-03 12:21:05,914 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Sending print command: {"print": {"sequence_id": "20000", "command": "project_file", "param": "Metadata/plate_1.gcode", "url": "ftp://BirdBuddy_-_Anti_Squirrel_Beta.3mf", "file": "BirdBuddy_-_Anti_Squirrel_Beta.3mf", "md5": "", "bed_type": "auto", "timelapse": false, "bed_leveling": false, "auto_bed_leveling": 2, "flow_cali": false, "vibration_cali": true, "layer_inspect": false, "use_ams": true, "cfg": "0", "extrude_cali_flag": 2, "extrude_cali_manual_mode": 0, "nozzle_offset_cali": 1, "subtask_name": "BirdBuddy_-_Anti_Squirrel_Beta", "profile_id": "0", "project_id": "1731671610", "subtask_id": "1731671610", "task_id": "1731671610", "ams_mapping": [128], "ams_mapping2": [{"ams_id": 128, "slot_id": 0}]}}
+2026-09-03 12:21:05,914 INFO [backend.app.services.print_scheduler] [-] Queue item 86: Print started successfully - BirdBuddy - Anti Squirrel Beta.gcode.3mf
+2026-09-03 12:21:05,923 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Captured ams_mapping from print command: [128]
+2026-09-03 12:21:48,065 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS Filament Backup: OFF
+2026-09-03 12:21:48,194 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS Filament Backup: ON
+2026-09-03 12:21:50,350 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] PRINT START detected - file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta, is_new: True, is_file_change: False
+2026-09-03 12:21:50,351 INFO [backend.app.main] [-] [CALLBACK] on_print_start called for printer 1, data keys: ['filename', 'subtask_name', 'remaining_time', 'raw_data', 'ams_mapping']
+2026-09-03 12:21:50,355 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] Skipped trays with invalid remain% for printer 1: AMS0-T1(remain=-1), AMS0-T2(remain=-1), AMS0-T3(remain=-1), AMS128-T0(remain=-1)
+2026-09-03 12:21:50,355 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] PRINT START printer 1: mapping=[32768], tray_now=255, last_loaded_tray=1
+2026-09-03 12:21:50,355 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] PRINT START printer 1: mapping-related keys: {'mapping': [32768], 'ams_extruder_map': {'0': 0, '128': 1}}
+2026-09-03 12:21:50,355 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] PRINT START printer 1 AMS 0: T0(type=PLA, color=A1FFACFF, now=?, tar=?), T1(type=PLA, color=161616FF, now=?, tar=?), T2(type=PLA, color=898989FF, now=?, tar=?), T3(type=PLA, color=DCDCDCFF, now=?, tar=?)
+2026-09-03 12:21:50,355 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] PRINT START printer 1 AMS 128: T0(type=PETG, color=DCDCDCFF, now=?, tar=?)
+2026-09-03 12:21:50,356 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] Snapshotted 1 spool assignments for printer 1: {'0-0': 3}
+2026-09-03 12:21:50,358 INFO [backend.app.services.usage_tracker] [-] [UsageTracker] Captured start remain% for printer 1 (3 trays): {'0-0': 78, '255-0': 0, '255-1': 0}
+2026-09-03 12:21:50,359 INFO [backend.app.main] [-] [PLATE CHECK] printer_id=1, plate_detection_enabled=False
+2026-09-03 12:21:50,359 INFO [backend.app.main] [-] [CALLBACK] Print start detected - filename: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:21:50,359 INFO [backend.app.main] [-] Using expected archive 79 for print (skipping duplicate)
+2026-09-03 12:21:50,361 INFO [backend.app.main] [-] [ENERGY] No smart plug for printer 1 (archive 79)
+2026-09-03 12:21:50,363 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-09-03 12:21:50,363 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: H2D)
+2026-09-03 12:21:51,490 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 413959 bytes
+2026-09-03 12:21:51,490 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 413959 bytes
+2026-09-03 12:21:51,491 INFO [backend.app.services.notification_service] [-] on_print_start called for printer 1 ([PRINTER])
+2026-09-03 12:21:51,492 INFO [backend.app.services.notification_service] [-] No notification providers configured for print_start event on printer 1
+2026-09-03 12:21:51,493 INFO [backend.app.main] [-] Loaded 8 printable objects for printer 1
+2026-09-03 12:21:51,653 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=H2D, prot_c=False)
+2026-09-03 12:21:51,762 INFO [backend.app.main] [-] [TIMELAPSE] Baseline at print start: 24 video files for printer 1
+2026-09-03 12:22:39,139 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 12:24:18,576 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=128 at layer=0
+2026-09-03 12:27:39,146 INFO [backend.app.main] [-] Recorded 2 AMS sensor history entries
+2026-09-03 12:30:21,198 INFO [backend.app.api.routes.printers] [77d3b291] Cover: resolved plate 1 before download (subtask=BirdBuddy_-_Anti_Squirrel_Beta)
+2026-09-03 12:30:21,199 INFO [backend.app.api.routes.printers] [77d3b291] Cover using cached 3MF from /opt/[user]/data/archive/library/files/60f542aadf3040338530cf4d390d1ec3.3mf (avoided duplicate FTP)
+2026-09-03 12:30:21,199 INFO [backend.app.api.routes.printers] [77d3b291] Downloaded file size: 1587232 bytes
+2026-09-03 12:30:21,211 INFO [backend.app.api.routes.printers] [8e26e758] Cover: resolved plate 1 before download (subtask=BirdBuddy_-_Anti_Squirrel_Beta)
+2026-09-03 12:30:21,223 INFO [backend.app.api.routes.cloud] [664d871b] get_filament_info called with 3 IDs: ['GFA12', 'GFL01', 'GFG02']
+2026-09-03 12:30:21,484 INFO [uvicorn.access] [-] [IP]:0 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-09-03 12:32:15,440 INFO [backend.app.api.routes.support] [42f26418] Log level changed to DEBUG
+2026-09-03 12:32:15,440 INFO [backend.app.api.routes.bug_report] [42f26418] Bug report: enabled debug logging
+2026-09-03 12:32:15,440 DEBUG [backend.app.services.bambu_mqtt] [42f26418] [[SERIAL]] Requesting status update (pushall)
+2026-09-03 12:32:15,440 INFO [uvicorn.access] [-] [IP]:0 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:16,310 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 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '11', 'ams_exist_bits_raw': '11', 'cali_id': 255, 'cali_stat': 0, 'insert_flag': True, 'power_on_flag': False, 'tray_exist_bits': '1000f', 'tray_hall_out_bits': '10000', 'tray_is_bbl_bits': '1000f', 'tray_now': '0', 'tray_pre': '0', 'tray_read_done_bits': '1000f', 'tray_reading_bits': '0', 'tray_tar': '0', 'unbind_ams_stat': 0, 'version': 192211}
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 128
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 2 new units, 2 total
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x10002003 -> extruder 0
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 128 info=0x10002104 -> extruder 1
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] device.extruder.state=33042 (switch_state bits 12-14: 0)
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 33°C (heater OFF)
+2026-09-03 12:32:16,310 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ctc_info keys: ['temp']
+2026-09-03 12:32:16,311 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=33.0, heating=False, respect_local=False
+2026-09-03 12:32:16,311 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 33.0, target: 0.0, heating: False
+2026-09-03 12:32:16,311 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:16,311 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': 5, 'mode_bits': 2, 'resolution': '1080p', 'rtsp_url': 'rtsps://[IP]:322/streaming/live/1', 'timelapse': 'disable', 'tl_store_hpd_type': 2, 'tl_store_path_type': 2, 'tutk_server': 'disable'}
+2026-09-03 12:32:16,311 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -33dBm
+2026-09-03 12:32:16,311 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:16,311 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:16,311 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:17,902 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:17,903 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 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 34°C (heater OFF)
+2026-09-03 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=34.0, heating=False, respect_local=False
+2026-09-03 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 34.0, target: 0.0, heating: False
+2026-09-03 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:17,903 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:17,904 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:17,904 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:19,541 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 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 33°C (heater OFF)
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=33.0, heating=False, respect_local=False
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 33.0, target: 0.0, heating: False
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:19,541 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:20,979 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:20,979 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 12:32:20,979 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:20,979 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:20,979 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:20,979 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:20,979 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:20,979 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:20,979 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:20,980 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:22,478 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:22,478 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 12:32:22,481 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:22,481 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:22,481 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:22,481 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:22,482 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 34°C (heater OFF)
+2026-09-03 12:32:22,482 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=34.0, heating=False, respect_local=False
+2026-09-03 12:32:22,482 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 34.0, target: 0.0, heating: False
+2026-09-03 12:32:22,482 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:22,482 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:22,482 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:22,482 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:24,101 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:24,101 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 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 33°C (heater OFF)
+2026-09-03 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=33.0, heating=False, respect_local=False
+2026-09-03 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 33.0, target: 0.0, heating: False
+2026-09-03 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:24,102 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:24,103 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:24,103 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:25,766 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:25,766 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 12:32:25,767 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:25,767 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:25,767 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:25,767 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:25,767 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 34°C (heater OFF)
+2026-09-03 12:32:25,767 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=34.0, heating=False, respect_local=False
+2026-09-03 12:32:25,767 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 34.0, target: 0.0, heating: False
+2026-09-03 12:32:25,767 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:25,768 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:25,768 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:25,768 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:27,369 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:27,369 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 12:32:27,370 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:27,370 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:27,370 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:27,370 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:27,370 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:27,370 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:27,370 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:27,370 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:28,984 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': True, 'cfg': 1797559, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-09-03 12:32:28,984 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 12:32:28,984 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797559 (binary: 0b110110110110110110111)
+2026-09-03 12:32:28,984 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_1.gcode, subtask_name: BirdBuddy_-_Anti_Squirrel_Beta
+2026-09-03 12:32:28,984 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '128': 1}
+2026-09-03 12:32:28,984 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-09-03 12:32:28,984 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-09-03 12:32:28,984 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'mode': 'on', 'node': 'chamber_light'}, {'mode': 'flashing', 'node': 'work_light'}, {'mode': 'on', 'node': 'chamber_light2'}]
+2026-09-03 12:32:28,985 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [32768]
+2026-09-03 12:32:28,985 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_1.gcode, subtask: BirdBuddy_-_Anti_Squirrel_Beta