Running "multipass" failed: timeout waiting for snap system profiles to get updated

Hello! When I try to launch multipass with multipass launch, I’m having the following error:

running "multipass" failed: timeout waiting for snap system profiles to get updated

Informations about my setup:

  • WSL2
  • Ubuntu 23.04 (all updates are done)
  • Multipass running on this snap.
  • Snap version 2.59.1+23.04ubuntu1.2

I can provide any information about my setup on request! Thanks for helping.

Here is the output of journalctl -u snapd.service --no-pager -n 100:

Dec 28 22:03:00 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 28 22:03:03 pfc snapd[202]: overlord.go:272: Acquiring state lock file
Dec 28 22:03:03 pfc snapd[202]: overlord.go:277: Acquired state lock file
Dec 28 22:03:03 pfc snapd[202]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 28 22:03:03 pfc snapd[202]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 28 22:03:03 pfc snapd[202]: backends.go:58: AppArmor status: apparmor not enabled
Dec 28 22:03:03 pfc systemd[1]: Started snapd.service - Snap Daemon.
Dec 28 22:08:04 pfc snapd[202]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
-- Boot 6214832159a44635a3929b7e5841418e --
Dec 28 23:04:55 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 28 23:04:58 pfc snapd[208]: overlord.go:272: Acquiring state lock file
Dec 28 23:04:58 pfc snapd[208]: overlord.go:277: Acquired state lock file
Dec 28 23:04:58 pfc snapd[208]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 28 23:04:58 pfc snapd[208]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 28 23:04:58 pfc snapd[208]: backends.go:58: AppArmor status: apparmor not enabled
Dec 28 23:04:59 pfc systemd[1]: Started snapd.service - Snap Daemon.
-- Boot bafb0d6f7f044330b1c84a865ba61d3b --
Dec 29 00:14:04 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 29 00:14:07 pfc snapd[195]: overlord.go:272: Acquiring state lock file
Dec 29 00:14:07 pfc snapd[195]: overlord.go:277: Acquired state lock file
Dec 29 00:14:07 pfc snapd[195]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 29 00:14:07 pfc snapd[195]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 29 00:14:07 pfc snapd[195]: backends.go:58: AppArmor status: apparmor not enabled
Dec 29 00:14:07 pfc systemd[1]: Started snapd.service - Snap Daemon.
-- Boot aa74879de5674d1f8264b79cb73cfdbc --
Dec 29 01:17:19 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 29 01:17:22 pfc snapd[196]: overlord.go:272: Acquiring state lock file
Dec 29 01:17:22 pfc snapd[196]: overlord.go:277: Acquired state lock file
Dec 29 01:17:22 pfc snapd[196]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 29 01:17:22 pfc snapd[196]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 29 01:17:22 pfc snapd[196]: backends.go:58: AppArmor status: apparmor not enabled
Dec 29 01:17:22 pfc systemd[1]: Started snapd.service - Snap Daemon.
Dec 29 01:22:24 pfc snapd[196]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
Dec 29 01:27:38 pfc snapd[196]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
-- Boot 4e6211449ce04737981a93ce990fe61e --
Dec 29 21:16:03 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 29 21:16:06 pfc snapd[216]: overlord.go:272: Acquiring state lock file
Dec 29 21:16:06 pfc snapd[216]: overlord.go:277: Acquired state lock file
Dec 29 21:16:06 pfc snapd[216]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 29 21:16:06 pfc snapd[216]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 29 21:16:06 pfc snapd[216]: backends.go:58: AppArmor status: apparmor not enabled
Dec 29 21:16:06 pfc systemd[1]: Started snapd.service - Snap Daemon.
Dec 29 21:21:09 pfc snapd[216]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
Dec 29 21:26:26 pfc snapd[216]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
-- Boot eed4009acde84b198e3e3a4f2d5b9b2b --
Dec 30 13:34:21 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 30 13:34:25 pfc snapd[211]: overlord.go:272: Acquiring state lock file
Dec 30 13:34:25 pfc snapd[211]: overlord.go:277: Acquired state lock file
Dec 30 13:34:25 pfc snapd[211]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 30 13:34:25 pfc snapd[211]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 30 13:34:25 pfc snapd[211]: backends.go:58: AppArmor status: apparmor not enabled
Dec 30 13:34:25 pfc systemd[1]: Started snapd.service - Snap Daemon.
Dec 30 13:39:27 pfc snapd[211]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
Dec 30 13:44:37 pfc snapd[211]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
-- Boot a60659a06f9742c18866408c1d5b7aea --
Dec 30 15:58:42 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 30 15:58:46 pfc snapd[211]: overlord.go:272: Acquiring state lock file
Dec 30 15:58:46 pfc snapd[211]: overlord.go:277: Acquired state lock file
Dec 30 15:58:46 pfc snapd[211]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 30 15:58:46 pfc snapd[211]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 30 15:58:46 pfc snapd[211]: backends.go:58: AppArmor status: apparmor not enabled
Dec 30 15:58:46 pfc systemd[1]: Started snapd.service - Snap Daemon.
Dec 30 16:08:58 pfc snapd[211]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
-- Boot 1d45f0ab2efd411597823e49aaeff739 --
Dec 31 13:09:50 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 31 13:09:54 pfc snapd[217]: overlord.go:272: Acquiring state lock file
Dec 31 13:09:54 pfc snapd[217]: overlord.go:277: Acquired state lock file
Dec 31 13:09:54 pfc snapd[217]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 31 13:09:54 pfc snapd[217]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 31 13:09:54 pfc snapd[217]: backends.go:58: AppArmor status: apparmor not enabled
Dec 31 13:09:54 pfc systemd[1]: Started snapd.service - Snap Daemon.
Dec 31 13:14:56 pfc snapd[217]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
-- Boot 1e2e79c8a1954f6492fcca643eccb71c --
Dec 31 13:53:15 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 31 13:53:18 pfc snapd[216]: overlord.go:272: Acquiring state lock file
Dec 31 13:53:18 pfc snapd[216]: overlord.go:277: Acquired state lock file
Dec 31 13:53:18 pfc snapd[216]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 31 13:53:18 pfc snapd[216]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 31 13:53:19 pfc snapd[216]: backends.go:58: AppArmor status: apparmor not enabled
Dec 31 13:53:19 pfc systemd[1]: Started snapd.service - Snap Daemon.
-- Boot 78770a45cdbb4055a3acce8ee61eb515 --
Dec 31 21:33:02 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 31 21:33:05 pfc snapd[207]: overlord.go:272: Acquiring state lock file
Dec 31 21:33:05 pfc snapd[207]: overlord.go:277: Acquired state lock file
Dec 31 21:33:05 pfc snapd[207]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 31 21:33:05 pfc snapd[207]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 31 21:33:05 pfc snapd[207]: backends.go:58: AppArmor status: apparmor not enabled
Dec 31 21:33:05 pfc systemd[1]: Started snapd.service - Snap Daemon.
-- Boot edeb671731cb43cfab0303d297b484f4 --
Dec 31 22:39:31 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 31 22:39:34 pfc snapd[210]: overlord.go:272: Acquiring state lock file
Dec 31 22:39:34 pfc snapd[210]: overlord.go:277: Acquired state lock file
Dec 31 22:39:34 pfc snapd[210]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 31 22:39:34 pfc snapd[210]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 31 22:39:34 pfc snapd[210]: backends.go:58: AppArmor status: apparmor not enabled
Dec 31 22:39:34 pfc systemd[1]: Started snapd.service - Snap Daemon.
Dec 31 22:44:36 pfc snapd[210]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "snapd", "ubuntu-desktop-installer"
-- Boot 9ae76ca2a70d4905b7a74135226c6bed --
Dec 31 23:01:20 pfc systemd[1]: Starting snapd.service - Snap Daemon...
Dec 31 23:01:23 pfc snapd[211]: overlord.go:272: Acquiring state lock file
Dec 31 23:01:23 pfc snapd[211]: overlord.go:277: Acquired state lock file
Dec 31 23:01:24 pfc snapd[211]: daemon.go:247: started snapd/2.60.4 (series 16; classic; devmode; wsl) ubuntu/23.04 (amd64) linux/5.15.133.1-microsoft-stan.
Dec 31 23:01:24 pfc snapd[211]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Dec 31 23:01:24 pfc snapd[211]: backends.go:58: AppArmor status: apparmor not enabled
Dec 31 23:01:24 pfc systemd[1]: Started snapd.service - Snap Daemon.
Dec 31 23:01:35 pfc snapd[211]: api_snaps.go:412: Installing snap "multipass" revision unset
Dec 31 23:01:47 pfc modprobe[957]: FATAL: Module arp_tables not found in directory /lib/modules/5.15.133.1-microsoft-standard-WSL2
Dec 31 23:01:47 pfc modprobe[958]: FATAL: Module br_netfilter not found in directory /lib/modules/5.15.133.1-microsoft-standard-WSL2
Dec 31 23:01:47 pfc modprobe[959]: FATAL: Module ip6table_filter not found in directory /lib/modules/5.15.133.1-microsoft-standard-WSL2
Dec 31 23:11:33 pfc snapd[211]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "multipass", "snapd", "ubuntu-desktop-installer"
Jan 01 00:51:24 pfc snapd[211]: storehelpers.go:773: cannot refresh: snap has no updates available: "bare", "core22", "gtk-common-themes", "multipass", "snapd", "ubuntu-desktop-installer"

Can you run SNAPD_DEBUG=1 snap run multipass launch and attach the output?

Hi! Thanks for your answer. Here is the output:

2024/01/03 01:44:26.970825 logger.go:92: DEBUG: -- snap startup {"stage":"start", "time":"1704235466.970819"}
2024/01/03 01:44:26.991544 logger.go:92: DEBUG: system key mismatch detected, waiting for snapd to start responding...
error: timeout waiting for snap system profiles to get updated

By the way, I upgraded to Ubuntu 23.10 to see if problem still persists. New snap version installed is: 2.60.4+23.10.1

Edit: I figured out the problem.

  1. Snap service was down.
  2. Even on reboot, It wasn’t properly started in my case.
  3. Re-enabled systemd support (in /etc/wsl2.conf), manually started snap service and it’s up!

But at first occasion, I think it wasn’t about systemd. Probably snapd was down and waiting to be restarted manually.

@mborzecki1 Thanks for your attention! I think this thread can be marked as solved.