After installation of a snap daemon service is unable to start because of mising apparmor profile

[rahul-tt]

Test process:

Update back and forth from revisions: gadget 1 → 4 snapd 15541 → 20298 application snap(turftank-mk2m-sw) 81 → 84

- Since the gadget is updated, snapd requests for a reboot.
-- Reboot --

gadget 4 --> 1
snapd 20298 --> 15541 
application snap(turftank-mk2m-sw) 84 --> 81

- Wait for all the permissions to be setup --> update --> reboot after update.
-- Reboot --

During the update, snapd is supposed to set security profiles, but it does not do that, some of the time. Here is the snapd logs to prove that.

Because of which i get file permission errors in my application snap.

-- Reboot --
Dec 07 09:25:52 ubuntu systemd[1]: Starting Snap Daemon...
Dec 07 09:25:55 ubuntu snapd[744]: AppArmor status: apparmor is enabled and all features are available
Dec 07 09:25:55 ubuntu snapd[744]: overlord.go:263: Acquiring state lock file
Dec 07 09:25:55 ubuntu snapd[744]: overlord.go:268: Acquired state lock file
Dec 07 09:25:56 ubuntu snapd[744]: daemon.go:247: started snapd/2.55.3 (series 16) ubuntu-core/20 (arm64) linux/5.4.0-1059-raspi.
Dec 07 09:25:56 ubuntu snapd[744]: daemon.go:340: adjusting startup timeout by 1m5s (pessimistic estimate of 30s plus 5s per snap)
Dec 07 09:25:56 ubuntu snapd[744]: devicemgr.go:306: save already mounted under /var/lib/snapd/save
Dec 07 09:25:56 ubuntu snapd[744]: standby.go:95: DEBUG: will consider standby after: 5s
Dec 07 09:25:56 ubuntu snapd[744]: main.go:88: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Dec 07 09:25:56 ubuntu snapd[744]: main.go:149: DEBUG: activation done in 1.022s
Dec 07 09:25:56 ubuntu systemd[1]: Started Snap Daemon.
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=1979;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.885808406s 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=1997;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.179992999s 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2033;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.32713ms 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2042;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.405666ms 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2060;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.330315ms 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2068;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.244833ms 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2075;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.475204ms 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2082;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.476129ms 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2090;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.27724ms 200
Dec 07 09:25:58 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2098;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.134352ms 200
Dec 07 09:25:59 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12452 on Do: Make snap "turftank-mk2m-sw" (86) available to the system
Dec 07 09:25:59 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12436 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Dec 07 09:26:01 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12437 on Do: Set automatic aliases for snap "turftank-mk2-gadget"
Dec 07 09:26:01 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12453 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Dec 07 09:26:02 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12438 on Do: Setup snap "turftank-mk2-gadget" aliases
Dec 07 09:26:02 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12454 on Do: Set automatic aliases for snap "turftank-mk2m-sw"
Dec 07 09:26:02 ubuntu snapd[744]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:26:02 ubuntu snapd[744]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:26:02 ubuntu snapd[744]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:26:02 ubuntu snapd[744]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:26:02 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12455 on Do: Setup snap "turftank-mk2m-sw" aliases
Dec 07 09:26:02 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12439 on Do: Run post-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:26:03 ubuntu snapd[744]: hotplug.go:160: DEBUG: ignoring device /dev/ttyS0, interface "serial-port" (handled by gadget slot serial0)
Dec 07 09:26:03 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12456 on Do: Run post-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:26:03 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12440 on Do: Start snap "turftank-mk2-gadget" (4) services
Dec 07 09:26:04 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12457 on Do: Start snap "turftank-mk2m-sw" (86) services
Dec 07 09:26:04 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12441 on Do: Clean up "turftank-mk2-gadget" (4) install
Dec 07 09:26:04 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12442 on Do: Run configure hook of "turftank-mk2-gadget" snap if present
Dec 07 09:26:04 ubuntu snapd[744]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA0, interface "serial-port" (handled by gadget slot bt-serial)
Dec 07 09:26:04 ubuntu snapd[744]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA1, interface "serial-port" (handled by gadget slot imu)
Dec 07 09:26:04 ubuntu snapd[744]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA2, interface "serial-port" (handled by gadget slot uhf)
Dec 07 09:26:04 ubuntu snapd[744]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA3, interface "serial-port" (handled by gadget slot mcu)
Dec 07 09:26:04 ubuntu snapd[744]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA4, interface "serial-port" (handled by gadget slot gnss)
Dec 07 09:26:06 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12443 on Do: Run health check of "turftank-mk2-gadget" snap
Dec 07 09:26:07 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12458 on Do: Clean up "turftank-mk2m-sw" (86) install
Dec 07 09:26:07 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12459 on Do: Run configure hook of "turftank-mk2m-sw" snap if present
Dec 07 09:26:08 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12460 on Do: Run health check of "turftank-mk2m-sw" snap
Dec 07 09:26:15 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2317;uid=0;socket=/run/snapd.socket; GET /v2/snaps 299.335536ms 200
Dec 07 09:26:15 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2317;uid=0;socket=/run/snapd.socket; GET /v2/snaps 238.674408ms 200
Dec 07 09:26:24 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; GET /v2/users 507.792µs 200
Dec 07 09:26:24 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; GET /v2/validation-sets 12.01228ms 200
Dec 07 09:26:25 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; GET /v2/snaps?snaps=turftank-mk2m-sw 175.067901ms 200
Dec 07 09:26:25 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; GET /v2/snaps 217.876135ms 200
Dec 07 09:26:25 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; GET /v2/changes 1.254761ms 200
Dec 07 09:26:25 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; GET /v2/snaps 211.829303ms 200
Dec 07 09:27:47 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2471;uid=0;socket=/run/snapd.socket; POST /v2/apps 129.567835ms 202
Dec 07 09:27:48 ubuntu snapd[744]: autorefresh.go:427: refresh is managed via the snapd-control interface
Dec 07 09:27:48 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12461 on Do: Run service command "reload-or-restart" for services ["networkmanager"] of snap "network-manager"
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2679;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.310204ms 200
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2688;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.134333ms 200
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2695;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.594722ms 200
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2706;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.197149ms 200
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2714;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.284074ms 200
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2721;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.652611ms 200
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2728;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.153686ms 200
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2736;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.187148ms 200
Dec 07 09:27:50 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2744;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.446778ms 200
Dec 07 09:35:02 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; POST /v2/assertions 39.957426ms 200
Dec 07 09:35:02 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; POST /v2/assertions 21.371537ms 200
Dec 07 09:35:02 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; POST /v2/assertions 29.670555ms 200
Dec 07 09:35:02 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; POST /v2/assertions 24.960056ms 200
Dec 07 09:35:02 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; POST /v2/assertions 47.431722ms 200
Dec 07 09:35:02 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; POST /v2/assertions 24.091259ms 200
Dec 07 09:35:02 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; POST /v2/assertions 43.91337ms 200
Dec 07 09:35:13 ubuntu snapd[744]: daemon.go:217: DEBUG: pid=2473;uid=0;socket=/run/snapd.socket; POST /v2/snaps 10.938578142s 202
Dec 07 09:35:13 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12481 on Do: Ensure prerequisites for "turftank-mk2m-sw" are available
Dec 07 09:35:14 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12482 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-446745759" (84)
Dec 07 09:35:14 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12462 on Do: Ensure prerequisites for "turftank-mk2-gadget" are available
Dec 07 09:35:14 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12483 on Do: Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:35:14 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12463 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-325192768" (1)
Dec 07 09:35:15 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12464 on Do: Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:35:15 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12484 on Do: Stop snap "turftank-mk2m-sw" services
Dec 07 09:35:15 ubuntu snapd[744]: services.go:754: DEBUG: StopServices called for ["turftank-mk2m-sw.mk2l" "turftank-mk2m-sw.mk2m" "turftank-mk2m-sw.state-manager" "turftank-mk2m-sw.timesync-off" "turftank-mk2m-sw.udisksd" "turftank-mk2m-sw.automount"], reason: refresh
Dec 07 09:35:15 ubuntu snapd[744]: services.go:769: DEBUG:  mk2l refresh-mode:
Dec 07 09:35:15 ubuntu snapd[744]: services.go:769: DEBUG:  mk2m refresh-mode:
Dec 07 09:35:15 ubuntu snapd[744]: services.go:769: DEBUG:  state-manager refresh-mode:
Dec 07 09:35:15 ubuntu snapd[744]: services.go:769: DEBUG:  timesync-off refresh-mode:
Dec 07 09:35:15 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12465 on Do: Stop snap "turftank-mk2-gadget" services
Dec 07 09:35:15 ubuntu snapd[744]: services.go:769: DEBUG:  udisksd refresh-mode:
Dec 07 09:35:15 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12466 on Do: Remove aliases for snap "turftank-mk2-gadget"
Dec 07 09:35:16 ubuntu snapd[744]: services.go:769: DEBUG:  automount refresh-mode:
Dec 07 09:35:16 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12467 on Do: Make current revision for snap "turftank-mk2-gadget" unavailable
Dec 07 09:35:17 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12468 on Do: Update assets from gadget "turftank-mk2-gadget" (1)
Dec 07 09:35:17 ubuntu snapd[744]: task.go:343: DEBUG: 2023-12-07T09:35:17Z INFO No gadget assets update needed
Dec 07 09:35:17 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12469 on Do: Update kernel command line from gadget "turftank-mk2-gadget" (1)
Dec 07 09:35:17 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12485 on Do: Remove aliases for snap "turftank-mk2m-sw"
Dec 07 09:35:17 ubuntu snapd[744]: handlers_gadget.go:275: DEBUG: no kernel command line update from gadget
Dec 07 09:35:18 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12470 on Do: Copy snap "turftank-mk2-gadget" data
Dec 07 09:35:18 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12486 on Do: Make current revision for snap "turftank-mk2m-sw" unavailable
Dec 07 09:35:18 ubuntu snapd[744]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.mk2l.service
Dec 07 09:35:18 ubuntu snapd[744]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.mk2m.service
Dec 07 09:35:18 ubuntu snapd[744]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.state-manager.service
Dec 07 09:35:18 ubuntu snapd[744]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.timesync-off.service
Dec 07 09:35:18 ubuntu snapd[744]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.udisksd.service
Dec 07 09:35:18 ubuntu snapd[744]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.automount.service
Dec 07 09:35:20 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12487 on Do: Copy snap "turftank-mk2m-sw" data
Dec 07 09:35:20 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12471 on Do: Setup snap "turftank-mk2-gadget" (1) security profiles
Dec 07 09:35:22 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12472 on Do: Make snap "turftank-mk2-gadget" (1) available to the system
Dec 07 09:35:22 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12488 on Do: Setup snap "turftank-mk2m-sw" (84) security profiles
Dec 07 09:35:39 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12473 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Dec 07 09:35:39 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12489 on Do: Make snap "turftank-mk2m-sw" (84) available to the system
Dec 07 09:35:42 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12490 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Dec 07 09:35:42 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12474 on Do: Set automatic aliases for snap "turftank-mk2-gadget"
Dec 07 09:35:43 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12475 on Do: Setup snap "turftank-mk2-gadget" aliases
Dec 07 09:35:43 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12491 on Do: Set automatic aliases for snap "turftank-mk2m-sw"
Dec 07 09:35:43 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12476 on Do: Run post-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:35:43 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12492 on Do: Setup snap "turftank-mk2m-sw" aliases
Dec 07 09:35:44 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12477 on Do: Start snap "turftank-mk2-gadget" (1) services
Dec 07 09:35:44 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12493 on Do: Run post-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:35:44 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12478 on Do: Clean up "turftank-mk2-gadget" (1) install
Dec 07 09:35:44 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12494 on Do: Start snap "turftank-mk2m-sw" (84) services
Dec 07 09:35:45 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12479 on Do: Run configure hook of "turftank-mk2-gadget" snap if present
Dec 07 09:35:47 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12480 on Do: Run health check of "turftank-mk2-gadget" snap
Dec 07 09:35:48 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12495 on Do: Clean up "turftank-mk2m-sw" (84) install
Dec 07 09:35:48 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12496 on Do: Run configure hook of "turftank-mk2m-sw" snap if present
Dec 07 09:35:48 ubuntu snapd[744]: taskrunner.go:439: DEBUG: Running task 12497 on Do: Run health check of "turftank-mk2m-sw" snap
Dec 07 09:36:05 ubuntu snapd[744]: main.go:155: Exiting on terminated signal.
Dec 07 09:36:05 ubuntu snapd[744]: overlord.go:504: Released state lock file
Dec 07 09:36:05 ubuntu systemd[1]: Stopping Snap Daemon...
Dec 07 09:36:05 ubuntu systemd[1]: snapd.service: Succeeded.
Dec 07 09:36:05 ubuntu systemd[1]: Stopped Snap Daemon.
-- Reboot --
Dec 07 09:36:32 ubuntu systemd[1]: Starting Snap Daemon...
Dec 07 09:36:35 ubuntu snapd[701]: AppArmor status: apparmor is enabled and all features are available
Dec 07 09:36:35 ubuntu snapd[701]: overlord.go:263: Acquiring state lock file
Dec 07 09:36:35 ubuntu snapd[701]: overlord.go:268: Acquired state lock file
Dec 07 09:36:36 ubuntu snapd[701]: daemon.go:247: started snapd/2.55.3 (series 16) ubuntu-core/20 (arm64) linux/5.4.0-1059-raspi.
Dec 07 09:36:36 ubuntu snapd[701]: daemon.go:340: adjusting startup timeout by 1m5s (pessimistic estimate of 30s plus 5s per snap)
Dec 07 09:36:36 ubuntu snapd[701]: devicemgr.go:306: save already mounted under /var/lib/snapd/save
Dec 07 09:36:36 ubuntu snapd[701]: standby.go:95: DEBUG: will consider standby after: 5s
Dec 07 09:36:36 ubuntu snapd[701]: main.go:88: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Dec 07 09:36:36 ubuntu snapd[701]: main.go:149: DEBUG: activation done in 1.024s
Dec 07 09:36:36 ubuntu systemd[1]: Started Snap Daemon.
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2045;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 429.840129ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2044;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 14.24624ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2090;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.470648ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2100;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.475111ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2124;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.414741ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2131;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.443574ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2138;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.504204ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2148;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.848741ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2159;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.626018ms 200
Dec 07 09:36:37 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2169;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.249629ms 200
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:160: DEBUG: ignoring device /dev/ttyS0, interface "serial-port" (handled by gadget slot serial0)
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA0, interface "serial-port" (handled by gadget slot bt-serial)
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA1, interface "serial-port" (handled by gadget slot imu)
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA2, interface "serial-port" (handled by gadget slot uhf)
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA3, interface "serial-port" (handled by gadget slot mcu)
Dec 07 09:36:38 ubuntu snapd[701]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA4, interface "serial-port" (handled by gadget slot gnss)
Dec 07 09:36:50 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; GET /v2/users 553.387µs 200
Dec 07 09:36:50 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; GET /v2/validation-sets 11.554887ms 200
Dec 07 09:36:50 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; GET /v2/snaps?snaps=turftank-mk2m-sw 109.042663ms 200
Dec 07 09:36:51 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; GET /v2/snaps 182.558908ms 200
Dec 07 09:36:51 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; GET /v2/changes 1.428644ms 200
Dec 07 09:38:31 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2340;uid=0;socket=/run/snapd.socket; POST /v2/apps 142.970072ms 202
Dec 07 09:38:31 ubuntu snapd[701]: autorefresh.go:427: refresh is managed via the snapd-control interface
Dec 07 09:38:31 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12498 on Do: Run service command "reload-or-restart" for services ["networkmanager"] of snap "network-manager"
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2645;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.299445ms 200
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2654;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.183482ms 200
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2660;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.280148ms 200
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2670;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.199259ms 200
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2677;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.152074ms 200
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2684;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.289037ms 200
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2691;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.28026ms 200
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2699;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.372592ms 200
Dec 07 09:38:34 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2707;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.449592ms 200
Dec 07 09:40:05 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; POST /v2/assertions 51.709833ms 200
Dec 07 09:40:05 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; POST /v2/assertions 23.080111ms 200
Dec 07 09:40:05 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; POST /v2/assertions 26.585648ms 200
Dec 07 09:40:05 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; POST /v2/assertions 39.847852ms 200
Dec 07 09:40:05 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; POST /v2/assertions 28.899407ms 200
Dec 07 09:40:05 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; POST /v2/assertions 26.314223ms 200
Dec 07 09:40:05 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; POST /v2/assertions 44.912777ms 200
Dec 07 09:40:19 ubuntu snapd[701]: daemon.go:217: DEBUG: pid=2266;uid=0;socket=/run/snapd.socket; POST /v2/snaps 13.717414732s 202
Dec 07 09:40:19 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12499 on Do: Ensure prerequisites for "snapd" are available
Dec 07 09:40:19 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12500 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-245556816" (20298)
Dec 07 09:40:20 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12501 on Do: Run pre-refresh hook of "snapd" snap if present
Dec 07 09:40:20 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12502 on Do: Stop snap "snapd" services
Dec 07 09:40:20 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12503 on Do: Remove aliases for snap "snapd"
Dec 07 09:40:21 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12504 on Do: Make current revision for snap "snapd" unavailable
Dec 07 09:40:21 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12505 on Do: Copy snap "snapd" data
Dec 07 09:40:22 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12506 on Do: Setup snap "snapd" (20298) security profiles
Dec 07 09:40:24 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12507 on Do: Make snap "snapd" (20298) available to the system
Dec 07 09:40:28 ubuntu systemd[1]: snapd.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Dec 07 09:40:43 ubuntu snapd[701]: task.go:343: DEBUG: 2023-12-07T09:40:43Z INFO Requested daemon restart (snapd snap).
Dec 07 09:40:43 ubuntu snapd[701]: daemon.go:521: gracefully waiting for running hooks
Dec 07 09:40:43 ubuntu snapd[701]: daemon.go:523: done waiting for running hooks
Dec 07 09:40:43 ubuntu snapd[701]: taskrunner.go:439: DEBUG: Running task 12508 on Do: Automatically connect eligible plugs and slots of snap "snapd"
Dec 07 09:40:44 ubuntu snapd[701]: task.go:343: DEBUG: 2023-12-07T09:40:44Z INFO Waiting for automatic snapd restart...
Dec 07 09:40:44 ubuntu snapd[701]: overlord.go:504: Released state lock file
Dec 07 09:40:44 ubuntu systemd[1]: snapd.service: Succeeded.
Dec 07 09:40:44 ubuntu systemd[1]: snapd.service: Scheduled restart job, restart counter is at 1.
Dec 07 09:40:44 ubuntu systemd[1]: Stopped Snap Daemon.
Dec 07 09:40:44 ubuntu systemd[1]: Starting Snap Daemon...
Dec 07 09:40:46 ubuntu snapd[3037]: overlord.go:272: Acquiring state lock file
Dec 07 09:40:46 ubuntu snapd[3037]: overlord.go:277: Acquired state lock file
Dec 07 09:40:46 ubuntu snapd[3037]: patch.go:64: Patching system state level 6 to sublevel 1...
Dec 07 09:40:46 ubuntu snapd[3037]: patch.go:64: Patching system state level 6 to sublevel 2...
Dec 07 09:40:46 ubuntu snapd[3037]: patch.go:64: Patching system state level 6 to sublevel 3...
Dec 07 09:40:47 ubuntu snapd[3037]: logger.go:92: DEBUG: modeenv for model "mk2" found
Dec 07 09:40:47 ubuntu snapd[3037]: daemon.go:247: started snapd/2.60.4 (series 16) ubuntu-core/20 (arm64) linux/5.4.0-1059-raspi.
Dec 07 09:40:47 ubuntu snapd[3037]: daemon.go:340: adjusting startup timeout by 1m5s (pessimistic estimate of 30s plus 5s per snap)
Dec 07 09:40:47 ubuntu snapd[3037]: backends.go:58: AppArmor status: apparmor is enabled and all features are available (using snapd provided apparmor_parser)
Dec 07 09:41:03 ubuntu snapd[3037]: logger.go:92: DEBUG: system-observe: /boot/config* not found, skipping mount of /boot/
Dec 07 09:41:17 ubuntu snapd[3037]: devicemgr.go:339: save already mounted under /var/lib/snapd/save
Dec 07 09:41:17 ubuntu snapd[3037]: logger.go:92: DEBUG: will consider standby after: 5s
Dec 07 09:41:17 ubuntu snapd[3037]: logger.go:92: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Dec 07 09:41:17 ubuntu snapd[3037]: logger.go:92: DEBUG: activation done in 31.572s
Dec 07 09:41:17 ubuntu systemd[1]: Started Snap Daemon.
Dec 07 09:41:17 ubuntu snapd[3037]: autorefresh.go:523: refresh is managed via the snapd-control interface
Dec 07 09:41:20 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12508 on Doing: Automatically connect eligible plugs and slots of snap "snapd"
Dec 07 09:41:22 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12509 on Do: Set automatic aliases for snap "snapd"
Dec 07 09:41:23 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12510 on Do: Setup snap "snapd" aliases
Dec 07 09:41:23 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12511 on Do: Update managed boot config assets from "snapd" (20298)
Dec 07 09:41:23 ubuntu snapd[3037]: logger.go:92: DEBUG: appended kernel command line part is ""
Dec 07 09:41:23 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12512 on Do: Run post-refresh hook of "snapd" snap if present
Dec 07 09:41:24 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12513 on Do: Start snap "snapd" (20298) services
Dec 07 09:41:24 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12514 on Do: Clean up "snapd" (20298) install
Dec 07 09:41:24 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12515 on Do: Run health check of "snapd" snap
Dec 07 09:41:24 ubuntu snapd[3037]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:41:25 ubuntu snapd[3037]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:41:25 ubuntu snapd[3037]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:41:25 ubuntu snapd[3037]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:41:25 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12516 on Do: Ensure prerequisites for "turftank-mk2-gadget" are available
Dec 07 09:41:25 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12535 on Do: Ensure prerequisites for "turftank-mk2m-sw" are available
Dec 07 09:41:25 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12517 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-989399407" (4)
Dec 07 09:41:26 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12518 on Do: Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:41:26 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12536 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-118261621" (81)
Dec 07 09:41:26 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12537 on Do: Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:41:26 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12519 on Do: Stop snap "turftank-mk2-gadget" services
Dec 07 09:41:26 ubuntu snapd[3037]: logger.go:92: DEBUG: ignoring device /dev/ttyS0, interface "serial-port" (handled by gadget slot serial0)
Dec 07 09:41:26 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12520 on Do: Remove aliases for snap "turftank-mk2-gadget"
Dec 07 09:41:26 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12538 on Do: Stop snap "turftank-mk2m-sw" services
Dec 07 09:41:27 ubuntu snapd[3037]: logger.go:92: DEBUG: StopServices called for ["turftank-mk2m-sw.state-manager" "turftank-mk2m-sw.timesync-off" "turftank-mk2m-sw.udisksd" "turftank-mk2m-sw.automount"], reason: refresh
Dec 07 09:41:27 ubuntu snapd[3037]: logger.go:92: DEBUG:  state-manager refresh-mode:
Dec 07 09:41:27 ubuntu snapd[3037]: logger.go:92: DEBUG:  timesync-off refresh-mode:
Dec 07 09:41:27 ubuntu snapd[3037]: logger.go:92: DEBUG:  udisksd refresh-mode:
Dec 07 09:41:27 ubuntu snapd[3037]: logger.go:92: DEBUG:  automount refresh-mode:
Dec 07 09:41:27 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12521 on Do: Make current revision for snap "turftank-mk2-gadget" unavailable
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12522 on Do: Update assets from gadget "turftank-mk2-gadget" (4)
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: OnDiskStructureFromPartition: p.FilesystemType "ext4", p.FilesystemLabel "ubuntu-data"
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: OnDiskStructureFromPartition: p.FilesystemType "ext4", p.FilesystemLabel "ubuntu-save"
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: OnDiskStructureFromPartition: p.FilesystemType "vfat", p.FilesystemLabel "ubuntu-boot"
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: OnDiskStructureFromPartition: p.FilesystemType "vfat", p.FilesystemLabel "ubuntu-seed"
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: checking volume compatibility between gadget volume mk2 and disk /dev/mmcblk0
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: partition /dev/mmcblk0p1 (offset 1048576) matched to gadget structure ubuntu-seed
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: partition /dev/mmcblk0p2 (offset 1259339776) matched to gadget structure ubuntu-boot
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: partition /dev/mmcblk0p3 (offset 2045771776) matched to gadget structure ubuntu-save
Dec 07 09:41:28 ubuntu snapd[3037]: logger.go:92: DEBUG: partition /dev/mmcblk0p4 (offset 2062548992) matched to gadget structure ubuntu-data
Dec 07 09:41:35 ubuntu snapd[3037]: logger.go:92: DEBUG: 2023-12-07T09:41:35Z INFO Requested system restart
Dec 07 09:41:35 ubuntu snapd[3037]: daemon.go:521: gracefully waiting for running hooks
Dec 07 09:41:35 ubuntu snapd[3037]: daemon.go:523: done waiting for running hooks
Dec 07 09:41:35 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12523 on Do: Update kernel command line from gadget "turftank-mk2-gadget" (4)
Dec 07 09:41:35 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12539 on Do: Remove aliases for snap "turftank-mk2m-sw"
Dec 07 09:41:35 ubuntu snapd[3037]: logger.go:92: DEBUG: updating kernel command line
Dec 07 09:41:35 ubuntu snapd[3037]: logger.go:92: DEBUG: appended kernel command line part is ""
Dec 07 09:41:35 ubuntu snapd[3037]: logger.go:92: DEBUG: no kernel command line update from gadget
Dec 07 09:41:35 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12540 on Do: Make current revision for snap "turftank-mk2m-sw" unavailable
Dec 07 09:41:35 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12524 on Do: Copy snap "turftank-mk2-gadget" data
Dec 07 09:41:36 ubuntu snapd[3037]: services.go:1103: RemoveSnapServices - disabling snap.turftank-mk2m-sw.state-manager.service
Dec 07 09:41:36 ubuntu snapd[3037]: services.go:1103: RemoveSnapServices - disabling snap.turftank-mk2m-sw.timesync-off.service
Dec 07 09:41:36 ubuntu snapd[3037]: services.go:1103: RemoveSnapServices - disabling snap.turftank-mk2m-sw.udisksd.service
Dec 07 09:41:36 ubuntu snapd[3037]: services.go:1103: RemoveSnapServices - disabling snap.turftank-mk2m-sw.automount.service
Dec 07 09:41:38 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12541 on Do: Copy snap "turftank-mk2m-sw" data
Dec 07 09:41:38 ubuntu snapd[3037]: logger.go:92: DEBUG: Running task 12525 on Do: Setup snap "turftank-mk2-gadget" (4) security profiles
Dec 07 09:41:38 ubuntu snapd[3037]: logger.go:92: DEBUG: system-observe: /boot/config* not found, skipping mount of /boot/
Dec 07 09:41:38 ubuntu snapd[3037]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA0, interface "serial-port" (handled by gadget slot bt-serial)
Dec 07 09:41:38 ubuntu snapd[3037]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA1, interface "serial-port" (handled by gadget slot imu)
Dec 07 09:41:38 ubuntu snapd[3037]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA2, interface "serial-port" (handled by gadget slot uhf)
Dec 07 09:41:38 ubuntu snapd[3037]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA3, interface "serial-port" (handled by gadget slot mcu)
Dec 07 09:41:38 ubuntu snapd[3037]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA4, interface "serial-port" (handled by gadget slot gnss)
Dec 07 09:41:43 ubuntu snapd[3037]: overlord.go:516: Released state lock file
Dec 07 09:41:44 ubuntu snapd[3037]: daemon.go:639: Waiting for system reboot
Dec 07 09:41:51 ubuntu systemd[1]: Stopping Snap Daemon...
Dec 07 09:41:52 ubuntu systemd[1]: snapd.service: Succeeded.
Dec 07 09:41:52 ubuntu systemd[1]: Stopped Snap Daemon.
-- Reboot --
Dec 07 09:42:21 ubuntu systemd[1]: Starting Snap Daemon...
Dec 07 09:42:24 ubuntu snapd[923]: overlord.go:272: Acquiring state lock file
Dec 07 09:42:24 ubuntu snapd[923]: overlord.go:277: Acquired state lock file
Dec 07 09:42:24 ubuntu snapd[923]: logger.go:92: DEBUG: modeenv for model "mk2" found
Dec 07 09:42:24 ubuntu snapd[923]: daemon.go:247: started snapd/2.60.4 (series 16) ubuntu-core/20 (arm64) linux/5.4.0-1059-raspi.
Dec 07 09:42:24 ubuntu snapd[923]: daemon.go:340: adjusting startup timeout by 1m5s (pessimistic estimate of 30s plus 5s per snap)
Dec 07 09:42:24 ubuntu snapd[923]: backends.go:58: AppArmor status: apparmor is enabled and all features are available (using snapd provided apparmor_parser)
Dec 07 09:42:25 ubuntu snapd[923]: devicemgr.go:339: save already mounted under /var/lib/snapd/save
Dec 07 09:42:25 ubuntu snapd[923]: logger.go:92: DEBUG: will consider standby after: 5s
Dec 07 09:42:25 ubuntu systemd[1]: Started Snap Daemon.
Dec 07 09:42:25 ubuntu snapd[923]: logger.go:92: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Dec 07 09:42:25 ubuntu snapd[923]: logger.go:92: DEBUG: activation done in 863ms
Dec 07 09:42:26 ubuntu snapd[923]: logger.go:92: DEBUG: pid=1976;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.812127184s 200
Dec 07 09:42:26 ubuntu snapd[923]: logger.go:92: DEBUG: pid=1944;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.826632851s 200
Dec 07 09:42:26 ubuntu snapd[923]: logger.go:92: DEBUG: pid=2011;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.507648ms 200
Dec 07 09:42:26 ubuntu snapd[923]: logger.go:92: DEBUG: pid=2024;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.482277ms 200
Dec 07 09:42:27 ubuntu snapd[923]: logger.go:92: DEBUG: pid=2034;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.449149ms 200
Dec 07 09:42:27 ubuntu snapd[923]: logger.go:92: DEBUG: pid=2044;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.223297ms 200
Dec 07 09:42:27 ubuntu snapd[923]: logger.go:92: DEBUG: pid=2050;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.285796ms 200
Dec 07 09:42:27 ubuntu snapd[923]: logger.go:92: DEBUG: pid=2057;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.195963ms 200
Dec 07 09:42:27 ubuntu snapd[923]: logger.go:92: DEBUG: pid=2064;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.245482ms 200
Dec 07 09:42:27 ubuntu snapd[923]: logger.go:92: DEBUG: pid=2071;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.213148ms 200
Dec 07 09:42:27 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12526 on Do: Make snap "turftank-mk2-gadget" (4) available to the system
Dec 07 09:42:27 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12542 on Do: Setup snap "turftank-mk2m-sw" (81) security profiles
Dec 07 09:42:28 ubuntu snapd[923]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:42:28 ubuntu snapd[923]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:42:28 ubuntu snapd[923]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:42:28 ubuntu snapd[923]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:42:28 ubuntu snapd[923]: logger.go:92: DEBUG: ignoring device /dev/ttyS0, interface "serial-port" (handled by gadget slot serial0)
Dec 07 09:42:29 ubuntu snapd[923]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA0, interface "serial-port" (handled by gadget slot bt-serial)
Dec 07 09:42:29 ubuntu snapd[923]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA1, interface "serial-port" (handled by gadget slot imu)
Dec 07 09:42:29 ubuntu snapd[923]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA2, interface "serial-port" (handled by gadget slot uhf)
Dec 07 09:42:29 ubuntu snapd[923]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA3, interface "serial-port" (handled by gadget slot mcu)
Dec 07 09:42:29 ubuntu snapd[923]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA4, interface "serial-port" (handled by gadget slot gnss)
Dec 07 09:42:32 ubuntu snapd[923]: logger.go:92: DEBUG: system-observe: /boot/config* not found, skipping mount of /boot/
Dec 07 09:42:34 ubuntu snapd[923]: udevmon.go:149: udev event error: Unable to parse uevent, err: no buffer space available
Dec 07 09:42:37 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12527 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Dec 07 09:42:37 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12543 on Do: Make snap "turftank-mk2m-sw" (81) available to the system
Dec 07 09:42:39 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12544 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Dec 07 09:42:39 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12528 on Do: Set automatic aliases for snap "turftank-mk2-gadget"
Dec 07 09:42:40 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12529 on Do: Setup snap "turftank-mk2-gadget" aliases
Dec 07 09:42:40 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12545 on Do: Set automatic aliases for snap "turftank-mk2m-sw"
Dec 07 09:42:41 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12530 on Do: Run post-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:42:41 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12546 on Do: Setup snap "turftank-mk2m-sw" aliases
Dec 07 09:42:41 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12547 on Do: Run post-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:42:41 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12531 on Do: Start snap "turftank-mk2-gadget" (4) services
Dec 07 09:42:43 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12548 on Do: Start snap "turftank-mk2m-sw" (81) services
Dec 07 09:42:43 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12532 on Do: Clean up "turftank-mk2-gadget" (4) install
Dec 07 09:42:43 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12533 on Do: Run configure hook of "turftank-mk2-gadget" snap if present
Dec 07 09:42:45 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12534 on Do: Run health check of "turftank-mk2-gadget" snap
Dec 07 09:42:46 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12549 on Do: Clean up "turftank-mk2m-sw" (81) install
Dec 07 09:42:46 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12550 on Do: Run configure hook of "turftank-mk2m-sw" snap if present
Dec 07 09:42:46 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12551 on Do: Run health check of "turftank-mk2m-sw" snap
Dec 07 09:42:53 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3458;uid=0;socket=/run/snapd.socket; GET /v2/snaps 231.289908ms 200
Dec 07 09:42:53 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3458;uid=0;socket=/run/snapd.socket; GET /v2/snaps 194.381462ms 200
Dec 07 09:43:04 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; GET /v2/users 599.037µs 200
Dec 07 09:43:04 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; GET /v2/validation-sets 10.378018ms 200
Dec 07 09:43:04 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; GET /v2/snaps?snaps=turftank-mk2m-sw 131.765555ms 200
Dec 07 09:43:04 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; GET /v2/snaps 170.212666ms 200
Dec 07 09:43:04 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; GET /v2/changes 1.175963ms 200
Dec 07 09:43:04 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; GET /v2/snaps 168.70487ms 200
Dec 07 09:44:27 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3658;uid=0;socket=/run/snapd.socket; POST /v2/apps 112.687352ms 202
Dec 07 09:44:27 ubuntu snapd[923]: autorefresh.go:523: refresh is managed via the snapd-control interface
Dec 07 09:44:27 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12552 on Do: Run service command "reload-or-restart" for services ["networkmanager"] of snap "network-manager"
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3864;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.584111ms 200
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3873;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.306315ms 200
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3879;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.318ms 200
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3889;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.420111ms 200
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3897;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 3.047926ms 200
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3904;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.415648ms 200
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3911;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.332ms 200
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3918;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.169445ms 200
Dec 07 09:44:30 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3925;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.260278ms 200
Dec 07 09:46:10 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; POST /v2/assertions 25.891ms 200
Dec 07 09:46:10 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; POST /v2/assertions 20.159315ms 200
Dec 07 09:46:10 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; POST /v2/assertions 23.743204ms 200
Dec 07 09:46:11 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; POST /v2/assertions 30.061333ms 200
Dec 07 09:46:11 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; POST /v2/assertions 40.688537ms 200
Dec 07 09:46:11 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; POST /v2/assertions 21.565574ms 200
Dec 07 09:46:11 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; POST /v2/assertions 49.880352ms 200
Dec 07 09:46:24 ubuntu snapd[923]: logger.go:92: DEBUG: pid=3660;uid=0;socket=/run/snapd.socket; POST /v2/snaps 13.615401196s 202
Dec 07 09:46:24 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12553 on Do: Ensure prerequisites for "snapd" are available
Dec 07 09:46:25 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12554 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-3112299331" (15541)
Dec 07 09:46:25 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12555 on Do: Run pre-refresh hook of "snapd" snap if present
Dec 07 09:46:25 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12556 on Do: Stop snap "snapd" services
Dec 07 09:46:25 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12557 on Do: Remove aliases for snap "snapd"
Dec 07 09:46:26 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12558 on Do: Make current revision for snap "snapd" unavailable
Dec 07 09:46:26 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12559 on Do: Copy snap "snapd" data
Dec 07 09:46:27 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12560 on Do: Setup snap "snapd" (15541) security profiles
Dec 07 09:46:27 ubuntu snapd[923]: logger.go:92: DEBUG: system-observe: /boot/config* not found, skipping mount of /boot/
Dec 07 09:46:28 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12561 on Do: Make snap "snapd" (15541) available to the system
Dec 07 09:46:28 ubuntu snapd[923]: handlers.go:2277: Downgrading snapd to version "2.55.3", discarding preserved namespaces
Dec 07 09:46:28 ubuntu snapd[923]: logger.go:92: DEBUG: Discarding namespace for snap "modem-manager"
Dec 07 09:46:28 ubuntu snapd[923]: logger.go:92: DEBUG: Discarding namespace for snap "network-manager"
Dec 07 09:46:28 ubuntu snapd[923]: logger.go:92: DEBUG: Discarding namespace for snap "pi-kernel"
Dec 07 09:46:28 ubuntu snapd[923]: logger.go:92: DEBUG: Discarding namespace for snap "snapd"
Dec 07 09:46:28 ubuntu snapd[923]: logger.go:92: DEBUG: Discarding namespace for snap "turftank-mk2-gadget"
Dec 07 09:46:28 ubuntu snapd[923]: logger.go:92: DEBUG: Discarding namespace for snap "turftank-mk2m-sw"
Dec 07 09:46:28 ubuntu snapd[923]: logger.go:92: DEBUG: Discarding namespace for snap "core20"
Dec 07 09:46:28 ubuntu snapd[923]: handlers.go:2338: Downgrading snapd to version "2.55.3", discarding all existing snap AppArmor profiles
Dec 07 09:46:31 ubuntu snapd[923]: core18.go:232: failed to disable "snapd.mounts-pre.target": systemctl command [--no-reload disable snapd.mounts-pre.target] failed with exit status 1: Failed to disable unit: Unit file snapd.mounts-pre.target does not exist.
Dec 07 09:46:31 ubuntu snapd[923]: core18.go:232: failed to disable "snapd.mounts.target": systemctl command [--no-reload disable snapd.mounts.target] failed with exit status 1: Failed to disable unit: Unit file snapd.mounts.target does not exist.
Dec 07 09:46:32 ubuntu systemd[1]: snapd.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Dec 07 09:46:33 ubuntu snapd[923]: logger.go:92: DEBUG: 2023-12-07T09:46:33Z INFO Requested daemon restart (snapd snap).
Dec 07 09:46:33 ubuntu snapd[923]: daemon.go:521: gracefully waiting for running hooks
Dec 07 09:46:33 ubuntu snapd[923]: daemon.go:523: done waiting for running hooks
Dec 07 09:46:33 ubuntu snapd[923]: logger.go:92: DEBUG: Running task 12562 on Do: Automatically connect eligible plugs and slots of snap "snapd"
Dec 07 09:46:33 ubuntu snapd[923]: logger.go:92: DEBUG: 2023-12-07T09:46:33Z INFO Waiting for automatic snapd restart...
Dec 07 09:46:34 ubuntu snapd[923]: overlord.go:516: Released state lock file
Dec 07 09:46:34 ubuntu systemd[1]: snapd.service: Succeeded.
Dec 07 09:46:34 ubuntu systemd[1]: snapd.service: Scheduled restart job, restart counter is at 1.
Dec 07 09:46:34 ubuntu systemd[1]: Stopped Snap Daemon.
Dec 07 09:46:34 ubuntu systemd[1]: Starting Snap Daemon...
Dec 07 09:46:36 ubuntu snapd[4507]: AppArmor status: apparmor is enabled and all features are available
Dec 07 09:46:36 ubuntu snapd[4507]: overlord.go:263: Acquiring state lock file
Dec 07 09:46:36 ubuntu snapd[4507]: overlord.go:268: Acquired state lock file
Dec 07 09:46:36 ubuntu snapd[4507]: patch.go:63: Patching system state level 6 to sublevel 1...
Dec 07 09:46:37 ubuntu snapd[4507]: patch.go:63: Patching system state level 6 to sublevel 2...
Dec 07 09:46:37 ubuntu snapd[4507]: patch.go:63: Patching system state level 6 to sublevel 3...
Dec 07 09:46:37 ubuntu snapd[4507]: daemon.go:247: started snapd/2.55.3 (series 16) ubuntu-core/20 (arm64) linux/5.4.0-1059-raspi.
Dec 07 09:46:37 ubuntu snapd[4507]: daemon.go:340: adjusting startup timeout by 1m5s (pessimistic estimate of 30s plus 5s per snap)
Dec 07 09:46:37 ubuntu snapd[4507]: helpers.go:137: error trying to compare the snap system key: system-key missing on disk
Dec 07 09:47:16 ubuntu snapd[4507]: devicemgr.go:306: save already mounted under /var/lib/snapd/save
Dec 07 09:47:16 ubuntu snapd[4507]: standby.go:95: DEBUG: will consider standby after: 5s
Dec 07 09:47:16 ubuntu systemd[1]: Started Snap Daemon.
Dec 07 09:47:16 ubuntu snapd[4507]: main.go:88: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Dec 07 09:47:16 ubuntu snapd[4507]: main.go:149: DEBUG: activation done in 40.079s
Dec 07 09:47:16 ubuntu snapd[4507]: autorefresh.go:427: refresh is managed via the snapd-control interface
Dec 07 09:47:17 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12562 on Doing: Automatically connect eligible plugs and slots of snap "snapd"
Dec 07 09:47:17 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12563 on Do: Set automatic aliases for snap "snapd"
Dec 07 09:47:18 ubuntu snapd[4507]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:47:18 ubuntu snapd[4507]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:47:18 ubuntu snapd[4507]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:47:18 ubuntu snapd[4507]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:47:18 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12564 on Do: Setup snap "snapd" aliases
Dec 07 09:47:19 ubuntu snapd[4507]: hotplug.go:160: DEBUG: ignoring device /dev/ttyS0, interface "serial-port" (handled by gadget slot serial0)
Dec 07 09:47:19 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12565 on Do: Update managed boot config assets from "snapd" (15541)
Dec 07 09:47:19 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12566 on Do: Run post-refresh hook of "snapd" snap if present
Dec 07 09:47:20 ubuntu snapd[4507]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA0, interface "serial-port" (handled by gadget slot bt-serial)
Dec 07 09:47:20 ubuntu snapd[4507]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA1, interface "serial-port" (handled by gadget slot imu)
Dec 07 09:47:20 ubuntu snapd[4507]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA2, interface "serial-port" (handled by gadget slot uhf)
Dec 07 09:47:20 ubuntu snapd[4507]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA3, interface "serial-port" (handled by gadget slot mcu)
Dec 07 09:47:20 ubuntu snapd[4507]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA4, interface "serial-port" (handled by gadget slot gnss)
Dec 07 09:47:20 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12567 on Do: Start snap "snapd" (15541) services
Dec 07 09:47:20 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12568 on Do: Clean up "snapd" (15541) install
Dec 07 09:47:21 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12569 on Do: Run health check of "snapd" snap
Dec 07 09:47:21 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12570 on Do: Ensure prerequisites for "turftank-mk2-gadget" are available
Dec 07 09:47:22 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12589 on Do: Ensure prerequisites for "turftank-mk2m-sw" are available
Dec 07 09:47:22 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12571 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-2080615397" (1)
Dec 07 09:47:23 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12572 on Do: Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:47:23 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12590 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-1742818553" (84)
Dec 07 09:47:24 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12591 on Do: Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:47:24 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12573 on Do: Stop snap "turftank-mk2-gadget" services
Dec 07 09:47:24 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12592 on Do: Stop snap "turftank-mk2m-sw" services
Dec 07 09:47:24 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12574 on Do: Remove aliases for snap "turftank-mk2-gadget"
Dec 07 09:47:24 ubuntu snapd[4507]: services.go:754: DEBUG: StopServices called for ["turftank-mk2m-sw.state-manager" "turftank-mk2m-sw.timesync-off" "turftank-mk2m-sw.udisksd" "turftank-mk2m-sw.automount" "turftank-mk2m-sw.mk2l" "turftank-mk2m-sw.mk2m"], reason: refresh
Dec 07 09:47:24 ubuntu snapd[4507]: services.go:769: DEBUG:  state-manager refresh-mode:
Dec 07 09:47:24 ubuntu snapd[4507]: services.go:769: DEBUG:  timesync-off refresh-mode:
Dec 07 09:47:25 ubuntu snapd[4507]: services.go:769: DEBUG:  udisksd refresh-mode:
Dec 07 09:47:25 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12575 on Do: Make current revision for snap "turftank-mk2-gadget" unavailable
Dec 07 09:47:25 ubuntu snapd[4507]: services.go:769: DEBUG:  automount refresh-mode:
Dec 07 09:47:25 ubuntu snapd[4507]: services.go:769: DEBUG:  mk2l refresh-mode:
Dec 07 09:47:25 ubuntu snapd[4507]: services.go:769: DEBUG:  mk2m refresh-mode:
Dec 07 09:47:26 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12593 on Do: Remove aliases for snap "turftank-mk2m-sw"
Dec 07 09:47:27 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12594 on Do: Make current revision for snap "turftank-mk2m-sw" unavailable
Dec 07 09:47:27 ubuntu snapd[4507]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.mk2l.service
Dec 07 09:47:27 ubuntu snapd[4507]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.mk2m.service
Dec 07 09:47:27 ubuntu snapd[4507]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.state-manager.service
Dec 07 09:47:27 ubuntu snapd[4507]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.timesync-off.service
Dec 07 09:47:27 ubuntu snapd[4507]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.udisksd.service
Dec 07 09:47:27 ubuntu snapd[4507]: services.go:919: RemoveSnapServices - disabling snap.turftank-mk2m-sw.automount.service
Dec 07 09:47:29 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12576 on Do: Update assets from gadget "turftank-mk2-gadget" (1)
Dec 07 09:47:29 ubuntu snapd[4507]: task.go:343: DEBUG: 2023-12-07T09:47:29Z INFO No gadget assets update needed
Dec 07 09:47:30 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12577 on Do: Update kernel command line from gadget "turftank-mk2-gadget" (1)
Dec 07 09:47:30 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12595 on Do: Copy snap "turftank-mk2m-sw" data
Dec 07 09:47:30 ubuntu snapd[4507]: handlers_gadget.go:275: DEBUG: no kernel command line update from gadget
Dec 07 09:47:31 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12578 on Do: Copy snap "turftank-mk2-gadget" data
Dec 07 09:47:31 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12596 on Do: Setup snap "turftank-mk2m-sw" (84) security profiles
Dec 07 09:47:36 ubuntu snapd[4507]: udevmon.go:149: udev event error: Unable to parse uevent, err: no buffer space available
Dec 07 09:47:49 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12597 on Do: Make snap "turftank-mk2m-sw" (84) available to the system
Dec 07 09:47:49 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12579 on Do: Setup snap "turftank-mk2-gadget" (1) security profiles
Dec 07 09:47:52 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12580 on Do: Make snap "turftank-mk2-gadget" (1) available to the system
Dec 07 09:47:52 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12598 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Dec 07 09:47:53 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12599 on Do: Set automatic aliases for snap "turftank-mk2m-sw"
Dec 07 09:47:53 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12581 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Dec 07 09:47:54 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12582 on Do: Set automatic aliases for snap "turftank-mk2-gadget"
Dec 07 09:47:54 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12600 on Do: Setup snap "turftank-mk2m-sw" aliases
Dec 07 09:47:54 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12601 on Do: Run post-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:47:54 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12583 on Do: Setup snap "turftank-mk2-gadget" aliases
Dec 07 09:47:55 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12584 on Do: Run post-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:47:55 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12602 on Do: Start snap "turftank-mk2m-sw" (84) services
Dec 07 09:47:55 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12585 on Do: Start snap "turftank-mk2-gadget" (1) services
Dec 07 09:47:56 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12586 on Do: Clean up "turftank-mk2-gadget" (1) install
Dec 07 09:47:56 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12587 on Do: Run configure hook of "turftank-mk2-gadget" snap if present
Dec 07 09:47:58 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12588 on Do: Run health check of "turftank-mk2-gadget" snap
Dec 07 09:47:59 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12603 on Do: Clean up "turftank-mk2m-sw" (84) install
Dec 07 09:48:00 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12604 on Do: Run configure hook of "turftank-mk2m-sw" snap if present
Dec 07 09:48:00 ubuntu snapd[4507]: taskrunner.go:439: DEBUG: Running task 12605 on Do: Run health check of "turftank-mk2m-sw" snap
Dec 07 09:48:17 ubuntu snapd[4507]: main.go:155: Exiting on terminated signal.
Dec 07 09:48:17 ubuntu systemd[1]: Stopping Snap Daemon...
Dec 07 09:48:17 ubuntu snapd[4507]: overlord.go:504: Released state lock file
Dec 07 09:48:17 ubuntu systemd[1]: snapd.service: Succeeded.
Dec 07 09:48:17 ubuntu systemd[1]: Stopped Snap Daemon.
-- Reboot --
Dec 07 09:48:44 ubuntu systemd[1]: Starting Snap Daemon...
Dec 07 09:48:47 ubuntu snapd[685]: AppArmor status: apparmor is enabled and all features are available
Dec 07 09:48:48 ubuntu snapd[685]: overlord.go:263: Acquiring state lock file
Dec 07 09:48:48 ubuntu snapd[685]: overlord.go:268: Acquired state lock file
Dec 07 09:48:48 ubuntu snapd[685]: daemon.go:247: started snapd/2.55.3 (series 16) ubuntu-core/20 (arm64) linux/5.4.0-1059-raspi.
Dec 07 09:48:48 ubuntu snapd[685]: daemon.go:340: adjusting startup timeout by 1m5s (pessimistic estimate of 30s plus 5s per snap)
Dec 07 09:48:49 ubuntu snapd[685]: devicemgr.go:306: save already mounted under /var/lib/snapd/save
Dec 07 09:48:49 ubuntu snapd[685]: standby.go:95: DEBUG: will consider standby after: 5s
Dec 07 09:48:49 ubuntu snapd[685]: main.go:88: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Dec 07 09:48:49 ubuntu snapd[685]: main.go:149: DEBUG: activation done in 923ms
Dec 07 09:48:49 ubuntu systemd[1]: Started Snap Daemon.
Dec 07 09:48:49 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2037;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 349.515889ms 200
Dec 07 09:48:49 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2036;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.503815ms 200
Dec 07 09:48:49 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2080;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.621759ms 200
Dec 07 09:48:49 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2096;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.335537ms 200
Dec 07 09:48:49 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2110;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.904722ms 200
Dec 07 09:48:49 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2126;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.555445ms 200
Dec 07 09:48:50 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2135;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.321648ms 200
Dec 07 09:48:50 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2142;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.275778ms 200
Dec 07 09:48:50 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2149;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.188166ms 200
Dec 07 09:48:50 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2157;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.401093ms 200
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:199: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:160: DEBUG: ignoring device /dev/ttyS0, interface "serial-port" (handled by gadget slot serial0)
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA0, interface "serial-port" (handled by gadget slot bt-serial)
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA1, interface "serial-port" (handled by gadget slot imu)
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA2, interface "serial-port" (handled by gadget slot uhf)
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA3, interface "serial-port" (handled by gadget slot mcu)
Dec 07 09:48:51 ubuntu snapd[685]: hotplug.go:160: DEBUG: ignoring device /dev/ttyAMA4, interface "serial-port" (handled by gadget slot gnss)
Dec 07 09:49:02 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; GET /v2/users 491.517µs 200
Dec 07 09:49:02 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; GET /v2/validation-sets 12.126568ms 200
Dec 07 09:49:02 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; GET /v2/snaps?snaps=turftank-mk2m-sw 123.28596ms 200
Dec 07 09:49:03 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; GET /v2/snaps 174.373234ms 200
Dec 07 09:49:03 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; GET /v2/changes 1.072013ms 200
Dec 07 09:50:42 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2337;uid=0;socket=/run/snapd.socket; POST /v2/apps 154.420214ms 202
Dec 07 09:50:42 ubuntu snapd[685]: autorefresh.go:427: refresh is managed via the snapd-control interface
Dec 07 09:50:42 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12606 on Do: Run service command "reload-or-restart" for services ["networkmanager"] of snap "network-manager"
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2640;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.35637ms 200
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2648;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.663537ms 200
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2654;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.527555ms 200
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2664;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.242574ms 200
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2671;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.213556ms 200
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2678;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.362352ms 200
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2684;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.072963ms 200
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2692;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.067185ms 200
Dec 07 09:50:45 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2700;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.3175ms 200
Dec 07 09:52:17 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; POST /v2/assertions 50.710851ms 200
Dec 07 09:52:17 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; POST /v2/assertions 25.022556ms 200
Dec 07 09:52:17 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; POST /v2/assertions 25.527611ms 200
Dec 07 09:52:17 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; POST /v2/assertions 33.099019ms 200
Dec 07 09:52:18 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; POST /v2/assertions 33.788241ms 200
Dec 07 09:52:18 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; POST /v2/assertions 26.114518ms 200
Dec 07 09:52:18 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; POST /v2/assertions 42.435611ms 200
Dec 07 09:52:31 ubuntu snapd[685]: daemon.go:217: DEBUG: pid=2226;uid=0;socket=/run/snapd.socket; POST /v2/snaps 13.5455899s 202
Dec 07 09:52:31 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12607 on Do: Ensure prerequisites for "snapd" are available
Dec 07 09:52:31 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12608 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-831784967" (20298)
Dec 07 09:52:32 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12609 on Do: Run pre-refresh hook of "snapd" snap if present
Dec 07 09:52:32 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12610 on Do: Stop snap "snapd" services
Dec 07 09:52:33 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12611 on Do: Remove aliases for snap "snapd"
Dec 07 09:52:33 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12612 on Do: Make current revision for snap "snapd" unavailable
Dec 07 09:52:34 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12613 on Do: Copy snap "snapd" data
Dec 07 09:52:34 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12614 on Do: Setup snap "snapd" (20298) security profiles
Dec 07 09:52:37 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12615 on Do: Make snap "snapd" (20298) available to the system
Dec 07 09:52:41 ubuntu systemd[1]: snapd.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Dec 07 09:52:55 ubuntu snapd[685]: task.go:343: DEBUG: 2023-12-07T09:52:55Z INFO Requested daemon restart (snapd snap).
Dec 07 09:52:56 ubuntu snapd[685]: daemon.go:521: gracefully waiting for running hooks
Dec 07 09:52:56 ubuntu snapd[685]: daemon.go:523: done waiting for running hooks
Dec 07 09:52:56 ubuntu snapd[685]: taskrunner.go:439: DEBUG: Running task 12616 on Do: Automatically connect eligible plugs and slots of snap "snapd"
Dec 07 09:52:56 ubuntu snapd[685]: task.go:343: DEBUG: 2023-12-07T09:52:56Z INFO Waiting for automatic snapd restart...
Dec 07 09:52:56 ubuntu snapd[685]: overlord.go:504: Released state lock file
Dec 07 09:52:56 ubuntu systemd[1]: snapd.service: Succeeded.
Dec 07 09:52:56 ubuntu systemd[1]: snapd.service: Scheduled restart job, restart counter is at 1.
Dec 07 09:52:56 ubuntu systemd[1]: Stopped Snap Daemon.
Dec 07 09:52:56 ubuntu systemd[1]: Starting Snap Daemon...
Dec 07 09:52:58 ubuntu snapd[3027]: overlord.go:272: Acquiring state lock file
Dec 07 09:52:58 ubuntu snapd[3027]: overlord.go:277: Acquired state lock file
Dec 07 09:52:58 ubuntu snapd[3027]: patch.go:64: Patching system state level 6 to sublevel 1...
Dec 07 09:52:59 ubuntu snapd[3027]: patch.go:64: Patching system state level 6 to sublevel 2...
Dec 07 09:52:59 ubuntu snapd[3027]: patch.go:64: Patching system state level 6 to sublevel 3...
Dec 07 09:52:59 ubuntu snapd[3027]: logger.go:92: DEBUG: modeenv for model "mk2" found
Dec 07 09:52:59 ubuntu snapd[3027]: daemon.go:247: started snapd/2.60.4 (series 16) ubuntu-core/20 (arm64) linux/5.4.0-1059-raspi.
Dec 07 09:52:59 ubuntu snapd[3027]: daemon.go:340: adjusting startup timeout by 1m5s (pessimistic estimate of 30s plus 5s per snap)
Dec 07 09:52:59 ubuntu snapd[3027]: backends.go:58: AppArmor status: apparmor is enabled and all features are available (using snapd provided apparmor_parser)
Dec 07 09:53:16 ubuntu snapd[3027]: logger.go:92: DEBUG: system-observe: /boot/config* not found, skipping mount of /boot/
Dec 07 09:53:29 ubuntu snapd[3027]: devicemgr.go:339: save already mounted under /var/lib/snapd/save
Dec 07 09:53:29 ubuntu snapd[3027]: logger.go:92: DEBUG: will consider standby after: 5s
Dec 07 09:53:29 ubuntu systemd[1]: Started Snap Daemon.
Dec 07 09:53:29 ubuntu snapd[3027]: logger.go:92: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Dec 07 09:53:29 ubuntu snapd[3027]: logger.go:92: DEBUG: activation done in 31.643s
Dec 07 09:53:30 ubuntu snapd[3027]: autorefresh.go:523: refresh is managed via the snapd-control interface
Dec 07 09:53:30 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12616 on Doing: Automatically connect eligible plugs and slots of snap "snapd"
Dec 07 09:53:33 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12617 on Do: Set automatic aliases for snap "snapd"
Dec 07 09:53:33 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12618 on Do: Setup snap "snapd" aliases
Dec 07 09:53:34 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12619 on Do: Update managed boot config assets from "snapd" (20298)
Dec 07 09:53:34 ubuntu snapd[3027]: logger.go:92: DEBUG: appended kernel command line part is ""
Dec 07 09:53:34 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12620 on Do: Run post-refresh hook of "snapd" snap if present
Dec 07 09:53:34 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12621 on Do: Start snap "snapd" (20298) services
Dec 07 09:53:35 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12622 on Do: Clean up "snapd" (20298) install
Dec 07 09:53:35 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12623 on Do: Run health check of "snapd" snap
Dec 07 09:53:35 ubuntu snapd[3027]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:53:35 ubuntu snapd[3027]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:53:35 ubuntu snapd[3027]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:53:35 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12624 on Do: Ensure prerequisites for "turftank-mk2-gadget" are available
Dec 07 09:53:36 ubuntu snapd[3027]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:53:36 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12643 on Do: Ensure prerequisites for "turftank-mk2m-sw" are available
Dec 07 09:53:36 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12625 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-602595258" (4)
Dec 07 09:53:36 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12644 on Do: Prepare snap "/var/lib/snapd/snaps/.local-install-468527944" (81)
Dec 07 09:53:36 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12626 on Do: Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12627 on Do: Stop snap "turftank-mk2-gadget" services
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12645 on Do: Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG: ignoring device /dev/ttyS0, interface "serial-port" (handled by gadget slot serial0)
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12646 on Do: Stop snap "turftank-mk2m-sw" services
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12628 on Do: Remove aliases for snap "turftank-mk2-gadget"
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG: StopServices called for ["turftank-mk2m-sw.automount" "turftank-mk2m-sw.state-manager" "turftank-mk2m-sw.timesync-off" "turftank-mk2m-sw.udisksd"], reason: refresh
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG:  automount refresh-mode:
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG:  state-manager refresh-mode:
Dec 07 09:53:37 ubuntu snapd[3027]: logger.go:92: DEBUG:  timesync-off refresh-mode:
Dec 07 09:53:38 ubuntu snapd[3027]: logger.go:92: DEBUG:  udisksd refresh-mode:
Dec 07 09:53:38 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12629 on Do: Make current revision for snap "turftank-mk2-gadget" unavailable
Dec 07 09:53:38 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12647 on Do: Remove aliases for snap "turftank-mk2m-sw"
Dec 07 09:53:39 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12648 on Do: Make current revision for snap "turftank-mk2m-sw" unavailable
Dec 07 09:53:39 ubuntu snapd[3027]: services.go:1103: RemoveSnapServices - disabling snap.turftank-mk2m-sw.automount.service
Dec 07 09:53:39 ubuntu snapd[3027]: services.go:1103: RemoveSnapServices - disabling snap.turftank-mk2m-sw.state-manager.service
Dec 07 09:53:39 ubuntu snapd[3027]: services.go:1103: RemoveSnapServices - disabling snap.turftank-mk2m-sw.timesync-off.service
Dec 07 09:53:39 ubuntu snapd[3027]: services.go:1103: RemoveSnapServices - disabling snap.turftank-mk2m-sw.udisksd.service
Dec 07 09:53:41 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12630 on Do: Update assets from gadget "turftank-mk2-gadget" (4)
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: OnDiskStructureFromPartition: p.FilesystemType "ext4", p.FilesystemLabel "ubuntu-data"
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: OnDiskStructureFromPartition: p.FilesystemType "ext4", p.FilesystemLabel "ubuntu-save"
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: OnDiskStructureFromPartition: p.FilesystemType "vfat", p.FilesystemLabel "ubuntu-boot"
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: OnDiskStructureFromPartition: p.FilesystemType "vfat", p.FilesystemLabel "ubuntu-seed"
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: checking volume compatibility between gadget volume mk2 and disk /dev/mmcblk0
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: partition /dev/mmcblk0p1 (offset 1048576) matched to gadget structure ubuntu-seed
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: partition /dev/mmcblk0p2 (offset 1259339776) matched to gadget structure ubuntu-boot
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: partition /dev/mmcblk0p3 (offset 2045771776) matched to gadget structure ubuntu-save
Dec 07 09:53:42 ubuntu snapd[3027]: logger.go:92: DEBUG: partition /dev/mmcblk0p4 (offset 2062548992) matched to gadget structure ubuntu-data
Dec 07 09:53:48 ubuntu snapd[3027]: logger.go:92: DEBUG: 2023-12-07T09:53:48Z INFO Requested system restart
Dec 07 09:53:48 ubuntu snapd[3027]: daemon.go:521: gracefully waiting for running hooks
Dec 07 09:53:48 ubuntu snapd[3027]: daemon.go:523: done waiting for running hooks
Dec 07 09:53:48 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12631 on Do: Update kernel command line from gadget "turftank-mk2-gadget" (4)
Dec 07 09:53:48 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12649 on Do: Copy snap "turftank-mk2m-sw" data
Dec 07 09:53:48 ubuntu snapd[3027]: logger.go:92: DEBUG: updating kernel command line
Dec 07 09:53:48 ubuntu snapd[3027]: logger.go:92: DEBUG: appended kernel command line part is ""
Dec 07 09:53:48 ubuntu snapd[3027]: logger.go:92: DEBUG: no kernel command line update from gadget
Dec 07 09:53:49 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12632 on Do: Copy snap "turftank-mk2-gadget" data
Dec 07 09:53:49 ubuntu snapd[3027]: logger.go:92: DEBUG: Running task 12650 on Do: Setup snap "turftank-mk2m-sw" (81) security profiles
Dec 07 09:53:50 ubuntu snapd[3027]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA0, interface "serial-port" (handled by gadget slot bt-serial)
Dec 07 09:53:50 ubuntu snapd[3027]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA1, interface "serial-port" (handled by gadget slot imu)
Dec 07 09:53:50 ubuntu snapd[3027]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA2, interface "serial-port" (handled by gadget slot uhf)
Dec 07 09:53:50 ubuntu snapd[3027]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA3, interface "serial-port" (handled by gadget slot mcu)
Dec 07 09:53:50 ubuntu snapd[3027]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA4, interface "serial-port" (handled by gadget slot gnss)
Dec 07 09:53:56 ubuntu snapd[3027]: logger.go:92: DEBUG: system-observe: /boot/config* not found, skipping mount of /boot/
Dec 07 09:54:02 ubuntu snapd[3027]: overlord.go:516: Released state lock file
Dec 07 09:54:02 ubuntu snapd[3027]: daemon.go:639: Waiting for system reboot
Dec 07 09:54:06 ubuntu systemd[1]: Stopping Snap Daemon...
Dec 07 09:54:06 ubuntu systemd[1]: snapd.service: Succeeded.
Dec 07 09:54:06 ubuntu systemd[1]: Stopped Snap Daemon.
-- Reboot --
Dec 07 09:54:37 ubuntu systemd[1]: Starting Snap Daemon...
Dec 07 09:54:40 ubuntu snapd[1203]: overlord.go:272: Acquiring state lock file
Dec 07 09:54:40 ubuntu snapd[1203]: overlord.go:277: Acquired state lock file
Dec 07 09:54:40 ubuntu snapd[1203]: logger.go:92: DEBUG: modeenv for model "mk2" found
Dec 07 09:54:40 ubuntu snapd[1203]: daemon.go:247: started snapd/2.60.4 (series 16) ubuntu-core/20 (arm64) linux/5.4.0-1059-raspi.
Dec 07 09:54:41 ubuntu snapd[1203]: daemon.go:340: adjusting startup timeout by 1m5s (pessimistic estimate of 30s plus 5s per snap)
Dec 07 09:54:41 ubuntu snapd[1203]: backends.go:58: AppArmor status: apparmor is enabled and all features are available (using snapd provided apparmor_parser)
Dec 07 09:54:41 ubuntu snapd[1203]: devicemgr.go:339: save already mounted under /var/lib/snapd/save
Dec 07 09:54:41 ubuntu snapd[1203]: logger.go:92: DEBUG: will consider standby after: 5s
Dec 07 09:54:41 ubuntu snapd[1203]: logger.go:92: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Dec 07 09:54:41 ubuntu snapd[1203]: logger.go:92: DEBUG: activation done in 924ms
Dec 07 09:54:41 ubuntu systemd[1]: Started Snap Daemon.
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=1991;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.78186385s 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=1959;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.824407981s 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2027;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.284648ms 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2037;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.517018ms 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2053;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.573537ms 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2062;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.619888ms 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2069;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.327074ms 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2076;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.136889ms 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2083;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.269981ms 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2090;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.122444ms 200
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12633 on Do: Setup snap "turftank-mk2-gadget" (4) security profiles
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12651 on Do: Make snap "turftank-mk2m-sw" (81) available to the system
Dec 07 09:54:43 ubuntu snapd[1203]: logger.go:92: DEBUG: system-observe: /boot/config* not found, skipping mount of /boot/
Dec 07 09:54:46 ubuntu snapd[1203]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:54:46 ubuntu snapd[1203]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:54:46 ubuntu snapd[1203]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:54:46 ubuntu snapd[1203]: hotplug.go:200: hotplug device add event ignored, enable experimental.hotplug
Dec 07 09:54:46 ubuntu snapd[1203]: logger.go:92: DEBUG: ignoring device /dev/ttyS0, interface "serial-port" (handled by gadget slot serial0)
Dec 07 09:54:47 ubuntu snapd[1203]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA0, interface "serial-port" (handled by gadget slot bt-serial)
Dec 07 09:54:47 ubuntu snapd[1203]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA1, interface "serial-port" (handled by gadget slot imu)
Dec 07 09:54:47 ubuntu snapd[1203]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA2, interface "serial-port" (handled by gadget slot uhf)
Dec 07 09:54:47 ubuntu snapd[1203]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA3, interface "serial-port" (handled by gadget slot mcu)
Dec 07 09:54:47 ubuntu snapd[1203]: logger.go:92: DEBUG: ignoring device /dev/ttyAMA4, interface "serial-port" (handled by gadget slot gnss)
Dec 07 09:54:48 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12652 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Dec 07 09:54:48 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12634 on Do: Make snap "turftank-mk2-gadget" (4) available to the system
Dec 07 09:54:48 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12653 on Do: Set automatic aliases for snap "turftank-mk2m-sw"
Dec 07 09:54:49 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12635 on Do: Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Dec 07 09:54:49 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12636 on Do: Set automatic aliases for snap "turftank-mk2-gadget"
Dec 07 09:54:49 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12654 on Do: Setup snap "turftank-mk2m-sw" aliases
Dec 07 09:54:50 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12655 on Do: Run post-refresh hook of "turftank-mk2m-sw" snap if present
Dec 07 09:54:50 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12637 on Do: Setup snap "turftank-mk2-gadget" aliases
Dec 07 09:54:51 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12638 on Do: Run post-refresh hook of "turftank-mk2-gadget" snap if present
Dec 07 09:54:51 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12656 on Do: Start snap "turftank-mk2m-sw" (81) services
Dec 07 09:54:51 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12639 on Do: Start snap "turftank-mk2-gadget" (4) services
Dec 07 09:54:52 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12640 on Do: Clean up "turftank-mk2-gadget" (4) install
Dec 07 09:54:52 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12641 on Do: Run configure hook of "turftank-mk2-gadget" snap if present
Dec 07 09:54:53 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12642 on Do: Run health check of "turftank-mk2-gadget" snap
Dec 07 09:54:54 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12657 on Do: Clean up "turftank-mk2m-sw" (81) install
Dec 07 09:54:54 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12658 on Do: Run configure hook of "turftank-mk2m-sw" snap if present
Dec 07 09:54:54 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12659 on Do: Run health check of "turftank-mk2m-sw" snap
Dec 07 09:55:00 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2250;uid=0;socket=/run/snapd.socket; GET /v2/snaps 195.731092ms 200
Dec 07 09:55:00 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2250;uid=0;socket=/run/snapd.socket; GET /v2/snaps 154.614093ms 200
Dec 07 09:55:09 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2404;uid=0;socket=/run/snapd.socket; GET /v2/users 561.556µs 200
Dec 07 09:55:09 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2404;uid=0;socket=/run/snapd.socket; GET /v2/validation-sets 12.149667ms 200
Dec 07 09:55:09 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2404;uid=0;socket=/run/snapd.socket; GET /v2/snaps?snaps=turftank-mk2m-sw 133.998315ms 200
Dec 07 09:55:10 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2404;uid=0;socket=/run/snapd.socket; GET /v2/snaps 168.989278ms 200
Dec 07 09:55:10 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2404;uid=0;socket=/run/snapd.socket; GET /v2/changes 1.047463ms 200
Dec 07 09:55:10 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2404;uid=0;socket=/run/snapd.socket; GET /v2/snaps 158.288407ms 200
Dec 07 09:56:33 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2402;uid=0;socket=/run/snapd.socket; POST /v2/apps 131.080519ms 202
Dec 07 09:56:33 ubuntu snapd[1203]: autorefresh.go:523: refresh is managed via the snapd-control interface
Dec 07 09:56:33 ubuntu snapd[1203]: logger.go:92: DEBUG: Running task 12660 on Do: Run service command "reload-or-restart" for services ["networkmanager"] of snap "network-manager"
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2608;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.445352ms 200
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2617;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.351815ms 200
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2624;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.32213ms 200
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2633;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.328648ms 200
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2641;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.189981ms 200
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2648;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.345352ms 200
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2655;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.370815ms 200
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2662;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.685685ms 200
Dec 07 09:56:36 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2670;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.225074ms 200
Dec 07 09:57:55 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2773;uid=0;socket=/run/snapd.socket; POST /v2/assertions 30.274111ms 200
Dec 07 09:57:55 ubuntu groupadd[2782]: group added to /var/lib/extrausers/group: name=turftank, GID=1000
Dec 07 09:57:55 ubuntu groupadd[2782]: group added to /var/lib/extrausers/gshadow: name=turftank
Dec 07 09:57:55 ubuntu groupadd[2782]: new group: name=turftank, GID=1000
Dec 07 09:57:55 ubuntu useradd[2788]: new user: name=turftank, UID=1000, GID=1000, home=/home/turftank, shell=/bin/bash, from=none
Dec 07 09:57:55 ubuntu usermod[2800]: change user 'turftank' password
Dec 07 09:57:56 ubuntu chfn[2807]: changed user 'turftank' information
Dec 07 09:57:56 ubuntu usermod[2813]: change user 'turftank' password
Dec 07 09:57:56 ubuntu snapd[1203]: logger.go:92: DEBUG: pid=2773;uid=0;socket=/run/snapd.socket; POST /v2/users 691.979278ms 200

