Author Topic: What's causing systemd (userspace) to take 12 minutes 27 sec to run on LiveCD?  (Read 690 times)

VastOne

  •      v-ger
  • Posts: 4131
    • View Profile
    • VSIDO Community
I need new eyes on this please to help me spot what is happening

Attached to this are two files.. the journalctl -b of both Stock and New VSIDO ISO.. 1.txt is from the original Stock and 2.txt is from the New VSIDO

This is a journalctl -b section of the Stock ISO that is taking 12 minutes and 27 seconds to run


Code: [Select]
Feb 03 18:12:10 vsido ntpdate[626]: step time server 137.190.2.4 offset 1.628547 sec
Feb 03 18:16:30 vsido groupadd[655]: group added to /etc/group: name=vsido, GID=1000
Feb 03 18:16:30 vsido groupadd[655]: group added to /etc/gshadow: name=vsido
Feb 03 18:16:30 vsido groupadd[655]: new group: name=vsido, GID=1000
Feb 03 18:18:01 vsido useradd[659]: new user: name=vsido, UID=1000, GID=1000, home=/home/vsido, shell=/bin/bash
Feb 03 18:21:01 vsido usermod[668]: change user 'vsido' password
Feb 03 18:21:02 vsido chfn[673]: changed user 'vsido' information
Feb 03 18:21:02 vsido usermod[679]: change user 'vsido' password
Feb 03 18:21:02 vsido gpasswd[685]: user vsido added by root to group audio
Feb 03 18:21:02 vsido gpasswd[690]: user vsido added by root to group cdrom
Feb 03 18:21:02 vsido gpasswd[695]: user vsido added by root to group dip
Feb 03 18:21:02 vsido gpasswd[700]: user vsido added by root to group floppy
Feb 03 18:21:02 vsido gpasswd[705]: user vsido added by root to group video
Feb 03 18:21:02 vsido gpasswd[710]: user vsido added by root to group plugdev
Feb 03 18:21:02 vsido gpasswd[715]: user vsido added by root to group netdev
Feb 03 18:22:32 vsido gpasswd[722]: user vsido added by root to group scanner
Feb 03 18:22:32 vsido gpasswd[727]: user vsido added by root to group bluetooth
Feb 03 18:24:03 vsido sudo[736]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/bin/sh -c echo 'SU_TO_ROOT_SU=sudo' >> /home/vsido/.su-to-rootrc
Feb 03 18:24:03 vsido sudo[736]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 18:24:03 vsido sudo[736]: pam_unix(sudo:session): session closed for user vsido
Feb 03 18:24:03 vsido sudo[738]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/usr/bin/gconftool-2 -s -t bool /apps/gksu/sudo-mode true
Feb 03 18:24:03 vsido sudo[738]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 18:24:03 vsido sudo[738]: pam_unix(sudo:session): session closed for user vsido
Feb 03 18:24:03 vsido sudo[740]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/usr/bin/gconftool-2 -s -t bool /apps/gksu/display-no-pass-info false
Feb 03 18:24:03 vsido sudo[740]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 18:24:03 vsido sudo[740]: pam_unix(sudo:session): session closed for user vsido
Feb 03 18:24:03 vsido sudo[742]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/bin/sh -c umask 0077 && mkdir -p /home/vsido/.kde/share/config && cat > /home/vsido/.kde/share/config/kdesurc
Feb 03 18:24:03 vsido sudo[742]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 18:24:03 vsido sudo[742]: pam_unix(sudo:session): session closed for user vsido
Feb 03 18:24:04 vsido live-config[369]: live-config: debconf hostname user-setup sudo locales tzdata
Feb 03 18:24:04 vsido live-config[369]: Current default time zone: 'Etc/UTC'
Feb 03 18:24:04 vsido live-config[369]: Local time is now:      Sat Feb  3 18:24:04 UTC 2018.
Feb 03 18:24:04 vsido live-config[369]: Universal Time is now:  Sat Feb  3 18:24:04 UTC 2018.
Feb 03 18:24:04 vsido live-config[369]:  lightdm lxdm keyboard-configuration policykit anacron util-linux login xserver-xorg xscreensaver linstallerdefaultConfiguration file /etc/linstaller/trios doesn't exist. Breaking here.
Feb 03 18:24:04 vsido live-config[369]:  linstallersession.
Feb 03 18:24:04 vsido systemd[1]: Started live-config contains the components that configure a live system during the boot process (late userspace)..
Feb 03 18:24:04 vsido systemd[1]: Reached target Basic System.
Feb 03 18:24:04 vsido systemd[1]: Starting Modem Manager...
Feb 03 18:24:04 vsido systemd[1]: Started Regular background program processing daemon.
Feb 03 18:24:04 vsido systemd[1]: Started Deferred execution scheduler.
Feb 03 18:24:04 vsido systemd[1]: Starting Login Service...
Feb 03 18:24:04 vsido systemd[1]: Starting Initialize hardware monitoring sensors...
Feb 03 18:24:04 vsido systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Feb 03 18:24:04 vsido systemd[1]: Starting Save/Restore Sound Card State...
Feb 03 18:24:04 vsido systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Feb 03 18:24:04 vsido systemd[1]: Started Run anacron jobs.
Feb 03 18:24:04 vsido systemd[1]: Starting Disk Manager...
Feb 03 18:24:04 vsido systemd[1]: Started D-Bus System Message Bus.
Feb 03 18:24:04 vsido cron[845]: (CRON) INFO (pidfile fd = 3)
Feb 03 18:24:05 vsido systemd-logind[847]: Watching system buttons on /dev/input/event1 (Power Button)
Feb 03 18:24:05 vsido systemd-logind[847]: Watching system buttons on /dev/input/event2 (Sleep Button)
Feb 03 18:24:05 vsido systemd-logind[847]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Feb 03 18:24:05 vsido systemd-logind[847]: New seat seat0.
Feb 03 18:24:05 vsido avahi-daemon[851]: Found user 'avahi' (UID 108) and group 'avahi' (GID 115).
Feb 03 18:24:05 vsido avahi-daemon[851]: Successfully dropped root privileges.
Feb 03 18:24:05 vsido avahi-daemon[851]: avahi-daemon 0.7 starting up.
Feb 03 18:24:05 vsido sensors[848]: No sensors found!
Feb 03 18:24:05 vsido sensors[848]: Make sure you loaded all the kernel drivers you need.
Feb 03 18:24:05 vsido sensors[848]: Try sensors-detect to find out which these are.
Feb 03 18:24:05 vsido ModemManager[844]: <info>  ModemManager (version 1.6.8) starting in system bus...
Feb 03 18:24:05 vsido cron[845]: (CRON) INFO (Running @reboot jobs)
Feb 03 18:24:05 vsido udisksd[853]: udisks daemon version 2.7.5 starting
Feb 03 18:24:05 vsido systemd[1]: Started Login Service.
Feb 03 18:24:05 vsido avahi-daemon[851]: Successfully called chroot().
Feb 03 18:24:05 vsido systemd[1]: Starting LSB: Starts and stops Wicd...
Feb 03 18:24:05 vsido avahi-daemon[851]: Successfully dropped remaining capabilities.
Feb 03 18:24:05 vsido avahi-daemon[851]: Loading service file /services/udisks.service.
Feb 03 18:24:05 vsido avahi-daemon[851]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::a00:27ff:fee7:3dae.
Feb 03 18:24:05 vsido avahi-daemon[851]: New relevant interface eth0.IPv6 for mDNS.
Feb 03 18:24:05 vsido avahi-daemon[851]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.1.107.
Feb 03 18:24:05 vsido avahi-daemon[851]: New relevant interface eth0.IPv4 for mDNS.
Feb 03 18:24:05 vsido avahi-daemon[851]: Network interface enumeration completed.
Feb 03 18:24:05 vsido avahi-daemon[851]: Registering new address record for fe80::a00:27ff:fee7:3dae on eth0.*.
Feb 03 18:24:05 vsido avahi-daemon[851]: Registering new address record for 10.0.1.107 on eth0.IPv4.
Feb 03 18:24:05 vsido systemd[1]: Starting WPA supplicant...
Feb 03 18:24:05 vsido rtkit-daemon[849]: Successfully called chroot.
Feb 03 18:24:05 vsido rtkit-daemon[849]: Successfully dropped privileges.
Feb 03 18:24:05 vsido rtkit-daemon[849]: Successfully limited resources.
Feb 03 18:24:05 vsido rtkit-daemon[849]: Running.
Feb 03 18:24:05 vsido dbus-daemon[854]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.4' (uid=0 pid=844 comm="/usr/sbin/ModemManager ")
Feb 03 18:24:05 vsido systemd[1]: Started irqbalance daemon.
Feb 03 18:24:05 vsido rtkit-daemon[849]: Watchdog thread running.
Feb 03 18:24:05 vsido rtkit-daemon[849]: Canary thread running.
Feb 03 18:24:05 vsido systemd[1]: Starting System Logging Service...
Feb 03 18:24:05 vsido systemd[1]: Starting LSB: NFS support files common to client and server...
Feb 03 18:24:05 vsido systemd[1]: Started ACPI event daemon.
Feb 03 18:24:05 vsido systemd[1]: Starting LSB: Set up packet writing devices...
Feb 03 18:24:05 vsido systemd[1]: Started Save/Restore Sound Card State.
Feb 03 18:24:05 vsido systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Feb 03 18:24:05 vsido systemd[1]: Started RealtimeKit Scheduling Policy Service.
Feb 03 18:24:05 vsido sensors[872]: No sensors found!
Feb 03 18:24:05 vsido sensors[872]: Make sure you loaded all the kernel drivers you need.
Feb 03 18:24:05 vsido sensors[872]: Try sensors-detect to find out which these are.
Feb 03 18:24:06 vsido systemd[1]: Started Initialize hardware monitoring sensors.
Feb 03 18:24:06 vsido udftools[871]: Not starting udftools packet writing, no devices listed in /etc/default/udftools.
Feb 03 18:24:06 vsido systemd[1]: Started LSB: Set up packet writing devices.
Feb 03 18:24:06 vsido acpid[870]: starting up with netlink and the input layer
Feb 03 18:24:06 vsido systemd[1]: Started LSB: NFS support files common to client and server.
Feb 03 18:24:06 vsido systemd[1]: Starting Authorization Manager...
Feb 03 18:24:06 vsido systemd[1]: Started Run anacron jobs.
Feb 03 18:24:06 vsido wpa_supplicant[863]: Successfully initialized wpa_supplicant
Feb 03 18:24:06 vsido systemd[1]: Started WPA supplicant.
Feb 03 18:24:06 vsido systemd[1]: Reached target Network.
Feb 03 18:24:06 vsido systemd[1]: Reached target Network is Online.
Feb 03 18:24:06 vsido systemd[1]: Starting LSB: disk temperature monitoring daemon...
Feb 03 18:24:06 vsido systemd[1]: Starting LSB: RPC portmapper replacement...
Feb 03 18:24:06 vsido systemd[1]: Starting LSB: exim Mail Transport Agent...
Feb 03 18:24:06 vsido systemd[1]: Starting Permit User Sessions...
Feb 03 18:24:06 vsido avahi-daemon[851]: Server startup complete. Host name is vsido.local. Local service cookie is 1006096771.
Feb 03 18:24:06 vsido systemd[1]: Starting Network Time Service...
Feb 03 18:24:06 vsido systemd[1]: Starting /etc/rc.local Compatibility...
Feb 03 18:24:06 vsido systemd[1]: Started LSB: RPC portmapper replacement.
Feb 03 18:24:06 vsido systemd[1]: Started /etc/rc.local Compatibility.
Feb 03 18:24:06 vsido systemd[1]: Started Permit User Sessions.
Feb 03 18:24:06 vsido systemd[1]: Starting LXDE Display Manager...
Feb 03 18:24:06 vsido systemd[1]: Started Getty on tty1.
Feb 03 18:24:06 vsido systemd[1]: Reached target Login Prompts.
Feb 03 18:24:06 vsido rsyslogd[868]: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.32.0]
Feb 03 18:24:06 vsido systemd[1]: Started System Logging Service.
Feb 03 18:24:06 vsido rsyslogd[868]:  [origin software="rsyslogd" swVersion="8.32.0" x-pid="868" x-info="http://www.rsyslog.com"] start
Feb 03 18:24:06 vsido systemd[1]: Started LXDE Display Manager.
Feb 03 18:24:06 vsido acpid[870]: 27 rules loaded
Feb 03 18:24:06 vsido acpid[870]: waiting for events: event logging is off
Feb 03 18:24:07 vsido hddtemp[889]: Starting disk temperature monitoring daemon: hddtemp: no disks with monitoring capability were found..
Feb 03 18:24:07 vsido systemd[1]: Started LSB: disk temperature monitoring daemon.
Feb 03 18:24:07 vsido avahi-daemon[851]: Service "vsido" (/services/udisks.service) successfully established.
Feb 03 18:24:07 vsido ntpd[916]: ntpd 4.2.8p10@1.3728-o (1): Starting
Feb 03 18:24:07 vsido polkitd[886]: started daemon version 0.105 using authority implementation `local' version `0.105'
Feb 03 18:24:07 vsido dbus-daemon[854]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Feb 03 18:24:07 vsido systemd[1]: Started Authorization Manager.
Feb 03 18:24:07 vsido ntpd[916]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 111:120
Feb 03 18:24:07 vsido ntpd[970]: proto: precision = 1.396 usec (-19)
Feb 03 18:24:07 vsido systemd[1]: Started Network Time Service.
Feb 03 18:24:07 vsido ntpd[970]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Feb 03 18:24:07 vsido ntpd[970]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2018-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Feb 03 18:24:07 vsido ntpd[970]: Listen and drop on 0 v6wildcard [::]:123
Feb 03 18:24:07 vsido ntpd[970]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Feb 03 18:24:07 vsido ntpd[970]: Listen normally on 2 lo 127.0.0.1:123
Feb 03 18:24:07 vsido ntpd[970]: Listen normally on 3 eth0 10.0.1.107:123
Feb 03 18:24:07 vsido ntpd[970]: Listen normally on 4 lo [::1]:123
Feb 03 18:24:07 vsido ntpd[970]: Listen normally on 5 eth0 [fe80::a00:27ff:fee7:3dae%2]:123
Feb 03 18:24:07 vsido ntpd[970]: Listening on routing socket on fd #22 for interface updates
Feb 03 18:24:07 vsido systemd[1]: Started Disk Manager.
Feb 03 18:24:07 vsido udisksd[853]: Acquired the name org.freedesktop.UDisks2 on the system message bus
Feb 03 18:24:08 vsido systemd[1]: Started Modem Manager.
Feb 03 18:24:08 vsido ntpd[970]: Soliciting pool server 162.210.111.4
Feb 03 18:24:09 vsido ntpd[970]: Soliciting pool server 74.120.81.219
Feb 03 18:24:09 vsido ntpd[970]: Soliciting pool server 129.250.35.251
Feb 03 18:24:09 vsido exim4[891]: Starting MTA: exim4.
Feb 03 18:24:09 vsido systemd[1]: Started LSB: exim Mail Transport Agent.
Feb 03 18:24:10 vsido ntpd[970]: Soliciting pool server 198.58.110.84
Feb 03 18:24:10 vsido ntpd[970]: Soliciting pool server 108.61.56.35
Feb 03 18:24:10 vsido ntpd[970]: Soliciting pool server 107.161.30.25
Feb 03 18:24:10 vsido ModemManager[844]: <info>  Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:03.0': not supported by any plugin
Feb 03 18:24:11 vsido ntpd[970]: Soliciting pool server 173.230.144.109
Feb 03 18:24:11 vsido ntpd[970]: Soliciting pool server 208.88.126.235
Feb 03 18:24:11 vsido ntpd[970]: Soliciting pool server 45.127.113.2
Feb 03 18:24:11 vsido ntpd[970]: Soliciting pool server 96.126.105.86
Feb 03 18:24:11 vsido wicd[861]: Starting Network connection manager: wicd.
Feb 03 18:24:11 vsido systemd[1]: Started LSB: Starts and stops Wicd.
Feb 03 18:24:11 vsido systemd[1]: Reached target Multi-User System.
Feb 03 18:24:11 vsido systemd[1]: Reached target Graphical Interface.
Feb 03 18:24:12 vsido systemd[1]: Starting Update UTMP about System Runlevel Changes...
Feb 03 18:24:12 vsido systemd[1]: Started Update UTMP about System Runlevel Changes.
Feb 03 18:24:12 vsido systemd[1]: Startup finished in 6.651s (kernel) + 12min 20.520s (userspace) = 12min 27.171s

