Lamps can not be switched via MQTT

[probutus]

I'm on latest 1.13.1 branch and have a ikea tradfri 900lm and a philips hue e27 color lamp connected. Both work flawlessly with the original hue 2.1. bridge.

I paired both lamps with zigbee2mqtt and now I try to switch the lamps via MQTT (homeassistant is OFF; I'm using plain MQTT)

Independently what I send to zigbee2mqtt the lamp is switched off and its brightness is set to 0. This behaviour is the same with the ikea and the hue Lamp. I found a similar post where it was written that it should work exactly this way hence I'm filing a bug report I sent these commands (initially, I switched the lamp manually on)

tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"ON"\,"brightness":"255"}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":\ "ON"}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":\ "ON"}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":\ "ON"}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":\ "ON"}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"ON"}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"TOGGLE"}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"OFF"}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"OFF"\,"brightness":100}
tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"ON"\,"brightness":100}

Debug Log is here:

> zigbee2mqtt@1.13.1 start /opt/zigbee2mqtt
> node index.js

zigbee2mqtt:info  2020-05-22 17:34:54: Logging to console and directory: '/opt/zigbee2mqtt/data/log/2020-05-22.17-34-54' filename: log.txt
zigbee2mqtt:debug 2020-05-22 17:34:54: Removing old log directory '/opt/zigbee2mqtt/data/log/2020-05-21.21-02-42'
zigbee2mqtt:debug 2020-05-22 17:34:54: Loaded state from file /opt/zigbee2mqtt/data/state.json
zigbee2mqtt:info  2020-05-22 17:34:54: Starting zigbee2mqtt version 1.13.1 (commit #3046680)
zigbee2mqtt:info  2020-05-22 17:34:54: Starting zigbee-herdsman...
zigbee2mqtt:debug 2020-05-22 17:34:54: Using zigbee-herdsman with settings: '{"network":{"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11],"networkKey":"HIDDEN"},"databasePath":"/opt/zigbee2mqtt/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt/data/database.db.backup","backupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json","serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/ttyACM1"},"adapter":{"concurrent":null}}'
zigbee2mqtt:info  2020-05-22 17:34:56: zigbee-herdsman started
zigbee2mqtt:info  2020-05-22 17:34:56: Coordinator firmware version: '{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}}'
zigbee2mqtt:debug 2020-05-22 17:34:56: Zigbee network parameters: {"panID":6754,"extendedPanID":"0xdddddddddddddddd","channel":11}
zigbee2mqtt:info  2020-05-22 17:34:56: Currently 6 devices are joined:
zigbee2mqtt:info  2020-05-22 17:34:56: tradfri_remote (0x90fd9ffffe71ad17): E1524/E1810 - IKEA TRADFRI remote control (EndDevice)
zigbee2mqtt:info  2020-05-22 17:34:56: 0x000b57fffeec690b (0x000b57fffeec690b): LED1650R5 - IKEA TRADFRI LED bulb GU10 400 lumen, dimmable (Router)
zigbee2mqtt:info  2020-05-22 17:34:56: cc2531_repeater (0x00124b000e0eacb6): CC2530.ROUTER - Custom devices (DiY) [CC2530 router](http://ptvo.info/cc2530-based-zigbee-coordinator-and-router-112/) (Router)
zigbee2mqtt:info  2020-05-22 17:34:56: paulmann_remote (0x00158d0001c0b6be): 500.67 - Paulmann RGB remote control (EndDevice)
zigbee2mqtt:info  2020-05-22 17:34:56: hue_farblampe_ez (0x0017880108b16e4b): 9290022166 - Philips Hue white and color ambiance E26/E27 (Router)
zigbee2mqtt:info  2020-05-22 17:34:56: tradfri_900lm_ez (0x90fd9ffffe4cfea3): LED1545G12 - IKEA TRADFRI LED bulb E26/E27 980 lumen, dimmable, white spectrum, opal white (Router)
zigbee2mqtt:info  2020-05-22 17:34:56: Zigbee: disabling joining new devices.
zigbee2mqtt:info  2020-05-22 17:34:56: Connecting to MQTT server at mqtt://andromeda.fritz.box
zigbee2mqtt:info  2020-05-22 17:34:56: Connected to MQTT server
zigbee2mqtt:info  2020-05-22 17:34:56: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload 'online'
zigbee2mqtt:info  2020-05-22 17:34:56: MQTT publish: topic 'zigbee2mqtt/tradfri_remote', payload '{"update_available":true,"linkquality":0,"battery":47}'
zigbee2mqtt:info  2020-05-22 17:34:56: MQTT publish: topic 'zigbee2mqtt/0x000b57fffeec690b', payload '{"update_available":false}'
zigbee2mqtt:info  2020-05-22 17:34:56: MQTT publish: topic 'zigbee2mqtt/cc2531_repeater', payload '{"state":true,"led_state":false,"linkquality":89,"description":"37516/0x000B57FFFEEC690B","type":"NEIGHBOR","rssi":46,"led":false}'
zigbee2mqtt:info  2020-05-22 17:34:56: MQTT publish: topic 'zigbee2mqtt/paulmann_remote', payload '{"linkquality":47}'
zigbee2mqtt:info  2020-05-22 17:34:56: MQTT publish: topic 'zigbee2mqtt/hue_farblampe_ez', payload '{"state":"OFF","brightness":0,"linkquality":0,"update_available":false}'
zigbee2mqtt:info  2020-05-22 17:34:56: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'
zigbee2mqtt:info  2020-05-22 17:34:56: MQTT publish: topic 'zigbee2mqtt/bridge/config', payload '{"version":"1.13.1","commit":"3046680","coordinator":{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}},"log_level":"debug","permit_join":false}'

zigbee2mqtt:debug 2020-05-22 17:35:15: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:ON}'
zigbee2mqtt:debug 2020-05-22 17:35:15: Publishing 'set' 'state' to 'tradfri_900lm_ez'
zigbee2mqtt:info  2020-05-22 17:35:15: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'

zigbee2mqtt:debug 2020-05-22 17:35:31: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:TOGGLE}'
zigbee2mqtt:debug 2020-05-22 17:35:31: Publishing 'set' 'state' to 'tradfri_900lm_ez'
zigbee2mqtt:info  2020-05-22 17:35:31: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'

