Test Report : TEST-last_will_test-20240503194833.xml

  • last_will_test

Test Suite: last_will_test-20240503194833

Results

Duration3.057 sec
Tests1
Failures0

Tests

last_will_test

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

Suite stdout:

[1714765713521] [INFO] DEV-3996365522  Device 3996365522 (type: 1)  has been created
[1714765713522] [INFO] FW-UPD  Initialized
[1714765713522]  [DBG] FW-UPD  enter <function device_base.set_hub at 0x7f371ccd3d80> (<uclient.hub.HUB object at 0x7f371c8d7e50>,)
[1714765713522]  [DBG] FW-UPD  exit <function device_base.set_hub at 0x7f371ccd3d80> ret: None
[1714765713522]  [DBG] HUB  enter <function HUB.register_root_cb at 0x7f371ca65e40> ('>user/dev', <bound method user_device.on_user_dev of <uclient.fwupd.fwupd_device object at 0x7f371c8d7d90>>)
[1714765713522]  [DBG] HUB  exit <function HUB.register_root_cb at 0x7f371ca65e40> ret: None
[1714765713522]  [DBG] MQTT  enter <function mqtt_transport.set_lastwill at 0x7f371ccd2700> ('<48:3f:da:55:07:5c/status', '-1')
[1714765713522]  [DBG] MQTT  exit <function mqtt_transport.set_lastwill at 0x7f371ccd2700> ret: None
[1714765713522]  [DBG] MQTT  enter <function mqtt_transport.on_connect at 0x7f371ccd2480> (<bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>,)
[1714765713522]  [DBG] MQTT  exit <function mqtt_transport.on_connect at 0x7f371ccd2480> ret: None
[1714765713522]  [DBG] MQTT  enter <function mqtt_transport.on_disconnect at 0x7f371ccd25c0> (<bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>,)
[1714765713522]  [DBG] MQTT  exit <function mqtt_transport.on_disconnect at 0x7f371ccd25c0> ret: None
[1714765713522]  [DBG] HUB  enter <function HUB.connect at 0x7f371ca66700> ()
[1714765713522] [WARN] HUB  Try to connect without uri-schema
[1714765713522]  [DBG] MQTT  enter <function mqtt_transport.connect at 0x7f371ccd1e40> ('x.ks.ua',)
[1714765714526]  [DBG] HUB  enter <function HUB.__on_connect at 0x7f371ca65800> ()
[1714765714526] [INFO] HUB  Connected
[1714765714526]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f371ccd20c0> ('>hub', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>)
[1714765714527]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f371ccd20c0> ret: None
[1714765714527]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f371ccd20c0> ('>user/dev', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>)
[1714765714527]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f371ccd20c0> ret: None
[1714765714527]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f371ca64f40> ('time', '/')
[1714765714527] [INFO] HUB  (HUB) Subscribe to : {topic: /48:3f:da:55:07:5c/time }
[1714765714527]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f371ccd20c0> ('/48:3f:da:55:07:5c/time', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>)
[1714765714527]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f371ccd20c0> ret: None
[1714765714527]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f371ca64f40> ret: None
[1714765714527]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f371ca64f40> ('lifetime', '>')
[1714765714527] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/lifetime }
[1714765714527]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f371ccd20c0> ('>48:3f:da:55:07:5c/lifetime', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>)
[1714765714527]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f371ccd20c0> ret: None
[1714765714527]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f371ca64f40> ret: None
[1714765714527]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f371ca64f40> ('error', '>')
[1714765714527] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/error }
[1714765714527]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f371ccd20c0> ('>48:3f:da:55:07:5c/error', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>)
[1714765714527]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f371ccd20c0> ret: None
[1714765714527]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f371ca64f40> ret: None
[1714765714528]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f371ca64f40> ('ping', '>')
[1714765714528] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/ping }
[1714765714528]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f371ccd20c0> ('>48:3f:da:55:07:5c/ping', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>)
[1714765714528]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f371ccd20c0> ret: None
[1714765714528]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f371ca64f40> ret: None
[1714765714528]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f371ca64f40> ('version', '>')
[1714765714528] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/version }
[1714765714528]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f371ccd20c0> ('>48:3f:da:55:07:5c/version', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>)
[1714765714528]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f371ccd20c0> ret: None
[1714765714528]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f371ca64f40> ret: None
[1714765714528]  [DBG] HUB  exit <function HUB.__on_connect at 0x7f371ca65800> ret: None
[1714765714528]  [DBG] MQTT  exit <function mqtt_transport.connect at 0x7f371ccd1e40> ret: None
[1714765714528]  [DBG] HUB  exit <function HUB.connect at 0x7f371ca66700> ret: None
[1714765714539]  [DBG] HUB  Step, state: 0 (next: 1714765715539)
[1714765714539]  [DBG] HUB  enter <function HUB.__send_reg at 0x7f371ca656c0> ()
[1714765714539] [INFO] HUB  Publish reg token: 48:3f:da:55:07:5c
[1714765714539]  [DBG] MQTT  enter <function mqtt_transport.publish at 0x7f371ccd1f80> ('/reg', '48:3f:da:55:07:5c')
[1714765714539]  [DBG] MQTT  exit <function mqtt_transport.publish at 0x7f371ccd1f80> ret: None
[1714765714539]  [DBG] HUB  exit <function HUB.__send_reg at 0x7f371ca656c0> ret: None
[1714765714572]  [DBG] HUB  enter <function HUB.__on_message at 0x7f371ca65bc0> ('/48:3f:da:55:07:5c/time', b'1714765714571')
[1714765714572] [INFO] HUB  Message: {topic: /48:3f:da:55:07:5c/time, value: 1714765714571 }
[1714765714573]  [DBG] HUB  enter <function HUB.__time_hnd at 0x7f371ca65080> ('1714765714571',)
[1714765714573] [INFO] HUB  Registration finish, time: 1714765714571
[1714765714573]  [DBG] FW-UPD  enter <function device_base.register at 0x7f371ca642c0> (1714765714,)
[1714765714573] [INFO] FW-UPD  Device 3996365522 registration on the server @ 1714765714
[1714765714573]  [DBG] HUB  enter <function HUB.subscribe_hub at 0x7f371ca64f40> ('3996365522/#', '>')
[1714765714573] [INFO] HUB  (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/3996365522/# }
[1714765714573]  [DBG] MQTT  enter <function mqtt_transport.subscribe at 0x7f371ccd20c0> ('>48:3f:da:55:07:5c/3996365522/#', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f371c8d7e50>>)
[1714765714573]  [DBG] MQTT  exit <function mqtt_transport.subscribe at 0x7f371ccd20c0> ret: None
[1714765714573]  [DBG] HUB  exit <function HUB.subscribe_hub at 0x7f371ca64f40> ret: None
[1714765714573]  [DBG] FW-UPD  exit <function device_base.register at 0x7f371ca642c0> ret: None
[1714765714573]  [DBG] HUB  exit <function HUB.__time_hnd at 0x7f371ca65080> ret: None
[1714765714573]  [DBG] HUB  exit <function HUB.__on_message at 0x7f371ca65bc0> ret: None
[1714765715541]  [DBG] HUB  Step, state: 1 (next: 1714765716541)
[1714765715541]  [DBG] FW-UPD  enter <function device_base.info_req at 0x7f371ca64b80> ()
[1714765715541]  [DBG] FW-UPD  exit <function device_base.info_req at 0x7f371ca64b80> ret: {'s': 3996365522, 't': 1, 'r': {0: 3996365522, 1: 1714765714}}
[1714765715541]  [DBG] HUB  enter <function HUB.pub_hub at 0x7f371ca64e00> ('info', '{"mac": "48:3f:da:55:07:5c", "version": "R240503;master;c2667ff72f2b1f6acb109be9b5c2675332675736", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714765714}}]}', '/')
[1714765715541] [INFO] HUB  Publish message: { topic: info, value: {"mac": "48:3f:da:55:07:5c", "version": "R240503;master;c2667ff72f2b1f6acb109be9b5c2675332675736", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714765714}}]} }
[1714765715541]  [DBG] MQTT  enter <function mqtt_transport.publish at 0x7f371ccd1f80> ('/48:3f:da:55:07:5c/info', '{"mac": "48:3f:da:55:07:5c", "version": "R240503;master;c2667ff72f2b1f6acb109be9b5c2675332675736", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714765714}}]}')
[1714765715541]  [DBG] MQTT  exit <function mqtt_transport.publish at 0x7f371ccd1f80> ret: None
[1714765715541]  [DBG] HUB  exit <function HUB.pub_hub at 0x7f371ca64e00> ret: None

Suite stderr:

2024-05-03 19:48:35,553 - TEST - INFO - OP take: 1024.64 ms
2024-05-03 19:48:35,556 - TEST - INFO - Connected with result code: 0
2024-05-03 19:48:35,565 - TEST - INFO - OP take: 10.083 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 147, 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-03 19:48:36,576 - TEST - INFO - OP take: 10.138 ms
2024-05-03 19:48:36,577 - TEST - WARNING - Disconecting from broker
2024-05-03 19:48:36,577 - TEST - WARNING - Disconecting from broker