Test Report : TEST-last_will_test-20240505094754.xml

  • last_will_test

Test Suite: last_will_test-20240505094754

Results

Duration4.071 sec
Tests1
Failures0

Tests

last_will_test

Test case:test
Outcome:Passed
Duration:4.071 sec
FailedNone
None

Suite stdout:

[1714902474489] [INFO] DEV-3996365522  Device 3996365522 (type: 1)  has been created
[1714902474490] [INFO] FW-UPD  Initialized
[1714902474490]  [DBG] FW-UPD  enter <function device_base.set_hub at 0x7f20f65ebf60> (<uclient.hub.HUB object at 0x7f20f703aad0>,)
[1714902474490]  [DBG] FW-UPD  exit <function device_base.set_hub at 0x7f20f65ebf60> ret: None
[1714902474490]  [DBG] HUB  enter <function HUB.register_root_cb at 0x7f20f63e2020> ('>user/dev', <bound method user_device.on_user_dev of <uclient.fwupd.fwupd_device object at 0x7f20f64d5ed0>>)
[1714902474490]  [DBG] HUB  exit <function HUB.register_root_cb at 0x7f20f63e2020> ret: None
[1714902474490]  [DBG] MQTT  enter <function mqtt_transport.set_lastwill at 0x7f20f65ea980> ('<48:3f:da:55:07:5c/status', '-1')
[1714902474490]  [DBG] MQTT  exit <function mqtt_transport.set_lastwill at 0x7f20f65ea980> ret: None
[1714902474490]  [DBG] MQTT  enter <function mqtt_transport.on_connect at 0x7f20f65ea700> (<bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>,)
[1714902474490]  [DBG] MQTT  exit <function mqtt_transport.on_connect at 0x7f20f65ea700> ret: None
[1714902474490]  [DBG] MQTT  enter <function mqtt_transport.on_disconnect at 0x7f20f65ea840> (<bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>,)
[1714902474490]  [DBG] MQTT  exit <function mqtt_transport.on_disconnect at 0x7f20f65ea840> ret: None
[1714902474490]  [DBG] HUB  enter <function HUB.connect at 0x7f20f63e28e0> ()
[1714902474490] [WARN] HUB  Try to connect without uri-schema
[1714902474490]  [DBG] MQTT  enter <function mqtt_transport.connect at 0x7f20f65ea0c0> ('x.ks.ua',)
[1714902475494]  [DBG] HUB  enter <function HUB.__on_connect at 0x7f20f63e19e0> ()
[1714902475494] [INFO] HUB  Connected
[1714902475494]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f20f65ea340> ('>hub', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>)
[1714902475494]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f20f65ea340> ret: None
[1714902475494]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f20f65ea340> ('>user/dev', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>)
[1714902475494]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f20f65ea340> ret: None
[1714902475494]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f20f63e1120> ('time', '/')
[1714902475494] [INFO] HUB  (HUB) Subscribe to : {topic: /48:3f:da:55:07:5c/time }
[1714902475494]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f20f65ea340> ('/48:3f:da:55:07:5c/time', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>)
[1714902475494]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f20f65ea340> ret: None
[1714902475494]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f20f63e1120> ret: None
[1714902475494]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f20f63e1120> ('lifetime', '>')
[1714902475494] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/lifetime }
[1714902475494]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f20f65ea340> ('>48:3f:da:55:07:5c/lifetime', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>)
[1714902475494]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f20f65ea340> ret: None
[1714902475494]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f20f63e1120> ret: None
[1714902475495]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f20f63e1120> ('error', '>')
[1714902475495] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/error }
[1714902475495]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f20f65ea340> ('>48:3f:da:55:07:5c/error', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>)
[1714902475495]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f20f65ea340> ret: None
[1714902475495]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f20f63e1120> ret: None
[1714902475495]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f20f63e1120> ('ping', '>')
[1714902475495] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/ping }
[1714902475495]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f20f65ea340> ('>48:3f:da:55:07:5c/ping', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>)
[1714902475495]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f20f65ea340> ret: None
[1714902475495]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f20f63e1120> ret: None
[1714902475495]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f20f63e1120> ('version', '>')
[1714902475495] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/version }
[1714902475495]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f20f65ea340> ('>48:3f:da:55:07:5c/version', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>)
[1714902475495]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f20f65ea340> ret: None
[1714902475495]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f20f63e1120> ret: None
[1714902475495]  [DBG] HUB  exit <function HUB.__on_connect at 0x7f20f63e19e0> ret: None
[1714902475495]  [DBG] MQTT  exit <function mqtt_transport.connect at 0x7f20f65ea0c0> ret: None
[1714902475495]  [DBG] HUB  exit <function HUB.connect at 0x7f20f63e28e0> ret: None
[1714902475506]  [DBG] HUB  Step, state: 0 (next: 1714902476506)
[1714902475506]  [DBG] HUB  enter <function HUB.__send_reg at 0x7f20f63e18a0> ()
[1714902475506] [INFO] HUB  Publish reg token: 48:3f:da:55:07:5c
[1714902475506]  [DBG] MQTT  enter <function mqtt_transport.publish at 0x7f20f65ea200> ('/reg', '48:3f:da:55:07:5c')
[1714902475506]  [DBG] MQTT  exit <function mqtt_transport.publish at 0x7f20f65ea200> ret: None
[1714902475506]  [DBG] HUB  exit <function HUB.__send_reg at 0x7f20f63e18a0> ret: None
[1714902476512]  [DBG] HUB  Step, state: 0 (next: 1714902477512)
[1714902476512]  [DBG] HUB  enter <function HUB.__send_reg at 0x7f20f63e18a0> ()
[1714902476512] [INFO] HUB  Publish reg token: 48:3f:da:55:07:5c
[1714902476512]  [DBG] MQTT  enter <function mqtt_transport.publish at 0x7f20f65ea200> ('/reg', '48:3f:da:55:07:5c')
[1714902476512]  [DBG] MQTT  exit <function mqtt_transport.publish at 0x7f20f65ea200> ret: None
[1714902476512]  [DBG] HUB  exit <function HUB.__send_reg at 0x7f20f63e18a0> ret: None
[1714902476567]  [DBG] HUB  enter <function HUB.__on_message at 0x7f20f63e1da0> ('/48:3f:da:55:07:5c/time', b'1714902476566')
[1714902476567] [INFO] HUB  Message: {topic: /48:3f:da:55:07:5c/time, value: 1714902476566 }
[1714902476567]  [DBG] HUB  enter <function HUB.__time_hnd at 0x7f20f63e1260> ('1714902476566',)
[1714902476567] [INFO] HUB  Registration finish, time: 1714902476566
[1714902476567]  [DBG] FW-UPD  enter <function device_base.register at 0x7f20f63e04a0> (1714902476,)
[1714902476567] [INFO] FW-UPD  Device 3996365522 registration on the server @ 1714902476
[1714902476567]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f20f63e1120> ('3996365522/#', '>')
[1714902476567] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/3996365522/# }
[1714902476567]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f20f65ea340> ('>48:3f:da:55:07:5c/3996365522/#', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f20f703aad0>>)
[1714902476567]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f20f65ea340> ret: None
[1714902476567]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f20f63e1120> ret: None
[1714902476567]  [DBG] FW-UPD  exit <function device_base.register at 0x7f20f63e04a0> ret: None
[1714902476567]  [DBG] HUB  exit <function HUB.__time_hnd at 0x7f20f63e1260> ret: None
[1714902476567]  [DBG] HUB  exit <function HUB.__on_message at 0x7f20f63e1da0> ret: None
[1714902477514]  [DBG] HUB  Step, state: 1 (next: 1714902478514)
[1714902477515]  [DBG] FW-UPD  enter <function device_base.info_req at 0x7f20f63e0d60> ()
[1714902477515]  [DBG] FW-UPD  exit <function device_base.info_req at 0x7f20f63e0d60> ret: {'s': 3996365522, 't': 1, 'r': {0: 3996365522, 1: 1714902476}}
[1714902477515]  [DBG] HUB  enter <function HUB.pub_hub at 0x7f20f63e0fe0> ('info', '{"mac": "48:3f:da:55:07:5c", "version": "R240505;master;1bc93cf09952cae1e1905f746731ac58c1190052", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714902476}}]}', '/')
[1714902477515] [INFO] HUB  Publish message: { topic: info, value: {"mac": "48:3f:da:55:07:5c", "version": "R240505;master;1bc93cf09952cae1e1905f746731ac58c1190052", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714902476}}]} }
[1714902477515]  [DBG] MQTT  enter <function mqtt_transport.publish at 0x7f20f65ea200> ('/48:3f:da:55:07:5c/info', '{"mac": "48:3f:da:55:07:5c", "version": "R240505;master;1bc93cf09952cae1e1905f746731ac58c1190052", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714902476}}]}')
[1714902477516]  [DBG] MQTT  exit <function mqtt_transport.publish at 0x7f20f65ea200> ret: None
[1714902477516]  [DBG] HUB  exit <function HUB.pub_hub at 0x7f20f63e0fe0> ret: None