zigbee2mqtt:debug 2020-05-22 17:35:39: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:OFF}'
zigbee2mqtt:debug 2020-05-22 17:35:39: Publishing 'set' 'state' to 'tradfri_900lm_ez'
zigbee2mqtt:info  2020-05-22 17:35:39: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'
zigbee2mqtt:debug 2020-05-22 17:35:56: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:OFF,brightness:100}'
zigbee2mqtt:debug 2020-05-22 17:35:56: Publishing 'set' 'state' to 'tradfri_900lm_ez'
zigbee2mqtt:info  2020-05-22 17:35:56: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'
zigbee2mqtt:debug 2020-05-22 17:36:09: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:ON,brightness:100}'
zigbee2mqtt:debug 2020-05-22 17:36:09: Publishing 'set' 'state' to 'tradfri_900lm_ez'
zigbee2mqtt:info  2020-05-22 17:36:09: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'

More detailed debug log with tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"ON"\,"brightness":100} tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"TOGGLE"} tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"ON"\,"brightness":100} tux@Darkstar:~$ mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {"state":"OFF"}

[17:47:09] root@raspberrypi:/opt/zigbee2mqtt# DEBUG=zigbee-herdsman* npm start

> zigbee2mqtt@1.13.1 start /opt/zigbee2mqtt
> node index.js

