Auto-connected interfaces missing on initial snap install

stgraber · April 4, 2018, 3:47am

We have received reports that when doing snap install lxd on a completely clean system, LXD is installed with none of its interfaces connected.

If the user then does snap remove lxd followed by snap install lxd, the LXD snap then gets all its interfaces properly connected.

Upstream report: https://github.com/lxc/lxd/issues/4403

I’ve confirmed this myself on 3 different systems, all of them completely clean Ubuntu 16.04 installations. As a point of reference, I installed a whole bunch of systems this afternoon (8 hours or so ago) without running into this problem, so something changed since.

stgraber · April 4, 2018, 3:53am

I suspect this has to do with the version of the core snap as I see records of a number of my machines recently upgrading to a new core snap, could it be that it’s this new core snap that’s somehow bad?

stgraber · April 4, 2018, 4:04am

First system, clean Ubuntu 16.04 LTS, installing lxd snap and confirming that no interfaces got connected:

root@vm09:~# lsb_release -a
No LSB modules are available.
Distributor ID:	Ubuntu
Description:	Ubuntu 16.04.4 LTS
Release:	16.04
Codename:	xenial
root@vm09:~# apt update
Hit:1 http://us.archive.ubuntu.com/ubuntu xenial InRelease
Hit:2 http://us.archive.ubuntu.com/ubuntu xenial-updates InRelease
Hit:3 http://us.archive.ubuntu.com/ubuntu xenial-backports InRelease
Hit:4 http://us.archive.ubuntu.com/ubuntu xenial-security InRelease
Reading package lists... Done                     
Building dependency tree       
Reading state information... Done
All packages are up to date.
root@vm09:~# apt dist-upgrade
Reading package lists... Done
Building dependency tree       
Reading state information... Done
Calculating upgrade... Done
0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
root@vm09:~# snap version
snap    2.29.4.2
snapd   2.29.4.2
series  16
ubuntu  16.04
kernel  4.4.0-116-generic
root@vm09:~# snap install lxd
lxd 3.0.0 from 'canonical' installed
root@vm09:~# snap interfaces
Slot                       Plug
:account-control           -
:accounts-service          -
:alsa                      -
:autopilot-introspection   -
:avahi-control             -
:avahi-observe             -
:bluetooth-control         -
:bluez                     -
:broadcom-asic-control     -
:browser-support           -
:camera                    -
:classic-support           -
:core-support              core:core-support-plug
:cups-control              -
:dcdbas-control            -
:desktop                   -
:desktop-legacy            -
:docker-support            -
:firewall-control          -
:framebuffer               -
:fuse-support              -
:gpg-keys                  -
:gpg-public-keys           -
:gpio-memory-control       -
:greengrass-support        -
:gsettings                 -
:hardware-observe          -
:hardware-random-control   -
:hardware-random-observe   -
:home                      -
:io-ports-control          -
:joystick                  -
:kernel-module-control     -
:kubernetes-support        -
:kvm                       -
:libvirt                   -
:locale-control            -
:log-observe               -
:lxd-support               -
:modem-manager             -
:mount-observe             -
:netlink-audit             -
:netlink-connector         -
:network                   -
:network-bind              -
:network-control           -
:network-manager           -
:network-observe           -
:network-setup-control     -
:network-setup-observe     -
:ofono                     -
:opengl                    -
:openvswitch               -
:openvswitch-support       -
:optical-drive             -
:password-manager-service  -
:physical-memory-control   -
:physical-memory-observe   -
:ppp                       -
:process-control           -
:pulseaudio                -
:raw-usb                   -
:removable-media           -
:screen-inhibit-control    -
:shutdown                  -
:snapd-control             -
:ssh-keys                  -
:ssh-public-keys           -
:system-observe            -
:system-trace              -
:time-control              -
:timeserver-control        -
:timezone-control          -
:tpm                       -
:uhid                      -
:unity7                    -
:upower-observe            -
:wayland                   -
:x11                       -
lxd:lxd                    -
-                          lxd:lxd-support
-                          lxd:network
-                          lxd:system-observe
root@vm09:~# snap list
Name  Version    Rev   Tracking  Developer  Notes
core  16-2.32.1  4327  stable    canonical  core
lxd   3.0.0      6492  stable    canonical  -
root@vm09:~#

stgraber · April 4, 2018, 4:05am

Second system, same clean Ubuntu 16.04 LTS, installing core and then install LXD, confirming that interfaces are properly connected in this instance:

