device.py 24 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335336337338339340341342343344345346347348349350351352353354355356357358359360361362363364365366367368369370371372373374375376377378379380381382383384385386387388389390391392393394395396397398399400401402403404405406407408409410411412413414415416417418419420421422423424425426427428429430431432433434435436437438439440441442443444445446447448449450451452453454455456457458459460461462463464465466467468469470471472473474475476477478479480481482483484485486487488489490491492493494495496497498499500501502503504505506507508509510511512513514515516517518519520521522523524525526527528529530531532533534535536537538539540541542543544545546547548549550551552553554555556557558559560561562563564565566567568569570571572573574575576577578579580581582583584585586587588589590591592593594595596597598599600601602603604605606607608609610611612613614615616617618619620621622623624625626627628629630631632633634635636637638639640641642643644645646647648649650651652653654655656657658659660661
  1. """Library to handle connection with Switchbot."""
  2. from __future__ import annotations
  3. import asyncio
  4. import binascii
  5. import logging
  6. import time
  7. from dataclasses import replace
  8. from enum import Enum
  9. from typing import Any, Callable, TypeVar, cast
  10. from uuid import UUID
  11. import async_timeout
  12. from bleak import BleakError
  13. from bleak.backends.device import BLEDevice
  14. from bleak.backends.service import BleakGATTCharacteristic, BleakGATTServiceCollection
  15. from bleak.exc import BleakDBusError
  16. from bleak_retry_connector import (
  17. BLEAK_RETRY_EXCEPTIONS,
  18. BleakClientWithServiceCache,
  19. BleakNotFoundError,
  20. ble_device_has_changed,
  21. establish_connection,
  22. )
  23. from ..const import DEFAULT_RETRY_COUNT, DEFAULT_SCAN_TIMEOUT
  24. from ..discovery import GetSwitchbotDevices
  25. from ..models import SwitchBotAdvertisement
  26. _LOGGER = logging.getLogger(__name__)
  27. REQ_HEADER = "570f"
  28. # Keys common to all device types
  29. DEVICE_GET_BASIC_SETTINGS_KEY = "5702"
  30. DEVICE_SET_MODE_KEY = "5703"
  31. DEVICE_SET_EXTENDED_KEY = REQ_HEADER
  32. # Base key when encryption is set
  33. KEY_PASSWORD_PREFIX = "571"
  34. # How long to hold the connection
  35. # to wait for additional commands for
  36. # disconnecting the device.
  37. DISCONNECT_DELAY = 8.5
  38. class ColorMode(Enum):
  39. OFF = 0
  40. COLOR_TEMP = 1
  41. RGB = 2
  42. EFFECT = 3
  43. # If the scanner is in passive mode, we
  44. # need to poll the device to get the
  45. # battery and a few rarely updating
  46. # values.
  47. PASSIVE_POLL_INTERVAL = 60 * 60 * 24
  48. class CharacteristicMissingError(Exception):
  49. """Raised when a characteristic is missing."""
  50. class SwitchbotOperationError(Exception):
  51. """Raised when an operation fails."""
  52. def _sb_uuid(comms_type: str = "service") -> UUID | str:
  53. """Return Switchbot UUID."""
  54. _uuid = {"tx": "002", "rx": "003", "service": "d00"}
  55. if comms_type in _uuid:
  56. return UUID(f"cba20{_uuid[comms_type]}-224d-11e6-9fb8-0002a5d5c51b")
  57. return "Incorrect type, choose between: tx, rx or service"
  58. READ_CHAR_UUID = _sb_uuid(comms_type="rx")
  59. WRITE_CHAR_UUID = _sb_uuid(comms_type="tx")
  60. WrapFuncType = TypeVar("WrapFuncType", bound=Callable[..., Any])
  61. def update_after_operation(func: WrapFuncType) -> WrapFuncType:
  62. """Define a wrapper to update after an operation."""
  63. async def _async_update_after_operation_wrap(
  64. self: SwitchbotBaseDevice, *args: Any, **kwargs: Any
  65. ) -> None:
  66. ret = await func(self, *args, **kwargs)
  67. await self.update()
  68. return ret
  69. return cast(WrapFuncType, _async_update_after_operation_wrap)
  70. def _merge_data(old_data: dict[str, Any], new_data: dict[str, Any]) -> dict[str, Any]:
  71. """Merge data but only add None keys if they are missing."""
  72. merged = old_data.copy()
  73. for key, value in new_data.items():
  74. if value is not None or key not in old_data:
  75. merged[key] = value
  76. return merged
  77. class SwitchbotBaseDevice:
  78. """Base Representation of a Switchbot Device."""
  79. def __init__(
  80. self,
  81. device: BLEDevice,
  82. password: str | None = None,
  83. interface: int = 0,
  84. **kwargs: Any,
  85. ) -> None:
  86. """Switchbot base class constructor."""
  87. self._interface = f"hci{interface}"
  88. self._device = device
  89. self._sb_adv_data: SwitchBotAdvertisement | None = None
  90. self._override_adv_data: dict[str, Any] | None = None
  91. self._scan_timeout: int = kwargs.pop("scan_timeout", DEFAULT_SCAN_TIMEOUT)
  92. self._retry_count: int = kwargs.pop("retry_count", DEFAULT_RETRY_COUNT)
  93. self._connect_lock = asyncio.Lock()
  94. self._operation_lock = asyncio.Lock()
  95. if password is None or password == "":
  96. self._password_encoded = None
  97. else:
  98. self._password_encoded = "%08x" % (
  99. binascii.crc32(password.encode("ascii")) & 0xFFFFFFFF
  100. )
  101. self._client: BleakClientWithServiceCache | None = None
  102. self._read_char: BleakGATTCharacteristic | None = None
  103. self._write_char: BleakGATTCharacteristic | None = None
  104. self._disconnect_timer: asyncio.TimerHandle | None = None
  105. self._expected_disconnect = False
  106. self.loop = asyncio.get_event_loop()
  107. self._callbacks: list[Callable[[], None]] = []
  108. self._notify_future: asyncio.Future[bytearray] | None = None
  109. self._last_full_update: float = -PASSIVE_POLL_INTERVAL
  110. self._timed_disconnect_task: asyncio.Task[None] | None = None
  111. def advertisement_changed(self, advertisement: SwitchBotAdvertisement) -> bool:
  112. """Check if the advertisement has changed."""
  113. return bool(
  114. not self._sb_adv_data
  115. or ble_device_has_changed(self._sb_adv_data.device, advertisement.device)
  116. or advertisement.data != self._sb_adv_data.data
  117. )
  118. def _commandkey(self, key: str) -> str:
  119. """Add password to key if set."""
  120. if self._password_encoded is None:
  121. return key
  122. key_action = key[3]
  123. key_suffix = key[4:]
  124. return KEY_PASSWORD_PREFIX + key_action + self._password_encoded + key_suffix
  125. async def _send_command(self, key: str, retry: int | None = None) -> bytes | None:
  126. """Send command to device and read response."""
  127. if retry is None:
  128. retry = self._retry_count
  129. command = bytearray.fromhex(self._commandkey(key))
  130. _LOGGER.debug("%s: Scheduling command %s", self.name, command.hex())
  131. max_attempts = retry + 1
  132. if self._operation_lock.locked():
  133. _LOGGER.debug(
  134. "%s: Operation already in progress, waiting for it to complete; RSSI: %s",
  135. self.name,
  136. self.rssi,
  137. )
  138. async with self._operation_lock:
  139. for attempt in range(max_attempts):
  140. try:
  141. return await self._send_command_locked(key, command)
  142. except BleakNotFoundError:
  143. _LOGGER.error(
  144. "%s: device not found, no longer in range, or poor RSSI: %s",
  145. self.name,
  146. self.rssi,
  147. exc_info=True,
  148. )
  149. raise
  150. except CharacteristicMissingError as ex:
  151. if attempt == retry:
  152. _LOGGER.error(
  153. "%s: characteristic missing: %s; Stopping trying; RSSI: %s",
  154. self.name,
  155. ex,
  156. self.rssi,
  157. exc_info=True,
  158. )
  159. raise
  160. _LOGGER.debug(
  161. "%s: characteristic missing: %s; RSSI: %s",
  162. self.name,
  163. ex,
  164. self.rssi,
  165. exc_info=True,
  166. )
  167. except BLEAK_RETRY_EXCEPTIONS:
  168. if attempt == retry:
  169. _LOGGER.error(
  170. "%s: communication failed; Stopping trying; RSSI: %s",
  171. self.name,
  172. self.rssi,
  173. exc_info=True,
  174. )
  175. raise
  176. _LOGGER.debug(
  177. "%s: communication failed with:", self.name, exc_info=True
  178. )
  179. raise RuntimeError("Unreachable")
  180. @property
  181. def name(self) -> str:
  182. """Return device name."""
  183. return f"{self._device.name} ({self._device.address})"
  184. @property
  185. def data(self) -> dict[str, Any]:
  186. """Return device data."""
  187. if self._sb_adv_data:
  188. return self._sb_adv_data.data
  189. return {}
  190. @property
  191. def parsed_data(self) -> dict[str, Any]:
  192. """Return parsed device data."""
  193. return self.data.get("data") or {}
  194. @property
  195. def rssi(self) -> int:
  196. """Return RSSI of device."""
  197. if self._sb_adv_data:
  198. return self._sb_adv_data.rssi
  199. return self._device.rssi
  200. async def _ensure_connected(self):
  201. """Ensure connection to device is established."""
  202. if self._connect_lock.locked():
  203. _LOGGER.debug(
  204. "%s: Connection already in progress, waiting for it to complete; RSSI: %s",
  205. self.name,
  206. self.rssi,
  207. )
  208. if self._client and self._client.is_connected:
  209. self._reset_disconnect_timer()
  210. return
  211. async with self._connect_lock:
  212. # Check again while holding the lock
  213. if self._client and self._client.is_connected:
  214. self._reset_disconnect_timer()
  215. return
  216. _LOGGER.debug("%s: Connecting; RSSI: %s", self.name, self.rssi)
  217. client: BleakClientWithServiceCache = await establish_connection(
  218. BleakClientWithServiceCache,
  219. self._device,
  220. self.name,
  221. self._disconnected,
  222. use_services_cache=True,
  223. ble_device_callback=lambda: self._device,
  224. )
  225. _LOGGER.debug("%s: Connected; RSSI: %s", self.name, self.rssi)
  226. self._client = client
  227. try:
  228. self._resolve_characteristics(client.services)
  229. except CharacteristicMissingError as ex:
  230. _LOGGER.debug(
  231. "%s: characteristic missing, clearing cache: %s; RSSI: %s",
  232. self.name,
  233. ex,
  234. self.rssi,
  235. exc_info=True,
  236. )
  237. await client.clear_cache()
  238. self._cancel_disconnect_timer()
  239. await self._execute_disconnect_with_lock()
  240. raise
  241. self._reset_disconnect_timer()
  242. await self._start_notify()
  243. def _resolve_characteristics(self, services: BleakGATTServiceCollection) -> None:
  244. """Resolve characteristics."""
  245. self._read_char = services.get_characteristic(READ_CHAR_UUID)
  246. if not self._read_char:
  247. raise CharacteristicMissingError(READ_CHAR_UUID)
  248. self._write_char = services.get_characteristic(WRITE_CHAR_UUID)
  249. if not self._write_char:
  250. raise CharacteristicMissingError(WRITE_CHAR_UUID)
  251. def _reset_disconnect_timer(self):
  252. """Reset disconnect timer."""
  253. self._cancel_disconnect_timer()
  254. self._expected_disconnect = False
  255. self._disconnect_timer = self.loop.call_later(
  256. DISCONNECT_DELAY, self._disconnect_from_timer
  257. )
  258. def _disconnected(self, client: BleakClientWithServiceCache) -> None:
  259. """Disconnected callback."""
  260. if self._expected_disconnect:
  261. _LOGGER.debug(
  262. "%s: Disconnected from device; RSSI: %s", self.name, self.rssi
  263. )
  264. return
  265. _LOGGER.warning(
  266. "%s: Device unexpectedly disconnected; RSSI: %s",
  267. self.name,
  268. self.rssi,
  269. )
  270. def _disconnect_from_timer(self):
  271. """Disconnect from device."""
  272. if self._operation_lock.locked() and self._client.is_connected:
  273. _LOGGER.debug(
  274. "%s: Operation in progress, resetting disconnect timer; RSSI: %s",
  275. self.name,
  276. self.rssi,
  277. )
  278. self._reset_disconnect_timer()
  279. return
  280. self._cancel_disconnect_timer()
  281. self._timed_disconnect_task = self._execute_timed_disconnect()
  282. def _cancel_disconnect_timer(self):
  283. """Cancel disconnect timer."""
  284. if self._disconnect_timer:
  285. self._disconnect_timer.cancel()
  286. self._disconnect_timer = None
  287. async def _execute_forced_disconnect(self) -> None:
  288. """Execute forced disconnection."""
  289. self._cancel_disconnect_timer()
  290. _LOGGER.debug(
  291. "%s: Executing forced disconnect",
  292. self.name,
  293. )
  294. await self._execute_disconnect()
  295. async def _execute_timed_disconnect(self) -> None:
  296. """Execute timed disconnection."""
  297. _LOGGER.debug(
  298. "%s: Executing timed disconnect after timeout of %s",
  299. self.name,
  300. DISCONNECT_DELAY,
  301. )
  302. await self._execute_disconnect()
  303. async def _execute_disconnect(self) -> None:
  304. """Execute disconnection."""
  305. _LOGGER.debug("%s: Executing disconnect", self.name)
  306. async with self._connect_lock:
  307. await self._execute_disconnect_with_lock()
  308. async def _execute_disconnect_with_lock(self) -> None:
  309. """Execute disconnection while holding the lock."""
  310. assert self._connect_lock.locked(), "Lock not held"
  311. _LOGGER.debug("%s: Executing disconnect with lock", self.name)
  312. if self._disconnect_timer: # If the timer was reset, don't disconnect
  313. _LOGGER.debug("%s: Skipping disconnect as timer reset", self.name)
  314. return
  315. client = self._client
  316. self._expected_disconnect = True
  317. self._client = None
  318. self._read_char = None
  319. self._write_char = None
  320. if client:
  321. _LOGGER.debug("%s: Disconnecting", self.name)
  322. await client.disconnect()
  323. _LOGGER.debug("%s: Disconnect completed", self.name)
  324. else:
  325. _LOGGER.debug("%s: Already disconnected", self.name)
  326. async def _send_command_locked(self, key: str, command: bytes) -> bytes:
  327. """Send command to device and read response."""
  328. await self._ensure_connected()
  329. try:
  330. return await self._execute_command_locked(key, command)
  331. except BleakDBusError as ex:
  332. # Disconnect so we can reset state and try again
  333. await asyncio.sleep(0.25)
  334. _LOGGER.debug(
  335. "%s: RSSI: %s; Backing off %ss; Disconnecting due to error: %s",
  336. self.name,
  337. self.rssi,
  338. 0.25,
  339. ex,
  340. )
  341. await self._execute_forced_disconnect()
  342. raise
  343. except BleakError as ex:
  344. # Disconnect so we can reset state and try again
  345. _LOGGER.debug(
  346. "%s: RSSI: %s; Disconnecting due to error: %s", self.name, self.rssi, ex
  347. )
  348. await self._execute_forced_disconnect()
  349. raise
  350. def _notification_handler(self, _sender: int, data: bytearray) -> None:
  351. """Handle notification responses."""
  352. if self._notify_future and not self._notify_future.done():
  353. self._notify_future.set_result(data)
  354. return
  355. _LOGGER.debug("%s: Received unsolicited notification: %s", self.name, data)
  356. async def _start_notify(self) -> None:
  357. """Start notification."""
  358. _LOGGER.debug("%s: Subscribe to notifications; RSSI: %s", self.name, self.rssi)
  359. await self._client.start_notify(self._read_char, self._notification_handler)
  360. async def _execute_command_locked(self, key: str, command: bytes) -> bytes:
  361. """Execute command and read response."""
  362. assert self._client is not None
  363. assert self._read_char is not None
  364. assert self._write_char is not None
  365. self._notify_future = asyncio.Future()
  366. client = self._client
  367. _LOGGER.debug("%s: Sending command: %s", self.name, key)
  368. await client.write_gatt_char(self._write_char, command, False)
  369. async with async_timeout.timeout(5):
  370. notify_msg = await self._notify_future
  371. _LOGGER.debug("%s: Notification received: %s", self.name, notify_msg.hex())
  372. self._notify_future = None
  373. if notify_msg == b"\x07":
  374. _LOGGER.error("Password required")
  375. elif notify_msg == b"\t":
  376. _LOGGER.error("Password incorrect")
  377. return notify_msg
  378. def get_address(self) -> str:
  379. """Return address of device."""
  380. return self._device.address
  381. def _override_state(self, state: dict[str, Any]) -> None:
  382. """Override device state."""
  383. if self._override_adv_data is None:
  384. self._override_adv_data = {}
  385. self._override_adv_data.update(state)
  386. self._update_parsed_data(state)
  387. def _get_adv_value(self, key: str) -> Any:
  388. """Return value from advertisement data."""
  389. if self._override_adv_data and key in self._override_adv_data:
  390. _LOGGER.debug(
  391. "%s: Using override value for %s: %s",
  392. self.name,
  393. key,
  394. self._override_adv_data[key],
  395. )
  396. return self._override_adv_data[key]
  397. if not self._sb_adv_data:
  398. return None
  399. return self._sb_adv_data.data["data"].get(key)
  400. def get_battery_percent(self) -> Any:
  401. """Return device battery level in percent."""
  402. return self._get_adv_value("battery")
  403. def update_from_advertisement(self, advertisement: SwitchBotAdvertisement) -> None:
  404. """Update device data from advertisement."""
  405. # Only accept advertisements if the data is not missing
  406. # if we already have an advertisement with data
  407. self._device = advertisement.device
  408. async def get_device_data(
  409. self, retry: int | None = None, interface: int | None = None
  410. ) -> SwitchBotAdvertisement | None:
  411. """Find switchbot devices and their advertisement data."""
  412. if retry is None:
  413. retry = self._retry_count
  414. if interface:
  415. _interface: int = interface
  416. else:
  417. _interface = int(self._interface.replace("hci", ""))
  418. _data = await GetSwitchbotDevices(interface=_interface).discover(
  419. retry=retry, scan_timeout=self._scan_timeout
  420. )
  421. if self._device.address in _data:
  422. self._sb_adv_data = _data[self._device.address]
  423. return self._sb_adv_data
  424. async def _get_basic_info(self) -> bytes | None:
  425. """Return basic info of device."""
  426. _data = await self._send_command(
  427. key=DEVICE_GET_BASIC_SETTINGS_KEY, retry=self._retry_count
  428. )
  429. if _data in (b"\x07", b"\x00"):
  430. _LOGGER.error("Unsuccessful, please try again")
  431. return None
  432. return _data
  433. def _fire_callbacks(self) -> None:
  434. """Fire callbacks."""
  435. _LOGGER.debug("%s: Fire callbacks", self.name)
  436. for callback in self._callbacks:
  437. callback()
  438. def subscribe(self, callback: Callable[[], None]) -> Callable[[], None]:
  439. """Subscribe to device notifications."""
  440. self._callbacks.append(callback)
  441. def _unsub() -> None:
  442. """Unsubscribe from device notifications."""
  443. self._callbacks.remove(callback)
  444. return _unsub
  445. async def update(self, interface: int | None = None) -> None:
  446. """Update position, battery percent and light level of device."""
  447. if info := await self.get_basic_info():
  448. self._last_full_update = time.monotonic()
  449. self._update_parsed_data(info)
  450. self._fire_callbacks()
  451. async def get_basic_info(self) -> dict[str, Any] | None:
  452. """Get device basic settings."""
  453. if not (_data := await self._get_basic_info()):
  454. return None
  455. return {
  456. "battery": _data[1],
  457. "firmware": _data[2] / 10.0,
  458. }
  459. def _check_command_result(
  460. self, result: bytes | None, index: int, values: set[int]
  461. ) -> bool:
  462. """Check command result."""
  463. if not result or len(result) - 1 < index:
  464. result_hex = result.hex() if result else "None"
  465. raise SwitchbotOperationError(
  466. f"{self.name}: Sending command failed (result={result_hex} index={index} expected={values} rssi={self.rssi})"
  467. )
  468. return result[index] in values
  469. def _update_parsed_data(self, new_data: dict[str, Any]) -> bool:
  470. """Update data.
  471. Returns true if data has changed and False if not.
  472. """
  473. if not self._sb_adv_data:
  474. _LOGGER.exception("No advertisement data to update")
  475. return
  476. old_data = self._sb_adv_data.data.get("data") or {}
  477. merged_data = _merge_data(old_data, new_data)
  478. if merged_data == old_data:
  479. return False
  480. self._set_parsed_data(self._sb_adv_data, merged_data)
  481. return True
  482. def _set_parsed_data(
  483. self, advertisement: SwitchBotAdvertisement, data: dict[str, Any]
  484. ) -> None:
  485. """Set data."""
  486. self._sb_adv_data = replace(
  487. advertisement, data=self._sb_adv_data.data | {"data": data}
  488. )
  489. def _set_advertisement_data(self, advertisement: SwitchBotAdvertisement) -> None:
  490. """Set advertisement data."""
  491. new_data = advertisement.data.get("data") or {}
  492. if advertisement.active:
  493. # If we are getting active data, we can assume we are
  494. # getting active scans and we do not need to poll
  495. self._last_full_update = time.monotonic()
  496. if not self._sb_adv_data:
  497. self._sb_adv_data = advertisement
  498. elif new_data:
  499. self._update_parsed_data(new_data)
  500. self._override_adv_data = None
  501. def switch_mode(self) -> bool | None:
  502. """Return true or false from cache."""
  503. # To get actual position call update() first.
  504. return self._get_adv_value("switchMode")
  505. def poll_needed(self, seconds_since_last_poll: float | None) -> bool:
  506. """Return if device needs polling."""
  507. if (
  508. seconds_since_last_poll is not None
  509. and seconds_since_last_poll < PASSIVE_POLL_INTERVAL
  510. ):
  511. return False
  512. time_since_last_full_update = time.monotonic() - self._last_full_update
  513. if time_since_last_full_update < PASSIVE_POLL_INTERVAL:
  514. return False
  515. return True
  516. class SwitchbotDevice(SwitchbotBaseDevice):
  517. """Base Representation of a Switchbot Device.
  518. This base class consumes the advertisement data during connection. If the device
  519. sends stale advertisement data while connected, use
  520. SwitchbotDeviceOverrideStateDuringConnection instead.
  521. """
  522. def update_from_advertisement(self, advertisement: SwitchBotAdvertisement) -> None:
  523. """Update device data from advertisement."""
  524. super().update_from_advertisement(advertisement)
  525. self._set_advertisement_data(advertisement)
  526. class SwitchbotDeviceOverrideStateDuringConnection(SwitchbotBaseDevice):
  527. """Base Representation of a Switchbot Device.
  528. This base class ignores the advertisement data during connection and uses the
  529. data from the device instead.
  530. """
  531. def update_from_advertisement(self, advertisement: SwitchBotAdvertisement) -> None:
  532. super().update_from_advertisement(advertisement)
  533. if self._client and self._client.is_connected:
  534. # We do not consume the advertisement data if we are connected
  535. # to the device. This is because the advertisement data is not
  536. # updated when the device is connected for some devices.
  537. _LOGGER.debug("%s: Ignore advertisement data during connection", self.name)
  538. return
  539. self._set_advertisement_data(advertisement)
  540. class SwitchbotSequenceDevice(SwitchbotDevice):
  541. """A Switchbot sequence device.
  542. This class must not use SwitchbotDeviceOverrideStateDuringConnection because
  543. it needs to know when the sequence_number has changed.
  544. """
  545. def update_from_advertisement(self, advertisement: SwitchBotAdvertisement) -> None:
  546. """Update device data from advertisement."""
  547. current_state = self._get_adv_value("sequence_number")
  548. super().update_from_advertisement(advertisement)
  549. new_state = self._get_adv_value("sequence_number")
  550. _LOGGER.debug(
  551. "%s: update advertisement: %s (seq before: %s) (seq after: %s)",
  552. self.name,
  553. advertisement,
  554. current_state,
  555. new_state,
  556. )
  557. if current_state != new_state:
  558. asyncio.ensure_future(self.update())