This is the same section of the New Build ISO's to compare .. This one takes 17.5 seconds to run

Code: [Select]
Feb 03 19:41:21 vsido groupadd[532]: group added to /etc/group: name=vsido, GID=1000
Feb 03 19:41:21 vsido groupadd[532]: group added to /etc/gshadow: name=vsido
Feb 03 19:41:21 vsido groupadd[532]: new group: name=vsido, GID=1000
Feb 03 19:41:21 vsido useradd[536]: new user: name=vsido, UID=1000, GID=1000, home=/home/vsido, shell=/bin/bash
Feb 03 19:41:21 vsido usermod[544]: change user 'vsido' password
Feb 03 19:41:22 vsido chfn[549]: changed user 'vsido' information
Feb 03 19:41:22 vsido usermod[555]: change user 'vsido' password
Feb 03 19:41:22 vsido gpasswd[561]: user vsido added by root to group audio
Feb 03 19:41:22 vsido gpasswd[566]: user vsido added by root to group cdrom
Feb 03 19:41:22 vsido gpasswd[571]: user vsido added by root to group dip
Feb 03 19:41:22 vsido gpasswd[576]: user vsido added by root to group floppy
Feb 03 19:41:22 vsido gpasswd[581]: user vsido added by root to group video
Feb 03 19:41:22 vsido gpasswd[586]: user vsido added by root to group plugdev
Feb 03 19:41:22 vsido gpasswd[591]: user vsido added by root to group netdev
Feb 03 19:41:22 vsido gpasswd[598]: user vsido added by root to group bluetooth
Feb 03 19:41:23 vsido sudo[607]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/bin/sh -c echo 'SU_TO_ROOT_SU=sudo' >> /home/vsido/.su-to-rootrc
Feb 03 19:41:23 vsido sudo[607]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 19:41:23 vsido sudo[607]: pam_unix(sudo:session): session closed for user vsido
Feb 03 19:41:23 vsido sudo[609]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/usr/bin/gconftool-2 -s -t bool /apps/gksu/sudo-mode true
Feb 03 19:41:23 vsido sudo[609]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 19:41:23 vsido sudo[609]: pam_unix(sudo:session): session closed for user vsido
Feb 03 19:41:23 vsido sudo[611]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/usr/bin/gconftool-2 -s -t bool /apps/gksu/display-no-pass-info false
Feb 03 19:41:23 vsido sudo[611]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 19:41:23 vsido sudo[611]: pam_unix(sudo:session): session closed for user vsido
Feb 03 19:41:23 vsido sudo[613]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/bin/sh -c umask 0077 && mkdir -p /home/vsido/.kde/share/config && cat > /home/vsido/.kde/share/config/kdesurc
Feb 03 19:41:23 vsido sudo[613]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 19:41:23 vsido sudo[613]: pam_unix(sudo:session): session closed for user vsido
Feb 03 19:41:24 vsido live-config[331]: live-config: debconf hostname user-setup sudo locales tzdata
Feb 03 19:41:24 vsido live-config[331]: Current default time zone: 'Etc/UTC'
Feb 03 19:41:24 vsido live-config[331]: Local time is now:      Sat Feb  3 19:41:24 UTC 2018.
Feb 03 19:41:24 vsido live-config[331]: Universal Time is now:  Sat Feb  3 19:41:24 UTC 2018.
Feb 03 19:41:24 vsido live-config[331]:  lxdm keyboard-configuration policykit util-linux login xserver-xorg linstallerdefaultConfiguration file /etc/linstaller/trios doesn't exist. Breaking here.
Feb 03 19:41:24 vsido live-config[331]:  linstallersession.
Feb 03 19:41:24 vsido systemd[1]: Started live-config contains the components that configure a live system during the boot process (late userspace)..
Feb 03 19:41:24 vsido systemd[1]: Reached target Basic System.
Feb 03 19:41:24 vsido systemd[1]: Starting Modem Manager...
Feb 03 19:41:24 vsido systemd[1]: Starting Initialize hardware monitoring sensors...
Feb 03 19:41:24 vsido systemd[1]: Started Regular background program processing daemon.
Feb 03 19:41:24 vsido systemd[1]: Starting Restore /etc/resolv.conf if the system crashed before the ppp link was shut down...
Feb 03 19:41:24 vsido systemd[1]: Starting System Logging Service...
Feb 03 19:41:24 vsido systemd[1]: Started D-Bus System Message Bus.
Feb 03 19:41:24 vsido cron[709]: (CRON) INFO (pidfile fd = 3)
Feb 03 19:41:25 vsido sensors[708]: No sensors found!
Feb 03 19:41:25 vsido sensors[708]: Make sure you loaded all the kernel drivers you need.
Feb 03 19:41:25 vsido sensors[708]: Try sensors-detect to find out which these are.
Feb 03 19:41:25 vsido cron[709]: (CRON) INFO (Running @reboot jobs)
Feb 03 19:41:25 vsido systemd[1]: Starting LSB: Starts and stops Wicd...
Feb 03 19:41:25 vsido systemd[1]: Starting Login Service...
Feb 03 19:41:25 vsido systemd[1]: Starting WPA supplicant...
Feb 03 19:41:25 vsido systemd[1]: Started Restore /etc/resolv.conf if the system crashed before the ppp link was shut down.
Feb 03 19:41:25 vsido sensors[717]: No sensors found!
Feb 03 19:41:25 vsido sensors[717]: Make sure you loaded all the kernel drivers you need.
Feb 03 19:41:25 vsido sensors[717]: Try sensors-detect to find out which these are.
Feb 03 19:41:25 vsido systemd[1]: Started LSB: Starts and stops Wicd.
Feb 03 19:41:25 vsido systemd[1]: Started Initialize hardware monitoring sensors.
Feb 03 19:41:25 vsido ModemManager[707]: <info>  ModemManager (version 1.6.8) starting in system bus...
Feb 03 19:41:25 vsido dbus-daemon[712]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.1' (uid=0 pid=707 comm="/usr/sbin/ModemManager ")
Feb 03 19:41:25 vsido systemd-logind[715]: Watching system buttons on /dev/input/event1 (Power Button)
Feb 03 19:41:25 vsido systemd-logind[715]: Watching system buttons on /dev/input/event2 (Sleep Button)
Feb 03 19:41:25 vsido systemd[1]: Starting Authorization Manager...
Feb 03 19:41:25 vsido rsyslogd[711]: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.32.0]
Feb 03 19:41:25 vsido systemd[1]: Started System Logging Service.
Feb 03 19:41:25 vsido rsyslogd[711]:  [origin software="rsyslogd" swVersion="8.32.0" x-pid="711" x-info="http://www.rsyslog.com"] start
Feb 03 19:41:25 vsido systemd-logind[715]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Feb 03 19:41:25 vsido systemd-logind[715]: New seat seat0.
Feb 03 19:41:25 vsido systemd[1]: Started Login Service.
Feb 03 19:41:25 vsido polkitd[722]: started daemon version 0.105 using authority implementation `local' version `0.105'
Feb 03 19:41:25 vsido systemd[1]: Started WPA supplicant.
Feb 03 19:41:25 vsido systemd[1]: Reached target Network.
Feb 03 19:41:25 vsido systemd[1]: Reached target Network is Online.
Feb 03 19:41:25 vsido systemd[1]: Starting LSB: disk temperature monitoring daemon...
Feb 03 19:41:25 vsido systemd[1]: Starting Permit User Sessions...
Feb 03 19:41:25 vsido dbus-daemon[712]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Feb 03 19:41:25 vsido systemd[1]: Started Authorization Manager.
Feb 03 19:41:25 vsido wpa_supplicant[716]: Successfully initialized wpa_supplicant
Feb 03 19:41:25 vsido systemd[1]: Started Permit User Sessions.
Feb 03 19:41:25 vsido systemd[1]: Started Getty on tty1.
Feb 03 19:41:25 vsido systemd[1]: Reached target Login Prompts.
Feb 03 19:41:25 vsido systemd[1]: Starting LXDE Display Manager...
Feb 03 19:41:25 vsido systemd[1]: Started LXDE Display Manager.
Feb 03 19:41:25 vsido systemd[1]: Started LSB: disk temperature monitoring daemon.
Feb 03 19:41:25 vsido systemd[1]: Started Modem Manager.
Feb 03 19:41:25 vsido systemd[1]: Reached target Multi-User System.
Feb 03 19:41:25 vsido systemd[1]: Reached target Graphical Interface.
Feb 03 19:41:25 vsido systemd[1]: Starting Update UTMP about System Runlevel Changes...
Feb 03 19:41:25 vsido systemd[1]: Started Update UTMP about System Runlevel Changes.
Feb 03 19:41:25 vsido systemd[1]: Startup finished in 5.993s (kernel) + 11.599s (userspace) = 17.592s.