[pmeulengracht]

Thanks for the logs, can you also find the change with snap changes to see the ID of the upgrade, and then do a snap change X where x is the id?

Can I ask why your software snap is rebooting the system? Normally snapd handles this automatically once a reboot is scheduled, also to allow itself to finish any running task and shutdown normally.

[rahul-tt]

When snapd schedules a reboot, it creates a file “/run/systemd/shutdown/scheduled”. This is scheduled after 1 min. So, instead of waiting, my software snap looks for this file and then reboots the system. I was informed, this is the right approach. Is it wrong to do so?

Moreover, i have also tried these updates where i disable my software snap from rebooting and the update still breaks.

Also, i have to reboot because, in the software snap update, it has 2 new daemon simple services (with endure mode). So, i need a reboot for them to work. Just restarting the services after update does not work.

Even when going back to the old revision with just one daemon simple service requires a reboot.

This is the snap changes for the above snapd logs. As you can see at the last update, it shows that snapd has set the profile for turftank-mk2m-sw(81), turftank-mk2-gadget(4), snapd(20298). But it did not set the apparmor profile. I can see that in /var/lib/snapd/apparmor/profiles/snap.turftank-mk2m-sw., the revision mentioned in the file is of turftank-mk2m-sw(84) instead.

-- Reboot--
Status  Spawn                   Ready                   Summary
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Ensure prerequisites for "turftank-mk2-gadget" are available
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-325192768" (1)
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Stop snap "turftank-mk2-gadget" services
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Remove aliases for snap "turftank-mk2-gadget"
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Make current revision for snap "turftank-mk2-gadget" unavailable
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Update assets from gadget "turftank-mk2-gadget" (1)
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Update kernel command line from gadget "turftank-mk2-gadget" (1)
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Copy snap "turftank-mk2-gadget" data
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Setup snap "turftank-mk2-gadget" (1) security profiles
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Make snap "turftank-mk2-gadget" (1) available to the system
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Set automatic aliases for snap "turftank-mk2-gadget"
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Setup snap "turftank-mk2-gadget" aliases
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Run post-refresh hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Start snap "turftank-mk2-gadget" (1) services
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Clean up "turftank-mk2-gadget" (1) install
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Run configure hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Run health check of "turftank-mk2-gadget" snap
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Ensure prerequisites for "turftank-mk2m-sw" are available
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-446745759" (84)
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Stop snap "turftank-mk2m-sw" services
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Remove aliases for snap "turftank-mk2m-sw"
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Make current revision for snap "turftank-mk2m-sw" unavailable
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Copy snap "turftank-mk2m-sw" data
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Setup snap "turftank-mk2m-sw" (84) security profiles
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Make snap "turftank-mk2m-sw" (84) available to the system
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Set automatic aliases for snap "turftank-mk2m-sw"
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Setup snap "turftank-mk2m-sw" aliases
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Run post-refresh hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Start snap "turftank-mk2m-sw" (84) services
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Clean up "turftank-mk2m-sw" (84) install
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Run configure hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:35 UTC  yesterday at 09:35 UTC  Run health check of "turftank-mk2m-sw" snap

