Bug report logs 5ea0661cf78c45599e6e0465555264f3.log

logs/5ea0661cf78c45599e6e0465555264f3.log

@@ -0,0 +1,200 @@
+2026-07-31 11:08:44,878 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(17, 1, 33, None)]
+2026-07-31 11:08:44,878 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 1}
+2026-07-31 11:08:44,878 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-07-31 11:08:46,098 INFO [uvicorn.access] [-] [IP]:46186 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:46,797 INFO [uvicorn.access] [-] [IP]:46218 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:47,404 INFO [uvicorn.access] [-] [IP]:46234 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:49,101 INFO [uvicorn.access] [-] [IP]:46256 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:50,094 INFO [uvicorn.access] [-] [IP]:46298 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:50,295 INFO [uvicorn.access] [-] [IP]:32986 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:51,824 INFO [uvicorn.access] [-] [IP]:33304 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-07-31 11:08:52,407 INFO [uvicorn.access] [-] [IP]:33446 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:54,045 INFO [uvicorn.access] [-] [IP]:33458 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:54,100 INFO [uvicorn.access] [-] [IP]:33478 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:54,102 INFO [uvicorn.access] [-] [IP]:33480 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:57,408 INFO [uvicorn.access] [-] [IP]:33508 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:58,101 INFO [uvicorn.access] [-] [IP]:33524 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:58,314 INFO [uvicorn.access] [-] [IP]:33540 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:08:59,285 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:54322
+2026-07-31 11:08:59,285 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:50802
+2026-07-31 11:08:59,285 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:55770
+2026-07-31 11:09:00,972 INFO [uvicorn.access] [-] [IP]:57666 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-07-31 11:09:01,093 INFO [uvicorn.access] [-] [IP]:57828 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:02,094 INFO [uvicorn.access] [-] [IP]:57846 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:04,686 INFO [uvicorn.access] [-] [IP]:57998 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:05,406 INFO [uvicorn.access] [-] [IP]:58014 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:06,096 INFO [uvicorn.access] [-] [IP]:58024 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:08,926 INFO [uvicorn.access] [-] [IP]:58196 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:09,111 INFO [uvicorn.access] [-] [IP]:58210 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:11,097 INFO [uvicorn.access] [-] [IP]:47178 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:11,410 INFO [uvicorn.access] [-] [IP]:47198 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:14,176 INFO [uvicorn.access] [-] [IP]:47310 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:14,637 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] MQTT publish to device/[SERIAL]/request: {"pushing":{"command":"pushall","push_target":1,"sequence_id":"20077","version":1}}...
+2026-07-31 11:09:14,637 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] MQTT pushing command: pushall
+2026-07-31 11:09:14,638 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] Sending status report in response to pushall
+2026-07-31 11:09:14,883 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(17, 1, 33, None)]
+2026-07-31 11:09:14,884 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 1}
+2026-07-31 11:09:14,884 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-07-31 11:09:16,094 INFO [uvicorn.access] [-] [IP]:47376 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:18,095 INFO [uvicorn.access] [-] [IP]:47546 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:19,077 INFO [uvicorn.access] [-] [IP]:47562 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:19,408 INFO [uvicorn.access] [-] [IP]:47590 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:23,103 INFO [uvicorn.access] [-] [IP]:55540 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:24,267 INFO [uvicorn.access] [-] [IP]:55652 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:25,405 INFO [uvicorn.access] [-] [IP]:55684 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:26,095 INFO [uvicorn.access] [-] [IP]:55702 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:29,406 INFO [uvicorn.access] [-] [IP]:55738 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:30,095 INFO [uvicorn.access] [-] [IP]:55770 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:30,526 INFO [uvicorn.access] [-] [IP]:36734 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:32,096 INFO [uvicorn.access] [-] [IP]:36738 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:32,398 INFO [backend.app.api.routes.print_log] [ff571afb] Print log cleared: 18 entries deleted
+2026-07-31 11:09:32,398 INFO [uvicorn.access] [-] [IP]:36754 - "DELETE /api/v1/print-log/ HTTP/1.1" 200
+2026-07-31 11:09:33,394 INFO [uvicorn.access] [-] [IP]:36808 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:34,581 INFO [uvicorn.access] [-] [IP]:37014 - "POST /api/v1/printers/camera/stream-token HTTP/1.1" 200
+2026-07-31 11:09:36,109 INFO [uvicorn.access] [-] [IP]:37220 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:36,459 INFO [uvicorn.access] [-] [IP]:37352 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:37,095 INFO [uvicorn.access] [-] [IP]:37364 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:37,405 INFO [uvicorn.access] [-] [IP]:37380 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:41,412 INFO [uvicorn.access] [-] [IP]:59138 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:42,093 INFO [uvicorn.access] [-] [IP]:59150 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:42,506 INFO [uvicorn.access] [-] [IP]:59156 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:44,096 INFO [uvicorn.access] [-] [IP]:59162 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:44,889 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(17, 1, 33, None)]
+2026-07-31 11:09:44,889 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 1}
+2026-07-31 11:09:44,889 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-07-31 11:09:45,405 INFO [uvicorn.access] [-] [IP]:59198 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:48,103 INFO [uvicorn.access] [-] [IP]:59236 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:48,697 INFO [uvicorn.access] [-] [IP]:59238 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:49,409 INFO [uvicorn.access] [-] [IP]:59248 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:51,095 INFO [uvicorn.access] [-] [IP]:33312 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:53,417 INFO [uvicorn.access] [-] [IP]:33336 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:53,962 INFO [uvicorn.access] [-] [IP]:33374 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:54,094 INFO [uvicorn.access] [-] [IP]:33386 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:56,095 INFO [uvicorn.access] [-] [IP]:33408 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:57,406 INFO [uvicorn.access] [-] [IP]:33454 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:09:59,401 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:54322
+2026-07-31 11:09:59,401 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:50802
+2026-07-31 11:09:59,402 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:55770
+2026-07-31 11:10:00,093 INFO [uvicorn.access] [-] [IP]:33508 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:01,093 INFO [uvicorn.access] [-] [IP]:36062 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:01,406 INFO [uvicorn.access] [-] [IP]:36070 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:01,523 INFO [uvicorn.access] [-] [IP]:36078 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:05,093 INFO [uvicorn.access] [-] [IP]:36132 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:05,407 INFO [uvicorn.access] [-] [IP]:36144 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:07,644 INFO [uvicorn.access] [-] [IP]:36174 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:08,097 INFO [uvicorn.access] [-] [IP]:36186 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:09,405 INFO [uvicorn.access] [-] [IP]:36200 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:10,092 INFO [uvicorn.access] [-] [IP]:36232 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:10,675 INFO [uvicorn.access] [-] [IP]:33236 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:12,098 INFO [uvicorn.access] [-] [IP]:33260 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:13,408 INFO [uvicorn.access] [-] [IP]:33286 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:13,717 INFO [uvicorn.access] [-] [IP]:33312 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:14,896 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(17, 1, 33, None)]
+2026-07-31 11:10:14,896 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 1}
+2026-07-31 11:10:14,896 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-07-31 11:10:16,100 INFO [uvicorn.access] [-] [IP]:33358 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:16,102 INFO [uvicorn.access] [-] [IP]:33346 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:16,766 INFO [uvicorn.access] [-] [IP]:33376 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:17,396 INFO [uvicorn.access] [-] [IP]:33400 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:19,826 INFO [uvicorn.access] [-] [IP]:33446 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:20,095 INFO [uvicorn.access] [-] [IP]:33472 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:21,101 INFO [uvicorn.access] [-] [IP]:54486 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:21,409 INFO [uvicorn.access] [-] [IP]:54494 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:22,860 INFO [uvicorn.access] [-] [IP]:54510 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:24,095 INFO [uvicorn.access] [-] [IP]:54528 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:25,408 INFO [uvicorn.access] [-] [IP]:54572 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:25,912 INFO [uvicorn.access] [-] [IP]:54586 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:27,099 INFO [uvicorn.access] [-] [IP]:54606 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:28,101 INFO [uvicorn.access] [-] [IP]:54624 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:28,945 INFO [uvicorn.access] [-] [IP]:54636 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:30,406 INFO [uvicorn.access] [-] [IP]:35190 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:32,019 INFO [uvicorn.access] [-] [IP]:35208 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:32,096 INFO [uvicorn.access] [-] [IP]:35230 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:35,072 INFO [uvicorn.access] [-] [IP]:35282 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:35,094 INFO [uvicorn.access] [-] [IP]:35288 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:36,110 INFO [uvicorn.access] [-] [IP]:35308 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:37,411 INFO [uvicorn.access] [-] [IP]:35324 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:38,149 INFO [uvicorn.access] [-] [IP]:35328 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:40,095 INFO [uvicorn.access] [-] [IP]:35380 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:41,251 INFO [uvicorn.access] [-] [IP]:46478 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:43,096 INFO [uvicorn.access] [-] [IP]:46496 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:43,410 INFO [uvicorn.access] [-] [IP]:46510 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:44,094 INFO [uvicorn.access] [-] [IP]:46518 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:44,389 INFO [uvicorn.access] [-] [IP]:46538 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:44,902 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(17, 1, 33, None)]
+2026-07-31 11:10:44,902 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 1}
+2026-07-31 11:10:44,902 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-07-31 11:10:47,577 INFO [uvicorn.access] [-] [IP]:46590 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:48,106 INFO [uvicorn.access] [-] [IP]:46622 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:48,109 INFO [uvicorn.access] [-] [IP]:46606 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:48,410 INFO [uvicorn.access] [-] [IP]:46640 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:51,113 INFO [uvicorn.access] [-] [IP]:49034 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:52,094 INFO [uvicorn.access] [-] [IP]:49056 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:53,097 INFO [uvicorn.access] [-] [IP]:49082 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:53,411 INFO [uvicorn.access] [-] [IP]:49088 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:54,633 INFO [uvicorn.access] [-] [IP]:49094 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:56,107 INFO [uvicorn.access] [-] [IP]:49126 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:59,259 INFO [uvicorn.access] [-] [IP]:49180 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:10:59,516 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:54322
+2026-07-31 11:10:59,516 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:50802
+2026-07-31 11:10:59,516 INFO [backend.app.services.virtual_printer.mqtt_server] [48b4ee82] [Bambuddy] 1Hz status push: 60 pushes/min to [IP]:55770
+2026-07-31 11:11:00,109 INFO [uvicorn.access] [-] [IP]:49220 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:01,096 INFO [uvicorn.access] [-] [IP]:60490 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:01,406 INFO [uvicorn.access] [-] [IP]:60506 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:04,112 INFO [uvicorn.access] [-] [IP]:60534 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:04,546 INFO [uvicorn.access] [-] [IP]:60562 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:05,963 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 50870 bytes
+2026-07-31 11:11:06,103 INFO [uvicorn.access] [-] [IP]:60600 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:09,101 INFO [uvicorn.access] [-] [IP]:60654 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:09,401 INFO [uvicorn.access] [-] [IP]:60670 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:11,351 INFO [uvicorn.access] [-] [IP]:42648 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:12,100 INFO [uvicorn.access] [-] [IP]:42656 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:14,097 INFO [backend.app.main] [-] [SNAPSHOT] Using buffered frame for printer 1: 52057 bytes
+2026-07-31 11:11:14,406 INFO [uvicorn.access] [-] [IP]:42664 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:14,908 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(17, 1, 33, None)]
+2026-07-31 11:11:14,908 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 1}
+2026-07-31 11:11:14,909 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-07-31 11:11:15,094 INFO [uvicorn.access] [-] [IP]:42686 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:18,141 INFO [uvicorn.access] [-] [IP]:42740 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:20,102 INFO [uvicorn.access] [-] [IP]:42762 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:21,093 INFO [uvicorn.access] [-] [IP]:56256 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:21,408 INFO [uvicorn.access] [-] [IP]:56266 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:24,973 INFO [uvicorn.access] [-] [IP]:56302 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:25,100 INFO [uvicorn.access] [-] [IP]:56318 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:28,094 INFO [uvicorn.access] [-] [IP]:56348 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:30,410 INFO [uvicorn.access] [-] [IP]:39710 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:31,093 INFO [uvicorn.access] [-] [IP]:39724 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:32,375 INFO [uvicorn.access] [-] [IP]:39746 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:36,100 INFO [uvicorn.access] [-] [IP]:39812 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:36,576 INFO [uvicorn.access] [-] [IP]:39820 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:37,097 INFO [uvicorn.access] [-] [IP]:39826 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:39,407 INFO [uvicorn.access] [-] [IP]:39856 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:40,710 INFO [uvicorn.access] [-] [IP]:44784 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:41,093 INFO [uvicorn.access] [-] [IP]:44804 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:42,805 INFO [backend.app.api.routes.support] [878bb893] Log level changed to DEBUG
+2026-07-31 11:11:42,805 INFO [backend.app.api.routes.bug_report] [878bb893] Bug report: enabled debug logging
+2026-07-31 11:11:42,805 DEBUG [backend.app.services.bambu_mqtt] [878bb893] [[SERIAL]] Requesting status update (pushall)
+2026-07-31 11:11:42,805 INFO [uvicorn.access] [-] [IP]:44822 - "POST /api/v1/bug-report/start-logging HTTP/1.1" 200
+2026-07-31 11:11:43,576 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received gcode_state: RUNNING, gcode_file: Koffer-Plate_plate_2.3mf, subtask_name: Koffer-Plate_plate_2
+2026-07-31 11:11:43,576 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS dict fields: {'ams_exist_bits': '1', 'tray_exist_bits': 'd', 'tray_is_bbl_bits': 'd', 'tray_tar': '3', 'tray_now': '3', 'tray_pre': '3', 'tray_read_done_bits': 'd', 'tray_reading_bits': '0', 'version': 11, 'insert_flag': True, 'power_on_flag': False}
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] tray_now updated: 3
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Merged AMS data: 1 new units, 1 total
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] AMS 0 info=0x1001 -> extruder 0
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_extruder_map: {'0': 0}
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ams_status: 768 (main=3, sub=0)
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Received command response: push_status
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper raw value: 5.0
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] chamber_temper direct value: 5.0°C (heater OFF)
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber heating calculated: target=0.0, current=5.0, heating=False, respect_local=False
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] Chamber temp updated to: 5.0, target: 0.0, heating: False
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] HMS data received: []
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] ipcam field: {'ipcam_dev': '1', 'ipcam_record': 'enable', 'timelapse': 'disable', 'resolution': '', 'tutk_server': 'disable', 'mode_bits': 3}
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] wifi_signal received: -49dBm
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] lights_report: [{'node': 'chamber_light', 'mode': 'on'}]
+2026-07-31 11:11:43,577 DEBUG [backend.app.services.bambu_mqtt] [-] [[SERIAL]] gcode_state: RUNNING -> RUNNING, file: Koffer-Plate_plate_2.3mf, subtask: Koffer-Plate_plate_2
+2026-07-31 11:11:44,103 INFO [uvicorn.access] [-] [IP]:44852 - "POST /api/v1/auth/ws-token HTTP/1.1" 200
+2026-07-31 11:11:44,238 DEBUG [backend.app.services.virtual_printer.ssdp_server] [48b4ee82] Sent SSDP NOTIFY for Bambuddy (Location=[IP], USN=[SERIAL], bind=[IP])
+2026-07-31 11:11:44,915 INFO [backend.app.services.print_scheduler] [-] Queue check: found 1 pending items: [(17, 1, 33, None)]
+2026-07-31 11:11:44,915 INFO [backend.app.services.print_scheduler] [-] Queue skip summary: {'manual_start': 1}
+2026-07-31 11:11:44,915 INFO [backend.app.services.print_scheduler] [-] Queue: printer 1 not available — connected=True, state=RUNNING, awaiting_plate_clear=False
+2026-07-31 11:11:45,408 INFO [uvicorn.access] [-] [IP]:44880 - "POST /api/v1/auth/ws-token HTTP/1.1" 200