stas-demydiuk / domoticz-zigbee2mqtt-plugin

zigbee2mqtt plugin for domoticz
MIT License
136 stars 98 forks source link

Plugin doesn't create new Devices in existing database #79

Closed maaakit closed 5 years ago

maaakit commented 5 years ago

I've configured zigbee2mqtt and plugin for domoticz running on raspberry pi B+. I have 2 Xiaomi devices paired to zigbee2mqtt instance. When I'm starting zigbee2mqtt plugin in my domoticz instane which have already a lot of devices I can se in log that devices are discovered and added to database. But I can't find no new devices in "Devices" menu. There are no errors logged too. I'm not sure if problem is in plugin or in domoticz API (Domoticz.Device Create()) or at the plugin side. Do you have any idea how I can increase log level of domoticz?

Surprisingly when I'm starting from clean database zigbee devices are added properly.

Zigbee2MQTT version: 1.1.1 Python version: 3.5.3 Domoticz version: V4.10429 Plugin version: 0.0.17

2019-02-18 11:40:19.877 Status: (zgb) Started. context.c:55: warning: mpd_setminalloc: ignoring request to set MPD_MINALLOC a second time

2019-02-18 11:40:21.281 Status: (zgb) Initialized version 0.0.17 2019-02-18 11:40:21.291 Status: (zgb) Entering work loop. 2019-02-18 11:40:21.293 (zgb) Debug logging mask set to: PYTHON PLUGIN DEVICE CONNECTION MESSAGE 2019-02-18 11:40:21.295 (zgb) onStart called 2019-02-18 11:40:21.296 (zgb) MqttClient::init 2019-02-18 11:40:21.298 (zgb) MqttClient::Open 2019-02-18 11:40:21.300 (zgb) Protocol set to: 'MQTT'. 2019-02-18 11:40:21.303 (zgb) Transport set to: 'TCP/IP', 192.168.99.12:1883. 2019-02-18 11:40:21.305 (zgb) Connect directive received, action initiated successfully. 2019-02-18 11:40:21.309 (zgb) onConnect called 2019-02-18 11:40:21.312 (zgb) MQTT connected successfully. 2019-02-18 11:40:21.313 (zgb) MqttClient::Connect 2019-02-18 11:40:21.316 (zgb) MQTT CONNECT ID: 'Domoticz_1550490021_9905' 2019-02-18 11:40:21.317 (zgb) Sending 38 bytes of data 2019-02-18 11:40:21.319 (zgb) 10 24 00 04 4d 51 54 54 04 02 00 3c 00 18 44 6f 6d 6f 74 69 .$..MQTT...<..Domoti 2019-02-18 11:40:21.320 (zgb) 63 7a 5f 31 35 35 30 34 39 30 30 32 31 5f 39 39 30 35 .. .. cz_1550490021_9905 2019-02-18 11:40:21.324 (zgb) Received 4 bytes of data 2019-02-18 11:40:21.325 (zgb) 20 02 00 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .... 2019-02-18 11:40:21.327 (zgb) MqttClient::Subscribe 2019-02-18 11:40:21.330 (zgb) Sending 27 bytes of data 2019-02-18 11:40:21.331 (zgb) 82 19 00 01 00 14 7a 69 67 62 65 65 32 6d 71 74 74 2f 62 72 �.....zigbee2mqtt/br 2019-02-18 11:40:21.332 (zgb) 69 64 67 65 2f 23 00 .. .. .. .. .. .. .. .. .. .. .. .. .. idge/#. 2019-02-18 11:40:21.336 (zgb) Received 5 bytes of data 2019-02-18 11:40:21.337 (zgb) 90 03 00 01 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. �.... 2019-02-18 11:40:21.338 (zgb) onMQTTSubscribed 2019-02-18 11:40:21.391 (zgb) Received 102 bytes of data 2019-02-18 11:40:21.391 (zgb) 31 42 00 19 7a 69 67 62 65 65 32 6d 71 74 74 2f 62 72 69 64 1B..zigbee2mqtt/brid 2019-02-18 11:40:21.391 (zgb) 67 65 2f 63 6f 6e 66 69 67 7b 22 6c 6f 67 5f 6c 65 76 65 6c ge/config{"log_level 2019-02-18 11:40:21.392 (zgb) 22 3a 22 69 6e 66 6f 22 2c 22 70 65 72 6d 69 74 5f 6a 6f 69 ":"info","permit_joi 2019-02-18 11:40:21.392 (zgb) 6e 22 3a 74 72 75 65 7d 31 20 00 18 7a 69 67 62 65 65 32 6d n":true}1...zigbee2m 2019-02-18 11:40:21.392 (zgb) 71 74 74 2f 62 72 69 64 67 65 2f 73 74 61 74 65 6f 6e 6c 69 qtt/bridge/stateonli 2019-02-18 11:40:21.393 (zgb) 6e 65 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. ne 2019-02-18 11:40:21.406 (zgb) MQTT message: zigbee2mqtt/bridge/config {'log_level': 'info', 'permit_join': True} 2019-02-18 11:40:21.408 (zgb) Zigbee2mqtt log level is info 2019-02-18 11:40:21.410 (zgb) Joining new devices is enabled on the zigbee bridge 2019-02-18 11:40:21.412 (zgb) MQTT message: zigbee2mqtt/bridge/state online 2019-02-18 11:40:21.414 (zgb) Zigbee2mqtt bridge is online 2019-02-18 11:40:21.416 (zgb) MqttClient::Publish zigbee2mqtt/bridge/config/devices () 2019-02-18 11:40:21.418 (zgb) MqttClient::Publish zigbee2mqtt/bridge/config/permit_join (true) 2019-02-18 11:40:21.420 (zgb) Sending 37 bytes of data 2019-02-18 11:40:21.423 (zgb) 30 23 00 21 7a 69 67 62 65 65 32 6d 71 74 74 2f 62 72 69 64 0#.!zigbee2mqtt/brid 2019-02-18 11:40:21.424 (zgb) 67 65 2f 63 6f 6e 66 69 67 2f 64 65 76 69 63 65 73 .. .. .. ge/config/devices 2019-02-18 11:40:21.433 (zgb) Sending 45 bytes of data 2019-02-18 11:40:21.436 (zgb) 30 2b 00 25 7a 69 67 62 65 65 32 6d 71 74 74 2f 62 72 69 64 0+.%zigbee2mqtt/brid 2019-02-18 11:40:21.438 (zgb) 67 65 2f 63 6f 6e 66 69 67 2f 70 65 72 6d 69 74 5f 6a 6f 69 ge/config/permit_joi 2019-02-18 11:40:21.439 (zgb) 6e 74 72 75 65 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. ntrue 2019-02-18 11:40:21.443 (zgb) Received 313 bytes of data 2019-02-18 11:40:21.444 (zgb) 30 23 00 21 7a 69 67 62 65 65 32 6d 71 74 74 2f 62 72 69 64 0#.!zigbee2mqtt/brid 2019-02-18 11:40:21.445 (zgb) 67 65 2f 63 6f 6e 66 69 67 2f 64 65 76 69 63 65 73 30 91 02 ge/config/devices0�. 2019-02-18 11:40:21.452 (zgb) 00 16 7a 69 67 62 65 65 32 6d 71 74 74 2f 62 72 69 64 67 65 ..zigbee2mqtt/bridge 2019-02-18 11:40:21.453 (zgb) 2f 6c 6f 67 7b 22 74 79 70 65 22 3a 22 64 65 76 69 63 65 73 /log{"type":"devices 2019-02-18 11:40:21.454 (zgb) 22 2c 22 6d 65 73 73 61 67 65 22 3a 5b 7b 22 69 65 65 65 41 ","message":[{"ieeeA 2019-02-18 11:40:21.454 (zgb) 64 64 72 22 3a 22 30 78 30 30 31 35 38 64 30 30 30 32 30 30 ddr":"0x00158d000200 2019-02-18 11:40:21.455 (zgb) 38 66 38 36 22 2c 22 74 79 70 65 22 3a 22 45 6e 64 44 65 76 8f86","type":"EndDev 2019-02-18 11:40:21.455 (zgb) 69 63 65 22 2c 22 6d 6f 64 65 6c 22 3a 22 57 58 4b 47 31 31 ice","model":"WXKG11 2019-02-18 11:40:21.455 (zgb) 4c 4d 22 2c 22 66 72 69 65 6e 64 6c 79 5f 6e 61 6d 65 22 3a LM","friendly_name": 2019-02-18 11:40:21.455 (zgb) 22 30 78 30 30 31 35 38 64 30 30 30 32 30 30 38 66 38 36 22 "0x00158d0002008f86" 2019-02-18 11:40:21.456 (zgb) 7d 2c 7b 22 69 65 65 65 41 64 64 72 22 3a 22 30 78 30 30 31 },{"ieeeAddr":"0x001 2019-02-18 11:40:21.456 (zgb) 35 38 64 30 30 30 32 62 66 64 63 37 63 22 2c 22 74 79 70 65 58d0002bfdc7c","type 2019-02-18 11:40:21.456 (zgb) 22 3a 22 45 6e 64 44 65 76 69 63 65 22 2c 22 6d 6f 64 65 6c ":"EndDevice","model 2019-02-18 11:40:21.456 (zgb) 22 3a 22 4d 43 43 47 51 31 31 4c 4d 22 2c 22 66 72 69 65 6e ":"MCCGQ11LM","frien 2019-02-18 11:40:21.457 (zgb) 64 6c 79 5f 6e 61 6d 65 22 3a 22 30 78 30 30 31 35 38 64 30 dly_name":"0x00158d0 2019-02-18 11:40:21.457 (zgb) 30 30 32 62 66 64 63 37 63 22 7d 5d 7d .. .. .. .. .. .. .. 002bfdc7c"}]} 2019-02-18 11:40:21.457 (zgb) Received 45 bytes of data 2019-02-18 11:40:21.458 (zgb) 30 2b 00 25 7a 69 67 62 65 65 32 6d 71 74 74 2f 62 72 69 64 0+.%zigbee2mqtt/brid 2019-02-18 11:40:21.458 (zgb) 67 65 2f 63 6f 6e 66 69 67 2f 70 65 72 6d 69 74 5f 6a 6f 69 ge/config/permit_joi 2019-02-18 11:40:21.458 (zgb) 6e 74 72 75 65 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. ntrue 2019-02-18 11:40:21.459 (zgb) MQTT message: zigbee2mqtt/bridge/config/devices 2019-02-18 11:40:21.460 (zgb) Unhandled message from zigbee2mqtt: zigbee2mqtt/bridge/config/devices 2019-02-18 11:40:21.461 (zgb) MQTT message: zigbee2mqtt/bridge/log {'type': 'devices', 'message': [{'model': 'WXKG11LM', 'type': 'EndDevice', 'ieeeAddr': '0x00158d0002008f86', 'friendly_name': '0x00158d0002008f86'}, {'model': 'MCCGQ11LM', 'type': 'EndDevice', 'ieeeAddr': '0x00158d0002bfdc7c', 'friendly_name': '0x00158d0002bfdc7c'}]} 2019-02-18 11:40:21.461 (zgb) Received available devices list from bridge 2019-02-18 11:40:21.462 (zgb) Device WXKG11LM 0x00158d0002008f86 (0x00158d0002008f86) 2019-02-18 11:40:21.488 (zgb) Creating domoticz device to handle "linkquality" key for device with ieeeAddr 0x00158d0002008f86 2019-02-18 11:40:21.492 (zgb) Creating device '0x00158d0002008f86 (Link Quality)'. 2019-02-18 11:40:21.492 (zgb) Creating domoticz device to handle "battery_voltage" key for device with ieeeAddr 0x00158d0002008f86 2019-02-18 11:40:21.493 (zgb) Creating device '0x00158d0002008f86 (Battery Voltage)'. 2019-02-18 11:40:21.493 (zgb) Creating domoticz device to handle "state" key for device with ieeeAddr 0x00158d0002008f86 2019-02-18 11:40:21.494 (zgb) Creating device '0x00158d0002008f86'. 2019-02-18 11:40:21.494 (zgb) Device MCCGQ11LM 0x00158d0002bfdc7c (0x00158d0002bfdc7c) 2019-02-18 11:40:21.495 (zgb) Creating domoticz device to handle "linkquality" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:21.496 (zgb) Creating device '0x00158d0002bfdc7c (Link Quality)'. 2019-02-18 11:40:21.496 (zgb) Creating domoticz device to handle "battery_voltage" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:21.497 (zgb) Creating device '0x00158d0002bfdc7c (Battery Voltage)'. 2019-02-18 11:40:21.497 (zgb) Creating domoticz device to handle "contact" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:21.518 (zgb) Creating device '0x00158d0002bfdc7c'. 2019-02-18 11:40:21.519 (zgb) MqttClient::Subscribe 2019-02-18 11:40:21.521 (zgb) MQTT message: zigbee2mqtt/bridge/config/permit_join True 2019-02-18 11:40:21.523 (zgb) Unhandled message from zigbee2mqtt: zigbee2mqtt/bridge/config/permit_join True 2019-02-18 11:40:21.525 (zgb) Sending 20 bytes of data 2019-02-18 11:40:21.526 (zgb) 82 12 00 02 00 0d 7a 69 67 62 65 65 32 6d 71 74 74 2f 2b 00 �.....zigbee2mqtt/+. 2019-02-18 11:40:21.529 (zgb) Received 68 bytes of data 2019-02-18 11:40:21.530 (zgb) 30 42 00 19 7a 69 67 62 65 65 32 6d 71 74 74 2f 62 72 69 64 0B..zigbee2mqtt/brid 2019-02-18 11:40:21.530 (zgb) 67 65 2f 63 6f 6e 66 69 67 7b 22 6c 6f 67 5f 6c 65 76 65 6c ge/config{"log_level 2019-02-18 11:40:21.531 (zgb) 22 3a 22 69 6e 66 6f 22 2c 22 70 65 72 6d 69 74 5f 6a 6f 69 ":"info","permit_joi 2019-02-18 11:40:21.534 (zgb) 6e 22 3a 74 72 75 65 7d .. .. .. .. .. .. .. .. .. .. .. .. n":true} 2019-02-18 11:40:21.535 (zgb) Received 5 bytes of data 2019-02-18 11:40:21.541 (zgb) 90 03 00 02 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. �.... 2019-02-18 11:40:21.543 (zgb) MQTT message: zigbee2mqtt/bridge/config {'log_level': 'info', 'permitjoin': True} 2019-02-18 11:40:21.546 (zgb) Zigbee2mqtt log level is info 2019-02-18 11:40:21.547 (zgb) Joining new devices is enabled on the zigbee bridge 2019-02-18 11:40:21.551 (zgb) onMQTTSubscribed 2019-02-18 11:40:25.100 MySensorsMQTT: Topic: domoticz/in/MyMQTT/0/0/3/0/22, Message: PONG 2019-02-18 11:40:31.340 (zgb) MqttClient::Ping 2019-02-18 11:40:31.341 (zgb) Sending 2 bytes of data 2019-02-18 11:40:31.341 (zgb) c0 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. �. 2019-02-18 11:40:31.344 (zgb) Received 2 bytes of data 2019-02-18 11:40:31.346 (zgb) d0 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. �. 2019-02-18 11:40:36.510 (zgb) Received 97 bytes of data 2019-02-18 11:40:36.511 (zgb) 30 5f 00 1e 7a 69 67 62 65 65 32 6d 71 74 74 2f 30 78 30 30 0..zigbee2mqtt/0x00 2019-02-18 11:40:36.511 (zgb) 31 35 38 64 30 30 30 32 62 66 64 63 37 63 7b 22 63 6f 6e 74 158d0002bfdc7c{"cont 2019-02-18 11:40:36.512 (zgb) 61 63 74 22 3a 66 61 6c 73 65 2c 22 6c 69 6e 6b 71 75 61 6c act":false,"linkqual 2019-02-18 11:40:36.512 (zgb) 69 74 79 22 3a 32 31 2c 22 62 61 74 74 65 72 79 22 3a 31 30 ity":21,"battery":10 2019-02-18 11:40:36.513 (zgb) 30 2c 22 76 6f 6c 74 61 67 65 22 3a 33 30 34 35 7d .. .. .. 0,"voltage":3045} 2019-02-18 11:40:36.514 (zgb) MQTT message: zigbee2mqtt/0x00158d0002bfdc7c {'battery': 100, 'contact': False, 'linkquality': 21, 'voltage': 3045} 2019-02-18 11:40:36.516 (zgb) Creating domoticz device to handle "linkquality" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:36.517 (zgb) Creating device '0x00158d0002bfdc7c (Link Quality)'. 2019-02-18 11:40:36.518 (zgb) Creating domoticz device to handle "battery_voltage" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:36.519 (zgb) Creating device '0x00158d0002bfdc7c (Battery Voltage)'. 2019-02-18 11:40:36.519 (zgb) Creating domoticz device to handle "contact" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:36.520 (zgb) Creating device '0x00158d0002bfdc7c'. 2019-02-18 11:40:37.722 Status: MySensors: Using serial port: /dev/ttyUSB0 2019-02-18 11:40:38.723 Status: MySensors: retrying in 30 seconds... 2019-02-18 11:40:41.318 (zgb) MqttClient::Ping 2019-02-18 11:40:41.318 (zgb) Sending 2 bytes of data 2019-02-18 11:40:41.318 (zgb) c0 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. �. 2019-02-18 11:40:41.320 (zgb) Received 2 bytes of data 2019-02-18 11:40:41.320 (zgb) d0 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. �. 2019-02-18 11:40:42.624 (zgb) Received 96 bytes of data 2019-02-18 11:40:42.626 (zgb) 30 5e 00 1e 7a 69 67 62 65 65 32 6d 71 74 74 2f 30 78 30 30 0^..zigbee2mqtt/0x00 2019-02-18 11:40:42.627 (zgb) 31 35 38 64 30 30 30 32 62 66 64 63 37 63 7b 22 63 6f 6e 74 158d0002bfdc7c{"cont 2019-02-18 11:40:42.628 (zgb) 61 63 74 22 3a 74 72 75 65 2c 22 6c 69 6e 6b 71 75 61 6c 69 act":true,"linkquali 2019-02-18 11:40:42.630 (zgb) 74 79 22 3a 32 38 2c 22 62 61 74 74 65 72 79 22 3a 31 30 30 ty":28,"battery":100 2019-02-18 11:40:42.631 (zgb) 2c 22 76 6f 6c 74 61 67 65 22 3a 33 30 34 35 7d .. .. .. .. ,"voltage":3045} 2019-02-18 11:40:42.633 (zgb) MQTT message: zigbee2mqtt/0x00158d0002bfdc7c {'battery': 100, 'contact': True, 'linkquality': 28, 'voltage': 3045} 2019-02-18 11:40:42.635 (zgb) Creating domoticz device to handle "linkquality" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:42.637 (zgb) Creating device '0x00158d0002bfdc7c (Link Quality)'. 2019-02-18 11:40:42.638 (zgb) Creating domoticz device to handle "battery_voltage" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:42.641 (zgb) Creating device '0x00158d0002bfdc7c (Battery Voltage)'. 2019-02-18 11:40:42.643 (zgb) Creating domoticz device to handle "contact" key for device with ieeeAddr 0x00158d0002bfdc7c 2019-02-18 11:40:42.645 (zgb) Creating device '0x00158d0002bfdc7c'. 2019-02-18 11:40:51.316 (zgb) MqttClient::Ping 2019-02-18 11:40:51.317 (zgb) Sending 2 bytes of data 2019-02-18 11:40:51.317 (zgb) c0 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. �. 2019-02-18 11:40:51.322 (zgb) Received 2 bytes of data 2019-02-18 11:40:51.322 (zgb) d0 00 .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. .. �.

maaakit commented 5 years ago

Problem solved. Please close this issue. The real reason of skipping device creation was disabled option "Accept new Hardware Devices"