SOLVED: Aeon-Labs Door/Window Sensor not Showing up in HA

cdikland · December 12, 2018, 4:27pm

Fixed. See post below:

I have 7 such (Aetec DBS29 Door/Window) sensons “included” within my Aeon z-wave USB stick and all but one work perfectly in HA. For some reason the 7th sensor never appears to show up.

I know this “missing” device is communicating with the z-wave stick because each time when I push the “z-wave” button on this Door/Window sensor the LED on the z-wave stick flashes.

The Aeon z-wave USB stick is a secondary hub in my Smartthings environment. This particular sensor along with the other 6 door/window sensors window sensors work perfectly within ST. Based on all the above I assume both this device and the USB stick are fine but for some unknown reason HA does not recognize it. I have excluded/included it several times and the end result is always the same.

Anyone have any suggestions on what I might try or look at??

I am running Hassbian v0.83.3 on a Raspberry 3 B+

finity · December 13, 2018, 12:11am

Have you looked in your z wave log in HA to see if there are any clues there?

Aussybob · December 13, 2018, 12:37am

If it’s on the USB stick then Hass would be showing the entities… Suggest what @finity said and check the OZW log for more information. Confirm that the device hasn’t been included on another hub.

cdikland · December 13, 2018, 1:20pm

The log shows entries for node id: 227 (more on that below) which I have confirmed is the rogue device not appearing within HA. Besides the OZW log I also found this log entry shown in the web ui when I click on “I” (information) underneath the “Developers Tools” option

Z-Wave entity Unknown Node 227 Alarm Level (node_id: 227) not ready after 30 seconds, continuing anyway

Node Id 227 cant be found anywhere in either the web UI “Manage your z-wave Network” or Developer Tools options.

Back to the OZW log. The first reference to node227 in this log contains this

2018-12-13 07:39:41.566 Detail, Node227, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2018-12-13 07:39:41.566 Detail, Node227, QueryStage_CacheLoad
2018-12-13 07:39:41.566 Info, Node227, Node Identity Codes: 0000:0000:0000
2018-12-13 07:39:41.566 Info, Node227, NoOperation::Set - Routing=true
2018-12-13 07:39:41.566 Detail, Node227, Queuing (NoOp) NoOperation_Set (Node=227): 0x01, 0x09, 0x00, 0x13, 0xe3, 0x02, 0x00, 0x00, 0x25, 0x2d, 0x0c
2018-12-13 07:39:41.566 Detail, Node227, Queuing (Query) Query Stage Complete (CacheLoad)
2018-12-13 07:39:41.566 Detail,   Expected reply was received
2018-12-13 07:39:41.566 Detail,   Message transaction complete

Further below the log contains

2018-12-13 07:39:46.479 Detail, Node227, Notification: NodeAdded
2018-12-13 07:39:46.484 Detail, Node227, Notification: NodeProtocolInfo
2018-12-13 07:39:46.488 Detail, Node227, Notification: EssentialNodeQueriesComplete
2018-12-13 07:39:46.489 Detail, Node227, Notification: ValueAdded
2018-12-13 07:39:46.495 Detail, Node227, Notification: ValueAdded
2018-12-13 07:39:46.505 Detail, Node227, Notification: ValueAdded
2018-12-13 07:39:46.516 Detail, Node227, Notification: ValueAdded
2018-12-13 07:39:46.520 Detail, Node227, Notification: ValueAdded
2018-12-13 07:39:46.523 Detail, Node227, Notification: Group
2018-12-13 07:39:46.524 Detail, Node227, Notification: ValueAdded
2018-12-13 07:39:46.530 Detail, Node227, Notification: ValueAdded
2018-12-13 07:39:46.534 Detail, Node227, Notification: ValueAdded

And…

2018-12-13 07:40:49.275 Detail, Node227,   Received: 0x01, 0x05, 0x00, 0x13, 0x2d, 0x01, 0xc5
2018-12-13 07:40:49.275 Detail, Node227,   ZW_SEND_DATA Request with callback ID 0x2d received (expected 0x2d)
2018-12-13 07:40:49.275 Info, Node227, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2018-12-13 07:40:49.276 Info, Node227,   Node 227 has been marked as asleep
2018-12-13 07:40:49.276 Info, Node227, Node not responding - moving QueryStageComplete command to Wake-Up queue
2018-12-13 07:40:49.276 Detail, Node227, Notification: Notification - NoOperation
2018-12-13 07:40:49.281 Detail, Node227, Notification: Notification - Node Asleep

I then played around with the device by removing and re-applying the magnet to simulate open/close door as well as pressing and releasing the Alarm switch. . This is what then appeared in the log