zigbee2mqtt:info  2020-05-22 17:47:15: Logging to console and directory: '/opt/zigbee2mqtt/data/log/2020-05-22.17-47-15' filename: log.txt
zigbee2mqtt:debug 2020-05-22 17:47:15: Removing old log directory '/opt/zigbee2mqtt/data/log/2020-05-21.21-49-55'
zigbee2mqtt:debug 2020-05-22 17:47:15: Loaded state from file /opt/zigbee2mqtt/data/state.json
zigbee2mqtt:info  2020-05-22 17:47:15: Starting zigbee2mqtt version 1.13.1 (commit #3046680)
zigbee2mqtt:info  2020-05-22 17:47:15: Starting zigbee-herdsman...
zigbee2mqtt:debug 2020-05-22 17:47:15: Using zigbee-herdsman with settings: '{"network":{"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11],"networkKey":"HIDDEN"},"databasePath":"/opt/zigbee2mqtt/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt/data/database.db.backup","backupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json","serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/ttyACM1"},"adapter":{"concurrent":null}}'
  zigbee-herdsman:adapter Path '/dev/ttyACM1' is valid for 'ZStackAdapter' +0ms
  zigbee-herdsman:controller:log Starting with options '{"network":{"networkKeyDistribute":false,"networkKey":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13],"panID":6754,"extendedPanID":[221,221,221,221,221,221,221,221],"channelList":[11]},"serialPort":{"baudRate":115200,"rtscts":true,"path":"/dev/ttyACM1"},"databasePath":"/opt/zigbee2mqtt/data/database.db","databaseBackupPath":"/opt/zigbee2mqtt/data/database.db.backup","backupPath":"/opt/zigbee2mqtt/data/coordinator_backup.json","adapter":{"concurrent":null}}' +0ms
  zigbee-herdsman:adapter:zStack:znp:log Opening SerialPort with /dev/ttyACM1 and {"baudRate":115200,"rtscts":true,"autoOpen":false} +0ms
  zigbee-herdsman:adapter:zStack:znp:log Serialport opened +7ms
  zigbee-herdsman:adapter:zStack:znp:log Writing skip bootloader payload +1ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> buffer [239] +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - ping - {"capabilities":1} +0ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,1,32] +1s
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,2,97,1,121,1,26] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,2,97,1,121,1,26] +2ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 2 - 3 - 1 - 1 - [121,1] - 26 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - ping - {"capabilities":377} +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +7ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - version - {} +23ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,33,2,35] +19ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,97,2,2,0,2,6,3,144,21,52,1,2,0,0,0,0,218] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,97,2,2,0,2,6,3,144,21,52,1,2,0,0,0,0,218] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 3 - 1 - 2 - [2,0,2,6,3,144,21,52,1,2,0,0,0,0] - 218 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - version - {"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608} +12ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:adapter Adapter concurrent: 2 +0ms
  zigbee-herdsman:adapter:zStack:adapter Detected znp version 'zStack12' ({"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}) +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvRead - {"id":3840,"offset":0,"len":1,"value":{"type":"Buffer","data":[85]}} +11ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,33,8,0,15,0,37] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,97,8,0,1,85,62] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,97,8,0,1,85,62] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 3 - 1 - 8 - [0,1,85] - 62 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvRead - {"status":0,"len":1,"value":{"type":"Buffer","data":[85]}} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp Item 'hasConfigured' is valid +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvRead - {"id":3840,"offset":0,"len":1,"value":{"type":"Buffer","data":[85]}} +13ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,33,8,0,15,0,37] +12ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,97,8,0,1,85,62] +9ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,97,8,0,1,85,62] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 3 - 1 - 8 - [0,1,85] - 62 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvRead - {"status":0,"len":1,"value":{"type":"Buffer","data":[85]}} +11ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp Item 'hasConfigured' is valid +10ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvRead - {"id":132,"len":4,"offset":0,"value":{"type":"Buffer","data":[0,8,0,0]}} +11ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,33,8,132,0,0,174] +10ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,6,97,8,0,4,0,8,0,0,99] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,6,97,8,0,4,0,8,0,0,99] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 6 - 3 - 1 - 8 - [0,4,0,8,0,0] - 99 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvRead - {"status":0,"len":4,"value":{"type":"Buffer","data":[0,8,0,0]}} +15ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp Item 'channelList' is valid +15ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvRead - {"id":99,"len":1,"offset":0,"value":{"type":"Buffer","data":[0]}} +13ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,33,8,99,0,0,73] +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,97,8,0,1,0,107] +15ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,97,8,0,1,0,107] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 3 - 1 - 8 - [0,1,0] - 107 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvRead - {"status":0,"len":1,"value":{"type":"Buffer","data":[0]}} +16ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp Item 'networkKeyDistribute' is valid +15ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SAPI - readConfiguration - {"id":98,"configid":98,"len":16,"offset":0,"value":{"type":"Buffer","data":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13]}} +16ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,1,38,4,98,65] +16ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,19,102,4,0,98,16,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,0] +25ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,19,102,4,0,98,16,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13,0] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 19 - 3 - 6 - 4 - [0,98,16,1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13] - 0 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SAPI - readConfiguration - {"status":0,"configid":98,"len":16,"value":{"type":"Buffer","data":[1,3,5,7,9,11,13,15,0,2,4,6,8,10,12,13]}} +26ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp Item 'networkKey' is valid +27ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvRead - {"id":131,"len":2,"offset":0,"value":{"type":"Buffer","data":[98,26]}} +27ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,33,8,131,0,0,169] +26ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,4,97,8,0,2,98,26,23] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,4,97,8,0,2,98,26,23] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 4 - 3 - 1 - 8 - [0,2,98,26] - 23 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvRead - {"status":0,"len":2,"value":{"type":"Buffer","data":[98,26]}} +9ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp Item 'panID' is valid +9ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> SYS - osalNvRead - {"id":45,"len":8,"offset":0,"value":{"type":"Buffer","data":[221,221,221,221,221,221,221,221]}} +9ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,33,8,45,0,0,7] +9ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,10,97,8,0,8,221,221,221,221,221,221,221,221,107] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,10,97,8,0,8,221,221,221,221,221,221,221,221,107] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 10 - 3 - 1 - 8 - [0,8,221,221,221,221,221,221,221,221] - 107 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- SYS - osalNvRead - {"status":0,"len":8,"value":{"type":"Buffer","data":[221,221,221,221,221,221,221,221]}} +9ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp Item 'extendedPanID' is valid +9ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> UTIL - getDeviceInfo - {} +9ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,39,0,39] +9ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,24,103,0,0,151,68,1,18,0,75,18,0,0,0,7,9,5,70,31,140,146,113,248,85,96,81,99,36] +8ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,24,103,0,0,151,68,1,18,0,75,18,0,0,0,7,9,5,70,31,140,146,113,248,85,96,81,99,36] +1ms                                                                                                                                             
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 24 - 3 - 7 - 0 - [0,151,68,1,18,0,75,18,0,0,0,7,9,5,70,31,140,146,113,248,85,96,81,99] - 36 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- UTIL - getDeviceInfo - {"status":0,"ieeeaddr":"0x00124b0012014497","shortaddr":0,"devicetype":7,"devicestate":9,"numassocdevices":5,"assocdeviceslist":[8006,37516,63601,24661,25425]} +11ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp ZNP is already started as coordinator +12ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - activeEpReq - {"dstaddr":0,"nwkaddrofinterest":0} +12ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,5,0,0,0,0,36] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,5,0,97] +10ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,5,0,97] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 5 - [0] - 97 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - activeEpReq - {"status":0} +11ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,19,69,133,0,0,0,0,0,13,242,47,13,12,110,11,8,6,5,4,3,2,1,104] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,19,69,133,0,0,0,0,0,13,242,47,13,12,110,11,8,6,5,4,3,2,1,104] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 19 - 2 - 5 - 133 - [0,0,0,0,0,13,242,47,13,12,110,11,8,6,5,4,3,2,1] - 104 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - activeEpRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"activeepcount":13,"activeeplist":[242,47,13,12,110,11,8,6,5,4,3,2,1]} +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '1' already registered +16ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '2' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '3' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '4' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '5' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '6' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '8' already registered +1ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '11' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '110' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '12' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '13' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '47' already registered +0ms
  zigbee-herdsman:adapter:zStack:startZnp Endpoint '242' already registered +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extFindGroup - {"endpoint":242,"groupid":2948} +17ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,3,37,74,242,132,11,17] +16ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,19,101,74,0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,179] +6ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,19,101,74,0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,179] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 19 - 3 - 5 - 74 - [0,132,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0] - 179 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extFindGroup - {"status":0,"groupid":2948,"namelen":0,"groupname":{"type":"Buffer","data":[]}} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:controller:log Started with result 'resumed' +1s
  zigbee-herdsman:controller:log Injected database: true, adapter: true +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - activeEpReq - {"dstaddr":0,"nwkaddrofinterest":0} +8ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,5,0,0,0,0,36] +9ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,5,0,97] +10ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,5,0,97] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 5 - [0] - 97 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - activeEpReq - {"status":0} +11ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,19,69,133,0,0,0,0,0,13,242,47,13,12,110,11,8,6,5,4,3,2,1,104] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,19,69,133,0,0,0,0,0,13,242,47,13,12,110,11,8,6,5,4,3,2,1,104] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 19 - 2 - 5 - 133 - [0,0,0,0,0,13,242,47,13,12,110,11,8,6,5,4,3,2,1] - 104 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - activeEpRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"activeepcount":13,"activeeplist":[242,47,13,12,110,11,8,6,5,4,3,2,1]} +25ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> UTIL - getDeviceInfo - {} +14ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,39,0,39] +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,24,103,0,0,151,68,1,18,0,75,18,0,0,0,7,9,5,70,31,140,146,113,248,85,96,81,99,36] +8ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,24,103,0,0,151,68,1,18,0,75,18,0,0,0,7,9,5,70,31,140,146,113,248,85,96,81,99,36] +0ms                                                                                                                                             
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 24 - 3 - 7 - 0 - [0,151,68,1,18,0,75,18,0,0,0,7,9,5,70,31,140,146,113,248,85,96,81,99] - 36 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- UTIL - getDeviceInfo - {"status":0,"ieeeaddr":"0x00124b0012014497","shortaddr":0,"devicetype":7,"devicestate":9,"numassocdevices":5,"assocdeviceslist":[8006,37516,63601,24661,25425]} +15ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":242} +9ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,242,214] +9ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +8ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,242,224,161,5,0,0,0,0,113] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,242,224,161,5,0,0,0,0,113] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,242,224,161,5,0,0,0,0] - 113 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":242,"profileid":41440,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +23ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":47} +14ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,47,11] +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,47,4,1,5,0,0,0,0,232] +4ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,47,4,1,5,0,0,0,0,232] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,47,4,1,5,0,0,0,0] - 232 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":47,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":13} +14ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,13,41] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,16,69,132,0,0,0,0,0,10,13,4,1,5,0,0,1,25,0,0,206] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,16,69,132,0,0,0,0,0,10,13,4,1,5,0,0,1,25,0,0,206] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 16 - 2 - 5 - 132 - [0,0,0,0,0,10,13,4,1,5,0,0,1,25,0,0] - 206 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":10,"endpoint":13,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":1,"inclusterlist":[25],"numoutclusters":0,"outclusterlist":[]} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":12} +13ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,12,40] +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,12,94,192,5,0,0,0,0,80] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,12,94,192,5,0,0,0,0,80] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,12,94,192,5,0,0,0,0] - 80 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":12,"profileid":49246,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":110} +14ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,110,74] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,110,4,1,5,0,0,0,0,169] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,110,4,1,5,0,0,0,0,169] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,110,4,1,5,0,0,0,0] - 169 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":110,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":11} +13ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,11,47] +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,18,69,132,0,0,0,0,0,12,11,4,1,0,4,0,0,2,0,5,2,5,213] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,18,69,132,0,0,0,0,0,12,11,4,1,0,4,0,0,2,0,5,2,5,213] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 18 - 2 - 5 - 132 - [0,0,0,0,0,12,11,4,1,0,4,0,0,2,0,5,2,5] - 213 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":12,"endpoint":11,"profileid":260,"deviceid":1024,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":2,"outclusterlist":[1280,1282]} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":8} +14ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,8,44] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,8,4,1,5,0,0,0,0,207] +4ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,8,4,1,5,0,0,0,0,207] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,8,4,1,5,0,0,0,0] - 207 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":8,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":6} +13ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,6,34] +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,6,9,1,5,0,0,0,0,204] +4ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,6,9,1,5,0,0,0,0,204] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,6,9,1,5,0,0,0,0] - 204 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":6,"profileid":265,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":5} +14ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,5,33] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,5,8,1,5,0,0,0,0,206] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,5,8,1,5,0,0,0,0,206] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,5,8,1,5,0,0,0,0] - 206 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":5,"profileid":264,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":4} +13ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,4,32] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +6ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,4,7,1,5,0,0,0,0,192] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,4,7,1,5,0,0,0,0,192] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,4,7,1,5,0,0,0,0] - 192 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":4,"profileid":263,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":3} +13ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,3,39] +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,3,5,1,5,0,0,0,0,197] +4ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,3,5,1,5,0,0,0,0,197] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,3,5,1,5,0,0,0,0] - 197 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":3,"profileid":261,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":2} +14ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,2,38] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,2,1,1,5,0,0,0,0,192] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,2,1,1,5,0,0,0,0,192] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,2,1,1,5,0,0,0,0] - 192 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":2,"profileid":257,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - simpleDescReq - {"dstaddr":0,"nwkaddrofinterest":0,"endpoint":1} +13ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,4,0,0,0,0,1,37] +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,4,0,96] +7ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,4,0,96] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 4 - [0] - 96 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - simpleDescReq - {"status":0} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,14,69,132,0,0,0,0,0,8,1,4,1,5,0,0,0,0,198] +4ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,14,69,132,0,0,0,0,0,8,1,4,1,5,0,0,0,0,198] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 14 - 2 - 5 - 132 - [0,0,0,0,0,8,1,4,1,5,0,0,0,0] - 198 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - simpleDescRsp - {"srcaddr":0,"status":0,"nwkaddr":0,"len":8,"endpoint":1,"profileid":260,"deviceid":5,"deviceversion":0,"numinclusters":0,"inclusterlist":[],"numoutclusters":0,"outclusterlist":[]} +13ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
zigbee2mqtt:info  2020-05-22 17:47:17: zigbee-herdsman started
zigbee2mqtt:info  2020-05-22 17:47:17: Coordinator firmware version: '{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}}'
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - extNwkInfo - {} +22ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,0,37,80,117] +23ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,24,101,80,0,0,9,98,26,0,0,221,221,221,221,221,221,221,221,0,0,0,0,0,0,0,0,11,87] +14ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,24,101,80,0,0,9,98,26,0,0,221,221,221,221,221,221,221,221,0,0,0,0,0,0,0,0,11,87] +0ms                                                                                                                                             
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 24 - 3 - 5 - 80 - [0,0,9,98,26,0,0,221,221,221,221,221,221,221,221,0,0,0,0,0,0,0,0,11] - 87 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - extNwkInfo - {"shortaddr":0,"devstate":9,"panid":6754,"parentaddr":0,"extendedpanid":"0xdddddddddddddddd","parentextaddr":"0x0000000000000000","channel":11} +21ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
zigbee2mqtt:debug 2020-05-22 17:47:17: Zigbee network parameters: {"panID":6754,"extendedPanID":"0xdddddddddddddddd","channel":11}
zigbee2mqtt:info  2020-05-22 17:47:17: Currently 6 devices are joined:
zigbee2mqtt:info  2020-05-22 17:47:17: tradfri_remote (0x90fd9ffffe71ad17): E1524/E1810 - IKEA TRADFRI remote control (EndDevice)
zigbee2mqtt:info  2020-05-22 17:47:17: 0x000b57fffeec690b (0x000b57fffeec690b): LED1650R5 - IKEA TRADFRI LED bulb GU10 400 lumen, dimmable (Router)
zigbee2mqtt:info  2020-05-22 17:47:17: cc2531_repeater (0x00124b000e0eacb6): CC2530.ROUTER - Custom devices (DiY) [CC2530 router](http://ptvo.info/cc2530-based-zigbee-coordinator-and-router-112/) (Router)
zigbee2mqtt:info  2020-05-22 17:47:17: paulmann_remote (0x00158d0001c0b6be): 500.67 - Paulmann RGB remote control (EndDevice)
zigbee2mqtt:info  2020-05-22 17:47:17: hue_farblampe_ez (0x0017880108b16e4b): 9290022166 - Philips Hue white and color ambiance E26/E27 (Router)
zigbee2mqtt:info  2020-05-22 17:47:17: tradfri_900lm_ez (0x90fd9ffffe4cfea3): LED1545G12 - IKEA TRADFRI LED bulb E26/E27 980 lumen, dimmable, white spectrum, opal white (Router)
zigbee2mqtt:info  2020-05-22 17:47:17: Zigbee: disabling joining new devices.
  zigbee-herdsman:controller:log Disable joining +228ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequestExt - {"dstaddrmode":2,"dstaddr":"0x000000000000fffd","destendpoint":242,"dstpanid":0,"srcendpoint":242,"clusterid":33,"transid":1,"options":0,"radius":30,"len":6,"data":{"type":"Buffer","data":[25,2,2,11,0,0]}} +24ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,26,36,2,2,253,255,0,0,0,0,0,0,242,0,0,242,33,0,1,0,30,6,0,25,2,2,11,0,0,22] +25ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,2,0,103] +31ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,2,0,103] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 2 - [0] - 103 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequestExt - {"status":0} +32ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,242,1,52] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,242,1,52] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,242,1] - 52 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":242,"transid":1} +53ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - mgmtPermitJoinReq - {"addrmode":15,"dstaddr":65532,"duration":0,"tcsignificance":0} +215ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,54,15,252,255,0,0,26] +213ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,54,0,82] +208ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,54,0,82] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 54 - [0] - 82 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - mgmtPermitJoinReq - {"status":0} +214ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
