search

dresden-elektronik / deconz-rest-plugin

deCONZ REST-API plugin to control ZigBee devices
BSD 3-Clause "New" or "Revised" License
1.9k stars 502 forks source link

Cannot (re)join ZigBee network from deCONZ GUI after upgrading to v2.24.2 #7377

Closed jotzet79 closed 9 months ago

jotzet79 commented 11 months ago

Does the issue really belong here?

Is there already an existing issue for this?

Describe the bug

After the last update to v2.24.2 I detected that if I start the deCONZ GUI and then leave the network, I cannot join the ZigBee network / Devices anymore. Additionally I can no longer control my Fyrtur shades from Ikea via Homebridge & homebridge-hue plugin.

Steps to reproduce the behavior

Only a complete halt of the device, detaching the RaspBee II module, waiting 15 secs, attaching the module again, and booting up my Raspberry running on Raspberry OS 12 BookWorm.

Expected behavior

Pressing the leave button and joining the network by pressing the button again leads to the Controller joining the network immediately.

Screenshots

Screenshot 2023-11-24 at 12 23 15

Environment

deCONZ Logs

12:24:18:900 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:24:18:942 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:19:468 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:19:991 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:20:519 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:21:041 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:21:569 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:22:092 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:22:620 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:23:193 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:23:771 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:24:343 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:24:900 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:25:424 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:25:951 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:26:475 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:26:001 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:27:526 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:28:053 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:28:578 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:28:900 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:24:28:901 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:28:901 Idle timer triggered
12:24:29:106 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:29:400 add task 464 type 21 to 0x680AE2FFFECFD817 cluster 0x0004 req.id 66
12:24:29:475 Erase task req-id: 66, type: 21 zcl seqno: 4 send time 0, profileId: 0x0104, clusterId: 0x0004
12:24:29:680 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:30:257 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:30:830 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:31:400 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:31:924 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:32:451 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:32:975 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:33:503 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:33:899 DB save zll database items 0x00000001
12:24:33:900 DB saved in 0 ms
12:24:34:025 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:34:552 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:34:837 Websocket 192.168.1.3:35734 send message: {"attr":{"id":"1","lastannounced":null,"lastseen":"2023-11-24T11:24Z","manufacturername":"dresden elektronik","modelid":"RaspBee","name":"Configuration tool 1","swversion":"0x00000000","type":"Configuration tool","uniqueid":"00:21:2e:ff:ff:06:eb:73-01"},"e":"changed","id":"1","r":"lights","t":"event","uniqueid":"00:21:2e:ff:ff:06:eb:73-01"} (ret = 342)
12:24:35:075 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:35:602 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:36:175 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:36:752 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:37:257 Websocket 192.168.1.3:35734 send message: {"attr":{"id":"3","lastannounced":null,"lastseen":"2023-11-24T11:24Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI Signal Repeater","name":"JoSaHome ZigBee RangeExt 2","productid":"E1746","swversion":"2.3.086","type":"Range extender","uniqueid":"68:0a:e2:ff:fe:cf:d8:17-01"},"e":"changed","id":"3","r":"lights","t":"event","uniqueid":"68:0a:e2:ff:fe:cf:d8:17-01"} (ret = 373)
12:24:37:325 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:37:899 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:38:425 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:38:900 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:24:38:951 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:39:475 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:39:002 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:40:526 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:41:055 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:41:578 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:42:105 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:42:677 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:43:255 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:43:829 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:44:400 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:44:452 Websocket 192.168.1.3:35734 send message: {"attr":{"id":"2","lastannounced":null,"lastseen":"2023-11-24T11:24Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI Signal Repeater","name":"JoSaHome ZigBee RangeExt 1","productid":"E1746","swversion":"2.3.086","type":"Range extender","uniqueid":"68:0a:e2:ff:fe:c8:0b:d0-01"},"e":"changed","id":"2","r":"lights","t":"event","uniqueid":"68:0a:e2:ff:fe:c8:0b:d0-01"} (ret = 373)
12:24:44:926 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:45:453 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:45:977 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:46:505 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:47:028 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:47:554 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:48:079 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:48:606 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:48:899 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:24:49:180 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:49:759 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:50:332 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:50:900 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:51:425 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:51:951 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:52:474 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:52:001 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:53:525 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:54:053 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:54:576 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:55:103 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:55:678 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:56:254 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:56:829 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:57:399 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:57:924 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:58:452 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:58:899 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:24:58:900 poll node 58:8e:81:ff:fe:62:39:91-01
12:24:58:900 Idle timer triggered
12:24:58:975 poll node 58:8e:81:ff:fe:b4:63:96-01
12:24:59:501 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:00:024 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:00:552 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:01:074 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:01:602 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:02:176 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:02:753 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:03:325 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:03:899 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:04:424 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:04:953 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:05:476 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:05:002 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:06:525 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:07:052 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:07:575 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:08:103 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:08:343 APS-DATA.request id: 3, addrmode: 0x03, addr: 0x680AE2FFFEC80BD0, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:08:423 APS-DATA.confirm id: 3, status: 0x00 SUCCESS
12:25:08:424 APS-DATA.confirm request id: 3 -> confirmed, timeout 2340445
12:25:08:519 APS-DATA.indication srcAddr: 0xF324, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 244, rssi: -73
12:25:08:520 APS-DATA.indication request id: 3 -> finished
12:25:08:520 APS-DATA.request id: 3 erase from queue
12:25:08:675 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:08:900 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:25:09:253 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:09:825 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:10:400 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:10:744 APS-DATA.request id: 15, addrmode: 0x03, addr: 0x00212EFFFF06EB73, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:10:745    asdu (length: 2): |tB�
12:25:10:819 APS-DATA.confirm id: 15, status: 0x00 SUCCESS
12:25:10:820 APS-DATA.confirm request id: 15 -> confirmed, timeout 2342863
12:25:10:865 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 244, rssi: -73
12:25:10:866    asdu: 440002000173eb06ffff2e2100d00bc8feffe20a6824f3250101f0
12:25:10:866 APS-DATA.indication request id: 15 -> finished
12:25:10:867 APS-DATA.request id: 15 erase from queue
12:25:10:867 APS-DATA.request id: 17, addrmode: 0x03, addr: 0x00212EFFFF06EB73, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:10:868    asdu (length: 2): |tB�
12:25:10:903 APS-DATA.confirm id: 17, status: 0x00 SUCCESS
12:25:10:904 APS-DATA.confirm request id: 17 -> confirmed, timeout 2342969
12:25:10:924 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:10:949 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 244, rssi: -73
12:25:10:949    asdu: 450002010173eb06ffff2e210017d8cffeffe20a682f00250101fb
12:25:10:950 APS-DATA.indication request id: 17 -> finished
12:25:10:950 APS-DATA.request id: 17 erase from queue
12:25:11:451 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:11:974 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:12:502 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:13:025 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:13:143 APS-DATA.request id: 28, addrmode: 0x03, addr: 0x680AE2FFFECFD817, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:13:144    asdu (length: 2): |tB�
12:25:13:211 APS-DATA.confirm id: 28, status: 0x00 SUCCESS
12:25:13:212 APS-DATA.confirm request id: 28 -> confirmed, timeout 2345245
12:25:13:317 APS-DATA.indication srcAddr: 0x002F, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 255, rssi: -70
12:25:13:317    asdu: 460003000373eb06ffff2e210073eb06ffff2e210000000402006c73eb06ffff2e2100d00bc8feffe20a6824f325020f9773eb06ffff2e2100d63ef806008d150062451200028d
12:25:13:318 APS-DATA.indication request id: 28 -> finished
12:25:13:318 APS-DATA.request id: 28 erase from queue
12:25:13:552 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:14:076 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:14:603 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:15:176 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:16:292 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:17:799 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:17:899 Skip idle timer callback, too early: elapsed 626 msec
12:25:18:375 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:18:899 Daylight now: solarNoon, status: 170, daylight: 1, dark: 0
12:25:18:902 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:19:425 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:19:431 APS-DATA.request id: 51, addrmode: 0x03, addr: 0x680AE2FFFEC80BD0, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:19:432    asdu (length: 2): |tB�
12:25:19:611 APS-DATA.confirm id: 51, status: 0x00 SUCCESS
12:25:19:612 APS-DATA.confirm request id: 51 -> confirmed, timeout 2351533
12:25:19:728 APS-DATA.indication srcAddr: 0xF324, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 228, rssi: -73
12:25:19:729    asdu: 470005000373eb06ffff2e210073eb06ffff2e210000000402006073eb06ffff2e210017d8cffeffe20a682f0025020f9873eb06ffff2e21001422fafeffe20a68d2dd12000200
12:25:19:729 APS-DATA.indication request id: 51 -> finished
12:25:19:730 APS-DATA.request id: 51 erase from queue
12:25:19:952 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:20:475 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:20:002 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:21:525 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:21:831 APS-DATA.request id: 62, addrmode: 0x03, addr: 0x680AE2FFFEC80BD0, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:21:832    asdu (length: 2): |tB�
12:25:21:911 APS-DATA.confirm id: 62, status: 0x00 SUCCESS
12:25:21:912 APS-DATA.confirm request id: 62 -> confirmed, timeout 2353934
12:25:22:008 APS-DATA.indication srcAddr: 0xF324, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 232, rssi: -73
12:25:22:009    asdu: 480005030273eb06ffff2e21009663b4feff818e5811d11200026073eb06ffff2e2100913962feff818e58e72612000261
12:25:22:010 APS-DATA.indication request id: 62 -> finished
12:25:22:011 APS-DATA.request id: 62 erase from queue
12:25:22:052 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:22:577 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:23:511 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:23:899 Skip idle timer callback, too early: elapsed 388 msec
12:25:24:035 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:24:392 APS-DATA.request id: 72, addrmode: 0x03, addr: 0x00212EFFFF06EB73, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:24:393    asdu (length: 2): |tB�
12:25:24:435 APS-DATA.confirm id: 72, status: 0x00 SUCCESS
12:25:24:436 APS-DATA.confirm request id: 72 -> confirmed, timeout 2356494
12:25:24:481 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 232, rssi: -73
12:25:24:482    asdu: 490002000173eb06ffff2e2100d00bc8feffe20a6824f3250101f0
12:25:24:483 APS-DATA.indication request id: 72 -> finished
12:25:24:483 APS-DATA.request id: 72 erase from queue
12:25:24:484 APS-DATA.request id: 75, addrmode: 0x03, addr: 0x00212EFFFF06EB73, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:24:485    asdu (length: 2): |tB�
12:25:24:567 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:24:583 APS-DATA.confirm id: 75, status: 0x00 SUCCESS
12:25:24:584 APS-DATA.confirm request id: 75 -> confirmed, timeout 2356586
12:25:24:629 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 232, rssi: -73
12:25:24:629    asdu: 4a0002010173eb06ffff2e210017d8cffeffe20a682f00250101fc
12:25:24:630 APS-DATA.indication request id: 75 -> finished
12:25:24:630 APS-DATA.request id: 75 erase from queue
12:25:25:090 poll node 58:8e:81:ff:fe:62:39:91-01
12:25:25:617 poll node 58:8e:81:ff:fe:b4:63:96-01
12:25:26:791 APS-DATA.request id: 86, addrmode: 0x03, addr: 0x680AE2FFFECFD817, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:26:792    asdu (length: 2): |tB�
12:25:26:855 APS-DATA.confirm id: 86, status: 0x00 SUCCESS
12:25:26:856 APS-DATA.confirm request id: 86 -> confirmed, timeout 2358893
12:25:26:968 APS-DATA.indication srcAddr: 0x002F, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 248, rssi: -70
12:25:26:969    asdu: 4b0003000373eb06ffff2e210073eb06ffff2e210000000402006c73eb06ffff2e2100d00bc8feffe20a6824f325020f9773eb06ffff2e2100d63ef806008d150062451200028d
12:25:26:969 APS-DATA.indication request id: 86 -> finished
12:25:26:970 APS-DATA.request id: 86 erase from queue