root@vm10:~# lsb_release -a
No LSB modules are available.
Distributor ID:	Ubuntu
Description:	Ubuntu 16.04.4 LTS
Release:	16.04
Codename:	xenial
root@vm10:~# apt update
Hit:1 http://us.archive.ubuntu.com/ubuntu xenial InRelease
Hit:2 http://us.archive.ubuntu.com/ubuntu xenial-updates InRelease
Hit:3 http://us.archive.ubuntu.com/ubuntu xenial-backports InRelease
Hit:4 http://us.archive.ubuntu.com/ubuntu xenial-security InRelease
Reading package lists... Done
Building dependency tree       
Reading state information... Done
All packages are up to date.
root@vm10:~# apt dist-upgrade
Reading package lists... Done
Building dependency tree       
Reading state information... Done
Calculating upgrade... Done
0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
root@vm10:~# snap version
snap    2.29.4.2
snapd   2.29.4.2
series  16
ubuntu  16.04
kernel  4.4.0-116-generic
root@vm10:~# snap install core
core 16-2.32.1 from 'canonical' installed
root@vm10:~# snap install lxd
lxd 3.0.0 from 'canonical' installed
root@vm10:~# snap interfaces
Slot                       Plug
:account-control           -
:accounts-service          -
:alsa                      -
:autopilot-introspection   -
:avahi-control             -
:avahi-observe             -
:bluetooth-control         -
:bluez                     -
:broadcom-asic-control     -
:browser-support           -
:camera                    -
:classic-support           -
:core-support              core:core-support-plug
:cups-control              -
:dcdbas-control            -
:desktop                   -
:desktop-legacy            -
:docker-support            -
:firewall-control          -
:framebuffer               -
:fuse-support              -
:gpg-keys                  -
:gpg-public-keys           -
:gpio-memory-control       -
:greengrass-support        -
:gsettings                 -
:hardware-observe          -
:hardware-random-control   -
:hardware-random-observe   -
:home                      -
:io-ports-control          -
:joystick                  -
:kernel-module-control     -
:kubernetes-support        -
:kvm                       -
:libvirt                   -
:locale-control            -
:log-observe               -
:lxd-support               lxd
:modem-manager             -
:mount-observe             -
:netlink-audit             -
:netlink-connector         -
:network                   lxd
:network-bind              -
:network-control           -
:network-manager           -
:network-observe           -
:network-setup-control     -
:network-setup-observe     -
:ofono                     -
:opengl                    -
:openvswitch               -
:openvswitch-support       -
:optical-drive             -
:password-manager-service  -
:physical-memory-control   -
:physical-memory-observe   -
:ppp                       -
:process-control           -
:pulseaudio                -
:raw-usb                   -
:removable-media           -
:screen-inhibit-control    -
:shutdown                  -
:snapd-control             -
:ssh-keys                  -
:ssh-public-keys           -
:system-observe            lxd
:system-trace              -
:time-control              -
:timeserver-control        -
:timezone-control          -
:tpm                       -
:uhid                      -
:unity7                    -
:upower-observe            -
:wayland                   -
:x11                       -
lxd:lxd                    -
root@vm10:~# snap list
Name  Version    Rev   Tracking  Developer  Notes
core  16-2.32.1  4327  stable    canonical  core
lxd   3.0.0      6492  stable    canonical  -
root@vm10:~#

stgraber · April 4, 2018, 4:07am

@mvo @niemeyer any chance we can have someone look at this ASAP?

stgraber · April 4, 2018, 4:13am

And third system, this time to confirm this isn’t a LXD specific issue, same problem with Spotify:

root@vm08:~# lsb_release -a
No LSB modules are available.
Distributor ID:	Ubuntu
Description:	Ubuntu 16.04.4 LTS
Release:	16.04
Codename:	xenial
root@vm08:~# apt update
Hit:1 http://us.archive.ubuntu.com/ubuntu xenial InRelease
Hit:2 http://us.archive.ubuntu.com/ubuntu xenial-updates InRelease
Hit:3 http://us.archive.ubuntu.com/ubuntu xenial-backports InRelease
Hit:4 http://us.archive.ubuntu.com/ubuntu xenial-security InRelease
Reading package lists... Done                     
Building dependency tree       
Reading state information... Done
All packages are up to date.
root@vm08:~# apt dist-upgrade
Reading package lists... Done
Building dependency tree       
Reading state information... Done
Calculating upgrade... Done
0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
root@vm08:~# snap install spotify
spotify 1.0.72.117.g6bd7cc73-35 from 'spotify' installed
root@vm08:~# snap interfaces
Slot                       Plug
:account-control           -
:accounts-service          -
:alsa                      -
:autopilot-introspection   -
:avahi-control             -
:avahi-observe             -
:bluetooth-control         -
:bluez                     -
:broadcom-asic-control     -
:browser-support           -
:camera                    -
:classic-support           -
:core-support              core:core-support-plug
:cups-control              -
:dcdbas-control            -
:desktop                   -
:desktop-legacy            -
:docker-support            -
:firewall-control          -
:framebuffer               -
:fuse-support              -
:gpg-keys                  -
:gpg-public-keys           -
:gpio-memory-control       -
:greengrass-support        -
:gsettings                 -
:hardware-observe          -
:hardware-random-control   -
:hardware-random-observe   -
:home                      -
:io-ports-control          -
:joystick                  -
:kernel-module-control     -
:kubernetes-support        -
:kvm                       -
:libvirt                   -
:locale-control            -
:log-observe               -
:lxd-support               -
:modem-manager             -
:mount-observe             -
:netlink-audit             -
:netlink-connector         -
:network                   -
:network-bind              -
:network-control           -
:network-manager           -
:network-observe           -
:network-setup-control     -
:network-setup-observe     -
:ofono                     -
:opengl                    -
:openvswitch               -
:openvswitch-support       -
:optical-drive             -
:password-manager-service  -
:physical-memory-control   -
:physical-memory-observe   -
:ppp                       -
:process-control           -
:pulseaudio                -
:raw-usb                   -
:removable-media           -
:screen-inhibit-control    -
:shutdown                  -
:snapd-control             -
:ssh-keys                  -
:ssh-public-keys           -
:system-observe            -
:system-trace              -
:time-control              -
:timeserver-control        -
:timezone-control          -
:tpm                       -
:uhid                      -
:unity7                    -
:upower-observe            -
:wayland                   -
:x11                       -
spotify:spotify-mpris      -
-                          spotify:browser-support
-                          spotify:desktop
-                          spotify:gsettings
-                          spotify:home
-                          spotify:mount-observe
-                          spotify:network
-                          spotify:opengl
-                          spotify:pulseaudio
-                          spotify:unity7
-                          spotify:x11

mborzecki · April 4, 2018, 5:31am

