Skip to content

Instantly share code, notes, and snippets.

@WillNilges
Created June 12, 2020 19:30
Show Gist options
  • Save WillNilges/e67eaa2f98156227475c8476862d0c04 to your computer and use it in GitHub Desktop.
Save WillNilges/e67eaa2f98156227475c8476862d0c04 to your computer and use it in GitHub Desktop.
debuglog
-- Logs begin at Fri 2020-06-12 19:15:20 UTC, end at Fri 2020-06-12 19:24:51 UTC. --
Jun 12 19:16:40 localhost systemd[1]: Starting Snap Daemon...
Jun 12 19:16:40 localhost snapd[3149]: AppArmor status: apparmor not enabled
Jun 12 19:16:41 localhost snapd[3149]: patch.go:64: Patching system state level 6 to sublevel 1...
Jun 12 19:16:41 localhost snapd[3149]: patch.go:64: Patching system state level 6 to sublevel 2...
Jun 12 19:16:41 localhost snapd[3149]: patch.go:64: Patching system state level 6 to sublevel 3...
Jun 12 19:16:41 localhost snapd[3149]: daemon.go:343: started snapd/2.45 (series 16; devmode) ubuntu-core/18 (armhf) linux/5.4.45-dirty.
Jun 12 19:16:41 localhost snapd[3149]: daemon.go:436: adjusting startup timeout by 30s (pessimistic estimate of 30s plus 5s per snap)
Jun 12 19:16:41 localhost snapd[3149]: helpers.go:961: cannot retrieve info for snap "kernel": cannot find installed snap "kernel" at revision unset: missing file /snap/kernel/unset/meta/snap.yaml
Jun 12 19:16:41 localhost snapd[3149]: standby.go:96: DEBUG: will consider standby after: 5s
Jun 12 19:16:41 localhost snapd[3149]: main.go:88: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Jun 12 19:16:41 localhost snapd[3149]: main.go:149: DEBUG: activation done in 938ms
Jun 12 19:16:41 localhost snapd[3149]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:41 localhost snapd[3149]: taskrunner.go:437: DEBUG: Running task 7 on Doing: Make snap "snapd" (7779) available to the system
Jun 12 19:16:42 localhost systemd[1]: Started Snap Daemon.
Jun 12 19:16:44 localhost snapd[3149]: task.go:337: DEBUG: 2020-06-12T19:16:44Z INFO Requested daemon restart (snapd snap).
Jun 12 19:16:44 localhost snapd[3149]: daemon.go:542: gracefully waiting for running hooks
Jun 12 19:16:44 localhost snapd[3149]: daemon.go:544: done waiting for running hooks
Jun 12 19:16:45 localhost systemd[1]: snapd.service: Service hold-off time over, scheduling restart.
Jun 12 19:16:45 localhost systemd[1]: snapd.service: Scheduled restart job, restart counter is at 1.
Jun 12 19:16:45 localhost systemd[1]: Stopped Snap Daemon.
Jun 12 19:16:45 localhost systemd[1]: Starting Snap Daemon...
Jun 12 19:16:45 localhost snapd[3176]: AppArmor status: apparmor not enabled
Jun 12 19:16:45 localhost snapd[3176]: patch.go:64: Patching system state level 6 to sublevel 1...
Jun 12 19:16:45 localhost snapd[3176]: patch.go:64: Patching system state level 6 to sublevel 2...
Jun 12 19:16:45 localhost snapd[3176]: patch.go:64: Patching system state level 6 to sublevel 3...
Jun 12 19:16:46 localhost snapd[3176]: daemon.go:343: started snapd/2.45 (series 16; devmode) ubuntu-core/18 (armhf) linux/5.4.45-dirty.
Jun 12 19:16:46 localhost snapd[3176]: daemon.go:436: adjusting startup timeout by 35s (pessimistic estimate of 30s plus 5s per snap)
Jun 12 19:16:46 localhost snapd[3176]: helpers.go:961: cannot retrieve info for snap "kernel": cannot find installed snap "kernel" at revision unset: missing file /snap/kernel/unset/meta/snap.yaml
Jun 12 19:16:46 localhost snapd[3176]: standby.go:96: DEBUG: will consider standby after: 5s
Jun 12 19:16:46 localhost snapd[3176]: main.go:88: DEBUG: Setting up sd_notify() watchdog timer every 2m30s
Jun 12 19:16:46 localhost snapd[3176]: main.go:149: DEBUG: activation done in 1.205s
Jun 12 19:16:46 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:46 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 36.176ms 400
Jun 12 19:16:46 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3186;uid=0;socket=/run/snapd.socket; GET /v2/changes?select=all 101.063667ms 200
Jun 12 19:16:47 localhost systemd[1]: Started Snap Daemon.
Jun 12 19:16:47 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 405µs 400
Jun 12 19:16:47 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 413µs 400
Jun 12 19:16:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 370.334µs 400
Jun 12 19:16:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 420.667µs 400
Jun 12 19:16:49 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 355.667µs 400
Jun 12 19:16:49 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361.334µs 400
Jun 12 19:16:50 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 401.333µs 400
Jun 12 19:16:50 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 398.667µs 400
Jun 12 19:16:51 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 358.333µs 400
Jun 12 19:16:51 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 381.666µs 400
Jun 12 19:16:52 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 395.333µs 400
Jun 12 19:16:52 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 8 on Doing: Automatically connect eligible plugs and slots of snap "snapd"
Jun 12 19:16:52 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 54.575333ms 400
Jun 12 19:16:52 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:53 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 9 on Do: Set automatic aliases for snap "snapd"
Jun 12 19:16:53 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 65.459667ms 400
Jun 12 19:16:53 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:53 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 10 on Do: Setup snap "snapd" aliases
Jun 12 19:16:53 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 60.058ms 400
Jun 12 19:16:54 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:54 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 11 on Do: Run install hook of "snapd" snap if present
Jun 12 19:16:54 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 96.449667ms 400
Jun 12 19:16:54 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:54 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 12 on Do: Start snap "snapd" (7779) services
Jun 12 19:16:55 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 45.27ms 400
Jun 12 19:16:55 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 24 on Do: Ensure prerequisites for "kernel" are available
Jun 12 19:16:55 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 48.089ms 400
Jun 12 19:16:55 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 25 on Do: Prepare snap "/var/lib/snapd/seed/snaps/kernel_x1.snap" (unset)
Jun 12 19:16:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 95.763667ms 400
Jun 12 19:16:56 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:16:56 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 26 on Do: Mount snap "kernel" (unset)
Jun 12 19:16:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 71.331666ms 400
Jun 12 19:16:56 localhost snapd[3176]: devicestate.go:177: installing unasserted kernel "kernel"
Jun 12 19:16:57 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 128.737ms 400
Jun 12 19:16:58 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 193.762667ms 400
Jun 12 19:16:58 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 67.726ms 400
Jun 12 19:16:59 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 48.585667ms 400
Jun 12 19:17:00 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 90.663ms 400
Jun 12 19:17:00 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 210.421334ms 400
Jun 12 19:17:01 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 122.142334ms 400
Jun 12 19:17:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 51.627ms 400
Jun 12 19:17:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 56.866666ms 400
Jun 12 19:17:03 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 75.889ms 400
Jun 12 19:17:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 60.536667ms 400
Jun 12 19:17:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 115.997333ms 400
Jun 12 19:17:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 59.869ms 400
Jun 12 19:17:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 62.05ms 400
Jun 12 19:17:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 57.904333ms 400
Jun 12 19:17:06 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 360µs 400
Jun 12 19:17:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 359µs 400
Jun 12 19:17:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 404.333µs 400
Jun 12 19:17:08 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 388µs 400
Jun 12 19:17:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 425.333µs 400
Jun 12 19:17:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 384.333µs 400
Jun 12 19:17:09 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:09 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 27 on Do: Copy snap "kernel" data
Jun 12 19:17:09 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:09 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 28 on Do: Setup snap "kernel" (unset) security profiles
Jun 12 19:17:09 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:09 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 29 on Do: Make snap "kernel" (unset) available to the system
Jun 12 19:17:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 160.857666ms 400
Jun 12 19:17:10 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:10 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 30 on Do: Automatically connect eligible plugs and slots of snap "kernel"
Jun 12 19:17:10 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:10 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 31 on Do: Set automatic aliases for snap "kernel"
Jun 12 19:17:10 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:10 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 32 on Do: Setup snap "kernel" aliases
Jun 12 19:17:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 243.707667ms 400
Jun 12 19:17:10 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:10 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 33 on Do: Run install hook of "kernel" snap if present
Jun 12 19:17:11 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:11 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 34 on Do: Start snap "kernel" (unset) services
Jun 12 19:17:11 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:11 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 13 on Do: Ensure prerequisites for "core18" are available
Jun 12 19:17:11 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:11 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 14 on Do: Prepare snap "/var/lib/snapd/seed/snaps/core18_x1.snap" (unset)
Jun 12 19:17:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 226.333ms 400
Jun 12 19:17:11 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:11 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 15 on Do: Mount snap "core18" (unset)
Jun 12 19:17:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.136667ms 400
Jun 12 19:17:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 448µs 400
Jun 12 19:17:13 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 362µs 400
Jun 12 19:17:13 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 720.666µs 400
Jun 12 19:17:14 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 380µs 400
Jun 12 19:17:14 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 365.667µs 400
Jun 12 19:17:15 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 366µs 400
Jun 12 19:17:15 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 363.667µs 400
Jun 12 19:17:16 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361.333µs 400
Jun 12 19:17:16 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 400.333µs 400
Jun 12 19:17:17 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 481.333µs 400
Jun 12 19:17:17 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 364.333µs 400
Jun 12 19:17:18 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 674µs 400
Jun 12 19:17:18 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 485µs 400
Jun 12 19:17:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 355.333µs 400
Jun 12 19:17:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 380µs 400
Jun 12 19:17:20 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 389.666µs 400
Jun 12 19:17:20 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 386µs 400
Jun 12 19:17:21 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 405µs 400
Jun 12 19:17:21 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 384µs 400
Jun 12 19:17:22 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 372µs 400
Jun 12 19:17:22 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 359µs 400
Jun 12 19:17:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 360µs 400
Jun 12 19:17:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 353µs 400
Jun 12 19:17:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 529.666µs 400
Jun 12 19:17:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 196.427333ms 400
Jun 12 19:17:25 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:25 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 16 on Do: Copy snap "core18" data
Jun 12 19:17:25 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:25 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 17 on Do: Setup snap "core18" (unset) security profiles
Jun 12 19:17:25 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:25 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 18 on Do: Make snap "core18" (unset) available to the system
Jun 12 19:17:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 203.985ms 400
Jun 12 19:17:25 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:25 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 19 on Do: Automatically connect eligible plugs and slots of snap "core18"
Jun 12 19:17:26 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:26 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 20 on Do: Set automatic aliases for snap "core18"
Jun 12 19:17:26 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:26 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 21 on Do: Setup snap "core18" aliases
Jun 12 19:17:26 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 186.583666ms 400
Jun 12 19:17:26 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:26 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 22 on Do: Run install hook of "core18" snap if present
Jun 12 19:17:26 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:26 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 23 on Do: Start snap "core18" (unset) services
Jun 12 19:17:26 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:26 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 36 on Do: Ensure prerequisites for "gadget" are available
Jun 12 19:17:26 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:26 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 37 on Do: Prepare snap "/var/lib/snapd/seed/snaps/gadget_x1.snap" (unset)
Jun 12 19:17:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 210.976334ms 400
Jun 12 19:17:27 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:27 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 38 on Do: Mount snap "gadget" (unset)
Jun 12 19:17:27 localhost snapd[3176]: devicestate.go:177: installing unasserted gadget "gadget"
Jun 12 19:17:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 395.333µs 400
Jun 12 19:17:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 421.333µs 400
Jun 12 19:17:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 359µs 400
Jun 12 19:17:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361.333µs 400
Jun 12 19:17:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 399µs 400
Jun 12 19:17:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 354.666µs 400
Jun 12 19:17:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 363µs 400
Jun 12 19:17:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 390µs 400
Jun 12 19:17:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 359.667µs 400
Jun 12 19:17:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361.666µs 400
Jun 12 19:17:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 354.334µs 400
Jun 12 19:17:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 387µs 400
Jun 12 19:17:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 417.333µs 400
Jun 12 19:17:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 459.667µs 400
Jun 12 19:17:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 362.334µs 400
Jun 12 19:17:35 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:35 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 39 on Do: Update assets from gadget "gadget" (unset)
Jun 12 19:17:35 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 68.040334ms 400
Jun 12 19:17:35 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:35 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 40 on Do: Copy snap "gadget" data
Jun 12 19:17:35 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:35 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 41 on Do: Setup snap "gadget" (unset) security profiles
Jun 12 19:17:35 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:35 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 42 on Do: Make snap "gadget" (unset) available to the system
Jun 12 19:17:36 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 255.680667ms 400
Jun 12 19:17:36 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:36 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 43 on Do: Automatically connect eligible plugs and slots of snap "gadget"
Jun 12 19:17:36 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:36 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 44 on Do: Set automatic aliases for snap "gadget"
Jun 12 19:17:36 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:36 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 45 on Do: Setup snap "gadget" aliases
Jun 12 19:17:36 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 266.847ms 400
Jun 12 19:17:36 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:36 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 46 on Do: Run install hook of "gadget" snap if present
Jun 12 19:17:37 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:37 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 47 on Do: Start snap "gadget" (unset) services
Jun 12 19:17:37 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:37 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 1 on Do: Run configure hook of "core" snap if present
Jun 12 19:17:37 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:37 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 35 on Do: Run configure hook of "kernel" snap if present
Jun 12 19:17:37 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 168.431666ms 400
Jun 12 19:17:37 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:37 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 48 on Do: Run configure hook of "gadget" snap if present
Jun 12 19:17:37 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:37 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 49 on Do: Ensure prerequisites for "network-manager" are available
Jun 12 19:17:37 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:37 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 50 on Do: Prepare snap "/var/lib/snapd/seed/snaps/network-manager_552.snap" (552)
Jun 12 19:17:38 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 165.126334ms 400
Jun 12 19:17:38 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:38 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 51 on Do: Mount snap "network-manager" (552)
Jun 12 19:17:38 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 479.666µs 400
Jun 12 19:17:39 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 390µs 400
Jun 12 19:17:39 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 4.490333ms 400
Jun 12 19:17:40 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361µs 400
Jun 12 19:17:40 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 363.333µs 400
Jun 12 19:17:41 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 407µs 400
Jun 12 19:17:41 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 533.667µs 400
Jun 12 19:17:42 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 354µs 400
Jun 12 19:17:42 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 362.667µs 400
Jun 12 19:17:43 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 417.333µs 400
Jun 12 19:17:43 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361.333µs 400
Jun 12 19:17:44 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361.333µs 400
Jun 12 19:17:44 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 357µs 400
Jun 12 19:17:45 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 420.666µs 400
Jun 12 19:17:45 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 434.333µs 400
Jun 12 19:17:46 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:46 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 52 on Do: Copy snap "network-manager" data
Jun 12 19:17:46 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 178.706333ms 400
Jun 12 19:17:46 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:17:46 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 53 on Do: Setup snap "network-manager" (552) security profiles
Jun 12 19:17:47 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 420µs 400
Jun 12 19:17:47 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 466.334µs 400
Jun 12 19:17:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 752.667µs 400
Jun 12 19:17:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 401.667µs 400
Jun 12 19:17:49 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 379.667µs 400
Jun 12 19:17:49 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 434.333µs 400
Jun 12 19:17:50 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 400µs 400
Jun 12 19:17:50 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 352.333µs 400
Jun 12 19:17:51 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 433.333µs 400
Jun 12 19:17:51 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 395.666µs 400
Jun 12 19:17:52 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 356µs 400
Jun 12 19:17:52 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 400.666µs 400
Jun 12 19:17:53 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 381.667µs 400
Jun 12 19:17:53 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 367.667µs 400
Jun 12 19:17:54 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 382µs 400
Jun 12 19:17:54 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 395µs 400
Jun 12 19:17:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 359.667µs 400
Jun 12 19:17:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 384.334µs 400
Jun 12 19:17:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 686µs 400
Jun 12 19:17:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 566µs 400
Jun 12 19:17:57 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 620.334µs 400
Jun 12 19:17:58 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 608.333µs 400
Jun 12 19:17:59 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 638.666µs 400
Jun 12 19:17:59 localhost snapd[3176]: udevmon.go:149: udev event error: Unable to parse uevent, err: no buffer space available
Jun 12 19:18:00 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 616.334µs 400
Jun 12 19:18:00 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 434µs 400
Jun 12 19:18:01 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 726.667µs 400
Jun 12 19:18:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 25.337667ms 400
Jun 12 19:18:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 620µs 400
Jun 12 19:18:03 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 588.333µs 400
Jun 12 19:18:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 809.333µs 400
Jun 12 19:18:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 401.333µs 400
Jun 12 19:18:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 397µs 400
Jun 12 19:18:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 620µs 400
Jun 12 19:18:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 627µs 400
Jun 12 19:18:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 575µs 400
Jun 12 19:18:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 690.333µs 400
Jun 12 19:18:08 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.333µs 400
Jun 12 19:18:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 78.21ms 400
Jun 12 19:18:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 357.333µs 400
Jun 12 19:18:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361µs 400
Jun 12 19:18:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 656.334µs 400
Jun 12 19:18:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 375.333µs 400
Jun 12 19:18:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 359µs 400
Jun 12 19:18:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 383.667µs 400
Jun 12 19:18:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 403.666µs 400
Jun 12 19:18:13 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 360.666µs 400
Jun 12 19:18:14 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 137.096666ms 400
Jun 12 19:18:14 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:14 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 54 on Do: Make snap "network-manager" (552) available to the system
Jun 12 19:18:19 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 4.948903s 400
Jun 12 19:18:19 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 55 on Do: Automatically connect eligible plugs and slots of snap "network-manager"
Jun 12 19:18:21 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.598128667s 400
Jun 12 19:18:21 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:21 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 123 on Do: Connect network-manager:ppp to snapd:ppp
Jun 12 19:18:21 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:21 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "snapd"
Jun 12 19:18:22 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:22 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 122 on Do: Connect network-manager:nmcli to network-manager:service
Jun 12 19:18:22 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "network-manager"
Jun 12 19:18:22 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 159.815333ms 400
Jun 12 19:18:22 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:22 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 125 on Do: Connect network-manager:login-session-observe to snapd:login-session-observe
Jun 12 19:18:22 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "snapd"
Jun 12 19:18:22 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:22 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 128 on Do: Connect network-manager:network-setup-observe to snapd:network-setup-observe
Jun 12 19:18:22 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "snapd"
Jun 12 19:18:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 90.772333ms 400
Jun 12 19:18:23 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:23 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 121 on Do: Connect network-manager:network to snapd:network
Jun 12 19:18:23 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "snapd"
Jun 12 19:18:23 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:23 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 120 on Do: Connect network-manager:hardware-observe to snapd:hardware-observe
Jun 12 19:18:23 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "snapd"
Jun 12 19:18:23 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 113.600666ms 400
Jun 12 19:18:23 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 126 on Do: Connect network-manager:network-observe to snapd:network-observe
Jun 12 19:18:23 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "snapd"
Jun 12 19:18:23 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:23 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 124 on Do: Connect network-manager:firewall-control to snapd:firewall-control
Jun 12 19:18:24 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "snapd"
Jun 12 19:18:24 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:24 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 127 on Do: Connect network-manager:network-setup-control to snapd:network-setup-control
Jun 12 19:18:24 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "snapd"
Jun 12 19:18:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 258.565ms 400
Jun 12 19:18:24 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:24 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 119 on Do: Setup snap "network-manager" (552) security profiles for auto-connections
Jun 12 19:18:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 394.667µs 400
Jun 12 19:18:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.151667ms 400
Jun 12 19:18:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 420µs 400
Jun 12 19:18:26 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 644.333µs 400
Jun 12 19:18:26 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 411.334µs 400
Jun 12 19:18:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 400µs 400
Jun 12 19:18:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 508.667µs 400
Jun 12 19:18:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 397µs 400
Jun 12 19:18:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 402µs 400
Jun 12 19:18:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 361.334µs 400
Jun 12 19:18:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 3.864333ms 400
Jun 12 19:18:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 400.667µs 400
Jun 12 19:18:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 397.333µs 400
Jun 12 19:18:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.522333ms 400
Jun 12 19:18:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 391.667µs 400
Jun 12 19:18:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 362µs 400
Jun 12 19:18:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 364.666µs 400
Jun 12 19:18:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 362.667µs 400
Jun 12 19:18:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 461.333µs 400
Jun 12 19:18:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 405.334µs 400
Jun 12 19:18:35 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 624.667µs 400
Jun 12 19:18:36 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 681.333µs 400
Jun 12 19:18:36 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 576.667µs 400
Jun 12 19:18:37 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 631.666µs 400
Jun 12 19:18:38 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 752.667µs 400
Jun 12 19:18:38 localhost snapd[3176]: udevmon.go:149: udev event error: Unable to parse uevent, err: no buffer space available
Jun 12 19:18:38 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 613.333µs 400
Jun 12 19:18:39 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 623.333µs 400
Jun 12 19:18:40 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 7.541667ms 400
Jun 12 19:18:40 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 614µs 400
Jun 12 19:18:41 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 612.334µs 400
Jun 12 19:18:42 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 446.334µs 400
Jun 12 19:18:42 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 625.333µs 400
Jun 12 19:18:43 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 9.643ms 400
Jun 12 19:18:43 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 644µs 400
Jun 12 19:18:44 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 628.667µs 400
Jun 12 19:18:45 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 399µs 400
Jun 12 19:18:45 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 620.333µs 400
Jun 12 19:18:46 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 607.666µs 400
Jun 12 19:18:47 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 620.667µs 400
Jun 12 19:18:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 216.333667ms 400
Jun 12 19:18:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 613µs 400
Jun 12 19:18:49 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 559µs 400
Jun 12 19:18:50 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 618.334µs 400
Jun 12 19:18:50 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 363.333µs 400
Jun 12 19:18:51 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 366.667µs 400
Jun 12 19:18:51 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 414.334µs 400
Jun 12 19:18:52 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 372µs 400
Jun 12 19:18:52 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 365.667µs 400
Jun 12 19:18:53 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 367.333µs 400
Jun 12 19:18:53 localhost modprobe[3723]: FATAL: Module arp_tables not found in directory /lib/modules/5.4.45-dirty
Jun 12 19:18:53 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 472µs 400
Jun 12 19:18:54 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:54 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 56 on Do: Set automatic aliases for snap "network-manager"
Jun 12 19:18:54 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 153.434333ms 400
Jun 12 19:18:54 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:54 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 57 on Do: Setup snap "network-manager" aliases
Jun 12 19:18:54 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:54 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 58 on Do: Run install hook of "network-manager" snap if present
Jun 12 19:18:55 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:55 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 59 on Do: Start snap "network-manager" (552) services
Jun 12 19:18:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 104.035ms 400
Jun 12 19:18:55 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:18:55 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 60 on Do: Run configure hook of "network-manager" snap if present
Jun 12 19:18:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 214.071ms 400
Jun 12 19:18:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 360.666µs 400
Jun 12 19:18:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 403.333µs 400
Jun 12 19:18:57 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 358.667µs 400
Jun 12 19:18:58 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 393.333µs 400
Jun 12 19:18:58 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 597.666µs 400
Jun 12 19:18:59 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 362µs 400
Jun 12 19:18:59 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 383.334µs 400
Jun 12 19:19:00 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 383µs 400
Jun 12 19:19:00 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 367µs 400
Jun 12 19:19:01 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 356.334µs 400
Jun 12 19:19:01 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 2.992ms 400
Jun 12 19:19:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 380µs 400
Jun 12 19:19:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 366.667µs 400
Jun 12 19:19:03 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 366.333µs 400
Jun 12 19:19:03 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 358µs 400
Jun 12 19:19:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 381.667µs 400
Jun 12 19:19:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3962;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.976666ms 200
Jun 12 19:19:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3972;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.821ms 200
Jun 12 19:19:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 417µs 400
Jun 12 19:19:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3979;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.607333ms 200
Jun 12 19:19:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3984;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.750667ms 200
Jun 12 19:19:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3991;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.768667ms 200
Jun 12 19:19:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3990;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.686667ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3999;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.441ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4004;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.857ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.308333ms 400
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4008;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.676666ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4013;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.77ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4017;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.613333ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4022;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.756667ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4026;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.627667ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4031;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.787ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4035;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.593667ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4041;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 3.083667ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 365.333µs 400
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4044;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.684ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4048;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.667666ms 200
Jun 12 19:19:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4052;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.659334ms 200
Jun 12 19:19:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4057;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.790666ms 200
Jun 12 19:19:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 382.333µs 400
Jun 12 19:19:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4060;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.698334ms 200
Jun 12 19:19:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4064;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.696333ms 200
Jun 12 19:19:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4067;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.669667ms 200
Jun 12 19:19:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4070;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.595ms 200
Jun 12 19:19:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 487.738667ms 400
Jun 12 19:19:07 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:19:07 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 61 on Do: Run health check of "network-manager" snap
Jun 12 19:19:07 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:19:07 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 62 on Do: Ensure prerequisites for "modem-manager" are available
Jun 12 19:19:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 130.142ms 400
Jun 12 19:19:07 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:19:07 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 63 on Do: Prepare snap "/var/lib/snapd/seed/snaps/modem-manager_416.snap" (416)
Jun 12 19:19:08 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:19:08 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 64 on Do: Mount snap "modem-manager" (416)
Jun 12 19:19:08 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 61.349ms 400
Jun 12 19:19:08 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 601.333µs 400
Jun 12 19:19:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 592µs 400
Jun 12 19:19:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 565µs 400
Jun 12 19:19:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.953334ms 400
Jun 12 19:19:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 581.334µs 400
Jun 12 19:19:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 511µs 400
Jun 12 19:19:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 557.333µs 400
Jun 12 19:19:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 606µs 400
Jun 12 19:19:13 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 581.667µs 400
Jun 12 19:19:13 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 520.666µs 400
Jun 12 19:19:14 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 543.666µs 400
Jun 12 19:19:14 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 541µs 400
Jun 12 19:19:15 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 521.333µs 400
Jun 12 19:19:15 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 520µs 400
Jun 12 19:19:16 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 570.333µs 400
Jun 12 19:19:16 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 666.333µs 400
Jun 12 19:19:17 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 666.666µs 400
Jun 12 19:19:17 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 588.667µs 400
Jun 12 19:19:18 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 590µs 400
Jun 12 19:19:18 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 124.05ms 400
Jun 12 19:19:18 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:19:18 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 65 on Do: Copy snap "modem-manager" data
Jun 12 19:19:19 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:19:19 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 66 on Do: Setup snap "modem-manager" (416) security profiles
Jun 12 19:19:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 71.253667ms 400
Jun 12 19:19:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 612.666µs 400
Jun 12 19:19:20 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 551.667µs 400
Jun 12 19:19:20 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571µs 400
Jun 12 19:19:21 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 986.667µs 400
Jun 12 19:19:21 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 607.334µs 400
Jun 12 19:19:22 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 528.667µs 400
Jun 12 19:19:22 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 539µs 400
Jun 12 19:19:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 580µs 400
Jun 12 19:19:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 616.667µs 400
Jun 12 19:19:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 581µs 400
Jun 12 19:19:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4349;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.660666ms 200
Jun 12 19:19:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4356;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.542ms 200
Jun 12 19:19:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 519µs 400
Jun 12 19:19:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4361;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 3.729334ms 200
Jun 12 19:19:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4368;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 8.129333ms 200
Jun 12 19:19:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 532µs 400
Jun 12 19:19:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4374;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.477333ms 200
Jun 12 19:19:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4379;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 6.231333ms 200
Jun 12 19:19:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4383;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 3.804667ms 200
Jun 12 19:19:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=4389;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 21.147334ms 200
Jun 12 19:19:26 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 760.333µs 400
Jun 12 19:19:26 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 521.333µs 400
Jun 12 19:19:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 572.667µs 400
Jun 12 19:19:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 544.333µs 400
Jun 12 19:19:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 632.666µs 400
Jun 12 19:19:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 527µs 400
Jun 12 19:19:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 611.667µs 400
Jun 12 19:19:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 577.334µs 400
Jun 12 19:19:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.667µs 400
Jun 12 19:19:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 530µs 400
Jun 12 19:19:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 581µs 400
Jun 12 19:19:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 588µs 400
Jun 12 19:19:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 529.666µs 400
Jun 12 19:19:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 508µs 400
Jun 12 19:19:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 612.333µs 400
Jun 12 19:19:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 573.333µs 400
Jun 12 19:19:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 518µs 400
Jun 12 19:19:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 575.334µs 400
Jun 12 19:19:35 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 515µs 400
Jun 12 19:19:35 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 559µs 400
Jun 12 19:19:36 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.497333ms 400
Jun 12 19:19:36 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 547µs 400
Jun 12 19:19:37 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.593ms 400
Jun 12 19:19:37 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 531.334µs 400
Jun 12 19:19:38 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 889µs 400
Jun 12 19:19:39 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.319667ms 400
Jun 12 19:19:40 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 876µs 400
Jun 12 19:19:40 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 908µs 400
Jun 12 19:19:41 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 956µs 400
Jun 12 19:19:42 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.031334ms 400
Jun 12 19:19:43 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 901.667µs 400
Jun 12 19:19:43 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 212.325ms 400
Jun 12 19:19:44 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 919.666µs 400
Jun 12 19:19:44 localhost snapd[3176]: udevmon.go:149: udev event error: Unable to parse uevent, err: no buffer space available
Jun 12 19:19:45 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 922µs 400
Jun 12 19:19:45 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 894µs 400
Jun 12 19:19:46 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 914.333µs 400
Jun 12 19:19:47 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 7.845334ms 400
Jun 12 19:19:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 875.666µs 400
Jun 12 19:19:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 881µs 400
Jun 12 19:19:49 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555µs 400
Jun 12 19:19:49 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.292ms 400
Jun 12 19:19:50 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 521µs 400
Jun 12 19:19:50 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 528µs 400
Jun 12 19:19:51 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 907.666µs 400
Jun 12 19:19:51 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 548.333µs 400
Jun 12 19:19:52 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.512ms 400
Jun 12 19:19:53 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 39.994333ms 400
Jun 12 19:19:53 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 517.667µs 400
Jun 12 19:19:54 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 525.667µs 400
Jun 12 19:19:54 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552µs 400
Jun 12 19:19:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 554.666µs 400
Jun 12 19:19:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 554.334µs 400
Jun 12 19:19:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 516µs 400
Jun 12 19:19:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 534.667µs 400
Jun 12 19:19:57 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 882µs 400
Jun 12 19:19:57 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 596.334µs 400
Jun 12 19:19:58 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 541µs 400
Jun 12 19:19:58 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 523.333µs 400
Jun 12 19:19:59 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 522.667µs 400
Jun 12 19:19:59 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 517µs 400
Jun 12 19:20:00 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552.333µs 400
Jun 12 19:20:00 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 516.667µs 400
Jun 12 19:20:01 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 529.666µs 400
Jun 12 19:20:01 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 532.666µs 400
Jun 12 19:20:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 522.333µs 400
Jun 12 19:20:03 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 556µs 400
Jun 12 19:20:03 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 547.334µs 400
Jun 12 19:20:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 563µs 400
Jun 12 19:20:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 517.667µs 400
Jun 12 19:20:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 528µs 400
Jun 12 19:20:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 526.333µs 400
Jun 12 19:20:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 56.817334ms 400
Jun 12 19:20:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 514µs 400
Jun 12 19:20:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 546.666µs 400
Jun 12 19:20:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 893µs 400
Jun 12 19:20:08 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 545µs 400
Jun 12 19:20:08 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 539µs 400
Jun 12 19:20:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 547.333µs 400
Jun 12 19:20:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 526.667µs 400
Jun 12 19:20:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 875µs 400
Jun 12 19:20:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 565.334µs 400
Jun 12 19:20:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 632µs 400
Jun 12 19:20:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 661.666µs 400
Jun 12 19:20:12 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:12 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 67 on Do: Make snap "modem-manager" (416) available to the system
Jun 12 19:20:17 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 5.236845667s 400
Jun 12 19:20:17 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:17 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 68 on Do: Automatically connect eligible plugs and slots of snap "modem-manager"
Jun 12 19:20:18 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:18 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 130 on Do: Connect modem-manager:mmcli to modem-manager:service
Jun 12 19:20:18 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:18 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "modem-manager" and "modem-manager"
Jun 12 19:20:18 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 157.054333ms 400
Jun 12 19:20:18 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:18 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 131 on Do: Connect network-manager:modem-manager to modem-manager:service
Jun 12 19:20:18 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "network-manager" and "modem-manager"
Jun 12 19:20:18 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:18 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 129 on Do: Setup snap "modem-manager" (416) security profiles for auto-connections
Jun 12 19:20:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 964.667µs 400
Jun 12 19:20:19 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:19 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 69 on Do: Set automatic aliases for snap "modem-manager"
Jun 12 19:20:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 271.612ms 400
Jun 12 19:20:19 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:19 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 70 on Do: Setup snap "modem-manager" aliases
Jun 12 19:20:20 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:20 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 71 on Do: Run install hook of "modem-manager" snap if present
Jun 12 19:20:20 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:20 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 72 on Do: Start snap "modem-manager" (416) services
Jun 12 19:20:20 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 95.448667ms 400
Jun 12 19:20:21 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:21 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 73 on Do: Run configure hook of "modem-manager" snap if present
Jun 12 19:20:21 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 222.295333ms 400
Jun 12 19:20:21 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 644.333µs 400
Jun 12 19:20:22 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 6.611666ms 400
Jun 12 19:20:22 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 542.666µs 400
Jun 12 19:20:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 570µs 400
Jun 12 19:20:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 540.333µs 400
Jun 12 19:20:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 561.667µs 400
Jun 12 19:20:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 615.333µs 400
Jun 12 19:20:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 561.667µs 400
Jun 12 19:20:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=5680;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.707ms 200
Jun 12 19:20:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=5683;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.942334ms 200
Jun 12 19:20:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 538.666µs 400
Jun 12 19:20:26 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 521.333µs 400
Jun 12 19:20:26 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:26 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 74 on Do: Run health check of "modem-manager" snap
Jun 12 19:20:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 305.762334ms 400
Jun 12 19:20:27 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:27 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 75 on Do: Ensure prerequisites for "node-red" are available
Jun 12 19:20:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 121.396ms 400
Jun 12 19:20:27 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:27 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 76 on Do: Prepare snap "/var/lib/snapd/seed/snaps/node-red_594.snap" (594)
Jun 12 19:20:28 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:28 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 77 on Do: Mount snap "node-red" (594)
Jun 12 19:20:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 134.581ms 400
Jun 12 19:20:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 619.667µs 400
Jun 12 19:20:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 659µs 400
Jun 12 19:20:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 616.666µs 400
Jun 12 19:20:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 4.497ms 400
Jun 12 19:20:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 550.333µs 400
Jun 12 19:20:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 577.333µs 400
Jun 12 19:20:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 579µs 400
Jun 12 19:20:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 573.666µs 400
Jun 12 19:20:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 950.333µs 400
Jun 12 19:20:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 569.333µs 400
Jun 12 19:20:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555.333µs 400
Jun 12 19:20:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 4.531667ms 400
Jun 12 19:20:35 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 553µs 400
Jun 12 19:20:35 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.146334ms 400
Jun 12 19:20:36 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.132333ms 400
Jun 12 19:20:36 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 589.334µs 400
Jun 12 19:20:37 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 551.667µs 400
Jun 12 19:20:37 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 559.333µs 400
Jun 12 19:20:38 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 623.334µs 400
Jun 12 19:20:38 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 554µs 400
Jun 12 19:20:39 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 551µs 400
Jun 12 19:20:39 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 576µs 400
Jun 12 19:20:40 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 563.667µs 400
Jun 12 19:20:40 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 598.334µs 400
Jun 12 19:20:41 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 568.333µs 400
Jun 12 19:20:41 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.667µs 400
Jun 12 19:20:42 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 911.667µs 400
Jun 12 19:20:42 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555.333µs 400
Jun 12 19:20:43 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 576.667µs 400
Jun 12 19:20:43 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.334µs 400
Jun 12 19:20:44 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 569.666µs 400
Jun 12 19:20:44 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 569.666µs 400
Jun 12 19:20:45 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 634.333µs 400
Jun 12 19:20:45 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 554µs 400
Jun 12 19:20:46 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:46 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 78 on Do: Copy snap "node-red" data
Jun 12 19:20:46 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 127.482333ms 400
Jun 12 19:20:46 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:46 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 79 on Do: Setup snap "node-red" (594) security profiles
Jun 12 19:20:46 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 705.666µs 400
Jun 12 19:20:47 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 697µs 400
Jun 12 19:20:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 654µs 400
Jun 12 19:20:48 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 622.666µs 400
Jun 12 19:20:49 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 159.211333ms 400
Jun 12 19:20:49 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:49 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 80 on Do: Make snap "node-red" (594) available to the system
Jun 12 19:20:54 localhost snapd[3176]: desktop.go:183: DEBUG: ignoring line 2 ("Encoding=UTF-8") in source of desktop file "node-red_node-red.desktop"
Jun 12 19:20:54 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 5.171179668s 400
Jun 12 19:20:54 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:54 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 81 on Do: Automatically connect eligible plugs and slots of snap "node-red"
Jun 12 19:20:55 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:55 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 133 on Do: Connect node-red:network to snapd:network
Jun 12 19:20:55 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:55 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "node-red" and "snapd"
Jun 12 19:20:55 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 186.446ms 400
Jun 12 19:20:55 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 134 on Do: Connect node-red:network-bind to snapd:network-bind
Jun 12 19:20:55 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:55 localhost snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "node-red" and "snapd"
Jun 12 19:20:55 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:55 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 132 on Do: Setup snap "node-red" (594) security profiles for auto-connections
Jun 12 19:20:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 635µs 400
Jun 12 19:20:56 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 558.334µs 400
Jun 12 19:20:57 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 619µs 400
Jun 12 19:20:57 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:57 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 76.496ms 400
Jun 12 19:20:57 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 82 on Do: Set automatic aliases for snap "node-red"
Jun 12 19:20:58 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:58 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 83 on Do: Setup snap "node-red" aliases
Jun 12 19:20:58 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:58 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 84 on Do: Run install hook of "node-red" snap if present
Jun 12 19:20:58 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 302.616667ms 400
Jun 12 19:20:58 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:58 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 85 on Do: Start snap "node-red" (594) services
Jun 12 19:20:59 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:59 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 86 on Do: Run configure hook of "node-red" snap if present
Jun 12 19:20:59 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 662.982333ms 400
Jun 12 19:20:59 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:20:59 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 87 on Do: Run health check of "node-red" snap
Jun 12 19:21:00 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:00 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 88 on Do: Ensure prerequisites for "base-files" are available
Jun 12 19:21:01 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 885.325667ms 400
Jun 12 19:21:01 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:01 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 89 on Do: Prepare snap "/var/lib/snapd/seed/snaps/base-files_x1.snap" (unset)
Jun 12 19:21:01 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 70.127666ms 400
Jun 12 19:21:01 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:01 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 90 on Do: Mount snap "base-files" (unset)
Jun 12 19:21:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.183667ms 400
Jun 12 19:21:02 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.554667ms 400
Jun 12 19:21:03 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 572µs 400
Jun 12 19:21:03 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 638.333µs 400
Jun 12 19:21:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 559.667µs 400
Jun 12 19:21:04 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 594.334µs 400
Jun 12 19:21:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.645333ms 400
Jun 12 19:21:05 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 589µs 400
Jun 12 19:21:06 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 623.667µs 400
Jun 12 19:21:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 575.333µs 400
Jun 12 19:21:07 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 594.333µs 400
Jun 12 19:21:08 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 719.667µs 400
Jun 12 19:21:08 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555.333µs 400
Jun 12 19:21:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552.667µs 400
Jun 12 19:21:09 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 546.667µs 400
Jun 12 19:21:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555.666µs 400
Jun 12 19:21:10 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 547.667µs 400
Jun 12 19:21:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.934334ms 400
Jun 12 19:21:11 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 579µs 400
Jun 12 19:21:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 551.667µs 400
Jun 12 19:21:12 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 563µs 400
Jun 12 19:21:13 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 563.333µs 400
Jun 12 19:21:13 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552.667µs 400
Jun 12 19:21:14 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 686.334µs 400
Jun 12 19:21:14 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 639.667µs 400
Jun 12 19:21:15 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 619µs 400
Jun 12 19:21:15 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555.666µs 400
Jun 12 19:21:16 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:16 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 91 on Do: Copy snap "base-files" data
Jun 12 19:21:16 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 386.754ms 400
Jun 12 19:21:16 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:16 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 92 on Do: Setup snap "base-files" (unset) security profiles
Jun 12 19:21:17 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 689µs 400
Jun 12 19:21:17 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 559.667µs 400
Jun 12 19:21:18 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 593.334µs 400
Jun 12 19:21:18 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 678.667µs 400
Jun 12 19:21:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.334µs 400
Jun 12 19:21:19 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 575.333µs 400
Jun 12 19:21:20 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 579µs 400
Jun 12 19:21:20 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:20 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 93 on Do: Make snap "base-files" (unset) available to the system
Jun 12 19:21:21 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 437.490333ms 400
Jun 12 19:21:21 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:21 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 94 on Do: Automatically connect eligible plugs and slots of snap "base-files"
Jun 12 19:21:21 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:21 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 95 on Do: Set automatic aliases for snap "base-files"
Jun 12 19:21:22 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 569.566667ms 400
Jun 12 19:21:22 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:22 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 96 on Do: Setup snap "base-files" aliases
Jun 12 19:21:22 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:22 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 97 on Do: Run install hook of "base-files" snap if present
Jun 12 19:21:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 348.784ms 400
Jun 12 19:21:23 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:23 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 98 on Do: Start snap "base-files" (unset) services
Jun 12 19:21:23 localhost snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:23 localhost snapd[3176]: taskrunner.go:437: DEBUG: Running task 99 on Do: Run configure hook of "base-files" snap if present
Jun 12 19:21:23 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 99.162667ms 400
Jun 12 19:21:24 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 615µs 400
Jun 12 19:21:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.171333ms 400
Jun 12 19:21:25 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 575.666µs 400
Jun 12 19:21:25 localhost sudo[5893]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/netplan/00-snapd-config.yaml
Jun 12 19:21:25 localhost sudo[5893]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 12 19:21:25 localhost sudo[5893]: pam_unix(sudo:session): session closed for user root
Jun 12 19:21:25 localhost sudo[5895]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cat
Jun 12 19:21:25 localhost sudo[5895]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 12 19:21:26 localhost sudo[5895]: pam_unix(sudo:session): session closed for user root
Jun 12 19:21:26 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 567µs 400
Jun 12 19:21:26 localhost sudo[5897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/netplan apply
Jun 12 19:21:26 localhost sudo[5897]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 12 19:21:26 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 570µs 400
Jun 12 19:21:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 595µs 400
Jun 12 19:21:27 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 590.333µs 400
Jun 12 19:21:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552.667µs 400
Jun 12 19:21:28 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 560µs 400
Jun 12 19:21:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 574.333µs 400
Jun 12 19:21:29 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 600µs 400
Jun 12 19:21:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 569µs 400
Jun 12 19:21:30 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 556.333µs 400
Jun 12 19:21:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555.667µs 400
Jun 12 19:21:31 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 546.666µs 400
Jun 12 19:21:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.401334ms 400
Jun 12 19:21:32 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 553µs 400
Jun 12 19:21:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 573µs 400
Jun 12 19:21:33 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 550.667µs 400
Jun 12 19:21:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.333µs 400
Jun 12 19:21:34 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 557µs 400
Jun 12 19:21:35 localhost snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 597.333µs 400
Jun 12 19:21:35 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 560µs 400
Jun 12 19:21:36 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 572.333µs 400
Jun 12 19:21:36 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 935.667µs 400
Jun 12 19:21:37 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 574.333µs 400
Jun 12 19:21:37 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 549.667µs 400
Jun 12 19:21:38 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555.333µs 400
Jun 12 19:21:38 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 629.667µs 400
Jun 12 19:21:39 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 556.334µs 400
Jun 12 19:21:39 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 564µs 400
Jun 12 19:21:40 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571µs 400
Jun 12 19:21:40 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 679.333µs 400
Jun 12 19:21:41 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 559.667µs 400
Jun 12 19:21:41 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 557.667µs 400
Jun 12 19:21:42 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 563.667µs 400
Jun 12 19:21:42 localhost.localdomain sudo[5897]: pam_unix(sudo:session): session closed for user root
Jun 12 19:21:42 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552µs 400
Jun 12 19:21:43 localhost.localdomain sudo[6053]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/su -c useradd --extrausers awesomedeviceuser -s /bin/bash -m
Jun 12 19:21:43 localhost.localdomain sudo[6053]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 12 19:21:43 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 574.333µs 400
Jun 12 19:21:43 localhost.localdomain su[6059]: Successful su for root by root
Jun 12 19:21:43 localhost.localdomain su[6059]: + ??? root:root
Jun 12 19:21:43 localhost.localdomain su[6059]: pam_unix(su:session): session opened for user root by (uid=0)
Jun 12 19:21:43 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 569.667µs 400
Jun 12 19:21:44 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.666µs 400
Jun 12 19:21:44 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 860µs 400
Jun 12 19:21:45 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 575.333µs 400
Jun 12 19:21:45 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 586.667µs 400
Jun 12 19:21:46 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 565µs 400
Jun 12 19:21:47 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 616µs 400
Jun 12 19:21:47 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 579µs 400
Jun 12 19:21:48 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 643.334µs 400
Jun 12 19:21:48 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 556.666µs 400
Jun 12 19:21:49 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 564.667µs 400
Jun 12 19:21:49 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 599µs 400
Jun 12 19:21:50 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 576.666µs 400
Jun 12 19:21:50 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 551.333µs 400
Jun 12 19:21:51 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.333µs 400
Jun 12 19:21:51 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 572.667µs 400
Jun 12 19:21:52 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 563.334µs 400
Jun 12 19:21:52 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=6266;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.685ms 200
Jun 12 19:21:52 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 559µs 400
Jun 12 19:21:52 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=6273;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 5.113667ms 200
Jun 12 19:21:52 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=6279;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.637333ms 200
Jun 12 19:21:53 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 615µs 400
Jun 12 19:21:53 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=6286;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 29.684333ms 200
Jun 12 19:21:53 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=6292;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 57.117333ms 200
Jun 12 19:21:53 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 595µs 400
Jun 12 19:21:53 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=6296;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 3.433667ms 200
Jun 12 19:21:53 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=6301;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 2.51ms 200
Jun 12 19:21:54 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=6306;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 8.240667ms 200
Jun 12 19:21:54 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 562.667µs 400
Jun 12 19:21:54 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 565µs 400
Jun 12 19:21:55 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.047667ms 400
Jun 12 19:21:55 localhost.localdomain sudo[6320]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/chpasswd
Jun 12 19:21:55 localhost.localdomain sudo[6320]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 12 19:21:55 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 586.667µs 400
Jun 12 19:21:56 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 553µs 400
Jun 12 19:21:56 localhost.localdomain chpasswd[6322]: pam_extrausers(chpasswd:chauthtok): password changed for awesomedeviceuser
Jun 12 19:21:56 localhost.localdomain sudo[6320]: pam_unix(sudo:session): session closed for user root
Jun 12 19:21:56 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 572.334µs 400
Jun 12 19:21:56 localhost.localdomain sudo[6326]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cat
Jun 12 19:21:56 localhost.localdomain sudo[6326]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 12 19:21:56 localhost.localdomain sudo[6326]: pam_unix(sudo:session): session closed for user root
Jun 12 19:21:57 localhost.localdomain sudo[6328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/hostnamectl set-hostname awesomedevicecore18
Jun 12 19:21:57 localhost.localdomain sudo[6328]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 12 19:21:57 localhost.localdomain snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 592.667µs 400
Jun 12 19:21:57 awesomedevicecore18 sudo[6328]: pam_unix(sudo:session): session closed for user root
Jun 12 19:21:57 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:57 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 164.637333ms 400
Jun 12 19:21:57 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 100 on Do: Run health check of "base-files" snap
Jun 12 19:21:58 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:58 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 101 on Do: Ensure prerequisites for "device-config" are available
Jun 12 19:21:59 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 646.248333ms 400
Jun 12 19:21:59 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:59 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 102 on Do: Prepare snap "/var/lib/snapd/seed/snaps/device-config_x1.snap" (unset)
Jun 12 19:21:59 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:21:59 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 103 on Do: Mount snap "device-config" (unset)
Jun 12 19:21:59 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 202.854666ms 400
Jun 12 19:22:00 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 790µs 400
Jun 12 19:22:00 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 652.333µs 400
Jun 12 19:22:01 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 556.666µs 400
Jun 12 19:22:01 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.008667ms 400
Jun 12 19:22:02 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 599.667µs 400
Jun 12 19:22:02 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552.667µs 400
Jun 12 19:22:03 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552µs 400
Jun 12 19:22:03 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 572.334µs 400
Jun 12 19:22:04 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 554µs 400
Jun 12 19:22:04 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 550µs 400
Jun 12 19:22:05 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 605.667µs 400
Jun 12 19:22:06 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 635.333µs 400
Jun 12 19:22:06 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 557.667µs 400
Jun 12 19:22:07 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 557µs 400
Jun 12 19:22:07 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 620.667µs 400
Jun 12 19:22:08 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 562.666µs 400
Jun 12 19:22:08 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 558.334µs 400
Jun 12 19:22:09 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 549µs 400
Jun 12 19:22:09 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 552µs 400
Jun 12 19:22:10 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 582.333µs 400
Jun 12 19:22:10 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 575.333µs 400
Jun 12 19:22:11 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 553.667µs 400
Jun 12 19:22:11 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 555µs 400
Jun 12 19:22:12 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 628.667µs 400
Jun 12 19:22:12 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 583µs 400
Jun 12 19:22:13 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 107.136667ms 400
Jun 12 19:22:13 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:13 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 104 on Do: Copy snap "device-config" data
Jun 12 19:22:13 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:13 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 105 on Do: Setup snap "device-config" (unset) security profiles
Jun 12 19:22:13 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 774.667µs 400
Jun 12 19:22:14 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 566.667µs 400
Jun 12 19:22:14 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 629.333µs 400
Jun 12 19:22:15 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 182.777666ms 400
Jun 12 19:22:15 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:15 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 106 on Do: Make snap "device-config" (unset) available to the system
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 5.322365667s 400
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 107 on Do: Automatically connect eligible plugs and slots of snap "device-config"
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 138 on Do: Connect device-config:network-bind to snapd:network-bind
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "device-config" and "snapd"
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 100.224333ms 400
Jun 12 19:22:21 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 137 on Do: Connect device-config:network to snapd:network
Jun 12 19:22:22 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:22 awesomedevicecore18 snapd[3176]: handlers.go:501: DEBUG: Connect handler: skipping setupSnapSecurity for snaps "device-config" and "snapd"
Jun 12 19:22:22 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:22 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 136 on Do: Setup snap "device-config" (x1) security profiles for auto-connections
Jun 12 19:22:22 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.677667ms 400
Jun 12 19:22:22 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 614µs 400
Jun 12 19:22:23 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:23 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 108 on Do: Set automatic aliases for snap "device-config"
Jun 12 19:22:23 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 348.154ms 400
Jun 12 19:22:23 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:23 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 109 on Do: Setup snap "device-config" aliases
Jun 12 19:22:24 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:24 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 110 on Do: Run install hook of "device-config" snap if present
Jun 12 19:22:24 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:24 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 111 on Do: Start snap "device-config" (unset) services
Jun 12 19:22:24 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 97.417333ms 400
Jun 12 19:22:25 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:25 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 112 on Do: Run configure hook of "device-config" snap if present
Jun 12 19:22:25 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 509.651333ms 400
Jun 12 19:22:26 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 584.333µs 400
Jun 12 19:22:26 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 611µs 400
Jun 12 19:22:26 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=6447;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.859ms 200
Jun 12 19:22:26 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=6452;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.892333ms 200
Jun 12 19:22:27 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=6455;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.677ms 200
Jun 12 19:22:27 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 621.667µs 400
Jun 12 19:22:27 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=6458;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 1.840333ms 200
Jun 12 19:22:27 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=6463;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 7.487ms 200
Jun 12 19:22:27 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.666µs 400
Jun 12 19:22:28 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 598.667µs 400
Jun 12 19:22:28 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 560.667µs 400
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=6476;uid=0;socket=/run/snapd-snap.socket; POST /v2/snapctl 239.223667ms 200
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 29.780333ms 400
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 113 on Do: Run health check of "device-config" snap
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 139 on Do: restart of [device-config.run]
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 640.333µs 400
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: catalogrefresh.go:71: DEBUG: CatalogRefresh:Ensure: skipping refresh, system is not seeded yet
Jun 12 19:22:29 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 114 on Do: Mark system seeded
Jun 12 19:22:30 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 391.175ms 400
Jun 12 19:22:30 awesomedevicecore18 snapd[3176]: catalogrefresh.go:100: DEBUG: Catalog refresh starting now; next scheduled for 2020-06-14 00:43:55.055378842 +0000 UTC m=+106029.865657499.
Jun 12 19:22:30 awesomedevicecore18 snapd[3176]: retry.go:49: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/sections, attempt 1, elapsed time=144.667µs
Jun 12 19:22:31 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.014334ms 400
Jun 12 19:22:31 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 571.334µs 400
Jun 12 19:22:32 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.073333ms 400
Jun 12 19:22:32 awesomedevicecore18 snapd[3176]: retry.go:61: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/sections finished after 1 retries, elapsed time=1.847417334s, status: 200
Jun 12 19:22:32 awesomedevicecore18 snapd[3176]: retry.go:49: DEBUG: Retrying https://api.snapcraft.io/api/v1/snaps/names?confinement=strict, attempt 1, elapsed time=40µs
Jun 12 19:22:32 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.107ms 400
Jun 12 19:22:33 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 1.051555ms 400
Jun 12 19:22:33 awesomedevicecore18 snapd[3176]: retry.go:184: DEBUG: Not retrying: &errors.errorString{s:"too many requests"}
Jun 12 19:22:33 awesomedevicecore18 snapd[3176]: retry.go:112: DEBUG: ShouldRetryError: too many requests *errors.errorString -> false
Jun 12 19:22:33 awesomedevicecore18 snapd[3176]: retry.go:61: DEBUG: The retry loop for https://api.snapcraft.io/api/v1/snaps/names?confinement=strict finished after 1 retries, elapsed time=1.115604133s, status: too many requests
Jun 12 19:22:33 awesomedevicecore18 snapd[3176]: catalogrefresh.go:107: DEBUG: Catalog refresh postponed.
Jun 12 19:22:33 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 140 on Do: Generate device key
Jun 12 19:22:34 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=3083;uid=0;socket=/run/snapd.socket; GET /v2/snaps/system/conf?keys=seed.loaded 394.842309ms 200
Jun 12 19:22:36 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=6515;uid=0;socket=/run/snapd.socket; GET /v2/system-info 2.750908ms 200
Jun 12 19:22:36 awesomedevicecore18 snapd[3176]: daemon.go:313: DEBUG: pid=6516;uid=0;socket=/run/snapd.socket; GET /v2/system-info 2.264051ms 200
Jun 12 19:23:52 awesomedevicecore18 snapd[3176]: taskrunner.go:437: DEBUG: Running task 141 on Do: Request device serial
Jun 12 19:23:55 awesomedevicecore18 snapd[3176]: task.go:337: DEBUG: 2020-06-12T19:23:55Z ERROR cannot deliver device serial request: Cannot process serial request for device with brand "UqvuXMIhQMIKtH2ysGFEVNKyAlOzW1W9" and model "awesome-device"
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment