Errors after updating to MQTTController build 25139

[gwp1]

@tamorgen I'm poking at it now. It seems for the iblinds I can now use cover.close and the [0] NO (default) setting for direction vs having it set to [1] YES to make it tilt the way I want it to when closed.

I was using power_switch.off previously to close my v3.1's.

[gwp1]

UPDATE: after many lost hours last night/this morning (hello, 0230 ET, looking at you) I found cover.x and power_switch.x to be woefully unreliable after reinterviewing the blinds.

What has proven reliable is position.set for which I use 0 for closed, 50 for open and have the blinds (as they were previously) set to [1] YES for Reverse Direction.

[tamorgen]

I've been using the ID hass>cover_blind_horizontal_slats_angle and x_hass_cover.open_cover_tilt or x_haas_cover.close_cover_tilt and it works flawlessly for open and close. If I want to set the position for partial close when the sun is glarring into the room, I use x_hass_cover.set_cover_tilt_position with the same ID, and set the tilt_position to whatever I want to reduce the glare.

My only problem now is that I use mushroom for my dashboard in Home Assistant, and the templates for covers hasn't caught up with the new Window Covering class. It makes adjusting the blinds more problematic from HA.

[toggledbits]

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

UPDATE: after many lost hours last night/this morning (hello, 0230 ET, looking at you) I found cover.x and power_switch.x to be woefully unreliable after reinterviewing the blinds.

Can you please be more descriptive here? I don't have these devices, so I can't test them, but that doesn't mean I'm not interested in getting them working reliably. But the description given isn't enough for me to do any troubleshooting at all.

[gwp1]

@toggledbits of course, no problem! I cannot seem to upload images into this response. I've uploaded to your Dropbox shared folder a pair of images.

Using the iblind I sent you logs and such for previously as the example, I have a blind called Beach Blind (two guest rooms, one is named Ocean, the other Beach). I use the commands from the ID Beach Blind from zwavejs. As @tamorgen notes, there is also a new one (with the changes HA made) from my HASS controller called Beach Blind Horizontal Slates Angle which has a few less commands including those mentioned starting with x_hass_*.

I use the ZwaveJSController commands.

cover.open
cover.close
power_switch.on
power_switch.off
position.set
zwave_device.refresh
zwave_device.set_config

These are the ones I most commonly use to open, close, and tilt the blinds. refresh is used to force HA to update dasheboards with any changes and set_config I use less often, weekly, to recalibrate the blinds as they do wander sometimes and "open" becomes "open-ish".

I'd been using power_switch.on to open and cover.close to close. The blinds have been happiest that way. With these new changes from HA, these commands have become hit/miss. In the HA ZWaveJS plugin the status would reflect 0/closed but the blind was clearly wide open.

Last night/this morning I opted to use position.set and it's been pretty solid from what I've seen throughout the day.
position.set = 0 nets me a closed blind, position.set = .5 nets me a wide open/"slates level with the ground" blind. I used position.set = .2 or .3 to tilt the sun away during specific dayparts based on sun azimuth.

I did not grab MSR logs during those tests - apologies, it was 0200 ET and I just wanted the blinds closed for the night. I have amassed 13 of these iblinds over the past couple years.

If you wish, I'll set some time tonight or tomorrow night and generate some MSR logs.

[tamorgen]

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

With these new changes from HA, these commands have become hit/miss.

To be precise, it's not so much a change from HA, but from ZWaveJS. They're recognizing a new command class that the developers at iBlinds actually recommend.

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.

if you go into the switch, it shows it as unavailable.

I would guess the power on/off cover.close / cover.open are remnants and probably need to be removed.

[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.