[Help] TOS sleep/suspend/hibernate mode causing UPS notifications

[renob]

F4-425-Plus with TOS 7.0.0804 and BIOS MTWL0101.V04

UniFi UPS Tower with firmware 1.6.1

Every other day since 8/4/2026 TOS is coming out of a sleep/suspend/hibernate at 12 PM PST which is triggering UPS notifications as though there has been a loss of power event on the UPS when there has been none.

The firmware on the UPS was updated on 8/3/2026 and it is possible TNAS is now doing something weird with the output from the UPS.

https://community.ui.com/releases/UniFi ... a18d46595e

Code: Select all

Improvements
Added power and power factor reporting to the NUT server.
Improved NUT server status reporting by suppressing the charging state when the battery is fully charged.

Bugfixes
Fixed inconsistent battery current readings in power and voltage reports.

Code: Select all

"2026-08-08T12:00:10.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-08 12:00:10.199 [DEBU] ############### will insert msg:&{ID:46 Type:general_set TypeName:general_settings,general_settings Code:ups_notify_on_power_restored Level:info Snip: Detail:The UPS connected to device TNAS425 has entered AC power mode. Time:2026-08-08 12:00:06 SendMail:true Notified:false SendMobile:true SendSMS:false Username:root Mustmail:false Customize: Model: User: Jump:{Path: Action:}}"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-08 12:00:06.188 [DEBU] Insert A Message ######:&{ID:0 Type:general_set TypeName:general_settings,general_settings Code:ups_notify_on_power_restored Level:info Snip: Detail:The UPS connected to device TNAS425 has entered AC power mode. Time:2026-08-08 12:00:06 SendMail:true Notified:false SendMobile:true SendSMS:false Username:root Mustmail:false Customize: Model: User: Jump:{Path: Action:}}"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: OS just finished sleep/suspend/hibernate mode, de-activating obsolete UPS readings to avoid an unfortunate shutdown"
"2026-08-06T12:00:11.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-06 12:00:11.843 [DEBU] ############### will insert msg:&{ID:45 Type:general_set TypeName:general_settings,general_settings Code:ups_notify_on_power_restored Level:info Snip: Detail:The UPS connected to device TNAS425 has entered AC power mode. Time:2026-08-06 12:00:07 SendMail:true Notified:false SendMobile:true SendSMS:false Username:root Mustmail:false Customize: Model: User: Jump:{Path: Action:}}"
"2026-08-06T12:00:07.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-06 12:00:07.507 [DEBU] Insert A Message ######:&{ID:0 Type:general_set TypeName:general_settings,general_settings Code:ups_notify_on_power_restored Level:info Snip: Detail:The UPS connected to device TNAS425 has entered AC power mode. Time:2026-08-06 12:00:07 SendMail:true Notified:false SendMobile:true SendSMS:false Username:root Mustmail:false Customize: Model: User: Jump:{Path: Action:}}"
"2026-08-06T12:00:02.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: OS just finished sleep/suspend/hibernate mode, de-activating obsolete UPS readings to avoid an unfortunate shutdown"
"2026-08-04T12:00:08.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-04 12:00:08.734 [DEBU] ############### will insert msg:&{ID:44 Type:general_set TypeName:general_settings,general_settings Code:ups_notify_on_power_restored Level:info Snip: Detail:The UPS connected to device TNAS425 has entered AC power mode. Time:2026-08-04 12:00:04 SendMail:true Notified:false SendMobile:true SendSMS:false Username:root Mustmail:false Customize: Model: User: Jump:{Path: Action:}}"
"2026-08-04T12:00:04.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-04 12:00:04.970 [DEBU] Insert A Message ######:&{ID:0 Type:general_set TypeName:general_settings,general_settings Code:ups_notify_on_power_restored Level:info Snip: Detail:The UPS connected to device TNAS425 has entered AC power mode. Time:2026-08-04 12:00:04 SendMail:true Notified:false SendMobile:true SendSMS:false Username:root Mustmail:false Customize: Model: User: Jump:{Path: Action:}}"
"2026-08-04T12:00:00.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: OS just finished sleep/suspend/hibernate mode, de-activating obsolete UPS readings to avoid an unfortunate shutdown"

Code: Select all

# upsc [email protected].:3493
Init SSL without certificate database
Connecting in SSL to '192.168.' (no certificate name specified)
Do not intend to authenticate server 192.168.
SSL handshake done successfully with server 192.168.
Connected to NUT server 192.168. in SSL
Certificate verification is disabled
battery.charge: 100
battery.low: 9.67
battery.voltage: 13.7
battery.runtime: 1018
input.voltage: 118.2
input.frequency: 60.0
input.voltage.nominal: 120.0
input.transfer.high: 145.0
input.transfer.low: 89.0
output.voltage: 118.2
output.current: 1.20
ups.realpower: 162.0
ups.realpower.nominal: 600.0
ups.power: 141.8
ups.power.nominal: 1000
output.powerfactor: 1.00
output.frequency: 60.0
ups.load: 27
ups.status: OL
ups.model: TOWER_1000VA_120V
ups.serial:
ups.mfr: Ubiquiti
ups.id: ups
ups.type: line-interactive
ups.test.result: done and passed
ups.test.date: 2026-08-04
ups.test.interval: 604800
ups.temperature: 25.0

[renob]

A lot seems to go on all at once at 12 PM when this happens. Here is the full log from 12:00:00 PM to 12:01:00 PM today.

Code: Select all

"2026-08-08T12:00:10.000-07:00","TNAS425","TNAS425 nut-monitor[1660090]: Warning: no shutdown command defined!"
"2026-08-08T12:00:10.000-07:00","TNAS425","TNAS425 nut-monitor[1660092]: 2026-08-08 12:00:10.224 [INFO] main.go:152: -------------------------------------------------"
"2026-08-08T12:00:10.000-07:00","TNAS425","TNAS425 nut-monitor[1660092]: 2026-08-08 12:00:10.223 [INFO] upsmsgproc.go:32: the power is restored"
"2026-08-08T12:00:10.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-08 12:00:10.199 [DEBU] ############### will insert msg:&{ID:46 Type:general_set TypeName:general_settings,general_settings Code:ups_notify_on_power_restored Level:info Snip: Detail:The UPS connected to device TNAS425 has entered AC power mode. Time:2026-08-08 12:00:06 SendMail:true Notified:false SendMobile:true SendSMS:false Username:root Mustmail:false Customize: Model: User: Jump:{Path: Action:}}"
"2026-08-08T12:00:10.000-07:00","TNAS425","TNAS425 TOSDaemon[17195]: metrics persisted topic=disk expected=1248 actual=1248"
"2026-08-08T12:00:10.000-07:00","TNAS425","TNAS425 TOSDaemon[17195]: metrics persisted topic=net expected=1248 actual=1248"
"2026-08-08T12:00:10.000-07:00","TNAS425","TNAS425 TOSDaemon[17195]: metrics persisted topic=mem expected=1248 actual=1248"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-08 12:00:06.249 [INFO] SendMail now..."
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 msgcenter[17221]: 2026-08-08 12:00:06.188 [DEBU] Insert A Message ######:&{ID:0 Type:general_set TypeName:general_settings,general_settings Code:ups_notify_on_power_restored Level:info Snip: Detail:The UPS connected to device TNAS425 has entered AC power mode. Time:2026-08-08 12:00:06 SendMail:true Notified:false SendMobile:true SendSMS:false Username:root Mustmail:false Customize: Model: User: Jump:{Path: Action:}}"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: Do not intend to authenticate server 192.168."
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: Connecting in SSL to '192.168.' (no certificate name specified)"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 nut-monitor[1660080]: Warning: no shutdown command defined!"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: Applying debug level 0 from original command line arguments"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: Reloading configuration"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: upsmon: Processing OS wake-up after sleep"
"2026-08-08T12:00:06.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: OS just finished sleep/suspend/hibernate mode, de-activating obsolete UPS readings to avoid an unfortunate shutdown"
"2026-08-08T12:00:05.000-07:00","TNAS425","TNAS425 systemd[1]: user-task-on-shutdown.service: Consumed 1.524s CPU time."
"2026-08-08T12:00:05.000-07:00","TNAS425","TNAS425 systemd[1]: Finished User Tasks On Shutdown."
"2026-08-08T12:00:05.000-07:00","TNAS425","TNAS425 systemd[1]: user-task-on-shutdown.service: Deactivated successfully."
"2026-08-08T12:00:05.000-07:00","TNAS425","TNAS425 TOSDaemon[17195]: metrics persisted topic=load expected=1248 actual=1248"
"2026-08-08T12:00:05.000-07:00","TNAS425","TNAS425 systemd-resolved[14528]: Clock change detected. Flushing caches."
"2026-08-08T12:00:05.000-07:00","TNAS425","TNAS425 TOSDaemon[17195]: 2026/08/08 12:00:05 #033[31;1m/root/go/pkg/mod/gitlab.local/!terra!master/[email protected]/src/resource/class.go:427 #033[35;1mtoo many SQL variables"
"2026-08-08T12:00:05.000-07:00","TNAS425","TNAS425 TOSDaemon[17195]: #015"
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 systemd[1]: Finished Rotate log files."
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 systemd[1]: logrotate.service: Deactivated successfully."
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 systemd[1]: Starting User Tasks On Shutdown..."
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 systemd[1]: Starting Rotate log files..."
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 nut-monitor[1660092]: 2026-08-08 12:00:02.408 [INFO] main.go:76: run upsctl arg --> :  online"
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 nut-monitor[1660092]: 2026-08-08 12:00:02.408 [INFO] main.go:72: first admin user:"
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 nut-monitor[1660092]: 2026-08-08 12:00:02.404 [INFO] main.go:69: -------------------------------------------------"
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 filemanager[16910]: [DEBUG /bin/bash -c ntpdate -b us.pool.ntp.org]"
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 twm_authentication[17217]: [DEBUG /bin/bash -c ntpdate -b us.pool.ntp.org]"
"2026-08-08T12:00:02.000-07:00","TNAS425","TNAS425 storagemanager[17193]: DEBUG /bin/bash -c ntpdate -b us.pool.ntp.org"
"2026-08-08T12:00:01.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: Certificate verification is disabled"
"2026-08-08T12:00:01.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: Connected to NUT server 192.168. in SSL"
"2026-08-08T12:00:01.000-07:00","TNAS425","TNAS425 nut-monitor[17914]: SSL handshake done successfully with server 192.168."
"2026-08-08T12:00:01.000-07:00","TNAS425","TNAS425 systemd-resolved[14528]: Clock change detected. Flushing caches."

