search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
73.49k stars 30.71k forks source link

ZHA "Reconfigure Device" causing zigpy modules logs level to switch to debug until next core reboot #49124

Closed stickpin closed 3 years ago

stickpin commented 3 years ago

The problem

Hi,

Since the upgrade to core-2021.4.x, ZHA "Reconfigure Device" causing zigpy modules logs level to switch to debug until next core reboot.

Is there a way to switch it back to info level without core reboot?

Thanks in advance!

What is version of Home Assistant Core has the issue?

core-2021.4.3

What was the last working version of Home Assistant Core?

core-2021.3.4

What type of installation are you running?

Home Assistant OS

Integration causing the issue

ZHA

Link to integration documentation on our website

https://www.home-assistant.io/integrations/zha/

Example YAML snippet

No response

Anything in the logs that might be useful for us?

No response

probot-home-assistant[bot] commented 3 years ago

Hey there @dmulcahey, @adminiuga, mind taking a look at this issue as its been labeled with an integration (zha) you are listed as a codeowner for? Thanks! (message by CodeOwnersMention)

dmulcahey commented 3 years ago

This should snap back to what it was when the dialog is dismissed... is that not the case? I’ll take a look at this later tonight.

stickpin commented 3 years ago

@dmulcahey, unfortunately, does not work, have tried multiple times with different devices.

MattWestb commented 3 years ago

I was updating my production system and it was working OK. One of my test instances was not doing the upgrade OK and was rolling back the 2021.03 and i was getting problems with the fast long pull time that is fixes in 2021.4 but was making i must doing reconfigure on all IKEA remotes in the test setup after getting 2021.4. The first was looking OK but all after was not working at all and only one very long list that was not updating. I was by mistake doing on reconfigure on one IKEA light and it was not executed (no feedback with blinking then is was finished).

Now the strange things is starting. Little now and then the IKEA light was blinking and i was looking in the HA long and ZHA was doing one reconfig of 5 devices (test remotes and the light). And some hours later it was doing the same. And some hours . . . .

In the end i was restarting HA some times and it was starting working more normal (not repeating the reconfig for all 5 devices) and i can do reconfing on one device without problems.

I dont knowing wot was doing the strange things but in the end its working OK and i have not testing if i can reproducing it.

One think that can have making it going in looping mode is that i was rejoining some of the devices for getting the long pull time being corrected in the device after the false "downgrading" but i dont being sure if it was that or some thing else.

But im very happy with the function is implanted and done need sniffing the zigbee traffic for being sure its was being done :-)))

Adminiuga commented 3 years ago

Can you post your logging: component configuration?

MattWestb commented 3 years ago

My in the test container is:

logger:
  default: info
  logs:
    homeassistant.core: info
    homeassistant.components.zha: debug
    zigpy: debug
    zigpy.zdo: debug
    zigpy.application: debug
    bellows.ezsp: debug
    bellows.ezsp.protocol: debug
    bellows.zigbee.application: debug
#    bellows.uart: debug

#    zigpy_cc: debug
#    zigpy_deconz.zigbee.application: debug
#    zigpy_deconz.api: debug
#    zigpy_xbee.zigbee.application: debug
#    zigpy_xbee.api: debug
#    zigpy_zigate: debug
#    zhaquirks: debug
stickpin commented 3 years ago

@Adminiuga mine:

logger:
  default: info
  logs:
    pyhap.hap_server: warning
dmulcahey commented 3 years ago

FYI working on a fix for this that will remove the logging altogether...

https://user-images.githubusercontent.com/1335687/115022834-f1d76500-9e8b-11eb-8be4-3c199f08b29f.mov

MattWestb commented 3 years ago

I was having on drained battery in my IKEA remote in my test network and changing it yesterday and was doing on repairing and reconfigure that was not looking nice and very long but i was seen it was sending to the remote.

To day i was sniffing how MG21 and MG22 is (not) doing IEE802.15.4 ack and then ZHA was staring doing one reconfigure of the remote without it was "online" and it was not being dont in on check in from the device so it was sleeping and not receiving any commands (that is expected if not waking it up).