zigbee2mqtt:info  2020-05-22 17:47:17: Connecting to MQTT server at mqtt://andromeda.fritz.box
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,182,0,0,0,240] +75ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,182,0,0,0,240] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 182 - [0,0,0] - 240 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - mgmtPermitJoinRsp - {"srcaddr":0,"status":0} +283ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
zigbee2mqtt:info  2020-05-22 17:47:17: Connected to MQTT server
zigbee2mqtt:info  2020-05-22 17:47:17: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload 'online'
zigbee2mqtt:info  2020-05-22 17:47:17: MQTT publish: topic 'zigbee2mqtt/tradfri_remote', payload '{"update_available":true,"linkquality":0,"battery":47}'
zigbee2mqtt:info  2020-05-22 17:47:17: MQTT publish: topic 'zigbee2mqtt/0x000b57fffeec690b', payload '{"update_available":false}'
zigbee2mqtt:info  2020-05-22 17:47:17: MQTT publish: topic 'zigbee2mqtt/cc2531_repeater', payload '{"state":true,"led_state":false,"linkquality":89,"description":"37516/0x000B57FFFEEC690B","type":"NEIGHBOR","rssi":46,"led":false}'
zigbee2mqtt:info  2020-05-22 17:47:17: MQTT publish: topic 'zigbee2mqtt/paulmann_remote', payload '{"linkquality":47}'
zigbee2mqtt:info  2020-05-22 17:47:17: MQTT publish: topic 'zigbee2mqtt/hue_farblampe_ez', payload '{"state":"OFF","brightness":0,"linkquality":0,"update_available":false}'
zigbee2mqtt:info  2020-05-22 17:47:17: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'
zigbee2mqtt:info  2020-05-22 17:47:17: MQTT publish: topic 'zigbee2mqtt/bridge/config', payload '{"version":"1.13.1","commit":"3046680","coordinator":{"type":"zStack12","meta":{"transportrev":2,"product":0,"majorrel":2,"minorrel":6,"maintrel":3,"revision":20190608}},"log_level":"debug","permit_join":false}'