Additional context

No response

phuongpham commented 11 months ago

Maybe similar to the problem I have? Can you try to remove and repair the device? I assume it could be some issue with the DDF file after the latest upgrade. Seems like the DDF files have been updated/changed on last build https://github.com/dresden-elektronik/deconz-rest-plugin/issues/7374

@ebaauw maybe you know something about this?

manup commented 11 months ago

Are you sure it's not connected? The logs show Zigbee commands being send and received:

12:25:24:392 APS-DATA.request id: 72, addrmode: 0x03, addr: 0x00212EFFFF06EB73, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:24:393    asdu (length: 2): |tB��
12:25:24:435 APS-DATA.confirm id: 72, status: 0x00 SUCCESS
12:25:24:436 APS-DATA.confirm request id: 72 -> confirmed, timeout 2356494
12:25:24:481 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 232, rssi: -73
12:25:24:482    asdu: 490002000173eb06ffff2e2100d00bc8feffe20a6824f3250101f0
jotzet79 commented 11 months ago

Maybe similar to the problem I have? Can you try to remove and repair the device? I assume it could be some issue with the DDF file after the latest upgrade. Seems like the DDF files have been updated/changed on last build #7374

@ebaauw maybe you know something about this?

Yeeees, thank you! Same issues here... Oddly enough after reconnect works now, the last 3 days it didn't (what was totally frustrating)!?