Suite stderr:

2024-05-05 09:47:57,535 - TEST - INFO - OP take: 2038.927 ms
2024-05-05 09:47:57,539 - TEST - INFO - Connected with result code: 0
2024-05-05 09:47:57,546 - TEST - INFO - OP take: 10.098 ms
  File "/actions-runner/_work/esp32-mpy-uclient/esp32-mpy-uclient/tests/test_tpl.py", line 85, in _ucl_step_thread
    self._uclient.step()
  File "/actions-runner/_work/esp32-mpy-uclient/esp32-mpy-uclient/src/uclient/hub.py", line 260, in step
    self.client.step()
  File "/actions-runner/_work/esp32-mpy-uclient/esp32-mpy-uclient/src/uclient/mqtt_transport.py", line 149, in step
    self.client.check_msg()
  File "/actions-runner/_work/esp32-mpy-uclient/esp32-mpy-uclient/src/net/mqtt.py", line 255, in check_msg
    return self.wait_msg()
           ^^^^^^^^^^^^^^^
  File "/actions-runner/_work/esp32-mpy-uclient/esp32-mpy-uclient/src/net/mqtt.py", line 182, in wait_msg
    sock, _, _ = select.select((self.sock,),(),(), 0.01)
                 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2024-05-05 09:47:58,557 - TEST - INFO - OP take: 10.291 ms
2024-05-05 09:47:58,558 - TEST - WARNING - Disconecting from broker
2024-05-05 09:47:58,567 - TEST - WARNING - Disconecting from broker
2024-05-05 09:47:58,572 - TEST - INFO - Connected with result code: 0