zigbee2mqtt:debug 2020-05-22 17:47:30: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:ON,brightness:100}'
zigbee2mqtt:debug 2020-05-22 17:47:30: Publishing 'set' 'state' to 'tradfri_900lm_ez'
  zigbee-herdsman:controller:endpoint Command 0x90fd9ffffe4cfea3/1 genLevelCtrl.moveToLevelWithOnOff({"level":0,"transtime":0}, {"timeout":10000,"manufacturerCode":null,"disableDefaultResponse":false}) +0ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":63853,"destendpoint":1,"srcendpoint":1,"clusterid":8,"transid":2,"options":0,"radius":30,"len":6,"data":{"type":"Buffer","data":[1,3,4,0,0,0]}} +12s
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,16,36,1,109,249,1,1,8,0,2,0,30,6,1,3,4,0,0,0,181] +12s
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100] +12s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0} +12s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,2,196] +4ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,2,196] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,2] - 196 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":2} +12s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,109,249,0,22] +9ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,109,249,0,22] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [109,249,0] - 22 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":63853,"relaycount":0,"relaylist":[]} +10ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,25,68,129,0,0,8,0,109,249,1,1,0,76,0,194,147,115,0,0,5,8,3,11,4,0,109,249,29,166] +58ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,25,68,129,0,0,8,0,109,249,1,1,0,76,0,194,147,115,0,0,5,8,3,11,4,0,109,249,29,166] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 25 - 2 - 4 - 129 - [0,0,8,0,109,249,1,1,0,76,0,194,147,115,0,0,5,8,3,11,4,0,109,249,29] - 166 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":8,"srcaddr":63853,"srcendpoint":1,"dstendpoint":1,"wasbroadcast":0,"linkquality":76,"securityuse":0,"timestamp":7574466,"transseqnumber":0,"len":5,"data":{"type":"Buffer","data":[8,3,11,4,0]}} +60ms
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false},"transactionSequenceNumber":3,"manufacturerCode":null,"commandIdentifier":11},"Payload":{"cmdId":4,"statusCode":0}},"address":63853,"endpoint":1,"linkquality":76,"groupID":0}' +13s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +9ms
zigbee2mqtt:info  2020-05-22 17:47:30: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'
zigbee2mqtt:debug 2020-05-22 17:47:35: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:TOGGLE}'
zigbee2mqtt:debug 2020-05-22 17:47:35: Publishing 'set' 'state' to 'tradfri_900lm_ez'
  zigbee-herdsman:controller:endpoint Command 0x90fd9ffffe4cfea3/1 genLevelCtrl.moveToLevelWithOnOff({"level":0,"transtime":0}, {"timeout":10000,"manufacturerCode":null,"disableDefaultResponse":false}) +6s
  zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":63853,"destendpoint":1,"srcendpoint":1,"clusterid":8,"transid":3,"options":0,"radius":30,"len":6,"data":{"type":"Buffer","data":[1,4,4,0,0,0]}} +6s
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,16,36,1,109,249,1,1,8,0,3,0,30,6,1,4,4,0,0,0,179] +6s
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100] +5s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0} +6s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +2ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,3,197] +4ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,3,197] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,3] - 197 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":3} +5s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,109,249,0,22] +10ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,109,249,0,22] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [109,249,0] - 22 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":63853,"relaycount":0,"relaylist":[]} +12ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,25,68,129,0,0,8,0,109,249,1,1,0,76,0,136,215,115,0,0,5,8,4,11,4,0,109,249,29,175] +52ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,25,68,129,0,0,8,0,109,249,1,1,0,76,0,136,215,115,0,0,5,8,4,11,4,0,109,249,29,175] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 25 - 2 - 4 - 129 - [0,0,8,0,109,249,1,1,0,76,0,136,215,115,0,0,5,8,4,11,4,0,109,249,29] - 175 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":8,"srcaddr":63853,"srcendpoint":1,"dstendpoint":1,"wasbroadcast":0,"linkquality":76,"securityuse":0,"timestamp":7591816,"transseqnumber":0,"len":5,"data":{"type":"Buffer","data":[8,4,11,4,0]}} +55ms
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false},"transactionSequenceNumber":4,"manufacturerCode":null,"commandIdentifier":11},"Payload":{"cmdId":4,"statusCode":0}},"address":63853,"endpoint":1,"linkquality":76,"groupID":0}' +6s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +4ms
zigbee2mqtt:info  2020-05-22 17:47:35: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'
zigbee2mqtt:debug 2020-05-22 17:47:37: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:ON,brightness:100}'
zigbee2mqtt:debug 2020-05-22 17:47:37: Publishing 'set' 'state' to 'tradfri_900lm_ez'
  zigbee-herdsman:controller:endpoint Command 0x90fd9ffffe4cfea3/1 genLevelCtrl.moveToLevelWithOnOff({"level":0,"transtime":0}, {"timeout":10000,"manufacturerCode":null,"disableDefaultResponse":false}) +2s
  zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":63853,"destendpoint":1,"srcendpoint":1,"clusterid":8,"transid":4,"options":0,"radius":30,"len":6,"data":{"type":"Buffer","data":[1,5,4,0,0,0]}} +2s
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,16,36,1,109,249,1,1,8,0,4,0,30,6,1,5,4,0,0,0,181] +2s
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100] +2s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0} +2s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,4,194] +5ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,4,194] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,4] - 194 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":4} +2s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,109,249,0,22] +10ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,109,249,0,22] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [109,249,0] - 22 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":63853,"relaycount":0,"relaylist":[]} +12ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,25,68,129,0,0,8,0,109,249,1,1,0,73,0,15,239,115,0,0,5,8,5,11,4,0,109,249,29,20] +56ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,25,68,129,0,0,8,0,109,249,1,1,0,73,0,15,239,115,0,0,5,8,5,11,4,0,109,249,29,20] +0ms                                                                                                                                              
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 25 - 2 - 4 - 129 - [0,0,8,0,109,249,1,1,0,73,0,15,239,115,0,0,5,8,5,11,4,0,109,249,29] - 20 +1ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":8,"srcaddr":63853,"srcendpoint":1,"dstendpoint":1,"wasbroadcast":0,"linkquality":73,"securityuse":0,"timestamp":7597839,"transseqnumber":0,"len":5,"data":{"type":"Buffer","data":[8,5,11,4,0]}} +57ms
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false},"transactionSequenceNumber":5,"manufacturerCode":null,"commandIdentifier":11},"Payload":{"cmdId":4,"statusCode":0}},"address":63853,"endpoint":1,"linkquality":73,"groupID":0}' +2s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +2ms
zigbee2mqtt:info  2020-05-22 17:47:37: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'
zigbee2mqtt:debug 2020-05-22 17:47:41: Received MQTT message on 'zigbee2mqtt/tradfri_900lm_ez/set' with data '{state:OFF}'
zigbee2mqtt:debug 2020-05-22 17:47:41: Publishing 'set' 'state' to 'tradfri_900lm_ez'
  zigbee-herdsman:controller:endpoint Command 0x90fd9ffffe4cfea3/1 genLevelCtrl.moveToLevelWithOnOff({"level":0,"transtime":0}, {"timeout":10000,"manufacturerCode":null,"disableDefaultResponse":false}) +4s
  zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequest - {"dstaddr":63853,"destendpoint":1,"srcendpoint":1,"clusterid":8,"transid":5,"options":0,"radius":30,"len":6,"data":{"type":"Buffer","data":[1,6,4,0,0,0]}} +4s
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,16,36,1,109,249,1,1,8,0,5,0,30,6,1,6,4,0,0,0,183] +4s
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,1,0,100] +4s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,1,0,100] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 1 - [0] - 100 +1ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequest - {"status":0} +4s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,1,5,195] +6ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,1,5,195] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,1,5] - 195 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":1,"transid":5} +4s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,69,196,109,249,0,22] +11ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,196,109,249,0,22] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 196 - [109,249,0] - 22 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - srcRtgInd - {"dstaddr":63853,"relaycount":0,"relaylist":[]} +12ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,25,68,129,0,0,8,0,109,249,1,1,0,73,0,219,32,116,0,0,5,8,6,11,4,0,109,249,29,11] +56ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,25,68,129,0,0,8,0,109,249,1,1,0,73,0,219,32,116,0,0,5,8,6,11,4,0,109,249,29,11] +0ms                                                                                                                                              
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 25 - 2 - 4 - 129 - [0,0,8,0,109,249,1,1,0,73,0,219,32,116,0,0,5,8,6,11,4,0,109,249,29] - 11 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - incomingMsg - {"groupid":0,"clusterid":8,"srcaddr":63853,"srcendpoint":1,"dstendpoint":1,"wasbroadcast":0,"linkquality":73,"securityuse":0,"timestamp":7610587,"transseqnumber":0,"len":5,"data":{"type":"Buffer","data":[8,6,11,4,0]}} +57ms
  zigbee-herdsman:controller:log Received 'zcl' data '{"frame":{"Header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false},"transactionSequenceNumber":6,"manufacturerCode":null,"commandIdentifier":11},"Payload":{"cmdId":4,"statusCode":0}},"address":63853,"endpoint":1,"linkquality":73,"groupID":0}' +4s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +3ms