VSIDO      VSIDO Twitter     VSIDO FB     VSIDO Google+   

I dev VSIDO

PackRat

  • Posts: 2141
    • View Profile
    • PackRat configs on github
These are time lags, no -

Code: [Select]
Feb 03 18:12:10 vsido ntpdate[626]: step time server 137.190.2.4 offset 1.628547 sec
Feb 03 18:16:30 vsido groupadd[655]: group added to /etc/group: name=vsido, GID=1000

Feb 03 18:18:01 vsido useradd[659]: new user: name=vsido, UID=1000, GID=1000, home=/home/vsido, shell=/bin/bash
Feb 03 18:21:01 vsido usermod[668]: change user 'vsido' password

Feb 03 18:21:02 vsido gpasswd[715]: user vsido added by root to group netdev
Feb 03 18:22:32 vsido gpasswd[722]: user vsido added by root to group scanner
Feb 03 18:22:32 vsido gpasswd[727]: user vsido added by root to group bluetooth
Feb 03 18:24:03 vsido sudo[736]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/bin/sh -c echo 'SU_TO_ROOT_SU=sudo' >> /home/vsido/.su-to-rootrc

I assume it hangs at the first entry before progressing to the next, so ~4.5 minutes to connect to a time server and on down the line, minute here and there adds up to your 12min 27sec (that's about 9 min that I posted).

I didn't even spot ntp is the other boot.

Can you even configure the time server to query for a live-session?
I am tired of talk that comes to nothing.
-- Chief Joseph

...the sun, the darkness, the winds are all listening to what we have to say.
-- Geronimo

VastOne

  •      v-ger
  • Posts: 4131
    • View Profile
    • VSIDO Community
That ntpdate in that first line is misleading. If you look at the end of it you will see a finish time of 1.6 seconds it completed in.  That's when the useradd  stuff begins It appears that the . I have completely removed ntp and ntpdate and rebuilt the iso's, and then the time was 14 minutes instead of the 12.27.  I'll post the logs from that one so that we can compare these as well. Right now I'm removing services, to see if they have any effect on this

It appears that the first action of group added to /etc/group: name=vsido, GID=1000 took 4 minutes
VSIDO      VSIDO Twitter     VSIDO FB     VSIDO Google+   

I dev VSIDO

VastOne

  •      v-ger
  • Posts: 4131
    • View Profile
    • VSIDO Community
Here is the one with ntp and ntpdate removed

Code: [Select]
Feb 03 22:56:05 vsido groupadd[650]: group added to /etc/group: name=vsido, GID=1000
Feb 03 22:56:05 vsido groupadd[650]: group added to /etc/gshadow: name=vsido
Feb 03 22:56:05 vsido groupadd[650]: new group: name=vsido, GID=1000
Feb 03 22:57:36 vsido useradd[654]: new user: name=vsido, UID=1000, GID=1000, home=/home/vsido, shell=/bin/bash
Feb 03 23:00:36 vsido usermod[663]: change user 'vsido' password
Feb 03 23:00:37 vsido chfn[668]: changed user 'vsido' information
Feb 03 23:00:37 vsido usermod[674]: change user 'vsido' password
Feb 03 23:00:37 vsido gpasswd[680]: user vsido added by root to group audio
Feb 03 23:00:37 vsido gpasswd[685]: user vsido added by root to group cdrom
Feb 03 23:00:37 vsido gpasswd[690]: user vsido added by root to group dip
Feb 03 23:00:37 vsido gpasswd[695]: user vsido added by root to group floppy
Feb 03 23:00:37 vsido gpasswd[700]: user vsido added by root to group video
Feb 03 23:00:37 vsido gpasswd[705]: user vsido added by root to group plugdev
Feb 03 23:00:37 vsido gpasswd[710]: user vsido added by root to group netdev
Feb 03 23:03:37 vsido gpasswd[718]: user vsido added by root to group bluetooth
Feb 03 23:05:08 vsido sudo[727]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/bin/sh -c echo 'SU_TO_ROOT_SU=sudo' >> /home/vsido/.su-to-rootrc
Feb 03 23:05:08 vsido sudo[727]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 23:05:08 vsido sudo[727]: pam_unix(sudo:session): session closed for user vsido
Feb 03 23:05:08 vsido sudo[729]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/usr/bin/gconftool-2 -s -t bool /apps/gksu/sudo-mode true
Feb 03 23:05:08 vsido sudo[729]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 23:05:08 vsido sudo[729]: pam_unix(sudo:session): session closed for user vsido
Feb 03 23:05:08 vsido sudo[731]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/usr/bin/gconftool-2 -s -t bool /apps/gksu/display-no-pass-info false
Feb 03 23:05:08 vsido sudo[731]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 23:05:08 vsido sudo[731]: pam_unix(sudo:session): session closed for user vsido
Feb 03 23:05:08 vsido sudo[733]:     root : TTY=unknown ; PWD=/ ; USER=vsido ; COMMAND=/bin/sh -c umask 0077 && mkdir -p /home/vsido/.kde/share/config && cat > /home/vsido/.kde/share/config/kdesurc
Feb 03 23:05:08 vsido sudo[733]: pam_unix(sudo:session): session opened for user vsido by (uid=0)
Feb 03 23:05:08 vsido sudo[733]: pam_unix(sudo:session): session closed for user vsido
Feb 03 23:05:09 vsido live-config[372]: live-config: debconf hostname user-setup sudo locales tzdata
Feb 03 23:05:09 vsido live-config[372]: Current default time zone: 'Etc/UTC'
Feb 03 23:05:09 vsido live-config[372]: Local time is now:      Sat Feb  3 23:05:09 UTC 2018.
Feb 03 23:05:09 vsido live-config[372]: Universal Time is now:  Sat Feb  3 23:05:09 UTC 2018.
Feb 03 23:05:09 vsido live-config[372]:  lightdm lxdm keyboard-configuration policykit anacron util-linux login xserver-xorg xscreensaver linstallerdefaultConfiguration file /etc/linstaller/trios doesn't exist. Breaking here.
Feb 03 23:05:09 vsido live-config[372]:  linstallersession.
Feb 03 23:05:09 vsido systemd[1]: Started live-config contains the components that configure a live system during the boot process (late userspace)..
Feb 03 23:05:09 vsido systemd[1]: Reached target Basic System.
Feb 03 23:05:09 vsido systemd[1]: Starting LSB: NFS support files common to client and server...
Feb 03 23:05:09 vsido systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Feb 03 23:05:09 vsido systemd[1]: Starting LSB: Set up packet writing devices...
Feb 03 23:05:09 vsido systemd[1]: Started D-Bus System Message Bus.
Feb 03 23:05:09 vsido systemd[1]: Started RealtimeKit Scheduling Policy Service.
Feb 03 23:05:09 vsido rtkit-daemon[836]: Successfully called chroot.
Feb 03 23:05:09 vsido systemd[1]: Starting Save/Restore Sound Card State...
Feb 03 23:05:09 vsido rtkit-daemon[836]: Successfully dropped privileges.
Feb 03 23:05:09 vsido systemd[1]: Started Deferred execution scheduler.
Feb 03 23:05:09 vsido rtkit-daemon[836]: Successfully limited resources.
Feb 03 23:05:09 vsido systemd[1]: Starting LSB: Starts and stops Wicd...
Feb 03 23:05:09 vsido rtkit-daemon[836]: Running.
Feb 03 23:05:09 vsido systemd[1]: Starting Modem Manager...
Feb 03 23:05:09 vsido rtkit-daemon[836]: Watchdog thread running.
Feb 03 23:05:10 vsido rtkit-daemon[836]: Canary thread running.
Feb 03 23:05:10 vsido systemd[1]: Started ACPI event daemon.
Feb 03 23:05:10 vsido systemd[1]: Started irqbalance daemon.
Feb 03 23:05:10 vsido systemd[1]: Starting Disk Manager...
Feb 03 23:05:10 vsido systemd[1]: Starting Initialize hardware monitoring sensors...
Feb 03 23:05:10 vsido systemd[1]: Started Regular background program processing daemon.
Feb 03 23:05:10 vsido systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Feb 03 23:05:10 vsido systemd[1]: Starting System Logging Service...
Feb 03 23:05:10 vsido systemd[1]: Starting WPA supplicant...
Feb 03 23:05:10 vsido systemd[1]: Started Run anacron jobs.
Feb 03 23:05:10 vsido systemd[1]: Starting Login Service...
Feb 03 23:05:10 vsido systemd[1]: Started Save/Restore Sound Card State.
Feb 03 23:05:10 vsido cron[857]: (CRON) INFO (pidfile fd = 3)
Feb 03 23:05:10 vsido cron[857]: (CRON) INFO (Running @reboot jobs)
Feb 03 23:05:10 vsido systemd[1]: Started Run anacron jobs.
Feb 03 23:05:10 vsido acpid[851]: starting up with netlink and the input layer
Feb 03 23:05:10 vsido systemd[1]: Started LSB: NFS support files common to client and server.
Feb 03 23:05:10 vsido systemd-logind[863]: Watching system buttons on /dev/input/event1 (Power Button)
Feb 03 23:05:10 vsido systemd-logind[863]: Watching system buttons on /dev/input/event2 (Sleep Button)
Feb 03 23:05:10 vsido udftools[837]: Not starting udftools packet writing, no devices listed in /etc/default/udftools.
Feb 03 23:05:10 vsido systemd[1]: Started LSB: Set up packet writing devices.
Feb 03 23:05:10 vsido systemd-logind[863]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Feb 03 23:05:10 vsido systemd-logind[863]: New seat seat0.
Feb 03 23:05:10 vsido systemd[1]: Started Login Service.
Feb 03 23:05:10 vsido ModemManager[850]: <info>  ModemManager (version 1.6.8) starting in system bus...
Feb 03 23:05:10 vsido dbus-daemon[839]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.3' (uid=0 pid=850 comm="/usr/sbin/ModemManager ")
Feb 03 23:05:10 vsido avahi-daemon[858]: Found user 'avahi' (UID 108) and group 'avahi' (GID 115).
Feb 03 23:05:10 vsido avahi-daemon[858]: Successfully dropped root privileges.
Feb 03 23:05:10 vsido avahi-daemon[858]: avahi-daemon 0.7 starting up.
Feb 03 23:05:10 vsido sensors[856]: No sensors found!
Feb 03 23:05:10 vsido sensors[856]: Make sure you loaded all the kernel drivers you need.
Feb 03 23:05:10 vsido sensors[856]: Try sensors-detect to find out which these are.
Feb 03 23:05:11 vsido avahi-daemon[858]: Successfully called chroot().
Feb 03 23:05:11 vsido avahi-daemon[858]: Successfully dropped remaining capabilities.
Feb 03 23:05:11 vsido avahi-daemon[858]: Loading service file /services/udisks.service.
Feb 03 23:05:11 vsido systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Feb 03 23:05:11 vsido systemd[1]: Starting Authorization Manager...
Feb 03 23:05:11 vsido sensors[875]: No sensors found!
Feb 03 23:05:11 vsido sensors[875]: Make sure you loaded all the kernel drivers you need.
Feb 03 23:05:11 vsido sensors[875]: Try sensors-detect to find out which these are.
Feb 03 23:05:11 vsido systemd[1]: Started Initialize hardware monitoring sensors.
Feb 03 23:05:11 vsido udisksd[855]: udisks daemon version 2.7.5 starting
Feb 03 23:05:11 vsido systemd[1]: Started WPA supplicant.
Feb 03 23:05:11 vsido systemd[1]: Reached target Network.
Feb 03 23:05:11 vsido systemd[1]: Starting Permit User Sessions...
Feb 03 23:05:11 vsido systemd[1]: Reached target Network is Online.
Feb 03 23:05:11 vsido systemd[1]: Starting LSB: RPC portmapper replacement...
Feb 03 23:05:11 vsido systemd[1]: Starting /etc/rc.local Compatibility...
Feb 03 23:05:11 vsido wpa_supplicant[861]: Successfully initialized wpa_supplicant
Feb 03 23:05:11 vsido systemd[1]: Starting LSB: disk temperature monitoring daemon...
Feb 03 23:05:11 vsido systemd[1]: Starting LSB: exim Mail Transport Agent...
Feb 03 23:05:11 vsido systemd[1]: Started LSB: RPC portmapper replacement.
Feb 03 23:05:11 vsido avahi-daemon[858]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::a00:27ff:fee7:3dae.
Feb 03 23:05:11 vsido avahi-daemon[858]: New relevant interface eth0.IPv6 for mDNS.
Feb 03 23:05:11 vsido avahi-daemon[858]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.1.107.
Feb 03 23:05:11 vsido avahi-daemon[858]: New relevant interface eth0.IPv4 for mDNS.
Feb 03 23:05:11 vsido avahi-daemon[858]: Network interface enumeration completed.
Feb 03 23:05:11 vsido avahi-daemon[858]: Registering new address record for fe80::a00:27ff:fee7:3dae on eth0.*.
Feb 03 23:05:11 vsido avahi-daemon[858]: Registering new address record for 10.0.1.107 on eth0.IPv4.
Feb 03 23:05:11 vsido systemd[1]: Started /etc/rc.local Compatibility.
Feb 03 23:05:11 vsido acpid[851]: 27 rules loaded
Feb 03 23:05:11 vsido acpid[851]: waiting for events: event logging is off
Feb 03 23:05:11 vsido systemd[1]: Started Permit User Sessions.
Feb 03 23:05:11 vsido systemd[1]: Started Getty on tty1.
Feb 03 23:05:11 vsido systemd[1]: Reached target Login Prompts.
Feb 03 23:05:11 vsido systemd[1]: Starting LXDE Display Manager...
Feb 03 23:05:11 vsido rsyslogd[859]: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.32.0]
Feb 03 23:05:11 vsido systemd[1]: Started System Logging Service.
Feb 03 23:05:11 vsido rsyslogd[859]:  [origin software="rsyslogd" swVersion="8.32.0" x-pid="859" x-info="http://www.rsyslog.com"] start
Feb 03 23:05:11 vsido systemd[1]: Started LXDE Display Manager.
Feb 03 23:05:11 vsido polkitd[876]: started daemon version 0.105 using authority implementation `local' version `0.105'
Feb 03 23:05:11 vsido dbus-daemon[839]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Feb 03 23:05:11 vsido systemd[1]: Started Authorization Manager.
Feb 03 23:05:12 vsido hddtemp[886]: Starting disk temperature monitoring daemon: hddtemp: no disks with monitoring capability were found..
Feb 03 23:05:12 vsido systemd[1]: Started LSB: disk temperature monitoring daemon.
Feb 03 23:05:12 vsido avahi-daemon[858]: Server startup complete. Host name is vsido.local. Local service cookie is 3317621500.
Feb 03 23:05:13 vsido avahi-daemon[858]: Service "vsido" (/services/udisks.service) successfully established.
Feb 03 23:05:13 vsido systemd[1]: Started Modem Manager.
Feb 03 23:05:13 vsido systemd[1]: Started Disk Manager.
Feb 03 23:05:13 vsido udisksd[855]: Acquired the name org.freedesktop.UDisks2 on the system message bus
Feb 03 23:05:14 vsido exim4[887]: Starting MTA: exim4.
Feb 03 23:05:14 vsido systemd[1]: Started LSB: exim Mail Transport Agent.
Feb 03 23:05:16 vsido ModemManager[850]: <info>  Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:03.0': not supported by any plugin
Feb 03 23:05:17 vsido wicd[849]: Starting Network connection manager: wicd.
Feb 03 23:05:17 vsido systemd[1]: Started LSB: Starts and stops Wicd.
Feb 03 23:05:17 vsido systemd[1]: Reached target Multi-User System.
Feb 03 23:05:17 vsido systemd[1]: Reached target Graphical Interface.
Feb 03 23:05:17 vsido systemd[1]: Starting Update UTMP about System Runlevel Changes...
Feb 03 23:05:17 vsido systemd[1]: Started Update UTMP about System Runlevel Changes.
Feb 03 23:05:17 vsido systemd[1]: Startup finished in 6.637s (kernel) + 13min 50.884s (userspace) = 13min 57.521s.
VSIDO      VSIDO Twitter     VSIDO FB     VSIDO Google+   

I dev VSIDO