I’d would be great if you could collect some logs to help identify the source of this problem. Unfortunately the next planned refresh time is only logged when debug level logging is enabled. You can enable it executing the following commands as root:
Messages like those below will start to be logged:
$ journalctl -u snapd.service |grep autorefresh.go
Feb 05 07:15:30 galeon snapd[56845]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-05T07:15:30+01:00.
Feb 05 07:22:37 galeon snapd[57248]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-05T12:47:03+01:00.
Feb 05 12:52:07 galeon snapd[57248]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 05 12:57:02 galeon snapd[57248]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-05T23:41:35+01:00.
Feb 05 12:22:39 Books snapd[992]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 05 13:16:18 Books snapd[22552]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-05T21:00:00-05:00.
Thursday 3:22 PM
$ snap refresh --time
timer: 21:00-22:00
last: today at 09:49 EST
next: today at 21:00 EST
Friday 9:33 PM
$ snap refresh --time
timer: 21:00-22:00
last: today at 10:33 EST
next: today at 21:00 EST
Saturday 9:39 AM
$ snap refresh --time
timer: 21:00-22:00
last: yesterday at 10:33 EST
next: yesterday at 21:00 EST
Saturday 6:10 PM
$ snap refresh --time
timer: 21:00-22:00
last: today at 11:03 EST
next: today at 21:00 EST
Sunday 7:04 AM
$ snap refresh --time
timer: 21:00-22:00
last: today at 06:25 EST
next: today at 21:00 EST
$ journalctl -u snapd.service |grep autorefresh.go | tail -n 10
Feb 05 12:22:39 Books snapd[992]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 05 13:16:18 Books snapd[22552]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-05T21:00:00-05:00.
Feb 06 09:54:28 Books snapd[22552]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-06T21:00:00-05:00.
Feb 07 10:33:01 Books snapd[22552]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 07 11:14:35 Books snapd[22552]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-07T21:00:00-05:00.
Feb 08 11:03:54 Books snapd[22552]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 08 11:08:51 Books snapd[22552]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-08T21:00:00-05:00.
Feb 08 18:30:26 Books snapd[1004]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-08T21:00:00-05:00.
Feb 09 06:25:12 Books snapd[1004]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 09 06:30:09 Books snapd[1004]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-09T21:00:00-05:00.
Here’s the latest data starting just after the last post.
Monday 6:27 AM
$ snap refresh --time
timer: 21:00-22:00
last: yesterday at 06:25 EST
next: yesterday at 21:00 EST
Monday 3:02 PM
$ snap refresh --time
timer: 21:00-22:00
last: today at 11:33 EST
next: today at 21:00 EST
Tuesday 9:29 AM
$ snap refresh --time
timer: 21:00-22:00
last: today at 08:48
next: today at 21:00 EST
Wednesday 11:01 AM
$ snap refresh --time
timer: 21:00-22:00
last: yesterday at 21:22 EST
next: today at 21:00 EST
Thursday 5:33 AM
$ snap refresh --time
timer: 21:00-22:00
last: yesterday at 23:54 EST
next: today at 21:00 EST
Thursday 3:37 PM
$ snap refresh --time
timer: 21:00-22:00
last: yesterday at 23:54 EST
next: today at 21:00 EST
Friday 6:43 AM
$ snap refresh --time
timer: 21:00-22:00
last: 2 days ago, at 23:54 EST
next: yesterday at 21:00 EST
Friday 5:56 PM
$ snap refresh --time
timer: 21:00-22:00
last: today at 16:37 EST
next: today at 21:00 EST
Sunday 7:54 AM
$ snap refresh --time
timer: 21:00-22:00
last: yesterday at 06:04 EST
next: yesterday at 21:00 EST
Monday 7:43 AM
$ snap refresh --time
timer: 21:00-22:00
last: yesterday at 11:57 EST
next: yesterday at 21:00 EST
Monday 4:28 PM
$ snap refresh --time
timer: 21:00-22:00
last: today at 16:08 EST
next: today at 21:00 EST
Tuesday 9:58 AM
$ snap refresh --time
timer: 21:00-22:00
last: today at 07:17 EST
next: today at 21:00 EST
$ journalctl -u snapd.service |grep autorefresh.go | tail -n 20
Feb 10 11:33:34 Books snapd[1004]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 10 11:38:32 Books snapd[1004]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-10T21:00:00-05:00.
Feb 11 08:53:28 Books snapd[23417]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-11T21:00:00-05:00.
Feb 11 16:51:57 Books snapd[1052]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-11T21:00:00-05:00.
Feb 11 21:22:40 Books snapd[1052]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 11 21:27:38 Books snapd[1052]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-12T21:00:00-05:00.
Feb 12 10:51:23 Books snapd[1039]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-12T21:00:00-05:00.
Feb 12 23:54:11 Books snapd[1039]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 12 23:59:08 Books snapd[1039]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-13T21:00:00-05:00.
Feb 14 16:37:08 Books snapd[1039]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 14 16:37:08 Books snapd[1039]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-14T21:00:00-05:00.
Feb 14 17:03:41 Books snapd[963]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-14T21:00:00-05:00.
Feb 15 06:04:37 Books snapd[963]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 15 06:09:34 Books snapd[963]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-15T21:00:00-05:00.
Feb 16 11:57:49 Books snapd[963]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 16 11:57:49 Books snapd[963]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-16T21:00:00-05:00.
Feb 17 16:08:17 Books snapd[963]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 17 16:13:14 Books snapd[963]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-17T21:00:00-05:00.
Feb 18 07:17:56 Books snapd[963]: autorefresh.go:397: auto-refresh: all snaps are up-to-date
Feb 18 07:22:53 Books snapd[963]: autorefresh.go:253: DEBUG: Next refresh scheduled for 2020-02-18T21:00:00-05:00.
snap refresh --time
timer: 02:00-04:00
last: today at 13:47 EDT
next: tomorrow at 02:00 EDT
snap get system refresh.timer
02:00-04:00
snap changes
ID Status Spawn Ready Summary
340 Done yesterday at 08:56 EDT yesterday at 08:56 EDT Pre-download "firefox" for auto-refresh
341 Done today at 13:47 EDT today at 13:48 EDT Pre-download "firefox" for auto-refresh
342 Done today at 13:47 EDT today at 13:48 EDT Auto-refresh snap "spotify"