No. Time    Protocol    Zigbee Src  Zigbee Dst  IEEE Src    IEEE Dst    Group Nr    ZBN Seq ZBA Seq ZDP Seq Nwk Seq Info
1637    08:17:06,150504 ZigBee  0x2646  Broadcast   0x2646  0xffff      126         72  Many-to-One Route Request, Dst: 0xfffc, Src: 0x2646
1638    08:17:07,379251 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      105 104     9   ZCL Touchlink: Get Group Identifiers Request, Seq: 136[Malformed Packet]
1639    08:17:07,381114 IEEE 802.15.4   N/A N/A                         9   Ack
1640    08:17:07,522382 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      107 105 138 10  Bind Request, Poll Control (Cluster ID: 0x0020) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1641    08:17:07,524217 IEEE 802.15.4   N/A N/A                         10  Ack
1642    08:17:07,646502 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      109 106 140 11  Bind Request, Power Configuration (Cluster ID: 0x0001) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1643    08:17:07,648394 IEEE 802.15.4   N/A N/A                         11  Ack
1644    08:17:07,774020 ZigBee  0x69b5  Broadcast   0x69b5  0xffff      233         193 Link Status
1645    08:17:08,986028 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      110 104     12  ZCL Touchlink: Get Group Identifiers Request, Seq: 136[Malformed Packet]
1646    08:17:08,987797 IEEE 802.15.4   N/A N/A                         12  Ack
1647    08:17:09,142196 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      111 105 138 13  Bind Request, Poll Control (Cluster ID: 0x0020) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1648    08:17:09,158160 IEEE 802.15.4   N/A N/A                         13  Ack
1649    08:17:09,255048 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      112 106 140 14  Bind Request, Power Configuration (Cluster ID: 0x0001) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1650    08:17:09,256527 IEEE 802.15.4   N/A N/A                         14  Ack
1651    08:17:10,600540 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      113 104     15  ZCL Touchlink: Get Group Identifiers Request, Seq: 136[Malformed Packet]
1652    08:17:10,602827 IEEE 802.15.4   N/A N/A                         15  Ack
1653    08:17:10,742173 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      114 105 138 16  Bind Request, Poll Control (Cluster ID: 0x0020) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1654    08:17:10,743693 IEEE 802.15.4   N/A N/A                         16  Ack
1655    08:17:10,866441 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      115 106 140 17  Bind Request, Power Configuration (Cluster ID: 0x0001) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1656    08:17:10,867990 IEEE 802.15.4   N/A N/A                         17  Ack
1657    08:17:12,210574 ZigBee  0x0000  Broadcast   0x0000  0xffff      116         18  Route Request, Dst: 0x4b21, Src: 0x0000
1658    08:17:12,233211 ZigBee  0x2646  0x0000  0x2646  0x0000      130         73  Route Reply, Dst: 0x4b21, Src: 0x0000
1659    08:17:12,234785 IEEE 802.15.4   N/A N/A                         73  Ack
1660    08:17:12,237679 ZigBee  0x0000  Broadcast   0xefd5  0xffff      116         53  Route Request, Dst: 0x4b21, Src: 0x0000
1661    08:17:12,245181 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      116         38  Route Request, Dst: 0x4b21, Src: 0x0000
1662    08:17:12,252239 ZigBee  0x0000  Broadcast   0x69b5  0xffff      116         194 Route Request, Dst: 0x4b21, Src: 0x0000
1663    08:17:12,340039 ZigBee  0x0000  Broadcast   0x5550  0xffff      116         66  Route Request, Dst: 0x4b21, Src: 0x0000
1664    08:17:12,400772 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      118 107 142 19  Bind Request, Basic (Cluster ID: 0x0000) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1665    08:17:12,402960 IEEE 802.15.4   N/A N/A                         19  Ack
1666    08:17:12,491451 ZigBee  0x0000  Broadcast   0x0000  0xffff      116         20  Route Request, Dst: 0x4b21, Src: 0x0000
1667    08:17:12,546177 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      120 108     21  ZCL: Write Attributes, Seq: 144
1668    08:17:12,547881 IEEE 802.15.4   N/A N/A                         21  Ack
1669    08:17:12,580659 ZigBee  0x0000  Broadcast   0xefd5  0xffff      116         54  Route Request, Dst: 0x4b21, Src: 0x0000
1670    08:17:12,587635 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      116         39  Route Request, Dst: 0x4b21, Src: 0x0000
1671    08:17:12,616636 ZigBee  0x0000  Broadcast   0x69b5  0xffff      116         195 Route Request, Dst: 0x4b21, Src: 0x0000
1672    08:17:12,634771 ZigBee  0x2646  0x0000  0x2646  0x0000      132         74  Route Reply, Dst: 0x4b21, Src: 0x0000
1673    08:17:12,636860 IEEE 802.15.4   N/A N/A                         74  Ack
1674    08:17:12,684543 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      122 109     22  ZCL: Configure Reporting, Seq: 146
1675    08:17:12,685952 IEEE 802.15.4   N/A N/A                         22  Ack
1676    08:17:12,725658 ZigBee  0x0000  Broadcast   0x5550  0xffff      116         67  Route Request, Dst: 0x4b21, Src: 0x0000
1677    08:17:12,933442 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      116         40  Route Request, Dst: 0x4b21, Src: 0x0000
1678    08:17:12,943210 ZigBee  0x0000  Broadcast   0xefd5  0xffff      116         55  Route Request, Dst: 0x4b21, Src: 0x0000
1679    08:17:12,951758 ZigBee  0x0000  Broadcast   0x69b5  0xffff      116         196 Route Request, Dst: 0x4b21, Src: 0x0000
1680    08:17:13,039625 ZigBee  0x2646  0x0000  0x2646  0x0000      134         75  Route Reply, Dst: 0x4b21, Src: 0x0000
1681    08:17:13,041148 IEEE 802.15.4   N/A N/A                         75  Ack
1682    08:17:13,106633 ZigBee  0x0000  Broadcast   0x0000  0xffff      116         24  Route Request, Dst: 0x4b21, Src: 0x0000
1683    08:17:13,115211 ZigBee  0x0000  Broadcast   0x5550  0xffff      116         68  Route Request, Dst: 0x4b21, Src: 0x0000
1684    08:17:14,010967 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      123 107 142 25  Bind Request, Basic (Cluster ID: 0x0000) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1685    08:17:14,013840 IEEE 802.15.4   N/A N/A                         25  Ack
1686    08:17:14,085551 ZigBee  0x0000  Broadcast   0x0000  0xffff      124         26  Link Status
1687    08:17:14,154689 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      125 108     27  ZCL: Write Attributes, Seq: 144
1688    08:17:14,156042 IEEE 802.15.4   N/A N/A                         27  Ack
1689    08:17:14,293691 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      126 109     28  ZCL: Configure Reporting, Seq: 146
1690    08:17:14,296080 IEEE 802.15.4   N/A N/A                         28  Ack
1691    08:17:15,064599 ZigBee  0x2646  0x0000  0x2646  0x0000      135         76  Network Status, 0x4b21: Indirect Transaction Expiry
1692    08:17:15,072025 ZigBee  0x2646  0x0000  0x2646  0x0000      135         76  Network Status, 0x4b21: Indirect Transaction Expiry
1693    08:17:15,073846 IEEE 802.15.4   N/A N/A                         76  Ack
1694    08:17:15,203960 ZigBee  0x2646  0x0000  0x2646  0x0000      136         77  Network Status, 0x4b21: Indirect Transaction Expiry
1695    08:17:15,205842 IEEE 802.15.4   N/A N/A                         77  Ack
1696    08:17:15,331453 ZigBee  0x2646  0x0000  0x2646  0x0000      137         78  Network Status, 0x4b21: Indirect Transaction Expiry
1697    08:17:15,334004 IEEE 802.15.4   N/A N/A                         78  Ack
1698    08:17:15,620856 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      127 107 142 29  Bind Request, Basic (Cluster ID: 0x0000) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1699    08:17:15,622572 IEEE 802.15.4   N/A N/A                         29  Ack
1700    08:17:15,763961 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      128 108     30  ZCL: Write Attributes, Seq: 144
1701    08:17:15,765610 IEEE 802.15.4   N/A N/A                         30  Ack
1702    08:17:15,905713 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      129 109     31  ZCL: Configure Reporting, Seq: 146
1703    08:17:15,909289 IEEE 802.15.4   N/A N/A                         31  Ack
1704    08:17:16,675971 ZigBee  0x2646  0x0000  0x2646  0x0000      138         79  Network Status, 0x4b21: Indirect Transaction Expiry
1705    08:17:16,677783 IEEE 802.15.4   N/A N/A                         79  Ack
1706    08:17:16,818908 ZigBee  0x2646  0x0000  0x2646  0x0000      139         80  Network Status, 0x4b21: Indirect Transaction Expiry
1707    08:17:16,820866 IEEE 802.15.4   N/A N/A                         80  Ack
1708    08:17:16,939817 ZigBee  0x2646  0x0000  0x2646  0x0000      140         81  Network Status, 0x4b21: Indirect Transaction Expiry
1709    08:17:16,943639 IEEE 802.15.4   N/A N/A                         81  Ack
1710    08:17:17,231579 ZigBee  0x0000  Broadcast   0x0000  0xffff      130         32  Route Request, Dst: 0x4b21, Src: 0x0000
1711    08:17:17,244888 ZigBee  0x2646  0x0000  0x2646  0x0000      143         82  Route Reply, Dst: 0x4b21, Src: 0x0000
1712    08:17:17,249864 IEEE 802.15.4   N/A N/A                         82  Ack
1713    08:17:17,259529 ZigBee  0x0000  Broadcast   0x69b5  0xffff      130         197 Route Request, Dst: 0x4b21, Src: 0x0000
1714    08:17:17,263661 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      130         41  Route Request, Dst: 0x4b21, Src: 0x0000
1715    08:17:17,275861 ZigBee  0x0000  Broadcast   0xefd5  0xffff      130         56  Route Request, Dst: 0x4b21, Src: 0x0000
1716    08:17:17,297491 ZigBee  0x0000  Broadcast   0x5550  0xffff      130         69  Route Request, Dst: 0x4b21, Src: 0x0000
1717    08:17:17,385473 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      132 110 148 33  Bind Request, Level Control (Cluster ID: 0x0008) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1718    08:17:17,387374 IEEE 802.15.4   N/A N/A                         33  Ack
1719    08:17:17,525723 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      134 111 150 34  Bind Request, On/Off (Cluster ID: 0x0006) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1720    08:17:17,528223 IEEE 802.15.4   N/A N/A                         34  Ack
1721    08:17:17,535363 ZigBee  0x0000  Broadcast   0x0000  0xffff      130         35  Route Request, Dst: 0x4b21, Src: 0x0000
1722    08:17:17,565765 ZigBee  0x0000  Broadcast   0x5550  0xffff      130         70  Route Request, Dst: 0x4b21, Src: 0x0000
1723    08:17:17,580641 ZigBee  0x0000  Broadcast   0xefd5  0xffff      130         57  Route Request, Dst: 0x4b21, Src: 0x0000
1724    08:17:17,587626 ZigBee  0x0000  Broadcast   0x69b5  0xffff      130         198 Route Request, Dst: 0x4b21, Src: 0x0000
1725    08:17:17,650871 ZigBee  0x2646  0x0000  0x2646  0x0000      145         83  Route Reply, Dst: 0x4b21, Src: 0x0000
1726    08:17:17,652733 IEEE 802.15.4   N/A N/A                         83  Ack
1727    08:17:17,662049 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      136 112     36  ZCL: Configure Reporting, Seq: 152
1728    08:17:17,663690 IEEE 802.15.4   N/A N/A                         36  Ack
1729    08:17:17,862929 ZigBee  0x0000  Broadcast   0x69b5  0xffff      130         199 Route Request, Dst: 0x4b21, Src: 0x0000
1730    08:17:17,869922 ZigBee  0x0000  Broadcast   0xefd5  0xffff      130         58  Route Request, Dst: 0x4b21, Src: 0x0000
1731    08:17:17,880872 ZigBee  0x0000  Broadcast   0x5550  0xffff      130         71  Route Request, Dst: 0x4b21, Src: 0x0000
1732    08:17:17,950224 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      130         43  Route Request, Dst: 0x4b21, Src: 0x0000
1733    08:17:18,059129 ZigBee  0x2646  0x0000  0x2646  0x0000      147         84  Route Reply, Dst: 0x4b21, Src: 0x0000
1734    08:17:18,061720 IEEE 802.15.4   N/A N/A                         84  Ack
1735    08:17:18,270880 ZigBee  0x0000  Broadcast   0x0000  0xffff      130         38  Route Request, Dst: 0x4b21, Src: 0x0000
1736    08:17:18,287845 ZigBee  0x2646  0x0000  0x2646  0x0000      148         85  Network Status, 0x4b21: Indirect Transaction Expiry
1737    08:17:18,290649 IEEE 802.15.4   N/A N/A                         85  Ack
1738    08:17:18,428066 ZigBee  0x2646  0x0000  0x2646  0x0000      149         86  Network Status, 0x4b21: Indirect Transaction Expiry
1739    08:17:18,434956 ZigBee  0x2646  0x0000  0x2646  0x0000      149         86  Network Status, 0x4b21: Indirect Transaction Expiry
1740    08:17:18,436970 IEEE 802.15.4   N/A N/A                         86  Ack
1741    08:17:18,550824 ZigBee  0x2646  0x0000  0x2646  0x0000      150         87  Network Status, 0x4b21: Indirect Transaction Expiry
1742    08:17:18,552585 IEEE 802.15.4   N/A N/A                         87  Ack
1743    08:17:18,812567 ZigBee  0x5550  Broadcast   0x5550  0xffff      62          72  Link Status
1744    08:17:18,993560 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      137 110 148 39  Bind Request, Level Control (Cluster ID: 0x0008) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1745    08:17:18,995311 IEEE 802.15.4   N/A N/A                         39  Ack
1746    08:17:19,134044 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      138 111 150 40  Bind Request, On/Off (Cluster ID: 0x0006) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1747    08:17:19,135690 IEEE 802.15.4   N/A N/A                         40  Ack
1748    08:17:19,268690 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      139 112     41  ZCL: Configure Reporting, Seq: 152
1749    08:17:19,270468 IEEE 802.15.4   N/A N/A                         41  Ack
1750    08:17:20,084836 ZigBee  0x2646  0x0000  0x2646  0x0000      151         88  Network Status, 0x4b21: Indirect Transaction Expiry
1751    08:17:20,087585 IEEE 802.15.4   N/A N/A                         88  Ack
1752    08:17:20,237372 ZigBee  0x2646  0x0000  0x2646  0x0000      152         89  Network Status, 0x4b21: Indirect Transaction Expiry
1753    08:17:20,245692 IEEE 802.15.4   N/A N/A                         89  Ack
1754    08:17:20,248374 ZigBee  0xefd5  Broadcast   0xefd5  0xffff      228         59  Link Status
1755    08:17:20,372115 ZigBee  0x2646  0x0000  0x2646  0x0000      153         90  Network Status, 0x4b21: Indirect Transaction Expiry
1756    08:17:20,374348 IEEE 802.15.4   N/A N/A                         90  Ack
1757    08:17:20,604902 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      140 110 148 42  Bind Request, Level Control (Cluster ID: 0x0008) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1758    08:17:20,607028 IEEE 802.15.4   N/A N/A                         42  Ack
1759    08:17:20,633048 ZigBee  0x2646  Broadcast   0x2646  0xffff      154         91  Link Status
1760    08:17:20,744371 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      141 111 150 43  Bind Request, On/Off (Cluster ID: 0x0006) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1761    08:17:20,745927 IEEE 802.15.4   N/A N/A                         43  Ack
1762    08:17:20,880216 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      142 112     44  ZCL: Configure Reporting, Seq: 152
1763    08:17:20,885485 IEEE 802.15.4   N/A N/A                         44  Ack
1764    08:17:21,693572 ZigBee  0x2646  0x0000  0x2646  0x0000      155         92  Network Status, 0x4b21: Indirect Transaction Expiry
1765    08:17:21,695371 IEEE 802.15.4   N/A N/A                         92  Ack
1766    08:17:21,842170 ZigBee  0x2646  0x0000  0x2646  0x0000      156         93  Network Status, 0x4b21: Indirect Transaction Expiry
1767    08:17:21,844028 IEEE 802.15.4   N/A N/A                         93  Ack
1768    08:17:21,980192 ZigBee  0x2646  0x0000  0x2646  0x0000      157         94  Network Status, 0x4b21: Indirect Transaction Expiry
1769    08:17:21,981923 IEEE 802.15.4   N/A N/A                         94  Ack
1770    08:17:22,355123 ZigBee  0x0000  Broadcast   0x0000  0xffff      143         45  Route Request, Dst: 0x4b21, Src: 0x0000
1771    08:17:22,367969 ZigBee  0x2646  0x0000  0x2646  0x0000      160         95  Route Reply, Dst: 0x4b21, Src: 0x0000
1772    08:17:22,370156 IEEE 802.15.4   N/A N/A                         95  Ack
1773    08:17:22,383623 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      145 113 154 46  Bind Request, OTA Upgrade (Cluster ID: 0x0019) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1774    08:17:22,385472 IEEE 802.15.4   N/A N/A                         46  Ack
1775    08:17:22,397626 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      143         44  Route Request, Dst: 0x4b21, Src: 0x0000
1776    08:17:22,407999 ZigBee  0x0000  Broadcast   0x5550  0xffff      143         73  Route Request, Dst: 0x4b21, Src: 0x0000
1777    08:17:22,447093 ZigBee  0x0000  Broadcast   0x69b5  0xffff      143         200 Route Request, Dst: 0x4b21, Src: 0x0000
1778    08:17:22,478397 ZigBee  0x0000  Broadcast   0xefd5  0xffff      143         60  Route Request, Dst: 0x4b21, Src: 0x0000
1779    08:17:22,515964 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      147 114 156 47  Bind Request, Scenes (Cluster ID: 0x0005) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1780    08:17:22,517664 IEEE 802.15.4   N/A N/A                         47  Ack
1781    08:17:22,616547 ZigBee  0x5c1f  Broadcast   0x5c1f  0xffff      29          45  Link Status
1782    08:17:22,709055 ZigBee  0x0000  Broadcast   0x5550  0xffff      143         74  Route Request, Dst: 0x4b21, Src: 0x0000
1783    08:17:22,720336 ZigBee  0x0000  Broadcast   0x69b5  0xffff      143         201 Route Request, Dst: 0x4b21, Src: 0x0000
1784    08:17:22,735412 ZigBee  0x0000  Broadcast   0x0000  0xffff      143         48  Route Request, Dst: 0x4b21, Src: 0x0000
1785    08:17:22,749950 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      143         46  Route Request, Dst: 0x4b21, Src: 0x0000
1786    08:17:22,776300 ZigBee  0x2646  0x0000  0x2646  0x0000      162         96  Route Reply, Dst: 0x4b21, Src: 0x0000
1787    08:17:22,778055 IEEE 802.15.4   N/A N/A                         96  Ack
1788    08:17:22,784904 ZigBee  0x0000  Broadcast   0x0000  0xffff      148         49  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1789    08:17:22,858419 ZigBee  0x0000  Broadcast   0xefd5  0xffff      143         61  Route Request, Dst: 0x4b21, Src: 0x0000
1790    08:17:22,882299 ZigBee  0x0000  Broadcast   0x2646  0xffff      148         97  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1791    08:17:22,891393 ZigBee  0x0000  Broadcast   0xefd5  0xffff      148         62  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1792    08:17:22,903348 ZigBee  0x0000  Broadcast   0x69b5  0xffff      148         202 Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1793    08:17:22,913669 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      148         47  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1794    08:17:22,923595 ZigBee  0x0000  Broadcast   0x5550  0xffff      148         75  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1795    08:17:23,031015 ZigBee  0x0000  Broadcast   0x5550  0xffff      143         76  Route Request, Dst: 0x4b21, Src: 0x0000
1796    08:17:23,083645 ZigBee  0x0000  Broadcast   0x0000  0xffff      143         50  Route Request, Dst: 0x4b21, Src: 0x0000
1797    08:17:23,090702 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      143         48  Route Request, Dst: 0x4b21, Src: 0x0000
1798    08:17:23,099941 ZigBee  0x0000  Broadcast   0x69b5  0xffff      143         203 Route Request, Dst: 0x4b21, Src: 0x0000
1799    08:17:23,121822 ZigBee  0x0000  Broadcast   0x0000  0xffff      148         51  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1800    08:17:23,129951 ZigBee  0x0000  Broadcast   0xefd5  0xffff      143         63  Route Request, Dst: 0x4b21, Src: 0x0000
1801    08:17:23,161651 ZigBee  0x0000  Broadcast   0x2646  0xffff      148         98  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1802    08:17:23,182668 ZigBee  0x2646  0x0000  0x2646  0x0000      164         99  Route Reply, Dst: 0x4b21, Src: 0x0000
1803    08:17:23,184372 IEEE 802.15.4   N/A N/A                         99  Ack
1804    08:17:23,191877 ZigBee  0x0000  Broadcast   0xefd5  0xffff      148         64  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1805    08:17:23,201711 ZigBee  0x0000  Broadcast   0x69b5  0xffff      148         204 Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1806    08:17:23,225131 ZigBee  0x0000  Broadcast   0x5550  0xffff      148         77  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1807    08:17:23,237738 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      148         49  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1808    08:17:23,307325 ZigBee  0x2646  0x0000  0x2646  0x0000      165         100 Network Status, 0x4b21: Indirect Transaction Expiry
1809    08:17:23,308903 IEEE 802.15.4   N/A N/A                         100 Ack
1810    08:17:23,417187 ZigBee  0x0000  Broadcast   0x0000  0xffff      143         52  Route Request, Dst: 0x4b21, Src: 0x0000
1811    08:17:23,452369 ZigBee  0x2646  0x0000  0x2646  0x0000      166         101 Network Status, 0x4b21: Indirect Transaction Expiry
1812    08:17:23,454515 IEEE 802.15.4   N/A N/A                         101 Ack
1813    08:17:23,470177 ZigBee  0x0000  Broadcast   0x0000  0xffff      148         53  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1814    08:17:23,512972 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      148         50  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1815    08:17:23,519912 ZigBee  0x0000  Broadcast   0x69b5  0xffff      148         205 Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1816    08:17:23,528996 ZigBee  0x0000  Broadcast   0x2646  0xffff      148         102 Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1817    08:17:23,541435 ZigBee  0x0000  Broadcast   0x5550  0xffff      148         78  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1818    08:17:23,548724 ZigBee  0x0000  Broadcast   0xefd5  0xffff      148         65  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1819    08:17:23,591562 ZigBee  0x2646  0x0000  0x2646  0x0000      167         103 Network Status, 0x4b21: Indirect Transaction Expiry
1820    08:17:23,593046 IEEE 802.15.4   N/A N/A                         103 Ack
1821    08:17:23,779505 ZigBee  0x0000  Broadcast   0x0000  0xffff      148         54  Many-to-One Route Request, Dst: 0xfffc, Src: 0x0000
1822    08:17:23,984620 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      149 113 154 55  Bind Request, OTA Upgrade (Cluster ID: 0x0019) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1823    08:17:23,986877 IEEE 802.15.4   N/A N/A                         55  Ack
1824    08:17:24,129305 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      150 114 156 56  Bind Request, Scenes (Cluster ID: 0x0005) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1825    08:17:24,130937 IEEE 802.15.4   N/A N/A                         56  Ack
1826    08:17:24,238927 ZigBee  0x69b5  Broadcast   0x69b5  0xffff      234         206 Link Status
1827    08:17:25,069946 ZigBee  0x2646  0x0000  0x2646  0x0000      168         104 Network Status, 0x4b21: Indirect Transaction Expiry
1828    08:17:25,072886 IEEE 802.15.4   N/A N/A                         104 Ack
1829    08:17:25,210387 ZigBee  0x2646  0x0000  0x2646  0x0000      169         105 Network Status, 0x4b21: Indirect Transaction Expiry
1830    08:17:25,212191 IEEE 802.15.4   N/A N/A                         105 Ack
1831    08:17:25,345954 ZigBee  0x2646  0x0000  0x2646  0x0000      170         106 Network Status, 0x4b21: Indirect Transaction Expiry
1832    08:17:25,347906 IEEE 802.15.4   N/A N/A                         106 Ack
1833    08:17:25,593729 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      151 113 154 57  Bind Request, OTA Upgrade (Cluster ID: 0x0019) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1834    08:17:25,596206 IEEE 802.15.4   N/A N/A                         57  Ack
1835    08:17:25,738192 ZigBee ZDP  0x0000  0x4b21  0x0000  0x2646      152 114 156 58  Bind Request, Scenes (Cluster ID: 0x0005) Src: Ember_ff:fe:51:43:5c, Dst: SiliconL_ff:fe:d5:92:9c
1836    08:17:25,739846 IEEE 802.15.4   N/A N/A                         58  Ack
1837    08:17:26,679557 ZigBee  0x2646  0x0000  0x2646  0x0000      171         107 Network Status, 0x4b21: Indirect Transaction Expiry
1838    08:17:26,684909 IEEE 802.15.4   N/A N/A                         107 Ack
1839    08:17:26,821113 ZigBee  0x2646  0x0000  0x2646  0x0000      172         108 Network Status, 0x4b21: Indirect Transaction Expiry
1840    08:17:26,823057 IEEE 802.15.4   N/A N/A                         108 Ack
1841    08:17:26,955837 ZigBee  0x2646  0x0000  0x2646  0x0000      173         109 Network Status, 0x4b21: Indirect Transaction Expiry
1842    08:17:26,959531 IEEE 802.15.4   N/A N/A                         109 Ack
1843    08:17:27,347667 ZigBee  0x0000  Broadcast   0x0000  0xffff      153         59  Route Request, Dst: 0x4b21, Src: 0x0000
1844    08:17:27,360118 ZigBee  0x2646  0x0000  0x2646  0x0000      176         110 Route Reply, Dst: 0x4b21, Src: 0x0000
1845    08:17:27,361729 IEEE 802.15.4   N/A N/A                         110 Ack
1846    08:17:27,377147 ZigBee  0x0000  Broadcast   0x5550  0xffff      153         79  Route Request, Dst: 0x4b21, Src: 0x0000
1847    08:17:27,464789 ZigBee  0x0000  Broadcast   0x69b5  0xffff      153         207 Route Request, Dst: 0x4b21, Src: 0x0000
1848    08:17:27,472405 ZigBee  0x0000  Broadcast   0xefd5  0xffff      153         66  Route Request, Dst: 0x4b21, Src: 0x0000
1849    08:17:27,480649 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      153         51  Route Request, Dst: 0x4b21, Src: 0x0000
1850    08:17:27,530657 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      155 115     60  ZCL Identify: Trigger Effect, Seq: 158
1851    08:17:27,532855 IEEE 802.15.4   N/A N/A                         60  Ack
1852    08:17:27,648474 ZigBee  0x0000  Broadcast   0x0000  0xffff      153         61  Route Request, Dst: 0x4b21, Src: 0x0000
1853    08:17:27,708213 ZigBee  0x0000  Broadcast   0x5550  0xffff      153         80  Route Request, Dst: 0x4b21, Src: 0x0000
1854    08:17:27,767969 ZigBee  0x2646  0x0000  0x2646  0x0000      178         111 Route Reply, Dst: 0x4b21, Src: 0x0000
1855    08:17:27,782339 ZigBee  0x2646  0x0000  0x2646  0x0000      178         111 Route Reply, Dst: 0x4b21, Src: 0x0000
1856    08:17:27,786806 IEEE 802.15.4   N/A N/A                         111 Ack
1857    08:17:27,790163 ZigBee  0x0000  Broadcast   0xefd5  0xffff      153         67  Route Request, Dst: 0x4b21, Src: 0x0000
1858    08:17:27,797798 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      153         52  Route Request, Dst: 0x4b21, Src: 0x0000
1859    08:17:27,848640 ZigBee  0x0000  Broadcast   0x69b5  0xffff      153         208 Route Request, Dst: 0x4b21, Src: 0x0000
1860    08:17:27,968872 ZigBee  0x0000  Broadcast   0x0000  0xffff      153         62  Route Request, Dst: 0x4b21, Src: 0x0000
1861    08:17:28,023150 ZigBee  0x0000  Broadcast   0x5550  0xffff      153         81  Route Request, Dst: 0x4b21, Src: 0x0000
1862    08:17:28,087005 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      153         53  Route Request, Dst: 0x4b21, Src: 0x0000
1863    08:17:28,093934 ZigBee  0x0000  Broadcast   0xefd5  0xffff      153         68  Route Request, Dst: 0x4b21, Src: 0x0000
1864    08:17:28,180720 ZigBee  0x2646  0x0000  0x2646  0x0000      180         112 Route Reply, Dst: 0x4b21, Src: 0x0000
1865    08:17:28,183670 IEEE 802.15.4   N/A N/A                         112 Ack
1866    08:17:28,211651 ZigBee  0x0000  Broadcast   0x69b5  0xffff      153         209 Route Request, Dst: 0x4b21, Src: 0x0000
1867    08:17:28,257648 ZigBee  0x0000  Broadcast   0x0000  0xffff      153         63  Route Request, Dst: 0x4b21, Src: 0x0000
1868    08:17:28,287659 ZigBee  0x2646  0x0000  0x2646  0x0000      181         113 Network Status, 0x4b21: Indirect Transaction Expiry
1869    08:17:28,289404 IEEE 802.15.4   N/A N/A                         113 Ack
1870    08:17:28,432144 ZigBee  0x2646  0x0000  0x2646  0x0000      182         114 Network Status, 0x4b21: Indirect Transaction Expiry
1871    08:17:28,435750 IEEE 802.15.4   N/A N/A                         114 Ack
1872    08:17:28,564936 ZigBee  0x2646  0x0000  0x2646  0x0000      183         115 Network Status, 0x4b21: Indirect Transaction Expiry
1873    08:17:28,572460 ZigBee  0x2646  0x0000  0x2646  0x0000      183         115 Network Status, 0x4b21: Indirect Transaction Expiry
1874    08:17:28,574147 IEEE 802.15.4   N/A N/A                         115 Ack
1875    08:17:29,141211 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      156 115     64  ZCL Identify: Trigger Effect, Seq: 158
1876    08:17:29,142826 IEEE 802.15.4   N/A N/A                         64  Ack
1877    08:17:30,060561 ZigBee  0x2646  0x0000  0x2646  0x0000      184         116 Network Status, 0x4b21: Indirect Transaction Expiry
1878    08:17:30,062236 IEEE 802.15.4   N/A N/A                         116 Ack
1879    08:17:30,204758 ZigBee  0x2646  0x0000  0x2646  0x0000      185         117 Network Status, 0x4b21: Indirect Transaction Expiry
1880    08:17:30,207304 IEEE 802.15.4   N/A N/A                         117 Ack
1881    08:17:30,751572 ZigBee HA   0x0000  0x4b21  0x0000  0x2646      157 115     65  ZCL Identify: Trigger Effect, Seq: 158
1882    08:17:30,753308 IEEE 802.15.4   N/A N/A                         65  Ack
1883    08:17:31,670151 ZigBee  0x2646  0x0000  0x2646  0x0000      186         118 Network Status, 0x4b21: Indirect Transaction Expiry
1884    08:17:31,673983 IEEE 802.15.4   N/A N/A                         118 Ack
1885    08:17:31,813946 ZigBee  0x2646  0x0000  0x2646  0x0000      187         119 Network Status, 0x4b21: Indirect Transaction Expiry
1886    08:17:31,816238 IEEE 802.15.4   N/A N/A                         119 Ack
1887    08:17:32,192162 ZigBee  0x0000  Broadcast   0x0000  0xffff      158         66  Link Status
1888    08:17:32,363581 ZigBee  0x0000  Broadcast   0x0000  0xffff      159         67  Route Request, Dst: 0x4b21, Src: 0x0000
1889    08:17:32,376905 ZigBee  0x2646  0x0000  0x2646  0x0000      190         120 Route Reply, Dst: 0x4b21, Src: 0x0000
1890    08:17:32,378788 IEEE 802.15.4   N/A N/A                         120 Ack
1891    08:17:32,391945 ZigBee  0x0000  Broadcast   0x69b5  0xffff      159         210 Route Request, Dst: 0x4b21, Src: 0x0000
1892    08:17:32,397231 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      159         54  Route Request, Dst: 0x4b21, Src: 0x0000
1893    08:17:32,406905 ZigBee  0x0000  Broadcast   0xefd5  0xffff      159         69  Route Request, Dst: 0x4b21, Src: 0x0000
1894    08:17:32,439112 ZigBee  0x0000  Broadcast   0x5550  0xffff      159         82  Route Request, Dst: 0x4b21, Src: 0x0000
1895    08:17:32,661336 ZigBee  0x0000  Broadcast   0x69b5  0xffff      159         211 Route Request, Dst: 0x4b21, Src: 0x0000
1896    08:17:32,709842 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      159         55  Route Request, Dst: 0x4b21, Src: 0x0000
1897    08:17:32,722164 ZigBee  0x0000  Broadcast   0xefd5  0xffff      159         70  Route Request, Dst: 0x4b21, Src: 0x0000
1898    08:17:32,725844 ZigBee  0x0000  Broadcast   0x0000  0xffff      159         68  Route Request, Dst: 0x4b21, Src: 0x0000
1899    08:17:32,737464 ZigBee  0x0000  Broadcast   0x5550  0xffff      159         83  Route Request, Dst: 0x4b21, Src: 0x0000
1900    08:17:32,785812 ZigBee  0x2646  0x0000  0x2646  0x0000      192         121 Route Reply, Dst: 0x4b21, Src: 0x0000
1901    08:17:32,787498 IEEE 802.15.4   N/A N/A                         121 Ack
1902    08:17:33,010582 ZigBee  0x0000  Broadcast   0x69b5  0xffff      159         212 Route Request, Dst: 0x4b21, Src: 0x0000
1903    08:17:33,025047 ZigBee  0x0000  Broadcast   0xefd5  0xffff      159         71  Route Request, Dst: 0x4b21, Src: 0x0000
1904    08:17:33,034689 ZigBee  0x0000  Broadcast   0x0000  0xffff      159         69  Route Request, Dst: 0x4b21, Src: 0x0000
1905    08:17:33,055669 ZigBee  0x0000  Broadcast   0x5c1f  0xffff      159         56  Route Request, Dst: 0x4b21, Src: 0x0000
1906    08:17:33,067782 ZigBee  0x0000  Broadcast   0x5550  0xffff      159         84  Route Request, Dst: 0x4b21, Src: 0x0000
1907    08:17:33,193273 ZigBee  0x2646  0x0000  0x2646  0x0000      194         122 Route Reply, Dst: 0x4b21, Src: 0x0000
1908    08:17:33,195486 IEEE 802.15.4   N/A N/A                         122 Ack
1909    08:17:33,276383 ZigBee  0x2646  0x0000  0x2646  0x0000      195         123 Network Status, 0x4b21: Indirect Transaction Expiry
1910    08:17:33,278561 IEEE 802.15.4   N/A N/A                         123 Ack
1911    08:17:33,409770 ZigBee  0x0000  Broadcast   0x0000  0xffff      159         70  Route Request, Dst: 0x4b21, Src: 0x0000
1912    08:17:33,419273 ZigBee  0x2646  0x0000  0x2646  0x0000      196         124 Network Status, 0x4b21: Indirect Transaction Expiry
1913    08:17:33,425524 IEEE 802.15.4   N/A N/A                         124 Ack
1914    08:17:35,217720 ZigBee  0x2646  0x0000  0x2646  0x0000      197         125 Network Status, 0x4b21: Indirect Transaction Expiry
1915    08:17:35,219380 IEEE 802.15.4   N/A N/A                         125 Ack
1916    08:17:36,001936 ZigBee  0x5550  Broadcast   0x5550  0xffff      63          85  Link Status
1917    08:17:36,028476 ZigBee  0xefd5  Broadcast   0xefd5  0xffff      229         72  Link Status
1918    08:17:36,361878 IEEE 802.15.4   0xa46e  0xefd5  0xa46e  0xefd5                  221 Data Request
1919    08:17:36,363803 IEEE 802.15.4   N/A N/A                         221 Ack
1920    08:17:36,801724 ZigBee  0x2646  Broadcast   0x2646  0xffff      198         126 Link Status
1921    08:17:36,836209 ZigBee  0x2646  0x0000  0x2646  0x0000      199         127 Network Status, 0x4b21: Indirect Transaction Expiry
1922    08:17:36,838121 IEEE 802.15.4   N/A N/A                         127 Ack
1923    08:17:37,311892 ZigBee  0x5550  Broadcast   0x5550  0xffff      64          86  Link Status
1924    08:17:38,437656 ZigBee  0x2646  0x0000  0x2646  0x0000      200         128 Network Status, 0x4b21: Indirect Transaction Expiry

I dont knowing if it being triggered in my test system and no other system but the first time it was doing the same after restarting HA and was going away after some restarting but i dont knowing how i was getting it away.

Only for information if no other can reproducing the "delayed" reconfigure.

MattWestb commented 3 years ago

Update: Ca 1.5 hour later ZHA is doing the same thing sending all the command to the sleeping remote and the remotes parent is receiving all the commands but cant sending them to the remote then its sleeping.

Shall that not being timed to being done then the device is doing on pull control check in ?? I was thinking that is what pull control mechanism is made for.

One minute after the last timeout the device was doing on check in and it was normal (OK).