......................................................................
Update assets from gadget "turftank-mk2-gadget" (1)

2023-12-07T09:35:17Z INFO No gadget assets update needed

--Reboot--

Status  Spawn                   Ready                   Summary
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Ensure prerequisites for "snapd" are available
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-245556816" (20298)
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Run pre-refresh hook of "snapd" snap if present
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Stop snap "snapd" services
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Remove aliases for snap "snapd"
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Make current revision for snap "snapd" unavailable
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Copy snap "snapd" data
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Setup snap "snapd" (20298) security profiles
Done    yesterday at 09:40 UTC  yesterday at 09:40 UTC  Make snap "snapd" (20298) available to the system
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Automatically connect eligible plugs and slots of snap "snapd"
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Set automatic aliases for snap "snapd"
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Setup snap "snapd" aliases
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Update managed boot config assets from "snapd" (20298)
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Run post-refresh hook of "snapd" snap if present
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Start snap "snapd" (20298) services
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Clean up "snapd" (20298) install
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Run health check of "snapd" snap
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Ensure prerequisites for "turftank-mk2-gadget" are available
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-989399407" (4)
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Stop snap "turftank-mk2-gadget" services
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Remove aliases for snap "turftank-mk2-gadget"
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Make current revision for snap "turftank-mk2-gadget" unavailable
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Update assets from gadget "turftank-mk2-gadget" (4)
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Update kernel command line from gadget "turftank-mk2-gadget" (4)
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Copy snap "turftank-mk2-gadget" data
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Setup snap "turftank-mk2-gadget" (4) security profiles
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Make snap "turftank-mk2-gadget" (4) available to the system
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Set automatic aliases for snap "turftank-mk2-gadget"
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Setup snap "turftank-mk2-gadget" aliases
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Run post-refresh hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Start snap "turftank-mk2-gadget" (4) services
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Clean up "turftank-mk2-gadget" (4) install
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Run configure hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Run health check of "turftank-mk2-gadget" snap
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Ensure prerequisites for "turftank-mk2m-sw" are available
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-118261621" (81)
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Stop snap "turftank-mk2m-sw" services
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Remove aliases for snap "turftank-mk2m-sw"
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Make current revision for snap "turftank-mk2m-sw" unavailable
Done    yesterday at 09:40 UTC  yesterday at 09:41 UTC  Copy snap "turftank-mk2m-sw" data
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Setup snap "turftank-mk2m-sw" (81) security profiles
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Make snap "turftank-mk2m-sw" (81) available to the system
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Set automatic aliases for snap "turftank-mk2m-sw"
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Setup snap "turftank-mk2m-sw" aliases
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Run post-refresh hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Start snap "turftank-mk2m-sw" (81) services
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Clean up "turftank-mk2m-sw" (81) install
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Run configure hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:40 UTC  yesterday at 09:42 UTC  Run health check of "turftank-mk2m-sw" snap

......................................................................
Make snap "snapd" (20298) available to the system

2023-12-07T09:40:43Z INFO Requested daemon restart (snapd snap).

......................................................................
Automatically connect eligible plugs and slots of snap "snapd"

2023-12-07T09:40:44Z INFO Waiting for automatic snapd restart...

......................................................................
Update assets from gadget "turftank-mk2-gadget" (4)

2023-12-07T09:41:35Z INFO Requested system restart


--Reboot--

Status  Spawn                   Ready                   Summary
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Ensure prerequisites for "snapd" are available
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-3112299331" (15541)
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Run pre-refresh hook of "snapd" snap if present
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Stop snap "snapd" services
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Remove aliases for snap "snapd"
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Make current revision for snap "snapd" unavailable
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Copy snap "snapd" data
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Setup snap "snapd" (15541) security profiles
Done    yesterday at 09:46 UTC  yesterday at 09:46 UTC  Make snap "snapd" (15541) available to the system
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Automatically connect eligible plugs and slots of snap "snapd"
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Set automatic aliases for snap "snapd"
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Setup snap "snapd" aliases
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Update managed boot config assets from "snapd" (15541)
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Run post-refresh hook of "snapd" snap if present
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Start snap "snapd" (15541) services
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Clean up "snapd" (15541) install
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Run health check of "snapd" snap
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Ensure prerequisites for "turftank-mk2-gadget" are available
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-2080615397" (1)
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Stop snap "turftank-mk2-gadget" services
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Remove aliases for snap "turftank-mk2-gadget"
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Make current revision for snap "turftank-mk2-gadget" unavailable
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Update assets from gadget "turftank-mk2-gadget" (1)
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Update kernel command line from gadget "turftank-mk2-gadget" (1)
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Copy snap "turftank-mk2-gadget" data
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Setup snap "turftank-mk2-gadget" (1) security profiles
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Make snap "turftank-mk2-gadget" (1) available to the system
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Set automatic aliases for snap "turftank-mk2-gadget"
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Setup snap "turftank-mk2-gadget" aliases
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Run post-refresh hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Start snap "turftank-mk2-gadget" (1) services
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Clean up "turftank-mk2-gadget" (1) install
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Run configure hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Run health check of "turftank-mk2-gadget" snap
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Ensure prerequisites for "turftank-mk2m-sw" are available
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-1742818553" (84)
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Stop snap "turftank-mk2m-sw" services
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Remove aliases for snap "turftank-mk2m-sw"
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Make current revision for snap "turftank-mk2m-sw" unavailable
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Copy snap "turftank-mk2m-sw" data
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Setup snap "turftank-mk2m-sw" (84) security profiles
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Make snap "turftank-mk2m-sw" (84) available to the system
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Set automatic aliases for snap "turftank-mk2m-sw"
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Setup snap "turftank-mk2m-sw" aliases
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Run post-refresh hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Start snap "turftank-mk2m-sw" (84) services
Done    yesterday at 09:46 UTC  yesterday at 09:47 UTC  Clean up "turftank-mk2m-sw" (84) install
Done    yesterday at 09:46 UTC  yesterday at 09:48 UTC  Run configure hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:46 UTC  yesterday at 09:48 UTC  Run health check of "turftank-mk2m-sw" snap