2018-12-13 07:43:08.632 Detail, Node227,   Received: 0x01, 0x09, 0x00, 0x04, 0x08, 0xe3, 0x03, 0x20, 0x01, 0xff, 0xc4
2018-12-13 07:43:08.632 Detail, 
2018-12-13 07:43:08.632 Info, Node227, Received Basic set from node 227: level=255.  Sending event notification.
2018-12-13 07:43:08.632 Detail, Node227, Notification: NodeEvent
2018-12-13 07:43:08.658 Detail, Node227,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0xe3, 0x03, 0x20, 0x01, 0xff, 0xcc
2018-12-13 07:43:08.658 Detail, 
2018-12-13 07:43:08.658 Info, Node227, Received Basic set from node 227: level=255.  Sending event notification.
2018-12-13 07:43:08.659 Detail, Node227, Notification: NodeEvent
2018-12-13 07:43:08.784 Detail, Node227,   Received: 0x01, 0x09, 0x00, 0x04, 0x08, 0xe3, 0x03, 0x20, 0x01, 0x00, 0x3b
2018-12-13 07:43:08.785 Detail, 
2018-12-13 07:43:08.785 Info, Node227, Received Basic set from node 227: level=0.  Sending event notification.
2018-12-13 07:43:08.785 Detail, Node227, Notification: NodeEvent
2018-12-13 07:43:08.793 Detail, Node227,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0xe3, 0x03, 0x20, 0x01, 0x00, 0x33
2018-12-13 07:43:08.793 Detail, 
2018-12-13 07:43:08.793 Info, Node227, Received Basic set from node 227: level=0.  Sending event notification.
2018-12-13 07:43:08.794 Detail, Node227, Notification: NodeEvent
2018-12-13 07:43:08.947 Detail, Node227,   Received: 0x01, 0x0a, 0x00, 0x04, 0x08, 0xe3, 0x04, 0x71, 0x05, 0x00, 0xff, 0x95
2018-12-13 07:43:08.947 Detail, 
2018-12-13 07:43:08.947 Info, Node227, Received Alarm report: type=0, level=255
2018-12-13 07:43:08.947 Detail, Node227, Initial read of value
2018-12-13 07:43:08.947 Detail, Node227, Initial read of value
2018-12-13 07:43:08.947 Detail, Node227, Notification: ValueChanged
2018-12-13 07:43:08.958 Detail, Node227, Notification: ValueChanged
2018-12-13 07:43:08.975 Detail, Node227,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0xe3, 0x04, 0x71, 0x05, 0x00, 0xff, 0x9d
2018-12-13 07:43:08.975 Detail, 
2018-12-13 07:43:08.975 Info, Node227, Received Alarm report: type=0, level=255
2018-12-13 07:43:08.975 Detail, Node227, Refreshed Value: old value=0, new value=0, type=byte
2018-12-13 07:43:08.975 Detail, Node227, Changes to this value are not verified
2018-12-13 07:43:08.975 Detail, Node227, Refreshed Value: old value=255, new value=255, type=byte
2018-12-13 07:43:08.975 Detail, Node227, Changes to this value are not verified
2018-12-13 07:43:08.975 Detail, Node227, Notification: ValueChanged
2018-12-13 07:43:08.985 Detail, Node227, Notification: ValueChanged
2018-12-13 07:43:09.046 Detail, Node227,   Received: 0x01, 0x09, 0x00, 0x04, 0x08, 0xe3, 0x03, 0x20, 0x01, 0xff, 0xc4
2018-12-13 07:43:09.046 Detail, 
2018-12-13 07:43:09.046 Info, Node227, Received Basic set from node 227: level=255.  Sending event notification.
2018-12-13 07:43:09.046 Detail, Node227, Notification: NodeEvent
2018-12-13 07:43:09.074 Detail, Node227,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0xe3, 0x03, 0x20, 0x01, 0xff, 0xcc
2018-12-13 07:43:09.075 Detail, 
2018-12-13 07:43:09.075 Info, Node227, Received Basic set from node 227: level=255.  Sending event notification.
2018-12-13 07:43:09.075 Detail, Node227, Notification: NodeEvent
2018-12-13 07:43:11.437 Detail, Node193,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0xc1, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x45
2018-12-13 07:43:11.437 Detail, 
2018-12-13 07:43:11.872 Detail, Node227,   Received: 0x01, 0x0a, 0x00, 0x04, 0x08, 0xe3, 0x04, 0x71, 0x05, 0x00, 0x00, 0x6a
2018-12-13 07:43:11.872 Detail, 
2018-12-13 07:43:11.873 Info, Node227, Received Alarm report: type=0, level=0

I decided to copy all the info contained in the zwfg_xxxxxxx.xml file for one of the working sensors and paste at the bottom of this file. Of course I changed the Copied Node ID to Id-=227. After restart the device appeared. It would not however respond to the magnet removal/re-applying until I held down the Alarm switch and pressed the -Zwave button. From this point the device worked perfectly until I restarted HA. Then I would have to repeat the Alarm and z-wave button press to get it back to working.

Sorry for the long post but I am reallly lost here

cdikland · December 13, 2018, 2:42pm

Well its working now
This rogue device is not new and used to work just fine in the past. Recently I moved from hassio (on SD card) to Hassbian on USB. That is when i first noticed the problem.

I rebooted the old hassio sd card and noticed the device was also missing. Pressing the z-wave button sevseral times and it magically appeared. However, like the current problem, it only worked until I restarted HA. Then I had to press the alarm and z-wave button before it would work again. This time, however, I was able to change the config values for this device. Specifically, Configuration/Z-Wave/Node/Node Config Options/Config Parameter. I selected the the parameter 121 and entered the value 272 then saved the config. Voila… from that point on everything worked even after I rebooted my latest version from the USB.

Happy