More logs with debugs from cloud image: https://paste.ubuntu.com/p/6P4JpMhgPf/

Apr 04 05:13:22 ubuntu systemd[1]: Starting Snappy daemon...
Apr 04 05:13:23 ubuntu snapd[999]: 2018/04/04 05:13:23.664138 daemon.go:290: started snapd/2.28.5 (series 16; classic) ubuntu/16.04 (amd64) linux/4.4.0-103-generic.
Apr 04 05:13:23 ubuntu systemd[1]: Started Snappy daemon.
Apr 04 05:14:40 ubuntu snapd[999]: 2018/04/04 05:14:40.042418 api.go:992: Installing snap "spotify" revision unset
Apr 04 05:17:09 ubuntu snapd[999]: 2018/04/04 05:17:09.592900 snapmgr.go:430: No snaps to auto-refresh found
Apr 04 05:17:11 ubuntu snapd[999]: 2018/04/04 05:17:11.813536 backend.go:145: cannot create host snap-confine apparmor configuration: cannot replace snap-confine apparmor profile: AppArmor parser error for /etc/apparmor.d/snap.core.4327.usr.lib.snapd.snap-confine in /etc/apparmor.d/snap.core.4327.usr.lib.snapd.snap-confine at line 11: Could not open '/var/lib/snapd/apparmor/snap-confine'
Apr 04 05:17:12 ubuntu systemd[1]: snapd.service: Service hold-off time over, scheduling restart.
Apr 04 05:17:12 ubuntu systemd[1]: Stopped Snappy daemon.
Apr 04 05:17:12 ubuntu systemd[1]: Starting Snappy daemon...
Apr 04 05:17:12 ubuntu snapd[1566]: 2018/04/04 05:17:12.217798 cmd.go:203: DEBUG: restarting into "/snap/core/current/usr/lib/snapd/snapd"
Apr 04 05:17:12 ubuntu snapd[1566]: AppArmor status: apparmor is enabled and all features are available
Apr 04 05:17:12 ubuntu snapd[1566]: 2018/04/04 05:17:12.934446 helpers.go:115: error trying to compare the snap system key: system-key missing on disk
Apr 04 05:17:13 ubuntu snapd[1566]: 2018/04/04 05:17:13.530169 daemon.go:323: started snapd/2.32.1 (series 16; classic) ubuntu/16.04 (amd64) linux/4.4.0-103-generic.
Apr 04 05:17:13 ubuntu systemd[1]: Started Snappy daemon.
Apr 04 05:17:13 ubuntu snapd[1566]: 2018/04/04 05:17:13.531004 main.go:75: DEBUG: activation done in 602ms
Apr 04 05:17:13 ubuntu snapd[1566]: 2018/04/04 05:17:13.532664 autorefresh.go:216: DEBUG: Next refresh scheduled for 2018-04-04 08:37:01.191661179 +0000 UTC.
Apr 04 05:17:13 ubuntu snapd[1566]: 2018/04/04 05:17:13.533011 catalogrefresh.go:71: DEBUG: Catalog refresh starting now; next scheduled for 2018-04-05 05:17:13.533005861 +0000 UTC.
Apr 04 05:17:13 ubuntu snapd[1566]: 2018/04/04 05:17:13.533201 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/sections, attempt 1, elapsed time=5.35µs
Apr 04 05:17:14 ubuntu snapd[1566]: 2018/04/04 05:17:14.127962 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/sections finished after 1 retries, elapsed time=594.758232ms, status: 200
Apr 04 05:17:14 ubuntu snapd[1566]: 2018/04/04 05:17:14.133399 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/names?confinement=strict%2Cclassic, attempt 1, elapsed time=22.702µs
Apr 04 05:17:14 ubuntu snapd[1566]: 2018/04/04 05:17:14.968665 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/names?confinement=strict%2Cclassic finished after 1 retries, elapsed time=835.28831ms, status: 200
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.004904 taskrunner.go:403: DEBUG: Running task 8 on Do: Setup snap "core" (4327) security profiles (phase 2)
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.075496 taskrunner.go:403: DEBUG: Running task 9 on Do: Set automatic aliases for snap "core"
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.086925 taskrunner.go:403: DEBUG: Running task 10 on Do: Setup snap "core" aliases
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.095704 taskrunner.go:403: DEBUG: Running task 11 on Do: Run install hook of "core" snap if present
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.106290 taskrunner.go:403: DEBUG: Running task 12 on Do: Start snap "core" (4327) services
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.112931 taskrunner.go:403: DEBUG: Running task 13 on Do: Run configure hook of "core" snap if present
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.119654 taskrunner.go:403: DEBUG: Running task 14 on Do: Download snap "spotify" (6) from channel "stable"
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.134443 store.go:1567: DEBUG: Available deltas returned by store: []
Apr 04 05:17:15 ubuntu snapd[1566]: 2018/04/04 05:17:15.134667 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/download/pOBIoZ2LrCB3rDohMxoYGnbN14EHOgD7_6.snap, attempt 1, elapsed time=6.071µs
Apr 04 05:19:25 ubuntu snapd[1566]: 2018/04/04 05:19:25.742609 taskrunner.go:403: DEBUG: Running task 15 on Do: Fetch and check assertions for snap "spotify" (6)
Apr 04 05:19:26 ubuntu snapd[1566]: 2018/04/04 05:19:26.434507 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/snap-revision/Ll5rE3e1FDy8GnVsdYgju4qHx4F7tv6-5iYgMcjBSeexwiMKCb71vOMeSdiNXDdu?max-format=0, attempt 1, elapsed time=5.22µs
Apr 04 05:19:26 ubuntu snapd[1566]: 2018/04/04 05:19:26.889460 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/snap-revision/Ll5rE3e1FDy8GnVsdYgju4qHx4F7tv6-5iYgMcjBSeexwiMKCb71vOMeSdiNXDdu?max-format=0 finished after 1 retries, elapsed time=454.932567ms, status: 200
Apr 04 05:19:26 ubuntu snapd[1566]: 2018/04/04 05:19:26.890004 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/snap-declaration/16/pOBIoZ2LrCB3rDohMxoYGnbN14EHOgD7?max-format=2, attempt 1, elapsed time=6.242µs
Apr 04 05:19:26 ubuntu snapd[1566]: 2018/04/04 05:19:26.983913 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/snap-declaration/16/pOBIoZ2LrCB3rDohMxoYGnbN14EHOgD7?max-format=2 finished after 1 retries, elapsed time=93.938207ms, status: 200
Apr 04 05:19:26 ubuntu snapd[1566]: 2018/04/04 05:19:26.984492 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/account/Z7emHVerQzTDQrOcaLJ8wO1ir1NPl3fG?max-format=0, attempt 1, elapsed time=32.751µs
Apr 04 05:19:27 ubuntu snapd[1566]: 2018/04/04 05:19:27.084626 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/account/Z7emHVerQzTDQrOcaLJ8wO1ir1NPl3fG?max-format=0 finished after 1 retries, elapsed time=100.164587ms, status: 200
Apr 04 05:19:27 ubuntu snapd[1566]: 2018/04/04 05:19:27.085230 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/account-key/BWDEoaqyr25nF5SNCvEv2v7QnM9QsfCc0PBMYD_i2NGSQ32EF2d4D0hqUel3m8ul?max-format=0, attempt 1, elapsed time=6.983µs
Apr 04 05:19:27 ubuntu snapd[1566]: 2018/04/04 05:19:27.199361 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/account-key/BWDEoaqyr25nF5SNCvEv2v7QnM9QsfCc0PBMYD_i2NGSQ32EF2d4D0hqUel3m8ul?max-format=0 finished after 1 retries, elapsed time=114.140071ms, status: 200
Apr 04 05:19:27 ubuntu snapd[1566]: 2018/04/04 05:19:27.213754 taskrunner.go:403: DEBUG: Running task 16 on Do: Mount snap "spotify" (6)
Apr 04 05:19:27 ubuntu snapd[1566]: 2018/04/04 05:19:27.918280 taskrunner.go:403: DEBUG: Running task 17 on Do: Copy snap "spotify" data
Apr 04 05:19:27 ubuntu snapd[1566]: 2018/04/04 05:19:27.928406 taskrunner.go:403: DEBUG: Running task 18 on Do: Setup snap "spotify" (6) security profiles
Apr 04 05:19:28 ubuntu snapd[1566]: 2018/04/04 05:19:28.041357 taskrunner.go:403: DEBUG: Running task 19 on Do: Make snap "spotify" (6) available to the system
Apr 04 05:19:28 ubuntu snapd[1566]: 2018/04/04 05:19:28.046113 desktop.go:147: DEBUG: ignoring line 6 ("TryExec=spotify") in source of desktop file "spotify_spotify.desktop"
Apr 04 05:19:28 ubuntu snapd[1566]: 2018/04/04 05:19:28.053566 taskrunner.go:403: DEBUG: Running task 20 on Do: Set automatic aliases for snap "spotify"
Apr 04 05:19:28 ubuntu snapd[1566]: 2018/04/04 05:19:28.062350 taskrunner.go:403: DEBUG: Running task 21 on Do: Setup snap "spotify" aliases
Apr 04 05:19:28 ubuntu snapd[1566]: 2018/04/04 05:19:28.074891 taskrunner.go:403: DEBUG: Running task 22 on Do: Run install hook of "spotify" snap if present
Apr 04 05:19:28 ubuntu snapd[1566]: 2018/04/04 05:19:28.081502 taskrunner.go:403: DEBUG: Running task 23 on Do: Start snap "spotify" (6) services
Apr 04 05:19:28 ubuntu snapd[1566]: 2018/04/04 05:19:28.087412 taskrunner.go:403: DEBUG: Running task 24 on Do: Run configure hook of "spotify" snap if present
Apr 04 05:19:28 ubuntu snapd[1566]: 2018/04/04 05:19:28.177563 daemon.go:252: DEBUG: pid=1416;uid=0;socket=/run/snapd.socket;@ GET /v2/snaps?snaps=spotify 675.082µs 200
Apr 04 05:19:35 ubuntu snapd[1566]: 2018/04/04 05:19:35.012628 daemon.go:252: DEBUG: pid=1796;uid=1000;socket=/run/snapd.socket;@ GET /v2/interfaces 309.658µs 200

