Errors after updating to MQTTController build 25139

[gwp1]

@tamorgen said in [SOLVED] New iblind and zwaveJScontroller/MSR not communicating:

I don't believe the old power switch commands work with the new command class. When I go into a specific device in ZwaveJS, the switch type devices are greyed out.

I noticed this as well. I've also noticed that tapping into the dialogue you're showing (how did you copy/paste images, I keep getting a parsing server error...) nets a slider in HA that does nothing. It literally says UNKNOWN at the top where a percentage would be display of openness or closure.

[tamorgen]

@gwp1
It seems to me that something may be missing from this command class integration. I know iBlinds was working with the ZWaveJS developers. I'm curious is this is what they expected.

When I look at the entities in MSR, there are a lot of entries that have null or unknown. I'm not sure if this is coming from MSR expecting entries in a specific command class or if ZWaveJs is providing it to MSR that way.

Of all the actions in that window, the cover.xxx don't seem to work for me. The only ones that seem to work are:

x_hass_cover.close_cover_tilt
x_hass_cover.open_cover_tilt
x_hass_cover.set_cover_tilt_position

Others may work, but these are the one's I know specifically work and from an automation perspective meet my needs.

[gwp1]

@tamorgen Yep. The ZWaveJSController from @toggledbits was doing the same and that's what started this thread. He reviewed data from my system and updated his ZWaveJSController to work with those command classes.

[tamorgen]

Okay, gotcha. I did verify that the cover.open using the zwavejscontroller entries does work on my system with the updated release. Curious that the cover.open command does not work using the equivalent haas entity.

[gwp1]

@tamorgen cover.open and cover.close worked for me intermittently, eventually degrading to not at all. position.set seems to be working pretty well.

[gwp1]

@toggledbits I conducted some tests tonight.

MSR's ZWaveJSController
HA ZWaveJSController Plugin via HA

Sending you an image since I can't upload any here for some reason (parser error) via your file share.

Results of my tests:
MSR's ZWJSC: no response
HA ZWJSC plugin: no response from any command other than x_hass_cover.set_cover_tilt_position