zigbee2mqtt:info  2020-05-22 17:47:41: MQTT publish: topic 'zigbee2mqtt/tradfri_900lm_ez', payload '{"update_available":true,"state":"OFF","brightness":0}'

^Czigbee2mqtt:debug 2020-05-22 17:47:47: Saving state to file /opt/zigbee2mqtt/data/state.json
zigbee2mqtt:info  2020-05-22 17:47:47: MQTT publish: topic 'zigbee2mqtt/bridge/state', payload 'offline'
zigbee2mqtt:info  2020-05-22 17:47:47: Disconnecting from MQTT server
  zigbee-herdsman:controller:log Disable joining +6s
  zigbee-herdsman:adapter:zStack:znp:SREQ --> AF - dataRequestExt - {"dstaddrmode":2,"dstaddr":"0x000000000000fffd","destendpoint":242,"dstpanid":0,"srcendpoint":242,"clusterid":33,"transid":6,"options":0,"radius":30,"len":6,"data":{"type":"Buffer","data":[25,7,2,11,0,0]}} +6s
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,26,36,2,2,253,255,0,0,0,0,0,0,242,0,0,242,33,0,6,0,30,6,0,25,7,2,11,0,0,20] +6s
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,100,2,0,103] +6s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,100,2,0,103] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 4 - 2 - [0] - 103 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- AF - dataRequestExt - {"status":0} +6s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,3,68,128,0,242,6,51] +6ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,68,128,0,242,6,51] +0ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 4 - 128 - [0,242,6] - 51 +0ms
  zigbee-herdsman:adapter:zStack:znp:AREQ <-- AF - dataConfirm - {"status":0,"endpoint":242,"transid":6} +6s
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - mgmtPermitJoinReq - {"addrmode":15,"dstaddr":65532,"duration":0,"tcsignificance":0} +215ms
  zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,54,15,252,255,0,0,26] +214ms
  zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,54,0,82] +208ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,54,0,82] +1ms
  zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 54 - [0] - 82 +0ms
  zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - mgmtPermitJoinReq - {"status":0} +217ms
  zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [] +1ms
  zigbee-herdsman:adapter:zStack:znp:log Port closed +32s
zigbee2mqtt:info  2020-05-22 17:47:47: zigbee-herdsman stopped

[Koenkk]

The payloads you send are not valid JSON, e.g. {state:ON} should be {"state": "ON"}. Try with mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m '{"state": "ON"}'

[probutus]

you' re right. I got tricked by the BASH

This works: mosquitto_pub -h andromeda.fritz.box -p 1883 -t zigbee2mqtt/tradfri_900lm_ez/set -m {\"state\":\"ON\"\,\"brightness\":255}