Same thing did not reproduce on on my host with snapd built from master: https://paste.ubuntu.com/p/hDzWMgY2zb/

Apr 04 07:13:54 galeon snapd[8301]: 2018/04/04 07:13:54.240097 daemon.go:323: started snapd/2.32.2.r508.g629585a3f-1 (series 16; classic; devmode) arch/ (amd64) linux/4.15.14-1-ARCH.
Apr 04 07:13:54 galeon snapd[8301]: 2018/04/04 07:13:54.240240 main.go:75: DEBUG: activation done in 10ms
Apr 04 07:13:54 galeon systemd[1]: Started Snappy daemon.
Apr 04 07:13:54 galeon snapd[8301]: 2018/04/04 07:13:54.240888 autorefresh.go:216: DEBUG: Next refresh scheduled for 2018-04-04 07:13:54.240873465 +0200 CEST m=+0.015020151.
Apr 04 07:13:54 galeon snapd[8301]: 2018/04/04 07:13:54.241114 catalogrefresh.go:71: DEBUG: Catalog refresh starting now; next scheduled for 2018-04-05 07:13:54.241109618 +0200 CEST m=+86400.015256254.
Apr 04 07:13:54 galeon snapd[8301]: 2018/04/04 07:13:54.241153 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/sections, attempt 1, elapsed time=1.963<C2><B5>s
Apr 04 07:13:54 galeon snapd[8301]: 2018/04/04 07:13:54.676191 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/sections finished after 1 retries, elapsed time=435.036291ms, status: 200
Apr 04 07:13:54 galeon snapd[8301]: 2018/04/04 07:13:54.688347 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/names?confinement=strict%2Cclassic, attempt 1, elapsed time=5.681<C2><B5>s
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.325089 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/names?confinement=strict%2Cclassic finished after 1 retries, elapsed time=636.744299ms, status: 200
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.341221 taskrunner.go:403: DEBUG: Running task 25 on Doing: Setup snap "core" (4327) security profiles (phase 2)
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.383251 taskrunner.go:403: DEBUG: Running task 26 on Do: Automatically connect eligible plugs and slots of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.400807 taskrunner.go:403: DEBUG: Running task 33 on Do: Run hook prepare-plug-core-support-plug of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.418751 taskrunner.go:403: DEBUG: Running task 34 on Do: Run hook prepare-slot-core-support of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.434383 taskrunner.go:403: DEBUG: Running task 38 on Do: Run hook prepare-plug-core-support-plug of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.442142 taskrunner.go:403: DEBUG: Running task 32 on Do: Connect core:core-support-plug to core:core-support
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.456932 taskrunner.go:403: DEBUG: Running task 39 on Do: Run hook prepare-slot-core-support of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.557134 taskrunner.go:403: DEBUG: Running task 35 on Do: Run hook connect-slot-core-support of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.564517 taskrunner.go:403: DEBUG: Running task 37 on Do: Connect core:core-support-plug to core:core-support
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.579094 taskrunner.go:403: DEBUG: Running task 36 on Do: Run hook connect-plug-core-support-plug of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.667301 taskrunner.go:403: DEBUG: Running task 40 on Do: Run hook connect-slot-core-support of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.683081 taskrunner.go:403: DEBUG: Running task 41 on Do: Run hook connect-plug-core-support-plug of snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.698362 taskrunner.go:403: DEBUG: Running task 27 on Do: Set automatic aliases for snap "core"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.718967 taskrunner.go:403: DEBUG: Running task 28 on Do: Setup snap "core" aliases
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.739632 taskrunner.go:403: DEBUG: Running task 29 on Do: Run install hook of "core" snap if present
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.755989 taskrunner.go:403: DEBUG: Running task 30 on Do: Start snap "core" (4327) services
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.770482 taskrunner.go:403: DEBUG: Running task 31 on Do: Run configure hook of "core" snap if present
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.784552 taskrunner.go:403: DEBUG: Running task 4 on Do: Download snap "spotify" (6) from channel "stable"
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.791284 store.go:1486: DEBUG: Available deltas returned by store: []
Apr 04 07:13:55 galeon snapd[8301]: 2018/04/04 07:13:55.791349 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/download/pOBIoZ2LrCB3rDohMxoYGnbN14EHOgD7_6.snap, attempt 1, elapsed time=4.659<C2><B5>s
Apr 04 07:17:11 galeon snapd[8301]: 2018/04/04 07:17:11.852953 taskrunner.go:403: DEBUG: Running task 5 on Do: Fetch and check assertions for snap "spotify" (6)
Apr 04 07:17:12 galeon snapd[8301]: 2018/04/04 07:17:12.552547 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/snap-revision/Ll5rE3e1FDy8GnVsdYgju4qHx4F7tv6-5iYgMcjBSeexwiMKCb71vOMeSdiNXDdu?max-format=0, attempt 1, elapsed time=6.522<C2><B5>s
Apr 04 07:17:12 galeon snapd[8301]: 2018/04/04 07:17:12.881464 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/snap-revision/Ll5rE3e1FDy8GnVsdYgju4qHx4F7tv6-5iYgMcjBSeexwiMKCb71vOMeSdiNXDdu?max-format=0 finished after 1 retries, elapsed time=328.922524ms, status: 200
Apr 04 07:17:12 galeon snapd[8301]: 2018/04/04 07:17:12.881570 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/snap-declaration/16/pOBIoZ2LrCB3rDohMxoYGnbN14EHOgD7?max-format=2, attempt 1, elapsed time=4.308<C2><B5>s
Apr 04 07:17:12 galeon snapd[8301]: 2018/04/04 07:17:12.978997 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/snap-declaration/16/pOBIoZ2LrCB3rDohMxoYGnbN14EHOgD7?max-format=2 finished after 1 retries, elapsed time=97.43143ms, status: 200
Apr 04 07:17:12 galeon snapd[8301]: 2018/04/04 07:17:12.979066 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/account/Z7emHVerQzTDQrOcaLJ8wO1ir1NPl3fG?max-format=0, attempt 1, elapsed time=2.685<C2><B5>s
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.078474 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/account/Z7emHVerQzTDQrOcaLJ8wO1ir1NPl3fG?max-format=0 finished after 1 retries, elapsed time=99.404827ms, status: 200
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.078578 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/assertions/account-key/BWDEoaqyr25nF5SNCvEv2v7QnM9QsfCc0PBMYD_i2NGSQ32EF2d4D0hqUel3m8ul?max-format=0, attempt 1, elapsed time=4.128<C2><B5>s
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.179538 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/assertions/account-key/BWDEoaqyr25nF5SNCvEv2v7QnM9QsfCc0PBMYD_i2NGSQ32EF2d4D0hqUel3m8ul?max-format=0 finished after 1 retries, elapsed time=100.962031ms, status: 200
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.211794 taskrunner.go:403: DEBUG: Running task 6 on Do: Mount snap "spotify" (6)
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.722032 taskrunner.go:403: DEBUG: Running task 7 on Do: Copy snap "spotify" data
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.737811 taskrunner.go:403: DEBUG: Running task 8 on Do: Setup snap "spotify" (6) security profiles
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.848304 taskrunner.go:403: DEBUG: Running task 9 on Do: Make snap "spotify" (6) available to the system
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.859082 desktop.go:147: DEBUG: ignoring line 6 ("TryExec=spotify") in source of desktop file "spotify_spotify.desktop"
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.866897 desktop.go:182: DEBUG: update-desktop-database successful
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.884609 taskrunner.go:403: DEBUG: Running task 10 on Do: Automatically connect eligible plugs and slots of snap "spotify"
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.908579 taskrunner.go:403: DEBUG: Running task 53 on Do: Run hook prepare-plug-gsettings of snap "spotify"
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.930099 taskrunner.go:403: DEBUG: Running task 73 on Do: Run hook prepare-plug-pulseaudio of snap "spotify"
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.930164 taskrunner.go:403: DEBUG: Running task 54 on Do: Run hook prepare-slot-gsettings of snap "core"
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.947714 taskrunner.go:403: DEBUG: Running task 78 on Do: Run hook prepare-plug-unity7 of snap "spotify"
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.970574 taskrunner.go:403: DEBUG: Running task 43 on Do: Run hook prepare-plug-browser-support of snap "spotify"
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.970623 taskrunner.go:403: DEBUG: Running task 74 on Do: Run hook prepare-slot-pulseaudio of snap "core"
Apr 04 07:17:13 galeon snapd[8301]: 2018/04/04 07:17:13.978844 taskrunner.go:403: DEBUG: Running task 52 on Do: Connect spotify:gsettings to core:gsettings
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.002472 taskrunner.go:403: DEBUG: Running task 58 on Do: Run hook prepare-plug-home of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.002511 taskrunner.go:403: DEBUG: Running task 79 on Do: Run hook prepare-slot-unity7 of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.032665 taskrunner.go:403: DEBUG: Running task 48 on Do: Run hook prepare-plug-desktop of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.032747 taskrunner.go:403: DEBUG: Running task 44 on Do: Run hook prepare-slot-browser-support of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.064900 taskrunner.go:403: DEBUG: Running task 68 on Do: Run hook prepare-plug-opengl of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.064958 taskrunner.go:403: DEBUG: Running task 59 on Do: Run hook prepare-slot-home of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.091491 taskrunner.go:403: DEBUG: Running task 49 on Do: Run hook prepare-slot-desktop of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.091526 taskrunner.go:403: DEBUG: Running task 63 on Do: Run hook prepare-plug-network of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.139536 taskrunner.go:403: DEBUG: Running task 55 on Do: Run hook connect-slot-gsettings of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.139596 taskrunner.go:403: DEBUG: Running task 83 on Do: Run hook prepare-plug-x11 of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.148703 taskrunner.go:403: DEBUG: Running task 47 on Do: Connect spotify:desktop to core:desktop
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.182680 taskrunner.go:403: DEBUG: Running task 69 on Do: Run hook prepare-slot-opengl of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.182821 taskrunner.go:403: DEBUG: Running task 56 on Do: Run hook connect-plug-gsettings of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.210821 taskrunner.go:403: DEBUG: Running task 84 on Do: Run hook prepare-slot-x11 of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.227774 taskrunner.go:403: DEBUG: Running task 64 on Do: Run hook prepare-slot-network of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.293619 taskrunner.go:403: DEBUG: Running task 50 on Do: Run hook connect-slot-desktop of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.302320 taskrunner.go:403: DEBUG: Running task 42 on Do: Connect spotify:browser-support to core:browser-support
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.316745 taskrunner.go:403: DEBUG: Running task 51 on Do: Run hook connect-plug-desktop of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.416680 taskrunner.go:403: DEBUG: Running task 45 on Do: Run hook connect-slot-browser-support of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.429119 taskrunner.go:403: DEBUG: Running task 62 on Do: Connect spotify:network to core:network
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.446379 taskrunner.go:403: DEBUG: Running task 46 on Do: Run hook connect-plug-browser-support of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.542406 taskrunner.go:403: DEBUG: Running task 65 on Do: Run hook connect-slot-network of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.558270 taskrunner.go:403: DEBUG: Running task 77 on Do: Connect spotify:unity7 to core:unity7
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.577685 taskrunner.go:403: DEBUG: Running task 66 on Do: Run hook connect-plug-network of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.662063 taskrunner.go:403: DEBUG: Running task 80 on Do: Run hook connect-slot-unity7 of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.670265 taskrunner.go:403: DEBUG: Running task 67 on Do: Connect spotify:opengl to core:opengl
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.686514 taskrunner.go:403: DEBUG: Running task 81 on Do: Run hook connect-plug-unity7 of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.844608 taskrunner.go:403: DEBUG: Running task 70 on Do: Run hook connect-slot-opengl of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.853483 taskrunner.go:403: DEBUG: Running task 82 on Do: Connect spotify:x11 to core:x11
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.870752 taskrunner.go:403: DEBUG: Running task 71 on Do: Run hook connect-plug-opengl of snap "spotify"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.984366 taskrunner.go:403: DEBUG: Running task 85 on Do: Run hook connect-slot-x11 of snap "core"
Apr 04 07:17:14 galeon snapd[8301]: 2018/04/04 07:17:14.995952 taskrunner.go:403: DEBUG: Running task 57 on Do: Connect spotify:home to core:home
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.014134 taskrunner.go:403: DEBUG: Running task 86 on Do: Run hook connect-plug-x11 of snap "spotify"
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.105112 taskrunner.go:403: DEBUG: Running task 60 on Do: Run hook connect-slot-home of snap "core"
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.114105 taskrunner.go:403: DEBUG: Running task 72 on Do: Connect spotify:pulseaudio to core:pulseaudio
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.130636 taskrunner.go:403: DEBUG: Running task 61 on Do: Run hook connect-plug-home of snap "spotify"
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.230484 taskrunner.go:403: DEBUG: Running task 75 on Do: Run hook connect-slot-pulseaudio of snap "core"
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.251461 taskrunner.go:403: DEBUG: Running task 76 on Do: Run hook connect-plug-pulseaudio of snap "spotify"
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.270333 taskrunner.go:403: DEBUG: Running task 11 on Do: Set automatic aliases for snap "spotify"
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.301507 taskrunner.go:403: DEBUG: Running task 12 on Do: Setup snap "spotify" aliases
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.325037 taskrunner.go:403: DEBUG: Running task 13 on Do: Run install hook of "spotify" snap if present
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.345477 taskrunner.go:403: DEBUG: Running task 14 on Do: Start snap "spotify" (6) services
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.360823 taskrunner.go:403: DEBUG: Running task 15 on Do: Run configure hook of "spotify" snap if present
Apr 04 07:17:15 galeon snapd[8301]: 2018/04/04 07:17:15.391700 daemon.go:252: DEBUG: pid=7853;uid=1000;socket=/run/snapd.socket;@ GET /v2/snaps?snaps=spotify 1.052546ms 200
Apr 04 07:17:43 galeon snapd[8301]: 2018/04/04 07:17:43.282811 retry.go:40: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/details/spotify?channel=&fields=anon_download_url%2Carchitecture%2Cchannel%2Cdownload_sha3_384%2Csummary%2Cdescription%2Cdeltas%2Cbinary_filesize%2Cdownload_url%2Cepoch%2Cicon_url%2Clast_updated%2Cpackage_name%2Cprices%2Cpublisher%2Cratings_average%2Crevision%2Cscreenshot_urls%2Csnap_id%2Clicense%2Cbase%2Csupport_url%2Ccontact%2Ctitle%2Ccontent%2>
Apr 04 07:17:44 galeon snapd[8301]: 2018/04/04 07:17:44.250952 retry.go:52: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/details/spotify?channel=&fields=anon_download_url%2Carchitecture%2Cchannel%2Cdownload_sha3_384%2Csummary%2Cdescription%2Cdeltas%2Cbinary_filesize%2Cdownload_url%2Cepoch%2Cicon_url%2Clast_updated%2Cpackage_name%2Cprices%2Cpublisher%2Cratings_average%2Crevision%2Cscreenshot_urls%2Csnap_id%2Clicense%2Cbase%2Csupport_url%2Ccontact%2Ctitle%2>
Apr 04 07:17:44 galeon snapd[8301]: 2018/04/04 07:17:44.251342 daemon.go:252: DEBUG: pid=8954;uid=1000;socket=/run/snapd.socket;@ GET /v2/find?name=spotify 968.682957ms 200
Apr 04 07:17:44 galeon snapd[8301]: 2018/04/04 07:17:44.252506 daemon.go:252: DEBUG: pid=8954;uid=1000;socket=/run/snapd.socket;@ GET /v2/snaps/spotify 525.397<C2><B5>s 200

