[2021-04-14 23:59:34][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'000000218000004001000015b4c94f32ffff029c0004' [2021-04-14 23:59:34][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:34][DEBUG] : Received incomingMessageHandler frame with [, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=21), 180, -55, 0x324f, 255, 255, b'\x9c\x00'] [2021-04-14 23:59:34][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:34][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:34][DEBUG] : executing functools.partial() [2021-04-14 23:59:34][DEBUG] : Data frame: b'0363b1a96b2a15fd6b944a04aa5592099d4e27ce70ce67bafe7e' [2021-04-14 23:59:34][DEBUG] : Sending: b'8160597e' [2021-04-14 23:59:34][DEBUG] : Application frame 63 (messageSentHandler) received: b'004f3200002100000040010000659d0000' [2021-04-14 23:59:34][DEBUG] : Received messageSentHandler frame with [, 12879, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=101), 157, , b''] [2021-04-14 23:59:34][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bound 'poll_control' input cluster [2021-04-14 23:59:34][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Intput cluster 32 has specific function to initialize, I used it [2021-04-14 23:59:34][DEBUG] : [0x324f] Extending timeout for 0x9e request [2021-04-14 23:59:34][DEBUG] : Extending timeout for 68:0a:e2:ff:fe:40:5a:16/0x324f [2021-04-14 23:59:34][DEBUG] : Send command setExtendedTimeout: (68:0a:e2:ff:fe:40:5a:16, True) [2021-04-14 23:59:34][DEBUG] : Sending: b'316021a92a2a03e8196ab5c7a03d9320e87e' [2021-04-14 23:59:34][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:34][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:34][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:34][DEBUG] : executing functools.partial() [2021-04-14 23:59:34][DEBUG] : Data frame: b'1460a1a92a2ad8237e' [2021-04-14 23:59:34][DEBUG] : Sending: b'82503a7e' [2021-04-14 23:59:34][DEBUG] : Application frame 126 (setExtendedTimeout) received: b'' [2021-04-14 23:59:34][DEBUG] : Send command sendUnicast: (, 0x324f, EmberApsFrame(profileId=260, clusterId=32, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=158), 159, b'\x00\x9e\x02\x00\x00#@8\x00\x00') [2021-04-14 23:59:34][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:34][DEBUG] : Sending: b'426121a9602a15fd6b904b05aa5493099d4e273572c46715ffc663aabc463fa75b817e' [2021-04-14 23:59:34][DEBUG] : Data frame: b'2561a1a9602a15d440a97e' [2021-04-14 23:59:34][DEBUG] : Sending: b'83401b7e' [2021-04-14 23:59:34][DEBUG] : Application frame 52 (sendUnicast) received: b'0066' [2021-04-14 23:59:36][DEBUG] : Data frame: b'3561b1a90d2a5a804fce0adb55b798212484261533eaa07e' [2021-04-14 23:59:36][DEBUG] : Sending: b'8430fc7e' [2021-04-14 23:59:36][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:36][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:36][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:36][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:36][DEBUG] : Data frame: b'4561b1a90d2a5a804fce0adb55b79821288726153362577e' [2021-04-14 23:59:36][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:36][DEBUG] : Sending: b'8520dd7e' [2021-04-14 23:59:36][DEBUG] : executing functools.partial() [2021-04-14 23:59:36][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b4c901bede' [2021-04-14 23:59:36][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 180, -55, [0xdebe]] [2021-04-14 23:59:36][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 180, -55, [0xdebe]) [2021-04-14 23:59:36][DEBUG] : Data frame: b'5561b1a9112a15b658b44a24ab1593499c599f61a2fc9874f9defd8dfc7aa1a87e' [2021-04-14 23:59:36][DEBUG] : Sending: b'8610be7e' [2021-04-14 23:59:36][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:36][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'000401200001014001000017b8ca4f32ffff04189e040004' [2021-04-14 23:59:36][DEBUG] : Received incomingMessageHandler frame with [, EmberApsFrame(profileId=260, clusterId=32, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=23), 184, -54, 0x324f, 255, 255, b'\x18\x9e\x04\x00'] [2021-04-14 23:59:36][DEBUG] : Data frame: b'6561b1a96b2a15fd6b904b05aa5493099d4e27cd72ce6744087e' [2021-04-14 23:59:36][DEBUG] : Sending: b'87009f7e' [2021-04-14 23:59:36][DEBUG] : [0x324f:1:0x0020] ZCL deserialize: manufacturer=None tsn=158 command_id=Command.Write_Attributes_rsp> [2021-04-14 23:59:36][DEBUG] : Application frame 63 (messageSentHandler) received: b'004f3204012000010140010000669f0000' [2021-04-14 23:59:36][DEBUG] : Received messageSentHandler frame with [, 12879, EmberApsFrame(profileId=260, clusterId=32, sourceEndpoint=1, destinationEndpoint=1, options=, groupId=0, sequence=102), 159, , b''] [2021-04-14 23:59:36][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][chanels.general.PollControl.initialize] 3600.0s check-in interval set: [[WriteAttributesStatusRecord(status=)]] [2021-04-14 23:59:36][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] End configuration of input cluster 'poll_control' [2021-04-14 23:59:36][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Begin configuration of input cluster 'lightlink', is_server 'True' [2021-04-14 23:59:36][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bind input cluster 'lightlink' [2021-04-14 23:59:36][DEBUG] : [0x324f] Extending timeout for 0xa0 request [2021-04-14 23:59:36][DEBUG] : Extending timeout for 68:0a:e2:ff:fe:40:5a:16/0x324f [2021-04-14 23:59:36][DEBUG] : Send command setExtendedTimeout: (68:0a:e2:ff:fe:40:5a:16, True) [2021-04-14 23:59:36][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:36][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:36][DEBUG] : Sending: b'576621a92a2a03e8196ab5c7a03d934af97e' [2021-04-14 23:59:36][DEBUG] : executing functools.partial() [2021-04-14 23:59:36][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:36][DEBUG] : Data frame: b'7666a1a92a2ac1fe7e' [2021-04-14 23:59:36][DEBUG] : Sending: b'8070787e' [2021-04-14 23:59:36][DEBUG] : Application frame 126 (setExtendedTimeout) received: b'' [2021-04-14 23:59:36][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:36][DEBUG] : Send command sendUnicast: (, 0x324f, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=160), 161, b'\xa0\x16Z@\xfe\xff\xe2 h\x01\x00\x10\x03\xc6\xf1\xa5\xfe\xff\xcc\xcc\xcc\x01') [2021-04-14 23:59:36][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:36][DEBUG] : Sending: b'606721a9602a15fd6b944a04aa5592099d4e270b4cd8c79da7869d761e7457a6ebdddda97d5e5a3924191ea58d6d7f7e' [2021-04-14 23:59:36][DEBUG] : executing functools.partial() [2021-04-14 23:59:36][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:36][DEBUG] : Data frame: b'0767a1a9602a15d505637e' [2021-04-14 23:59:36][DEBUG] : Sending: b'8160597e' [2021-04-14 23:59:36][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:36][DEBUG] : Application frame 52 (sendUnicast) received: b'0067' [2021-04-14 23:59:36][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:36][DEBUG] : executing functools.partial() [2021-04-14 23:59:36][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:37][DEBUG] : Data frame: b'1767b1a90d2a5a804fce0adb55b798212484261533e17c7e' [2021-04-14 23:59:37][DEBUG] : Sending: b'82503a7e' [2021-04-14 23:59:37][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:37][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:37][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:37][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:37][DEBUG] : Data frame: b'2767b1a90d2a5a804fce0adb55b798212484261533620a7e' [2021-04-14 23:59:37][DEBUG] : Sending: b'83401b7e' [2021-04-14 23:59:37][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:37][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:37][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:37][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:37][DEBUG] : executing functools.partial() [2021-04-14 23:59:37][DEBUG] : Data frame: b'3767b1a9112a15b259b5ca25aa1593499c569f61a2fc9874ff66638d00017e' [2021-04-14 23:59:37][DEBUG] : Sending: b'8430fc7e' [2021-04-14 23:59:37][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:37][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'000000218000004001000018b8ca4f32ffff02a00004' [2021-04-14 23:59:37][DEBUG] : Received incomingMessageHandler frame with [, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=24), 184, -54, 0x324f, 255, 255, b'\xa0\x00'] [2021-04-14 23:59:37][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:37][DEBUG] : Data frame: b'4767b1a96b2a15fd6b944a04aa5592099d4e27cc4cce6705c67e' [2021-04-14 23:59:37][DEBUG] : Sending: b'8520dd7e' [2021-04-14 23:59:37][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:37][DEBUG] : Application frame 63 (messageSentHandler) received: b'004f320000210000004001000067a10000' [2021-04-14 23:59:37][DEBUG] : Received messageSentHandler frame with [, 12879, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=103), 161, , b''] [2021-04-14 23:59:37][DEBUG] : executing functools.partial() [2021-04-14 23:59:37][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bound 'lightlink' input cluster [2021-04-14 23:59:37][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] End configuration of input cluster 'lightlink' [2021-04-14 23:59:37][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Begin configuration of input cluster 'manufacturer_specific', is_server 'True' [2021-04-14 23:59:37][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] End configuration of input cluster 'manufacturer_specific' [2021-04-14 23:59:37][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'on_off', is_server 'False' [2021-04-14 23:59:37][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bind 'on_off' output cluster [2021-04-14 23:59:37][DEBUG] : [0x324f] Extending timeout for 0xa2 request [2021-04-14 23:59:37][DEBUG] : Extending timeout for 68:0a:e2:ff:fe:40:5a:16/0x324f [2021-04-14 23:59:37][DEBUG] : Send command setExtendedTimeout: (68:0a:e2:ff:fe:40:5a:16, True) [2021-04-14 23:59:37][DEBUG] : Sending: b'756421a92a2a03e8196ab5c7a03d936cf67e' [2021-04-14 23:59:37][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:37][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:37][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:37][DEBUG] : Data frame: b'5064a1a92a2a3d947e' [2021-04-14 23:59:37][DEBUG] : Sending: b'8610be7e' [2021-04-14 23:59:37][DEBUG] : Application frame 126 (setExtendedTimeout) received: b'' [2021-04-14 23:59:37][DEBUG] : Send command sendUnicast: (, 0x324f, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=162), 163, b'\xa2\x16Z@\xfe\xff\xe2 h\x01\x06\x00\x03\xc6\xf1\xa5\xfe\xff\xcc\xcc\xcc\x01') [2021-04-14 23:59:37][DEBUG] : executing functools.partial() [2021-04-14 23:59:37][DEBUG] : Sending: b'066521a9602a15fd6b944a04aa5592099d4e27094ed8c59da7869d761e7457a6edcddda97d5e5a3924191ea58ddd677e' [2021-04-14 23:59:37][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:37][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:37][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:37][DEBUG] : executing functools.partial() [2021-04-14 23:59:37][DEBUG] : Data frame: b'6165a1a9602a15da991c7e' [2021-04-14 23:59:37][DEBUG] : Sending: b'87009f7e' [2021-04-14 23:59:37][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:37][DEBUG] : Application frame 52 (sendUnicast) received: b'0068' [2021-04-14 23:59:37][DEBUG] : Data frame: b'7165b1a96b2a134da6904b26aa546d499d4e27ba12ce6746c37e' [2021-04-14 23:59:37][DEBUG] : Sending: b'8070787e' [2021-04-14 23:59:37][DEBUG] : Application frame 63 (messageSentHandler) received: b'06ffff0401030001ff0001000011ff0000' [2021-04-14 23:59:37][DEBUG] : Received messageSentHandler frame with [, 65535, EmberApsFrame(profileId=260, clusterId=3, sourceEndpoint=1, destinationEndpoint=255, options=, groupId=0, sequence=17), 255, , b''] [2021-04-14 23:59:38][DEBUG] : Unexpected message send notification tag: 255 [2021-04-14 23:59:38][DEBUG] : Data frame: b'0165b1a90d2a5a804fce0adb55b798212484261533d8a17e' [2021-04-14 23:59:38][DEBUG] : Sending: b'8160597e' [2021-04-14 23:59:38][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:38][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:38][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:38][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:38][DEBUG] : Data frame: b'1165b1a90d2a5a804fce0adb55b798212887261533469b7e' [2021-04-14 23:59:38][DEBUG] : Sending: b'82503a7e' [2021-04-14 23:59:38][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b4c901bede' [2021-04-14 23:59:38][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 180, -55, [0xdebe]] [2021-04-14 23:59:38][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 180, -55, [0xdebe]) [2021-04-14 23:59:38][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:38][DEBUG] : executing functools.partial() [2021-04-14 23:59:38][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:38][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:38][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:38][DEBUG] : executing functools.partial() [2021-04-14 23:59:38][DEBUG] : Data frame: b'2165b1a9112a15b259b5ca25aa1593499c579f61a2fc9874ff64638d2e9e7e' [2021-04-14 23:59:38][DEBUG] : Sending: b'83401b7e' [2021-04-14 23:59:38][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'000000218000004001000019b8ca4f32ffff02a20004' [2021-04-14 23:59:38][DEBUG] : Received incomingMessageHandler frame with [, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=25), 184, -54, 0x324f, 255, 255, b'\xa2\x00'] [2021-04-14 23:59:38][DEBUG] : Data frame: b'3165b1a96b2a15fd6b944a04aa5592099d4e27c34ece67bba17e' [2021-04-14 23:59:38][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:38][DEBUG] : Sending: b'8430fc7e' [2021-04-14 23:59:38][DEBUG] : Application frame 63 (messageSentHandler) received: b'004f320000210000004001000068a30000' [2021-04-14 23:59:38][DEBUG] : Received messageSentHandler frame with [, 12879, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=104), 163, , b''] [2021-04-14 23:59:38][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bound 'on_off' output cluster [2021-04-14 23:59:38][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] End configuration of output cluster 'on_off' [2021-04-14 23:59:38][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'level', is_server 'False' [2021-04-14 23:59:38][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:38][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bind 'level' output cluster [2021-04-14 23:59:38][DEBUG] : [0x324f] Extending timeout for 0xa4 request [2021-04-14 23:59:38][DEBUG] : Extending timeout for 68:0a:e2:ff:fe:40:5a:16/0x324f [2021-04-14 23:59:38][DEBUG] : Send command setExtendedTimeout: (68:0a:e2:ff:fe:40:5a:16, True) [2021-04-14 23:59:38][DEBUG] : Sending: b'146a21a92a2a03e8196ab5c7a03d93951b7e' [2021-04-14 23:59:38][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:38][DEBUG] : executing functools.partial() [2021-04-14 23:59:38][DEBUG] : Data frame: b'426aa1a92a2a63f87e' [2021-04-14 23:59:38][DEBUG] : Sending: b'8520dd7e' [2021-04-14 23:59:38][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:38][DEBUG] : Application frame 126 (setExtendedTimeout) received: b'' [2021-04-14 23:59:38][DEBUG] : Send command sendUnicast: (, 0x324f, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=164), 165, b'\xa4\x16Z@\xfe\xff\xe2 h\x01\x08\x00\x03\xc6\xf1\xa5\xfe\xff\xcc\xcc\xcc\x01') [2021-04-14 23:59:38][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:38][DEBUG] : Sending: b'256b21a9602a15fd6b944a04aa5592099d4e270f48d8c39da7869d761e7457a6e3cddda97d5e5a3924191ea58d31017e' [2021-04-14 23:59:38][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:38][DEBUG] : executing functools.partial() [2021-04-14 23:59:38][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:38][DEBUG] : Data frame: b'536ba1a9602a15db72cf7e' [2021-04-14 23:59:38][DEBUG] : Sending: b'8610be7e' [2021-04-14 23:59:38][DEBUG] : Application frame 52 (sendUnicast) received: b'0069' [2021-04-14 23:59:38][DEBUG] : Send command nop: () [2021-04-14 23:59:38][DEBUG] : Sending: b'366821a9512a6ddd7e' [2021-04-14 23:59:38][DEBUG] : Data frame: b'6468a1a9512a4b317e' [2021-04-14 23:59:38][DEBUG] : Sending: b'87009f7e' [2021-04-14 23:59:38][DEBUG] : Application frame 5 (nop) received: b'' [2021-04-14 23:59:39][DEBUG] : Data frame: b'7468b1a90d2a5a804fce0adb55b798212484261533ac2a7e' [2021-04-14 23:59:39][DEBUG] : Sending: b'8070787e' [2021-04-14 23:59:39][DEBUG] : Data frame: b'0468b1a90d2a5a804fce0adb55b798212484261533342a7e' [2021-04-14 23:59:39][DEBUG] : Sending: b'8160597e' [2021-04-14 23:59:39][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:39][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:39][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:39][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:39][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:39][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:39][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:39][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:39][DEBUG] : executing functools.partial() [2021-04-14 23:59:39][DEBUG] : Data frame: b'1468b1a9112a15b259b5ca25aa1593499c549f61a2fc9874ff62638d02c87e' [2021-04-14 23:59:39][DEBUG] : Sending: b'82503a7e' [2021-04-14 23:59:39][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:39][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00000021800000400100001ab8ca4f32ffff02a40004' [2021-04-14 23:59:39][DEBUG] : Received incomingMessageHandler frame with [, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=26), 184, -54, 0x324f, 255, 255, b'\xa4\x00'] [2021-04-14 23:59:39][DEBUG] : Data frame: b'2468b1a96b2a15fd6b944a04aa5592099d4e27c248ce673aff7e' [2021-04-14 23:59:39][DEBUG] : Sending: b'83401b7e' [2021-04-14 23:59:39][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:39][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:39][DEBUG] : Application frame 63 (messageSentHandler) received: b'004f320000210000004001000069a50000' [2021-04-14 23:59:39][DEBUG] : Received messageSentHandler frame with [, 12879, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=105), 165, , b''] [2021-04-14 23:59:39][DEBUG] : executing functools.partial() [2021-04-14 23:59:39][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bound 'level' output cluster [2021-04-14 23:59:39][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] End configuration of output cluster 'level' [2021-04-14 23:59:39][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'window_covering', is_server 'False' [2021-04-14 23:59:39][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bind 'window_covering' output cluster [2021-04-14 23:59:39][DEBUG] : [0x324f] Extending timeout for 0xa6 request [2021-04-14 23:59:39][DEBUG] : Extending timeout for 68:0a:e2:ff:fe:40:5a:16/0x324f [2021-04-14 23:59:39][DEBUG] : Send command setExtendedTimeout: (68:0a:e2:ff:fe:40:5a:16, True) [2021-04-14 23:59:39][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:39][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:39][DEBUG] : Sending: b'436921a92a2a03e8196ab5c7a03d9304ac7e' [2021-04-14 23:59:39][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:39][DEBUG] : executing functools.partial() [2021-04-14 23:59:39][DEBUG] : Data frame: b'3569a1a92a2a00f77e' [2021-04-14 23:59:39][DEBUG] : Sending: b'8430fc7e' [2021-04-14 23:59:39][DEBUG] : Application frame 126 (setExtendedTimeout) received: b'' [2021-04-14 23:59:39][DEBUG] : Send command sendUnicast: (, 0x324f, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=166), 167, b'\xa6\x16Z@\xfe\xff\xe2 h\x01\x02\x01\x03\xc6\xf1\xa5\xfe\xff\xcc\xcc\xcc\x01') [2021-04-14 23:59:39][DEBUG] : Sending: b'546e21a9602a15fd6b944a04aa5592099d4e270d4ad8c19da7869d761e7457a6e9ccdda97d5e5a3924191ea58de6677e' [2021-04-14 23:59:39][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:39][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:39][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:39][DEBUG] : executing functools.partial() [2021-04-14 23:59:39][DEBUG] : Data frame: b'466ea1a9602a15d84e017e' [2021-04-14 23:59:39][DEBUG] : Sending: b'8520dd7e' [2021-04-14 23:59:39][DEBUG] : Application frame 52 (sendUnicast) received: b'006a' [2021-04-14 23:59:39][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:40][DEBUG] : Data frame: b'566eb1a90d2a5a804fce0adb55b798212484261533a7f67e' [2021-04-14 23:59:40][DEBUG] : Sending: b'8610be7e' [2021-04-14 23:59:40][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:40][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:40][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:40][DEBUG] : Data frame: b'666eb1a90d2a5a804fce0adb55b79821248426153324807e' [2021-04-14 23:59:40][DEBUG] : Sending: b'87009f7e' [2021-04-14 23:59:40][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:40][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:40][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:40][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:40][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:40][DEBUG] : executing functools.partial() [2021-04-14 23:59:40][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:40][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:40][DEBUG] : Data frame: b'766eb1a9112a15b259b5ca25aa1593499c559f61a2fc9874ff60638de4b07e' [2021-04-14 23:59:40][DEBUG] : Sending: b'8070787e' [2021-04-14 23:59:40][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:40][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00000021800000400100001bb8ca4f32ffff02a60004' [2021-04-14 23:59:40][DEBUG] : Received incomingMessageHandler frame with [, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=27), 184, -54, 0x324f, 255, 255, b'\xa6\x00'] [2021-04-14 23:59:40][DEBUG] : executing functools.partial() [2021-04-14 23:59:40][DEBUG] : Data frame: b'066eb1a96b2a15fd6b944a04aa5592099d4e27c14ace6719487e' [2021-04-14 23:59:40][DEBUG] : Sending: b'8160597e' [2021-04-14 23:59:40][DEBUG] : Application frame 63 (messageSentHandler) received: b'004f32000021000000400100006aa70000' [2021-04-14 23:59:40][DEBUG] : Received messageSentHandler frame with [, 12879, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=106), 167, , b''] [2021-04-14 23:59:40][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:40][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:40][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bound 'window_covering' output cluster [2021-04-14 23:59:40][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:40][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] End configuration of output cluster 'window_covering' [2021-04-14 23:59:40][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'lightlink', is_server 'False' [2021-04-14 23:59:40][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bind 'lightlink' output cluster [2021-04-14 23:59:40][DEBUG] : [0x324f] Extending timeout for 0xa8 request [2021-04-14 23:59:40][DEBUG] : Extending timeout for 68:0a:e2:ff:fe:40:5a:16/0x324f [2021-04-14 23:59:40][DEBUG] : Send command setExtendedTimeout: (68:0a:e2:ff:fe:40:5a:16, True) [2021-04-14 23:59:40][DEBUG] : executing functools.partial() [2021-04-14 23:59:40][DEBUG] : Sending: b'616f21a92a2a03e8196ab5c7a03d93df067e' [2021-04-14 23:59:40][DEBUG] : Data frame: b'176fa1a92a2a733a7e' [2021-04-14 23:59:40][DEBUG] : Sending: b'82503a7e' [2021-04-14 23:59:40][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:40][DEBUG] : Application frame 126 (setExtendedTimeout) received: b'' [2021-04-14 23:59:40][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:40][DEBUG] : Send command sendUnicast: (, 0x324f, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=168), 169, b'\xa8\x16Z@\xfe\xff\xe2 h\x01\x00\x10\x03\xc6\xf1\xa5\xfe\xff\xcc\xcc\xcc\x01') [2021-04-14 23:59:40][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:40][DEBUG] : Sending: b'726c21a9602a15fd6b944a04aa5592099d4e270344d8cf9da7869d761e7457a6ebdddda97d5e5a3924191ea58dfc367e' [2021-04-14 23:59:40][DEBUG] : executing functools.partial() [2021-04-14 23:59:40][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:40][DEBUG] : Data frame: b'206ca1a9602a15d933b07e' [2021-04-14 23:59:40][DEBUG] : Sending: b'83401b7e' [2021-04-14 23:59:40][DEBUG] : Application frame 52 (sendUnicast) received: b'006b' [2021-04-14 23:59:41][DEBUG] : Data frame: b'306cb1a90d2a5a804fce0adb55b798212484261533062b7e' [2021-04-14 23:59:41][DEBUG] : Sending: b'8430fc7e' [2021-04-14 23:59:41][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:41][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:41][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:41][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:41][DEBUG] : Data frame: b'406cb1a90d2a5a804fce0adb55b7982124842615339e2b7e' [2021-04-14 23:59:41][DEBUG] : Sending: b'8520dd7e' [2021-04-14 23:59:41][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b8ca01bede' [2021-04-14 23:59:41][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]] [2021-04-14 23:59:41][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 184, -54, [0xdebe]) [2021-04-14 23:59:41][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:41][DEBUG] : executing functools.partial() [2021-04-14 23:59:41][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:41][DEBUG] : Data frame: b'506cb1a9112a15b259b5ca25aa1593499c529f61a2fc9874ff6e638df5bc7e' [2021-04-14 23:59:41][DEBUG] : Sending: b'8610be7e' [2021-04-14 23:59:41][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00000021800000400100001cb8ca4f32ffff02a80004' [2021-04-14 23:59:41][DEBUG] : Received incomingMessageHandler frame with [, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=28), 184, -54, 0x324f, 255, 255, b'\xa8\x00'] [2021-04-14 23:59:41][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:41][DEBUG] : Data frame: b'606cb1a96b2a15fd6b944a04aa5592099d4e27c044ce6740627e' [2021-04-14 23:59:41][DEBUG] : Sending: b'87009f7e' [2021-04-14 23:59:41][DEBUG] : Application frame 63 (messageSentHandler) received: b'004f32000021000000400100006ba90000' [2021-04-14 23:59:41][DEBUG] : Received messageSentHandler frame with [, 12879, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=107), 169, , b''] [2021-04-14 23:59:41][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] Bound 'lightlink' output cluster [2021-04-14 23:59:41][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize][Endpoint 1] End configuration of output cluster 'lightlink' [2021-04-14 23:59:41][WARNING] : [68:0a:e2:ff:fe:40:5a:16][zdevices.get_basic_info] Begin get basic info from device [2021-04-14 23:59:41][WARNING] : [68:0a:e2:ff:fe:40:5a:16][zdevices.get_basic_info] End point found [2021-04-14 23:59:41][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:41][DEBUG] : executing functools.partial() [2021-04-14 23:59:41][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:41][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:41][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:41][DEBUG] : executing functools.partial() [2021-04-14 23:59:41][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:41][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:41][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:41][DEBUG] : executing functools.partial() [2021-04-14 23:59:41][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:44][WARNING] : [68:0a:e2:ff:fe:40:5a:16][zdevices.get_basic_info] End get basic info from device [2021-04-14 23:59:44][DEBUG] : [68:0a:e2:ff:fe:40:5a:16][zdevices.initialize] End device initialize [2021-04-14 23:59:44][DEBUG] : Data frame: b'706cb1a90d2a5a804fce0adb55b7982128872615330daa7e' [2021-04-14 23:59:44][DEBUG] : Sending: b'8070787e' [2021-04-14 23:59:44][DEBUG] : Application frame 89 (incomingRouteRecordHandler) received: b'4f32165a40feffe20a68b4c901bede' [2021-04-14 23:59:44][DEBUG] : Received incomingRouteRecordHandler frame with [0x324f, 68:0a:e2:ff:fe:40:5a:16, 180, -55, [0xdebe]] [2021-04-14 23:59:44][DEBUG] : Processing route record request: (0x324f, 68:0a:e2:ff:fe:40:5a:16, 180, -55, [0xdebe]) [2021-04-14 23:59:44][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:44][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:44][DEBUG] : executing functools.partial() [2021-04-14 23:59:44][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:44][DEBUG] : executing functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) [2021-04-14 23:59:44][DEBUG] : operation functools.partial(, 'INSERT OR REPLACE INTO relays VALUES (?, ?)', (68:0a:e2:ff:fe:40:5a:16, b'\x01\xbe\xde')) completed [2021-04-14 23:59:44][DEBUG] : executing functools.partial() [2021-04-14 23:59:44][DEBUG] : Data frame: b'006cb1a9112a15b259b5ca25aa1593499c589f61a2fc9874ff5a638d33cf7e' [2021-04-14 23:59:44][DEBUG] : Sending: b'8160597e' [2021-04-14 23:59:44][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'000000218000004001000016b8ca4f32ffff029c0004' [2021-04-14 23:59:44][DEBUG] : Received incomingMessageHandler frame with [, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=, groupId=0, sequence=22), 184, -54, 0x324f, 255, 255, b'\x9c\x00'] [2021-04-14 23:59:44][DEBUG] : [0x324f:zdo] ZDO request ZDOCmd.Bind_rsp: [] [2021-04-14 23:59:44][DEBUG] : operation functools.partial() completed [2021-04-14 23:59:44][DEBUG] : [0x324f:zdo] Unsupported ZDO request:ZDOCmd.Bind_rsp [2021-04-14 23:59:48][DEBUG] : Send command nop: () [2021-04-14 23:59:48][DEBUG] : Sending: b'016d21a9512aa9477e' [2021-04-14 23:59:48][DEBUG] : Data frame: b'116da1a9512a6efb7e' [2021-04-14 23:59:48][DEBUG] : Sending: b'82503a7e' [2021-04-14 23:59:48][DEBUG] : Application frame 5 (nop) received: b'' [2021-04-14 23:59:50][DEBUG] : [DeviceHandler.prepare] Json arg : None [2021-04-14 23:59:50][DEBUG] : [0xdebe:1] Manufacturer: IKEA of Sweden [2021-04-14 23:59:50][DEBUG] : [0xdebe:1] Model: TRADFRI Signal Repeater [2021-04-14 23:59:50][DEBUG] : [0xd60f:1] Manufacturer: IKEA of Sweden [2021-04-14 23:59:50][DEBUG] : [0xd60f:1] Model: TRADFRI Signal Repeater [2021-04-14 23:59:50][DEBUG] : [0x9447:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0x9447:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0xa122:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0xa122:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0x78f4:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0x78f4:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0x550b:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0x550b:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0x97e7:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0x97e7:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0x6863:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0x6863:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0x8520:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0x8520:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0xed15:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0xed15:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0x4a6d:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0x4a6d:1] Model: lumi.sensor_magnet.aq2 [2021-04-14 23:59:50][DEBUG] : [0xf692:1] Manufacturer: IKEA of Sweden [2021-04-14 23:59:50][DEBUG] : [0xf692:1] Model: KADRILJ roller blind [2021-04-14 23:59:50][DEBUG] : [0x356a:1] Manufacturer: IKEA of Sweden [2021-04-14 23:59:50][DEBUG] : [0x356a:1] Model: KADRILJ roller blind [2021-04-14 23:59:50][DEBUG] : [0x687a:1] Manufacturer: IKEA of Sweden [2021-04-14 23:59:50][DEBUG] : [0x687a:1] Model: KADRILJ roller blind [2021-04-14 23:59:50][DEBUG] : [0xa0a8:1] Manufacturer: KE [2021-04-14 23:59:50][DEBUG] : [0xa0a8:1] Model: TRADFRI open/close remote [2021-04-14 23:59:50][DEBUG] : [0x8d82:1] Manufacturer: IKEA of Sweden [2021-04-14 23:59:50][DEBUG] : [0x8d82:1] Model: TRADFRI Signal Repeater [2021-04-14 23:59:50][DEBUG] : [0xa6a3:1] Manufacturer: IKEA of Sweden [2021-04-14 23:59:50][DEBUG] : [0xa6a3:1] Model: TRADFRI Signal Repeater [2021-04-14 23:59:50][DEBUG] : [0xaaa5:1] Manufacturer: LUMI [2021-04-14 23:59:50][DEBUG] : [0xaaa5:1] Model: lumi.weather [2021-04-14 23:59:50][DEBUG] : [0x324f:1] Manufacturer: IKEA of Sweden [2021-04-14 23:59:50][DEBUG] : [0x324f:1] Model: TRADFRI open/close remote [2021-04-14 23:59:50][INFO] : 200 GET /device/all?with_attributes=2 (127.0.0.1) 52.37ms [2021-04-14 23:59:50][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '68:0a:e2:ff:fe:f2:7a:83'} [2021-04-14 23:59:50][DEBUG] : [DeviceHandler.put/update_specific] Update specific configuration for 68:0a:e2:ff:fe:f2:7a:83 to {'poll_control': {'long_poll': '1160'}} [2021-04-14 23:59:50][INFO] : 200 PUT /device/update_specific (127.0.0.1) 6.14ms [2021-04-14 23:59:50][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': '68:0a:e2:ff:fe:40:5a:16'} [2021-04-14 23:59:50][DEBUG] : [DeviceHandler.put/update_specific] Update specific configuration for 68:0a:e2:ff:fe:40:5a:16 to {'poll_control': {'long_poll': '1160'}} [2021-04-14 23:59:50][INFO] : 200 PUT /device/update_specific (127.0.0.1) 3.43ms [2021-04-14 23:59:50][DEBUG] : [GroupHandler.prepare] Json arg : None [2021-04-14 23:59:50][INFO] : 200 GET /group/all (127.0.0.1) 2.76ms [2021-04-14 23:59:50][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=J0Ru2vO2VwB59XbYJE7VeNQaJErSvMr6 HTTP/1.1" 200 0 [2021-04-14 23:59:58][DEBUG] : Send command nop: () [2021-04-14 23:59:58][DEBUG] : Sending: b'127221a9512a1c807e' [2021-04-14 23:59:58][DEBUG] : Data frame: b'2272a1a9512aee347e' [2021-04-14 23:59:58][DEBUG] : Sending: b'83401b7e' [2021-04-14 23:59:58][DEBUG] : Application frame 5 (nop) received: b'' [2021-04-15 00:00:03][DEBUG] : [DeviceHandler.prepare] Json arg : None [2021-04-15 00:00:03][DEBUG] : [0xdebe:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:03][DEBUG] : [0xdebe:1] Model: TRADFRI Signal Repeater [2021-04-15 00:00:03][DEBUG] : [0xd60f:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:03][DEBUG] : [0xd60f:1] Model: TRADFRI Signal Repeater [2021-04-15 00:00:03][DEBUG] : [0x9447:1] Manufacturer: LUMI [2021-04-15 00:00:03][DEBUG] : [0x9447:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:03][DEBUG] : [0xa122:1] Manufacturer: LUMI [2021-04-15 00:00:03][DEBUG] : [0xa122:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:03][DEBUG] : [0x78f4:1] Manufacturer: LUMI [2021-04-15 00:00:03][DEBUG] : [0x78f4:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x550b:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x550b:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x97e7:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x97e7:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x6863:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x6863:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x8520:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x8520:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0xed15:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0xed15:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x4a6d:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x4a6d:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0xf692:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0xf692:1] Model: KADRILJ roller blind [2021-04-15 00:00:04][DEBUG] : [0x356a:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0x356a:1] Model: KADRILJ roller blind [2021-04-15 00:00:04][DEBUG] : [0x687a:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0x687a:1] Model: KADRILJ roller blind [2021-04-15 00:00:04][DEBUG] : [0xa0a8:1] Manufacturer: KE [2021-04-15 00:00:04][DEBUG] : [0xa0a8:1] Model: TRADFRI open/close remote [2021-04-15 00:00:04][DEBUG] : [0x8d82:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0x8d82:1] Model: TRADFRI Signal Repeater [2021-04-15 00:00:04][DEBUG] : [0xa6a3:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0xa6a3:1] Model: TRADFRI Signal Repeater [2021-04-15 00:00:04][DEBUG] : [0xaaa5:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0xaaa5:1] Model: lumi.weather [2021-04-15 00:00:04][DEBUG] : [0x324f:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0x324f:1] Model: TRADFRI open/close remote [2021-04-15 00:00:04][INFO] : 200 GET /device/all (127.0.0.1) 197.39ms [2021-04-15 00:00:04][DEBUG] : [DeviceHandler.prepare] Json arg : None [2021-04-15 00:00:04][DEBUG] : [0xdebe:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0xdebe:1] Model: TRADFRI Signal Repeater [2021-04-15 00:00:04][DEBUG] : [0xd60f:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0xd60f:1] Model: TRADFRI Signal Repeater [2021-04-15 00:00:04][DEBUG] : [0x9447:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x9447:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0xa122:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0xa122:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x78f4:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x78f4:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x550b:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x550b:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x97e7:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x97e7:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x6863:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x6863:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x8520:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x8520:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0xed15:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0xed15:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0x4a6d:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0x4a6d:1] Model: lumi.sensor_magnet.aq2 [2021-04-15 00:00:04][DEBUG] : [0xf692:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0xf692:1] Model: KADRILJ roller blind [2021-04-15 00:00:04][DEBUG] : [0x356a:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0x356a:1] Model: KADRILJ roller blind [2021-04-15 00:00:04][DEBUG] : [0x687a:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0x687a:1] Model: KADRILJ roller blind [2021-04-15 00:00:04][DEBUG] : [0xa0a8:1] Manufacturer: KE [2021-04-15 00:00:04][DEBUG] : [0xa0a8:1] Model: TRADFRI open/close remote [2021-04-15 00:00:04][DEBUG] : [0x8d82:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0x8d82:1] Model: TRADFRI Signal Repeater [2021-04-15 00:00:04][DEBUG] : [0xa6a3:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0xa6a3:1] Model: TRADFRI Signal Repeater [2021-04-15 00:00:04][DEBUG] : [0xaaa5:1] Manufacturer: LUMI [2021-04-15 00:00:04][DEBUG] : [0xaaa5:1] Model: lumi.weather [2021-04-15 00:00:04][DEBUG] : [0x324f:1] Manufacturer: IKEA of Sweden [2021-04-15 00:00:04][DEBUG] : [0x324f:1] Model: TRADFRI open/close remote [2021-04-15 00:00:04][INFO] : 200 GET /device/all (127.0.0.1) 92.38ms [2021-04-15 00:00:08][DEBUG] : Send command nop: () [2021-04-15 00:00:08][DEBUG] : Sending: b'237321a9512adcfd7e' [2021-04-15 00:00:08][DEBUG] : Data frame: b'3373a1a9512a1b417e' [2021-04-15 00:00:08][DEBUG] : Sending: b'8430fc7e' [2021-04-15 00:00:08][DEBUG] : Application frame 5 (nop) received: b''