NC-6 goes into set time mode

[nksoft]

Hi,

When I have the heater running for a while, the Brivis NC-6 Networker Controller on the wall asks me to set the time and I lose device control from this custom component. Not sure what happens 1st.

The logs are shown below.

2023-05-24 10:36:34.312 DEBUG (MainThread) [custom_components.rinnaitouch.climate] Internal temperature sensor reports: 999
2023-05-24 10:36:37.335 DEBUG (Thread-2 (poll_loop)) [custom_components.rinnaitouch.climate] Ext. temp sensor entity name: sensor.living_room_temperature_sensor
2023-05-24 10:36:37.335 DEBUG (Thread-2 (poll_loop)) [custom_components.rinnaitouch.climate] Ext. temp sensor reports: 20.8
2023-05-24 10:36:37.337 DEBUG (MainThread) [custom_components.rinnaitouch.climate] Internal temperature sensor reports: 999

2023-05-24 10:37:13.458 ERROR (Thread-3 (receiver)) [pyrinnaitouch.receiver] Socket timed out, renewing connection (TimeoutError('timed out'))
2023-05-24 10:37:13.724 ERROR (Thread-3 (receiver)) [pyrinnaitouch.receiver] Couldn't decode JSON (probably HELLO), skipping (AttributeError("'NoneType' object has no attribute 'group'"))
2023-05-24 10:37:14.424 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))

2023-05-24 10:37:34.368 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:37:35.368 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:37:37.368 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))

[funtastix]

How frequently does this happen? Does it happen when you deactivate the module in HA?

[nksoft]

Thank you very much for the quick reply. Really appreciate all your hard work on this.

I don't deactivate the module. I usually have it running always. It happens at least once a day (max twice a day).

[funtastix]

Thanks for getting back on this. What I meant is, if you can deactivate the integration in home assistant and see if the unit still behaves the same way, we can find it if it's something that's wrong with the unit or the software

[nksoft]

Sorry, I misunderstood. I disabled it under Settings > Devices & Services. Is that enough or do I need to uninstall from HACS? Just a note, I don't remember setting the time like this in the last 5yrs. I'll get back to you after a couple of days.

[funtastix]

That is enough. The next thing we'll need to know is the log where it happens. If it is the home assistant, then there should be a command it sends that causes this...

[nksoft]

I am using Home Assistant and the log is on the first message. Here it is again. I downloaded the full log and then took the part where the error occurred. Don't have the full log now, unfortunately.

2023-05-24 10:35:35.211 DEBUG (MainThread) [custom_components.rinnaitouch.climate] Internal temperature sensor reports: 999
2023-05-24 10:35:37.211 DEBUG (Thread-2 (poll_loop)) [custom_components.rinnaitouch.climate] Ext. temp sensor entity name: sensor.living_room_temperature_sensor
2023-05-24 10:35:37.211 DEBUG (Thread-2 (poll_loop)) [custom_components.rinnaitouch.climate] Ext. temp sensor reports: 20.8
2023-05-24 10:35:37.212 DEBUG (MainThread) [custom_components.rinnaitouch.climate] Internal temperature sensor reports: 999
2023-05-24 10:36:34.311 DEBUG (Thread-2 (poll_loop)) [custom_components.rinnaitouch.climate] Ext. temp sensor entity name: sensor.living_room_temperature_sensor
2023-05-24 10:36:34.311 DEBUG (Thread-2 (poll_loop)) [custom_components.rinnaitouch.climate] Ext. temp sensor reports: 20.8
2023-05-24 10:36:34.312 DEBUG (MainThread) [custom_components.rinnaitouch.climate] Internal temperature sensor reports: 999
2023-05-24 10:36:37.335 DEBUG (Thread-2 (poll_loop)) [custom_components.rinnaitouch.climate] Ext. temp sensor entity name: sensor.living_room_temperature_sensor
2023-05-24 10:36:37.335 DEBUG (Thread-2 (poll_loop)) [custom_components.rinnaitouch.climate] Ext. temp sensor reports: 20.8
2023-05-24 10:36:37.337 DEBUG (MainThread) [custom_components.rinnaitouch.climate] Internal temperature sensor reports: 999
2023-05-24 10:37:13.458 ERROR (Thread-3 (receiver)) [pyrinnaitouch.receiver] Socket timed out, renewing connection (TimeoutError('timed out'))
2023-05-24 10:37:13.724 ERROR (Thread-3 (receiver)) [pyrinnaitouch.receiver] Couldn't decode JSON (probably HELLO), skipping (AttributeError("'NoneType' object has no attribute 'group'"))
2023-05-24 10:37:14.424 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:37:34.368 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:37:35.368 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:37:37.368 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:37:39.378 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:38:35.471 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:39:34.583 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:40:34.685 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:41:34.798 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:42:34.910 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:43:35.012 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:44:35.124 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:45:35.226 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:46:34.338 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:47:34.441 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:48:34.553 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:49:35.666 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:50:35.768 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))
2023-05-24 10:51:34.880 ERROR (Thread-2 (poll_loop)) [pyrinnaitouch.system_status] Couldn't decode JSON (exception), skipping (UnknownModeException('Unknown mode, this is not going well.'))