......................................................................
Make snap "snapd" (15541) available to the system

2023-12-07T09:46:33Z INFO Requested daemon restart (snapd snap).

......................................................................
Automatically connect eligible plugs and slots of snap "snapd"

2023-12-07T09:46:33Z INFO Waiting for automatic snapd restart...

......................................................................
Update assets from gadget "turftank-mk2-gadget" (1)

2023-12-07T09:47:29Z INFO No gadget assets update needed


--Reboot--

Status  Spawn                   Ready                   Summary
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Ensure prerequisites for "snapd" are available
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-831784967" (20298)
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Run pre-refresh hook of "snapd" snap if present
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Stop snap "snapd" services
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Remove aliases for snap "snapd"
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Make current revision for snap "snapd" unavailable
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Copy snap "snapd" data
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Setup snap "snapd" (20298) security profiles
Done    yesterday at 09:52 UTC  yesterday at 09:52 UTC  Make snap "snapd" (20298) available to the system
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Automatically connect eligible plugs and slots of snap "snapd"
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Set automatic aliases for snap "snapd"
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Setup snap "snapd" aliases
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Update managed boot config assets from "snapd" (20298)
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Run post-refresh hook of "snapd" snap if present
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Start snap "snapd" (20298) services
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Clean up "snapd" (20298) install
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Run health check of "snapd" snap
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Ensure prerequisites for "turftank-mk2-gadget" are available
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-602595258" (4)
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Stop snap "turftank-mk2-gadget" services
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Remove aliases for snap "turftank-mk2-gadget"
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Make current revision for snap "turftank-mk2-gadget" unavailable
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Update assets from gadget "turftank-mk2-gadget" (4)
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Update kernel command line from gadget "turftank-mk2-gadget" (4)
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Copy snap "turftank-mk2-gadget" data
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Setup snap "turftank-mk2-gadget" (4) security profiles
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Make snap "turftank-mk2-gadget" (4) available to the system
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Set automatic aliases for snap "turftank-mk2-gadget"
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Setup snap "turftank-mk2-gadget" aliases
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Run post-refresh hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Start snap "turftank-mk2-gadget" (4) services
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Clean up "turftank-mk2-gadget" (4) install
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Run configure hook of "turftank-mk2-gadget" snap if present
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Run health check of "turftank-mk2-gadget" snap
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Ensure prerequisites for "turftank-mk2m-sw" are available
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Prepare snap "/var/lib/snapd/snaps/.local-install-468527944" (81)
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Stop snap "turftank-mk2m-sw" services
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Remove aliases for snap "turftank-mk2m-sw"
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Make current revision for snap "turftank-mk2m-sw" unavailable
Done    yesterday at 09:52 UTC  yesterday at 09:53 UTC  Copy snap "turftank-mk2m-sw" data
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Setup snap "turftank-mk2m-sw" (81) security profiles
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Make snap "turftank-mk2m-sw" (81) available to the system
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Set automatic aliases for snap "turftank-mk2m-sw"
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Setup snap "turftank-mk2m-sw" aliases
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Run post-refresh hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Start snap "turftank-mk2m-sw" (81) services
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Clean up "turftank-mk2m-sw" (81) install
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Run configure hook of "turftank-mk2m-sw" snap if present
Done    yesterday at 09:52 UTC  yesterday at 09:54 UTC  Run health check of "turftank-mk2m-sw" snap