mborzecki · April 4, 2018, 5:44am

From what I understand, the task list was created using the snapd from the original image, which did not create the auto-connect task. During install a new core gets pulled in, snapd restarts & reexecs and proceeds to execute the task list. But at no point the list of tasks gets updated, thus auto-connect task is never added.

@pstolowski @mvo does this sound sane?

As a workaround, refreshing core first, before installing any snaps, should do the trick.

mvo · April 4, 2018, 6:19am

I think you are correct here, when starting with a snapd without auto-connect task (like what we have in xenial-updates right now) the following scenario does break right now:

no snaps installed yet
core and another snap S are install in the same transaction
old snapd will create the list of tasks, it will create a “setup-profiles task” but not “auto-connect” task
new snapd gets installed and is re-execd into in “link-snap”. It will continue with the install of S but the new code does only “half” the work that “setup-profile” and the other half in “auto-connect”. But “auto-connect” is missing.

We need to add compat code into the new snapd “setup-profiles” task to ensure that this is handled correctly. Probably best to scan the tasks and inject the missing task from there.

pstolowski · April 4, 2018, 7:16am

Yes, I think you’re right. I’ll prepare a fix, thanks for digging into it.

hwsiew · April 4, 2018, 8:41am

Meanwhile waiting for the fix, may i know how can i re-connect back with my lxd again?