[latest-23242]2023-09-17T23:34:45.311Z <Rule:INFO> The Internet is up (rule-l2gdh04j in Notifications) evaluation complete
[latest-23242]2023-09-17T23:34:45.312Z <Rule:INFO> Check Internet Status (rule-l2f9yuad in Reactor Alerts and Checks) evaluation complete
[latest-23242]2023-09-17T23:34:45.313Z <Rule:INFO> HTTP Failed Alerts (rule-l2kd6w6k in Reactor Alerts and Checks) evaluation complete
[latest-23242]2023-09-17T23:34:45.313Z <Rule:INFO> MSR Update Notifier (rule-l9vf6nai in Reactor Alerts and Checks) evaluation complete
[latest-23242]2023-09-17T23:34:45.316Z <Rule:INFO> The Internet is up (rule-l2gdh04j in Notifications) starting evaluation; because entity-changed System#reactor_system>system
[latest-23242]2023-09-17T23:34:45.317Z <Rule:INFO> Check Internet Status (rule-l2f9yuad in Reactor Alerts and Checks) starting evaluation; because entity-changed System#reactor_system>system
[latest-23242]2023-09-17T23:34:45.317Z <Rule:INFO> HTTP Failed Alerts (rule-l2kd6w6k in Reactor Alerts and Checks) starting evaluation; because entity-changed System#reactor_system>system
[latest-23242]2023-09-17T23:34:45.317Z <Rule:INFO> MSR Update Notifier (rule-l9vf6nai in Reactor Alerts and Checks) starting evaluation; because entity-changed System#reactor_system>system
[latest-23242]2023-09-17T23:34:45.318Z <Rule:INFO> The Internet is up (rule-l2gdh04j in Notifications) trigger evaluation result is false (previously false)
[latest-23242]2023-09-17T23:34:45.318Z <Rule:INFO> The Internet is up (rule-l2gdh04j in Notifications) evaluated; trigger state unchanged (false); rule state remains RESET
[latest-23242]2023-09-17T23:34:45.320Z <Rule:INFO> Check Internet Status (rule-l2f9yuad in Reactor Alerts and Checks) trigger evaluation result is false (previously false)
[latest-23242]2023-09-17T23:34:45.320Z <Rule:INFO> Check Internet Status (rule-l2f9yuad in Reactor Alerts and Checks) evaluated; trigger state unchanged (false); rule state remains RESET
[latest-23242]2023-09-17T23:34:45.321Z <Rule:INFO> HTTP Failed Alerts (rule-l2kd6w6k in Reactor Alerts and Checks) trigger evaluation result is false (previously false)
[latest-23242]2023-09-17T23:34:45.321Z <Rule:INFO> HTTP Failed Alerts (rule-l2kd6w6k in Reactor Alerts and Checks) evaluated; trigger state unchanged (false); rule state remains RESET
[latest-23242]2023-09-17T23:34:45.322Z <Rule:INFO> MSR Update Notifier (rule-l9vf6nai in Reactor Alerts and Checks) trigger evaluation result is false (previously false)
[latest-23242]2023-09-17T23:34:45.323Z <Rule:INFO> MSR Update Notifier (rule-l9vf6nai in Reactor Alerts and Checks) evaluated; trigger state unchanged (false); rule state remains RESET
[latest-23242]2023-09-17T23:34:45.323Z <Rule:INFO> The Internet is up (rule-l2gdh04j in Notifications) evaluation complete
[latest-23242]2023-09-17T23:34:45.323Z <Rule:INFO> Check Internet Status (rule-l2f9yuad in Reactor Alerts and Checks) evaluation complete
[latest-23242]2023-09-17T23:34:45.324Z <Rule:INFO> HTTP Failed Alerts (rule-l2kd6w6k in Reactor Alerts and Checks) evaluation complete
[latest-23242]2023-09-17T23:34:45.324Z <Rule:INFO> MSR Update Notifier (rule-l9vf6nai in Reactor Alerts and Checks) evaluation complete
[latest-23242]2023-09-17T23:34:45.346Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) starting evaluation; because entity-changed ValueSensor#hass>sensor_john_iphone_battery_status
[latest-23242]2023-09-17T23:34:45.349Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) trigger evaluation result is false (previously false)
[latest-23242]2023-09-17T23:34:45.349Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) evaluated; trigger state unchanged (false); rule state remains RESET
[latest-23242]2023-09-17T23:34:45.350Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) evaluation complete
[latest-23242]2023-09-17T23:34:50.341Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) starting evaluation; because entity-changed ValueSensor#hass>sensor_john_iphone_battery_status
[latest-23242]2023-09-17T23:34:50.347Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) trigger evaluation result is false (previously false)
[latest-23242]2023-09-17T23:34:50.348Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) evaluated; trigger state unchanged (false); rule state remains RESET
[latest-23242]2023-09-17T23:34:50.349Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) evaluation complete
[latest-23242]2023-09-17T23:34:51.559Z <HubitatController:INFO> HubitatController#hubitat ignoring DEVICE event for device 1405 (Sun Position); device not enumerated by Maker API
[latest-23242]2023-09-17T23:34:51.600Z <HubitatController:INFO> HubitatController#hubitat ignoring DEVICE event for device 1405 (Sun Position); device not enumerated by Maker API
[latest-23242]2023-09-17T23:34:51.662Z <HubitatController:INFO> HubitatController#hubitat ignoring DEVICE event for device 1405 (Sun Position); device not enumerated by Maker API
[latest-23242]2023-09-17T23:34:55.352Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) starting evaluation; because entity-changed ValueSensor#hass>sensor_john_iphone_battery_status
[latest-23242]2023-09-17T23:34:55.357Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) trigger evaluation result is false (previously false)
[latest-23242]2023-09-17T23:34:55.357Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) evaluated; trigger state unchanged (false); rule state remains RESET
[latest-23242]2023-09-17T23:34:55.359Z <Rule:INFO> Change to Night (via ESPresense) (rule-kwc6rmci in Mode Triggers) evaluation complete
[latest-23242]2023-09-17T23:34:55.426Z <Engine:INFO> Enqueueing "Blinds DR Open" (re-kx6a2k13)
**[latest-23242]2023-09-17T23:34:55.429Z <Engine:NOTICE> Starting reaction Blinds DR Open (re-kx6a2k13)**
[latest-23242]2023-09-17T23:34:56.685Z <ZWaveJSController:5:ZWaveJSController.js:798> ZWaveJSController#zwavejs handling node event statistics updated entity Cover#zwavejs>22-0
[latest-23242]2023-09-17T23:34:56.767Z <ZWaveJSController:5:ZWaveJSController.js:798> ZWaveJSController#zwavejs handling node event value updated entity Cover#zwavejs>22-0
[latest-23242]2023-09-17T23:34:56.769Z <ZWaveJSController:5:ZWaveJSController.js:994> ZWaveJSController#zwavejs update node 22 value "0:106:targetValue:23" data [Object]{ "source": "node", "event": "value updated", "nodeId": 22, "args": { "commandClassName": "Window Covering", "commandClass": 106, "endpoint": 0, "property": "targetValue", "propertyKey": 23, "newValue": 50, "prevValue": 0, "propertyName": "targetValue", "propertyKeyName": "Horizontal Slats Angle" } }
[latest-23242]2023-09-17T23:34:56.770Z <ZWaveJSController:5:ZWaveJSController.js:1006> ZWaveJSController#zwavejs updating attributes for node 22 value "0:106:targetValue:23"=50: [Array][  ]
[latest-23242]2023-09-17T23:34:56.771Z <ZWaveJSController:5:ZWaveJSController.js:1025> ZWaveJSController#zwavejs setting Cover#zwavejs>22-0.x_zwave_values.Window_Covering_targetValue_23 to 50
[latest-23242]2023-09-17T23:34:56.782Z <ZWaveJSController:5:ZWaveJSController.js:798> ZWaveJSController#zwavejs handling node event value updated entity Cover#zwavejs>22-0
[latest-23242]2023-09-17T23:34:56.783Z <ZWaveJSController:5:ZWaveJSController.js:994> ZWaveJSController#zwavejs update node 22 value "0:106:currentValue:23" data [Object]{ "source": "node", "event": "value updated", "nodeId": 22, "args": { "commandClassName": "Window Covering", "commandClass": 106, "property": "currentValue", "propertyKey": 23, "endpoint": 0, "newValue": 50, "prevValue": 0, "propertyName": "currentValue", "propertyKeyName": "Horizontal Slats Angle" } }
[latest-23242]2023-09-17T23:34:56.785Z <ZWaveJSController:5:ZWaveJSController.js:1006> ZWaveJSController#zwavejs updating attributes for node 22 value "0:106:currentValue:23"=50: [Array][ "cover.state", "position.value" ]
[latest-23242]2023-09-17T23:34:56.787Z <ZWaveJSController:5:ZWaveJSController.js:1013> ZWaveJSController#zwavejs updating attribute cover.state with [Object]{ "entity": "22-0", "impl": { "expr": "!(value == 0 || value >= 99)", "valueId": "106:currentValue:23" } }
[latest-23242]2023-09-17T23:34:56.787Z <ZWaveJSController:5:ZWaveJSController.js:947> ZWaveJSController#zwavejs _apply_value entity Cover#zwavejs>22-0 cover.state
[latest-23242]2023-09-17T23:34:56.788Z <ZWaveJSController:5:ZWaveJSController.js:987> ZWaveJSController#zwavejs _apply_value cover.state=true
[latest-23242]2023-09-17T23:34:56.789Z <ZWaveJSController:5:ZWaveJSController.js:1013> ZWaveJSController#zwavejs updating attribute position.value with [Object]{ "entity": "22-0", "impl": { "expr": "round( value / 99, 2 )", "valueId": "106:currentValue:23" } }
[latest-23242]2023-09-17T23:34:56.790Z <ZWaveJSController:5:ZWaveJSController.js:947> ZWaveJSController#zwavejs _apply_value entity Cover#zwavejs>22-0 position.value
[latest-23242]2023-09-17T23:34:56.791Z <ZWaveJSController:5:ZWaveJSController.js:987> ZWaveJSController#zwavejs _apply_value position.value=0.51
[latest-23242]2023-09-17T23:34:56.792Z <ZWaveJSController:5:ZWaveJSController.js:1025> ZWaveJSController#zwavejs setting Cover#zwavejs>22-0.x_zwave_values.Window_Covering_currentValue_23 to 50
[latest-23242]2023-09-17T23:34:56.807Z <HassController:INFO> HassController#hass action x_hass_cover.set_cover_tilt_position([Object]{ "tilt_position": 50 }) on Cover#hass>cover_dining_room2_horizontal_slats_angle succeeded
[latest-23242]2023-09-17T23:34:56.809Z <Engine:INFO> Resuming reaction Blinds DR Open (re-kx6a2k13) from step 1
[latest-23242]2023-09-17T23:34:56.810Z <Engine:NOTICE> Blinds DR Open delaying until 1694993697809<9/17/2023, 7:34:57 PM>
[latest-23242]2023-09-17T23:34:56.930Z <ZWaveJSController:5:ZWaveJSController.js:798> ZWaveJSController#zwavejs handling node event statistics updated entity Cover#zwavejs>22-0
[latest-23242]2023-09-17T23:34:57.811Z <Engine:INFO> Resuming reaction Blinds DR Open (re-kx6a2k13) from step 2
[latest-23242]2023-09-17T23:34:57.812Z <ZWaveJSController:INFO> ZWaveJSController#zwavejs performing zwave_device.refresh on Cover#zwavejs>7-0 with [Object]{  }
[latest-23242]2023-09-17T23:34:57.814Z <ZWaveJSController:5:ZWaveJSController.js:1750> ZWaveJSController#zwavejs no implementation mapped; attempting default
[latest-23242]2023-09-17T23:34:57.815Z <ZWaveJSController:5:ZWaveJSController.js:751> ZWaveJSController#zwavejs sending #1694993697815<9/17/2023, 7:34:57 PM>: [Object]{ "command": "node.refresh_values", "nodeId": 7, "messageId": 1694993697815 }
[latest-23242]2023-09-17T23:34:57.830Z <ZWaveJSController:5:ZWaveJSController.js:778> ZWaveJSController#zwavejs command result error: [Object]{ "type": "result", "success": false, "messageId": 1694993697815, "errorCode": "node_not_found", "args": { "nodeId": 7 } }
[latest-23242]2023-09-17T23:34:57.832Z <ZWaveJSController:ERR> ZWaveJSController#zwavejs request 1694993697815<9/17/2023, 7:34:57 PM> (node.refresh_values) failed: [Error] node_not_found [-]
[latest-23242]2023-09-17T23:34:57.834Z <Engine:INFO> Resuming reaction Blinds DR Open (re-kx6a2k13) from step 3
[latest-23242]2023-09-17T23:34:57.835Z <Engine:INFO> Blinds DR Open all actions completed.