So overall 2-3 problems remain -> 2 seem to be your issues too (re)solved?

phuongpham commented 11 months ago

@jotzet79 Thanks for testing this out. So with the latest build it actually breaks re-adding fyrtur blinds. Would be interesting if this problem also occur for other IKEA devices or any zigbee devices.

Anyway we just need to wait for some developers that can help us here :)

jotzet79 commented 11 months ago

Yeah, I agree, would be interesting if ti breaks other devices too - but then I think the number of issues would have been much higher. Anyway thanks for linking my issue to yours. I think we then could close this issue (as long as the Leave/Rejoin Network problems go away) in favour of yours.

jotzet79 commented 11 months ago

Are you sure it's not connected? The logs show Zigbee commands being send and received:

12:25:24:392 APS-DATA.request id: 72, addrmode: 0x03, addr: 0x00212EFFFF06EB73, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
12:25:24:393  asdu (length: 2): |tB��
12:25:24:435 APS-DATA.confirm id: 72, status: 0x00 SUCCESS
12:25:24:436 APS-DATA.confirm request id: 72 -> confirmed, timeout 2356494
12:25:24:481 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 232, rssi: -73
12:25:24:482  asdu: 490002000173eb06ffff2e2100d00bc8feffe20a6824f3250101f0

Dear Manuel, yeah thanks - the initial output was copied, when I did not execute the "leave" (because I thought that this trace already gives hints if sth is wrong). However now I have reconnect issues again:

