[2020-12-16 20:29:27][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:27][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:27][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:27][DEBUG] : [0xe798] Delivery error for seq # 0x10, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'on_off' output cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of output cluster 'on_off'
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'level', is_server 'False'
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind 'level' output cluster
[2020-12-16 20:29:27][DEBUG] : [0xe798] Extending timeout for 0x11 request
[2020-12-16 20:29:27][DEBUG] : request (0xe798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 17, b'\x110\xd4*\xfe\xff\xbd\x1b\xec\x01\x08\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:27][DEBUG] : Send: 0x0530 b'02e7980000002100000000161130d42afeffbd1bec01080003b3eded01008d150001'
[2020-12-16 20:29:27][DEBUG] : Frame to send: b'\x050\x00"\xe8\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x110\xd4*\xfe\xff\xbd\x1b\xec\x01\x08\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:27][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xe8\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x110\xd4*\xfe\xff\xbd\x1b\xec\x02\x11\x02\x18\x02\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:27][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:27][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:27][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:27][DEBUG] : [0xe798] Delivery error for seq # 0x11, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'level' output cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of output cluster 'level'
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'lightlink', is_server 'False'
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind 'lightlink' output cluster
[2020-12-16 20:29:27][DEBUG] : [0xe798] Extending timeout for 0x12 request
[2020-12-16 20:29:27][DEBUG] : request (0xe798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 18, b'\x120\xd4*\xfe\xff\xbd\x1b\xec\x01\x00\x10\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:27][DEBUG] : Send: 0x0530 b'02e7980000002100000000161230d42afeffbd1bec01001003b3eded01008d150001'
[2020-12-16 20:29:27][DEBUG] : Frame to send: b'\x050\x00"\xf3\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x120\xd4*\xfe\xff\xbd\x1b\xec\x01\x00\x10\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:27][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xf3\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x120\xd4*\xfe\xff\xbd\x1b\xec\x02\x11\x02\x10\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:27][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:27][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:27][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:27][DEBUG] : [0xe798] Delivery error for seq # 0x12, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'lightlink' output cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:27][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of output cluster 'lightlink'
[2020-12-16 20:29:27][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Begin get basic info from device
[2020-12-16 20:29:27][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] End point found
[2020-12-16 20:29:28][DEBUG] : [0xe798] Extending timeout for 0x13 request
[2020-12-16 20:29:28][DEBUG] : request (0xe798, 260, 0, 1, 1, 19, b'\x00\x13\x00\x00\x00\x01\x00\x02\x00\x03\x00', True, False)
[2020-12-16 20:29:28][DEBUG] : Send: 0x0530 b'02e79801010000010400000b0013000000010002000300'
[2020-12-16 20:29:28][DEBUG] : Frame to send: b'\x050\x00\x17B\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x0b\x00\x13\x00\x00\x00\x01\x00\x02\x00\x03\x00'
[2020-12-16 20:29:28][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x17B\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x1b\x02\x10\x13\x02\x10\x02\x10\x02\x10\x02\x11\x02\x10\x02\x12\x02\x10\x02\x13\x02\x10'
[2020-12-16 20:29:28][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:28][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:28][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:28][DEBUG] : [0xe798] Delivery error for seq # 0x13, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:28][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Error on read attribute level 2 : [0xe798:1:0x0000]: Message send failure
[2020-12-16 20:29:28][DEBUG] : [0xe798] Extending timeout for 0x14 request
[2020-12-16 20:29:28][DEBUG] : request (0xe798, 260, 0, 1, 1, 20, b'\x00\x14\x00\x07\x00', True, False)
[2020-12-16 20:29:28][DEBUG] : Send: 0x0530 b'02e7980101000001040000050014000700'
[2020-12-16 20:29:28][DEBUG] : Frame to send: b'\x050\x00\x11J\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00\x14\x00\x07\x00'
[2020-12-16 20:29:28][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11J\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10\x14\x02\x10\x02\x17\x02\x10'
[2020-12-16 20:29:28][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:28][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:28][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:28][DEBUG] : [0xe798] Delivery error for seq # 0x14, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:28][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Error on read attribute level 3 : [0xe798:1:0x0000]: Message send failure
[2020-12-16 20:29:28][DEBUG] : [0xe798] Extending timeout for 0x15 request
[2020-12-16 20:29:28][DEBUG] : request (0xe798, 260, 0, 1, 1, 21, b'\x00\x15\x00\x06\x00\x00@', True, False)
[2020-12-16 20:29:28][DEBUG] : Send: 0x0530 b'02e79801010000010400000700150006000040'
[2020-12-16 20:29:28][DEBUG] : Frame to send: b'\x050\x00\x13
\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00\x15\x00\x06\x00\x00@'
[2020-12-16 20:29:28][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13\x02\x1a\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10\x15\x02\x10\x02\x16\x02\x10\x02\x10@'
[2020-12-16 20:29:28][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:28][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:28][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:28][DEBUG] : [0xe798] Delivery error for seq # 0x15, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:28][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Error on read attribute level 4 : [0xe798:1:0x0000]: Message send failure
[2020-12-16 20:29:28][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] End get basic info from device
[2020-12-16 20:29:28][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize] End device initialize
[2020-12-16 20:29:34][DEBUG] : [0xe798:1] Manufacturer: IKEA of Sweden
[2020-12-16 20:29:34][DEBUG] : [0xe798:1] Model: SYMFONISK Sound Controller
[2020-12-16 20:29:34][DEBUG] : Checking quirks for IKEA of Sweden SYMFONISK Sound Controller (ec:1b:bd:ff:fe:2a:d4:30)
[2020-12-16 20:29:34][DEBUG] : Considering <class 'zhaquirks.ikea.symfonisk.IkeaSYMFONISK'>
[2020-12-16 20:29:34][DEBUG] : Found custom device replacement for ec:1b:bd:ff:fe:2a:d4:30: <class 'zhaquirks.ikea.symfonisk.IkeaSYMFONISK'>
[2020-12-16 20:29:34][INFO] : [ec:1b:bd:ff:fe:2a:d4:30][listener.device_initialized] new=True
[2020-12-16 20:29:34][DEBUG] : Send to jeedom :  {'device_initialized': 'ec:1b:bd:ff:fe:2a:d4:30'}
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize] Begin device initialize
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of input cluster 'basic', is_server 'True'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind input cluster 'basic'
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x16 request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 22, b'\x160\xd4*\xfe\xff\xbd\x1b\xec\x01\x00\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e7980000002100000000161630d42afeffbd1bec01000003b3eded01008d150001'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00"\xe7\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x160\xd4*\xfe\xff\xbd\x1b\xec\x01\x00\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xe7\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x160\xd4*\xfe\xff\xbd\x1b\xec\x02\x11\x02\x10\x02\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:34][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f7bee27c570>, 'INSERT INTO devices (ieee, nwk, status) VALUES (?, ?, ?)', (ec:1b:bd:ff:fe:2a:d4:30, 0xe798, <Status.ENDPOINTS_INIT: 2>))
[2020-12-16 20:29:34][INFO] : returning exception UNIQUE constraint failed: devices.ieee
[2020-12-16 20:29:34][DEBUG] : Device ec:1b:bd:ff:fe:2a:d4:30 already exists. Updating it.
[2020-12-16 20:29:34][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f7bee27c570>, 'UPDATE devices SET nwk=?, status=? WHERE ieee=?', (0xe798, <Status.ENDPOINTS_INIT: 2>, ec:1b:bd:ff:fe:2a:d4:30))
[2020-12-16 20:29:34][DEBUG] : returning <sqlite3.Cursor object at 0x7f7bedbda6c0>
[2020-12-16 20:29:34][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f7bee27c570>, 'INSERT OR REPLACE INTO node_descriptors VALUES (?, ?)', (ec:1b:bd:ff:fe:2a:d4:30, b'\x02@\x80|\x11RR\x00\x00,R\x00\x00'))
[2020-12-16 20:29:34][DEBUG] : returning <sqlite3.Cursor object at 0x7f7bedbda960>
[2020-12-16 20:29:34][DEBUG] : executing functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7f7bee27c570>, 'INSERT OR REPLACE INTO endpoints VALUES (?, ?, ?, ?, ?)', [(ec:1b:bd:ff:fe:2a:d4:30, 1, 260, <DeviceType.REMOTE_CONTROL: 6>, <Status.ZDO_INIT: 1>)])
[2020-12-16 20:29:34][DEBUG] : returning <sqlite3.Cursor object at 0x7f7bedbda6c0>
[2020-12-16 20:29:34][DEBUG] : executing functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7f7bee27c570>, 'INSERT OR REPLACE INTO clusters VALUES (?, ?, ?)', [(ec:1b:bd:ff:fe:2a:d4:30, 1, 0), (ec:1b:bd:ff:fe:2a:d4:30, 1, 1), (ec:1b:bd:ff:fe:2a:d4:30, 1, 3), (ec:1b:bd:ff:fe:2a:d4:30, 1, 32), (ec:1b:bd:ff:fe:2a:d4:30, 1, 4096)])
[2020-12-16 20:29:34][DEBUG] : returning <sqlite3.Cursor object at 0x7f7bedbda960>
[2020-12-16 20:29:34][DEBUG] : executing functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7f7bee27c570>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', [(ec:1b:bd:ff:fe:2a:d4:30, 1, 0, 4, 'IKEA of Sweden'), (ec:1b:bd:ff:fe:2a:d4:30, 1, 0, 5, 'SYMFONISK Sound Controller')])
[2020-12-16 20:29:34][DEBUG] : returning <sqlite3.Cursor object at 0x7f7bedbda6c0>
[2020-12-16 20:29:34][DEBUG] : executing functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7f7bee27c570>, 'INSERT OR REPLACE INTO output_clusters VALUES (?, ?, ?)', [(ec:1b:bd:ff:fe:2a:d4:30, 1, 3), (ec:1b:bd:ff:fe:2a:d4:30, 1, 4), (ec:1b:bd:ff:fe:2a:d4:30, 1, 6), (ec:1b:bd:ff:fe:2a:d4:30, 1, 8), (ec:1b:bd:ff:fe:2a:d4:30, 1, 25), (ec:1b:bd:ff:fe:2a:d4:30, 1, 4096)])
[2020-12-16 20:29:34][DEBUG] : returning <sqlite3.Cursor object at 0x7f7bedbda960>
[2020-12-16 20:29:34][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f7bee27c570>)
[2020-12-16 20:29:34][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-12-16 20:29:34][DEBUG] : returning None
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x16, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'basic' input cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of input cluster 'basic'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of input cluster 'power', is_server 'True'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind input cluster 'power'
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x17 request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 23, b'\x170\xd4*\xfe\xff\xbd\x1b\xec\x01\x01\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e7980000002100000000161730d42afeffbd1bec01010003b3eded01008d150001'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00"\xe7\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x170\xd4*\xfe\xff\xbd\x1b\xec\x01\x01\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xe7\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x170\xd4*\xfe\xff\xbd\x1b\xec\x02\x11\x02\x11\x02\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x17, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'power' input cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] This input cluster have REPORT_CONFIG, we need to configure it
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Reporting 'battery_voltage' attr on 'power' input cluster: 3600/10800/1: For: 'ec:1b:bd:ff:fe:2a:d4:30'
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x18 request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 260, 1, 1, 1, 24, b'\x00\x18\x06\x00 \x00 \x10\x0e0*\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e79801010001010400000c00180600200020100e302a01'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00\x18C\x02\xe7\x98\x01\x01\x00\x01\x01\x04\x00\x00\x0c\x00\x18\x06\x00 \x00 \x10\x0e0*\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x18C\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x11\x02\x11\x02\x14\x02\x10\x02\x10\x02\x1c\x02\x10\x18\x02\x16\x02\x10 \x02\x10 \x10\x02\x1e0*\x02\x11'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x18, on endpoint id 1 cluster 0x0001: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to set reporting for 'battery_voltage' attr on 'power' input cluster: [0xe798:1:0x0001]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Reporting 'battery_percentage_remaining' attr on 'power' input cluster: 3600/10800/1: For: 'ec:1b:bd:ff:fe:2a:d4:30'
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x19 request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 260, 1, 1, 1, 25, b'\x00\x19\x06\x00!\x00 \x10\x0e0*\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e79801010001010400000c00190600210020100e302a01'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00\x18C\x02\xe7\x98\x01\x01\x00\x01\x01\x04\x00\x00\x0c\x00\x19\x06\x00!\x00 \x10\x0e0*\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x18C\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x11\x02\x11\x02\x14\x02\x10\x02\x10\x02\x1c\x02\x10\x19\x02\x16\x02\x10!\x02\x10 \x10\x02\x1e0*\x02\x11'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x19, on endpoint id 1 cluster 0x0001: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to set reporting for 'battery_percentage_remaining' attr on 'power' input cluster: [0xe798:1:0x0001]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of input cluster 'power'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of input cluster 'poll_control', is_server 'True'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind input cluster 'poll_control'
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x1a request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 26, b'\x1a0\xd4*\xfe\xff\xbd\x1b\xec\x01 \x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e7980000002100000000161a30d42afeffbd1bec01200003b3eded01008d150001'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00"\xcb\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x1a0\xd4*\xfe\xff\xbd\x1b\xec\x01 \x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xcb\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x1a0\xd4*\xfe\xff\xbd\x1b\xec\x02\x11 \x02\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x1a, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'poll_control' input cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Intput cluster 32 has specific function to initialize, I used it
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x1b request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 260, 32, 1, 1, 27, b'\x00\x1b\x02\x00\x00#\x903\x00\x00', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e79801010020010400000a001b0200002390330000'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00\x16\xe8\x02\xe7\x98\x01\x01\x00 \x01\x04\x00\x00
\x00\x1b\x02\x00\x00#\x903\x00\x00'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x16\xe8\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10 \x02\x11\x02\x14\x02\x10\x02\x10\x02\x1a\x02\x10\x1b\x02\x12\x02\x10\x02\x10#\x903\x02\x10\x02\x10'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x1b, on endpoint id 1 cluster 0x0020: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][chanels.general.PollControl.initialize] Couldn't set check-in interval: [0xe798:1:0x0020]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of input cluster 'poll_control'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of input cluster 'lightlink', is_server 'True'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind input cluster 'lightlink'
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x1c request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 28, b'\x1c0\xd4*\xfe\xff\xbd\x1b\xec\x01\x00\x10\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e7980000002100000000161c30d42afeffbd1bec01001003b3eded01008d150001'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00"\xfd\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x1c0\xd4*\xfe\xff\xbd\x1b\xec\x01\x00\x10\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xfd\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x1c0\xd4*\xfe\xff\xbd\x1b\xec\x02\x11\x02\x10\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x1c, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'lightlink' input cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of input cluster 'lightlink'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'on_off', is_server 'False'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind 'on_off' output cluster
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x1d request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 29, b'\x1d0\xd4*\xfe\xff\xbd\x1b\xec\x01\x06\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e7980000002100000000161d30d42afeffbd1bec01060003b3eded01008d150001'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00"\xea\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x1d0\xd4*\xfe\xff\xbd\x1b\xec\x01\x06\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xea\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x1d0\xd4*\xfe\xff\xbd\x1b\xec\x02\x11\x02\x16\x02\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x1d, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'on_off' output cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of output cluster 'on_off'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'level', is_server 'False'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind 'level' output cluster
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x1e request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 30, b'\x1e0\xd4*\xfe\xff\xbd\x1b\xec\x01\x08\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e7980000002100000000161e30d42afeffbd1bec01080003b3eded01008d150001'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00"\xe7\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x1e0\xd4*\xfe\xff\xbd\x1b\xec\x01\x08\x00\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xe7\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x1e0\xd4*\xfe\xff\xbd\x1b\xec\x02\x11\x02\x18\x02\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x1e, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'level' output cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of output cluster 'level'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Begin configuration of output cluster 'lightlink', is_server 'False'
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Bind 'lightlink' output cluster
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x1f request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 0, <ZDOCmd.Bind_req: 0x0021>, 0, 0, 31, b'\x1f0\xd4*\xfe\xff\xbd\x1b\xec\x01\x00\x10\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e7980000002100000000161f30d42afeffbd1bec01001003b3eded01008d150001'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00"\xfe\x02\xe7\x98\x00\x00\x00!\x00\x00\x00\x00\x16\x1f0\xd4*\xfe\xff\xbd\x1b\xec\x01\x00\x10\x03\xb3\xed\xed\x01\x00\x8d\x15\x00\x01'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10"\xfe\x02\x12\xe7\x98\x02\x10\x02\x10\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x10\x16\x1f0\xd4*\xfe\xff\xbd\x1b\xec\x02\x11\x02\x10\x10\x02\x13\xb3\xed\xed\x02\x11\x02\x10\x8d\x15\x02\x10\x02\x11'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x1f, on endpoint id 0 cluster 0x0021: Message send failure 166
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] Failed to bind 'lightlink' output cluster: [0xe798:0:0x0021]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize][Endpoint 1] End configuration of output cluster 'lightlink'
[2020-12-16 20:29:34][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Begin get basic info from device
[2020-12-16 20:29:34][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] End point found
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x20 request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 260, 0, 1, 1, 32, b'\x00 \x00\x04\x00\x05\x00', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e79801010000010400000700200004000500'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00\x13x\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00 \x00\x04\x00\x05\x00'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13x\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10 \x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x20, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:34][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Error on read attribute level 1 : [0xe798:1:0x0000]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x21 request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 260, 0, 1, 1, 33, b'\x00!\x00\x00\x00\x01\x00\x02\x00\x03\x00', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e79801010000010400000b0021000000010002000300'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00\x17p\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x0b\x00!\x00\x00\x00\x01\x00\x02\x00\x03\x00'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x17p\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x1b\x02\x10!\x02\x10\x02\x10\x02\x10\x02\x11\x02\x10\x02\x12\x02\x10\x02\x13\x02\x10'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x21, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:34][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Error on read attribute level 2 : [0xe798:1:0x0000]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x22 request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 260, 0, 1, 1, 34, b'\x00"\x00\x07\x00', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e7980101000001040000050022000700'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00\x11|\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00"\x00\x07\x00'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11|\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10"\x02\x10\x02\x17\x02\x10'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x22, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:34][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Error on read attribute level 3 : [0xe798:1:0x0000]: Message send failure
[2020-12-16 20:29:34][DEBUG] : [0xe798] Extending timeout for 0x23 request
[2020-12-16 20:29:34][DEBUG] : request (0xE798, 260, 0, 1, 1, 35, b'\x00#\x00\x06\x00\x00@', True, False)
[2020-12-16 20:29:34][DEBUG] : Send: 0x0530 b'02e79801010000010400000700230006000040'
[2020-12-16 20:29:34][DEBUG] : Frame to send: b'\x050\x00\x13<\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00#\x00\x06\x00\x00@'
[2020-12-16 20:29:34][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13<\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10#\x02\x10\x02\x16\x02\x10\x02\x10@'
[2020-12-16 20:29:34][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:34][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:34][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:34][DEBUG] : [0xe798] Delivery error for seq # 0x23, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:34][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] Error on read attribute level 4 : [0xe798:1:0x0000]: Message send failure
[2020-12-16 20:29:34][WARNING] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.get_basic_info] End get basic info from device
[2020-12-16 20:29:34][DEBUG] : [ec:1b:bd:ff:fe:2a:d4:30][zdevices.initialize] End device initialize
[2020-12-16 20:29:41][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-12-16 20:29:41][DEBUG] : [0xe798] Extending timeout for 0x24 request
[2020-12-16 20:29:41][DEBUG] : request (0xE798, 260, 0, 1, 1, 36, b'\x00$\x00\x04\x00\x05\x00', True, False)
[2020-12-16 20:29:41][DEBUG] : Send: 0x0530 b'02e79801010000010400000700240004000500'
[2020-12-16 20:29:41][DEBUG] : Frame to send: b'\x050\x00\x13|\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00$\x00\x04\x00\x05\x00'
[2020-12-16 20:29:41][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13|\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10$\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:29:41][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:41][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:41][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:41][DEBUG] : [0xe798] Delivery error for seq # 0x24, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:41][DEBUG] : [0xe798] Extending timeout for 0x25 request
[2020-12-16 20:29:41][DEBUG] : request (0xE798, 260, 0, 1, 1, 37, b'\x00%\x00\x04\x00', True, False)
[2020-12-16 20:29:41][DEBUG] : Send: 0x0530 b'02e7980101000001040000050025000400'
[2020-12-16 20:29:41][DEBUG] : Frame to send: b'\x050\x00\x11x\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00%\x00\x04\x00'
[2020-12-16 20:29:41][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11x\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10%\x02\x10\x02\x14\x02\x10'
[2020-12-16 20:29:41][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:41][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:41][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:41][DEBUG] : [0xe798] Delivery error for seq # 0x25, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:41][DEBUG] : [0xe798] Extending timeout for 0x26 request
[2020-12-16 20:29:41][DEBUG] : request (0xE798, 260, 0, 1, 1, 38, b'\x00&\x00\x05\x00', True, False)
[2020-12-16 20:29:41][DEBUG] : Send: 0x0530 b'02e7980101000001040000050026000500'
[2020-12-16 20:29:41][DEBUG] : Frame to send: b'\x050\x00\x11z\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00&\x00\x05\x00'
[2020-12-16 20:29:41][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11z\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10&\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:29:41][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:29:41][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:29:41][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:29:41][DEBUG] : [0xe798] Delivery error for seq # 0x26, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:29:41][DEBUG] : [0xe798:1] Manufacturer: None
[2020-12-16 20:29:41][DEBUG] : [0xe798:1] Model: None
[2020-12-16 20:29:41][INFO] : 200 GET /device/info?ieee=ec%3A1b%3Abd%3Aff%3Afe%3A2a%3Ad4%3A30 (127.0.0.1) 47.96ms
[2020-12-16 20:30:04][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-12-16 20:30:04][DEBUG] : [0x7bb0:1] Manufacturer: eWeLink
[2020-12-16 20:30:04][DEBUG] : [0x7bb0:1] Model: WB01
[2020-12-16 20:30:04][DEBUG] : [0xed4d:1] Manufacturer: SONOFF
[2020-12-16 20:30:04][DEBUG] : [0xed4d:1] Model: 01MINIZB
[2020-12-16 20:30:04][DEBUG] : [0x98cf:1] Manufacturer: LUMI
[2020-12-16 20:30:04][DEBUG] : [0x98cf:1] Model: lumi.sensor_switch
[2020-12-16 20:30:04][DEBUG] : [0xe798] Extending timeout for 0x27 request
[2020-12-16 20:30:04][DEBUG] : request (0xE798, 260, 0, 1, 1, 39, b"\x00'\x00\x04\x00\x05\x00", True, False)
[2020-12-16 20:30:04][DEBUG] : Send: 0x0530 b'02e79801010000010400000700270004000500'
[2020-12-16 20:30:04][DEBUG] : Frame to send: b"\x050\x00\x13\x7f\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00'\x00\x04\x00\x05\x00"
[2020-12-16 20:30:04][DEBUG] : Frame escaped: b"\x02\x150\x02\x10\x13\x7f\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10'\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10"
[2020-12-16 20:30:04][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:04][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:04][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:04][DEBUG] : [0xe798] Delivery error for seq # 0x27, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:04][DEBUG] : [0xe798] Extending timeout for 0x28 request
[2020-12-16 20:30:04][DEBUG] : request (0xE798, 260, 0, 1, 1, 40, b'\x00(\x00\x04\x00', True, False)
[2020-12-16 20:30:04][DEBUG] : Send: 0x0530 b'02e7980101000001040000050028000400'
[2020-12-16 20:30:04][DEBUG] : Frame to send: b'\x050\x00\x11u\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00(\x00\x04\x00'
[2020-12-16 20:30:04][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11u\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10(\x02\x10\x02\x14\x02\x10'
[2020-12-16 20:30:04][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:04][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:04][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:04][DEBUG] : [0xe798] Delivery error for seq # 0x28, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:04][DEBUG] : [0xe798] Extending timeout for 0x29 request
[2020-12-16 20:30:04][DEBUG] : request (0xE798, 260, 0, 1, 1, 41, b'\x00)\x00\x05\x00', True, False)
[2020-12-16 20:30:04][DEBUG] : Send: 0x0530 b'02e7980101000001040000050029000500'
[2020-12-16 20:30:04][DEBUG] : Frame to send: b'\x050\x00\x11u\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00)\x00\x05\x00'
[2020-12-16 20:30:04][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11u\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10)\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:30:04][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:04][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:04][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:04][DEBUG] : [0xe798] Delivery error for seq # 0x29, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:04][DEBUG] : [0xe798:1] Manufacturer: None
[2020-12-16 20:30:04][DEBUG] : [0xe798:1] Model: None
[2020-12-16 20:30:04][DEBUG] : [0x72fb:1] Manufacturer: Adeo
[2020-12-16 20:30:04][DEBUG] : [0x72fb:1] Model: LXEK-1
[2020-12-16 20:30:04][DEBUG] : [0x4d3f:1] Manufacturer: ADEO
[2020-12-16 20:30:04][DEBUG] : [0x4d3f:1] Model: LXEK-5
[2020-12-16 20:30:04][DEBUG] : [0xe917:1] Manufacturer: IKEA of Sweden
[2020-12-16 20:30:04][DEBUG] : [0xe917:1] Model: TRADFRI bulb E27 WW 806lm
[2020-12-16 20:30:04][INFO] : 200 GET /device/all (127.0.0.1) 48.66ms
[2020-12-16 20:30:04][DEBUG] : [GroupHandler.prepare] Json arg : None
[2020-12-16 20:30:04][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=ybTqGNReQnrtgy9y91qBF8kgcdYGATj1 HTTP/1.1" 200 0
[2020-12-16 20:30:04][INFO] : 200 GET /group/all (127.0.0.1) 2.54ms
[2020-12-16 20:30:12][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-12-16 20:30:12][DEBUG] : [0xe798] Extending timeout for 0x2a request
[2020-12-16 20:30:12][DEBUG] : request (0xE798, 260, 0, 1, 1, 42, b'\x00*\x00\x04\x00\x05\x00', True, False)
[2020-12-16 20:30:12][DEBUG] : Send: 0x0530 b'02e798010100000104000007002a0004000500'
[2020-12-16 20:30:12][DEBUG] : Frame to send: b'\x050\x00\x13r\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00*\x00\x04\x00\x05\x00'
[2020-12-16 20:30:12][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13r\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10*\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:30:12][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:12][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:12][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:12][DEBUG] : [0xe798] Delivery error for seq # 0x2a, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:12][DEBUG] : [0xe798] Extending timeout for 0x2b request
[2020-12-16 20:30:12][DEBUG] : request (0xE798, 260, 0, 1, 1, 43, b'\x00+\x00\x04\x00', True, False)
[2020-12-16 20:30:12][DEBUG] : Send: 0x0530 b'02e798010100000104000005002b000400'
[2020-12-16 20:30:12][DEBUG] : Frame to send: b'\x050\x00\x11v\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00+\x00\x04\x00'
[2020-12-16 20:30:12][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11v\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10+\x02\x10\x02\x14\x02\x10'
[2020-12-16 20:30:12][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:12][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:12][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:12][DEBUG] : [0xe798] Delivery error for seq # 0x2b, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:12][DEBUG] : [0xe798] Extending timeout for 0x2c request
[2020-12-16 20:30:12][DEBUG] : request (0xE798, 260, 0, 1, 1, 44, b'\x00,\x00\x05\x00', True, False)
[2020-12-16 20:30:12][DEBUG] : Send: 0x0530 b'02e798010100000104000005002c000500'
[2020-12-16 20:30:12][DEBUG] : Frame to send: b'\x050\x00\x11p\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00,\x00\x05\x00'
[2020-12-16 20:30:12][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11p\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10,\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:30:12][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:12][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:12][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:12][DEBUG] : [0xe798] Delivery error for seq # 0x2c, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:12][DEBUG] : [0xe798:1] Manufacturer: None
[2020-12-16 20:30:12][DEBUG] : [0xe798:1] Model: None
[2020-12-16 20:30:12][INFO] : 200 GET /device/info?ieee=ec%3A1b%3Abd%3Aff%3Afe%3A2a%3Ad4%3A30 (127.0.0.1) 45.71ms
[2020-12-16 20:30:29][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-12-16 20:30:29][DEBUG] : [0xe798] Extending timeout for 0x2d request
[2020-12-16 20:30:29][DEBUG] : request (0xE798, 260, 0, 1, 1, 45, b'\x00-\x00\x04\x00\x05\x00', True, False)
[2020-12-16 20:30:29][DEBUG] : Send: 0x0530 b'02e798010100000104000007002d0004000500'
[2020-12-16 20:30:29][DEBUG] : Frame to send: b'\x050\x00\x13u\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x00-\x00\x04\x00\x05\x00'
[2020-12-16 20:30:29][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13u\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x10-\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:30:29][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:29][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:29][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:29][DEBUG] : [0xe798] Delivery error for seq # 0x2d, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:29][DEBUG] : [0xe798] Extending timeout for 0x2e request
[2020-12-16 20:30:29][DEBUG] : request (0xE798, 260, 0, 1, 1, 46, b'\x00.\x00\x04\x00', True, False)
[2020-12-16 20:30:29][DEBUG] : Send: 0x0530 b'02e798010100000104000005002e000400'
[2020-12-16 20:30:29][DEBUG] : Frame to send: b'\x050\x00\x11s\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00.\x00\x04\x00'
[2020-12-16 20:30:29][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11s\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10.\x02\x10\x02\x14\x02\x10'
[2020-12-16 20:30:29][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:29][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:29][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:29][DEBUG] : [0xe798] Delivery error for seq # 0x2e, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:29][DEBUG] : [0xe798] Extending timeout for 0x2f request
[2020-12-16 20:30:29][DEBUG] : request (0xE798, 260, 0, 1, 1, 47, b'\x00/\x00\x05\x00', True, False)
[2020-12-16 20:30:29][DEBUG] : Send: 0x0530 b'02e798010100000104000005002f000500'
[2020-12-16 20:30:29][DEBUG] : Frame to send: b'\x050\x00\x11s\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x00/\x00\x05\x00'
[2020-12-16 20:30:29][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11s\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x10/\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:30:29][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:30:29][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:30:29][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:30:29][DEBUG] : [0xe798] Delivery error for seq # 0x2f, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:30:29][DEBUG] : [0xe798:1] Manufacturer: None
[2020-12-16 20:30:29][DEBUG] : [0xe798:1] Model: None
[2020-12-16 20:30:29][INFO] : 200 GET /device/info?ieee=ec%3A1b%3Abd%3Aff%3Afe%3A2a%3Ad4%3A30 (127.0.0.1) 46.81ms
[2020-12-16 20:31:52][DEBUG] : Frame received: 8035000643030000f10200
[2020-12-16 20:31:52][DEBUG] : data received 0x8035 b'030000f102' LQI:0
[2020-12-16 20:31:52][DEBUG] : zigate_callback_handler [3, 61698]
[2020-12-16 20:31:52][DEBUG] : PDM Event 3 E_PDM_SYSTEM_EVENT_LARGEST_RECORD_FULL_SAVE_NO_LONGER_POSSIBLE, record 61698
[2020-12-16 20:31:52][DEBUG] : Frame received: 8035000645030000f10400
[2020-12-16 20:31:52][DEBUG] : data received 0x8035 b'030000f104' LQI:0
[2020-12-16 20:31:52][DEBUG] : zigate_callback_handler [3, 61700]
[2020-12-16 20:31:52][DEBUG] : PDM Event 3 E_PDM_SYSTEM_EVENT_LARGEST_RECORD_FULL_SAVE_NO_LONGER_POSSIBLE, record 61700
[2020-12-16 20:31:52][DEBUG] : Frame received: 8035000640030000f10100
[2020-12-16 20:31:52][DEBUG] : data received 0x8035 b'030000f101' LQI:0
[2020-12-16 20:31:52][DEBUG] : zigate_callback_handler [3, 61697]
[2020-12-16 20:31:52][DEBUG] : PDM Event 3 E_PDM_SYSTEM_EVENT_LARGEST_RECORD_FULL_SAVE_NO_LONGER_POSSIBLE, record 61697
[2020-12-16 20:31:52][DEBUG] : Frame received: 8035000642030000f10300
[2020-12-16 20:31:52][DEBUG] : data received 0x8035 b'030000f103' LQI:0
[2020-12-16 20:31:52][DEBUG] : zigate_callback_handler [3, 61699]
[2020-12-16 20:31:52][DEBUG] : PDM Event 3 E_PDM_SYSTEM_EVENT_LARGEST_RECORD_FULL_SAVE_NO_LONGER_POSSIBLE, record 61699
[2020-12-16 20:31:52][DEBUG] : Frame received: 8035000642030000f10300
[2020-12-16 20:31:52][DEBUG] : data received 0x8035 b'030000f103' LQI:0
[2020-12-16 20:31:52][DEBUG] : zigate_callback_handler [3, 61699]
[2020-12-16 20:31:52][DEBUG] : PDM Event 3 E_PDM_SYSTEM_EVENT_LARGEST_RECORD_FULL_SAVE_NO_LONGER_POSSIBLE, record 61699
[2020-12-16 20:31:52][DEBUG] : Frame received: 8035000643030000f10200
[2020-12-16 20:31:52][DEBUG] : data received 0x8035 b'030000f102' LQI:0
[2020-12-16 20:31:52][DEBUG] : zigate_callback_handler [3, 61698]
[2020-12-16 20:31:52][DEBUG] : PDM Event 3 E_PDM_SYSTEM_EVENT_LARGEST_RECORD_FULL_SAVE_NO_LONGER_POSSIBLE, record 61698
[2020-12-16 20:31:52][DEBUG] : Frame received: 8035000645030000f10400
[2020-12-16 20:31:52][DEBUG] : data received 0x8035 b'030000f104' LQI:0
[2020-12-16 20:31:52][DEBUG] : zigate_callback_handler [3, 61700]
[2020-12-16 20:31:52][DEBUG] : PDM Event 3 E_PDM_SYSTEM_EVENT_LARGEST_RECORD_FULL_SAVE_NO_LONGER_POSSIBLE, record 61700
[2020-12-16 20:31:53][DEBUG] : Frame received: 8035000642030000f10300
[2020-12-16 20:31:53][DEBUG] : data received 0x8035 b'030000f103' LQI:0
[2020-12-16 20:31:53][DEBUG] : zigate_callback_handler [3, 61699]
[2020-12-16 20:31:53][DEBUG] : PDM Event 3 E_PDM_SYSTEM_EVENT_LARGEST_RECORD_FULL_SAVE_NO_LONGER_POSSIBLE, record 61699
[2020-12-16 20:40:44][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-12-16 20:40:44][DEBUG] : [0xe798] Extending timeout for 0x30 request
[2020-12-16 20:40:44][DEBUG] : request (0xE798, 260, 0, 1, 1, 48, b'\x000\x00\x04\x00\x05\x00', True, False)
[2020-12-16 20:40:44][DEBUG] : Send: 0x0530 b'02e79801010000010400000700300004000500'
[2020-12-16 20:40:44][DEBUG] : Frame to send: b'\x050\x00\x13h\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x000\x00\x04\x00\x05\x00'
[2020-12-16 20:40:44][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13h\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x100\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:40:44][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:40:44][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:40:44][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:40:44][DEBUG] : [0xe798] Delivery error for seq # 0x30, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:40:44][DEBUG] : [0xe798] Extending timeout for 0x31 request
[2020-12-16 20:40:44][DEBUG] : request (0xE798, 260, 0, 1, 1, 49, b'\x001\x00\x04\x00', True, False)
[2020-12-16 20:40:44][DEBUG] : Send: 0x0530 b'02e7980101000001040000050031000400'
[2020-12-16 20:40:44][DEBUG] : Frame to send: b'\x050\x00\x11l\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x001\x00\x04\x00'
[2020-12-16 20:40:44][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11l\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x101\x02\x10\x02\x14\x02\x10'
[2020-12-16 20:40:44][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:40:44][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:40:44][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:40:44][DEBUG] : [0xe798] Delivery error for seq # 0x31, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:40:44][DEBUG] : [0xe798] Extending timeout for 0x32 request
[2020-12-16 20:40:44][DEBUG] : request (0xE798, 260, 0, 1, 1, 50, b'\x002\x00\x05\x00', True, False)
[2020-12-16 20:40:44][DEBUG] : Send: 0x0530 b'02e7980101000001040000050032000500'
[2020-12-16 20:40:44][DEBUG] : Frame to send: b'\x050\x00\x11n\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x002\x00\x05\x00'
[2020-12-16 20:40:44][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11n\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x102\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:40:44][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:40:44][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:40:44][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:40:44][DEBUG] : [0xe798] Delivery error for seq # 0x32, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:40:44][DEBUG] : [0xe798:1] Manufacturer: None
[2020-12-16 20:40:44][DEBUG] : [0xe798:1] Model: None
[2020-12-16 20:40:44][INFO] : 200 GET /device/info?ieee=ec%3A1b%3Abd%3Aff%3Afe%3A2a%3Ad4%3A30 (127.0.0.1) 41.98ms
[2020-12-16 20:52:05][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-12-16 20:52:05][DEBUG] : [0x7bb0:1] Manufacturer: eWeLink
[2020-12-16 20:52:05][DEBUG] : [0x7bb0:1] Model: WB01
[2020-12-16 20:52:05][INFO] : 200 GET /device/info?ieee=00%3A12%3A4b%3A00%3A1f%3A44%3Aff%3A15 (127.0.0.1) 2.04ms
[2020-12-16 20:52:47][DEBUG] : [DeviceHandler.prepare] Json arg : None
[2020-12-16 20:52:47][DEBUG] : [0xe798] Extending timeout for 0x33 request
[2020-12-16 20:52:47][DEBUG] : request (0xE798, 260, 0, 1, 1, 51, b'\x003\x00\x04\x00\x05\x00', True, False)
[2020-12-16 20:52:47][DEBUG] : Send: 0x0530 b'02e79801010000010400000700330004000500'
[2020-12-16 20:52:47][DEBUG] : Frame to send: b'\x050\x00\x13k\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x07\x003\x00\x04\x00\x05\x00'
[2020-12-16 20:52:47][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x13k\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x17\x02\x103\x02\x10\x02\x14\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:52:47][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:52:47][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:52:47][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:52:47][DEBUG] : [0xe798] Delivery error for seq # 0x33, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:52:47][DEBUG] : [0xe798] Extending timeout for 0x34 request
[2020-12-16 20:52:47][DEBUG] : request (0xE798, 260, 0, 1, 1, 52, b'\x004\x00\x04\x00', True, False)
[2020-12-16 20:52:47][DEBUG] : Send: 0x0530 b'02e7980101000001040000050034000400'
[2020-12-16 20:52:47][DEBUG] : Frame to send: b'\x050\x00\x11i\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x004\x00\x04\x00'
[2020-12-16 20:52:47][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11i\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x104\x02\x10\x02\x14\x02\x10'
[2020-12-16 20:52:47][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:52:47][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:52:47][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:52:47][DEBUG] : [0xe798] Delivery error for seq # 0x34, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:52:47][DEBUG] : [0xe798] Extending timeout for 0x35 request
[2020-12-16 20:52:47][DEBUG] : request (0xE798, 260, 0, 1, 1, 53, b'\x005\x00\x05\x00', True, False)
[2020-12-16 20:52:47][DEBUG] : Send: 0x0530 b'02e7980101000001040000050035000500'
[2020-12-16 20:52:47][DEBUG] : Frame to send: b'\x050\x00\x11i\x02\xe7\x98\x01\x01\x00\x00\x01\x04\x00\x00\x05\x005\x00\x05\x00'
[2020-12-16 20:52:47][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x11i\x02\x12\xe7\x98\x02\x11\x02\x11\x02\x10\x02\x10\x02\x11\x02\x14\x02\x10\x02\x10\x02\x15\x02\x105\x02\x10\x02\x15\x02\x10'
[2020-12-16 20:52:47][DEBUG] : Frame received: 8000000516a600053000
[2020-12-16 20:52:47][DEBUG] : data received 0x8000 b'a6000530' LQI:0
[2020-12-16 20:52:47][DEBUG] : zigate_callback_handler [166, 0, 1328, b'']
[2020-12-16 20:52:47][DEBUG] : [0xe798] Delivery error for seq # 0x35, on endpoint id 1 cluster 0x0000: Message send failure 166
[2020-12-16 20:52:47][DEBUG] : [0xe798:1] Manufacturer: None
[2020-12-16 20:52:47][DEBUG] : [0xe798:1] Model: None
[2020-12-16 20:52:47][INFO] : 200 GET /device/info?ieee=ec%3A1b%3Abd%3Aff%3Afe%3A2a%3Ad4%3A30 (127.0.0.1) 43.48ms