Snapd killed by watchdog in VM with low specs

[ijohnson]

Hi,

Not sure what info will be helpful here, but while running ubuntu 18.04.2 inside a multipass VM with default settings on a low-end Macbook 12" after installing a snap with a lot of systemd services that allocates a bunch of RAM in the system, snapd gets killed by the watchdog timer:

Mar 29 19:18:30 dashing-oilbird systemd[1]: Starting Snappy daemon...
Mar 29 19:18:47 dashing-oilbird snapd[818]: AppArmor status: apparmor is enabled and all features are available
Mar 29 19:18:48 dashing-oilbird snapd[818]: helpers.go:145: error trying to compare the snap system key: system-key missing on disk
Mar 29 19:18:49 dashing-oilbird snapd[818]: daemon.go:379: started snapd/2.37.4+18.04 (series 16; classic) ubuntu/18.04 (amd64) linux/4.15.0-46-generic.
Mar 29 19:18:49 dashing-oilbird systemd[1]: Started Snappy daemon.
Mar 29 19:19:04 dashing-oilbird snapd[818]: daemon.go:611: gracefully waiting for running hooks
Mar 29 19:19:04 dashing-oilbird snapd[818]: daemon.go:613: done waiting for running hooks
Mar 29 19:19:04 dashing-oilbird snapd[818]: daemon stop requested to wait for socket activation
Mar 29 19:22:54 dashing-oilbird systemd[1]: Starting Snappy daemon...
Mar 29 19:22:54 dashing-oilbird snapd[3312]: AppArmor status: apparmor is enabled and all features are available
Mar 29 19:22:55 dashing-oilbird snapd[3312]: daemon.go:379: started snapd/2.37.4+18.04 (series 16; classic) ubuntu/18.04 (amd64) linux/4.15.0-46-generic.
Mar 29 19:22:55 dashing-oilbird systemd[1]: Started Snappy daemon.
Mar 29 19:22:55 dashing-oilbird snapd[3312]: daemon.go:191: polkit error: Authorization requires interaction
Mar 29 19:22:58 dashing-oilbird snapd[3312]: api.go:1077: Installing snap "core" revision unset
Mar 29 19:23:31 dashing-oilbird snapd[3312]: link.go:75: cannot update fontconfig cache: cannot get fc-cache-v6 from core: open /snap/core/current/bin/fc-cache-v6: no such file or directory
Mar 29 19:23:31 dashing-oilbird snapd[3312]: daemon.go:611: gracefully waiting for running hooks
Mar 29 19:23:31 dashing-oilbird snapd[3312]: daemon.go:613: done waiting for running hooks
Mar 29 19:23:32 dashing-oilbird systemd[1]: snapd.service: Service hold-off time over, scheduling restart.
Mar 29 19:23:32 dashing-oilbird systemd[1]: snapd.service: Scheduled restart job, restart counter is at 1.
Mar 29 19:23:32 dashing-oilbird systemd[1]: Stopped Snappy daemon.
Mar 29 19:23:32 dashing-oilbird systemd[1]: Starting Snappy daemon...
Mar 29 19:23:32 dashing-oilbird snapd[3489]: AppArmor status: apparmor is enabled and all features are available
Mar 29 19:23:34 dashing-oilbird snapd[3489]: AppArmor status: apparmor is enabled and all features are available
Mar 29 19:23:35 dashing-oilbird snapd[3489]: daemon.go:379: started snapd/2.38 (series 16; classic) ubuntu/18.04 (amd64) linux/4.15.0-46-generic.
Mar 29 19:23:35 dashing-oilbird systemd[1]: Started Snappy daemon.
Mar 29 19:23:49 dashing-oilbird snapd[3489]: daemon.go:191: polkit error: Authorization requires interaction
Mar 29 19:23:54 dashing-oilbird snapd[3489]: api.go:1071: Installing snap "edgexfoundry" revision unset
Mar 29 19:46:53 dashing-oilbird systemd[1]: snapd.service: Watchdog timeout (limit 5min)!
Mar 29 19:46:53 dashing-oilbird systemd[1]: snapd.service: Killing process 3489 (snapd) with signal SIGABRT.
Mar 29 19:48:24 dashing-oilbird systemd[1]: snapd.service: State 'stop-sigabrt' timed out. Terminating.
Mar 29 19:48:56 dashing-oilbird snapd[3489]: SIGABRT: abort
Mar 29 19:49:05 dashing-oilbird snapd[3489]: PC=0x564bc22f4111 m=0 sigcode=0
Mar 29 19:49:11 dashing-oilbird snapd[3489]: goroutine 0 [idle]:
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.futex(0x564bc320ee28, 0x0, 0x0, 0x0, 0x7fff00000000, 0x564bc22cbb5a, 0x0, 0x0, 0x7fff62f481a8, 0x564bc22ab09f, ...)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/sys_linux_amd64.s:530 +0x21
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.futexsleep(0x564bc320ee28, 0x7fff00000000, 0xffffffffffffffff)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/os_linux.go:45 +0x4b
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.notesleep(0x564bc320ee28)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/lock_futex.go:151 +0x9f
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.stoplockedm()
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/proc.go:2101 +0x90
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.schedule()
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/proc.go:2493 +0x2de
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.park_m(0xc420196600)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/proc.go:2604 +0xbc
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.mcall(0x564bc2907870)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/asm_amd64.s:351 +0x53
Mar 29 19:49:13 dashing-oilbird snapd[3489]: goroutine 1 [select, 25 minutes]:
Mar 29 19:49:13 dashing-oilbird snapd[3489]: main.run(0xc42026c8a0, 0x0, 0x0)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/cmd/snapd/main.go:145 +0x4e2
Mar 29 19:49:13 dashing-oilbird snapd[3489]: main.main()
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/cmd/snapd/main.go:58 +0xd7
Mar 29 19:49:13 dashing-oilbird snapd[3489]: goroutine 5 [runnable]:
Mar 29 19:49:13 dashing-oilbird snapd[3489]: os/signal.signal_recv(0x0)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/sigqueue.go:139 +0xa8
Mar 29 19:49:13 dashing-oilbird snapd[3489]: os/signal.loop()
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/os/signal/signal_unix.go:22 +0x24
Mar 29 19:49:13 dashing-oilbird snapd[3489]: created by os/signal.init.0
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/os/signal/signal_unix.go:28 +0x43
Mar 29 19:49:13 dashing-oilbird snapd[3489]: goroutine 7 [select, 25 minutes, locked to thread]:
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.gopark(0x564bc2ea33e0, 0x0, 0x564bc2908fcb, 0x6, 0x18, 0x1)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/proc.go:291 +0x120
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.selectgo(0xc420050750, 0xc4202aa8a0)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/select.go:392 +0xe56
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.ensureSigM.func1()
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/signal_unix.go:549 +0x1f6
Mar 29 19:49:13 dashing-oilbird snapd[3489]: runtime.goexit()
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/asm_amd64.s:2361 +0x1
Mar 29 19:49:13 dashing-oilbird snapd[3489]: goroutine 16 [runnable]:
Mar 29 19:49:13 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/overlord/standby.(*StandbyOpinions).Start.func1(0xc420091720)
Mar 29 19:49:13 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/overlord/standby/standby.go:91 +0x13d
Mar 29 19:49:13 dashing-oilbird snapd[3489]: created by github.com/snapcore/snapd/overlord/standby.(*StandbyOpinions).Start
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/overlord/standby/standby.go:84 +0x41
Mar 29 19:49:23 dashing-oilbird snapd[3489]: goroutine 19 [runnable]:
Mar 29 19:49:23 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/overlord/state.(*State).Prune(0xc4202c2100, 0x4e94914f0000, 0x2260ff9290000, 0x1f4)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/overlord/state/state.go:411 +0x89d
Mar 29 19:49:23 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/overlord.(*Overlord).Loop.func1(0x0, 0x0)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/overlord/overlord.go:299 +0x1b5
Mar 29 19:49:23 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/gopkg.in/tomb%2ev2.(*Tomb).run(0xc4202a4370, 0xc420031c10)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/gopkg.in/tomb.v2/tomb.go:163 +0x2d
Mar 29 19:49:23 dashing-oilbird snapd[3489]: created by github.com/snapcore/snapd/vendor/gopkg.in/tomb%2ev2.(*Tomb).Go
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/gopkg.in/tomb.v2/tomb.go:159 +0xbb
Mar 29 19:49:23 dashing-oilbird snapd[3489]: goroutine 20 [IO wait, 7 minutes]:
Mar 29 19:49:23 dashing-oilbird snapd[3489]: internal/poll.runtime_pollWait(0x7f01626e4e30, 0x72, 0x0)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/netpoll.go:173 +0x59
Mar 29 19:49:23 dashing-oilbird snapd[3489]: internal/poll.(*pollDesc).wait(0xc4200c4198, 0x72, 0xc420075800, 0x0, 0x0)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_poll_runtime.go:85 +0x9d
Mar 29 19:49:23 dashing-oilbird snapd[3489]: internal/poll.(*pollDesc).waitRead(0xc4200c4198, 0xffffffffffffff00, 0x0, 0x0)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_poll_runtime.go:90 +0x3f
Mar 29 19:49:23 dashing-oilbird snapd[3489]: internal/poll.(*FD).Accept(0xc4200c4180, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_unix.go:372 +0x1aa
Mar 29 19:49:23 dashing-oilbird snapd[3489]: net.(*netFD).accept(0xc4200c4180, 0xc420075840, 0x16, 0xc420061d98)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/fd_unix.go:238 +0x44
Mar 29 19:49:23 dashing-oilbird snapd[3489]: net.(*UnixListener).accept(0xc42008aab0, 0xc420061dc0, 0xc4202ebb58, 0xc4202c0738)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/unixsock_posix.go:162 +0x34
Mar 29 19:49:23 dashing-oilbird snapd[3489]: net.(*UnixListener).Accept(0xc42008aab0, 0x564bc2db3de0, 0xc420061e20, 0x564bc290159a, 0xc4202c0720)
Mar 29 19:49:23 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/unixsock.go:253 +0x4b
Mar 29 19:49:23 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/daemon.(*ucrednetListener).Accept(0xc420030800, 0x0, 0x0, 0x0, 0x0)
Mar 29 19:49:17 dashing-oilbird systemd[1]: snapd.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/daemon/ucrednet.go:102 +0x4f
Mar 29 19:49:45 dashing-oilbird snapd[3489]: net/http.(*Server).Serve(0xc420201520, 0x564bc2eaf180, 0xc420030800, 0x0, 0x0)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:2773 +0x1a7
Mar 29 19:49:45 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/daemon.(*shutdownServer).Serve(0xc4202c0720, 0xc4201aaeb0, 0x0)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/daemon/daemon.go:440 +0x42
Mar 29 19:49:45 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/daemon.(*Daemon).Start.func2(0x0, 0x0)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/daemon/daemon.go:562 +0x46
Mar 29 19:49:45 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/gopkg.in/tomb%2ev2.(*Tomb).run(0xc4200d8108, 0xc420031c20)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/gopkg.in/tomb.v2/tomb.go:163 +0x2d
Mar 29 19:49:45 dashing-oilbird snapd[3489]: created by github.com/snapcore/snapd/vendor/gopkg.in/tomb%2ev2.(*Tomb).Go
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/gopkg.in/tomb.v2/tomb.go:159 +0xbb
Mar 29 19:49:45 dashing-oilbird snapd[3489]: goroutine 21 [runnable]:
Mar 29 19:49:45 dashing-oilbird snapd[3489]: syscall.Getenv(0x564bc290db63, 0xd, 0x0, 0x0, 0x0)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/syscall/env_unix.go:80 +0xc6
Mar 29 19:49:45 dashing-oilbird snapd[3489]: os.Getenv(0x564bc290db63, 0xd, 0x2, 0x2)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/os/env.go:85 +0x4f
Mar 29 19:49:45 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/systemd.SdNotify(0x564bc290ba9e, 0xa, 0x0, 0x0)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/systemd/sdnotify.go:39 +0x6d
Mar 29 19:49:45 dashing-oilbird snapd[3489]: main.runWatchdog.func1(0xc420091810, 0xc4200d80c0)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/cmd/snapd/main.go:92 +0x4c
Mar 29 19:49:45 dashing-oilbird snapd[3489]: created by main.runWatchdog
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/cmd/snapd/main.go:85 +0x22d
Mar 29 19:49:45 dashing-oilbird snapd[3489]: goroutine 22 [syscall, 16 minutes]:
Mar 29 19:49:45 dashing-oilbird snapd[3489]: syscall.Syscall6(0x2d, 0x9, 0xc420463000, 0x1000, 0x2, 0xc42005fe08, 0xc42005fdfc, 0x564bc2dc7820, 0xc420308c30, 0xc420435e60)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/syscall/asm_linux_amd64.s:44 +0x5
Mar 29 19:49:45 dashing-oilbird snapd[3489]: syscall.recvfrom(0x9, 0xc420463000, 0x1000, 0x1000, 0x2, 0xc42005fe08, 0xc42005fdfc, 0x100000000000000, 0x0, 0x1000)
Mar 29 19:49:45 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/syscall/zsyscall_linux_amd64.go:1665 +0xa8
Mar 29 19:49:17 dashing-oilbird systemd[1]: snapd.service: Failed with result 'watchdog'.
Mar 29 19:49:50 dashing-oilbird snapd[3489]: syscall.Recvfrom(0x9, 0xc420463000, 0x1000, 0x1000, 0x2, 0x1000, 0xc420308c30, 0xc4204f5200, 0x2, 0xc420091d90)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/syscall/syscall_unix.go:252 +0xb1
Mar 29 19:49:50 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/osutil/udev/netlink.(*UEventConn).ReadMsg(0xc4202fbd60, 0x564bc22a0aac, 0xc420036c00, 0x0, 0xc420308c00, 0x0)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/osutil/udev/netlink/conn.go:65 +0xc9
Mar 29 19:49:50 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/osutil/udev/netlink.(*UEventConn).ReadUEvent(0xc4202fbd60, 0xc420036c00, 0x0, 0xc420348080)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/osutil/udev/netlink/conn.go:92 +0x31
Mar 29 19:49:50 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/osutil/udev/netlink.(*UEventConn).Monitor.func1(0xc420036c00, 0xc4202fbd60, 0xc420036ba0, 0x564bc2eb1280, 0xc420179f40, 0xc420036b40)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/osutil/udev/netlink/conn.go:120 +0x55
Mar 29 19:49:50 dashing-oilbird snapd[3489]: created by github.com/snapcore/snapd/osutil/udev/netlink.(*UEventConn).Monitor
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/osutil/udev/netlink/conn.go:114 +0xa6
Mar 29 19:49:50 dashing-oilbird snapd[3489]: goroutine 23 [IO wait, 18 minutes]:
Mar 29 19:49:50 dashing-oilbird snapd[3489]: internal/poll.runtime_pollWait(0x7f01626e4f00, 0x72, 0x0)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/netpoll.go:173 +0x59
Mar 29 19:49:50 dashing-oilbird snapd[3489]: internal/poll.(*pollDesc).wait(0xc4200c4298, 0x72, 0xc420075100, 0x0, 0x0)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_poll_runtime.go:85 +0x9d
Mar 29 19:49:50 dashing-oilbird snapd[3489]: internal/poll.(*pollDesc).waitRead(0xc4200c4298, 0xffffffffffffff00, 0x0, 0x0)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_poll_runtime.go:90 +0x3f
Mar 29 19:49:50 dashing-oilbird snapd[3489]: internal/poll.(*FD).Accept(0xc4200c4280, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_unix.go:372 +0x1aa
Mar 29 19:49:50 dashing-oilbird snapd[3489]: net.(*netFD).accept(0xc4200c4280, 0xc420075100, 0x0, 0xc420062d98)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/fd_unix.go:238 +0x44
Mar 29 19:49:50 dashing-oilbird snapd[3489]: net.(*UnixListener).accept(0xc42008ab10, 0xc420062dc0, 0xc42008c3c8, 0xc4202c06d8)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/unixsock_posix.go:162 +0x34
Mar 29 19:49:50 dashing-oilbird snapd[3489]: net.(*UnixListener).Accept(0xc42008ab10, 0x564bc2db3de0, 0xc420062e20, 0x564bc290159a, 0xc4202c06c0)
Mar 29 19:49:50 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/unixsock.go:253 +0x4b
Mar 29 19:49:17 dashing-oilbird systemd[1]: snapd.service: Triggering OnFailure= dependencies.
Mar 29 19:49:54 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/daemon.(*ucrednetListener).Accept(0xc420030810, 0x0, 0x0, 0x0, 0x0)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/daemon/ucrednet.go:102 +0x4f
Mar 29 19:49:54 dashing-oilbird snapd[3489]: net/http.(*Server).Serve(0xc420201450, 0x564bc2eaf180, 0xc420030810, 0x0, 0x0)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:2773 +0x1a7
Mar 29 19:49:54 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/daemon.(*shutdownServer).Serve(0xc4202c06c0, 0x0, 0x0)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/daemon/daemon.go:440 +0x42
Mar 29 19:49:54 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/daemon.(*Daemon).Start.func2.1(0x0, 0x0)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/daemon/daemon.go:554 +0x39
Mar 29 19:49:54 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/gopkg.in/tomb%2ev2.(*Tomb).run(0xc4200d8108, 0xc4201aaeb0)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/gopkg.in/tomb.v2/tomb.go:163 +0x2d
Mar 29 19:49:54 dashing-oilbird snapd[3489]: created by github.com/snapcore/snapd/vendor/gopkg.in/tomb%2ev2.(*Tomb).Go
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/gopkg.in/tomb.v2/tomb.go:159 +0xbb
Mar 29 19:49:54 dashing-oilbird snapd[3489]: goroutine 3326 [runnable]:
Mar 29 19:49:54 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/github.com/gorilla/mux.(*Router).Match(0xc4200906e0, 0xc4202c8000, 0xc4204cc0c0, 0x1)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/github.com/gorilla/mux/mux.go:56 +0xea
Mar 29 19:49:54 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/github.com/gorilla/mux.(*Router).ServeHTTP(0xc4200906e0, 0x564bc2eaf1c0, 0xc4204cc0a0, 0xc4202c8000)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/github.com/gorilla/mux/mux.go:92 +0x1cc
Mar 29 19:49:54 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/daemon.logit.func1(0x564bc2eaf7c0, 0xc4200f01c0, 0xc4202c8000)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/daemon/daemon.go:292 +0xdf
Mar 29 19:49:54 dashing-oilbird snapd[3489]: net/http.HandlerFunc.ServeHTTP(0xc420179100, 0x564bc2eaf7c0, 0xc4200f01c0, 0xc4202c8000)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:1947 +0x46
Mar 29 19:49:54 dashing-oilbird snapd[3489]: net/http.serverHandler.ServeHTTP(0xc420201520, 0x564bc2eaf7c0, 0xc4200f01c0, 0xc4202c8000)
Mar 29 19:49:54 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:2697 +0xbe
Mar 29 19:49:59 dashing-oilbird snapd[3489]: net/http.(*conn).serve(0xc420b0e000, 0x564bc2eb0340, 0xc420470000)
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:1830 +0x653
Mar 29 19:49:59 dashing-oilbird snapd[3489]: created by net/http.(*Server).Serve
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:2798 +0x27d
Mar 29 19:49:59 dashing-oilbird snapd[3489]: goroutine 27 [select, 25 minutes]:
Mar 29 19:49:59 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/overlord/ifacestate/udevmonitor.(*Monitor).Run.func1(0xc4202a4a50, 0x564bc2ea8901)
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/overlord/ifacestate/udevmonitor/udevmon.go:144 +0x35a
Mar 29 19:49:59 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/gopkg.in/tomb%2ev2.(*Tomb).run(0xc420136a20, 0xc420075b00)
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/gopkg.in/tomb.v2/tomb.go:163 +0x2d
Mar 29 19:49:59 dashing-oilbird snapd[3489]: created by github.com/snapcore/snapd/vendor/gopkg.in/tomb%2ev2.(*Tomb).Go
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/gopkg.in/tomb.v2/tomb.go:159 +0xbb
Mar 29 19:49:59 dashing-oilbird snapd[3489]: goroutine 3327 [runnable]:
Mar 29 19:49:59 dashing-oilbird snapd[3489]: net/http.(*connReader).handleReadError(0xc4205c4030, 0x564bc2ea7a00, 0xc420030070)
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:708 +0x4d
Mar 29 19:49:59 dashing-oilbird snapd[3489]: net/http.(*connReader).backgroundRead(0xc4205c4030)
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:681 +0x12e
Mar 29 19:49:59 dashing-oilbird snapd[3489]: created by net/http.(*connReader).startBackgroundRead
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/http/server.go:664 +0xd0
Mar 29 19:49:59 dashing-oilbird snapd[3489]: goroutine 43 [chan receive, 25 minutes]:
Mar 29 19:49:59 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/github.com/godbus/dbus.(*Conn).outWorker(0xc4202c8b00)
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/github.com/godbus/dbus/conn.go:427 +0x65
Mar 29 19:49:59 dashing-oilbird snapd[3489]: created by github.com/snapcore/snapd/vendor/github.com/godbus/dbus.(*Conn).Auth
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/github.com/godbus/dbus/auth.go:119 +0x6f0
Mar 29 19:49:59 dashing-oilbird snapd[3489]: goroutine 42 [IO wait, 25 minutes]:
Mar 29 19:49:59 dashing-oilbird snapd[3489]: internal/poll.runtime_pollWait(0x7f01626e4d60, 0x72, 0xc42024c2a0)
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/runtime/netpoll.go:173 +0x59
Mar 29 19:49:59 dashing-oilbird snapd[3489]: internal/poll.(*pollDesc).wait(0xc4200c4098, 0x72, 0xc4201a8c00, 0x1000, 0x0)
Mar 29 19:49:59 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_poll_runtime.go:85 +0x9d
Mar 29 19:49:18 dashing-oilbird systemd[1]: snapd.service: Service hold-off time over, scheduling restart.
Mar 29 19:50:17 dashing-oilbird snapd[5980]: AppArmor status: apparmor is enabled and all features are available
Mar 29 19:50:19 dashing-oilbird snapd[3489]: internal/poll.(*pollDesc).waitRead(0xc4200c4098, 0xc42024c200, 0x10, 0x10)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_poll_runtime.go:90 +0x3f
Mar 29 19:50:19 dashing-oilbird snapd[3489]: internal/poll.(*FD).ReadMsg(0xc4200c4080, 0xc42024c2a0, 0x10, 0x10, 0xc4201a8c20, 0x1000, 0x1000, 0x0, 0x0, 0x0, ...)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/internal/poll/fd_unix.go:231 +0x1f3
Mar 29 19:50:19 dashing-oilbird snapd[3489]: net.(*netFD).readMsg(0xc4200c4080, 0xc42024c2a0, 0x10, 0x10, 0xc4201a8c20, 0x1000, 0x1000, 0x564bc2d81400, 0xc4202e3fe0, 0x13f, ...)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/fd_unix.go:214 +0x92
Mar 29 19:50:19 dashing-oilbird snapd[3489]: net.(*UnixConn).readMsg(0xc4204f8b50, 0xc42024c2a0, 0x10, 0x10, 0xc4201a8c20, 0x1000, 0x1000, 0xc42024c180, 0x564bc2eb3b60, 0xc42024c148, ...)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/unixsock_posix.go:115 +0x93
Mar 29 19:50:19 dashing-oilbird snapd[3489]: net.(*UnixConn).ReadMsgUnix(0xc4204f8b50, 0xc42024c2a0, 0x10, 0x10, 0xc4201a8c20, 0x1000, 0x1000, 0x564bc22abc58, 0x7f01626ebf18, 0x0, ...)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/net/unixsock.go:137 +0xac
Mar 29 19:50:19 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/github.com/godbus/dbus.(*oobReader).Read(0xc4201a8c00, 0xc42024c2a0, 0x10, 0x10, 0x1020, 0xc4201a8c00, 0x0)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/github.com/godbus/dbus/transport_unix.go:21 +0x91
Mar 29 19:50:19 dashing-oilbird snapd[3489]: io.ReadAtLeast(0x564bc2ea8320, 0xc4201a8c00, 0xc42024c2a0, 0x10, 0x10, 0x10, 0x564bc2e18940, 0x13, 0x1020)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/io/io.go:309 +0x88
Mar 29 19:50:19 dashing-oilbird snapd[3489]: io.ReadFull(0x564bc2ea8320, 0xc4201a8c00, 0xc42024c2a0, 0x10, 0x10, 0x10, 0x564bc29fab72, 0x0)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /usr/lib/go-1.10/src/io/io.go:327 +0x5a
Mar 29 19:50:19 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/github.com/godbus/dbus.(*unixTransport).ReadMessage(0xc4202acaf0, 0xc4204157a0, 0x564b00000002, 0xc4200ed758)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/github.com/godbus/dbus/transport_unix.go:85 +0x115
Mar 29 19:50:19 dashing-oilbird snapd[3489]: github.com/snapcore/snapd/vendor/github.com/godbus/dbus.(*Conn).inWorker(0xc4202c8b00)
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/github.com/godbus/dbus/conn.go:285 +0x4d
Mar 29 19:50:19 dashing-oilbird snapd[3489]: created by github.com/snapcore/snapd/vendor/github.com/godbus/dbus.(*Conn).Auth
Mar 29 19:49:18 dashing-oilbird systemd[1]: snapd.service: Scheduled restart job, restart counter is at 2.
Mar 29 19:50:19 dashing-oilbird snapd[3489]:         /build/snapd-IVSkBe/snapd-2.38/_build/src/github.com/snapcore/snapd/vendor/github.com/godbus/dbus/auth.go:118 +0x6cb
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rax    0xca
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rbx    0x564bc320ece0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rcx    0x564bc22f4113
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rdx    0x0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rdi    0x564bc320ee28
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rsi    0x0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rbp    0x7fff62f48170
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rsp    0x7fff62f48128
Mar 29 19:50:19 dashing-oilbird snapd[3489]: r8     0x0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: r9     0x0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: r10    0x0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: r11    0x286
Mar 29 19:50:19 dashing-oilbird snapd[3489]: r12    0x0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: r13    0xf1
Mar 29 19:50:19 dashing-oilbird snapd[3489]: r14    0x11
Mar 29 19:50:19 dashing-oilbird snapd[3489]: r15    0x0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rip    0x564bc22f4111
Mar 29 19:50:19 dashing-oilbird snapd[3489]: rflags 0x286
Mar 29 19:50:19 dashing-oilbird snapd[3489]: cs     0x33
Mar 29 19:50:19 dashing-oilbird snapd[3489]: fs     0x0
Mar 29 19:50:19 dashing-oilbird snapd[3489]: gs     0x0
Mar 29 19:49:18 dashing-oilbird systemd[1]: Stopped Snappy daemon.
Mar 29 19:49:18 dashing-oilbird systemd[1]: Starting Snappy daemon...
Mar 29 19:50:48 dashing-oilbird systemd[1]: snapd.service: Start operation timed out. Terminating.
Mar 29 19:50:50 dashing-oilbird systemd[1]: snapd.service: Failed with result 'timeout'.
Mar 29 19:50:50 dashing-oilbird systemd[1]: Failed to start Snappy daemon.
Mar 29 19:50:50 dashing-oilbird systemd[1]: snapd.service: Triggering OnFailure= dependencies.
Mar 29 19:50:50 dashing-oilbird systemd[1]: snapd.service: Service hold-off time over, scheduling restart.
Mar 29 19:50:50 dashing-oilbird systemd[1]: snapd.service: Scheduled restart job, restart counter is at 3.
Mar 29 19:50:50 dashing-oilbird systemd[1]: Stopped Snappy daemon.
Mar 29 19:50:51 dashing-oilbird systemd[1]: Starting Snappy daemon...
Mar 29 19:51:28 dashing-oilbird snapd[6015]: AppArmor status: apparmor is enabled and all features are available
Mar 29 19:52:21 dashing-oilbird systemd[1]: snapd.service: Start operation timed out. Terminating.
Mar 29 19:52:23 dashing-oilbird systemd[1]: snapd.service: Failed with result 'timeout'.
Mar 29 19:52:23 dashing-oilbird systemd[1]: Failed to start Snappy daemon.
Mar 29 19:52:23 dashing-oilbird systemd[1]: snapd.service: Triggering OnFailure= dependencies.
Mar 29 19:52:23 dashing-oilbird systemd[1]: snapd.service: Service hold-off time over, scheduling restart.
Mar 29 19:52:23 dashing-oilbird systemd[1]: snapd.service: Scheduled restart job, restart counter is at 4.
Mar 29 19:52:23 dashing-oilbird systemd[1]: Stopped Snappy daemon.
Mar 29 19:52:23 dashing-oilbird systemd[1]: Starting Snappy daemon...
Mar 29 19:52:47 dashing-oilbird snapd[6050]: AppArmor status: apparmor is enabled and all features are available

I don’t expect folks to fix anything from this here, and it’s entirely reasonable to just say that the system is just too slow and under too much load, but in its current state I cannot run any snap commands in the VM because they hang trying to connect to the snapd socket. I can reproduce this rather easily by just running:

multipass launch
multipass shell $VM_NAME

and then in the VM:

snap install edgexfoundry

and then after the snap has installed, trying to do anything with snap will hang and eventually one sees the panic output above in the journal for snapd.

in case it’s relevant the multipass version is :

$ multipass version
multipass  v0.5-full
multipassd v0.5-full

Also, I can’t get snap version anymore, but it seems to be snapd 2.37.4 from the journal output above. I assume that if snapd had refreshed itself that upon the restart that accompanied the refresh it would have outputted the new version after the refresh.

[chipaca]

fascinating. I’d love to be able to reproduce this.

Not today though, I’m in weekend mode

[ijohnson]

Actually reading my log more closely it does look like snapd was able to refresh itself to 2.38

[scubadrew]

I am having this issue on a fairly powerful server (aws c5.xlarge) Ubuntu 20.04 AMI: ami-0f1289f37e46c1eff

Every day, around noon UTC the snap system has issues:

Mar 15 12:10:19 ip-10-0-1-74 systemd[1]: snapd.service: Watchdog timeout (limit 5min)!
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: SIGABRT: abort

Followed by:

Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: PC=0x55d2a743f231 m=0 sigcode=0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 0 [idle]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: runtime.futex(0x55d2a8f659a8, 0x80, 0x0, 0x0, 0x55d200000000, 0x7fff00000000, 0x55d2a7412b05, 0xc000234848, 0x7fff27e0cb70, 0x55d2a73e8623, ...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/sys_linux_amd64.s:535 +0x21
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: runtime.futexsleep(0x55d2a8f659a8, 0x7fff00000000, 0xffffffffffffffff)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/os_linux.go:44 +0x46
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: runtime.notesleep(0x55d2a8f659a8)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/lock_futex.go:151 +0xa3
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: runtime.stoplockedm()
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/proc.go:2068 +0x8c
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: runtime.schedule()
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/proc.go:2469 +0x489
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: runtime.park_m(0xc00033ef00)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/proc.go:2610 +0xa3
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: runtime.mcall(0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/asm_amd64.s:318 +0x53
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 1 [select, 1050 minutes]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: main.run(0xc000078c00, 0x0, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/cmd/snapd/main.go:153 +0x4e5
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: main.main()
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/cmd/snapd/main.go:64 +0x10e
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 6 [syscall, 1050 minutes]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: os/signal.signal_recv(0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/sigqueue.go:147 +0x9e
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: os/signal.loop()
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/os/signal/signal_unix.go:23 +0x24
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by os/signal.init.0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/os/signal/signal_unix.go:29 +0x43
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 68 [select, 10 minutes]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/overlord/standby.(*StandbyOpinions).Start.func1(0xc000278140)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/overlord/standby/standby.go:105 +0x12b
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by github.com/snapcore/snapd/overlord/standby.(*StandbyOpinions).Start
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/overlord/standby/standby.go:96 +0xaa
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 70 [IO wait, 985 minutes]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.runtime_pollWait(0x7fc76a3a8f08, 0x72, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/netpoll.go:184 +0x57
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*pollDesc).wait(0xc0000e3918, 0x72, 0x0, 0x0, 0x55d2a7ea029d)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_poll_runtime.go:87 +0x47
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*pollDesc).waitRead(...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_poll_runtime.go:92
Mar 15 12:10:19 ip-10-0-1-74 systemd[1]: snapd.service: Killing process 553 (snapd) with signal SIGABRT.
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*FD).Accept(0xc0000e3900, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_unix.go:384 +0x1fa
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*netFD).accept(0xc0000e3900, 0x203000, 0xc000507cb0, 0x55d2a740c393)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/fd_unix.go:238 +0x44
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*UnixListener).accept(0xc00045bcb0, 0x55d2a87c7b98, 0xc000507ce8, 0xc000507cc0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/unixsock_posix.go:162 +0x34
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*UnixListener).Accept(0xc00045bcb0, 0xc000391e58, 0x8, 0xc000507cc0, 0x55d2a7e86985)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/unixsock.go:260 +0x49
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/daemon.(*ucrednetListener).Accept(0xc00045bd40, 0xc000507da8, 0x18, 0xc000211c80, 0x55d2a76d5496)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/daemon/ucrednet.go:112 +0x4a
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net/http.(*Server).Serve(0xc0003c60e0, 0x55d2a8803f60, 0xc00045bd40, 0x0, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/http/server.go:2896 +0x282
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/daemon.(*Daemon).Start.func1(0xc000397f88, 0x55d2a772000f)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/daemon/daemon.go:378 +0x56
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: gopkg.in/tomb%2ev2.(*Tomb).run(0xc000126050, 0xc0004613c0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/gopkg.in/tomb.v2/tomb.go:163 +0x2d
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by gopkg.in/tomb%2ev2.(*Tomb).Go
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/gopkg.in/tomb.v2/tomb.go:159 +0xc9
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 69 [runnable]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/overlord.(*Overlord).ensureTimerReset(0xc000269380, 0xc0003b5ea4, 0x3, 0x1)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/overlord/overlord.go:407 +0x1b8
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/overlord.(*Overlord).Loop.func1(0x0, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/overlord/overlord.go:459 +0x52
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: gopkg.in/tomb%2ev2.(*Tomb).run(0xc000278230, 0xc0003bdf00)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/gopkg.in/tomb.v2/tomb.go:163 +0x2d
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by gopkg.in/tomb%2ev2.(*Tomb).Go
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/gopkg.in/tomb.v2/tomb.go:159 +0xc9
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 27 [select, 1008 minutes]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/overlord/ifacestate/udevmonitor.(*Monitor).Run.func1(0x0, 0x1)
Mar 15 12:10:19 ip-10-0-1-74 CRON[42333]: (root) CMD (command -v debian-sa1 -S XALL > /dev/null && debian-sa1 -S XALL 1 1)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/overlord/ifacestate/udevmonitor/udevmon.go:147 +0x329
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: gopkg.in/tomb%2ev2.(*Tomb).run(0xc000136b40, 0xc000077280)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/gopkg.in/tomb.v2/tomb.go:163 +0x2d
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by gopkg.in/tomb%2ev2.(*Tomb).Go
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/gopkg.in/tomb.v2/tomb.go:159 +0xc9
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 61 [syscall, 400 minutes]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: syscall.Syscall6(0x17, 0xb, 0xc000065e28, 0x0, 0x0, 0x0, 0x0, 0xc000300300, 0x200000003, 0xc000300300)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/syscall/asm_linux_amd64.s:44 +0x5
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: syscall.Select(0xb, 0xc000065e28, 0x0, 0x0, 0x0, 0x55d2a740c393, 0x55d2a87c70b0, 0xc000065e48)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/syscall/zsyscall_linux_amd64.go:1320 +0xa2
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/osutil/udev/netlink.stopperSelectReadable(0x9, 0xa, 0x0, 0xc0004b4c00, 0x3e)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/osutil/udev/netlink/rawsockstop.go:57 +0x16c
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/osutil/udev/netlink.RawSockStopper.func1(0xc000582d20, 0xc000037290, 0x6)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/osutil/udev/netlink/rawsockstop.go:31 +0x4e
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/osutil/udev/netlink.(*UEventConn).Monitor.func4(0xc000582d40, 0xc0000834a0, 0xc000083500, 0xc000083560, 0xc0002f3be0, 0x55d2a880d3a0, 0xc000582d20, 0xc000083440)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/osutil/udev/netlink/conn.go:139 +0x46
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by github.com/snapcore/snapd/osutil/udev/netlink.(*UEventConn).Monitor
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/osutil/udev/netlink/conn.go:136 +0x247
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 71 [runnable]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/systemd.SdNotify(0x55d2a7ea44e3, 0xa, 0x2, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/systemd/sdnotify.go:34 +0x413
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: main.runWatchdog.func1(0xc000278280, 0xc000126000)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/cmd/snapd/main.go:98 +0x4c
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by main.runWatchdog
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/cmd/snapd/main.go:91 +0x1e2
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 40 [IO wait, 1050 minutes]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.runtime_pollWait(0x7fc76a3a8e38, 0x72, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/netpoll.go:184 +0x57
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*pollDesc).wait(0xc0000e3a18, 0x72, 0x0, 0x0, 0x55d2a7ea029d)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_poll_runtime.go:87 +0x47
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*pollDesc).waitRead(...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_poll_runtime.go:92
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*FD).Accept(0xc0000e3a00, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_unix.go:384 +0x1fa
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*netFD).accept(0xc0000e3a00, 0x203000, 0xaa, 0x55d2a8f6b358)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/fd_unix.go:238 +0x44
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*UnixListener).accept(0xc00045bce0, 0xc0004b8030, 0x30, 0x30)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/unixsock_posix.go:162 +0x34
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*UnixListener).Accept(0xc00045bce0, 0xc0004b8030, 0x55d2a87c7b98, 0xc0000dbd28, 0xc0000cea80)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/unixsock.go:260 +0x49
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/daemon.(*ucrednetListener).Accept(0xc00045bd70, 0x55d2a87284a0, 0xc0004b8030, 0x55d2a8655ea0, 0x55d2a8f02130)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/daemon/ucrednet.go:112 +0x4a
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net/http.(*Server).Serve(0xc0003c60e0, 0x55d2a8803f60, 0xc00045bd70, 0x0, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/http/server.go:2896 +0x282
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/snapcore/snapd/daemon.(*Daemon).Start.func1.1(0x55d2a8f249c0, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/daemon/daemon.go:370 +0x4b
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: gopkg.in/tomb%2ev2.(*Tomb).run(0xc000126050, 0xc00024dff0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/gopkg.in/tomb.v2/tomb.go:163 +0x2d
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by gopkg.in/tomb%2ev2.(*Tomb).Go
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/gopkg.in/tomb.v2/tomb.go:159 +0xc9
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: goroutine 72 [IO wait, 1050 minutes]:
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.runtime_pollWait(0x7fc76a3a8d68, 0x72, 0x1000)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/runtime/netpoll.go:184 +0x57
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*pollDesc).wait(0xc000566698, 0x72, 0x0, 0x10, 0xc000193920)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_poll_runtime.go:87 +0x47
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*pollDesc).waitRead(...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_poll_runtime.go:92
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: internal/poll.(*FD).ReadMsg(0xc000566680, 0xc000254490, 0x10, 0x10, 0xc000193920, 0x1000, 0x1000, 0x0, 0x0, 0x0, ...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/internal/poll/fd_unix.go:243 +0x230
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*netFD).readMsg(0xc000566680, 0xc000254490, 0x10, 0x10, 0xc000193920, 0x1000, 0x1000, 0xc0004f7d50, 0x55d2a8f28816, 0x1, ...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/fd_unix.go:214 +0x92
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*UnixConn).readMsg(0xc0004ae9f8, 0xc000254490, 0x10, 0x10, 0xc000193920, 0x1000, 0x1000, 0xc000254048, 0xc000254044, 0xc00051e4a0, ...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/unixsock_posix.go:115 +0x93
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: net.(*UnixConn).ReadMsgUnix(0xc0004ae9f8, 0xc000254490, 0x10, 0x10, 0xc000193920, 0x1000, 0x1000, 0xc00051e8a0, 0x20, 0x18, ...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/net/unixsock.go:143 +0x9a
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/godbus/dbus.(*oobReader).Read(0xc000193900, 0xc000254490, 0x10, 0x10, 0xc0004f7df0, 0x55d2a73ea3aa, 0x20)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/github.com/godbus/dbus/transport_unix.go:21 +0x8f
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: io.ReadAtLeast(0x55d2a87e9500, 0xc000193900, 0xc000254490, 0x10, 0x10, 0x10, 0xc0001fced0, 0x0, 0x0)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/io/io.go:310 +0x89
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: io.ReadFull(...)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/usr/lib/go-1.13/src/io/io.go:329
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/godbus/dbus.(*unixTransport).ReadMessage(0xc00047e2e0, 0xc000000002, 0x2, 0xc00052c101)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/github.com/godbus/dbus/transport_unix.go:91 +0x11a
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: github.com/godbus/dbus.(*Conn).inWorker(0xc0003b8360)
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/github.com/godbus/dbus/conn.go:294 +0x42
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: created by github.com/godbus/dbus.(*Conn).Auth
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: #011/build/snapd/parts/snapd-deb/build/vendor/github.com/godbus/dbus/auth.go:118 +0x623
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rax    0xca
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rbx    0x55d2a8f65860
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rcx    0x55d2a743f233
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rdx    0x0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rdi    0x55d2a8f659a8
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rsi    0x80
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rbp    0x7fff27e0cb38
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rsp    0x7fff27e0caf0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: r8     0x0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: r9     0x0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: r10    0x0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: r11    0x286
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: r12    0xff
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: r13    0x0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: r14    0x55d2a80cd194
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: r15    0x0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rip    0x55d2a743f231
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: rflags 0x286
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: cs     0x33
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: fs     0x0
Mar 15 12:10:19 ip-10-0-1-74 snapd[553]: gs     0x0
Mar 15 12:10:19 ip-10-0-1-74 CRON[42336]: (root) CMD (command -v debian-sa1 -S XALL > /dev/null && debian-sa1 -S XALL 1 1)
Mar 15 12:10:19 ip-10-0-1-74 systemd[1]: snapd.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Mar 15 12:10:19 ip-10-0-1-74 systemd[1]: snapd.service: Failed with result 'watchdog'.
Mar 15 12:10:19 ip-10-0-1-74 CRON[42339]: (root) CMD (command -v debian-sa1 -S XALL > /dev/null && debian-sa1 -S XALL 1 1)
Mar 15 12:10:19 ip-10-0-1-74 CRON[42344]: (root) CMD (command -v debian-sa1 -S XALL > /dev/null && debian-sa1 -S XALL 1 1)
Mar 15 12:10:19 ip-10-0-1-74 CRON[42346]: (root) CMD (command -v debian-sa1 -S XALL > /dev/null && debian-sa1 -S XALL 1 1)
Mar 15 12:10:19 ip-10-0-1-74 CRON[42353]: (root) CMD (command -v debian-sa1 -S XALL > /dev/null && debian-sa1 -S XALL 1 1)
Mar 15 12:10:19 ip-10-0-1-74 systemd[1]: snapd.service: Scheduled restart job, restart counter is at 1.
Mar 15 12:10:19 ip-10-0-1-74 systemd[1]: Stopped Snap Daemon.
Mar 15 12:10:19 ip-10-0-1-74 systemd[1]: Starting Snap Daemon...
Mar 15 12:10:19 ip-10-0-1-74 snapd[42365]: AppArmor status: apparmor is enabled and all features are available
Mar 15 12:10:20 ip-10-0-1-74 snapd[42365]: overlord.go:268: Acquiring state lock file
Mar 15 12:10:20 ip-10-0-1-74 snapd[42365]: overlord.go:273: Acquired state lock file
Mar 15 12:10:21 ip-10-0-1-74 snapd[42365]: daemon.go:247: started snapd/2.58.2 (series 16; classic) ubuntu/20.04 (amd64) linux/5.15.0-1031-aws.
Mar 15 12:10:21 ip-10-0-1-74 kernel: [63043.326825] loop9: detected capacity change from 0 to 8
Mar 15 12:10:21 ip-10-0-1-74 systemd[1]: tmp-syscheck\x2dmountpoint\x2d168111008.mount: Succeeded.
Mar 15 12:10:21 ip-10-0-1-74 systemd[9958]: tmp-syscheck\x2dmountpoint\x2d168111008.mount: Succeeded.
Mar 15 12:10:21 ip-10-0-1-74 snapd[42365]: daemon.go:340: adjusting startup timeout by 55s (pessimistic estimate of 30s plus 5s per snap)
Mar 15 12:10:21 ip-10-0-1-74 snapd[42365]: backends.go:58: AppArmor status: apparmor is enabled and all features are available
Mar 15 12:10:21 ip-10-0-1-74 dbus-daemon[536]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.56' (uid=0 pid=42365 comm="/usr/lib/snapd/snapd " label="unconfined")
Mar 15 12:10:21 ip-10-0-1-74 systemd[1]: Started Snap Daemon.
Mar 15 12:10:21 ip-10-0-1-74 systemd[1]: Starting Time & Date Service...
Mar 15 12:10:21 ip-10-0-1-74 dbus-daemon[536]: [system] Successfully activated service 'org.freedesktop.timedate1'
Mar 15 12:10:21 ip-10-0-1-74 systemd[1]: Started Time & Date Service.
Mar 15 12:10:22 ip-10-0-1-74 snapd[42365]: storehelpers.go:769: cannot refresh: snap has no updates available: "amazon-ssm-agent", "core18", "core20", "lxd", "snapd"
Mar 15 12:10:22 ip-10-0-1-74 snapd[42365]: autorefresh.go:551: auto-refresh: all snaps are up-to-date