23:04:07:685 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:08:264 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:08:837 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:08:898 try to reconnect to network try=6
23:04:08:929 [Master] read param with arg 0x18
23:04:08:930 [Master] read param with arg 0x13
23:04:08:930 [Master] read param with arg 0x13
23:04:08:930 [Master] read param with arg 0x13
23:04:08:962 Device protocol version: 0x010E
23:04:09:077 [Master] read param with arg 0x19
23:04:09:221 CTRL ANT_CTRL 0x03
23:04:09:237 CTRL ZDP_RESPONSE handler 0x0001
23:04:09:437 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:09:481 CTRL read param resp id: 0x13, status: 0x04
23:04:09:541 Device protocol version: 0x010E
23:04:09:964 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:10:487 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:11:015 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:11:538 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:11:898 Daylight now: nightStart, status: 230, daylight: 0, dark: 1
23:04:12:065 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:12:588 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:12:761 [Master] read param with arg 0x18
23:04:12:762 [Master] read param with arg 0x13
23:04:12:762 [Master] read param with arg 0x13
23:04:12:762 [Master] read param with arg 0x13
23:04:12:794 Device protocol version: 0x010E
23:04:12:925 [Master] read param with arg 0x19
23:04:13:069 CTRL ANT_CTRL 0x03
23:04:13:085 CTRL ZDP_RESPONSE handler 0x0001
23:04:13:145 CTRL read param resp id: 0x13, status: 0x04
23:04:13:218 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:13:225 Device protocol version: 0x010E
23:04:13:521 [Master] read param with arg 0x18
23:04:13:522 [Master] read param with arg 0x13
23:04:13:522 [Master] read param with arg 0x13
23:04:13:522 [Master] read param with arg 0x13
23:04:13:550 Device protocol version: 0x010E
23:04:13:681 [Master] read param with arg 0x19
23:04:13:791 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:13:825 CTRL ANT_CTRL 0x03
23:04:13:841 CTRL ZDP_RESPONSE handler 0x0001
23:04:13:898 try to reconnect to network try=7
23:04:13:901 CTRL read param resp id: 0x13, status: 0x04
23:04:13:961 Device protocol version: 0x010E
23:04:14:113 [Master] read param with arg 0x18
23:04:14:114 [Master] read param with arg 0x13
23:04:14:114 [Master] read param with arg 0x13
23:04:14:115 [Master] read param with arg 0x13
23:04:14:150 Device protocol version: 0x010E
23:04:14:485 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:14:561 [Master] read param with arg 0x19
23:04:14:705 CTRL ANT_CTRL 0x03
23:04:14:721 CTRL ZDP_RESPONSE handler 0x0001
23:04:14:781 CTRL read param resp id: 0x13, status: 0x04
23:04:14:861 Device protocol version: 0x010E
23:04:15:011 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:15:054 [Master] read param with arg 0x18
23:04:15:054 [Master] read param with arg 0x13
23:04:15:054 [Master] read param with arg 0x13
23:04:15:055 [Master] read param with arg 0x13
23:04:15:082 Device protocol version: 0x010E
23:04:15:505 [Master] read param with arg 0x19
23:04:15:535 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:15:653 CTRL ANT_CTRL 0x03
23:04:15:669 CTRL ZDP_RESPONSE handler 0x0001
23:04:15:729 CTRL read param resp id: 0x13, status: 0x04
23:04:15:809 Device protocol version: 0x010E
23:04:16:062 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:16:238 [Master] read param with arg 0x18
23:04:16:239 [Master] read param with arg 0x13
23:04:16:239 [Master] read param with arg 0x13
23:04:16:240 [Master] read param with arg 0x13
23:04:16:266 Device protocol version: 0x010E
23:04:16:517 [Master] read param with arg 0x19
23:04:16:585 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:16:661 CTRL ANT_CTRL 0x03
23:04:16:677 CTRL ZDP_RESPONSE handler 0x0001
23:04:16:737 CTRL read param resp id: 0x13, status: 0x04
23:04:16:817 Device protocol version: 0x010E
23:04:17:113 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:17:346 [Master] read param with arg 0x18
23:04:17:346 [Master] read param with arg 0x13
23:04:17:347 [Master] read param with arg 0x13
23:04:17:347 [Master] read param with arg 0x13
23:04:17:378 Device protocol version: 0x010E
23:04:17:589 [Master] read param with arg 0x19
23:04:17:685 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:17:733 CTRL ANT_CTRL 0x03
23:04:17:749 CTRL ZDP_RESPONSE handler 0x0001
23:04:17:813 CTRL read param resp id: 0x13, status: 0x04
23:04:17:893 Device protocol version: 0x010E
23:04:18:262 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:18:836 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:18:890 [Master] read param with arg 0x18
23:04:18:891 [Master] read param with arg 0x13
23:04:18:891 [Master] read param with arg 0x13
23:04:18:892 [Master] read param with arg 0x13
23:04:18:926 Device protocol version: 0x010E
23:04:19:057 [Master] read param with arg 0x19
23:04:19:201 CTRL ANT_CTRL 0x03
23:04:19:217 CTRL ZDP_RESPONSE handler 0x0001
23:04:19:281 CTRL read param resp id: 0x13, status: 0x04
23:04:19:361 Device protocol version: 0x010E
23:04:19:397 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:19:923 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:19:941 [Master] read param with arg 0x18
23:04:19:942 [Master] read param with arg 0x13
23:04:19:942 [Master] read param with arg 0x13
23:04:19:943 [Master] read param with arg 0x13
23:04:19:978 Device protocol version: 0x010E
23:04:20:109 [Master] read param with arg 0x19
23:04:20:253 CTRL ANT_CTRL 0x03
23:04:20:269 CTRL ZDP_RESPONSE handler 0x0001
23:04:20:329 CTRL read param resp id: 0x13, status: 0x04
23:04:20:409 Device protocol version: 0x010E
23:04:20:453 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:20:977 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:21:504 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:21:899 Daylight now: nightStart, status: 230, daylight: 0, dark: 1
23:04:22:027 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:22:554 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:23:078 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:23:604 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:23:897 try to reconnect to network try=8
23:04:23:926 [Master] read param with arg 0x18
23:04:23:926 [Master] read param with arg 0x13
23:04:23:926 [Master] read param with arg 0x13
23:04:23:927 [Master] read param with arg 0x13
23:04:23:958 Device protocol version: 0x010E
23:04:24:077 [Master] read param with arg 0x19
23:04:24:438 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:24:537 CTRL ANT_CTRL 0x03
23:04:24:553 CTRL ZDP_RESPONSE handler 0x0001
23:04:24:613 CTRL read param resp id: 0x13, status: 0x04
23:04:24:693 Device protocol version: 0x010E
23:04:24:965 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:25:489 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:26:368 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:26:897 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:27:423 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:27:950 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:28:475 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:28:898 try to reconnect to network try=9
23:04:28:926 [Master] read param with arg 0x18
23:04:28:926 [Master] read param with arg 0x13
23:04:28:926 [Master] read param with arg 0x13
23:04:28:927 [Master] read param with arg 0x13
23:04:28:958 Device protocol version: 0x010E
23:04:29:009 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:29:069 [Master] read param with arg 0x19
23:04:29:213 CTRL ANT_CTRL 0x03
23:04:29:229 CTRL ZDP_RESPONSE handler 0x0001
23:04:29:289 CTRL read param resp id: 0x13, status: 0x04
23:04:29:369 Device protocol version: 0x010E
23:04:29:531 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:30:058 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:30:582 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:31:109 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:31:342 [Master] read param with arg 0x18
23:04:31:343 [Master] read param with arg 0x13
23:04:31:344 [Master] read param with arg 0x13
23:04:31:344 [Master] read param with arg 0x13
23:04:31:379 Device protocol version: 0x010E
23:04:31:509 [Master] read param with arg 0x19
23:04:31:673 CTRL ANT_CTRL 0x03
23:04:31:683 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:31:689 CTRL ZDP_RESPONSE handler 0x0001
23:04:31:749 CTRL read param resp id: 0x13, status: 0x04
23:04:31:829 Device protocol version: 0x010E
23:04:31:900 Daylight now: nightStart, status: 230, daylight: 0, dark: 1
23:04:32:259 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:32:321 [Master] read param with arg 0x18
23:04:32:322 [Master] read param with arg 0x13
23:04:32:322 [Master] read param with arg 0x13
23:04:32:323 [Master] read param with arg 0x13
23:04:32:350 Device protocol version: 0x010E
23:04:32:485 [Master] read param with arg 0x19
23:04:32:629 CTRL ANT_CTRL 0x03
23:04:32:645 CTRL ZDP_RESPONSE handler 0x0001
23:04:32:705 CTRL read param resp id: 0x13, status: 0x04
23:04:32:785 Device protocol version: 0x010E
23:04:32:832 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:33:218 [Master] read param with arg 0x18
23:04:33:218 [Master] read param with arg 0x13
23:04:33:218 [Master] read param with arg 0x13
23:04:33:219 [Master] read param with arg 0x13
23:04:33:250 Device protocol version: 0x010E
23:04:33:381 [Master] read param with arg 0x19
23:04:33:397 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:33:533 CTRL ANT_CTRL 0x03
23:04:33:549 CTRL ZDP_RESPONSE handler 0x0001
23:04:33:609 CTRL read param resp id: 0x13, status: 0x04
23:04:33:689 Device protocol version: 0x010E
23:04:33:897 try to reconnect to network try=10
23:04:33:922 [Master] read param with arg 0x18
23:04:33:922 [Master] read param with arg 0x13
23:04:33:922 [Master] read param with arg 0x13
23:04:33:923 [Master] read param with arg 0x13
23:04:33:928 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:33:954 Device protocol version: 0x010E
23:04:34:105 [Master] read param with arg 0x19
23:04:34:249 CTRL ANT_CTRL 0x03
23:04:34:265 CTRL ZDP_RESPONSE handler 0x0001
23:04:34:325 CTRL read param resp id: 0x13, status: 0x04
23:04:34:405 Device protocol version: 0x010E
23:04:34:455 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:34:580 [Master] read param with arg 0x18
23:04:34:581 [Master] read param with arg 0x13
23:04:34:582 [Master] read param with arg 0x13
23:04:34:582 [Master] read param with arg 0x13
23:04:34:606 Device protocol version: 0x010E
23:04:34:957 [Master] read param with arg 0x19
23:04:34:978 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:35:101 CTRL ANT_CTRL 0x03
23:04:35:117 CTRL ZDP_RESPONSE handler 0x0001
23:04:35:177 CTRL read param resp id: 0x13, status: 0x04
23:04:35:257 Device protocol version: 0x010E
23:04:35:490 [Master] read param with arg 0x18
23:04:35:490 [Master] read param with arg 0x13
23:04:35:490 [Master] read param with arg 0x13
23:04:35:491 [Master] read param with arg 0x13
23:04:35:535 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:35:537 Device protocol version: 0x010E
23:04:35:937 [Master] read param with arg 0x19
23:04:36:081 CTRL ANT_CTRL 0x03
23:04:36:097 CTRL ZDP_RESPONSE handler 0x0001
23:04:36:165 CTRL read param resp id: 0x13, status: 0x04
23:04:36:225 Device protocol version: 0x010E
23:04:36:398 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:36:454 [Master] read param with arg 0x18
23:04:36:455 [Master] read param with arg 0x13
23:04:36:455 [Master] read param with arg 0x13
23:04:36:456 [Master] read param with arg 0x13
23:04:36:490 Device protocol version: 0x010E
23:04:36:922 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:36:982 [Master] read param with arg 0x19
23:04:37:125 CTRL ANT_CTRL 0x03
23:04:37:141 CTRL ZDP_RESPONSE handler 0x0001
23:04:37:205 CTRL read param resp id: 0x13, status: 0x04
23:04:37:285 Device protocol version: 0x010E
23:04:37:438 [Master] read param with arg 0x18
23:04:37:438 [Master] read param with arg 0x13
23:04:37:439 [Master] read param with arg 0x13
23:04:37:439 [Master] read param with arg 0x13
23:04:37:459 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:37:474 Device protocol version: 0x010E
23:04:37:961 [Master] read param with arg 0x19
23:04:38:109 CTRL ANT_CTRL 0x03
23:04:38:125 CTRL ZDP_RESPONSE handler 0x0001
23:04:38:185 CTRL read param resp id: 0x13, status: 0x04
23:04:38:265 Device protocol version: 0x010E
23:04:38:374 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:38:422 [Master] read param with arg 0x18
23:04:38:423 [Master] read param with arg 0x13
23:04:38:423 [Master] read param with arg 0x13
23:04:38:424 [Master] read param with arg 0x13
23:04:38:501 Device protocol version: 0x010E
23:04:38:898 reconnect network failed, try later
23:04:38:899 networkState: CC_ReconnectNetwork
23:04:38:899 start reconnect to network
23:04:38:948 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:38:961 [Master] read param with arg 0x19
23:04:39:105 CTRL ANT_CTRL 0x03
23:04:39:121 CTRL ZDP_RESPONSE handler 0x0001
23:04:39:181 CTRL read param resp id: 0x13, status: 0x04
23:04:39:895 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:39:965 Device protocol version: 0x010E
23:04:40:423 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:40:949 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:41:473 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:41:897 Daylight now: nightStart, status: 230, daylight: 0, dark: 1
23:04:41:000 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:42:524 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:43:052 poll node 58:8e:81:ff:fe:b4:63:96-01
23:04:43:898 try to reconnect to network try=1
23:04:43:963 Device protocol version: 0x010E
23:04:44:221 CTRL ANT_CTRL 0x03
23:04:44:237 CTRL ZDP_RESPONSE handler 0x0001
23:04:44:377 Device protocol version: 0x010E
phuongpham commented 11 months ago