If you'd like the whole log I can upload that as well or anything else you need for review.

[toggledbits]

Yeah, a clean log would be good to review. In your logging.yaml, please add the following (if there's already a section for ZWaveJSController, just modify it to make it look like this):

  ZWaveJSController:
    level: 5
    streams:
      - type: file
        name: zwavejs.log
        keep: 2
        level: 999
        recycle: true

Restart Reactor and run a test (or a few) of cover.open and cover.close and position.set. Then copy just the zwavejs.log file up together with your latest zwavejs_devices_initial.json to link I sent you earlier. Thank you!

[gwp1]

@toggledbits I actually had that logging enabled already and didn't realize/remember it.

Scenarios tested:

Test #1: 22-0 ~10:20 ET
ZWJSC, cover.close
Result: blind closed, "inside down"
ZWJSC, cover.open
Result: blind opened, position=50%
Repeated, same results

Test #2: 22-0
ZWJSC, position.set = 0
Result: blind closed, "inside up"
ZWJSC, position.set = .5
Result: blind opened, position=50%
Repeated, same results

Test #3: 22-0
ZWJSC, cover.close
Result: blind closed, "inside down"
ZWJSC, cover.open
Result: blind opened, position=50%
Repeated, same results

Unfortunately, everything WAI. Kinda frustrated at the moment. Same blind as last night's testing when nothing responded. Log being uploaded momentarily.

[tamorgen]

@gwp1 ,
Not to steal your thread, but out of curiosity, have you noticed any issues with battery life lately? I've noticed that a few of my iBlinds are draining very rapidly, ending in a unresponsive state. I've charged them, then less than a week later, they are dead and unresponsive.

They all appear to be the older v2 iBlinds, so it could just be that the batteries are ending their useful life, however, it's also possible that the new configuration and command class is affecting them in some way. I'm gradually replacing them, as I've found the v3.1 iBlinds to be much more reliable. I haven't noticed this behavior in the v3.1 iBlinds.

I'm just curious if you've seen this as well. If you are seeing it, it could be an issue with how ZWaveJS is communicating with the iBlinds due to this new roleout.

[gwp1]

@tamorgen Not entirely stealing or off-topic as that has played a role before in iblind responsiveness. I have solar panels on mine - 50/50 on how well they work. My blinds are all v3.1, btw, @toggledbits, just as a point of reference.

I have found if ZWaveJS HA Plugin shows anything under 60% battery the blinds become unresponsive. A quick plug of the power cable and a tap of the ping button and the physical button on the blind itself and they come back.

For this specific topic, all tested blinds are either plugged in or showing 100% battery.

[tamorgen]

@gwp1,
Thanks, I figured it couldn't hurt to ask someone else running HA to see if they were experiencing the same problems. I think it just might be old batteries.

[tamorgen]

@gwp1,
I'm working on trying to make something useful in the dashboards using this new device class, and it's not going well. I've reached out to iBlinds/Eric B for help. What we are both seeing as unknown/null states could be a problem.

There are three values that are null or unknown: cover.state, position.value, and then a repeat using the x_hass.state.

When I go into Developer Tools in HA, the state is unknown as well. That makes sense since MSR has to grab it from somewhere.
I'm trying to work on my dashboard and display some useful information, and I'm unable to get the value of the attributes. I'm guessing that's because the State is unknown.

Hopefully iBlinds can work with HA and ZWaveJS and get these values to show properly.

[toggledbits]

Yeah, logs confirm everything going out as expected, ZWJS acknowledging the request, and also sending a notification for a value change asynchronously. ZWaveJSController appears to be doing the right things. ZWaveJS appears to be doing its part as well.

[gwp1]

@toggledbits I'm conducting an experiment.

DR2 blind (22-0) is configured as [Default] direction and using MSRs ZWJSController. I have set the three Reactions to:

DR OPEN: cover.open
DR CLOSE: cover.close
DR @25: position.set = .75 (resulting in the blind being tilted open 25%, "top inside").

All other iblinds are using HA's ZWJSC plugin and all ran flawlessly for the past 24 hours. I'm wondering if this issue is somehow load-induced. If this single blind remains solid I will add a second and so on.

UPDATE: as my patience level on any given day runs the gamut between saint and toddler I've added KITCHEN (18-0) to this test with the same settings as above excepting .80 instead of .75. Both of these iblinds were the most impacted in being unresponsive.

[gwp1]

@toggledbits we have fail!

Added two more iblinds to the test. They failed to respond so I isolated one of them and did the following tests on node 8-0:

LR Left OPEN: cover.open
LR Left CLOSE: cover.close
LR Left @20: position.set = .80 (resulting in the blind being tilted open 20%, "top inside").

Results:
cover.open = iblind opens, position of 50
cover.close = iblind moves to position of 50 <---expected result, position 0
position.set = iblind moves to position of 80

As this Reaction was to run BOTH left and right blinds I removed the right blind and ran the left solo as the kitchen and DR are (both still working fine, btw). For reference, Kitchen was existing unit and was re-interviewed to be current, DR is a brand new unit. LR's are existing units re-interviewed.

Log forthcoming.

[tamorgen]

@gwp1, I just replaced 6 of my ib2 motors with ib3.1, and they are working just fine (12 of 14 are v3.1). I can control them from MSR with ZWJSController without issue.

My only issue is actually Home Assistant itself. I've had to go to great lengths to get a somewhat working card for each of the blinds in my home, and I'm still not completely there yet. I'm hoping either the ZWaveJS devlopers or the HA developers figure out whats going on with the 'state' value for each of these, as they all return 'unknown', so none of the cards work out of the box.

[gwp1]

@tamorgen said in [SOLVED-ish] New iblind and zwaveJScontroller/MSR not communicating:

they are working just fine (12 of 14 are v3.1). I can control them from MSR with ZWJSController without issue.

It's more helpful if, instead of generalized statements, you provide settings/configurations you're using whilst having this success for comparison sake.

I ran into an issue this morning wherein, after completing the weekly zwave_device.set_config, the iblinds finish in a closed position but reporting back in all systems (HA, both ZWJSC's, and MSR) as 50 or open. I've created a workaround wherein, after completing the config, they wait a few seconds then tilt 20% and then set back to open.

[tamorgen]

@gwp1 said in [SOLVED-ish] New iblind and zwaveJScontroller/MSR not communicating:

It's more helpful if, instead of generalized statements, you provide settings/configurations you're using whilst having this success for comparison sake.

All of mine are like this, cover.close, cover.open.

Perform cover.close on Office Blind Left (zwavejs>30-0)
Delay for 5 seconds
Perform cover.close on Office Blind Right (zwavejs>31-0)
Delay for 5 seconds
Perform zwave_device.refresh on Office Blind Left (zwavejs>30-0)
Perform zwave_device.refresh on Office Blind Right (zwavejs>31-0)

I'm not even sure the zwave.device.refresh is needed anymore. This was more of an issue when trying to force the old device class to refresh on both the binary switch and the multi-level switch, as they weren't directly connected and didn't update each other. It was one of the reasons that the folks at iBlinds were pushing to get the window device class implemented.

[gwp1]

@tamorgen I see you doing the delay as I do. How do you do a weekly config? (The blinds will "drift" if not config'd weekly or bi-weekly.

I have removed the zwave_device.refresh to see how that goes.

Question: you have a great number of blinds in your fleet as do I - how many blinds do you group together? I see you grouped two for your office blinds, do you have more than two at a time grouped in a single reaction? Or are you skipping reactions and adding each entity in each ruleset?

[tamorgen]

@gwp1 ,

I really don't have any sort of weekly config on mine. I haven't noticed mine drifting at all, but a weekly recalibrate wouldn't be a bad idea.

I have rules for hourly refreshing the the cover, position, and switch positions that are now pretty much legacy of the old device class. I only have two of the IB2 devices left, and they're in non critical rooms ( guest bedroom and mudroom). I'll replace those later this year. Once those are gone, I'll be able to remove those three hourly rules.

I have reactions set up for each set of windows in a room. Most rooms only have two windows, so I group those. The lone exception is my family room, where I have four windows, but on different walls. I have those set up on two separate reactions, which allows me to separately set the blinds to a partial open position when the sun is shining directly in. The largest events with the blinds are at sunrise, sunset, and when the house is vacant or someone arrives, when I'll set the reactions from the rule, and I have "Wait for completion" enabled after each call for a reaction. It takes a couple of minutes for all my blinds to open/close when it's called. I initially didn't do this, but iBlinds recommended not calling them all at once (thus the pause in between each call for a blind to open/close). For the most part, this has helped tremendously with the IB2 motors, although they still had their fair share of problems, which is why I've been replacing them all this year.

I call the reactions from rulesets, so I don't have to reinvent the wheel each time I want to perform an action on a window. It also allows me to make an adjustment once and not 10 different times in different rules.