Slow startup?

Hi.

I have updated my 12.3 some days ago by.

sudo zypper up

Since that I have encountered slow start up.

IE, that it takes 4.1 minutes untill the login window appears.

Where can I see what is going on ?. Any log shows this ?

On 2013-07-11 11:46, erikja wrote:

> IE, that it takes 4.1 minutes untill the login window appears.

Network timeout? nfs mount?

>
> Where can I see what is going on ?. Any log shows this ?

syslog, ie, /var/log/messages


Cheers / Saludos,

Carlos E. R.
(from 12.3 x86_64 “Dartmouth” at Telcontar)

On 11/07/13 12:53, Carlos E. R. wrote:
> On 2013-07-11 11:46, erikja wrote:
>
>> IE, that it takes 4.1 minutes untill the login window appears.
>
> Network timeout? nfs mount?
>
>>
>> Where can I see what is going on ?. Any log shows this ?
>
> syslog, ie, /var/log/messages
>

How will Network timeout and nfs mount look like in the messages file ?

It’s not able to do a.


tail -f /var/log/messages

As the system is not booted at the time where it appears, that it boots
slowly.


Erik Jakobsen
Opensuse 12.3 64bit
Linux linux-z96b.site 3.7.10-1.11-desktop
KDE Version 4.10.2 “release 1”

/var/log/boot.msg or the command dmesg should help.

See exactly what is slow by running systemd-bootchart.

Don’t know why, but it was part of my default openSUSE 12.3 when it was first installed, then has disappeared completely (and not in my DVD). Maybe it was installed in my openSUSE 12.2 (systemd v.60) and removed since then.

In any case, you can get it from non-SUSE sources, eg
RPM resource bootchart

When you run it, it will log and display how long each boot component takes to load and IIRC might even be plottable in HTML (but likely unnecessary).

HTH,
TSU

On 11/07/13 16:56, AdaLovelace wrote:
>
> -/var/log/boot.msg- or the command dmesg should help.
>
>

Thank you, here are the content of boot.log


