mqtt_server.py 31 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335336337338339340341342343344345346347348349350351352353354355356357358359360361362363364365366367368369370371372373374375376377378379380381382383384385386387388389390391392393394395396397398399400401402403404405406407408409410411412413414415416417418419420421422423424425426427428429430431432433434435436437438439440441442443444445446447448449450451452453454455456457458459460461462463464465466467468469470471472473474475476477478479480481482483484485486487488489490491492493494495496497498499500501502503504505506507508509510511512513514515516517518519520521522523524525526527528529530531532533534535536537538539540541542543544545546547548549550551552553554555556557558559560561562563564565566567568569570571572573574575576577578579580581582583584585586587588589590591592593594595596597598599600601602603604605606607608609610611612613614615616617618619620621622623624625626627628629630631632633634635636637638639640641642643644645646647648649650651652653654655656657658659660661662663664665666667668669670671672673674675676677678679680681682683684685686687688689690691692693694695696697698699700701702703704705706707708709710711712713714715716717718719720721722723724725726727728729730731732733734735736737738739740741742743744745746747748749750751752753754755756757758759760761762763764765766767768769770771772773774775776777778779780781782783784785786787788789790791792793794795796797798799800801802803804805806807808809810811812813814815816817818819820821822823824825826827828829
  1. """MQTT broker for virtual printer.
  2. Implements an MQTT broker that accepts connections from slicers,
  3. authenticates with the configured access code, and logs print commands.
  4. """
  5. import asyncio
  6. import json
  7. import logging
  8. import ssl
  9. from collections.abc import Callable
  10. from pathlib import Path
  11. logger = logging.getLogger(__name__)
  12. # Default MQTT port for Bambu printers (MQTT over TLS)
  13. MQTT_PORT = 8883
  14. # Model code → product_name for version response (must match what slicer expects)
  15. MODEL_PRODUCT_NAMES = {
  16. "BL-P001": "X1 Carbon",
  17. "BL-P002": "X1",
  18. "C13": "X1E",
  19. "C11": "P1P",
  20. "C12": "P1S",
  21. "N7": "P2S",
  22. "N2S": "A1",
  23. "N1": "A1 mini",
  24. "O1D": "H2D",
  25. "O1C": "H2C",
  26. "O1C2": "H2C",
  27. "O1S": "H2S",
  28. }
  29. class VirtualPrinterMQTTServer:
  30. """MQTT broker that accepts connections from slicers.
  31. This is a minimal MQTT broker implementation that:
  32. - Accepts TLS connections on port 8883
  33. - Authenticates with username 'bblp' and the configured access code
  34. - Receives print commands on device/{serial}/request
  35. - Can publish status on device/{serial}/report
  36. """
  37. def __init__(
  38. self,
  39. serial: str,
  40. access_code: str,
  41. cert_path: Path,
  42. key_path: Path,
  43. port: int = MQTT_PORT,
  44. on_print_command: Callable[[str, dict], None] | None = None,
  45. ):
  46. """Initialize the MQTT server.
  47. Args:
  48. serial: Virtual printer serial number
  49. access_code: Password for authentication
  50. cert_path: Path to TLS certificate
  51. key_path: Path to TLS private key
  52. port: Port to listen on (default 8883)
  53. on_print_command: Callback when print command received (filename, data)
  54. """
  55. self.serial = serial
  56. self.access_code = access_code
  57. self.cert_path = cert_path
  58. self.key_path = key_path
  59. self.port = port
  60. self.on_print_command = on_print_command
  61. self._running = False
  62. self._broker = None
  63. self._broker_task = None
  64. async def start(self) -> None:
  65. """Start the MQTT broker."""
  66. if self._running:
  67. return
  68. # Try to import amqtt
  69. try:
  70. from amqtt.broker import Broker
  71. except ImportError:
  72. logger.error("amqtt not installed. Run: pip install amqtt")
  73. return
  74. logger.info("Starting virtual printer MQTT broker on port %s", self.port)
  75. # Build broker configuration
  76. config = {
  77. "listeners": {
  78. "default": {
  79. "type": "tcp",
  80. "bind": f"0.0.0.0:{self.port}",
  81. "ssl": "on",
  82. "certfile": str(self.cert_path),
  83. "keyfile": str(self.key_path),
  84. },
  85. },
  86. "auth": {
  87. "allow-anonymous": False,
  88. "plugins": ["auth_custom"],
  89. },
  90. "topic-check": {
  91. "enabled": False, # Allow any topic
  92. },
  93. }
  94. try:
  95. self._running = True
  96. # Create and start broker
  97. self._broker = Broker(config)
  98. # Register custom auth plugin
  99. self._broker.plugins_manager.plugins_handlers["auth_custom"] = self._authenticate
  100. # Start the broker
  101. await self._broker.start()
  102. logger.info("MQTT broker started on port %s", self.port)
  103. # Keep running
  104. while self._running:
  105. await asyncio.sleep(1)
  106. except OSError as e:
  107. if e.errno == 98: # Address already in use
  108. logger.error("MQTT port %s is already in use", self.port)
  109. else:
  110. logger.error("MQTT broker error: %s", e)
  111. except asyncio.CancelledError:
  112. logger.debug("MQTT broker task cancelled")
  113. except Exception as e:
  114. logger.error("MQTT broker error: %s", e)
  115. finally:
  116. await self.stop()
  117. async def _authenticate(self, session) -> bool:
  118. """Authenticate MQTT connection.
  119. Args:
  120. session: MQTT session with username/password
  121. Returns:
  122. True if authentication successful
  123. """
  124. username = getattr(session, "username", None)
  125. password = getattr(session, "password", None)
  126. # Bambu slicers use 'bblp' as username and access code as password
  127. if username == "bblp" and password == self.access_code:
  128. logger.debug("MQTT client authenticated from %s", session.remote_address)
  129. return True
  130. logger.warning("MQTT auth failed for user '%s' from %s", username, session.remote_address)
  131. return False
  132. async def stop(self) -> None:
  133. """Stop the MQTT broker."""
  134. logger.info("Stopping MQTT broker")
  135. self._running = False
  136. if self._broker:
  137. try:
  138. await self._broker.shutdown()
  139. except OSError as e:
  140. logger.debug("Error shutting down MQTT broker: %s", e)
  141. self._broker = None
  142. class SimpleMQTTServer:
  143. """Simplified MQTT server using raw sockets.
  144. This is a fallback implementation that handles basic MQTT protocol
  145. without requiring the amqtt library. It's less feature-complete but
  146. more lightweight.
  147. """
  148. def __init__(
  149. self,
  150. serial: str,
  151. access_code: str,
  152. cert_path: Path,
  153. key_path: Path,
  154. port: int = MQTT_PORT,
  155. on_print_command: Callable[[str, dict], None] | None = None,
  156. model: str = "",
  157. bind_address: str = "0.0.0.0", # nosec B104
  158. vp_name: str = "",
  159. ):
  160. self.serial = serial
  161. self.access_code = access_code
  162. self.model = model
  163. self.cert_path = cert_path
  164. self.key_path = key_path
  165. self.port = port
  166. self.on_print_command = on_print_command
  167. self.bind_address = bind_address
  168. self.vp_name = vp_name
  169. self._log_prefix = f"[{vp_name}] " if vp_name else ""
  170. self._running = False
  171. self._server = None
  172. self._clients: dict[str, asyncio.StreamWriter] = {}
  173. self._status_push_task: asyncio.Task | None = None
  174. self._sequence_id = 0
  175. # Dynamic state for status reports
  176. self._gcode_state = "IDLE"
  177. self._current_file = ""
  178. self._prepare_percent = "0"
  179. async def start(self) -> None:
  180. """Start the MQTT server."""
  181. if self._running:
  182. return
  183. logger.info("Starting simple MQTT server on port %s", self.port)
  184. # Create SSL context with Bambu-compatible settings
  185. ssl_context = ssl.SSLContext(ssl.PROTOCOL_TLS_SERVER)
  186. ssl_context.load_cert_chain(str(self.cert_path), str(self.key_path))
  187. # Match Bambu printer behavior - accept any client
  188. ssl_context.verify_mode = ssl.CERT_NONE
  189. # Allow TLS 1.2 for broader compatibility (some slicers may not support 1.3)
  190. ssl_context.minimum_version = ssl.TLSVersion.TLSv1_2
  191. # Disable hostname checking
  192. ssl_context.check_hostname = False
  193. # Log certificate info
  194. import subprocess
  195. try:
  196. result = subprocess.run(
  197. ["openssl", "x509", "-in", str(self.cert_path), "-noout", "-subject", "-issuer"],
  198. capture_output=True,
  199. text=True,
  200. timeout=5,
  201. )
  202. logger.info("MQTT SSL cert info: %s", result.stdout.strip())
  203. except (OSError, subprocess.SubprocessError):
  204. pass # Certificate info is for debug logging only; not critical
  205. logger.info("MQTT SSL context: TLS 1.2+, cert=%s", self.cert_path)
  206. try:
  207. self._running = True
  208. # Wrapper to log ALL connection attempts including SSL errors
  209. async def connection_handler(reader, writer):
  210. try:
  211. addr = writer.get_extra_info("peername")
  212. ssl_obj = writer.get_extra_info("ssl_object")
  213. if ssl_obj:
  214. logger.info(
  215. f"{self._log_prefix}MQTT TLS connection from {addr} - cipher={ssl_obj.cipher()}, version={ssl_obj.version()}"
  216. )
  217. else:
  218. logger.info("%sMQTT connection from %s (no TLS?)", self._log_prefix, addr)
  219. await self._handle_client(reader, writer)
  220. except ssl.SSLError as e:
  221. logger.error("MQTT SSL error: %s", e)
  222. except Exception as e:
  223. logger.error("MQTT connection handler error: %s", e)
  224. self._server = await asyncio.start_server(
  225. connection_handler,
  226. self.bind_address,
  227. self.port,
  228. ssl=ssl_context,
  229. )
  230. logger.info("Simple MQTT server listening on port %s", self.port)
  231. # Start periodic status push task
  232. self._status_push_task = asyncio.create_task(self._periodic_status_push())
  233. async with self._server:
  234. await self._server.serve_forever()
  235. except OSError as e:
  236. if e.errno == 98: # Address already in use
  237. logger.error("MQTT port %s is already in use", self.port)
  238. else:
  239. logger.error("MQTT server error: %s", e)
  240. except asyncio.CancelledError:
  241. logger.debug("MQTT server task cancelled")
  242. except Exception as e:
  243. logger.error("MQTT server error: %s", e)
  244. finally:
  245. await self.stop()
  246. async def stop(self) -> None:
  247. """Stop the MQTT server."""
  248. logger.info("Stopping simple MQTT server")
  249. self._running = False
  250. # Stop periodic status push
  251. if self._status_push_task:
  252. self._status_push_task.cancel()
  253. try:
  254. await self._status_push_task
  255. except asyncio.CancelledError:
  256. pass # Expected when stopping the periodic status push task
  257. self._status_push_task = None
  258. # Close all client connections (iterate over copy to avoid modification during iteration)
  259. for _client_id, writer in list(self._clients.items()):
  260. try:
  261. writer.close()
  262. await writer.wait_closed()
  263. except OSError:
  264. pass # Best-effort client connection cleanup; client may have disconnected
  265. self._clients.clear()
  266. if self._server:
  267. try:
  268. self._server.close()
  269. await self._server.wait_closed()
  270. except OSError:
  271. pass # Best-effort server shutdown; port may already be released
  272. self._server = None
  273. async def _periodic_status_push(self) -> None:
  274. """Send periodic status updates to all connected clients."""
  275. logger.info("Starting periodic status push task")
  276. while self._running:
  277. try:
  278. await asyncio.sleep(1) # Push every 1 second like real printers
  279. # Send status to all connected clients
  280. disconnected = []
  281. for client_id, writer in list(self._clients.items()):
  282. try:
  283. if writer.is_closing():
  284. disconnected.append(client_id)
  285. continue
  286. await self._send_status_report(writer)
  287. except OSError as e:
  288. logger.debug("Failed to push status to %s: %s", client_id, e)
  289. disconnected.append(client_id)
  290. # Remove disconnected clients
  291. for client_id in disconnected:
  292. self._clients.pop(client_id, None)
  293. except asyncio.CancelledError:
  294. break
  295. except Exception as e:
  296. logger.error("Periodic status push error: %s", e)
  297. logger.info("Periodic status push task stopped")
  298. async def _handle_client(self, reader: asyncio.StreamReader, writer: asyncio.StreamWriter) -> None:
  299. """Handle an MQTT client connection."""
  300. addr = writer.get_extra_info("peername")
  301. client_id = f"{addr[0]}:{addr[1]}" if addr else "unknown"
  302. logger.info("%sMQTT client connected: %s", self._log_prefix, client_id)
  303. authenticated = False
  304. try:
  305. while self._running:
  306. # Read MQTT fixed header
  307. try:
  308. header = await asyncio.wait_for(reader.read(1), timeout=60)
  309. except TimeoutError:
  310. break
  311. if not header:
  312. break
  313. packet_type = (header[0] & 0xF0) >> 4
  314. # Read remaining length
  315. remaining_length = await self._read_remaining_length(reader)
  316. if remaining_length is None:
  317. break
  318. # Read payload
  319. payload = await reader.read(remaining_length) if remaining_length > 0 else b""
  320. # Handle packet types
  321. if packet_type == 1: # CONNECT
  322. authenticated = await self._handle_connect(payload, writer)
  323. if not authenticated:
  324. break
  325. # Register client for periodic status pushes
  326. self._clients[client_id] = writer
  327. elif packet_type == 3: # PUBLISH
  328. if authenticated:
  329. await self._handle_publish(header[0], payload, writer)
  330. elif packet_type == 8: # SUBSCRIBE
  331. if authenticated:
  332. await self._handle_subscribe(payload, writer)
  333. elif packet_type == 12: # PINGREQ
  334. # Send PINGRESP
  335. writer.write(bytes([0xD0, 0x00]))
  336. await writer.drain()
  337. elif packet_type == 14: # DISCONNECT
  338. break
  339. except asyncio.CancelledError:
  340. pass # Expected when server is shutting down and cancels client tasks
  341. except Exception as e:
  342. logger.debug("MQTT client error: %s", e)
  343. finally:
  344. logger.debug("MQTT client disconnected: %s", client_id)
  345. if client_id in self._clients:
  346. del self._clients[client_id]
  347. try:
  348. writer.close()
  349. await writer.wait_closed()
  350. except OSError:
  351. pass # Best-effort socket cleanup on client disconnect
  352. async def _read_remaining_length(self, reader: asyncio.StreamReader) -> int | None:
  353. """Read MQTT remaining length (variable byte integer)."""
  354. multiplier = 1
  355. value = 0
  356. for _ in range(4):
  357. try:
  358. byte = await reader.read(1)
  359. if not byte:
  360. return None
  361. encoded = byte[0]
  362. value += (encoded & 127) * multiplier
  363. if (encoded & 128) == 0:
  364. return value
  365. multiplier *= 128
  366. except OSError:
  367. return None
  368. return None
  369. async def _handle_connect(self, payload: bytes, writer: asyncio.StreamWriter) -> bool:
  370. """Handle MQTT CONNECT packet.
  371. Returns True if authentication successful.
  372. """
  373. try:
  374. # Parse CONNECT packet
  375. # Skip protocol name length and name
  376. idx = 0
  377. proto_len = (payload[idx] << 8) | payload[idx + 1]
  378. idx += 2 + proto_len
  379. # Skip protocol level and connect flags
  380. # connect_flags = payload[idx + 1]
  381. idx += 2
  382. # Skip keepalive
  383. idx += 2
  384. # Read client ID
  385. client_id_len = (payload[idx] << 8) | payload[idx + 1]
  386. idx += 2
  387. # client_id = payload[idx : idx + client_id_len].decode("utf-8")
  388. idx += client_id_len
  389. # Read username
  390. username_len = (payload[idx] << 8) | payload[idx + 1]
  391. idx += 2
  392. username = payload[idx : idx + username_len].decode("utf-8")
  393. idx += username_len
  394. # Read password
  395. password_len = (payload[idx] << 8) | payload[idx + 1]
  396. idx += 2
  397. password = payload[idx : idx + password_len].decode("utf-8")
  398. # Authenticate
  399. if username == "bblp" and password == self.access_code:
  400. # Send CONNACK with success
  401. writer.write(bytes([0x20, 0x02, 0x00, 0x00]))
  402. await writer.drain()
  403. logger.info("%sMQTT client authenticated successfully", self._log_prefix)
  404. # Send immediate status report after auth - slicer expects this
  405. await self._send_status_report(writer)
  406. return True
  407. else:
  408. # Send CONNACK with auth failure
  409. writer.write(bytes([0x20, 0x02, 0x00, 0x05])) # Not authorized
  410. await writer.drain()
  411. logger.warning("%sMQTT auth failed for user '%s' (access code mismatch)", self._log_prefix, username)
  412. return False
  413. except (IndexError, ValueError) as e:
  414. logger.debug("MQTT CONNECT parse error: %s", e)
  415. # Send CONNACK with error
  416. writer.write(bytes([0x20, 0x02, 0x00, 0x02])) # Protocol error
  417. await writer.drain()
  418. return False
  419. async def _handle_subscribe(self, payload: bytes, writer: asyncio.StreamWriter) -> None:
  420. """Handle MQTT SUBSCRIBE packet."""
  421. try:
  422. # Parse packet ID
  423. packet_id = (payload[0] << 8) | payload[1]
  424. # Parse topic filters (just acknowledge them)
  425. idx = 2
  426. granted_qos = []
  427. while idx < len(payload):
  428. topic_len = (payload[idx] << 8) | payload[idx + 1]
  429. idx += 2
  430. topic = payload[idx : idx + topic_len].decode("utf-8")
  431. idx += topic_len
  432. requested_qos = payload[idx]
  433. idx += 1
  434. logger.info("%sMQTT subscribe: %s QoS=%s", self._log_prefix, topic, requested_qos)
  435. granted_qos.append(min(requested_qos, 1)) # Grant up to QoS 1
  436. # Send SUBACK
  437. suback = bytes([0x90, 2 + len(granted_qos), packet_id >> 8, packet_id & 0xFF])
  438. suback += bytes(granted_qos)
  439. writer.write(suback)
  440. await writer.drain()
  441. # Send initial status report after subscribe
  442. await self._send_status_report(writer)
  443. except (IndexError, ValueError, OSError) as e:
  444. logger.debug("MQTT SUBSCRIBE error: %s", e)
  445. async def _send_status_report(self, writer: asyncio.StreamWriter) -> None:
  446. """Send a status report to the slicer after connection."""
  447. try:
  448. # Build status message matching Bambu printer format
  449. self._sequence_id += 1
  450. status = {
  451. "print": {
  452. "sequence_id": str(self._sequence_id),
  453. "command": "push_status",
  454. "msg": 0,
  455. "gcode_state": self._gcode_state,
  456. "gcode_file": self._current_file,
  457. "gcode_file_prepare_percent": self._prepare_percent,
  458. "subtask_name": self._current_file.replace(".3mf", "") if self._current_file else "",
  459. "mc_print_stage": "",
  460. "mc_percent": 0,
  461. "mc_remaining_time": 0,
  462. "wifi_signal": "-44dBm",
  463. "print_error": 0,
  464. "print_type": "",
  465. "bed_temper": 25.0,
  466. "bed_target_temper": 0.0,
  467. "nozzle_temper": 25.0,
  468. "nozzle_target_temper": 0.0,
  469. "chamber_temper": 25.0,
  470. "cooling_fan_speed": "0",
  471. "big_fan1_speed": "0",
  472. "big_fan2_speed": "0",
  473. "heatbreak_fan_speed": "0",
  474. "spd_lvl": 1,
  475. "spd_mag": 100,
  476. "stg": [],
  477. "stg_cur": 0,
  478. "layer_num": 0,
  479. "total_layer_num": 0,
  480. "home_flag": 256, # Bit 8 = SD card present (HAS_SDCARD_NORMAL)
  481. "hw_switch_state": 0,
  482. "online": {"ahb": False, "rfid": False, "version": 7},
  483. "ams_status": 0,
  484. "sdcard": True,
  485. "storage": {"free": 1000000000, "total": 32000000000},
  486. "upgrade_state": {
  487. "sequence_id": 0,
  488. "progress": "",
  489. "status": "",
  490. "consistency_request": False,
  491. "dis_state": 0,
  492. "err_code": 0,
  493. "force_upgrade": False,
  494. "message": "",
  495. "module": "",
  496. "new_version_state": 2,
  497. "new_ver_list": [],
  498. "ota_new_version_number": "",
  499. "ahb_new_version_number": "",
  500. },
  501. "ipcam": {
  502. "ipcam_dev": "1",
  503. "ipcam_record": "enable",
  504. "timelapse": "disable",
  505. "resolution": "1080p",
  506. "mode_bits": 0,
  507. },
  508. "xcam": {
  509. "allow_skip_parts": False,
  510. "buildplate_marker_detector": True,
  511. "first_layer_inspector": True,
  512. "halt_print_sensitivity": "medium",
  513. "print_halt": True,
  514. "printing_monitor": True,
  515. "spaghetti_detector": True,
  516. },
  517. "lights_report": [{"node": "chamber_light", "mode": "on"}],
  518. "nozzle_diameter": "0.4",
  519. "nozzle_type": "hardened_steel",
  520. }
  521. }
  522. await self._publish_to_report(writer, status, self.serial)
  523. except OSError as e:
  524. logger.error("Failed to send status report: %s", e)
  525. async def _send_version_response(self, writer: asyncio.StreamWriter, sequence_id: str) -> None:
  526. """Send version info response to the slicer."""
  527. try:
  528. product_name = MODEL_PRODUCT_NAMES.get(self.model, self.model or "X1 Carbon")
  529. serial = self.serial
  530. # Build version response matching OrcaSlicer expectations
  531. # Required fields per module: name, product_name, sw_ver, sw_new_ver, sn, hw_ver, flag
  532. version_info = {
  533. "info": {
  534. "command": "get_version",
  535. "sequence_id": sequence_id,
  536. "module": [
  537. {
  538. "name": "ota",
  539. "product_name": product_name,
  540. "sw_ver": "01.07.00.00",
  541. "sw_new_ver": "",
  542. "hw_ver": "OTA",
  543. "sn": serial,
  544. "flag": 0,
  545. },
  546. {
  547. "name": "esp32",
  548. "product_name": product_name,
  549. "sw_ver": "01.07.22.25",
  550. "sw_new_ver": "",
  551. "hw_ver": "AP05",
  552. "sn": serial,
  553. "flag": 0,
  554. },
  555. {
  556. "name": "rv1126",
  557. "product_name": product_name,
  558. "sw_ver": "00.00.27.38",
  559. "sw_new_ver": "",
  560. "hw_ver": "AP05",
  561. "sn": serial,
  562. "flag": 0,
  563. },
  564. {
  565. "name": "th",
  566. "product_name": product_name,
  567. "sw_ver": "00.00.04.00",
  568. "sw_new_ver": "",
  569. "hw_ver": "TH07",
  570. "sn": serial,
  571. "flag": 0,
  572. },
  573. {
  574. "name": "mc",
  575. "product_name": product_name,
  576. "sw_ver": "00.00.10.00",
  577. "sw_new_ver": "",
  578. "hw_ver": "MC07",
  579. "sn": serial,
  580. "flag": 0,
  581. },
  582. ],
  583. }
  584. }
  585. await self._publish_to_report(writer, version_info, serial)
  586. logger.info("Sent version response (product_name=%s)", product_name)
  587. except OSError as e:
  588. logger.error("Failed to send version response: %s", e)
  589. def set_gcode_state(self, state: str, filename: str = "", prepare_percent: str = "0") -> None:
  590. """Update the gcode state reported to connected slicers.
  591. Called by the manager to reflect FTP upload progress/completion.
  592. """
  593. self._gcode_state = state
  594. self._current_file = filename
  595. self._prepare_percent = prepare_percent
  596. async def _publish_to_report(self, writer: asyncio.StreamWriter, payload: dict, serial: str = "") -> None:
  597. """Publish a message on the device report topic."""
  598. topic = f"device/{serial or self.serial}/report"
  599. message = json.dumps(payload)
  600. topic_bytes = topic.encode("utf-8")
  601. message_bytes = message.encode("utf-8")
  602. remaining = 2 + len(topic_bytes) + len(message_bytes)
  603. packet = bytes([0x30]) # PUBLISH, QoS 0
  604. while remaining > 0:
  605. byte = remaining % 128
  606. remaining //= 128
  607. if remaining > 0:
  608. byte |= 0x80
  609. packet += bytes([byte])
  610. packet += bytes([len(topic_bytes) >> 8, len(topic_bytes) & 0xFF])
  611. packet += topic_bytes
  612. packet += message_bytes
  613. writer.write(packet)
  614. # Timeout the drain to prevent blocking the event loop if the
  615. # MQTT client stops reading (e.g. slicer busy with FTP upload).
  616. try:
  617. await asyncio.wait_for(writer.drain(), timeout=5)
  618. except TimeoutError:
  619. logger.debug("MQTT drain timeout for %s — client may be busy", topic)
  620. async def _send_print_response(self, writer: asyncio.StreamWriter, sequence_id: str, filename: str) -> None:
  621. """Send project_file acknowledgment matching real Bambu printer behavior."""
  622. # Update state so periodic status pushes reflect preparation
  623. self._gcode_state = "PREPARE"
  624. self._current_file = filename
  625. self._prepare_percent = "0"
  626. try:
  627. # Send command acknowledgment — slicer expects to see
  628. # command: "project_file" echoed back before starting FTP upload
  629. subtask_name = filename.replace(".3mf", "") if filename else ""
  630. response = {
  631. "print": {
  632. "command": "project_file",
  633. "sequence_id": sequence_id,
  634. "param": "Metadata/plate_1.gcode",
  635. "subtask_name": subtask_name,
  636. "gcode_state": "PREPARE",
  637. "gcode_file": filename,
  638. "gcode_file_prepare_percent": "0",
  639. "result": "SUCCESS",
  640. "msg": 0,
  641. }
  642. }
  643. await self._publish_to_report(writer, response)
  644. logger.info("Sent project_file acknowledgment for %s", filename)
  645. except OSError as e:
  646. logger.error("Failed to send print response: %s", e)
  647. async def _handle_publish(self, header: int, payload: bytes, writer: asyncio.StreamWriter) -> None:
  648. """Handle MQTT PUBLISH packet."""
  649. try:
  650. # Parse topic
  651. idx = 0
  652. topic_len = (payload[idx] << 8) | payload[idx + 1]
  653. idx += 2
  654. topic = payload[idx : idx + topic_len].decode("utf-8")
  655. idx += topic_len
  656. # Check for packet ID (QoS > 0)
  657. qos = (header & 0x06) >> 1
  658. if qos > 0:
  659. # packet_id = (payload[idx] << 8) | payload[idx + 1]
  660. idx += 2
  661. # Parse message
  662. message = payload[idx:].decode("utf-8")
  663. logger.info("MQTT publish to %s: %s...", topic, message[:100])
  664. # Handle commands on device request topic
  665. if f"device/{self.serial}/request" in topic:
  666. try:
  667. data = json.loads(message)
  668. # Handle pushing command (status request)
  669. if "pushing" in data:
  670. pushing_data = data["pushing"]
  671. command = pushing_data.get("command", "")
  672. logger.info("MQTT pushing command: %s", command)
  673. if command == "pushall":
  674. # Slicer is requesting full status - send response
  675. logger.info("Sending status report in response to pushall")
  676. await self._send_status_report(writer)
  677. elif command == "start":
  678. # Slicer wants periodic status updates - send one now
  679. logger.info("Starting status push stream")
  680. await self._send_status_report(writer)
  681. # Handle info commands (get_version, etc.)
  682. if "info" in data:
  683. info_data = data["info"]
  684. command = info_data.get("command", "")
  685. sequence_id = info_data.get("sequence_id", "0")
  686. logger.info("MQTT info command: %s", command)
  687. if command == "get_version":
  688. await self._send_version_response(writer, sequence_id)
  689. # Handle print commands
  690. if "print" in data:
  691. print_data = data["print"]
  692. command = print_data.get("command", "")
  693. filename = print_data.get("subtask_name", "")
  694. sequence_id = print_data.get("sequence_id", "0")
  695. logger.info("MQTT print command: %s for %s", command, filename)
  696. if command == "project_file":
  697. # Respond with PREPARE status so slicer proceeds with FTP upload
  698. file_3mf = print_data.get("file", filename)
  699. await self._send_print_response(writer, sequence_id, file_3mf)
  700. if self.on_print_command:
  701. await self._notify_print_command(filename, print_data)
  702. except json.JSONDecodeError:
  703. pass # Non-JSON payloads on request topic are safely ignored
  704. except (IndexError, ValueError, OSError) as e:
  705. logger.debug("MQTT PUBLISH error: %s", e)
  706. async def _notify_print_command(self, filename: str, data: dict) -> None:
  707. """Notify callback of print command."""
  708. if self.on_print_command:
  709. try:
  710. result = self.on_print_command(filename, data)
  711. if asyncio.iscoroutine(result):
  712. await result
  713. except Exception as e:
  714. logger.error("Print command callback error: %s", e)