......................................................................
Make snap "snapd" (20298) available to the system

2023-12-07T09:52:55Z INFO Requested daemon restart (snapd snap).

......................................................................
Automatically connect eligible plugs and slots of snap "snapd"

2023-12-07T09:52:56Z INFO Waiting for automatic snapd restart...

......................................................................
Update assets from gadget "turftank-mk2-gadget" (4)

2023-12-07T09:53:48Z INFO Requested system restart

[rahul-tt]

Hi @pmeulengracht @baldeuniversel , I would like to reopen this issue. I have seen a case where even when i update the snap normally, the snapd sometimes does not create the AppArmor profiles.

Previously, I only observed this when updating, gadget, snapd and application snap together. But now, i have seen a case where only application snap and gadget is updated and i see this issue.

I can reinstall to fix this. but I would like to get into the source of why this happens.

Could you help me please.

[baldeuniversel]

Hello ! @rahul-tt .

What’s the log now ?

[rahul-tt]

Hi, I was running an update from 2 versions back to back. I have the refresh-control set to managed and my snap connected to snapd-control plug. But, during the update this happens:

1121  Done    2024-05-10T12:44:28Z  2024-05-10T12:47:14Z  Install snaps "turftank-mk2m-sw", "turftank-mk2-gadget" from files "turftank-mk2m-sw_94.snap", "turftank-mk2-gadget_4.snap"
1122  Done    2024-05-10T12:50:46Z  2024-05-10T12:51:40Z  Install snaps "turftank-mk2m-sw", "turftank-mk2-gadget" from files "turftank-mk2m-sw_49.snap", "turftank-mk2-gadget_1.snap"
1123  Done    2024-05-10T12:55:06Z  2024-05-10T12:55:09Z  Running service command
1124  Error   2024-05-10T12:56:47Z  2024-05-10T13:03:23Z  Install snaps "turftank-mk2m-sw", "turftank-mk2-gadget" from files "turftank-mk2m-sw_94.snap", "turftank-mk2-gadget_4.snap"
1125  Done    2024-05-10T12:59:03Z  2024-05-10T13:02:12Z  Auto-refresh snap "core20"
1126  Done    2024-05-10T14:07:02Z  2024-05-10T14:08:06Z  Refresh "turftank-mk2m-sw" snap
1127  Done    2024-05-10T14:08:49Z  2024-05-10T14:08:53Z  Running service command

