Edit: I seem to have solved it, I saw in the z wave js ui config the ‘USA long range’ and ‘USA’ options. That made me think that it might have something to do with the long range feature. I then switched to ‘Europe’ (had to do it in 2 places) and now everything seems to be working fine! I still don’t know why the long range option didn’t work or at least fall back.
I’m quite new to home assistant so I hope my problem resides between my char and keyboard and not my setup. I did follow the guides on home assistant so I am confused on why I can’t get this to work.
Yesterday I’ve setup a new home assistant server (container, using docker-compose mentioned in the guide) and configured it. I then enabled a wifi smart device and it popped up in home assistant within seconds, I added it in and it works like a charm.
I then setup a z-wave js ui container, configured my zooz z-wave 800 long range in it, enabled the web sockets server for home assistant and added it into home assistant (was also discovered within seconds).
All good so far. I then entered into inclusion mode and started to triple tap my fibaro dimmers to get them to pair. Nothing happened.
I rebooted the server running my container, power cycled the dimmers - still nothing. I tried secure, legacy secure and unsecure pairing - again nothing.
I then figured, maybe I need to be in close range of my usb controller. I used a Aeotex humidity sensor and entered that into pairing mode (LEDs are flashing red green indicating its in pairing mode). Still nothing. I’ve been looking and both the z wave js ui logs and the home assistant logs none of them indicates any error or warning. It logs the start of the inclusion process, then no sign of anything trying to pair and finally ‘Inclusion stopped’.
I also tried the following, without any improvement:
- Reboot everything
- Look for FW update (there was none)
- Factory reset the zooz stick
- Remove and readd the zwave integration
- Use different USB ports
- Delete the zwave js ui container and all its data and reinstall it
- Removed the zwave integration from my home assistant server, setup a second new fresh home assistant server add the zwave integration connected to zwave js ui
I’m quite lost at this moment and wondering if it’s the USB stick that might be faulty. If anyone can figure this out with me I would be very gratefull!
Logs:
2023-11-05 22:32:27.246 INFO (MainThread) [homeassistant.components.zwave_js] Enabling zwave-js-server logging
2023-11-05 22:32:27.247 DEBUG (MainThread) [zwave_js_server] Publishing message:
{'command': 'start_listening_logs',
'messageId': '99ab414bec654c7f94ef73f54dc3c4e6'}
2023-11-05 22:32:27.253 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"result","success":true,"messageId":"99ab414bec654c7f94ef73f54dc3c4e6","result":{}}', extra='')
2023-11-05 22:32:27.253 INFO (MainThread) [homeassistant.components.zwave_js] Zwave-js-server logging is enabled
2023-11-05 22:32:47.187 INFO (MainThread) [homeassistant.components.zwave_js] Enabling zwave-js-server logging
2023-11-05 22:32:47.188 DEBUG (MainThread) [zwave_js_server] Publishing message:
{'command': 'start_listening_logs',
'messageId': '21508c152c994dfd9fc70144dfb1968b'}
2023-11-05 22:32:47.190 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"result","success":true,"messageId":"21508c152c994dfd9fc70144dfb1968b","result":{}}', extra='')
2023-11-05 22:32:47.190 INFO (MainThread) [homeassistant.components.zwave_js] Zwave-js-server logging is enabled
2023-11-05 22:32:50.560 DEBUG (MainThread) [zwave_js_server] Publishing message:
{'command': 'controller.get_state',
'messageId': '1074be269dd54f3a8372e26e4aad621f'}
2023-11-05 22:32:50.561 DEBUG (MainThread) [zwave_js_server] Publishing message:
{'command': 'driver.is_statistics_enabled',
'messageId': 'abf9c61a91eb43e49e966497ce3b911b'}
2023-11-05 22:32:50.561 DEBUG (MainThread) [zwave_js_server] Publishing message:
{'command': 'controller.get_provisioning_entries',
'messageId': '83a7e16792254fee8e3486c4ff948f9c'}
2023-11-05 22:32:50.562 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"result","success":true,"messageId":"1074be269dd54f3a8372e26e4aad621f","result":{"state":{"type":7,"homeId":3476184453,"ownNodeId":1,"isUsingHomeIdFromOtherNetwork":false,"isSISPresent":true,"wasRealPrimary":true,"manufacturerId":634,"productType":4,"productId":1552,"supportedFunctionTypes":[2,3,5,6,7,8,9,10,11,16,18,19,20,21,22,28,32,33,34,35,36,39,40,41,42,44,46,55,56,57,58,59,60,63,65,66,68,69,70,71,72,73,74,75,77,79,80,81,83,84,85,86,87,88,94,95,96,97,98,99,101,128,144,146,147,152,160,162,164,165,166,168,169,171,189,190,191,210,211,212,214,215,216,217,218,219,220,221,222,223,231,232],"sucNodeId":1,"supportsTimers":false,"statistics":{"messagesTX":110,"messagesRX":110,"messagesDroppedRX":0,"NAK":0,"CAN":0,"timeoutACK":0,"timeoutResponse":0,"timeoutCallback":0,"messagesDroppedTX":0,"backgroundRSSI":{"channel0":{"current":-109,"average":-108},"channel1":{"current":-108,"average":-108},"channel2":{"current":-108,"average":-108},"timestamp":1699219943224}},"inclusionState":0,"sdkVersion":"7.19.3","firmwareVersion":"1.20","isPrimary":true,"isSUC":true,"nodeType":0,"rfRegion":9,"status":0,"isRebuildingRoutes":false}}}', extra='')
2023-11-05 22:32:50.563 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"result","success":true,"messageId":"abf9c61a91eb43e49e966497ce3b911b","result":{"statisticsEnabled":false}}', extra='')
2023-11-05 22:32:50.564 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"result","success":true,"messageId":"83a7e16792254fee8e3486c4ff948f9c","result":{"entries":[]}}', extra='')
2023-11-05 22:32:51.466 DEBUG (MainThread) [zwave_js_server] Publishing message:
{'command': 'controller.supports_feature',
'feature': 0,
'messageId': '8ead5cbba88247ab869bde32f4582d92'}
2023-11-05 22:32:51.467 DEBUG (MainThread) [zwave_js_server] Publishing message:
{'command': 'controller.begin_inclusion',
'messageId': '6abde5fb1b6f4a388603bacd6bf9f933',
'options': {'strategy': <InclusionStrategy.DEFAULT: 0>}}
2023-11-05 22:32:51.468 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"result","success":true,"messageId":"8ead5cbba88247ab869bde32f4582d92","result":{"supported":true}}', extra='')
2023-11-05 22:32:51.470 INFO (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.468Z:
2023-11-05T21:32:51.468Z CNTRLR Starting inclusion process with strategy Default...
2023-11-05 22:32:51.472 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.471Z:
2023-11-05T21:32:51.471Z DRIVER all queues busy
2023-11-05 22:32:51.475 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.474Z:
2023-11-05T21:32:51.474Z SERIAL » 0x0105004ac10f7e (7 bytes)
2023-11-05 22:32:51.476 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.475Z:
2023-11-05T21:32:51.475Z DRIVER » [REQ] [AddNodeToNetwork]
node type: Any
high power: true
network wide: true
callback id: 15
2023-11-05 22:32:51.478 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.477Z:
2023-11-05T21:32:51.477Z SERIAL « [ACK] (0x06)
2023-11-05 22:32:51.482 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.480Z:
2023-11-05T21:32:51.480Z SERIAL « 0x0108004a0f01000000b3 (10 bytes)
2023-11-05 22:32:51.483 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"event","event":{"source":"controller","event":"statistics updated","statistics":{"messagesTX":110,"messagesRX":111,"messagesDroppedRX":0,"NAK":0,"CAN":0,"timeoutACK":0,"timeoutResponse":0,"timeoutCallback":0,"messagesDroppedTX":0,"backgroundRSSI":{"channel0":{"current":-109,"average":-108},"channel1":{"current":-108,"average":-108},"channel2":{"current":-108,"average":-108},"timestamp":1699219943224}}}}', extra='')
2023-11-05 22:32:51.484 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.482Z:
2023-11-05T21:32:51.482Z SERIAL » [ACK] (0x06)
2023-11-05 22:32:51.487 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.484Z:
2023-11-05T21:32:51.484Z DRIVER « [REQ] [AddNodeToNetwork]
status: Ready
callback id: 15
2023-11-05 22:32:51.487 INFO (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.485Z:
2023-11-05T21:32:51.485Z CNTRLR The controller is now ready to add nodes
2023-11-05 22:32:51.488 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"event","event":{"source":"controller","event":"inclusion started","secure":true}}', extra='')
2023-11-05 22:32:51.488 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"result","success":true,"messageId":"6abde5fb1b6f4a388603bacd6bf9f933","result":{"success":true}}', extra='')
2023-11-05 22:32:51.490 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:51.488Z:
2023-11-05T21:32:51.488Z DRIVER all queues idle
2023-11-05 22:32:51.732 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"event","event":{"source":"controller","event":"statistics updated","statistics":{"messagesTX":111,"messagesRX":111,"messagesDroppedRX":0,"NAK":0,"CAN":0,"timeoutACK":0,"timeoutResponse":0,"timeoutCallback":0,"messagesDroppedTX":0,"backgroundRSSI":{"channel0":{"current":-109,"average":-108},"channel1":{"current":-108,"average":-108},"channel2":{"current":-108,"average":-108},"timestamp":1699219943224}}}}', extra='')
2023-11-05 22:32:56.492 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:56.490Z:
2023-11-05T21:32:56.490Z DRIVER all queues busy
2023-11-05 22:32:56.494 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:56.493Z:
2023-11-05T21:32:56.493Z SERIAL » 0x0103003bc7 (5 bytes)
2023-11-05 22:32:56.495 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:56.493Z:
2023-11-05T21:32:56.493Z DRIVER » [REQ] [GetBackgroundRSSI]
2023-11-05 22:32:56.497 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:56.495Z:
2023-11-05T21:32:56.495Z SERIAL « [ACK] (0x06)
2023-11-05 22:32:56.498 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:56.497Z:
2023-11-05T21:32:56.497Z SERIAL « 0x0107013b93949494c5 (9 bytes)
2023-11-05 22:32:56.499 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"event","event":{"source":"controller","event":"statistics updated","statistics":{"messagesTX":111,"messagesRX":112,"messagesDroppedRX":0,"NAK":0,"CAN":0,"timeoutACK":0,"timeoutResponse":0,"timeoutCallback":0,"messagesDroppedTX":0,"backgroundRSSI":{"channel0":{"current":-109,"average":-108},"channel1":{"current":-108,"average":-108},"channel2":{"current":-108,"average":-108},"timestamp":1699219943224}}}}', extra='')
2023-11-05 22:32:56.501 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:56.498Z:
2023-11-05T21:32:56.498Z SERIAL » [ACK] (0x06)
2023-11-05 22:32:56.501 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:56.499Z:
2023-11-05T21:32:56.499Z DRIVER « [RES] [GetBackgroundRSSI]
channel 0: -109 dBm
channel 1: -108 dBm
channel 2: -108 dBm
2023-11-05 22:32:56.503 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:32:56.501Z:
2023-11-05T21:32:56.501Z DRIVER all queues idle
2023-11-05 22:32:56.750 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"event","event":{"source":"controller","event":"statistics updated","statistics":{"messagesTX":112,"messagesRX":112,"messagesDroppedRX":0,"NAK":0,"CAN":0,"timeoutACK":0,"timeoutResponse":0,"timeoutCallback":0,"messagesDroppedTX":0,"backgroundRSSI":{"channel0":{"current":-109,"average":-108},"channel1":{"current":-108,"average":-108},"channel2":{"current":-108,"average":-108},"timestamp":1699219976501}}}}', extra='')
2023-11-05 22:33:08.300 DEBUG (MainThread) [zwave_js_server] Publishing message:
{'command': 'controller.stop_inclusion',
'messageId': '031766d637764e76be62159d2e835f16'}
2023-11-05 22:33:08.303 INFO (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.302Z:
2023-11-05T21:33:08.302Z CNTRLR stopping inclusion process...
2023-11-05 22:33:08.305 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.304Z:
2023-11-05T21:33:08.304Z DRIVER all queues busy
2023-11-05 22:33:08.308 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.307Z:
2023-11-05T21:33:08.307Z SERIAL » 0x0105004ac51065 (7 bytes)
2023-11-05 22:33:08.309 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.308Z:
2023-11-05T21:33:08.308Z DRIVER » [REQ] [AddNodeToNetwork]
action: Stop
high power: true
network wide: true
callback id: 16
2023-11-05 22:33:08.311 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.310Z:
2023-11-05T21:33:08.310Z SERIAL « [ACK] (0x06)
2023-11-05 22:33:08.313 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.312Z:
2023-11-05T21:33:08.312Z SERIAL « 0x0108004a1006000000ab (10 bytes)
2023-11-05 22:33:08.314 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"event","event":{"source":"controller","event":"statistics updated","statistics":{"messagesTX":112,"messagesRX":113,"messagesDroppedRX":0,"NAK":0,"CAN":0,"timeoutACK":0,"timeoutResponse":0,"timeoutCallback":0,"messagesDroppedTX":0,"backgroundRSSI":{"channel0":{"current":-109,"average":-108},"channel1":{"current":-108,"average":-108},"channel2":{"current":-108,"average":-108},"timestamp":1699219976501}}}}', extra='')
2023-11-05 22:33:08.316 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.314Z:
2023-11-05T21:33:08.314Z SERIAL » [ACK] (0x06)
2023-11-05 22:33:08.316 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.315Z:
2023-11-05T21:33:08.315Z DRIVER « [REQ] [AddNodeToNetwork]
status: Done
callback id: 16
2023-11-05 22:33:08.318 INFO (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.317Z:
2023-11-05T21:33:08.317Z CNTRLR The inclusion process was stopped
2023-11-05 22:33:08.318 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"event","event":{"source":"controller","event":"inclusion stopped"}}', extra='')
2023-11-05 22:33:08.318 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"result","success":true,"messageId":"031766d637764e76be62159d2e835f16","result":{"success":true}}', extra='')
2023-11-05 22:33:08.320 DEBUG (MainThread) [zwave_js_server.server] 2023-11-05T21:33:08.318Z:
2023-11-05T21:33:08.318Z DRIVER all queues idle
2023-11-05 22:33:08.565 DEBUG (MainThread) [zwave_js_server] Received message:
WSMessage(type=<WSMsgType.TEXT: 1>, data='{"type":"event","event":{"source":"controller","event":"statistics updated","statistics":{"messagesTX":113,"messagesRX":113,"messagesDroppedRX":0,"NAK":0,"CAN":0,"timeoutACK":0,"timeoutResponse":0,"timeoutCallback":0,"messagesDroppedTX":0,"backgroundRSSI":{"channel0":{"current":-109,"average":-108},"channel1":{"current":-108,"average":-108},"channel2":{"current":-108,"average":-108},"timestamp":1699219976501}}}}', extra='')