[BUG] Heater or Set Temp Changes Break nodejsPC

[matthewusf]

nodejs-poolController Version/commit

8.01

nodejs-poolController-dashPanel Version/commit

8.0.0

relayEquipmentManager Version/commit

No response

Node Version

No response

Platform

No response

RS485 Adapter

No response

Are you using Docker?

• Yes.

OCP

Intellicenter i5PS - 1.064

Pump(s)

No response

Chlorinator(s)

No response

What steps will reproduce the bug?

Occasionally, when you make any changes to the heater set point or heater mode, I get a communication timeout error, after which this module no longer works until nodejsPC is restarted (dsahPanel can stay running). Unfortunately (or fortunately), it only happens about once a week during normal use, so it's hard to capture a replay of it because I haven't noticed any patterns for when I might be causing it. It's not just if I turn the spa on and adjust features, for instance. It last happened with me simply changing the Set Temp while the heater wasn't even on.

What happens?

ApiError: Message aborted after 3 attempt(s): 165,1,15,33,168,41,0,0,18,1,0,0,0,0,0,0,0,0,0,0,176,89,27,110,3,0,0,88,100,94,100,1,1,0,0,15,0,1,0,100,0,0,0,0,0,0,0,5,67
at RS485Port.writeMessage (/home/admin/nodejs-poolController/controller/comms/Comms.ts:1010:31)
at RS485Port.processWaitPacket (/home/admin/nodejs-poolController/controller/comms/Comms.ts:946:22)
at RS485Port.processOutboundPackets (/home/admin/nodejs-poolController/controller/comms/Comms.ts:954:19)
at RS485Port.processPackets (/home/admin/nodejs-poolController/controller/comms/Comms.ts:936:14)
at Immediate._onImmediate (/home/admin/nodejs-poolController/controller/comms/Comms.ts:896:114)
at processImmediate (node:internal/timers:466:21)

What should have happened?

It doesn't change temp or heat mode, and then nodejs remains stuck where those things can no longer be changed. Everything else still works as normal.

Additional information

No response

[rstrouse]

Pull njsPC. I believe the throttling code to make sure njsPC does not process too many changes at once was getting stuck after IntelliCenter rejected the request.

[treyrich]

@rstrouse I'm running the current master and it appears that this is still occurring intermittently, once it breaks it seems to be broken until restarted, but what causes it to break still seems unclear to me. Next time it's happening I can try to capture some logs/replay, but right now it's working because I restarted it this morning before going searching for an issue.

[rstrouse]

It is important that I know which OCP you are using. EasyTouch, IntelliTouch, IntelliCenter, SunTouch, or Nixie. Also, please post a new issue when the issue is as old as this one. These will fall of the radar and get lost.

[treyrich]

Sorry, I assumed that it would be implied by commenting on an existing issue. I'm running IntelliCenter 1.064, same as OP. I'm also happy to open a separate issue if you want, I just figured that seeing as the issue is identical to this one it would make the most sense to start here. Let me know what is most helpful and I'm happy to do anything I can to help track this issue down.

[matthewusf]

I do also still have this issue occasionally, though it seems to be far more rare since it was addressed.

…

On Mon, Jun 17, 2024, 4:46 PM Trey Richards ***@***.***> wrote: Sorry, I assumed that it would be implied by commenting on an existing issue. I'm running IntelliCenter 1.064, same as OP. I'm also happy to open a separate issue if you want, I just figured that seeing as the issue is identical to this one it would make the most sense to start here. Let me know what is most helpful and I'm happy to do anything I can to help track this issue down. — Reply to this email directly, view it on GitHub <#848 (comment)>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/ACSGYRCLVE6FO67T5YP6JPLZH5DJ5AVCNFSM6AAAAABJOUQ63KVHI2DSMVQWIX3LMV43OSLTON2WKQ3PNVWWK3TUHMZDCNZUGM4TENZVHE> . You are receiving this because you modified the open/close state.Message ID: ***@***.***>

[treyrich]

replay.zip
Here's a capture of the issue happening. In this case I tried to set the heatmode unsuccessfully. This recently has seemed to be happening to me once every day or two necessitating a restart when my automations start failing.

I have zero familiarity with this codebase, so the learning curve would be steep, but if there's a desire for help with this issue I'm willing to help look into it @rstrouse, let me know if it's worth me diving into the code and starting to get familiar.

[tagyoureit]

Hi, this replay doesn't have the lower level logging that's needed to fully debug the issue. Can you capture the issue again following the directions in the wiki?

[treyrich]

The original capture was taken following the directions in the wiki, was there a particular step that was missed? Here's a new one that was captured including checking the box "Capture Configuration Reload", if this is incorrect please let me know what I'm missing and I'll happily reproduce, I'm sure it's my own issue, but other than checking this box I'm not sure what other steps I missed...
replay.zip

[tagyoureit]

The "Capture Configuration Reload" requests all the configuration items from the OCP so if you start the replay with that you should at least wait until it is done. Some messages in the queue failed because the OCP didn't respond to them because it was sending out the rest of the configuration.

I added a debug statement in the queueBodyHeadSettings function, but also added extra code to reset the bytes in the array. GIve it another try. If it still is unresponsive, we will have more debugging. If it's fixed, then yay.

[treyrich]

Got it.

I've just pulled the changes. Honestly we're outside of the heating window right now so it may be a couple months before this surfaces again in my system organically. If I get a chance I'll try to reproduce it though so that this doesn't get too stale.