Bug report logs b091f8d818b943e6b7fa0294310a8e47.log

logs/b091f8d818b943e6b7fa0294310a8e47.log

@@ -0,0 +1,200 @@
+2026-11-06 09:37:45,159 INFO [uvicorn.error] [-] Waiting for application startup.
+2026-11-06 09:37:45,168 INFO [backend.app.core.fd_limit] [-] Raised the open-file limit from 1024 to 524288 (hard limit 524288)
+2026-11-06 09:37:45,774 INFO [backend.app.core.database] [-] Filed 13 Bambu Lab catalogue colour(s) under PLA Silk+
+2026-11-06 09:37:45,884 INFO [backend.app.core.database] [-] SQLite: users now never reuses an id (next id after 0)
+2026-11-06 09:37:45,891 INFO [backend.app.core.database] [-] SQLite: api_keys now never reuses an id (next id after 0)
+2026-11-06 09:37:45,910 INFO [backend.app.core.database] [-] SQLite: printers now never reuses an id (next id after 1)
+2026-11-06 09:37:45,949 INFO [backend.app.core.database] [-] SQLite: print_archives now never reuses an id (next id after 96)
+2026-11-06 09:37:45,955 INFO [backend.app.core.database] [-] SQLite: long_lived_tokens now never reuses an id (next id after 0)
+2026-11-06 09:37:45,965 INFO [backend.app.core.database] [-] SQLite: spool now never reuses an id (next id after 9)
+2026-11-06 09:37:46,185 INFO [backend.app.core.database] [-] Added manyfold:view, manyfold:import to group 'Administrators' (matches its MakerWorld access)
+2026-11-06 09:37:46,185 INFO [backend.app.core.database] [-] Added manyfold:view, manyfold:import to group 'Operators' (matches its MakerWorld access)
+2026-11-06 09:37:46,185 INFO [backend.app.core.database] [-] Added manyfold:view to group 'Viewers' (matches its MakerWorld access)
+2026-11-06 09:37:46,187 INFO [backend.app.core.database] [-] Added queue:start_unreviewed to Administrators group (ALL_PERMISSIONS sync)
+2026-11-06 09:37:46,192 INFO [backend.app.core.database] [-] Added queue:start_unreviewed to group 'Operators' (#1620)
+2026-11-06 09:37:46,274 INFO [backend.app.services.printer_manager] [-] Loaded 1 printer(s) awaiting plate-clear acknowledgment: [1]
+2026-11-06 09:37:46,279 INFO [backend.app.services.mqtt_relay] [-] MQTT relay disabled
+2026-11-06 09:37:46,407 INFO [backend.app.main] [-] [ARCHIVE] Read the plate estimates of 96 archive(s)
+2026-11-06 09:37:46,415 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Request topic subscription accepted. ams_mapping capture enabled for slicer-initiated prints.
+2026-11-06 09:37:46,535 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Firmware version: 01.02.00.00
+2026-11-06 09:37:47,286 INFO [backend.app.services.smart_plug_manager] [-] Smart plug scheduler started
+2026-11-06 09:37:47,286 INFO [backend.app.services.smart_plug_manager] [-] Smart plug energy snapshot loop started
+2026-11-06 09:37:47,286 INFO [backend.app.services.smart_plug_manager] [-] Smart plug power alert loop started
+2026-11-06 09:37:47,286 INFO [backend.app.services.smart_plug_manager] [-] Smart plug MQTT relay energy loop started
+2026-11-06 09:37:47,286 INFO [backend.app.services.ha_sensor_manager] [-] Home Assistant sensor poller started
+2026-11-06 09:37:47,286 INFO [backend.app.services.location_ha_sensor_manager] [-] Home Assistant location-sensor poller started
+2026-11-06 09:37:47,288 INFO [backend.app.services.print_scheduler] [-] Print scheduler started
+2026-11-06 09:37:47,292 INFO [backend.app.services.notification_service] [-] Notification digest scheduler started
+2026-11-06 09:37:47,302 INFO [backend.app.services.github_backup] [-] Starting GitHub backup scheduler
+2026-11-06 09:37:47,302 INFO [backend.app.services.local_backup] [-] Starting local backup scheduler
+2026-11-06 09:37:47,309 INFO [backend.app.services.obico_detection] [-] Starting Obico detection service
+2026-11-06 09:37:47,309 INFO [backend.app.services.octoeverywhere_detection] [-] Starting OctoEverywhere detection service
+2026-11-06 09:37:47,310 INFO [backend.app.services.library_trash] [-] Starting library trash sweeper
+2026-11-06 09:37:47,310 INFO [backend.app.services.archive_purge] [-] Starting archive auto-purge sweeper
+2026-11-06 09:37:47,310 INFO [backend.app.main] [-] AMS history recording started
+2026-11-06 09:37:47,310 INFO [backend.app.main] [-] Printer sensor history recording started
+2026-11-06 09:37:47,310 INFO [backend.app.main] [-] Printer runtime tracking started
+2026-11-06 09:37:47,310 INFO [backend.app.main] [-] SpoolBuddy watchdog started
+2026-11-06 09:37:47,310 INFO [backend.app.main] [-] Camera stream cleanup started
+2026-11-06 09:37:47,310 INFO [backend.app.main] [-] Printer connection watchdog started
+2026-11-06 09:37:47,354 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS Filament Backup: ON
+2026-11-06 09:37:47,354 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Detected dual-nozzle printer from device.extruder.info
+2026-11-06 09:37:47,355 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Device identification: no known id fields; device.keys=['airduct', 'bed', 'bed_temp', 'cam', 'ctc', 'ext_tool', 'extruder', 'fan', 'fila_switch', 'fire_ext', 'fourth_axis', 'holder', 'laser', 'nozzle', 'plate', 'type']
+2026-11-06 09:37:47,355 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] RUNNING observed without PRINT START (restart-recovery) - file: /data/Metadata/plate_2.gcode, subtask: Front panel (face down)_plate_2
+2026-11-06 09:37:47,460 INFO [backend.app.main] [-] Expected prints cleanup started
+2026-11-06 09:37:47,461 INFO [backend.app.main] [-] Auth periodic cleanup started
+2026-11-06 09:37:47,461 INFO [backend.app.services.loop_watchdog] [-] Event-loop stall watchdog started — dumps all thread stacks to stderr if the loop stalls for more than 30s
+2026-11-06 09:37:47,473 INFO [backend.app.main] [-] [Printer 1] Broadcasting AMS change via WebSocket
+2026-11-06 09:37:47,474 INFO [backend.app.main] [-] [Printer 1] Broadcasting AMS change via WebSocket
+2026-11-06 09:37:47,475 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Requesting K-profiles for nozzle_diameter=0.4 (attempt 1/2, seq_id=3)
+2026-11-06 09:37:47,485 INFO [backend.app.services.virtual_printer.manager] [-] [VP BamBuddySRV] Starting server-mode services on [IP]
+2026-11-06 09:37:47,485 INFO [backend.app.services.virtual_printer.certificate] [-] Deleted printer certificate (CA preserved)
+2026-11-06 09:37:47,485 INFO [backend.app.services.virtual_printer.certificate] [-] Generating certificates for virtual printer (serial: 09400A391800001)...
+2026-11-06 09:37:47,521 INFO [backend.app.services.virtual_printer.certificate] [-] Using existing CA certificate (expires in 7178 days)
+2026-11-06 09:37:47,551 INFO [backend.app.services.virtual_printer.certificate] [-] Generating printer certificate with CN=09400A391800001, local IP: [IP]
+2026-11-06 09:37:47,555 INFO [backend.app.services.virtual_printer.certificate] [-] Generated certificate chain at /opt/[user]/virtual_printer/certs/1
+2026-11-06 09:37:47,555 INFO [backend.app.services.virtual_printer.certificate] [-]   CA: CN=Virtual Printer CA
+2026-11-06 09:37:47,555 INFO [backend.app.services.virtual_printer.certificate] [-]   Printer: CN=09400A391800001
+2026-11-06 09:37:47,555 INFO [backend.app.services.virtual_printer.mqtt_bridge] [-] [BamBuddySRV] MQTT bridge IP encoding armed: target=[IP] vp=[IP] (bind_address)
+2026-11-06 09:37:47,555 INFO [backend.app.services.virtual_printer.mqtt_bridge] [-] [BamBuddySRV] MQTT bridge bound to printer 1 (serial=[SERIAL])
+2026-11-06 09:37:47,555 INFO [backend.app.services.virtual_printer.ftp_server] [-] [BamBuddySRV] Starting virtual printer implicit FTPS on [IP]:990
+2026-11-06 09:37:47,556 INFO [backend.app.services.virtual_printer.ftp_server] [-] FTP SSL context created with standard settings
+2026-11-06 09:37:47,556 INFO [backend.app.services.virtual_printer.mqtt_server] [-] Starting simple MQTT server on port 8883
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.mqtt_server] [-] MQTT SSL cert info: subject=CN=09400A391800001
+issuer=CN=Virtual Printer CA
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.mqtt_server] [-] MQTT SSL context: TLS 1.2+, cert=/opt/[user]/virtual_printer/certs/1/virtual_printer.crt
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.tcp_proxy] [-] Starting Camera-322 TCP proxy: [IP]:322 → [IP]:322
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.bind_server] [-] Starting bind server on ports [3000, 3002] (serial=09400A391800001, model=O1C2, tls=True)
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.ssdp_server] [-] Starting virtual printer SSDP server: BamBuddySRV (09400A391800001)
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.ssdp_server] [-] SSDP server listening on port 2021, advertising IP: [IP]
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.ssdp_server] [-] Virtual printer: BamBuddySRV (09400A391800001) model=O1C2
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.ssdp_server] [-] SSDP server also listening on [IP]:2021
+2026-11-06 09:37:47,567 INFO [backend.app.services.virtual_printer.ssdp_server] [-] Sent initial SSDP NOTIFY announcement
+2026-11-06 09:37:47,568 INFO [backend.app.services.virtual_printer.ftp_server] [-] Implicit FTPS server started on port 990
+2026-11-06 09:37:47,568 INFO [backend.app.services.virtual_printer.ftp_server] [-] FTP passive data port range: 50000-50009
+2026-11-06 09:37:47,568 INFO [backend.app.services.virtual_printer.mqtt_server] [-] Simple MQTT server listening on port 8883
+2026-11-06 09:37:47,568 INFO [backend.app.services.virtual_printer.tcp_proxy] [-] Camera-322 TCP proxy listening on port 322
+2026-11-06 09:37:47,568 INFO [backend.app.services.virtual_printer.bind_server] [-] Bind server listening on [IP]:3000 (plain)
+2026-11-06 09:37:47,568 INFO [backend.app.services.virtual_printer.mqtt_server] [-] Starting periodic status push task
+2026-11-06 09:37:47,569 INFO [backend.app.services.virtual_printer.bind_server] [-] Bind server listening on [IP]:3002 (TLS)
+2026-11-06 09:37:47,569 INFO [backend.app.services.virtual_printer.manager] [-] [VP BamBuddySRV] Server-mode services started on [IP]
+2026-11-06 09:37:47,569 INFO [backend.app.services.virtual_printer.manager] [-] Started server-mode VP: BamBuddySRV on [IP]
+2026-11-06 09:37:47,569 INFO [root] [-] Virtual printer manager synced from database
+2026-11-06 09:37:47,569 INFO [uvicorn.error] [-] Application startup complete.
+2026-11-06 09:37:47,572 INFO [uvicorn.error] [-] Uvicorn running on http://[IP]:8000 (Press CTRL+C to quit)
+2026-11-06 09:37:47,588 INFO [backend.app.main] [-] Auto-unlink skipped: spool 8 AMS0-T1 — tray data cleared during a running print (runout?)
+2026-11-06 09:37:47,592 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:37:47,593 INFO [backend.app.services.camera] [-] Found ffmpeg at: /usr/bin/ffmpeg
+2026-11-06 09:37:47,593 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-11-06 09:37:47,600 INFO [backend.app.main] [-] [RESTART] Seeded tray change log for printer 1: tray=0 at layer=3
+2026-11-06 09:37:47,601 INFO [backend.app.main] [-] Auto-unlink skipped: spool 8 AMS0-T1 — tray data cleared during a running print (runout?)
+2026-11-06 09:37:47,667 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] K-profile response: nozzle=0.4, seq_id=3, 3 profiles, matched=True
+2026-11-06 09:37:47,667 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 3 K-profiles for nozzle=0.4
+2026-11-06 09:37:47,667 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Got 3 K-profiles for nozzle=0.4 on attempt 1
+2026-11-06 09:37:47,667 INFO [backend.app.main] [-] [Printer 1] Primed K-profile table for nozzle 0.4: 3 profiles
+2026-11-06 09:37:47,746 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=[PRINTER], prot_c=False)
+2026-11-06 09:37:47,783 INFO [backend.app.main] [-] [TIMELAPSE] Baseline at print start: 23 video files for printer 1
+2026-11-06 09:37:49,059 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 216083 bytes
+2026-11-06 09:37:49,059 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 216083 bytes
+2026-11-06 09:37:49,061 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:37:49,061 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-11-06 09:37:50,265 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 214107 bytes
+2026-11-06 09:37:50,265 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 214107 bytes
+2026-11-06 09:37:50,553 INFO [uvicorn.access] [-] [IP]:65492 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-11-06 09:37:50,564 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=<anonymous>)
+2026-11-06 09:37:50,564 INFO [uvicorn.error] [-] [IP]:65497 - "WebSocket /api/v1/ws?token=[REDACTED]" [accepted]
+2026-11-06 09:37:50,564 INFO [uvicorn.error] [-] connection open
+2026-11-06 09:37:50,564 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-11-06 09:37:50,565 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-11-06 09:37:57,472 INFO [backend.app.main] [-] Recorded 3 AMS sensor history entries
+2026-11-06 09:38:15,593 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:38:15,593 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-11-06 09:38:16,794 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 217201 bytes
+2026-11-06 09:38:16,794 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 217201 bytes
+2026-11-06 09:38:47,523 INFO [backend.app.services.announcements] [-] Announcements feed #18: 2 for this install
+2026-11-06 09:38:55,987 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:38:55,987 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-11-06 09:38:57,213 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 224197 bytes
+2026-11-06 09:38:57,213 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 224197 bytes
+2026-11-06 09:39:51,657 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:39:51,657 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-11-06 09:39:52,817 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 216860 bytes
+2026-11-06 09:39:52,817 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 216860 bytes
+2026-11-06 09:40:16,893 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=8 at layer=4
+2026-11-06 09:40:34,369 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:40:34,370 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-11-06 09:40:35,540 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 201899 bytes
+2026-11-06 09:40:35,540 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 201899 bytes
+2026-11-06 09:40:38,617 INFO [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Tray change during print: tray=0 at layer=5
+2026-11-06 09:41:44,196 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:41:44,196 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-11-06 09:41:45,379 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 192991 bytes
+2026-11-06 09:41:45,380 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 192991 bytes
+2026-11-06 09:42:43,032 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:42:43,032 INFO [backend.app.services.camera] [-] Capturing camera frame bytes from [IP] using RTSP (model: [PRINTER])
+2026-11-06 09:42:43,035 INFO [backend.app.main] [-] [SNAPSHOT] Capturing fresh frame for printer 1
+2026-11-06 09:42:44,223 INFO [backend.app.services.camera] [-] Successfully captured camera frame bytes: 215840 bytes
+2026-11-06 09:42:44,224 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 215840 bytes
+2026-11-06 09:42:44,224 INFO [backend.app.services.camera] [-] Reusing in-flight camera capture for [IP]: 215840 bytes (no second connection opened)
+2026-11-06 09:42:44,224 INFO [backend.app.main] [-] [SNAPSHOT] Fresh camera frame: 215840 bytes
+2026-11-06 09:42:51,307 INFO [backend.app.api.routes.websocket] [-] WebSocket client disconnected normally
+2026-11-06 09:42:51,630 INFO [uvicorn.access] [-] [IP]:49427 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-11-06 09:42:51,633 INFO [uvicorn.access] [-] [IP]:49450 - "POST /api/v1/auth/media-token HTTP/1.1" 200
+2026-11-06 09:42:51,681 INFO [uvicorn.access] [-] [IP]:49451 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-11-06 09:42:51,695 INFO [backend.app.api.routes.websocket] [-] WebSocket client connecting (principal=<anonymous>)
+2026-11-06 09:42:51,695 INFO [uvicorn.error] [-] [IP]:49456 - "WebSocket /api/v1/ws?token=[REDACTED]" [accepted]
+2026-11-06 09:42:51,695 INFO [uvicorn.error] [-] connection open
+2026-11-06 09:42:51,705 INFO [backend.app.api.routes.websocket] [-] WebSocket client connected
+2026-11-06 09:42:51,705 INFO [backend.app.api.routes.websocket] [-] Sent initial status for 1 printers
+2026-11-06 09:42:51,853 INFO [backend.app.api.routes.camera] [28fd3c6d] Using RTSP protocol for [PRINTER]
+2026-11-06 09:42:51,853 INFO [backend.app.api.routes.camera] [28fd3c6d] Camera viewer attached to printer-1 (subscribers=1)
+2026-11-06 09:42:51,891 INFO [backend.app.services.camera] [28fd3c6d] RTSP socket I/O timeout flag: -timeout
+2026-11-06 09:42:51,892 INFO [backend.app.api.routes.camera] [28fd3c6d] Starting RTSP camera stream for [IP] (stream_id=1-fanout-f52b9814, model=[PRINTER], fps=15, probesize=32, analyzeduration=0)
+2026-11-06 09:42:51,898 INFO [backend.app.api.routes.camera] [6eff14e0] Using RTSP protocol for [PRINTER]
+2026-11-06 09:42:51,898 INFO [backend.app.api.routes.camera] [6eff14e0] Camera viewer attached to printer-1 (subscribers=2)
+2026-11-06 09:42:51,917 INFO [backend.app.services.bambu_cloud] [7e454741] Bambu Cloud returned 401 without the expiry signature — treating as transient, not signing the stored token out
+2026-11-06 09:42:51,933 INFO [backend.app.api.routes.camera] [f0e0d0a9] Using RTSP protocol for [PRINTER]
+2026-11-06 09:42:51,933 INFO [backend.app.api.routes.camera] [f0e0d0a9] Camera viewer attached to printer-1 (subscribers=3)
+2026-11-06 09:42:57,357 INFO [backend.app.api.routes.camera] [2d94ff4d] Skipping force-shutdown for printer 1: 3 subscriber(s) still attached; natural cleanup will tear down when last viewer disconnects
+2026-11-06 09:42:57,358 INFO [uvicorn.access] [-] [IP]:49427 - "POST /api/v1/printers/1/camera/stop?viewer=v7y0m5b5rmg HTTP/1.1" 200
+2026-11-06 09:42:57,479 INFO [backend.app.main] [-] Recorded 3 AMS sensor history entries
+2026-11-06 09:42:59,441 INFO [backend.app.api.routes.printers] [f5eea5d6] Cover: resolved plate 2 before download (subtask=Front panel (face down)_plate_2)
+2026-11-06 09:42:59,444 INFO [backend.app.api.routes.printers] [f5eea5d6] Trying to download cover for 'Front panel (face down)_plate_2' from [IP] (trying 16 paths)
+2026-11-06 09:42:59,464 INFO [backend.app.api.routes.cloud] [785806fa] get_filament_info called with 5 IDs: ['GFA01', 'GFG01', 'GFG00', 'GFG02', 'GFA00']
+2026-11-06 09:42:59,561 INFO [backend.app.services.bambu_ftp] [-] FTP connected successfully to [IP] (model=[PRINTER], prot_c=False)
+2026-11-06 09:42:59,576 INFO [backend.app.services.bambu_ftp] [-] FTP download failed for /Front panel (face down)_plate_2.gcode.3mf: 550 Failed to open file. (not on printer)
+2026-11-06 09:42:59,788 INFO [backend.app.services.bambu_ftp] [-] Successfully downloaded /cache/Front panel (face down)_plate_2.gcode.3mf to /opt/[user]/archive/temp/cover_1_Front panel (face down)_plate_2.gcode.3mf (1712832 bytes)
+2026-11-06 09:42:59,791 INFO [backend.app.api.routes.printers] [f5eea5d6] Downloaded file size: 1712832 bytes
+2026-11-06 09:42:59,970 INFO [uvicorn.access] [-] [IP]:49450 - "POST /api/v1/cloud/filament-info HTTP/1.1" 200
+2026-11-06 09:42:59,986 INFO [backend.app.services.firmware_check] [1f2ecb33] Got Bambu Lab build ID: hwWH9X0bbnc3Qxjctvh7F
+2026-11-06 09:43:06,897 INFO [uvicorn.access] [-] [IP]:49474 - "POST /api/v1/announcements/84104c2aceeda039/read HTTP/1.1" 204
+2026-11-06 09:43:16,148 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 116734 bytes
+2026-11-06 09:44:32,876 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 119293 bytes
+2026-11-06 09:44:53,553 INFO [backend.app.api.routes.support] [686d22a5] Log level changed to DEBUG
+2026-11-06 09:44:53,553 INFO [backend.app.api.routes.bug_report] [686d22a5] Bug report: enabled debug logging
+2026-11-06 09:44:53,553 DEBUG [backend.app.services.bambu_mqtt] [686d22a5] [[SERIAL]] Requesting status update (pushall)
+2026-11-06 09:44:53,553 INFO [uvicorn.access] [-] [IP]:49589 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-11-06 09:44:54,093 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Found xcam inside print data: {'allow_skip_parts': False, 'buildplate_marker_detector': False, 'cfg': 1797543, 'first_layer_inspector': True, 'halt_print_sensitivity': 'medium', 'print_halt': True, 'printing_monitor': True, 'spaghetti_detector': True}
+2026-11-06 09:44:54,093 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-11-06 09:44:54,093 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] xcam cfg bitmask: 1797543 (binary: 0b110110110110110100111)
+2026-11-06 09:44:54,093 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: /data/Metadata/plate_2.gcode, subtask_name: Front panel (face down)_plate_2
+2026-11-06 09:44:54,093 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '7', 'ams_exist_bits_raw': '7', 'cali_id': 255, 'cali_stat': 0, 'cfs': [2, 3, 4, 5, 7], 'insert_flag': True, 'power_on_flag': False, 'tray_exist_bits': 'ffd', 'tray_hall_out_bits': '101', 'tray_is_bbl_bits': 'ffd', 'tray_now': '0', 'tray_pre': '0', 'tray_read_done_bits': 'ffd', 'tray_reading_bits': '0', 'tray_tar': '0', 'unbind_ams_stat': 0, 'version': 87786}
+2026-11-06 09:44:54,093 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 0
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 3 new units, 3 total
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x10002003 -> extruder 0
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 1 info=0x10002003 -> extruder 0
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 2 info=0x11002103 -> extruder 1
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0, '1': 0, '2': 1}
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] device.extruder.state=2 (switch_state bits 12-14: 0)
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] info.temp direct: 28°C (heater OFF)
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ctc_info keys: ['temp']
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=28.0, heating=False, respect_local=False
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 28.0, target: 0.0, heating: False
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: [{'attr': 83886592, 'code': 196618}]
+2026-11-06 09:44:54,094 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': 6, '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-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -60dBm
+2026-11-06 09:44:54,094 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-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] MQTT mapping field: [0, 512]
+2026-11-06 09:44:54,094 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: /data/Metadata/plate_2.gcode, subtask: Front panel (face down)_plate_2