Immediate moment around the failure:
Aug 28 07:26:12 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: storehelpers.go:919: cannot refresh snap "snapd": snap has no
updates available
Aug 28 07:26:14 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: cache.go:320: removed 0 entries/ 0 from downloads cache
Aug 28 07:26:14 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Reloading requested from client PID 12637 ('systemctl') (unit
snapd.service)...
Aug 28 07:26:14 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Reloading...
Aug 28 07:26:14 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Reloading finished in 138 ms.
Aug 28 07:26:14 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Mounting snap-snapd-27738.mount - Mount unit for snapd, revision
27738...
Aug 28 07:26:14 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
kernel: loop5: detected capacity change from 0 to 102952
Aug 28 07:26:14 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Mounted snap-snapd-27738.mount - Mount unit for snapd, revision
27738.
Aug 28 07:26:15 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: backend.go:285: reloading profiles of snap-confine provided by the
system snap
Aug 28 07:26:15 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
kernel: audit: type=1400 audit(1787901975.271:161): apparmor="STATUS"
operation="profile_load" profile="unconfined"
name="/snap/snapd/27738/usr/lib/snapd/snap-confine" pid=12692
comm="apparmor_parser"
Aug 28 07:26:15 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: daemon.go:569: gracefully waiting for running hooks
Aug 28 07:26:15 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: daemon.go:571: done waiting for running hooks
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: standby.go:121: standby monitoring stop requested
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: overlord.go:568: Released state lock file
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: daemon.go:648: restarting daemon after update
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[862]: seclog.go:189: security logger disabled
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.service: Deactivated successfully.
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.service: Scheduled restart job, restart counter is at 1.
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Starting snapd.service - Snap Daemon...
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: seclog.go:174: security logger enabled
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: overlord.go:313: Acquiring state lock file
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: overlord.go:318: Acquired state lock file
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: daemon.go:277: started snapd/2.76.3+ubuntu24.04 (series 16;
classic) ubuntu/24.04 (amd64) linux/6.8.0-1062-gke.
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
kernel: loop6: detected capacity change from 0 to 8
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: daemon.go:371: adjusting startup timeout by 55s (pessimistic
estimate of 30s plus 5s per snap)
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: tmp-syscheck\x2dmountpoint\x2d185014409.mount: Deactivated
successfully.
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: backends.go:70: AppArmor status: apparmor is enabled and all
features are available
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: backend.go:145: reloading profiles for snap-confine
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
kernel: audit: type=1400 audit(1787901978.742:162): apparmor="STATUS"
operation="profile_replace" info="same as current profile, skipping"
profile="unconfined" name="/usr/lib/snapd/snap-confine" pid=12754
comm="apparmor_parser"
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: standby.go:95: will consider standby after: 5s
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Started snapd.service - Snap Daemon.
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.autoimport.service - Auto import assertions from block
devices was skipped because no trigger condition checks were met.
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
dbus-daemon[841]: [system] Activating via systemd: service
name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
requested by ':1.105' (uid=0 pid=12698 comm="/usr/lib/snapd/snapd"
label="unconfined")
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
dbus-daemon[841]: [system] Successfully activated service
'org.freedesktop.timedate1'
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Started systemd-timedated.service - Time & Date Service.
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: snapmgr.go:1675: performing periodic snap downloads cache cleanup
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: cache.go:320: removed 0 entries/ 0 from downloads cache
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: devicemgr.go:1343: skipping boot ok check since it already ran
for boot-id "87bc72e2-b078-4119-9422-2864272a02cf"
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: backend.go:285: reloading profiles of snap-confine provided by
the system snap
Aug 28 07:26:18 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
kernel: audit: type=1400 audit(1787901978.858:163): apparmor="STATUS"
operation="profile_replace" info="same as current profile, skipping"
profile="unconfined" name="/snap/snapd/27738/usr/lib/snapd/snap-confine"
pid=12774 comm="apparm>
Aug 28 07:26:20 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: storehelpers.go:919: cannot refresh snap "snapd": snap has no
updates available
Aug 28 07:26:21 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: cache.go:320: removed 0 entries/ 0 from downloads cache
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Reloading requested from client PID 12852 ('systemctl') (unit
snapd.service)...
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Reloading...
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Reloading finished in 136 ms.
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Mounting snap-snapd-27911.mount - Mount unit for snapd, revision
27911...
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
kernel: loop6: detected capacity change from 0 to 91576
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Mounted snap-snapd-27911.mount - Mount unit for snapd, revision
27911.
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: backend.go:285: reloading profiles of snap-confine provided by
the system snap
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
kernel: audit: type=1400 audit(1787901982.416:164): apparmor="STATUS"
operation="profile_load" profile="unconfined"
name="/snap/snapd/27911/usr/lib/snapd/snap-confine" pid=12908
comm="apparmor_parser"
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: daemon.go:569: gracefully waiting for running hooks
Aug 28 07:26:22 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: daemon.go:571: done waiting for running hooks
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: standby.go:121: standby monitoring stop requested
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: overlord.go:568: Released state lock file
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: daemon.go:648: restarting daemon after update
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snapd[12698]: seclog.go:189: security logger disabled
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.service: Deactivated successfully.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.service: Scheduled restart job, restart counter is at 2.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.service: Start request repeated too quickly.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.service: Failed with result 'start-limit-hit'.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Failed to start snapd.service - Snap Daemon.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.socket: Failed with result 'service-start-limit-hit'.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.service: Triggering OnFailure= dependencies.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.service: Consumed 1.023s CPU time, 67.0M memory peak, 0B
memory swap peak.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.autoimport.service - Auto import assertions from block
devices was skipped because no trigger condition checks were met.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Starting snapd.failure.service - Failure handling of the snapd
snap...
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snap-failure[12914]: cmd_snapd.go:163: found previous revision of snapd snap:
27738
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snap-failure[12914]: cmd_snapd.go:169: stopping snapd socket
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: snapd.seeded.service: Deactivated successfully.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Stopped snapd.seeded.service - Wait until snapd is fully seeded.
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
snap-failure[12914]: cmd_snapd.go:176: restoring invoking snapd from:
/snap/snapd/27738/usr/lib/snapd/snapd
Aug 28 07:26:25 n-lgke-gke-6-8-c4hcpu4-u-qrt-apparmor.c.ubuntu-devel.internal
systemd[1]: Started run-u107.service - /snap/snapd/27738/usr/lib/snapd/snapd.
--
You received this bug notification because you are a member of Ubuntu
Bugs, which is subscribed to Ubuntu.
https://bugs.launchpad.net/bugs/2165135
Title:
test_snapd_snap_with_vendored_apparmor from ubuntu_qrt_apparmor failed
to refresh snapd to latest/beta (2.76.3+ubuntu24.04)
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu-kernel-tests/+bug/2165135/+subscriptions
--
ubuntu-bugs mailing list
[email protected]
https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs