dresden-elektronik / deconz-rest-plugin

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

IKEA TRADFRI E27 806lm RGB pairing results in a timeout #5771

Closed jja2000 closed 2 years ago

jja2000 commented 2 years ago

Describe the bug

I've been trying to pair the LED bulbs listed in the title (article nr: 704.391.58) to my conbeeII connected to the deconz integration in home assistant, but I cannot seem to get it to work. The status lights within deCONZ seem to point at a timeout.

Steps to reproduce the behavior

I've been doing the following:

  1. Press "Add new lights" in Phoscon
  2. Reset the lightbulbs (off and on 6 times, the light blinks once and then starts to fade in and out in brightness)

At this point the lights don't show up in phoscon, but they do show up in deCONZ as can be seen by the screenshots.

Both devices will show a red dot in the top left grayed out dot while the light is pulsing.

Turning off and on the lights once as instructed has no effect.

Afterwards I have retried pairing without deleting the nodes from deCONZ, which left them the way they were after the first try, but also with, which made the devices reappear as they are in the screenshot.

Expected behavior

The devices are supposed to show up in deconz as connected and Phoscon should show the newly added lights.

Screenshots

image

Environment

deCONZ Logs

The following logs are captured after a fresh restart:

17:47:00:321 DEV no DDF for 0x04CD15FFFE287140, modelId: TRADFRI motion sensor
17:47:00:322 DEV create on-the-fly DDF for 0x04CD15FFFE287140
17:47:00:376 dlg action: Read binding table
17:47:00:646 Announced to internet https://phoscon.de/discover
17:47:00:647 discovery server date: Fri, 04 Feb 2022 16:47:00 GMT
17:47:00:648     local time seems to be ok
17:47:01:145 COM: /dev/serial/by-id/usb-dresden_elektronik_ingenieurtechnik_GmbH_ConBee_II_DE2471167-if00 / serialno: DE2471167, ConBee II
17:47:01:269 Skip idle timer callback, too early: elapsed 944 msec
17:47:02:596 DEV Tick.Init: booted after 8000 seconds
17:47:02:650 Device firmware version 0x26720700 ConBee II
17:47:02:671 unlocked max nodes: 512
17:47:02:763 Device protocol version: 0x010E
17:47:02:862 Current channel 15
17:47:02:883 CTRL ANT_CTRL 0x03
17:47:02:885 CTRL ZDP_RESPONSE handler 0x0001
17:47:02:919 Device protocol version: 0x010E
17:47:03:020 CTRL ANT_CTRL 0x03
17:47:03:022 CTRL ZDP_RESPONSE handler 0x0001
17:47:03:168 Skip idle timer callback, too early: elapsed 949 msec
17:47:10:597 GW update firmware found: /usr/share/deCONZ/firmware/deCONZ_ConBeeII_0x26660700.bin.GCF
17:47:10:597 GW firmware version is up to date: 0x26720700
17:47:32:340 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:34:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:47:35:207 APS-DATA.request id: 170, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:47:35:208    asdu (length: 2): 1000
17:47:35:223 APS-DATA.confirm id: 170, status: 0x00 SUCCESS
17:47:35:223 APS-DATA.confirm request id: 170 -> confirmed, timeout 72585613
17:47:35:231 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:35:231    asdu: 10000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:35:232 APS-DATA.indication request id: 170 -> finished
17:47:35:232 APS-DATA.request id: 170 erase from queue
17:47:35:233 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:38:088 APS-DATA.request id: 183, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:47:38:089    asdu (length: 2): 1100
17:47:38:105 APS-DATA.confirm id: 183, status: 0x00 SUCCESS
17:47:38:106 APS-DATA.confirm request id: 183 -> confirmed, timeout 72588494
17:47:38:111 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:38:112    asdu: 11000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:38:112 APS-DATA.indication request id: 183 -> finished
17:47:38:112 APS-DATA.request id: 183 erase from queue
17:47:38:113 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:40:968 APS-DATA.request id: 197, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:47:40:969    asdu (length: 2): 1200
17:47:40:990 APS-DATA.confirm id: 197, status: 0x00 SUCCESS
17:47:40:991 APS-DATA.confirm request id: 197 -> confirmed, timeout 72591375
17:47:41:029 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:41:030    asdu: 12000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:41:030 APS-DATA.indication request id: 197 -> finished
17:47:41:031 APS-DATA.request id: 197 erase from queue
17:47:41:031 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:43:602 New websocket 127.0.0.1:47196 (state: 3) 
17:47:43:848 APS-DATA.request id: 211, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:47:43:849    asdu (length: 2): 1300
17:47:43:867 APS-DATA.confirm id: 211, status: 0x00 SUCCESS
17:47:43:868 APS-DATA.confirm request id: 211 -> confirmed, timeout 72594254
17:47:43:909 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:43:909    asdu: 13000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:43:910 APS-DATA.indication request id: 211 -> finished
17:47:43:910 APS-DATA.request id: 211 erase from queue
17:47:43:910 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:44:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:47:46:727 APS-DATA.request id: 224, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:47:46:729    asdu (length: 2): 1400
17:47:46:747 APS-DATA.confirm id: 224, status: 0x00 SUCCESS
17:47:46:748 APS-DATA.confirm request id: 224 -> confirmed, timeout 72597134
17:47:46:789 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:46:790    asdu: 14000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:46:790 APS-DATA.indication request id: 224 -> finished
17:47:46:790 APS-DATA.request id: 224 erase from queue
17:47:46:791 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:48:264 Websocket disconnected 127.0.0.1:47196, state: 0, close-code: 1000, reason: 
17:47:49:348 New websocket 127.0.0.1:47228 (state: 3) 
17:47:49:607 APS-DATA.request id: 238, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:47:49:608    asdu (length: 2): 1500
17:47:49:626 APS-DATA.confirm id: 238, status: 0x00 SUCCESS
17:47:49:626 APS-DATA.confirm request id: 238 -> confirmed, timeout 72600014
17:47:49:671 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:49:671    asdu: 15000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:49:672 APS-DATA.indication request id: 238 -> finished
17:47:49:672 APS-DATA.request id: 238 erase from queue
17:47:49:672 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:52:488 APS-DATA.request id: 251, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:47:52:489    asdu (length: 2): 1600
17:47:52:509 APS-DATA.confirm id: 251, status: 0x00 SUCCESS
17:47:52:510 APS-DATA.confirm request id: 251 -> confirmed, timeout 72602894
17:47:52:549 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:52:550    asdu: 16000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:52:550 APS-DATA.indication request id: 251 -> finished
17:47:52:550 APS-DATA.request id: 251 erase from queue
17:47:52:550 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:54:595 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:47:54:615 Master: read param with arg 0x19
17:47:54:673 Device TTL 5882 s flags: 0x7
17:47:55:096 APS-DATA.request id: 7, addrmode: 0x02, addr: 0xfffc, profile: 0x0000, cluster: 0x0036, ep: 0x00 -> 0x00 queue: 0 len: 3 tx.options 0x00
17:47:55:097    asdu (length: 3): 004101
17:47:55:098 send permit join, duration: 65
17:47:55:099 APS-DATA.request id: 8, addrmode: 0x02, addr: 0xfffc, profile: 0xA1E0, cluster: 0x0021, ep: 0xF2 -> 0xF2 queue: 1 len: 6 tx.options 0x00
17:47:55:100    asdu (length: 6): 1901020b2800
17:47:55:368 APS-DATA.request id: 11, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 2 len: 2 tx.options 0x00
17:47:55:368    asdu (length: 2): 1700
17:47:55:385 APS-DATA.confirm id: 11, status: 0x00 SUCCESS
17:47:55:386 APS-DATA.confirm request id: 11 -> confirmed, timeout 72605773
17:47:55:430 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:55:431    asdu: 17000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:55:431 APS-DATA.indication request id: 11 -> finished
17:47:55:432 APS-DATA.request id: 11 erase from queue
17:47:55:432 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:47:55:782 APS-DATA.confirm id: 7, status: 0x00 SUCCESS
17:47:55:790 APS-DATA.confirm id: 8, status: 0x00 SUCCESS
17:47:55:848 aps request id: 7 finished, erase from queue
17:47:55:928 aps request id: 8 finished, erase from queue
17:47:56:096 Idle timer triggered
17:47:58:248 APS-DATA.request id: 24, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:47:58:249    asdu (length: 2): 1800
17:47:58:269 APS-DATA.confirm id: 24, status: 0x00 SUCCESS
17:47:58:270 APS-DATA.confirm request id: 24 -> confirmed, timeout 72608655
17:47:58:307 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:47:58:307    asdu: 18000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:47:58:308 APS-DATA.indication request id: 24 -> finished
17:47:58:308 APS-DATA.request id: 24 erase from queue
17:47:58:308 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:48:01:128 APS-DATA.request id: 38, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:01:129    asdu (length: 2): 1900
17:48:01:147 APS-DATA.confirm id: 38, status: 0x00 SUCCESS
17:48:01:148 APS-DATA.confirm request id: 38 -> confirmed, timeout 72611535
17:48:01:189 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:01:190    asdu: 19000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:48:01:190 APS-DATA.indication request id: 38 -> finished
17:48:01:191 APS-DATA.request id: 38 erase from queue
17:48:01:191 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:48:04:008 APS-DATA.request id: 51, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:04:009    asdu (length: 2): 1a00
17:48:04:028 APS-DATA.confirm id: 51, status: 0x00 SUCCESS
17:48:04:028 APS-DATA.confirm request id: 51 -> confirmed, timeout 72614415
17:48:04:071 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:04:071    asdu: 1a000300012ed107ffff2e2100407128feff15cd0452e1120001ff
17:48:04:072 APS-DATA.indication request id: 51 -> finished
17:48:04:072 APS-DATA.request id: 51 erase from queue
17:48:04:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:48:06:407 APS-DATA.request id: 63, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:06:408    asdu (length: 2): 1b01
17:48:06:428 APS-DATA.confirm id: 63, status: 0x00 SUCCESS
17:48:06:429 APS-DATA.confirm request id: 63 -> confirmed, timeout 72616813
17:48:06:469 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:06:469    asdu: 1b000301012ed107ffff2e21006bac1afeff14430ca74d150001ff
17:48:06:470 APS-DATA.indication request id: 63 -> finished
17:48:06:470 APS-DATA.request id: 63 erase from queue
17:48:06:471 neigbor 0x0c4314fffe1aac6b is unknown child
17:48:06:890 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 2, node: 0x4DA7
17:48:06:890 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 1, node: 0x4DA7
17:48:06:891 new node - ext: 0x0c4314fffe1aac6b, nwk: 0x4DA7
17:48:08:808 APS-DATA.request id: 74, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:08:809    asdu (length: 2): 1c02
17:48:08:826 APS-DATA.confirm id: 74, status: 0x00 SUCCESS
17:48:08:827 APS-DATA.confirm request id: 74 -> confirmed, timeout 72619214
17:48:08:871 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:08:872    asdu: 1c000302012ed107ffff2e2100c69064feff65112ce13f150001ff
17:48:08:873 APS-DATA.indication request id: 74 -> finished
17:48:08:873 APS-DATA.request id: 74 erase from queue
17:48:08:874 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:48:08:874 neigbor 0x2c1165fffe6490c6 is unknown child
17:48:08:921 Websocket 127.0.0.1:47228 send message: {"attr":{"id":"1","lastannounced":null,"lastseen":"2022-02-04T16:48Z","manufacturername":"dresden elektronik","modelid":"ConBee II","name":"Configuration tool 1","swversion":"0x26720700","type":"Configuration tool","uniqueid":"00:21:2e:ff:ff:07:d1:2e-01"},"e":"changed","id":"1","r":"lights","t":"event","uniqueid":"00:21:2e:ff:ff:07:d1:2e-01"} (ret = 344)
17:48:09:292 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 2, node: 0x3FE1
17:48:09:293 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 1, node: 0x3FE1
17:48:09:294 new node - ext: 0x2c1165fffe6490c6, nwk: 0x3FE1
17:48:11:688 APS-DATA.request id: 88, addrmode: 0x03, addr: 0x0c4314fffe1aac6b, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:11:689    asdu (length: 2): 1d00
17:48:14:595 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:48:19:598 CTRL db store gui node 00:21:2e:ff:ff:07:d1:2e
17:48:19:601 CTRL db store gui node 04:cd:15:ff:fe:28:71:40
17:48:19:602 CTRL db store gui node 0c:43:14:ff:fe:1a:ac:6b
17:48:19:603 CTRL db store gui node 2c:11:65:ff:fe:64:90:c6
17:48:19:618 saved node state in 0 ms
17:48:19:618 sync() in 0 ms
17:48:21:787 APS-DATA.confirm id: 88, status: 0xD0 
17:48:22:248 APS-DATA.request id: 131, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:22:249    asdu (length: 2): 1e00
17:48:22:268 APS-DATA.confirm id: 131, status: 0x00 SUCCESS
17:48:22:269 APS-DATA.confirm request id: 131 -> confirmed, timeout 72632654
17:48:22:312 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:22:313    asdu: 1e000300012ed107ffff2e2100407128feff15cd0452e1120001ff
17:48:22:313 APS-DATA.indication request id: 131 -> finished
17:48:22:313 APS-DATA.request id: 131 erase from queue
17:48:24:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:48:24:648 APS-DATA.request id: 143, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:24:649    asdu (length: 2): 1f01
17:48:24:665 APS-DATA.confirm id: 143, status: 0x00 SUCCESS
17:48:24:666 APS-DATA.confirm request id: 143 -> confirmed, timeout 72635054
17:48:24:701 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:24:702    asdu: 1f000301012ed107ffff2e21006bac1afeff14430ca74d150001ff
17:48:24:702 APS-DATA.indication request id: 143 -> finished
17:48:24:702 APS-DATA.request id: 143 erase from queue
17:48:24:703 skip create link for 0x0000 (lqi: 255) - 0x4DA7 (lqi: 0)
17:48:26:096 sql exec SELECT conf FROM zbconf ORDER BY rowid desc limit 1
17:48:26:098 Wait 3s till query finished
17:48:26:771 APS-DATA.indication srcAddr: 0xe152, srcEp: 0x01 dstAddrMode: 1, profile: 0x0104, cluster: 0x0006, lqi: 255, rssi: -49
17:48:26:771    asdu: 0164420008070000
17:48:26:772 APS-DATA.indication from child 0xE152
17:48:26:772 [INFO] - Button 1002 - TRADFRI motion sensor, broadcast to: 0x0002, endpoint: 0x01, cluster: ONOFF (0x0006), action: On with timed off, payload: 0008070000, zclSeq: 100
17:48:26:773 0x04CD15FFFE287140 (TRADFRI motion sensor) create binding for attribute reporting of cluster 0x0001 on endpoint 0x01
17:48:26:773 queue binding task for 0x04CD15FFFE287140, cluster 0x0001
17:48:26:774 Force binding of attribute reporting for sensor TRÅDFRI Motion sensor
17:48:26:787 Websocket 127.0.0.1:47228 send message: {"config":{"alert":"none","battery":74,"delay":180,"duration":60,"group":"2","on":true,"reachable":true},"e":"changed","id":"2","r":"sensors","t":"event","uniqueid":"04:cd:15:ff:fe:28:71:40-01-0006"} (ret = 199)
17:48:26:789 Websocket 127.0.0.1:47228 send message: {"e":"changed","id":"2","r":"sensors","state":{"dark":true,"lastupdated":"2022-02-04T16:48:26.773","presence":true},"t":"event","uniqueid":"04:cd:15:ff:fe:28:71:40-01-0006"} (ret = 173)
17:48:26:790 Websocket 127.0.0.1:47228 send message: {"attr":{"id":"2","lastannounced":"2022-02-03T20:42:51Z","lastseen":"2022-02-04T16:48Z","manufacturername":"IKEA of Sweden","modelid":"TRADFRI motion sensor","name":"TRÅDFRI Motion sensor","swversion":"2.0.022","type":"ZHAPresence","uniqueid":"04:cd:15:ff:fe:28:71:40-01-0006"},"e":"changed","id":"2","r":"sensors","t":"event","uniqueid":"04:cd:15:ff:fe:28:71:40-01-0006"} (ret = 373)
17:48:26:792 APS-DATA.request id: 154, addrmode: 0x03, addr: 0x04cd15fffe287140, profile: 0x0000, cluster: 0x0021, ep: 0x00 -> 0x00 queue: 0 len: 22 tx.options 0x04
17:48:26:793    asdu (length: 22): 38407128feff15cd04010100032ed107ffff2e210001
17:48:27:048 APS-DATA.request id: 156, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 1 len: 2 tx.options 0x00
17:48:27:049    asdu (length: 2): 2002
17:48:27:067 APS-DATA.confirm id: 156, status: 0x00 SUCCESS
17:48:27:068 APS-DATA.confirm request id: 156 -> confirmed, timeout 72637454
17:48:27:110 Set sensor check interval to 100 milliseconds
17:48:27:111 Wait 2s till query finished
17:48:27:116 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:27:116    asdu: 20000302012ed107ffff2e2100c69064feff65112ce13f150001ff
17:48:27:117 APS-DATA.indication request id: 156 -> finished
17:48:27:117 APS-DATA.request id: 156 erase from queue
17:48:27:117 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:48:27:118 skip create link for 0x0000 (lqi: 255) - 0x3FE1 (lqi: 0)
17:48:27:834 APS-DATA.confirm id: 154, status: 0x00 SUCCESS
17:48:27:835 APS-DATA.confirm request id: 154 -> confirmed, timeout 72637199
17:48:28:096 Wait 1s till query finished
17:48:28:844 APS-DATA.indication srcAddr: 0xe152, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8021, lqi: 255, rssi: -49
17:48:28:845    asdu: 3800
17:48:28:846 APS-DATA.indication request id: 154 -> finished
17:48:28:846 APS-DATA.indication from child 0xE152
17:48:28:847 APS-DATA.request id: 154 erase from queue
17:48:28:848 Bind response success for 0x04cd15fffe287140 ep: 0x01 cluster: 0x0001
17:48:28:848 0x04CD15FFFE287140: added ZCL value 0x01/0x0001/0x0021
17:48:28:849 configure reporting rq seq 2 for 0x04CD15FFFE287140, attribute 0x0001/0x0021
17:48:28:850 APS-DATA.request id: 166, addrmode: 0x03, addr: 0x04cd15fffe287140, profile: 0x0104, cluster: 0x0001, ep: 0x01 -> 0x01 queue: 0 len: 12 tx.options 0x04
17:48:28:851    asdu (length: 12): 100206002100202c018c0a01
17:48:29:096 Wait 1s till query finished
17:48:29:899 APS-DATA.confirm id: 166, status: 0x00 SUCCESS
17:48:29:900 APS-DATA.confirm request id: 166 -> erase from queue
17:48:30:003 aps request id: 166 finished, erase from queue
17:48:30:096 Wait 0s till query finished
17:48:30:408 APS-DATA.request id: 174, addrmode: 0x03, addr: 0x2c1165fffe6490c6, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:30:408    asdu (length: 2): 2100
17:48:31:015 APS-DATA.indication srcAddr: 0xe152, srcEp: 0x01 dstAddrMode: 2, profile: 0x0104, cluster: 0x0001, lqi: 255, rssi: -49
17:48:31:016    asdu: 08020700
17:48:31:016 APS-DATA.indication from child 0xE152
17:48:31:017 ZCL configure reporting rsp seq: 2 0x04CD15FFFE287140 for ep: 0x01 cluster: 0x0001 attr: 0x0021 status: 0x00
17:48:31:017 APS-DATA.request id: 178, addrmode: 0x02, addr: 0xe152, profile: 0x0104, cluster: 0x0001, ep: 0x01 -> 0x01 queue: 1 len: 5 tx.options 0x04
17:48:31:018    asdu (length: 5): 10020b0700
17:48:31:039 APS-DATA.confirm id: 178, status: 0x00 SUCCESS
17:48:31:040 APS-DATA.confirm request id: 178 -> erase from queue
17:48:31:078 aps request id: 178 finished, erase from queue
17:48:31:096 Idle timer triggered
17:48:34:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:48:40:498 APS-DATA.confirm id: 174, status: 0xD0 
17:48:40:968 APS-DATA.request id: 219, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:40:969    asdu (length: 2): 2200
17:48:40:983 APS-DATA.confirm id: 219, status: 0x00 SUCCESS
17:48:40:984 APS-DATA.confirm request id: 219 -> confirmed, timeout 72651374
17:48:41:022 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:41:023    asdu: 22000300012ed107ffff2e2100407128feff15cd0452e1120001ff
17:48:41:023 APS-DATA.indication request id: 219 -> finished
17:48:41:024 APS-DATA.request id: 219 erase from queue
17:48:43:368 APS-DATA.request id: 231, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:43:369    asdu (length: 2): 2301
17:48:43:387 APS-DATA.confirm id: 231, status: 0x00 SUCCESS
17:48:43:388 APS-DATA.confirm request id: 231 -> confirmed, timeout 72653775
17:48:43:425 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:43:425    asdu: 23000301012ed107ffff2e21006bac1afeff14430ca74d150001ff
17:48:43:426 APS-DATA.indication request id: 231 -> finished
17:48:43:426 APS-DATA.request id: 231 erase from queue
17:48:43:426 skip create link for 0x0000 (lqi: 255) - 0x4DA7 (lqi: 0)
17:48:44:595 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:48:45:768 APS-DATA.request id: 242, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:45:769    asdu (length: 2): 2402
17:48:45:788 APS-DATA.confirm id: 242, status: 0x00 SUCCESS
17:48:45:789 APS-DATA.confirm request id: 242 -> confirmed, timeout 72656175
17:48:45:829 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:45:830    asdu: 24000302012ed107ffff2e2100c69064feff65112ce13f150001ff
17:48:45:830 APS-DATA.indication request id: 242 -> finished
17:48:45:831 APS-DATA.request id: 242 erase from queue
17:48:45:831 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:48:45:831 skip create link for 0x0000 (lqi: 255) - 0x3FE1 (lqi: 0)
17:48:49:607 APS-DATA.request id: 4, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:49:608    asdu (length: 2): 2500
17:48:49:625 APS-DATA.confirm id: 4, status: 0x00 SUCCESS
17:48:49:628 APS-DATA.confirm request id: 4 -> confirmed, timeout 72660013
17:48:49:668 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:49:668    asdu: 25000300012ed107ffff2e2100407128feff15cd0452e1120001ff
17:48:49:668 APS-DATA.indication request id: 4 -> finished
17:48:49:669 APS-DATA.request id: 4 erase from queue
17:48:52:007 APS-DATA.request id: 15, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:52:008    asdu (length: 2): 2601
17:48:52:028 APS-DATA.confirm id: 15, status: 0x00 SUCCESS
17:48:52:029 APS-DATA.confirm request id: 15 -> confirmed, timeout 72662414
17:48:52:068 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:52:068    asdu: 26000301012ed107ffff2e21006bac1afeff14430ca74d150001ff
17:48:52:069 APS-DATA.indication request id: 15 -> finished
17:48:52:069 APS-DATA.request id: 15 erase from queue
17:48:52:069 skip create link for 0x0000 (lqi: 255) - 0x4DA7 (lqi: 0)
17:48:54:407 APS-DATA.request id: 27, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:54:409    asdu (length: 2): 2702
17:48:54:427 APS-DATA.confirm id: 27, status: 0x00 SUCCESS
17:48:54:428 APS-DATA.confirm request id: 27 -> confirmed, timeout 72664814
17:48:54:471 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:54:472    asdu: 27000302012ed107ffff2e2100c69064feff65112ce13f150001ff
17:48:54:472 APS-DATA.indication request id: 27 -> finished
17:48:54:473 APS-DATA.request id: 27 erase from queue
17:48:54:473 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:48:54:473 skip create link for 0x0000 (lqi: 255) - 0x3FE1 (lqi: 0)
17:48:54:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:48:54:612 Master: read param with arg 0x19
17:48:54:674 Device TTL 5822 s flags: 0x7
17:48:55:896 APS-DATA.request id: 30, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0033, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:55:897    asdu (length: 2): 3700
17:48:55:916 APS-DATA.confirm id: 30, status: 0x00 SUCCESS
17:48:55:917 APS-DATA.confirm request id: 30 -> confirmed, timeout 72666302
17:48:55:959 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8033, lqi: 221, rssi: 30
17:48:55:960    asdu: 3700000000
17:48:55:961 APS-DATA.indication request id: 30 -> finished
17:48:55:961 APS-DATA.request id: 30 erase from queue
17:48:55:961 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 9, node: 0x0000
17:48:56:096 APS-DATA.request id: 37, addrmode: 0x02, addr: 0xfffc, profile: 0x0000, cluster: 0x0036, ep: 0x00 -> 0x00 queue: 0 len: 3 tx.options 0x00
17:48:56:097    asdu (length: 3): 014101
17:48:56:097 send permit join, duration: 65
17:48:56:097 APS-DATA.request id: 38, addrmode: 0x02, addr: 0xfffc, profile: 0xA1E0, cluster: 0x0021, ep: 0xF2 -> 0xF2 queue: 1 len: 6 tx.options 0x00
17:48:56:098    asdu (length: 6): 1903020b2800
17:48:56:771 APS-DATA.confirm id: 37, status: 0x00 SUCCESS
17:48:56:782 APS-DATA.confirm id: 38, status: 0x00 SUCCESS
17:48:56:826 aps request id: 37 finished, erase from queue
17:48:56:888 aps request id: 38 finished, erase from queue
17:48:58:248 APS-DATA.request id: 48, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:48:58:249    asdu (length: 2): 2800
17:48:58:267 APS-DATA.confirm id: 48, status: 0x00 SUCCESS
17:48:58:268 APS-DATA.confirm request id: 48 -> confirmed, timeout 72668654
17:48:58:307 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:48:58:307    asdu: 28000300012ed107ffff2e2100407128feff15cd0452e1120001ff
17:48:58:308 APS-DATA.indication request id: 48 -> finished
17:48:58:308 APS-DATA.request id: 48 erase from queue
17:49:00:648 APS-DATA.request id: 60, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:00:649    asdu (length: 2): 2901
17:49:00:667 APS-DATA.confirm id: 60, status: 0x00 SUCCESS
17:49:00:668 APS-DATA.confirm request id: 60 -> confirmed, timeout 72671054
17:49:00:707 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:00:708    asdu: 2900010100
17:49:00:708 APS-DATA.indication request id: 60 -> finished
17:49:00:708 APS-DATA.request id: 60 erase from queue
17:49:00:709 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:49:01:096 Idle timer triggered
17:49:04:488 APS-DATA.request id: 77, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:04:489    asdu (length: 2): 2a00
17:49:04:508 APS-DATA.confirm id: 77, status: 0x00 SUCCESS
17:49:04:509 APS-DATA.confirm request id: 77 -> confirmed, timeout 72674895
17:49:04:547 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:04:548    asdu: 2a000300012ed107ffff2e2100407128feff15cd0452e1120001ff
17:49:04:548 APS-DATA.indication request id: 77 -> finished
17:49:04:549 APS-DATA.request id: 77 erase from queue
17:49:04:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:49:06:888 APS-DATA.request id: 89, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:06:889    asdu (length: 2): 2b01
17:49:06:909 APS-DATA.confirm id: 89, status: 0x00 SUCCESS
17:49:06:910 APS-DATA.confirm request id: 89 -> confirmed, timeout 72677294
17:49:06:949 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:06:950    asdu: 2b000301012ed107ffff2e2100c69064feff65112ca2d8150001ff
17:49:06:950 APS-DATA.indication request id: 89 -> finished
17:49:06:950 APS-DATA.request id: 89 erase from queue
17:49:06:951     * different nwk address 0x3FE1 / 0xD8A2
17:49:06:951 neigbor 0x2c1165fffe6490c6 is unknown child
17:49:09:288 APS-DATA.request id: 100, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:09:289    asdu (length: 2): 2c02
17:49:09:309 APS-DATA.confirm id: 100, status: 0x00 SUCCESS
17:49:09:310 APS-DATA.confirm request id: 100 -> confirmed, timeout 72679695
17:49:09:351 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:09:352    asdu: 2c000302012ed107ffff2e21006bac1afeff14430c57e9150001ff
17:49:09:352 APS-DATA.indication request id: 100 -> finished
17:49:09:353 APS-DATA.request id: 100 erase from queue
17:49:09:353 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:49:09:353     * different nwk address 0x4DA7 / 0xE957
17:49:09:353 neigbor 0x0c4314fffe1aac6b is unknown child
17:49:09:366 Websocket 127.0.0.1:47228 send message: {"attr":{"id":"1","lastannounced":null,"lastseen":"2022-02-04T16:49Z","manufacturername":"dresden elektronik","modelid":"ConBee II","name":"Configuration tool 1","swversion":"0x26720700","type":"Configuration tool","uniqueid":"00:21:2e:ff:ff:07:d1:2e-01"},"e":"changed","id":"1","r":"lights","t":"event","uniqueid":"00:21:2e:ff:ff:07:d1:2e-01"} (ret = 344)
17:49:13:128 APS-DATA.request id: 118, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:13:129    asdu (length: 2): 2d00
17:49:13:151 APS-DATA.confirm id: 118, status: 0x00 SUCCESS
17:49:13:152 APS-DATA.confirm request id: 118 -> confirmed, timeout 72683535
17:49:13:191 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:13:191    asdu: 2d000300012ed107ffff2e2100407128feff15cd0452e1120001ff
17:49:13:192 APS-DATA.indication request id: 118 -> finished
17:49:13:192 APS-DATA.request id: 118 erase from queue
17:49:14:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:49:15:528 APS-DATA.request id: 129, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:15:529    asdu (length: 2): 2e01
17:49:15:545 APS-DATA.confirm id: 129, status: 0x00 SUCCESS
17:49:15:546 APS-DATA.confirm request id: 129 -> confirmed, timeout 72685934
17:49:15:588 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:15:589    asdu: 2e000301012ed107ffff2e2100c69064feff65112ca2d8150001ff
17:49:15:589 APS-DATA.indication request id: 129 -> finished
17:49:15:589 APS-DATA.request id: 129 erase from queue
17:49:15:590     * different nwk address 0x3FE1 / 0xD8A2
17:49:15:590 neigbor 0x2c1165fffe6490c6 is unknown child
17:49:17:928 APS-DATA.request id: 141, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:17:929    asdu (length: 2): 2f02
17:49:17:949 APS-DATA.confirm id: 141, status: 0x00 SUCCESS
17:49:17:950 APS-DATA.confirm request id: 141 -> confirmed, timeout 72688334
17:49:17:986 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:17:987    asdu: 2f000302012ed107ffff2e21006bac1afeff14430c57e9150001ff
17:49:17:988 APS-DATA.indication request id: 141 -> finished
17:49:17:988 APS-DATA.request id: 141 erase from queue
17:49:17:988 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:49:17:988     * different nwk address 0x4DA7 / 0xE957
17:49:17:989 neigbor 0x0c4314fffe1aac6b is unknown child
17:49:22:408 node 00C4314FFFE1AAC6B leave wait state
17:49:24:597 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:49:26:795 sensor 2 (TRADFRI motion sensor): disable presence
17:49:26:804 Websocket 127.0.0.1:47228 send message: {"e":"changed","id":"2","r":"sensors","state":{"dark":true,"lastupdated":"2022-02-04T16:49:26.796","presence":false},"t":"event","uniqueid":"04:cd:15:ff:fe:28:71:40-01-0006"} (ret = 174)
17:49:26:996 Set sensor check interval to 1000 milliseconds
17:49:31:096 sql exec SELECT conf FROM zbconf ORDER BY rowid desc limit 1
17:49:31:097 Idle timer triggered
17:49:31:097 Force read attributes for ZHAPresence SensorNode TRÅDFRI Motion sensor
17:49:32:096 Wait 1s till query finished
17:49:33:096 Idle timer triggered
17:49:33:288 APS-DATA.request id: 204, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:33:289    asdu (length: 2): 3000
17:49:33:306 APS-DATA.confirm id: 204, status: 0x00 SUCCESS
17:49:33:307 APS-DATA.confirm request id: 204 -> confirmed, timeout 72703694
17:49:33:347 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:33:348    asdu: 30000300012ed107ffff2e2100407128feff15cd0452e1120001ff
17:49:33:348 APS-DATA.indication request id: 204 -> finished
17:49:33:349 APS-DATA.request id: 204 erase from queue
17:49:34:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:49:41:207 node 02C1165FFFE6490C6 leave wait state
17:49:44:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:49:48:648 APS-DATA.request id: 12, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:49:48:649    asdu (length: 2): 3101
17:49:48:668 APS-DATA.confirm id: 12, status: 0x00 SUCCESS
17:49:48:669 APS-DATA.confirm request id: 12 -> confirmed, timeout 72719054
17:49:48:711 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:49:48:712    asdu: 31000301012ed107ffff2e2100c69064feff65112ca2d8150001ff
17:49:48:712 APS-DATA.indication request id: 12 -> finished
17:49:48:713 APS-DATA.request id: 12 erase from queue
17:49:48:713     * different nwk address 0x3FE1 / 0xD8A2
17:49:48:713 neigbor 0x2c1165fffe6490c6 is unknown child
17:49:54:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:49:54:631 Master: read param with arg 0x19
17:49:54:654 Device TTL 5761 s flags: 0x7
17:49:57:096 binding/unbinding timeout srcAddr: 0x04CD15FFFE287140, retry
17:49:57:098 APS-DATA.request id: 47, addrmode: 0x02, addr: 0xfffc, profile: 0x0000, cluster: 0x0036, ep: 0x00 -> 0x00 queue: 0 len: 3 tx.options 0x00
17:49:57:098    asdu (length: 3): 024101
17:49:57:099 send permit join, duration: 65
17:49:57:100 APS-DATA.request id: 48, addrmode: 0x02, addr: 0xfffc, profile: 0xA1E0, cluster: 0x0021, ep: 0xF2 -> 0xF2 queue: 1 len: 6 tx.options 0x00
17:49:57:101    asdu (length: 6): 1904020b2800
17:49:57:776 APS-DATA.confirm id: 47, status: 0x00 SUCCESS
17:49:57:784 APS-DATA.confirm id: 48, status: 0x00 SUCCESS
17:49:57:848 aps request id: 47 finished, erase from queue
17:49:57:928 aps request id: 48 finished, erase from queue
17:49:58:096 APS-DATA.request id: 53, addrmode: 0x03, addr: 0x04cd15fffe287140, profile: 0x0000, cluster: 0x0021, ep: 0x00 -> 0x00 queue: 0 len: 22 tx.options 0x04
17:49:58:097    asdu (length: 22): 2b407128feff15cd04010100032ed107ffff2e210001
17:50:03:095 Idle timer triggered
17:50:04:008 APS-DATA.request id: 78, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 1 len: 2 tx.options 0x00
17:50:04:010    asdu (length: 2): 3202
17:50:04:027 APS-DATA.confirm id: 78, status: 0x00 SUCCESS
17:50:04:028 APS-DATA.confirm request id: 78 -> confirmed, timeout 72734415
17:50:04:070 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:50:04:070    asdu: 3200010200
17:50:04:071 APS-DATA.indication request id: 78 -> finished
17:50:04:071 APS-DATA.request id: 78 erase from queue
17:50:04:071 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:50:04:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:50:05:748 APS-DATA.confirm id: 53, status: 0xF0 TRANSACTION_EXPIRED
17:50:05:749 APS-DATA.confirm id: 53 status: transaction expired
17:50:14:597 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1
17:50:20:808 APS-DATA.request id: 147, addrmode: 0x03, addr: 0x00212effff07d12e, profile: 0x0000, cluster: 0x0031, ep: 0x00 -> 0x00 queue: 0 len: 2 tx.options 0x00
17:50:20:809    asdu (length: 2): 3300
17:50:20:828 APS-DATA.confirm id: 147, status: 0x00 SUCCESS
17:50:20:829 APS-DATA.confirm request id: 147 -> confirmed, timeout 72751215
17:50:20:868 APS-DATA.indication srcAddr: 0x0000, srcEp: 0x00 dstAddrMode: 2, profile: 0x0000, cluster: 0x8031, lqi: 221, rssi: 30
17:50:20:868    asdu: 33000100012ed107ffff2e2100407128feff15cd0452e1120001ff
17:50:20:869 APS-DATA.indication request id: 147 -> finished
17:50:20:869 APS-DATA.request id: 147 erase from queue
17:50:20:869 void deCONZ::zmNode::setFetched(deCONZ::RequestId, bool) fetched item: 8, node: 0x0000
17:50:20:878 Websocket 127.0.0.1:47228 send message: {"attr":{"id":"1","lastannounced":null,"lastseen":"2022-02-04T16:50Z","manufacturername":"dresden elektronik","modelid":"ConBee II","name":"Configuration tool 1","swversion":"0x26720700","type":"Configuration tool","uniqueid":"00:21:2e:ff:ff:07:d1:2e-01"},"e":"changed","id":"1","r":"lights","t":"event","uniqueid":"00:21:2e:ff:ff:07:d1:2e-01"} (ret = 344)
17:50:24:596 Daylight now: sunsetEnd, status: 200, daylight: 0, dark: 1

Additional context

So far (as you can see) I only have a single Tradfri PIR connected.

Mimiix commented 2 years ago

Hi,

As this is not a bug report as described in #5113 , I am closing this issue.

Feel free to open an topic on the forums.

Kind regards,