pstolowski · April 4, 2018, 8:49am

Do you mean have the interfaces connected? You can try what @mborzecki suggested or just manually connect all lxd interfaces via snap connect ....

hwsiew · April 4, 2018, 9:05am

Hi @pstolowski, I am trying to get my lxd connected again. I tried

snap connect lxd:lxd-support core:lxd-support

but it shows an error

error: snap "lxd" has no plug named "lxd-support"

And, i am not longer accessible to my lxd.

Thanks

zyga-snapd · April 4, 2018, 9:07am

Can you please paste the contents of /snap/lxd/current/meta/snap.yaml and the output of snap interfaces

hwsiew · April 4, 2018, 9:10am

Sure. Here you go

> cat /snap/lxd/current/meta/snap.yaml

name: lxd
version: 3.0.0
summary: LXD - the container lightervisor
description: |-
  LXD is a container manager for system containers.

  It offers a REST API to remotely manage containers over the network,
  using an image based workflow and with support for live migration.

  Images are available for all Ubuntu releases and architectures as well
  as for a wide number of other Linux distributions.

  LXD containers are lightweight, secure by default and a great
  alternative to virtual machines.

  Supported configuration options (snap set lxd [<key>=<value>...]):
    - criu.enable: Enable experimental live-migration support [default=false]
    - daemon.debug: Increases logging to debug level [default=false]
    - daemon.group: Group of users that can interact with LXD [default=lxd]
    - ceph.builtin: Use snap-specific ceph configuration [default=false]
    - openvswitch.builtin: Run a snap-specific OVS daemon [default=false]
    - waitready.timeout: How long to wait for LXD to be ready [default=600]