> Trying manual resume from /dev/disk/by-id/ata-WDC_WD1600AVVS-00L2B0_WD-WCAV17472660-part1
> Invoking userspace resume from /dev/disk/by-id/ata-WDC_WD1600AVVS-00L2B0_WD-WCAV17472660-part1
> resume: libgcrypt version: 1.5.0
> Trying manual resume from /dev/disk/by-id/ata-WDC_WD1600AVVS-00L2B0_WD-WCAV17472660-part1
> Invoking in-kernel resume from /dev/disk/by-id/ata-WDC_WD1600AVVS-00L2B0_WD-WCAV17472660-part1
> Waiting for device /dev/root to appear:  ok
> fsck from util-linux 2.21.2
> [/sbin/fsck.ext4 (1) -- /] fsck.ext4 -a /dev/sda2
> /dev/sda2: clean, 352611/1313280 files, 2969473/5242880 blocks
> fsck succeeded. Mounting root device read-write.
> Mounting root /dev/root
> mount -o rw,acl,user_xattr -t ext4 /dev/root /root
>
> Welcome to e[0;32mopenSUSE 12.3 (Dartmouth) (x86_64)e[0m!
>
>          Starting Collect Read-Ahead Data...
>          Starting Replay Read-Ahead Data...
> [e[1;32m  OK  e[0m] Listening on Syslog Socket.
> e[1;32m  OK  e[0m] Listening on Delayed Shutdown Socket.
> e[1;32m  OK  e[0m] Listening on /dev/initctl Compatibility Named Pipe.
> e[1;32m  OK  e[0m] Set up automount Arbitrary Executable File Formats F...utomount Point.
> e[1;32m  OK  e[0m] Reached target Encrypted Volumes.
> e[1;32m  OK  e[0m] Listening on udev Kernel Socket.
> e[1;32m  OK  e[0m] Listening on udev Control Socket.
>          Expecting device dev-disk-by\x2did-ata\x2dWDC_WD1600...part1.device...
>          Expecting device dev-disk-by\x2did-ata\x2dWDC_WD1600...part3.device...
> e[1;32m  OK  e[0m] Listening on Journal Socket.
>          Starting LSB: Set default boot entry if called...
>          Mounting POSIX Message Queue File System...
>          Mounting Huge Pages File System...
>          Starting Create dynamic rule for /dev/root link...
>          Starting Journal Service...
> e[1;32m  OK  e[0m] Started Journal Service.
> e[1;32m  OK  e[0m] Started Collect Read-Ahead Data.
> e[1;32m  OK  e[0m] Started Replay Read-Ahead Data.
>          Starting Setup Virtual Console...
>          Starting Load Kernel Modules...
>          Starting Remount Root and Kernel File Systems...
> e[1;32m  OK  e[0m] Started Load Kernel Modules.
>          Starting Apply Kernel Variables...
> e[1;32m  OK  e[0m] Mounted Huge Pages File System.
> e[1;32m  OK  e[0m] Mounted POSIX Message Queue File System.
> e[1;32m  OK  e[0m] Started Apply Kernel Variables.
> e[1;32m  OK  e[0m] Started Remount Root and Kernel File Systems.
>          Starting Load Random Seed...
> e[1;32m  OK  e[0m] Started Load Random Seed.
> e%Ge[1;32m  OK  e[0m] Started LSB: Set default boot entry if called.
> e[1;32m  OK  e[0m] Started Create dynamic rule for /dev/root link.
>          Starting udev Kernel Device Manager...
> e[1;32m  OK  e[0m] Started udev Kernel Device Manager.
> e[1;32m  OK  e[0m] Reached target Local File Systems (Pre).
>          Mounting Lock Directory...
>          Mounting /sys/kernel/debug...
>          Mounting Runtime Directory...
> e[1;32m  OK  e[0m] Mounted Lock Directory.
> e[1;32m  OK  e[0m] Mounted /sys/kernel/debug.
> e[1;32m  OK  e[0m] Mounted Runtime Directory.
> e[1;32m  OK  e[0m] Started Setup Virtual Console.
>          Starting udev Coldplug all Devices...
> e[1;32m  OK  e[0m] Started udev Coldplug all Devices.
>          Starting Show Plymouth Boot Screen...
> e[1;32m  OK  e[0m] Reached target Sound Card.
> e[1;32m  OK  e[0m] Found device WDC_WD1600AVVS-00L2B0.
>          Activating swap /dev/disk/by-id/ata-WDC_WD1600AVVS-0...472660-part1...
> e[1;32m  OK  e[0m] Activated swap /dev/disk/by-id/ata-WDC_WD1600AVVS-00...17472660-part1.
> e[1;32m  OK  e[0m] Reached target Swap.
> e[1;32m  OK  e[0m] Found device WDC_WD1600AVVS-00L2B0.
>          Starting File System Check on /dev/disk/by-id/ata-WD...472660-part3...
> systemd-fsck[398]: /dev/sda3: clean, 41713/8331264 files, 3644302/33303296 blocks
> e[1;32m  OK  e[0m] Started File System Check on /dev/disk/by-id/ata-WDC...17472660-part3.
>          Mounting /home...
> e[1;32m  OK  e[0m] Mounted /home.
> e[1;32m  OK  e[0m] Reached target Local File Systems.
>          Starting Recreate Volatile Files and Directories...
>          Starting Tell Plymouth To Write Out Runtime Data...
> e[1;32m  OK  e[0m] Started Recreate Volatile Files and Directories.
> e[1;32m  OK  e[0m] Started Tell Plymouth To Write Out Runtime Data.
> e[1;32m  OK  e[0m] Reached target System Initialization.
> e[1;32m  OK  e[0m] Listening on PC/SC Smart Card Daemon Activation Socket.
> e[1;32m  OK  e[0m] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
> e[1;32m  OK  e[0m] Listening on RPCbind Server Activation Socket.
> e[1;32m  OK  e[0m] Reached target RPC Port Mapper.
> e[1;32m  OK  e[0m] Listening on CUPS Printing Service Sockets.
> e[1;32m  OK  e[0m] Listening on D-Bus System Message Bus Socket.
> e[1;32m  OK  e[0m] Reached target Sockets.
>          Starting Restore Sound Card State...
>          Starting Console System Startup Logging...
> e[1;32m  OK  e[0m] Started Restore Sound Card State.
> e[1;32m  OK  e[0m] Started Console System Startup Logging.
> e[1;32m  OK  e[0m] Started Show Plymouth Boot Screen.
> e[1;32m  OK  e[0m] Reached target Basic System.
>          Starting eveusb.service...
>          Starting Avahi mDNS/DNS-SD Stack...
>          Starting Haveged Entropy Gathering Daemon...
> e[1;32m  OK  e[0m] Started Haveged Entropy Gathering Daemon.
>          Starting System Logging Service...
>          Starting Login Service...
>          Starting RPC Bind...
> e[1;32m  OK  e[0m] Started RPC Bind.
> e[1;32m  OK  e[0m] Started System Logging Service.
> e[1;32m  OK  e[0m] Reached target Syslog.
>          Starting D-Bus System Message Bus...
> e[1;32m  OK  e[0m] Started D-Bus System Message Bus.
>          Starting Name Service Cache Daemon...
> e[1;32m  OK  e[0m] Started Name Service Cache Daemon.
> e[1;32m  OK  e[0m] Started eveusb.service.
>          Starting LSB: Framebuffer setup...
>          Starting LSB: Configure network interfaces and set up routing...
> e[1;32m  OK  e[0m] Started LSB: Framebuffer setup.
> e[1;32m  OK  e[0m] Started Avahi mDNS/DNS-SD Stack.
> e[1;32m  OK  e[0m] Started Login Service.
> e[1;32m  OK  e[0m] Started LSB: Configure network interfaces and set up routing.
> e[1;32m  OK  e[0m] Reached target Network.
>          Starting Command Scheduler...
> e[1;32m  OK  e[0m] Started Command Scheduler.
>          Starting LSB: NFS client services...
>          Starting OpenSSH Daemon...
>          Starting Vsftpd ftp daemon...
> e[1;32m  OK  e[0m] Started Vsftpd ftp daemon.
>          Starting /etc/init.d/boot.local Compatibility...
> e[1;32m  OK  e[0m] Reached target Host and Network Name Lookups.
> e[1;32m  OK  e[0m] Started OpenSSH Daemon.
> e[1;32m  OK  e[0m] Started /etc/init.d/boot.local Compatibility.
> e[1;32m  OK  e[0m] Started LSB: NFS client services.
> e[1;32m  OK  e[0m] Reached target Remote File Systems (Pre).
>          Mounting /mnt/erikbackup-nfs...
> e[1;32m  OK  e[0m] Mounted /mnt/erikbackup-nfs.
> e[1;32m  OK  e[0m] Reached target Remote File Systems.
>          Starting LSB: Automatically install DKMS modules for new kernels...
>          Starting LSB: Script infrastructure to enable/disabl...nt functions...
>          Starting LSB: Starts the xinet daemon. Be aware that...td) section....
>          Starting LSB: Network time protocol daemon (ntpd)...
>          Starting Trigger Flushing of Journal to Persistent Storage...
> e[1;32m  OK  e[0m] Started Trigger Flushing of Journal to Persistent Storage.
>          Starting Permit User Sessions...
> e[1;32m  OK  e[0m] Started Permit User Sessions.
>          Starting Getty on tty1...
> e[1;32m  OK  e[0m] Started Getty on tty1.
> e[1;32m  OK  e[0m] Reached target Login Prompts.
> e[1;32m  OK  e[0m] Started LSB: Script infrastructure to enable/disable...ment functions.
> e[1;32m  OK  e[0m] Started LSB: Network time protocol daemon (ntpd).
> e[1;32m  OK  e[0m] Reached target System Time Synchronized.
>          Starting Postfix Mail Transport Agent...
>          Starting LSB: X Display Manager...
> e[1;32m  OK  e[0m] Started LSB: Starts the xinet daemon. Be aware that ...netd) section..
> e[1;32m  OK  e[0m] Started LSB: X Display Manager.

What say here ?


Erik Jakobsen
Opensuse 12.3 64bit
Linux linux-z96b.site 3.7.10-1.11-desktop
KDE Version 4.10.2 “release 1”

Install the package “systemd-analyze” (included in openSUSE, but not installed by default). Then run “systemd-analyze blame” to see how long each service takes to start, or “systemd-analyze plot >plot.svg” to get a graphical chart of the boot process.

On 11/07/13 17:56, wolfi323 wrote:
>
> Install the package “systemd-analyze” (included in openSUSE, but not
> installed by default). Then run “systemd-analyze blame” to see how long
> each service takes to start, or “systemd-analyze plot >plot.svg” to get
> a graphical chart of the boot process.
>
>

Oh that was nice information, thank you :slight_smile:

Got this.


http://paste.opensuse.org/64553180

What say ?


Erik Jakobsen
Opensuse 12.3 64bit
Linux linux-z96b.site 3.7.10-1.11-desktop
KDE Version 4.10.2 “release 1”

try disabling nfs.service and restart again

On 11/07/13 20:06, vazhavandan wrote:
>
> try disabling nfs.service and restart again
>
>

It helped. Now the boottime until the login window appear is 2 minutes.

What say about that time ?


Erik Jakobsen
Opensuse 12.3 64bit
Linux linux-z96b.site 3.7.10-1.11-desktop
KDE Version 4.10.2 “release 1”

What does blame say now ?

systemd-analyze blame

Also set max size for log
Refer:- https://forums.opensuse.org/english/get-technical-help-here/install-boot-login/483150-slow-boot-time-opensuse-12-2-good-laptop-3.html#post2532089

The other thing that took quite long (90s) in your plot was the nfs-mounting of erikbackup.
I guess you have that in /etc/inittab.
Try to comment that out, something seems to be wrong here.

> What does blame say now ?
>
> Code:
> --------------------
> systemd-analyze blame
> --------------------


http://paste.opensuse.org/41768503

Also set max size for log
Refer:- http://tinyurl.com/c7x2o6s

Is it this you mean ?


> edited /etc/systemd/systemd-journald.conf and set the variable SystemMaxFileSize=10M


Erik Jakobsen
Opensuse 12.3 64bit
Linux linux-z96b.site 3.7.10-1.11-desktop
KDE Version 4.10.2 “release 1”

On 11/07/13 20:56, wolfi323 wrote:
>
> erikja;2571154 Wrote:
>> On 11/07/13 20:06, vazhavandan wrote:
>>>
>>> try disabling nfs.service and restart again
>>>
>>>
>>
>> It helped. Now the boottime until the login window appear is 2 minutes.
>>
>> What say about that time ?
>>
> The other thing that took quite long (90s) in your plot was the
> nfs-mounting of erikbackup.
> I guess you have that in /etc/inittab.
> Try to comment that out, something seems to be wrong here.
>
>

Ok, but the content of this file is only:


id::initdefault:


Erik Jakobsen
Opensuse 12.3 64bit
Linux linux-z96b.site 3.7.10-1.11-desktop
KDE Version 4.10.2 “release 1”

hmmmm

mounts are in the /etc/fstab NOT the inittab file

go there. note it is always good to make a backup copy of the file before changing in case you do something wrong.

On 11/07/13 20:56, wolfi323 wrote:
>
> erikja;2571154 Wrote:
>> On 11/07/13 20:06, vazhavandan wrote:
>>>
>>> try disabling nfs.service and restart again
>>>
>>>
>>
>> It helped. Now the boottime until the login window appear is 2 minutes.
>>
>> What say about that time ?
>>
> The other thing that took quite long (90s) in your plot was the
> nfs-mounting of erikbackup.
> I guess you have that in /etc/inittab.
> Try to comment that out, something seems to be wrong here.
>
>
Looked further for erikbackup.


>  locate erikbackup
> /run/systemd/generator/mnt-erikbackup\x2dnfs.mount
> /run/systemd/generator/remote-fs.target.requires/mnt-erikbackup\x2dnfs.mount
> /var/run/systemd/generator/mnt-erikbackup\x2dnfs.mount
> /var/run/systemd/generator/remote-fs.target.requires/mnt-erikbackup\x2dnfs.mount

And I only have a weak memory of what erikbackup is, even I of course
have made it :slight_smile:


Erik Jakobsen
Opensuse 12.3 64bit
Linux linux-z96b.site 3.7.10-1.11-desktop
KDE Version 4.10.2 “release 1”

On 12/07/13 08:36, gogalthorp wrote:
>
> hmmmm
>
> mounts are in the /etc/fstab NOT the inittab file
>
> go there. note it is always good to make a backup copy of the file
> before changing in case you do something wrong.
>
>
Thanks for this. Here it is.


> /dev/disk/by-id/ata-WDC_WD1600AVVS-00L2B0_WD-WCAV17472660-part1 swap    swap    defaults 0 0
> /dev/disk/by-id/ata-WDC_WD1600AVVS-00L2B0_WD-WCAV17472660-part2 /       ext4    acl,user_xattr 1 1
> /dev/disk/by-id/ata-WDC_WD1600AVVS-00L2B0_WD-WCAV17472660-part3 /home   ext4    acl,user_xattr 1 2
> proc    /proc   proc    defaults 0 0
> sysfs   /sys    sysfs   noauto 0 0
> debugfs /sys/kernel/debug       debugfs noauto 0 0
> usbfs   /proc/bus/usb   usbfs   noauto 0 0
> devpts  /dev/pts        devpts  mode=0620,gid=5 0 0
> BackupServer:/erikbackup        /mnt/erikbackup-nfs     nfs     defaults 0 0

Also ok with backup.

Can it just be uncomment the line with backup, to see if it helps ?


Erik Jakobsen
Opensuse 12.3 64bit
Linux linux-z96b.site 3.7.10-1.11-desktop
KDE Version 4.10.2 “release 1”

On 2013-07-11 16:56, AdaLovelace wrote:
>
> -/var/log/boot.msg-

That file is deprecated and does not exist. There may be one such
created by plymouth, but it is not reliable.


Cheers / Saludos,

Carlos E. R.
(from 12.3 x86_64 “Dartmouth” at Telcontar)

On 2013-07-11 17:38, Erik Jakobsen wrote:
> On 11/07/13 16:56, AdaLovelace wrote:
>>
>> -/var/log/boot.msg- or the command dmesg should help.
>>
>>
>
> Thank you, here are the content of boot.log

> What say here ?

Nothing, that file is useless, there are not timestamps.

Anyway, you DO have problems with NFS and Networking, as I said
yesterday, and is proved by the little info you posted already >;-)

Have a look at the “/var/log/messages” as I said. No, I do not know
exactly what to look for. Have a good look yourself, at the last boot
sequence, try to find anything related to nfs mounts of network problems.


Cheers / Saludos,

Carlos E. R.
(from 12.3 x86_64 “Dartmouth” at Telcontar)

@op- All you need to do is attack these two

 89999ms mnt-erikbackup\x2dnfs.mount
  2123ms dkms_autoinstaller.service

and your machine will probably boot in 30 ~45 seconds flat .