==================== SNAP CHANGES (in Error) ====================

===== tasks for 1124 =====
Status  Spawn                 Ready                 Summary
Done    2024-05-10T12:56:47Z  2024-05-10T13:03:21Z  Ensure prerequisites for "turftank-mk2-gadget" are available
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:50Z  Prepare snap "/var/lib/snapd/snaps/.local-install-713670153" (4)
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:48Z  Run pre-refresh hook of "turftank-mk2-gadget" snap if present
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:47Z  Stop snap "turftank-mk2-gadget" services
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:47Z  Remove aliases for snap "turftank-mk2-gadget"
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:46Z  Make current revision for snap "turftank-mk2-gadget" unavailable
Done    2024-05-10T12:56:47Z  2024-05-10T13:03:21Z  Update assets from gadget "turftank-mk2-gadget" (4)
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:46Z  Update kernel command line from gadget "turftank-mk2-gadget" (4)
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:45Z  Copy snap "turftank-mk2-gadget" data
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:44Z  Setup snap "turftank-mk2-gadget" (4) security profiles
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:35Z  Make snap "turftank-mk2-gadget" (4) available to the system
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:35Z  Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"
Undone  2024-05-10T12:56:47Z  2024-05-10T13:00:10Z  Set automatic aliases for snap "turftank-mk2-gadget"
Undone  2024-05-10T12:56:47Z  2024-05-10T13:00:08Z  Setup snap "turftank-mk2-gadget" aliases
Undone  2024-05-10T12:56:47Z  2024-05-10T13:00:07Z  Run post-refresh hook of "turftank-mk2-gadget" snap if present
Undone  2024-05-10T12:56:47Z  2024-05-10T13:00:06Z  Start snap "turftank-mk2-gadget" (4) services
Done    2024-05-10T12:56:47Z  2024-05-10T13:03:21Z  Clean up "turftank-mk2-gadget" (4) install
Error   2024-05-10T12:56:47Z  2024-05-10T13:00:04Z  Run configure hook of "turftank-mk2-gadget" snap if present
Hold    2024-05-10T12:56:47Z  2024-05-10T13:00:04Z  Run health check of "turftank-mk2-gadget" snap
Done    2024-05-10T12:56:47Z  2024-05-10T13:03:23Z  Ensure prerequisites for "turftank-mk2m-sw" are available
Undone  2024-05-10T12:56:47Z  2024-05-10T13:03:22Z  Prepare snap "/var/lib/snapd/snaps/.local-install-954227922" (94)
Undone  2024-05-10T12:56:47Z  2024-05-10T13:03:21Z  Run pre-refresh hook of "turftank-mk2m-sw" snap if present
Error   2024-05-10T12:56:47Z  2024-05-10T13:03:21Z  Stop snap "turftank-mk2m-sw" services
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:45Z  Remove aliases for snap "turftank-mk2m-sw"
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:43Z  Make current revision for snap "turftank-mk2m-sw" unavailable
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:41Z  Copy snap "turftank-mk2m-sw" data
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:40Z  Setup snap "turftank-mk2m-sw" (94) security profiles
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:34Z  Make snap "turftank-mk2m-sw" (94) available to the system
Undone  2024-05-10T12:56:47Z  2024-05-10T13:02:32Z  Automatically connect eligible plugs and slots of snap "turftank-mk2m-sw"
Undone  2024-05-10T12:56:47Z  2024-05-10T13:01:54Z  Set automatic aliases for snap "turftank-mk2m-sw"
Undone  2024-05-10T12:56:47Z  2024-05-10T13:01:52Z  Setup snap "turftank-mk2m-sw" aliases
Undone  2024-05-10T12:56:47Z  2024-05-10T13:01:49Z  Run post-refresh hook of "turftank-mk2m-sw" snap if present
Undone  2024-05-10T12:56:47Z  2024-05-10T13:00:12Z  Start snap "turftank-mk2m-sw" (94) services
Done    2024-05-10T12:56:47Z  2024-05-10T13:03:21Z  Clean up "turftank-mk2m-sw" (94) install
Undone  2024-05-10T12:56:47Z  2024-05-10T13:00:07Z  Run configure hook of "turftank-mk2m-sw" snap if present
Undone  2024-05-10T12:56:47Z  2024-05-10T13:00:06Z  Run health check of "turftank-mk2m-sw" snap
Undone  2024-05-10T12:58:50Z  2024-05-10T13:01:59Z  Connect turftank-mk2m-sw:block-devices to snapd:block-devices
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:18Z  Connect turftank-mk2m-sw:hardware-observe to snapd:hardware-observe
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:17Z  Connect turftank-mk2m-sw:network to snapd:network
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:14Z  Connect turftank-mk2m-sw:network-manager to network-manager:service
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:21Z  Connect turftank-mk2m-sw:timezone-control to snapd:timezone-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:20Z  Connect turftank-mk2m-sw:log-observe to snapd:log-observe
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:24Z  Connect turftank-mk2m-sw:system-observe to snapd:system-observe
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:16Z  Connect turftank-mk2m-sw:modem-manager to modem-manager:service
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:12Z  Connect turftank-mk2m-sw:timeserver-control to snapd:timeserver-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:24Z  Connect turftank-mk2m-sw:client to turftank-mk2m-sw:service
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:05Z  Connect turftank-mk2m-sw:gpiod to snapd:system-files
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:20Z  Connect turftank-mk2m-sw:snapd to snapd:snapd-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:28Z  Connect turftank-mk2m-sw:firewall-control to snapd:firewall-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:30Z  Connect turftank-mk2m-sw:home to snapd:home
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:13Z  Connect turftank-mk2m-sw:network-control to snapd:network-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:28Z  Connect turftank-mk2m-sw:time-control to snapd:time-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:31Z  Connect turftank-mk2m-sw:mount-observe to snapd:mount-observe
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:00Z  Connect turftank-mk2m-sw:process-control to snapd:process-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:07Z  Connect turftank-mk2m-sw:shutdown to snapd:shutdown
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:16Z  Connect turftank-mk2m-sw:gpio-control to snapd:gpio-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:26Z  Connect turftank-mk2m-sw:gpio-memory-control to snapd:gpio-memory-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:22Z  Connect turftank-mk2m-sw:network-bind to snapd:network-bind
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:09Z  Connect turftank-mk2m-sw:physical-memory-control to snapd:physical-memory-control
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:25Z  Connect turftank-mk2m-sw:removable-media to snapd:removable-media
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:19Z  Connect turftank-mk2m-sw:lte-usb-blacklist to snapd:system-files
Undone  2024-05-10T12:58:50Z  2024-05-10T13:02:29Z  Connect turftank-mk2m-sw:physical-memory-observe to snapd:physical-memory-observe
Undone  2024-05-10T12:58:50Z  2024-05-10T13:01:57Z  Setup snap "turftank-mk2m-sw" (94) security profiles for auto-connections
Undone  2024-05-10T12:59:25Z  2024-05-10T13:02:32Z  Connect turftank-mk2m-sw:uhf to turftank-mk2-gadget:uhf
Undone  2024-05-10T12:59:25Z  2024-05-10T13:01:53Z  Connect turftank-mk2-gadget:network-observe to snapd:network-observe
Undone  2024-05-10T12:59:25Z  2024-05-10T13:02:15Z  Connect turftank-mk2m-sw:imu to turftank-mk2-gadget:imu
Undone  2024-05-10T12:59:25Z  2024-05-10T13:02:06Z  Connect turftank-mk2m-sw:i2c-1 to turftank-mk2-gadget:i2c-1
Undone  2024-05-10T12:59:25Z  2024-05-10T13:02:27Z  Connect turftank-mk2m-sw:mcu to turftank-mk2-gadget:mcu
Undone  2024-05-10T12:59:25Z  2024-05-10T13:02:23Z  Connect turftank-mk2m-sw:gnss to turftank-mk2-gadget:gnss
Undone  2024-05-10T12:59:25Z  2024-05-10T13:01:50Z  Setup snap "turftank-mk2-gadget" (4) security profiles for auto-connections