architectures:
- amd64
confinement: strict
grade: stable
hooks:
  configure:
    plugs:
    - network
apps:
apps:
  benchmark:
    command: command-benchmark.wrapper
    plugs:
    - lxd-support
    - system-observe
  check-kernel:
    command: command-check-kernel.wrapper
    plugs:
    - lxd-support
    - system-observe
  daemon:
    command: command-daemon.wrapper
    daemon: simple
    plugs:
    - lxd-support
    - system-observe
    reload-command: commands/daemon.reload
    restart-condition: always
    slots:
    - lxd
    stop-command: stop-command-daemon.wrapper
    stop-timeout: 600s
  database:
    command: command-database.wrapper
    plugs:
    - lxd-support
    - system-observe
  lxc:
    command: command-lxc.wrapper
    completer: etc/bash_completion.d/snap.lxd.lxc
    plugs:
    - lxd-support
    - system-observe
  lxd:
    command: command-lxd.wrapper
    plugs:
    - lxd-support
    - system-observe
  migrate:
    command: command-migrate.wrapper
    plugs:
    - lxd-support
    - system-observe

> snap interfaces

Slot                       Plug
:account-control           -
:accounts-service          -
:alsa                      -
:autopilot-introspection   -
:avahi-control             -
:avahi-observe             -
:bluetooth-control         -
:bluez                     -
:broadcom-asic-control     -
:browser-support           -
:camera                    -
:classic-support           -
:core-support              core:core-support-plug
:cups-control              -
:dcdbas-control            -
:desktop                   -
:desktop-legacy            -
:docker-support            -
:firewall-control          -
:framebuffer               -
:fuse-support              -
:gpg-keys                  -
:gpg-public-keys           -
:gpio-memory-control       -
:greengrass-support        -
:gsettings                 -
:hardware-observe          -
:hardware-random-control   -
:hardware-random-observe   -
:home                      -
:io-ports-control          -
:joystick                  -
:kernel-module-control     -
:kubernetes-support        -
:kvm                       -
:libvirt                   -
:home                      -
:locale-control            -
:log-observe               -
:lxd-support               -
:modem-manager             -
:mount-observe             -
:netlink-audit             -
:netlink-connector         -
:network                   -
:network-bind              -
:network-control           -
:network-manager           -
:network-observe           -
:network-setup-control     -
:network-setup-observe     -
:ofono                     -
:opengl                    -
:openvswitch               -
:openvswitch-support       -
:optical-drive             -
:password-manager-service  -
:physical-memory-control   -
:physical-memory-observe   -
:ppp                       -
:process-control           -
:pulseaudio                -
:raw-usb                   -
:removable-media           -
:screen-inhibit-control    -
:shutdown                  -
:snapd-control             -
:ssh-keys                  -
:ssh-public-keys           -
:system-observe            -
:system-trace              -
:time-control              -
:timeserver-control        -
:timezone-control          -
:tpm                       -
:uhid                      -
:unity7                    -
:upower-observe            -
:wayland                   -
:x11                       -

