I'm seeing very slow startup on 3.8 latest when the lan cable is not connected. Does anyone know a solution?
Is this a boot on an already installed system you're talking about?
Or ....... the boot on the live system from USB (or DVD)?
In the latter case the live startup is slow due to it looking for wlan credentials on the the hard-drives.
The max probe time allotted is ~30 secs (too long IMO), after which it will continue to boot.
IMO it's an unneeded slowing down of the boot time and gives a bad impression of the speeds Elive is capable of......but hey, that's just me.
It's an installed system, 3.8 with updates applied on good hardware.
With a lan cable connected, using dhcp, the startup runs straight through.
When the lan cable is disconnected (and wifi pre-configured) it takes about 90 seconds to reach the desktop screen.
I'm guessing the pause is dhcp related, it waits for the lan and then tries wifi.
Normal start time with lan cable connected is 33 seconds
To see what's going on during boot, find out the names of your network devices (in my case enp0s25 for LAN and wlp4s0 for WLAN) and then check dmesg for logs in relation to your hardware :
dmesg |grep wlp4s0
... or whatever device you're looking for (like i.e your touchpad).
There might be some hints as to what's going on.
Or to scour dmesg page by page: dmesg | less
The startup waits for a dhcp timeout on the wired ethernet before attempting to try wifi, that is what is causing the long delay.
I don't know enough about systemd to workout if or how they can run in parallel. Can anyone share any insights?
If waiting for a LAN connection is the cause, it will show up in "dmesg".
Please back up your assumption with concrete logs.
There's simply no way to tell what's wrong with what you're communicating there. So be specific!
A 30sec error will definitely show in the logs.
I assume this issue only happens in Live mode, right? (should not happen when the OS is installed)
In any case is solved for the next build 3.8.24, betatests welcome
...and you're ignoring our feedback that 30s delay is way too much...
The delay is confirmed in the logs as waiting for dhcp timeout before starting wlan search
Oct 1 13:00:21 localhost systemd[1]: dns-clean.service: Succeeded.
Oct 1 13:00:21 localhost systemd[1]: Started Clean up any mess left by 0dns-up.
Oct 1 13:00:21 localhost systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Oct 1 13:00:21 localhost systemd[1]: Started Flush Journal to Persistent Storage.
Oct 1 13:00:21 localhost systemd[1]: Starting Create Volatile Files and Directories...
Oct 1 13:00:21 localhost systemd[1]: Started udev Kernel Device Manager.
Oct 1 13:00:21 localhost systemd[1]: Starting Show Plymouth Boot Screen...
Oct 1 13:00:21 localhost systemd-tmpfiles[374]: [/usr/lib/tmpfiles.d/fail2ban-tmpfiles.conf:1] Line references path below legacy directory /var/run/, updating /var/run/fail2ban → /run/fail2ban; please update the tmpfiles.d/ drop-in file accordingly.
Oct 1 13:00:21 localhost systemd[1]: plymouth-start.service: Succeeded.
Oct 1 13:00:21 localhost systemd[1]: Started Show Plymouth Boot Screen.
Oct 1 13:00:21 localhost systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
Oct 1 13:00:21 localhost systemd[1]: Reached target Local Encrypted Volumes.
Oct 1 13:00:21 localhost systemd[1]: Started Create Volatile Files and Directories.
Oct 1 13:00:21 localhost systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Oct 1 13:00:21 localhost systemd[1]: Started Update UTMP about System Boot/Shutdown.
Oct 1 13:00:21 localhost systemd-udevd[410]: Using default interface naming scheme 'v240'.
Oct 1 13:00:21 localhost systemd-udevd[410]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 1 13:00:21 localhost systemd[1]: Created slice system-systemd\x2dbacklight.slice.
Oct 1 13:00:21 localhost systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:intel_backlight...
Oct 1 13:00:21 localhost systemd-udevd[413]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 1 13:00:21 localhost systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:intel_backlight.
Oct 1 13:00:21 localhost laptop-mode: enabled, active
Oct 1 13:00:21 localhost systemd[1]: Found device INTEL_SSDSC2KW512G8 5.
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Oct 1 13:00:21 localhost systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in Kernel Configuration File System being skipped.
Oct 1 13:00:21 localhost systemd[1]: Starting Show Plymouth Boot Screen...
Oct 1 13:00:21 localhost systemd[1]: Activating swap /dev/disk/by-uuid/23bc9e3c-7cac-46ae-a17c-b40b2f21e611...
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in Network Time Synchronization being skipped.
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in FUSE Control File System being skipped.
Oct 1 13:00:21 localhost systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Oct 1 13:00:21 localhost systemd[1]: Received SIGRTMIN+20 from PID 250 (plymouthd).
Oct 1 13:00:21 localhost systemd[1]: plymouth-read-write.service: Succeeded.
Oct 1 13:00:21 localhost systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Oct 1 13:00:21 localhost systemd[1]: plymouth-start.service: Succeeded.
Oct 1 13:00:21 localhost systemd[1]: Started Show Plymouth Boot Screen.
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
Oct 1 13:00:21 localhost systemd[1]: Activated swap /dev/disk/by-uuid/23bc9e3c-7cac-46ae-a17c-b40b2f21e611.
Oct 1 13:00:21 localhost systemd[1]: Reached target Swap.
Oct 1 13:00:21 localhost systemd[1]: Reached target System Initialization.
Oct 1 13:00:21 localhost systemd[1]: Started Daily Cleanup of Temporary Directories.
Oct 1 13:00:21 localhost systemd[1]: Started Daily rotation of log files.
Oct 1 13:00:21 localhost systemd[1]: Started Trigger anacron every hour.
Oct 1 13:00:21 localhost systemd[1]: Started Runs Laptop Mode Tools - Polling Service, every 150 seconds.
Oct 1 13:00:21 localhost systemd[1]: Started Daily man-db regeneration.
Oct 1 13:00:21 localhost systemd[1]: Listening on ACPID Listen Socket.
Oct 1 13:00:21 localhost systemd[1]: Listening on CUPS Scheduler.
Oct 1 13:00:21 localhost systemd[1]: Started CUPS Scheduler.
Oct 1 13:00:21 localhost systemd[1]: Reached target Paths.
Oct 1 13:00:21 localhost systemd[1]: Started Daily apt download activities.
Oct 1 13:00:21 localhost systemd[1]: Started Daily apt upgrade and clean activities.
Oct 1 13:00:21 localhost systemd[1]: Started Discard unused blocks once a week.
Oct 1 13:00:21 localhost systemd[1]: Reached target Timers.
Oct 1 13:00:21 localhost systemd[1]: Listening on D-Bus System Message Bus Socket.
Oct 1 13:00:21 localhost systemd[1]: Reached target Sockets.
Oct 1 13:00:21 localhost systemd[1]: Reached target Basic System.
Oct 1 13:00:21 localhost systemd[1]: System is tainted: local-hwclock
Oct 1 13:00:21 localhost systemd[1]: Started Self Monitoring and Reporting Technology (SMART) Daemon.
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in Run anacron jobs being skipped.
Oct 1 13:00:21 localhost systemd[1]: Started ACPI event daemon.
Oct 1 13:00:21 localhost systemd[1]: Starting LSB: Close stalled processes...
Oct 1 13:00:21 localhost systemd[1]: Starting Restore /etc/resolv.conf if the system crashed before the ppp link was shut down...
Oct 1 13:00:21 localhost systemd[1]: Starting Disk Manager...
Oct 1 13:00:21 localhost systemd[1]: Started Manage Sound Card State (restore and store).
Oct 1 13:00:21 localhost alsactl[1100]: alsactl 1.1.8 daemon started
Oct 1 13:00:21 localhost systemd[1]: Starting LSB: Configure elivehealth...
Oct 1 13:00:21 localhost systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Oct 1 13:00:21 localhost systemd[1]: Started CUPS Scheduler.
Oct 1 13:00:21 localhost systemd[1]: Starting System Logging Service...
Oct 1 13:00:21 localhost systemd-udevd[416]: Using default interface naming scheme 'v240'.
Oct 1 13:00:21 localhost systemd-udevd[416]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 1 13:00:21 localhost systemd[1]: Started Make remote CUPS printers available locally.
Oct 1 13:00:21 localhost systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
Oct 1 13:00:21 localhost smartd[1091]: smartd 6.6 2017-11-05 r4594 [x86_64-linux-5.6.0-0.bpo.2-amd64] (local build)
Oct 1 13:00:21 localhost smartd[1091]: Copyright (C) 2002-17, Bruce Allen, Christian Franke, www.smartmontools.org
Oct 1 13:00:21 localhost smartd[1091]: Opened configuration file /etc/smartd.conf
Oct 1 13:00:21 localhost smartd[1091]: Drive: DEVICESCAN, implied '-a' Directive on line 21 of file /etc/smartd.conf
Oct 1 13:00:21 localhost smartd[1091]: Configuration file /etc/smartd.conf was parsed, found DEVICESCAN, scanning devices
Oct 1 13:00:21 localhost systemd[1]: Starting Modem Manager...
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda, type changed from 'scsi' to 'sat'
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda [SAT], opened
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda [SAT], INTEL SSDSC2KW512G8, S/N:BTLA7506057Y512DGN, WWN:5-5cd2e4-14ee991ff, FW:LHF002C, 512 GB
Oct 1 13:00:21 localhost systemd[1]: Started irqbalance daemon.
Oct 1 13:00:21 localhost systemd[1]: Starting Laptop Mode Tools...
Oct 1 13:00:21 localhost systemd[1]: Started Regular background program processing daemon.
Oct 1 13:00:21 localhost systemd[1]: Starting LSB: Start some power management scripts...
Oct 1 13:00:21 localhost systemd[1]: Starting Laptop Mode Tools - Battery Polling Service...
Oct 1 13:00:21 localhost systemd[1]: Started killswitch manager.
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda [SAT], not found in smartd database.
Oct 1 13:00:21 localhost systemd[1]: Starting Accounts Service...
Oct 1 13:00:21 localhost systemd[1]: Starting Save/Restore Sound Card State...
Oct 1 13:00:21 localhost systemd[1]: Starting Login Service...
Oct 1 13:00:21 localhost systemd[1]: Started D-Bus System Message Bus.
Oct 1 13:00:21 localhost systemd[1]: Starting WPA supplicant...
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda [SAT], can't monitor Current_Pending_Sector count - no Attribute 197
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda [SAT], can't monitor Offline_Uncorrectable count - no Attribute 198
Oct 1 13:00:21 localhost systemd[1]: Starting Network Manager...
Oct 1 13:00:21 localhost systemd[1]: pppd-dns.service: Succeeded.
Oct 1 13:00:21 localhost systemd[1]: Started Restore /etc/resolv.conf if the system crashed before the ppp link was shut down.
Oct 1 13:00:21 localhost systemd[1]: Started System Logging Service.
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda [SAT], is SMART capable. Adding to "monitor" list.
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda [SAT], state read from /var/lib/smartmontools/smartd.INTEL_SSDSC2KW512G8-BTLA7506057Y512DGN.ata.state
Oct 1 13:00:21 localhost smartd[1091]: Monitoring 1 ATA/SATA, 0 SCSI/SAS and 0 NVMe devices
Oct 1 13:00:21 localhost systemd[1]: Started Save/Restore Sound Card State.
Oct 1 13:00:21 localhost smartd[1091]: Device: /dev/sda [SAT], state written to /var/lib/smartmontools/smartd.INTEL_SSDSC2KW512G8-BTLA7506057Y512DGN.ata.state
Oct 1 13:00:21 localhost URfkill[1168]: Persistence file could not be loaded: No such file or directory
Oct 1 13:00:21 localhost systemd-udevd[382]: Using default interface naming scheme 'v240'.
Oct 1 13:00:21 localhost URfkill[1168]: Starting urfkilld version 0.5.0
Oct 1 13:00:21 localhost ModemManager[1146]: ModemManager (version 1.10.0) starting in system bus...
Oct 1 13:00:21 localhost systemd[1]: Started LSB: Close stalled processes.
Oct 1 13:00:21 localhost systemd[1]: lmt-poll.service: Succeeded.
Oct 1 13:00:21 localhost systemd[1]: Started Laptop Mode Tools - Battery Polling Service.
Oct 1 13:00:21 localhost systemd[1]: Reloading Laptop Mode Tools.
Oct 1 13:00:21 localhost systemd[1]: Reached target Sound Card.
Oct 1 13:00:21 localhost systemd[1]: Starting Load/Save RF Kill Switch Status...
Oct 1 13:00:21 localhost systemd[1]: Started Load/Save RF Kill Switch Status.
Oct 1 13:00:21 localhost systemd[1]: Reloaded Laptop Mode Tools.
Oct 1 13:00:21 localhost systemd[1]: Started WPA supplicant.
Oct 1 13:00:21 localhost wpa_supplicant[1182]: Successfully initialized wpa_supplicant
Oct 1 13:00:21 localhost dbus-daemon[1176]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1168 comm="/usr/lib/x86_64-linux-gnu/urfkill/urfkilld ")
Oct 1 13:00:21 localhost systemd[1]: Started Network Manager.
Oct 1 13:00:21 localhost systemd[1]: Starting Network Manager Wait Online...
Oct 1 13:00:21 localhost systemd[1]: Starting Authorization Manager...
Oct 1 13:00:21 localhost dbus-daemon[1176]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=1183 comm="/usr/sbin/NetworkManager --no-daemon ")
Oct 1 13:00:21 localhost systemd[1]: Starting Hostname Service...
Oct 1 13:00:21 localhost polkitd[1384]: started daemon version 0.105 using authority implementation local' version
0.105'
Oct 1 13:00:21 localhost dbus-daemon[1176]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Oct 1 13:00:21 localhost systemd[1]: Started Authorization Manager.
Oct 1 13:00:21 localhost accounts-daemon[1170]: started daemon version 0.6.45
Oct 1 13:00:21 localhost systemd[1]: Started Accounts Service.
Oct 1 13:00:21 localhost URfkill[1168]: adding killswitch idx 0 soft 0 hard 0
Oct 1 13:00:21 localhost URfkill[1168]: killswitch state: KILLSWITCH_STATE_NO_ADAPTER new_state: KILLSWITCH_STATE_UNBLOCKED
Oct 1 13:00:21 localhost URfkill[1168]: Setting device 0 (WLAN) to unblock
Oct 1 13:00:21 localhost URfkill[1168]: Setting WLAN to unblocked
Oct 1 13:00:21 localhost URfkill[1168]: Setting BLUETOOTH to unblocked
Oct 1 13:00:21 localhost URfkill[1168]: Setting UWB to unblocked
Oct 1 13:00:21 localhost URfkill[1168]: Setting WIMAX to unblocked
Oct 1 13:00:21 localhost URfkill[1168]: Setting WWAN to unblocked
Oct 1 13:00:21 localhost URfkill[1168]: Setting GPS to unblocked
Oct 1 13:00:21 localhost URfkill[1168]: Setting FM to unblocked
Oct 1 13:00:21 localhost URfkill[1168]: Setting NFC to unblocked
Oct 1 13:00:21 localhost dbus-daemon[1176]: [system] Successfully activated service 'org.freedesktop.hostname1'
Oct 1 13:00:21 localhost systemd[1]: Started Hostname Service.
Oct 1 13:00:21 localhost dbus-daemon[1176]: [system] Activating via systemd: service name='org.freedesktop.login1' unit='dbus-org.freedesktop.login1.service' requested by ':1.5' (uid=0 pid=1168 comm="/usr/lib/x86_64-linux-gnu/urfkill/urfkilld ")
Oct 1 13:00:21 localhost systemd[1]: Started Modem Manager.
Oct 1 13:00:21 localhost loadcpufreq[1139]: Loading cpufreq kernel modules...libkmod: ERROR ../libkmod/libkmod-module.c:1931 kmod_module_get_holders: could not open '/sys/module/acpi_cpufreq/holders': No such file or directory
Oct 1 13:00:21 localhost loadcpufreq[1139]: libkmod: ERROR ../libkmod/libkmod-module.c:1931 kmod_module_get_holders: could not open '/sys/module/pcc_cpufreq/holders': No such file or directory
Oct 1 13:00:21 localhost acpid: starting up with netlink and the input layer
Oct 1 13:00:21 localhost dbus-daemon[1176]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4' (uid=0 pid=1183 comm="/usr/sbin/NetworkManager --no-daemon ")
Oct 1 13:00:21 localhost acpid: 30 rules loaded
Oct 1 13:00:21 localhost acpid: waiting for events: event logging is off
Oct 1 13:00:21 localhost systemd[1]: Starting Network Manager Script Dispatcher Service...
Oct 1 13:00:21 localhost dbus-daemon[1176]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Oct 1 13:00:21 localhost systemd[1]: Started Network Manager Script Dispatcher Service.
Oct 1 13:00:21 localhost nm-dispatcher: req:1 'hostname': new request (4 scripts)
Oct 1 13:00:21 localhost nm-dispatcher: req:1 'hostname': start running ordered scripts...
Oct 1 13:00:22 localhost systemd[1]: Started Disk Manager.
Oct 1 13:00:22 localhost systemd[1]: Started LSB: Configure elivehealth.
Oct 1 13:00:22 localhost systemd[1]: Starting Bluetooth service...
Oct 1 13:00:22 localhost bluetoothd[1965]: Bluetooth daemon 5.50
Oct 1 13:00:22 localhost systemd[1]: Started Bluetooth service.
Oct 1 13:00:22 localhost systemd[1]: Reached target Bluetooth.
Oct 1 13:00:22 localhost bluetoothd[1965]: Starting SDP server
Oct 1 13:00:22 localhost nm-dispatcher: req:2 'connectivity-change': new request (4 scripts)
Oct 1 13:00:22 localhost nm-dispatcher: req:2 'connectivity-change': start running ordered scripts...
Oct 1 13:00:22 localhost bluetoothd[1965]: Bluetooth management interface 1.15 initialized
Oct 1 13:00:22 localhost bluetoothd[1965]: Sap driver initialization failed.
Oct 1 13:00:22 localhost bluetoothd[1965]: sap-server: Operation not permitted (1)
Oct 1 13:00:22 localhost acpi-support[1154]: Enabling power management...done.
Oct 1 13:00:22 localhost systemd[1]: Started LSB: Start some power management scripts.
Oct 1 13:00:22 localhost loadcpufreq[1139]: done (acpi-cpufreq).
Oct 1 13:00:22 localhost systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Oct 1 13:00:22 localhost systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Oct 1 13:00:22 localhost dbus-daemon[1176]: [system] Successfully activated service 'org.freedesktop.login1'
Oct 1 13:00:22 localhost systemd[1]: Started Login Service.
Oct 1 13:00:22 localhost URfkill[1168]: adding killswitch idx 1 soft 0 hard 0
Oct 1 13:00:22 localhost URfkill[1168]: killswitch state: KILLSWITCH_STATE_NO_ADAPTER new_state: KILLSWITCH_STATE_UNBLOCKED
Oct 1 13:00:22 localhost URfkill[1168]: Setting device 1 (BLUETOOTH) to unblock
Oct 1 13:00:23 localhost cpufrequtils[2375]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...CPU4...CPU5...CPU6...CPU7...done.
Oct 1 13:00:23 localhost systemd[1]: Started LSB: set CPUFreq kernel parameters.
Oct 1 13:00:24 localhost ModemManager[1146]: Couldn't check support for device '/sys/devices/pci0000:00/0000:00:1c.2/0000:05:00.2': not supported by any plugin
Oct 1 13:00:24 localhost ModemManager[1146]: Couldn't check support for device '/sys/devices/pci0000:00/0000:00:1c.3/0000:06:00.0': not supported by any plugin
Oct 1 13:00:25 localhost laptop-mode: enabled, active
Oct 1 13:00:25 localhost wpa_supplicant[1182]: wlp6s0: SME: Trying to authenticate with e0:63:da:d6:0c:a5 (SSID='mechtron' freq=2462 MHz)
Oct 1 13:00:25 localhost wpa_supplicant[1182]: wlp6s0: Trying to associate with e0:63:da:d6:0c:a5 (SSID='mechtron' freq=2462 MHz)
Oct 1 13:00:25 localhost wpa_supplicant[1182]: wlp6s0: Associated with e0:63:da:d6:0c:a5
Oct 1 13:00:25 localhost wpa_supplicant[1182]: wlp6s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 1 13:00:25 localhost wpa_supplicant[1182]: wlp6s0: WPA: Key negotiation completed with e0:63:da:d6:0c:a5 [PTK=CCMP GTK=CCMP]
Oct 1 13:00:25 localhost wpa_supplicant[1182]: wlp6s0: CTRL-EVENT-CONNECTED - Connection to e0:63:da:d6:0c:a5 completed [id=0 id_str=]
Oct 1 13:00:25 localhost dhclient[6428]: DHCPREQUEST for 192.168.7.53 on wlp6s0 to 255.255.255.255 port 67
Oct 1 13:00:25 localhost dhclient[6428]: DHCPACK of 192.168.7.53 from 192.168.7.254
Oct 1 13:00:25 localhost dhclient[6428]: bound to 192.168.7.53 -- renewal in 20210 seconds.
Oct 1 13:00:25 localhost nm-dispatcher: req:3 'up' [wlp6s0]: new request (4 scripts)
Oct 1 13:00:25 localhost nm-dispatcher: req:3 'up' [wlp6s0]: start running ordered scripts...
Oct 1 13:00:25 localhost nm-dispatcher: req:4 'connectivity-change': new request (4 scripts)
Oct 1 13:00:25 localhost systemd[1]: Started Network Manager Wait Online.
Oct 1 13:00:26 localhost wpa_supplicant[1182]: wlp6s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-53 noise=9999 txrate=1000
Oct 1 13:00:27 localhost systemd[1]: systemd-rfkill.service: Succeeded.
Oct 1 13:00:28 localhost laptop-mode: enabled, active
Oct 1 13:00:32 localhost systemd[1]: Started Helper to synchronize boot up for ifupdown.
Oct 1 13:00:32 localhost systemd[1]: Starting Raise network interfaces...
Oct 1 13:00:32 localhost dhclient[14282]: Internet Systems Consortium DHCP Client 4.4.1
Oct 1 13:00:32 localhost ifup[14237]: Internet Systems Consortium DHCP Client 4.4.1
Oct 1 13:00:32 localhost ifup[14237]: Copyright 2004-2018 Internet Systems Consortium.
Oct 1 13:00:32 localhost ifup[14237]: All rights reserved.
Oct 1 13:00:32 localhost ifup[14237]: For info, please visit ISC DHCP - ISC
Oct 1 13:00:32 localhost dhclient[14282]: Copyright 2004-2018 Internet Systems Consortium.
Oct 1 13:00:32 localhost dhclient[14282]: All rights reserved.
Oct 1 13:00:32 localhost dhclient[14282]: For info, please visit ISC DHCP - ISC
Oct 1 13:00:32 localhost dhclient[14282]:
Oct 1 13:00:32 localhost dhclient[14282]: Listening on LPF/enp5s0f2/00:90:f5:e9:a5:71
Oct 1 13:00:32 localhost ifup[14237]: Listening on LPF/enp5s0f2/00:90:f5:e9:a5:71
Oct 1 13:00:32 localhost ifup[14237]: Sending on LPF/enp5s0f2/00:90:f5:e9:a5:71
Oct 1 13:00:32 localhost ifup[14237]: Sending on Socket/fallback
Oct 1 13:00:32 localhost ifup[14237]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 3
Oct 1 13:00:32 localhost dhclient[14282]: Sending on LPF/enp5s0f2/00:90:f5:e9:a5:71
Oct 1 13:00:32 localhost dhclient[14282]: Sending on Socket/fallback
Oct 1 13:00:32 localhost dhclient[14282]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 3
Oct 1 13:00:35 localhost dhclient[14282]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 7
Oct 1 13:00:35 localhost ifup[14237]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 7
Oct 1 13:00:37 localhost nm-dispatcher: req:4 'connectivity-change': start running ordered scripts...
Oct 1 13:00:42 localhost dhclient[14282]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 13
Oct 1 13:00:42 localhost ifup[14237]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 13
Oct 1 13:00:48 localhost systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Oct 1 13:00:52 localhost systemd[1]: systemd-hostnamed.service: Succeeded.
Oct 1 13:00:55 localhost dhclient[14282]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 11
Oct 1 13:00:55 localhost ifup[14237]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 11
Oct 1 13:01:06 localhost dhclient[14282]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 8
Oct 1 13:01:06 localhost ifup[14237]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 8
Oct 1 13:01:14 localhost dhclient[14282]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 12
Oct 1 13:01:14 localhost ifup[14237]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 12
Oct 1 13:01:26 localhost dhclient[14282]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 7
Oct 1 13:01:26 localhost ifup[14237]: DHCPDISCOVER on enp5s0f2 to 255.255.255.255 port 67 interval 7
Oct 1 13:01:33 localhost dhclient[14282]: No DHCPOFFERS received.
Oct 1 13:01:33 localhost ifup[14237]: No DHCPOFFERS received.
Oct 1 13:01:33 localhost ifup[14237]: No working leases in persistent database - sleeping.
Oct 1 13:01:33 localhost dhclient[14282]: No working leases in persistent database - sleeping.
Oct 1 13:01:33 localhost systemd[1]: Started Raise network interfaces.
Oct 1 13:01:33 localhost systemd[1]: Reached target Network.
Oct 1 13:01:33 localhost systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Oct 1 13:01:33 localhost systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server...
Oct 1 13:01:33 localhost systemd[1]: Starting PostgreSQL Cluster 11-main...
Oct 1 13:01:33 localhost systemd[1]: Starting Fail2Ban Service...
Oct 1 13:01:33 localhost systemd[1]: Starting OpenVPN service...
Oct 1 13:01:33 localhost systemd[1]: Starting Permit User Sessions...
Oct 1 13:01:33 localhost dnsmasq[14397]: dnsmasq: syntax check OK.
Oct 1 13:01:33 localhost systemd[1]: Starting OpenBSD Secure Shell server...
Oct 1 13:01:33 localhost systemd[1]: Reached target Network is Online.
Oct 1 13:01:33 localhost systemd[1]: Starting Internet superserver...
Oct 1 13:01:33 localhost systemd[1]: Starting LSB: disk temperature monitoring daemon...
Oct 1 13:01:33 localhost systemd[1]: Starting Samba NMB Daemon...
Oct 1 13:01:33 localhost systemd[1]: Starting LSB: VirtualBox Linux kernel module...
Oct 1 13:01:33 localhost systemd[1]: Started strongSwan IPsec IKEv1/IKEv2 daemon using ipsec.conf.
Oct 1 13:01:33 localhost systemd[1]: Starting LSB: layer 2 tunelling protocol daemon...
Oct 1 13:01:33 localhost systemd[1]: Starting /etc/rc.local Compatibility...
Oct 1 13:01:33 localhost systemd[1]: Started Internet superserver.
Oct 1 13:01:33 localhost systemd[1]: Started Fail2Ban Service.
Oct 1 13:01:33 localhost systemd[1]: Started OpenVPN service.
Oct 1 13:01:33 localhost systemd[1]: Started Permit User Sessions.
Oct 1 13:01:33 localhost systemd[1]: Started /etc/rc.local Compatibility.
Oct 1 13:01:33 localhost ipsec[14409]: Starting strongSwan 5.7.2 IPsec [starter]...
Oct 1 13:01:33 localhost systemd[1]: Started OpenBSD Secure Shell server.
Oct 1 13:01:34 localhost systemd[1]: Started LSB: disk temperature monitoring daemon.
Oct 1 13:01:34 localhost xl2tpd[14434]: Not looking for kernel SAref support.
Oct 1 13:01:34 localhost systemd[1]: Starting Light Display Manager...
Oct 1 13:01:34 localhost dnsmasq[14441]: started, version 2.80 cachesize 150
Oct 1 13:01:34 localhost dnsmasq[14441]: DNS service limited to local subnets
Oct 1 13:01:34 localhost dnsmasq[14441]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile
Oct 1 13:01:34 localhost dnsmasq[14441]: reading /etc/resolv.conf
Oct 1 13:01:34 localhost dnsmasq[14441]: using nameserver 192.168.7.254#53
Oct 1 13:01:34 localhost dnsmasq[14441]: read /etc/hosts - 8 addresses
Oct 1 13:01:34 localhost systemd[1]: Starting Hold until boot process finishes up...
Oct 1 13:01:34 localhost systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server.
Oct 1 13:01:34 localhost systemd[1]: Reached target Host and Network Name Lookups.
Oct 1 13:01:34 localhost xl2tpd[14434]: Using l2tp kernel support.
Oct 1 13:01:34 localhost xl2tpd[14411]: Starting xl2tpd: xl2tpd.
Oct 1 13:01:34 localhost xl2tpd[14469]: xl2tpd version xl2tpd-1.3.12 started on tanker PID:14469
Oct 1 13:01:34 localhost systemd[1]: Started LSB: layer 2 tunelling protocol daemon.
Oct 1 13:01:34 localhost xl2tpd[14469]: Written by Mark Spencer, Copyright (C) 1998, Adtran, Inc.
Oct 1 13:01:34 localhost xl2tpd[14469]: Forked by Scott Balmos and David Stipp, (C) 2001
Oct 1 13:01:34 localhost xl2tpd[14469]: Inherited by Jeff McAdams, (C) 2002
Oct 1 13:01:34 localhost xl2tpd[14469]: Forked again by Xelerance (www.xelerance.com) (C) 2006-2016
Oct 1 13:01:34 localhost xl2tpd[14469]: Listening on IP address 0.0.0.0, port 1701
Oct 1 13:01:34 localhost charon: 00[DMN] Starting IKE charon daemon (strongSwan 5.7.2, Linux 5.6.0-0.bpo.2-amd64, x86_64)
Oct 1 13:01:34 localhost charon: 00[CFG] loading ca certificates from '/etc/ipsec.d/cacerts'
Oct 1 13:01:34 localhost charon: 00[CFG] loading aa certificates from '/etc/ipsec.d/aacerts'
Oct 1 13:01:34 localhost charon: 00[CFG] loading ocsp signer certificates from '/etc/ipsec.d/ocspcerts'
Oct 1 13:01:34 localhost charon: 00[CFG] loading attribute certificates from '/etc/ipsec.d/acerts'
Oct 1 13:01:34 localhost charon: 00[CFG] loading crls from '/etc/ipsec.d/crls'
Oct 1 13:01:34 localhost charon: 00[CFG] loading secrets from '/etc/ipsec.secrets'
Oct 1 13:01:34 localhost charon: 00[CFG] expanding file expression '/var/lib/strongswan/ipsec.secrets.inc' failed
Oct 1 13:01:34 localhost charon: 00[LIB] loaded plugins: charon aes rc2 sha2 sha1 md5 mgf1 random nonce x509 revocation constraints pubkey pkcs1 pkcs7 pkcs8 pkcs12 pgp dnskey sshkey pem fips-prf gmp xcbc hmac attr kernel-netlink resolve socket-default stroke updown counters
Oct 1 13:01:34 localhost charon: 00[LIB] dropped capabilities, running as uid 0, gid 0
Oct 1 13:01:34 localhost charon: 00[JOB] spawning 16 worker threads
Oct 1 13:01:34 localhost ipsec[14409]: charon (14468) started after 40 ms
Oct 1 13:01:34 localhost systemd[1]: Started Samba NMB Daemon.
Oct 1 13:01:34 localhost systemd[1]: Starting Samba SMB Daemon...
Oct 1 13:01:34 localhost systemd[1]: Received SIGRTMIN+21 from PID 250 (plymouthd).
Oct 1 13:01:34 localhost systemd[1]: Received SIGRTMIN+21 from PID 250 (plymouthd).
Oct 1 13:01:34 localhost virtualbox[14406]: Loading VirtualBox kernel modules...error: unexpectedly disconnected from boot status daemon
Oct 1 13:01:34 localhost systemd[1]: plymouth-quit-wait.service: Succeeded.
Oct 1 13:01:34 localhost virtualbox[14406]: .
Oct 1 13:01:34 localhost systemd[1]: Started Hold until boot process finishes up.
Oct 1 13:01:34 localhost systemd[1]: Started LSB: VirtualBox Linux kernel module.
Oct 1 13:01:34 localhost systemd[1]: Started Light Display Manager.
Oct 1 13:01:34 localhost systemd[1]: Started Getty on tty1.
Oct 1 13:01:34 localhost systemd[1]: Reached target Login Prompts.
Oct 1 13:01:34 localhost systemd[1]: Started Samba SMB Daemon.
Oct 1 13:01:34 localhost fail2ban-server[14420]: Server ready
Oct 1 13:01:36 localhost systemd[1]: Started PostgreSQL Cluster 11-main.
Oct 1 13:01:36 localhost systemd[1]: Starting PostgreSQL RDBMS...
Oct 1 13:01:36 localhost systemd[1]: Started PostgreSQL RDBMS.
Oct 1 13:01:36 localhost systemd[1]: Reached target Multi-User System.
Oct 1 13:01:36 localhost systemd[1]: Reached target Graphical Interface.
Oct 1 13:01:36 localhost systemd[1]: Starting Update UTMP about System Runlevel Changes...
Oct 1 13:01:36 localhost systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Oct 1 13:01:36 localhost systemd[1]: Started Update UTMP about System Runlevel Changes.
This happens on installed to disk.
My understanding of systemd is that it should start eth & wlan at the same time. It waits for eth dhcp to timeout before wlan is started.
Thanks for a great system, I've been using it since gem.
Well actually your wlan is the first to get activated by the kernel.
at 13.00.25 interface wlp6s0 logs in to "mechtron" and is connected at 13.00.26
That's one second.
Then at 13.00.32 the lan inteface enps5s0 kicks in and drops again at 13.00.33 due to not receiving any DHCPOFFERS.
At 13.00.55 systemd tries enps5s0 again to no avail...and does indeed need almost 30secs to activate wlps6s0
It's strange that it's that persistent there. Could it be that it was used as the initial connection during setup/install and wlan setup later?
If you've got a LAN cable plugged in, remove it to get some speed at boot or ...... have it connect to a properly set up gateway for dual connectivity.
..... Or disable the interface.
In comparison:
My (encrypted) Thinkpad X1Carbon boots up in 30secs....to a full desktop.
It looks like something broke in your network setup. At least I cannot replicate on my machines.