Test Report : TEST-last_will_test-20240430185338.xml
Test Suite: last_will_test-20240430185338
Results
| Duration | 4.066 sec |
| Tests | 1 |
| Failures | 0 |
Tests
last_will_test
| Test case: | test |
| Outcome: | Passed |
| Duration: | 4.066 sec |
| Failed | None |
None
Suite stdout:
[1714503218088] [INFO] DEV-3996365522 Device 3996365522 (type: 1) has been created
[1714503218088] [INFO] FW-UPD Initialized
[1714503218088] [DBG] FW-UPD enter <function device_base.set_hub at 0x7f5e81fffd80> (<uclient.hub.HUB object at 0x7f5e81c64cd0>,)
[1714503218088] [DBG] FW-UPD exit <function device_base.set_hub at 0x7f5e81fffd80> ret: None
[1714503218088] [DBG] HUB enter <function HUB.register_root_cb at 0x7f5e81df5e40> ('>user/dev', <bound method user_device.on_user_dev of <uclient.fwupd.fwupd_device object at 0x7f5e821157d0>>)
[1714503218088] [DBG] HUB exit <function HUB.register_root_cb at 0x7f5e81df5e40> ret: None
[1714503218088] [DBG] MQTT enter <function mqtt_transport.set_lastwill at 0x7f5e81ffe700> ('<48:3f:da:55:07:5c/status', '-1')
[1714503218088] [DBG] MQTT exit <function mqtt_transport.set_lastwill at 0x7f5e81ffe700> ret: None
[1714503218089] [DBG] MQTT enter <function mqtt_transport.on_connect at 0x7f5e81ffe480> (<bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>,)
[1714503218089] [DBG] MQTT exit <function mqtt_transport.on_connect at 0x7f5e81ffe480> ret: None
[1714503218089] [DBG] MQTT enter <function mqtt_transport.on_disconnect at 0x7f5e81ffe5c0> (<bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>,)
[1714503218089] [DBG] MQTT exit <function mqtt_transport.on_disconnect at 0x7f5e81ffe5c0> ret: None
[1714503218089] [DBG] HUB enter <function HUB.connect at 0x7f5e81df6700> ()
[1714503218089] [WARN] HUB Try to connect without uri-schema
[1714503218089] [DBG] MQTT enter <function mqtt_transport.connect at 0x7f5e81ffde40> ('x.ks.ua',)
[1714503219093] [DBG] HUB enter <function HUB.__on_connect at 0x7f5e81df5800> ()
[1714503219093] [INFO] HUB Connected
[1714503219093] [DBG] MQTT enter <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ('>hub', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>)
[1714503219094] [DBG] MQTT exit <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ret: None
[1714503219094] [DBG] MQTT enter <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ('>user/dev', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>)
[1714503219094] [DBG] MQTT exit <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ret: None
[1714503219094] [DBG] HUB enter <function HUB.subscribe_hub at 0x7f5e81df4f40> ('time', '/')
[1714503219094] [INFO] HUB (HUB) Subscribe to : {topic: /48:3f:da:55:07:5c/time }
[1714503219094] [DBG] MQTT enter <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ('/48:3f:da:55:07:5c/time', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>)
[1714503219094] [DBG] MQTT exit <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ret: None
[1714503219094] [DBG] HUB exit <function HUB.subscribe_hub at 0x7f5e81df4f40> ret: None
[1714503219094] [DBG] HUB enter <function HUB.subscribe_hub at 0x7f5e81df4f40> ('lifetime', '>')
[1714503219094] [INFO] HUB (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/lifetime }
[1714503219094] [DBG] MQTT enter <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ('>48:3f:da:55:07:5c/lifetime', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>)
[1714503219094] [DBG] MQTT exit <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ret: None
[1714503219094] [DBG] HUB exit <function HUB.subscribe_hub at 0x7f5e81df4f40> ret: None
[1714503219094] [DBG] HUB enter <function HUB.subscribe_hub at 0x7f5e81df4f40> ('error', '>')
[1714503219094] [INFO] HUB (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/error }
[1714503219094] [DBG] MQTT enter <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ('>48:3f:da:55:07:5c/error', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>)
[1714503219094] [DBG] MQTT exit <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ret: None
[1714503219094] [DBG] HUB exit <function HUB.subscribe_hub at 0x7f5e81df4f40> ret: None
[1714503219094] [DBG] HUB enter <function HUB.subscribe_hub at 0x7f5e81df4f40> ('ping', '>')
[1714503219094] [INFO] HUB (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/ping }
[1714503219094] [DBG] MQTT enter <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ('>48:3f:da:55:07:5c/ping', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>)
[1714503219094] [DBG] MQTT exit <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ret: None
[1714503219094] [DBG] HUB exit <function HUB.subscribe_hub at 0x7f5e81df4f40> ret: None
[1714503219094] [DBG] HUB enter <function HUB.subscribe_hub at 0x7f5e81df4f40> ('version', '>')
[1714503219094] [INFO] HUB (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/version }
[1714503219094] [DBG] MQTT enter <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ('>48:3f:da:55:07:5c/version', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>)
[1714503219094] [DBG] MQTT exit <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ret: None
[1714503219094] [DBG] HUB exit <function HUB.subscribe_hub at 0x7f5e81df4f40> ret: None
[1714503219094] [DBG] HUB exit <function HUB.__on_connect at 0x7f5e81df5800> ret: None
[1714503219094] [DBG] MQTT exit <function mqtt_transport.connect at 0x7f5e81ffde40> ret: None
[1714503219094] [DBG] HUB exit <function HUB.connect at 0x7f5e81df6700> ret: None
[1714503219105] [DBG] HUB Step, state: 0 (next: 1714503220105)
[1714503219105] [DBG] HUB enter <function HUB.__send_reg at 0x7f5e81df56c0> ()
[1714503219105] [INFO] HUB Publish reg token: 48:3f:da:55:07:5c
[1714503219105] [DBG] MQTT enter <function mqtt_transport.publish at 0x7f5e81ffdf80> ('/reg', '48:3f:da:55:07:5c')
[1714503219105] [DBG] MQTT exit <function mqtt_transport.publish at 0x7f5e81ffdf80> ret: None
[1714503219105] [DBG] HUB exit <function HUB.__send_reg at 0x7f5e81df56c0> ret: None
[1714503220114] [DBG] HUB Step, state: 0 (next: 1714503221114)
[1714503220115] [DBG] HUB enter <function HUB.__send_reg at 0x7f5e81df56c0> ()
[1714503220115] [INFO] HUB Publish reg token: 48:3f:da:55:07:5c
[1714503220115] [DBG] MQTT enter <function mqtt_transport.publish at 0x7f5e81ffdf80> ('/reg', '48:3f:da:55:07:5c')
[1714503220115] [DBG] MQTT exit <function mqtt_transport.publish at 0x7f5e81ffdf80> ret: None
[1714503220115] [DBG] HUB exit <function HUB.__send_reg at 0x7f5e81df56c0> ret: None
[1714503220158] [DBG] HUB enter <function HUB.__on_message at 0x7f5e81df5bc0> ('/48:3f:da:55:07:5c/time', b'1714503220157')
[1714503220158] [INFO] HUB Message: {topic: /48:3f:da:55:07:5c/time, value: 1714503220157 }
[1714503220158] [DBG] HUB enter <function HUB.__time_hnd at 0x7f5e81df5080> ('1714503220157',)
[1714503220158] [INFO] HUB Registration finish, time: 1714503220157
[1714503220158] [DBG] FW-UPD enter <function device_base.register at 0x7f5e81df42c0> (1714503220,)
[1714503220158] [INFO] FW-UPD Device 3996365522 registration on the server @ 1714503220
[1714503220158] [DBG] HUB enter <function HUB.subscribe_hub at 0x7f5e81df4f40> ('3996365522/#', '>')
[1714503220158] [INFO] HUB (HUB) Subscribe to : {topic: >48:3f:da:55:07:5c/3996365522/# }
[1714503220158] [DBG] MQTT enter <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ('>48:3f:da:55:07:5c/3996365522/#', <bound method log.dbg_wr.<locals>.wrapper of <uclient.hub.HUB object at 0x7f5e81c64cd0>>)
[1714503220158] [DBG] MQTT exit <function mqtt_transport.subscribe at 0x7f5e81ffe0c0> ret: None
[1714503220158] [DBG] HUB exit <function HUB.subscribe_hub at 0x7f5e81df4f40> ret: None
[1714503220158] [DBG] FW-UPD exit <function device_base.register at 0x7f5e81df42c0> ret: None
[1714503220158] [DBG] HUB exit <function HUB.__time_hnd at 0x7f5e81df5080> ret: None
[1714503220158] [DBG] HUB exit <function HUB.__on_message at 0x7f5e81df5bc0> ret: None
[1714503221120] [DBG] HUB Step, state: 1 (next: 1714503222120)
[1714503221120] [DBG] FW-UPD enter <function device_base.info_req at 0x7f5e81df4b80> ()
[1714503221120] [DBG] FW-UPD exit <function device_base.info_req at 0x7f5e81df4b80> ret: {'s': 3996365522, 't': 1, 'r': {0: 3996365522, 1: 1714503220}}
[1714503221121] [DBG] HUB enter <function HUB.pub_hub at 0x7f5e81df4e00> ('info', '{"mac": "48:3f:da:55:07:5c", "version": "R240430;master;38274a5b8a83c5fa684f71a958848479413f7085", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714503220}}]}', '/')
[1714503221121] [INFO] HUB Publish message: { topic: info, value: {"mac": "48:3f:da:55:07:5c", "version": "R240430;master;38274a5b8a83c5fa684f71a958848479413f7085", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714503220}}]} }
[1714503221121] [DBG] MQTT enter <function mqtt_transport.publish at 0x7f5e81ffdf80> ('/48:3f:da:55:07:5c/info', '{"mac": "48:3f:da:55:07:5c", "version": "R240430;master;38274a5b8a83c5fa684f71a958848479413f7085", "type": "mpy", "prot": 2, "devices": [{"s": 3996365522, "t": 1, "r": {"0": 3996365522, "1": 1714503220}}]}')
[1714503221121] [DBG] MQTT exit <function mqtt_transport.publish at 0x7f5e81ffdf80> ret: None
[1714503221121] [DBG] HUB exit <function HUB.pub_hub at 0x7f5e81df4e00> ret: None
Suite stderr:
2024-04-30 18:53:41,130 - TEST - INFO - OP take: 2035.29 ms
2024-04-30 18:53:41,133 - TEST - INFO - Connected with result code: 0
2024-04-30 18:53:41,141 - TEST - INFO - OP take: 10.091 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-04-30 18:53:42,152 - TEST - INFO - OP take: 10.121 ms
2024-04-30 18:53:42,153 - TEST - WARNING - Disconecting from broker