[TMzethar]

Hi,
Regarding the UPS notification issue you mentioned, the content posted on the TerraMaster forum and on UniFi appears to differ somewhat.

Leaving aside factors such as the UPS itself, one possible speculation is that it may be related to a known "clock change / NTP synchronization" issue in the current TOS 7 version.
viewtopic.php?t=10388&start=70
The exact connection would require further confirmation. You may want to keep an eye on the release notes for future TOS 7 updates.

If you have time, you could also try manually putting the NAS into sleep mode and then waking it up, to see if a similar UPS notification appears.

[Gremlin]

UPS notification and NTP issues may well be related, but I think it predates recent TOS7 updates.

[EriChan]

Thank you for your feedback. If you encounter the same issue, please provide the complete logs to help us troubleshoot.

[renob]

Hi,
Regarding the UPS notification issue you mentioned, the content posted on the TerraMaster forum and on UniFi appears to differ somewhat.

Leaving aside factors such as the UPS itself, one possible speculation is that it may be related to a known "clock change / NTP synchronization" issue in the current TOS 7 version.
viewtopic.php?t=10388&start=70
The exact connection would require further confirmation. You may want to keep an eye on the release notes for future TOS 7 updates.

If you have time, you could also try manually putting the NAS into sleep mode and then waking it up, to see if a similar UPS notification appears.

I don't believe what was posted is different in any meaninful way. The only difference is the removal of a duplicate line about the notification message which adds no value. That post was born of laziness before I decided to actually go look at my Graylog server which collects logs from everything in my network.

There is no logging from the UPS or other protected devices on the UPS to indicate anything has happened on the UPS. I am merely waiting for Ubiquiti to confirm what I already know at this point that it's not a problem with the UPS. There are also unprotected devices connected to the UPS which are not powering off during these events. I hope we're not sincerely thinking this is a problem with the UPS at this point when the notifications happen only when and every time the NAS is "de-activating obsolete UPS readings to avoid an unfortunate shutdown". If anything it may simply be an issue with how the NAS is parsing the information from the UPS which did change in some small ways adding additional information and removing the charging status if it is no longer charging... or some other bug like the NTP items you have mentioned.

I do not know how to put the NAS into sleep mode. I can't find any settings for this in the configuration pages and there is no documentation I can find on your website or the F4-425 Plus manual. I don't know why it is even doing this every 48 hours at 12 PM to begin with and assume it's just some normally scheduled operation on your NAS.

Edit:
The closest thing I could find was a scheduled shutdown and startup which reproduced the same behavior and logs.

The full logs in CSV format:
https://pastebin.com/g4yQRYLd

[EriChan]

We appreciate the logs and your detailed explanation. We will keep tracking this matter and try to replicate the problem you described.