......................................................................
Automatically connect eligible plugs and slots of snap "turftank-mk2-gadget"

2024-05-10T12:58:51Z INFO Waiting for conflicting change in progress: conflicting slot snap snapd, task "connect"
2024-05-10T12:58:59Z INFO Waiting for conflicting change in progress: conflicting slot snap snapd, task "connect"
2024-05-10T12:59:06Z INFO Waiting for conflicting change in progress: conflicting slot snap snapd, task "connect"
2024-05-10T12:59:13Z INFO Waiting for conflicting change in progress: conflicting slot snap snapd, task "connect"

......................................................................
Run configure hook of "turftank-mk2-gadget" snap if present

2024-05-10T13:00:04Z ERROR run hook "configure": 
-----
cmd_run.go:1035: DEBUG: executing snap-confine from /snap/snapd/15541/usr/lib/snapd/snap-confine
cmd_run.go:438: DEBUG: SELinux not enabled
tracking.go:46: DEBUG: creating transient scope snap.turftank-mk2-gadget.hook.configure
tracking.go:319: DEBUG: create transient scope job: /org/freedesktop/systemd1/job/3490
tracking.go:146: DEBUG: waited 8.036811ms for tracking
DEBUG: umask reset, old umask was  022
DEBUG: security tag: snap.turftank-mk2-gadget.hook.configure
DEBUG: executable:   /usr/lib/snapd/snap-exec
DEBUG: confinement:  non-classic
DEBUG: base snap:    core20
DEBUG: ruid: 0, euid: 0, suid: 0
DEBUG: rgid: 0, egid: 0, sgid: 0
DEBUG: apparmor label on snap-confine is: /snap/snapd/15541/usr/lib/snapd/snap-confine
DEBUG: apparmor mode is: enforce
DEBUG: creating lock directory /run/snapd/lock (if missing)
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: opening lock directory /run/snapd/lock
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: opening lock file: /run/snapd/lock/.lock
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: sanity timeout initialized and set for 30 seconds
DEBUG: acquiring exclusive lock (scope (global), uid 0)
DEBUG: sanity timeout reset and disabled
DEBUG: ensuring that snap mount directory is shared
DEBUG: unsharing snap namespace directory
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: releasing lock 5
DEBUG: opened snap-update-ns executable as file descriptor 5
DEBUG: opened snap-discard-ns executable as file descriptor 6
DEBUG: creating lock directory /run/snapd/lock (if missing)
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: opening lock directory /run/snapd/lock
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: opening lock file: /run/snapd/lock/turftank-mk2-gadget.lock
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: set_effective_identity uid:0 (change: no), gid:0 (change: yes)
DEBUG: sanity timeout initialized and set for 30 seconds
DEBUG: acquiring exclusive lock (scope turftank-mk2-gadget, uid 0)
DEBUG: sanity timeout reset and disabled
DEBUG: initializing mount namespace: turftank-mk2-gadget
cannot locate base snap core20: No such file or directory
-----

After which the turftank-mk2m-sw snap services are disabled