[funtastix]

There are some bits missing I think, just before the log except that you sent. Weirdly, the actual JSON received doesn't seem to come through, but also nowhere in the log are any messages sent to the unit, so if the integration is responsible, then it must happen earlier that what I can see in the log

[nksoft]

That's all that was there. I enabled debugging through the menu I get on Settings > Devices & Services. Maybe I need to enable debugging through the configuration.yaml? and maybe go to info level logging?

[funtastix]

You can follow these instructions: https://github.com/funtastix/rinnaitouch#enable-debug

[nksoft]

I'll first wait for a couple of days to see if the Network controller goes into the set time mode again.

[nksoft]

Hey funtastix, it has been 2 days now since I disabled the add-on and the NC-6 didn't go into setting time mode. Now I added the debug code onto the configuration.yaml and re-enabled it. I'll report back if it fails and give you the logs. Thanks.

[nksoft]

Hi funtastix, hope you had a good weekend.

With the add-on enabled, the NC-6 controller did go into set time mode :(

I've attached the log file, here are my comments below

2023-05-26 21:39:07.767 recovers no need to set the time
2023-05-27 21:48:58.917 recovers no need to set the time
2023-05-27 21:48:58.917 recovers no need to set the time
2023-05-28 20:38:54.946 doesn't recover and had to set the time
2023-05-28 23:42:30.209 doesn't recover and had to set the time

home-assistant_2023-05-28T22-33-32.238Z2.log

[funtastix]

Very peculiar.

I don't think the integration does anything to enter that mode. I found this: https://community.home-assistant.io/t/rinnai-heating-cooling-wifi-module/109908/568 so theoretically it's possible, but none of the commands the integration sends matches that (entering cool mode comes closest, but it's still different). Also it appears it happens after losing connection and the recovery is always similar, the unit enters time setting mode.
From that same link I can see it could be possible to fix this case by specifically testing for this weird state and send a command to exit time setting mode. I may try that. It appears some units may do the same as yours.

[nksoft]

I was actually using Mantorok's homekit version before and found this and this is waaaay easier to set up and maintain so I switched.

Fingers crossed if you can fix it in a future release. As I mentioned it only started since few months from my memory so something in the new updated code since then might be the issue. In any case thank you very much for your time and efforts.

[sgraystar]

Hi guys, I have an NC-7 that has been in time setting mode for a couple of weeks. It is normally reliable so I suspect a power glitch and I am not there to manually set the time.

The rinnai app gives 'The System is initialising', the integration state is CONNECTED and the touch periodic response below:
N000000[{"SYST": {"CFG": {"MTSP": "N", "NC": "00", "DF": "N", "TU": "C", "CF": "1", "VR": "0183", "CV": "0010", "CC": "086", "ZA": " ", "ZB": " ", "ZC": " ", "ZD": " " }, "AVM": {"HG": "Y", "EC": "N", "CG": "N", "RA": "N", "RH": "N", "RC": "N" }, "OSS": {"DY": "MON", "TM": "12:00", "BP": "Y", "RG": "N", "ST": "C", "MD": "N", "DE": "N", "DU": "N", "AT": "999", "LO": "N" }, "FLT": {"AV": "N", "C3": "000" }, "STM": {"DY": "MON", "TM": "12:00", "SV": "N" } } }]

For some reason I have not yet worked out the set_datetime does not appear to be sending any commands to the touch. Any suggestions where to look?

I had a go at changing the idle message to be a time save command without success. May have been user error from me. Should this have worked?