zyga-snapd · April 4, 2018, 9:31am

Woah, that’s the smoking gun. Thank you!

CC @pstolowski look we didn’t load interfaces at all there!

pstolowski · April 4, 2018, 11:18am

Interestingly, I cannot reproduce the issue of missing lxd plugs (testing on clean xenial where I easily reproduce the original problem of auto-connect).

@hwsiew Could you please send me your /var/lib/snapd/state.json via private message? You may need to stop snapd before making a copy of this file; also you may want to remove macaroon string from the copy before sending it to me. Thanks!

stgraber · April 4, 2018, 6:00pm

What are we looking as far as timeline to unblock new installs?

I have a large number of automated tests that are all failing here because of this issue and I’m getting a lot of e-mails from users that can’t get LXD working by following our instructions…

Can the core snap be reverted or something?

pstolowski · April 4, 2018, 6:38pm

The tentative fix for auto-connect issue has a PR https://github.com/snapcore/snapd/pull/4981 and should be available tomorrow, we need to test it from edge first.

The other issue mentioned above (with lxd plugs missing) is a different problem and slightly more complicated, it’s being discussed still on IRC.

UPDATE: see Summary of core + lxd refresh bugs discovered on 4th of April 2018

pstolowski · April 6, 2018, 7:51am

There was one more PR needed to fix this issue, we’ve landed and verified it in edge yesterday and it’s now in 2.32.3 which is in beta (see the other thread I linked above for details).