@jotzet79 Can you verify if you get same problem after upgrading to 2.24.2?

https://github.com/dresden-elektronik/deconz-rest-plugin/issues/7380 Please comment the results inside the issue #7380

jotzet79 commented 11 months ago

@jotzet79 Can you verify if you get same problem after upgrading to 2.24.2?

7380 Please comment the results inside the issue #7380

I guess this is then part of the closed source software (I guess all the GUI logic). However I am not using Desktop environment on my Raspberry.

manup commented 10 months ago

I guess this is then part of the closed source software (I guess all the GUI logic).

Spoiler alert: deCONZ will be open sourced fully once the cleanup is done :)

For the rejoin issue I'll do some more tests.

We have retested the Fyrtur now, initially it didn't work, the device was only visible in deCONZ and could be controlled from there via clusters. After a way too long while the configuration was done anyway and it popped up in Phoscon App and could be controlled there.

I'm not sure yet what is causing this delay.

jotzet79 commented 10 months ago

I guess this is then part of the closed source software (I guess all the GUI logic).

Spoiler alert: deCONZ will be open sourced fully once the cleanup is done :)

For the rejoin issue I'll do some more tests.

We have retested the Fyrtur now, initially it didn't work, the device was only visible in deCONZ and could be controlled from there via clusters. After a way too long while the configuration was done anyway and it popped up in Phoscon App and could be controlled there.

I'm not sure yet what is causing this delay.

Hi @manup, Hmm there must be some other interference now, my first guess that it has to do with the latest update was maybe wrong: also with the last firmware v2.23.2 disconnects happen, and devices become not reachable (over night).

github-actions[bot] commented 10 months ago

As there has not been any response in 21 days, this issue has been automatically marked as stale. At OP: Please either close this issue or keep it active It will be closed in 7 days if no further activity occurs.

github-actions[bot] commented 9 months ago

As there has not been any response in 28 days, this issue will be closed. @ OP: If this issue is solved post what fixed it for you. If it is not solved, request to get this opened again.