[2020-09-28 18:55:32][INFO] : Start zigbeed [2020-09-28 18:55:32][INFO] : Log level : debug [2020-09-28 18:55:32][INFO] : PID file : /tmp/jeedom/zigbee/deamon.pid [2020-09-28 18:55:32][INFO] : Device : /dev/ttyUSB1 [2020-09-28 18:55:32][INFO] : Apikey : 8G4O4g0UU0c8FfdS57jg2ZSuGLaHXGlL [2020-09-28 18:55:32][INFO] : Callback : http://127.0.0.1:80/plugins/zigbee/core/php/jeeZigbee.php [2020-09-28 18:55:32][INFO] : Cycle : 0.3 [2020-09-28 18:55:32][INFO] : Controller : zigate [2020-09-28 18:55:32][INFO] : Channel : 25 [2020-09-28 18:55:32][INFO] : Data folder : /var/www/html/plugins/zigbee/data [2020-09-28 18:55:32][INFO] : Find device : /dev/ttyUSB1 [2020-09-28 18:55:32][DEBUG] : Writing PID 14106 to /tmp/jeedom/zigbee/deamon.pid [2020-09-28 18:55:32][DEBUG] : Init request module v2.21.0 [2020-09-28 18:55:32][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80 [2020-09-28 18:55:32][DEBUG] : http://127.0.0.1:80 "GET /plugins/zigbee/core/php/jeeZigbee.php?apikey=8G4O4g0UU0c8FfdS57jg2ZSuGLaHXGlL HTTP/1.1" 200 0 [2020-09-28 18:55:32][DEBUG] : Using selector: EpollSelector [2020-09-28 18:55:32][DEBUG] : Init zigbee network with config : {'database_path': '/var/www/html/plugins/zigbee/data/network_zigate.db', 'device': {'path': '/dev/ttyUSB1'}, 'network': {'channel': 25}} [2020-09-28 18:55:32][DEBUG] : Loading application state from /var/www/html/plugins/zigbee/data/network_zigate.db [2020-09-28 18:55:32][DEBUG] : [0xad2a:1:0x0000] Attribute id: 4 value: LUMI [2020-09-28 18:55:32][DEBUG] : [0xad2a:1:0x0000] Attribute id: 5 value: lumi.ctrl_neutral2 [2020-09-28 18:55:32][DEBUG] : [0xa0aa:1:0x0000] Attribute id: 4 value: LUMI [2020-09-28 18:55:32][DEBUG] : [0xa0aa:1:0x0000] Attribute id: 5 value: lumi.ctrl_neutral1 [2020-09-28 18:55:32][DEBUG] : [0x3175:1:0x0000] Attribute id: 4 value: LUMI [2020-09-28 18:55:32][DEBUG] : [0x3175:1:0x0000] Attribute id: 5 value: lumi.ctrl_neutral2 [2020-09-28 18:55:32][DEBUG] : Checking quirks for LUMI lumi.ctrl_neutral2 (00:15:8d:00:02:c0:1e:fc) [2020-09-28 18:55:32][DEBUG] : Considering [2020-09-28 18:55:32][DEBUG] : Found custom device replacement for 00:15:8d:00:02:c0:1e:fc: [2020-09-28 18:55:32][DEBUG] : Checking quirks for LUMI lumi.ctrl_neutral1 (00:15:8d:00:03:3a:7d:7b) [2020-09-28 18:55:32][DEBUG] : Considering [2020-09-28 18:55:32][DEBUG] : Found custom device replacement for 00:15:8d:00:03:3a:7d:7b: [2020-09-28 18:55:32][DEBUG] : Checking quirks for LUMI lumi.ctrl_neutral2 (00:15:8d:00:03:5c:92:5d) [2020-09-28 18:55:32][DEBUG] : Considering [2020-09-28 18:55:32][DEBUG] : Found custom device replacement for 00:15:8d:00:03:5c:92:5d: [2020-09-28 18:55:32][DEBUG] : [0xad2a:1:0x0000] Attribute id: 4 value: LUMI [2020-09-28 18:55:32][DEBUG] : [0xad2a:1:0x0000] Attribute id: 5 value: lumi.ctrl_neutral2 [2020-09-28 18:55:32][DEBUG] : [0xad2a:1:0x0000] Attribute id: 65281 value: b'd\x10\x00e\x10\x01n \x00o \x02\x01!\xe4\x0c\x03(\x19\x05!\xb0\x00\x08!\x16& !\x00\x00\x99#\x00\x00\x00\x00\x9b!\x00\x00' [2020-09-28 18:55:32][DEBUG] : [0xa0aa:1:0x0000] Attribute id: 4 value: LUMI [2020-09-28 18:55:32][DEBUG] : [0xa0aa:1:0x0000] Attribute id: 5 value: lumi.ctrl_neutral1 [2020-09-28 18:55:32][DEBUG] : [0x3175:1:0x0000] Attribute id: 4 value: LUMI [2020-09-28 18:55:32][DEBUG] : [0x3175:1:0x0000] Attribute id: 5 value: lumi.ctrl_neutral2 [2020-09-28 18:55:32][DEBUG] : Connection made [2020-09-28 18:55:32][DEBUG] : Send: 0x2 b'01' [2020-09-28 18:55:32][DEBUG] : Frame to send: b'\x00\x02\x00\x01\x02\x01' [2020-09-28 18:55:32][DEBUG] : Frame escaped: b'\x02\x10\x02\x12\x02\x10\x02\x11\x02\x12\x02\x11' [2020-09-28 18:55:32][DEBUG] : Frame received: 80350006b1030000000100 [2020-09-28 18:55:32][DEBUG] : data received 0x8035 b'0300000001' LQI:0 [2020-09-28 18:55:32][ERROR] : Received unhandled response 0x8035 [2020-09-28 18:55:32][DEBUG] : Frame received: 80000005870000000200 [2020-09-28 18:55:32][DEBUG] : data received 0x8000 b'00000002' LQI:0 [2020-09-28 18:55:32][DEBUG] : zigate_callback_handler [0, 0, 2, b''] [2020-09-28 18:55:32][DEBUG] : Send: 0x10 b'' [2020-09-28 18:55:32][DEBUG] : Frame to send: b'\x00\x10\x00\x00\x10' [2020-09-28 18:55:32][DEBUG] : Frame escaped: b'\x02\x10\x10\x02\x10\x02\x10\x10' [2020-09-28 18:55:32][DEBUG] : Frame received: 80000005950000001000 [2020-09-28 18:55:32][DEBUG] : data received 0x8000 b'00000010' LQI:0 [2020-09-28 18:55:32][DEBUG] : zigate_callback_handler [0, 0, 16, b''] [2020-09-28 18:55:32][DEBUG] : Frame received: 80100005890003031c00 [2020-09-28 18:55:32][DEBUG] : data received 0x8010 b'0003031c' LQI:0 [2020-09-28 18:55:32][DEBUG] : zigate_callback_handler [3, 796] [2020-09-28 18:55:32][DEBUG] : Send: 0x9 b'' [2020-09-28 18:55:32][DEBUG] : Frame to send: b'\x00 \x00\x00 ' [2020-09-28 18:55:32][DEBUG] : Frame escaped: b'\x02\x10\x02\x19\x02\x10\x02\x10\x02\x19' [2020-09-28 18:55:32][DEBUG] : Frame received: 800000058c0000000900 [2020-09-28 18:55:32][DEBUG] : data received 0x8000 b'00000009' LQI:0 [2020-09-28 18:55:32][DEBUG] : zigate_callback_handler [0, 0, 9, b''] [2020-09-28 18:55:32][DEBUG] : Frame received: 80090016d0000000158d0001ede94f3b9d06b3caf84a3f12d00b00 [2020-09-28 18:55:32][DEBUG] : data received 0x8009 b'000000158d0001ede94f3b9d06b3caf84a3f12d00b' LQI:0 [2020-09-28 18:55:32][DEBUG] : zigate_callback_handler [0x0000, 00:15:8d:00:01:ed:e9:4f, 15261, 482952752805122768, 11] [2020-09-28 18:55:32][INFO] : ******************** Device is ready: new=False, device=00:15:8d:00:02:c0:1e:fc [2020-09-28 18:55:32][INFO] : ******************** Device is ready: new=False, device=00:15:8d:00:03:3a:7d:7b [2020-09-28 18:55:32][INFO] : ******************** Device is ready: new=False, device=00:15:8d:00:03:5c:92:5d [2020-09-28 18:55:32][INFO] : ******************** Device is ready: new=False, device=00:15:8d:00:01:ed:e9:4f [2020-09-28 18:55:32][DEBUG] : Init and start http server : {'database_path': '/var/www/html/plugins/zigbee/data/network_zigate.db', 'device': {'path': '/dev/ttyUSB1'}, 'network': {'channel': 25}} [2020-09-28 18:55:32][DEBUG] : Start zigbee network [2020-09-28 18:56:42][DEBUG] : Json arg : {'ieee': '00:15:8d:00:03:3a:7d:7b', 'cmd': [{'endpoint': 2, 'cluster': 'on_off', 'command': 'on', 'await': 1}]} [2020-09-28 18:56:42][DEBUG] : request (0xA0AA, 260, 6, 1, 2, 1, b'\x01\x01\x01', True, False) [2020-09-28 18:56:42][DEBUG] : Send: 0x530 b'02a0aa010200060104000003010101' [2020-09-28 18:56:42][DEBUG] : Frame to send: b'\x050\x00\x0f0\x02\xa0\xaa\x01\x02\x00\x06\x01\x04\x00\x00\x03\x01\x01\x01' [2020-09-28 18:56:42][DEBUG] : Frame escaped: b'\x02\x150\x02\x10\x02\x1f0\x02\x12\xa0\xaa\x02\x11\x02\x12\x02\x10\x02\x16\x02\x11\x02\x14\x02\x10\x02\x10\x02\x13\x02\x11\x02\x11\x02\x11' [2020-09-28 18:56:42][DEBUG] : Frame received: 8000000516a600053000 [2020-09-28 18:56:42][DEBUG] : data received 0x8000 b'a6000530' LQI:0 [2020-09-28 18:56:42][DEBUG] : zigate_callback_handler [166, 0, 1328, b''] [2020-09-28 18:56:42][DEBUG] : [0xa0aa] Delivery error for seq # 0x01, on endpoint id 2 cluster 0x0006: Message send failure 166 [2020-09-28 18:56:42][DEBUG] : Traceback (most recent call last): File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 206, in put await command() File "/usr/local/lib/python3.7/dist-packages/zigpy/device.py", line 207, in request self.nwk, dst_ep, cluster zigpy.exceptions.DeliveryError: [0xa0aa:2:0x0006]: Message send failure [2020-09-28 18:56:42][INFO] : 200 PUT /device/command (127.0.0.1) 13.75ms