Sorry if I was unclear, but could you run this after you have waited 10 minutes? All I see from the plot there is that it took snapd 3 minutes to seed and that I don’t see console-conf ran yet, but what I would like to see is a plot where console-conf was eventually run on the serial getty so we can see if something else got blocked waiting for that to run
Okay, I reinstalled and waited 10 minutes. This was all I got. It only goes to 380 seconds.
Hmm so what that implies is that the getty is ready at around 3min (and this is confirmed by the above systemd-analyze critical-chain serial-getty@... output), but it for some reason does not output the message to run console-conf by “Press enter to configure” until 7 minutes later. Is that correct ?
If so, then this sounds like a console-conf bug. I would refer you to bugs.launchpad.net/subiquity and to @xnox specifically
1 Like
IIRC in core18 console-conf was changed to actually wait until snapd marks the system fully seeded …
Gotcha. One remark that I posted about earlier, just so that we’re all on the same page, is that I tried @ogra’s suggestion of disabling console-conf and saw the same behavior, this behavior (now that I’ve updated to Kernel 5.4) being this output on first boot. This is the symptom indicating problems to me:
Starting Snap Daemon...
[ OK ] Started Snap Daemon.
[ OK ] Stopped Snap Daemon.
Starting Snap Daemon...
[ OK ] Closed /tmp/tmp.khPGA1wt81/usr/lib/snapd/snapd.
2020-06-15T19:43:58Z INFO Waiting for automatic snapd restart...
Automatically connect eligible plugs and slots of snap "snapd" -[ OK ] Started Snap Daemon.
Automatically connect eligible plugs and slots of snap "snapd" \[ OK ] Stopped Serial Getty on ttymxc1.
[ OK ] Started Serial Getty on ttymxc1.
[ OK ] Stopped Getty on tty1.
[ OK ] Started Getty on tty1.
Mount snap "kernel" (unset) - Mounting Mount unit for kernel, revision x1...
Mount snap "kernel" (unset) /[ OK ] Mounted Mount unit for kernel, revision x1.
Mount snap "core18" (1751) \ Mounting Mount unit for core18, revision 1751...
Mount snap "core18" (1751) -[ OK ] Mounted Mount unit for core18, revision 1751.
Mount snap "gadget" (unset) \ Mounting Mount unit for gadget, revision x1...
Mount snap "gadget" (unset) \[ OK ] Mounted Mount unit for gadget, revision x1.
Mount snap "core" (9291) \ Mounting Mount unit for core, revision 9291...
Mount snap "core" (9291) \[ OK ] Mounted Mount unit for core, revision 9291.
Mount snap "network-manager" (552) | Mounting Mount unit for network-manager, revision 552...
Mount snap "network-manager" (552) \[ OK ] Mounted Mount unit for network-manager, revision 552.
Setup snap "network-manager" (552) security profiles -[ OK ] Stopped Getty on tty1.
[ OK ] Started Getty on tty1.
[ OK ] Stopped Serial Getty on ttymxc1.
[ OK ] Started Serial Getty on ttymxc1.
Start snap "network-manager" (552) services |[ OK ] Started Service for snap application network-manager.networkmanager.
Mount snap "modem-manager" (416) \[ OK ] Stopped Service for snap application network-manager.networkmanager.
Mount snap "modem-manager" (416) |[ OK ] Started Service for snap application network-manager.networkmanager.
Mounting Mount unit for modem-manager, revision 416...
Mount snap "modem-manager" (416) |[ OK ] Mounted Mount unit for modem-manager, revision 416.
[ OK ] Stopped Serial Getty on ttymxc1.
[ OK ] Started Serial Getty on ttymxc1.
[ OK ] Stopped Getty on tty1.
[ OK ] Started Getty on tty1.
Start snap "modem-manager" (416) services |[ OK ] Started Service for snap application modem-manager.modemmanager.
Mount snap "node-red" (594) | Mounting Mount unit for node-red, revision 594...
Mount snap "node-red" (594) |[ OK ] Mounted Mount unit for node-red, revision 594.
Setup snap "node-red" (594) security profiles /[ OK ] Stopped Serial Getty on ttymxc1.
[ OK ] Started Serial Getty on ttymxc1.
[ OK ] Stopped Getty on tty1.
[ OK ] Started Getty on tty1.
Start snap "node-red" (594) services \[ OK ] Started Service for snap application node-red.node-red.
Mount snap "base-files" (unset) \ Mounting Mount unit for base-files, revision x1...
Mount snap "base-files" (unset) /[ OK ] Mounted Mount unit for base-files, revision x1.
Run configure hook of "base-files" snap if present / Starting Hostname Service...
Run configure hook of "base-files" snap if present /[ OK ] Started Hostname Service.
Run configure hook of "base-files" snap if present \ Stopping Service for snap applicati network-manager.networkmanager...
Run configure hook of "base-files" snap if present |[ OK ] Stopped Service for snap application network-manager.networkmanager.
Run configure hook of "base-files" snap if present |[ OK ] Started Service for snap application network-manager.networkmanager.
Run configure hook of "base-files" snap if present |[ OK ] Created slice User Slice of root.
Starting User Manager for UID 0...
[ OK ] Started Session c1 of user root.
Run configure hook of "base-files" snap if present \[ OK ] Started User Manager for UID 0.
Run configure hook of "base-files" snap if present | Stopping User Manager for UID 0...
Run configure hook of "base-files" snap if present -[ OK ] Stopped User Manager for UID 0.
[ OK ] Removed slice User Slice of root.
Mount snap "device-config" (unset) \ Mounting Mount unit for device-config, revision x1...
Mount snap "device-config" (unset) \[ OK ] Mounted Mount unit for device-config, revision x1.
Setup snap "device-config" (x1) security profiles for auto-connections /[ OK ] Stopped Getty on tty1.
[ OK ] Started Getty on tty1.
[ OK ] Stopped Serial Getty on ttymxc1.
[ OK ] Started Serial Getty on ttymxc1.
Start snap "device-config" (unset) services -[ OK ] Started Service for snap application device-config.run.
Mount snap "htop" (1217) \ Mounting Mount unit for htop, revision 1217...
Mount snap "htop" (1217) -[ OK ] Mounted Mount unit for htop, revision 1217.
Mount snap "nano" (29) | Mounting Mount unit for nano, revision 29...
Mount snap "nano" (29) |[ OK ] Mounted Mount unit for nano, revision 29.
Setup snap "nano" (29) security profiles | Stopping Service for snap application device-config.run...
restart of [device-config.run] /[ OK ] Stopped Service for snap application device-config.run.
[ OK ] Started Service for snap application device-config.run.
[ OK ] Started Start the snapd services from the snapd snap.
[ OK ] Started Ubuntu Core Firstboot Configuration tty1.
[ OK ] Started Ubuntu Core Firstboot Configuration ttymxc1.
[ OK ] Started Wait until snapd is fully seeded.
Starting Auto import assertions from block devices...
[ OK ] Started Auto import assertions from block devices.
[ OK ] Reached target Multi-User System.
[ OK ] Reached target Graphical Interface.
Starting Update UTMP about System Runlevel Changes...
Press enter to configure.
This takes about 7 minutes to get through.
Sorry your comment is a little confusing, you say you disabled console-conf, but in the log you pasted below, console-conf still runs, hence the Press enter to configure. message at the bottom. Was this log from a run with console-conf enabled, in which the Press enter to configure. message shows up after 7 minutes?
Ah my bad, so I have two boxes of identical make, and on one, I did the normal procedure, waited 10 minutes, and then pasted the log and the systemd-analyze. On the other I did was @ogra suggested.
Did you file a launchpad bug? If so please leave a link here, but I am lost as to where your 4 minutes are going on this device.
Here she is: https://bugs.launchpad.net/subiquity/+bug/1883749
1 Like