IKEA Tradfri bulb LED1545G12 doesn't pair (interview failed)

[frauhottelmann]

Bug Report

Tradfri bulb doestn't pair because Interview failed.

zigbee2mqtt:info 2019-11-20T16:20:45: Device '0x90fd9ffffefae31a' joined zigbee2mqtt:info 2019-11-20T16:20:45: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x90fd9ffffefae31a"}}' zigbee2mqtt:info 2019-11-20T16:20:45: Starting interview of '0x90fd9ffffefae31a' zigbee2mqtt:info 2019-11-20T16:20:45: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x90fd9ffffefae31a"}}' zigbee2mqtt:error 2019-11-20T16:21:05: Failed to interview '0x90fd9ffffefae31a', device has not successfully been paired zigbee2mqtt:info 2019-11-20T16:21:05: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_failed","meta":{"friendly_name":"0x90fd9ffffefae31a"}}'

What did you expect to happen

Interview beeing successful and bulb ist paired.

How to reproduce it (minimal and precise)

Reseting the bulb while "permit_join": true. I restartet the addon as well. The reset of the bulb is successful because it blinks.

Debug Info

zigbee2mqtt version: 1.7.1
CC2531 firmware version:
{"type":"zStack30x","meta":{"transportrev":2,"product":2,"majorrel":2,"minorrel":7,"maintrel":2,"revision":20190425}}

Log:
https://gist.github.com/frauhottelmann/a49d83d75b66f2d63137d4ec70368299

[Koenkk]

Please check #2355 (comment)

[CMDR-Sloma]

I have moved my post from other similar thread here because it of exactly the same issue.

I have the same problem with pairing devices (Ikea bulb LED1545G12 below):

zigbee2mqtt:info  2019-11-20T13:00:01: Device '0x90fd9ffffed54a16' joined
zigbee2mqtt:info  2019-11-20T13:00:01: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x90fd9ffffed54a16"}}'
zigbee2mqtt:info  2019-11-20T13:00:01: Starting interview of '0x90fd9ffffed54a16'
zigbee2mqtt:info  2019-11-20T13:00:01: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x90fd9ffffed54a16"}}'
zigbee2mqtt:debug 2019-11-20T13:00:07: Device '0x90fd9ffffed54a16' announced itself
zigbee2mqtt:error 2019-11-20T13:00:21: Failed to interview '0x90fd9ffffed54a16', device has not successfully been paired
zigbee2mqtt:info  2019-11-20T13:00:21: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_failed","meta":{"friendly_name":"0x90fd9ffffed54a16"}}'

I'm on hassio with zigbee2mqtt add-on v.1.7.1 and latest firmware on coordinator. Sometimes it works, sometimes I have to restart pairing by switching on-off the bulb.

UPDATE:

Overall, pairing after upgrade from 1.6 to 1.7.1 is total pain because of "Failed to interview" errors. Some of my Ikea bulbs pair on first try, other times it is after second or third but some I simply cannot pair because of this. I had no issues before, bulbs were always pairing on first try. I was previously on default firmware and had no issues but because I have more than 40 devices I wanted to try alternative firmware and pairing with this was much worse. I gave up after fourth device which I tried to pair probably 20 times with no luck. Now, I went back to default firmware and it's harder and harder after I paired 16 devices... Those are bulbs only....

Please check #2355 (comment)

I think it started after migration to zigbee-herdsman. Because of pairing problems I have downgraded my zigbee2mqtt to 1.6 and I don't have any issues pairing Ikea devices. Everything paired at first try. I have 9 Ikea GU10 Spotlights, 3 "candle" light LEDs, 5 Ikea sockets and 5 other Ikea bulbs. All gave me pairing problems under zigbee-herdsman but not under zigbee-shepherd.

[frauhottelmann]

Please check #2355 (comment)

That did work. Maybe it is a firmware issue with the bulbs.

@CMDR-Sloma I didn't have any issues with other devices.

[Koenkk]

@CMDR-Sloma I'm not sure wether zigbee-herdsman has regression regarding pairing or if that zigbee-shepherd does not log errors at all.

[CMDR-Sloma]

I'm sorry for re-opening but I want to be sure:

@frauhottelmann Do you mean other Ikea devices or simply other devices?

@Koenkk I noticed under zigbee-shepherd it eventually pairs after several seconds. I don't know if it helps here is my log for successful pairing of another IKEA device:

11/20/2019, 4:10:44 PM - error: Cannot get the Node Descriptor of the Device: 0x90fd9ffffef4cb72 (Error: Timed out after 10000 ms)
11/20/2019, 4:10:54 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genBasic","data":{"manufacturerName":"IKEA of Sweden","modelId":"TRADFRI bulb E27 WS opal 980lm","powerSource":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:54 PM - info: New device 'TRADFRI bulb E27 WS opal 980lm' with address 0x90fd9ffffef4cb72 connected!
11/20/2019, 4:10:54 PM - debug: Saving state to file /share/zigbee2mqtt/state.json
11/20/2019, 4:10:54 PM - info: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":"0x90fd9ffffef4cb72","meta":{"modelID":"TRADFRI bulb E27 WS opal 980lm"}}'
11/20/2019, 4:10:54 PM - info: MQTT publish: topic 'homeassistant/light/0x90fd9ffffef4cb72/light/config', payload '{"brightness":true,"color_temp":true,"schema":"json","command_topic":"zigbee2mqtt/0x90fd9ffffef4cb72/set","state_topic":"zigbee2mqtt/0x90fd9ffffef4cb72","name":"0x90fd9ffffef4cb72_light","unique_id":"0x90fd9ffffef4cb72_light_zigbee2mqtt","device":{"identifiers":["zigbee2mqtt_0x90fd9ffffef4cb72"],"name":"0x90fd9ffffef4cb72","sw_version":"Zigbee2mqtt 1.6.0","model":"TRADFRI LED bulb E26/E27 980 lumen, dimmable, white spectrum, opal white (LED1545G12)","manufacturer":"IKEA"},"availability_topic":"zigbee2mqtt/0x90fd9ffffef4cb72/availability"}'
11/20/2019, 4:10:54 PM - info: MQTT publish: topic 'homeassistant/sensor/0x90fd9ffffef4cb72/linkquality/config', payload '{"unit_of_measurement":"-","value_template":"{{ value_json.linkquality }}","state_topic":"zigbee2mqtt/0x90fd9ffffef4cb72","json_attributes_topic":"zigbee2mqtt/0x90fd9ffffef4cb72","name":"0x90fd9ffffef4cb72_linkquality","unique_id":"0x90fd9ffffef4cb72_linkquality_zigbee2mqtt","device":{"identifiers":["zigbee2mqtt_0x90fd9ffffef4cb72"],"name":"0x90fd9ffffef4cb72","sw_version":"Zigbee2mqtt 1.6.0","model":"TRADFRI LED bulb E26/E27 980 lumen, dimmable, white spectrum, opal white (LED1545G12)","manufacturer":"IKEA"},"availability_topic":"zigbee2mqtt/0x90fd9ffffef4cb72/availability"}'
11/20/2019, 4:10:54 PM - info: MQTT publish: topic 'zigbee2mqtt/0x90fd9ffffef4cb72/availability', payload 'online'
11/20/2019, 4:10:54 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genBasic","data":{"zclVersion":1,"appVersion":17,"stackVersion":87,"hwVersion":1,"dateCode":"20170331","swBuildId":"1.2.217"}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:55 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genBasic","data":{"zclVersion":1,"appVersion":17,"stackVersion":87,"hwVersion":1,"manufacturerName":"IKEA of Sweden"}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:55 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genBasic","data":{"10":{"type":"Buffer","data":[76,69,68,49,53,52,53,71,49,50,69,50,55,69,85]},"modelId":"TRADFRI bulb E27 WS opal 980lm","dateCode":"20170331","powerSource":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:55 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genBasic","data":{"65533":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:55 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:55 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genIdentify","data":{"65533":1,"identifyTime":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:55 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:55 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genGroups","data":{"65533":1,"nameSupport":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:55 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genScenes","data":{"count":0,"currentScene":0,"currentGroup":0,"sceneValid":0,"nameSupport":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genScenes","data":{"65533":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genOnOff","data":{"16387":1,"onOff":1,"globalSceneCtrl":1,"onTime":0,"offWaitTime":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:56 PM - info: MQTT publish: topic 'zigbee2mqtt/0x90fd9ffffef4cb72', payload '{"state":"ON","linkquality":42}'
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genOnOff","data":{"65533":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genLevelCtrl","data":{"15":0,"currentLevel":254,"remainingTime":0,"onOffTransitionTime":5,"onLevel":255}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:56 PM - info: MQTT publish: topic 'zigbee2mqtt/0x90fd9ffffef4cb72', payload '{"state":"ON","linkquality":42,"brightness":254}'
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"genLevelCtrl","data":{"16384":254,"65533":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:56 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:57 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:57 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:57 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"lightingColorCtrl","data":{"remainingTime":0,"currentX":30138,"currentY":26909,"colorTemperature":370,"colorMode":2}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:57 PM - info: MQTT publish: topic 'zigbee2mqtt/0x90fd9ffffef4cb72', payload '{"state":"ON","linkquality":42,"brightness":254,"color_temp":370,"color_mode":2,"color":{"x":0.4599,"y":0.4106}}'
11/20/2019, 4:10:57 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"lightingColorCtrl","data":{"15":0,"numPrimaries":0,"enhancedColorMode":2,"colorCapabilities":16,"colorTempPhysicalMin":250}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:57 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"lightingColorCtrl","data":{"16397":0,"16398":370,"65533":1,"colorTempPhysicalMax":454}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:57 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:57 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"haDiagnostic","data":{"numberOfResets":0,"macRxBcast":0,"macTxBcast":0,"aPSRxBcast":0,"aPSTxBcast":0}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:58 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"haDiagnostic","data":{"nwkFcFailure":0,"packetBufferAllocateFailures":0,"averageMacRetryPerApsMessageSent":0,"lastMessageLqi":80,"lastMessageRssi":-80}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:58 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"haDiagnostic","data":{"65533":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:58 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:58 PM - debug: Received zigbee message of type 'readRsp' with data '{"cid":"lightLink","data":{"65533":1}}' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:58 PM - debug: Received zigbee message of type 'devInterview' with data '"0x90fd9ffffef4cb72"'
11/20/2019, 4:10:58 PM - debug: Accept device incoming with ieeeAddr '0x90fd9ffffef4cb72' permit join is 'true'
11/20/2019, 4:10:58 PM - debug: Allowing device '0x90fd9ffffef4cb72' to join
11/20/2019, 4:10:58 PM - debug: Received zigbee message of type 'devIncoming' with data '"0x90fd9ffffef4cb72"' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:58 PM - info: Device incoming...
11/20/2019, 4:10:58 PM - info: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"device incoming"}'
11/20/2019, 4:10:58 PM - debug: Setting up reporting for 0x90fd9ffffef4cb72
11/20/2019, 4:10:58 PM - debug: Received zigbee message of type 'devStatus' with data '"online"' of device 'TRADFRI bulb E27 WS opal 980lm' (0x90fd9ffffef4cb72) of endpoint 1
11/20/2019, 4:10:58 PM - debug: Setup reporting for '0x90fd9ffffef4cb72' - genOnOff - 1
11/20/2019, 4:10:58 PM - debug: Setup reporting for '0x90fd9ffffef4cb72' - genLevelCtrl - 1
11/20/2019, 4:10:59 PM - debug: Setup reporting for '0x90fd9ffffef4cb72' - lightingColorCtrl - 3
11/20/2019, 4:10:59 PM - debug: Successfully setup reporting for '0x90fd9ffffef4cb72' - genOnOff - 1
11/20/2019, 4:10:59 PM - debug: Successfully setup reporting for '0x90fd9ffffef4cb72' - genLevelCtrl - 1
11/20/2019, 4:10:59 PM - debug: Successfully setup reporting for '0x90fd9ffffef4cb72' - lightingColorCtrl - 3

[frauhottelmann]

I'm sorry for re-opening but I want to be sure:

@frauhottelmann Do you mean other Ikea devices or simply other devices?

@Koenkk I noticed under zigbee-shepherd it eventually pairs after several seconds. I don't know if it helps here is my log for successful pairing of another IKEA device:

Both. I had no problems with another IKEA bulb and an IKEA repeater as well as my Xiaomi sensors and a switch.

[Koenkk]

@CMDR-Sloma can you post the log when running with DEBUG=* npm start and pairing the device (on zigbee2mqtt 1.7.1).

[CMDR-Sloma]

@Koenkk I'm on hassio with zigbee2mqtt add-on and I'm afraid, I don't have the ability to run npm

[Koenkk]

@CMDR-Sloma on the latest edge addon you can set zigbee_herdsman_debug: true in the config and it will show the debug logging.

[timdonovanuk]

@Koenkk I have -e DEBUG=* added to my docker.
It doesn't provide any additional info when pairing fails, but I do notice that at z2m startup, it shows:

0x90fd9ffffe4d91b2 (0x90fd9ffffe4d91b2): Not supported (Router)

This is also the device that later fails to pair:

debug 2020-03-13 16:44:15: Device '0x90fd9ffffe4d91b2' announced itself
info  2020-03-13 16:44:15: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_announced","message":"announce","meta":{"friendly_name":"0x90fd9ffffe4d91b2"}}'
info  2020-03-13 16:44:17: Starting interview of '0x90fd9ffffe4d91b2'
error 2020-03-13 16:45:17: Failed to interview '0x90fd9ffffe4d91b2', device has not successfully been paired

#2779 #2761

[HA-TB303]

Pairing after switch on/off/on/off/on/off/on/off reset:

2020-03-13T17:43:17.168Z zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - mgmtPermitJoinReq - {"addrmode":15,"dstaddr":65532,"duration":254,"tcsignificance":0}
2020-03-13T17:43:17.168Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,5,37,54,15,252,255,254,0,228]
2020-03-13T17:43:17.181Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,54,0,82,254,3]
2020-03-13T17:43:17.181Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,54,0,82,254,3]
2020-03-13T17:43:17.181Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 54 - [0] - 82
2020-03-13T17:43:17.181Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - mgmtPermitJoinReq - {"status":0}
2020-03-13T17:43:17.182Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3]
2020-03-13T17:43:17.193Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [69,182,0,0,0,240]
2020-03-13T17:43:17.194Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,3,69,182,0,0,0,240]
2020-03-13T17:43:17.194Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 3 - 2 - 5 - 182 - [0,0,0] - 240
2020-03-13T17:43:17.194Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - mgmtPermitJoinRsp - {"srcaddr":0,"status":0}
2020-03-13T17:43:17.194Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
zigbee2mqtt:info  2020-03-13 18:43:17: MQTT publish: topic 'zigbee2mqtt/bridge/config', payload '{"version":"1.11.0-dev","commit":"ce48bee4cd0eac6cb36ed36b20a39024e1ecf0de","coordinator":{"type":"zStack3x0","meta":{"transportrev":2,"product":1,"majorrel":2,"minorrel":7,"maintrel":1,"revision":20200306}},"log_level":"debug","permit_join":true}'
zigbee2mqtt:debug 2020-03-13 18:43:53: Saving state to file /share/zigbee2mqtt/state.json
2020-03-13T17:44:36.361Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,12,69,202,121,170,71,162]
2020-03-13T17:44:36.362Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,121,170,71,162]
2020-03-13T17:44:36.362Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [20,254,255,159,253,144,0,0,82]
2020-03-13T17:44:36.362Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,121,170,71,162,20,254,255,159,253,144,0,0,82]
2020-03-13T17:44:36.362Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 12 - 2 - 5 - 202 - [121,170,71,162,20,254,255,159,253,144,0,0] - 82
2020-03-13T17:44:36.363Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - tcDeviceInd - {"nwkaddr":43641,"extaddr":"0x90fd9ffffe14a247","parentaddr":0}
2020-03-13T17:44:36.364Z zigbee-herdsman:controller:log Device '0x90fd9ffffe14a247' joined
2020-03-13T17:44:36.365Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2020-03-13T17:44:36.365Z zigbee-herdsman:controller:log Device '0x90fd9ffffe14a247' accepted by handler
2020-03-13T17:44:36.365Z zigbee-herdsman:controller:log New device '0x90fd9ffffe14a247' joined
2020-03-13T17:44:36.365Z zigbee-herdsman:controller:log Creating device '0x90fd9ffffe14a247'
zigbee2mqtt:info  2020-03-13 18:44:36: Device '0x90fd9ffffe14a247' joined
zigbee2mqtt:info  2020-03-13 18:44:36: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"device_connected","message":{"friendly_name":"0x90fd9ffffe14a247"}}'
2020-03-13T17:44:36.372Z zigbee-herdsman:controller:log Interview '0x90fd9ffffe14a247' start
zigbee2mqtt:info  2020-03-13 18:44:36: Starting interview of '0x90fd9ffffe14a247'
zigbee2mqtt:info  2020-03-13 18:44:36: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"type":"pairing","message":"interview_started","meta":{"friendly_name":"0x90fd9ffffe14a247"}}'
2020-03-13T17:44:36.374Z zigbee-herdsman:controller:device Interview - start device '0x90fd9ffffe14a247'
2020-03-13T17:44:36.377Z zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":43641,"nwkaddrofinterest":43641}
2020-03-13T17:44:36.377Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,121,170,121,170,35]
2020-03-13T17:44:36.383Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102]
2020-03-13T17:44:36.383Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102]
2020-03-13T17:44:36.383Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102
2020-03-13T17:44:36.383Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0}
2020-03-13T17:44:36.383Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2020-03-13T17:44:37.530Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,12,69,202,121,170,71,162]
2020-03-13T17:44:37.531Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,121,170,71,162]
2020-03-13T17:44:37.531Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [20,254,255,159,253,144,0,0,82]
2020-03-13T17:44:37.531Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,12,69,202,121,170,71,162,20,254,255,159,253,144,0,0,82]
2020-03-13T17:44:37.531Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 12 - 2 - 5 - 202 - [121,170,71,162,20,254,255,159,253,144,0,0] - 82
2020-03-13T17:44:37.532Z zigbee-herdsman:adapter:zStack:znp:AREQ <-- ZDO - tcDeviceInd - {"nwkaddr":43641,"extaddr":"0x90fd9ffffe14a247","parentaddr":0}
2020-03-13T17:44:37.532Z zigbee-herdsman:controller:log Device '0x90fd9ffffe14a247' joined
2020-03-13T17:44:37.532Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []
2020-03-13T17:44:37.532Z zigbee-herdsman:controller:log Device '0x90fd9ffffe14a247' accepted by handler
2020-03-13T17:44:37.532Z zigbee-herdsman:controller:log Not interviewing '0x90fd9ffffe14a247', completed 'false', in progress 'true'
2020-03-13T17:44:46.377Z zigbee-herdsman:controller:device Interview - node descriptor request failed for '0x90fd9ffffe14a247', attempt 1
2020-03-13T17:44:46.378Z zigbee-herdsman:adapter:zStack:znp:SREQ --> ZDO - nodeDescReq - {"dstaddr":43641,"nwkaddrofinterest":43641}
2020-03-13T17:44:46.378Z zigbee-herdsman:adapter:zStack:unpi:writer --> frame [254,4,37,2,121,170,121,170,35]
2020-03-13T17:44:46.382Z zigbee-herdsman:adapter:zStack:unpi:parser <-- [254,1,101,2,0,102]
2020-03-13T17:44:46.382Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext [254,1,101,2,0,102]
2020-03-13T17:44:46.383Z zigbee-herdsman:adapter:zStack:unpi:parser --> parsed 1 - 3 - 5 - 2 - [0] - 102
2020-03-13T17:44:46.383Z zigbee-herdsman:adapter:zStack:znp:SRSP <-- ZDO - nodeDescReq - {"status":0}
2020-03-13T17:44:46.383Z zigbee-herdsman:adapter:zStack:unpi:parser --- parseNext []

[HA-TB303]

Hi @Koenkk .

I am not changing back to my CC2530 because switching back and forth costs me a day to re-pair everything again.

What can I do to help analyse this issue? I have half of my house lights and sensors not working now, I have the WAF bitchin'...... So I am extremely motivated to help ;)

[timdonovanuk]

Heh same, WAF is awful right now!

I just noticed zigbee_herdsman_debug logs seem to go to stdout which is a complete pain when running z2m in a docker using portainer. Any idea how to redirect them to a file?