slow boot: normal or configuration problem?

General TC > General TC Talk

(1/5) > >>

GNUser:
Pure64 10.1 is my main OS and I love it, but it always boots much slower (~40 seconds) compared to my prior OS (Devuan ASCII, ~12 seconds).

It's no big deal if it's normal. I just want to make sure that there is no problem with my configuration. Some of the delay is due to all my extensions, so let's see what happens if I exclude the bulk of the extensions. I will use the printk.time=1 boot parameter for the two tests below.

Boot time is ~12 seconds (/var/log/messages here) when my onboot.lst contains this:

--- Code: ---Xfbdev.tcz
aterm.tcz
fluxbox.tcz

--- End code ---

Boot time jumps up to ~24 seconds (/var/log/messages here; note the ~10 second delay between lines 1037 and 1038) when my onboot.lst contains this:

--- Code: ---Xorg-7.7.tcz
aterm.tcz
fluxbox.tcz
xf86-video-intel.tcz

--- End code ---

Does any obvious pathology jump out at you in either log? I prefer using full-blown Xorg7.7--is there a reason it takes about twice as long to boot than Xfbdev?

P.S. This machine is a T400 ThinkPad with integrated Intel graphics. My harddrive is a nice and fast SSD. I'm using mount mode for the extensions (i.e., I don't use copy2fs.flg).

Rich:
Hi GNUser
I'm guessing that's a 10 second timeout occurring due to the error. I see a similar error here:
https://bbs.archlinux.org/viewtopic.php?id=198157

curaga:
Xorg has a lot of deps, but certainly shouldn't take 10 seconds. If you boot with "showapps" you can roughly see on screen what takes time.

The wiki has info on using bootchart too.

Rich:
Hi GNUser
You might want to take a peek at Xorg.0.log (it's timestamped too) for other errors. I wonder if you need firmware-i915.tcz ?

GNUser:
Thank you, Rich and curaga. When it comes to graphics, I'm in way over my head. So I really appreciate any help/suggestions. Here are some updates based on the above suggestions:

- showapps shows no big delays during extension loading. The delays happen later.

- installing firmware-i915.tcz makes no difference

- /var/log/Xorg.0.log does not seem to show anything to account for the delay

- The Libreboot FAQ specifically says that KMS is required for graphics, so I tried booting with the i915.modeset=1 boot parameter, but it makes no difference

Here is the hotspot of /var/log/messages where the two big delays happen (one between 15.2 and 24.6, the other between 24.6 and 37.0) (BTW, running filetool.sh -r once system is booted takes less than 2 seconds, so restoring mydata.tgz is not to blame for the second delay):

--- Code: ---Nov 21 09:25:04 box user.debug kernel: [ 14.370429] ath: Country alpha2 being used: 00
Nov 21 09:25:04 box user.debug kernel: [ 14.370430] ath: Regpair used: 0x6a
Nov 21 09:25:04 box user.debug kernel: [ 14.381012] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Nov 21 09:25:04 box user.info kernel: [ 14.381264] ieee80211 phy0: Atheros AR9280 Rev:2 mem=0xffffc90000810000, irq=17
Nov 21 09:25:04 box user.info kernel: [ 14.459799] [drm] Initialized i915 1.6.0 20180719 for 0000:00:02.0 on minor 0
Nov 21 09:25:04 box user.info kernel: [ 14.644789] fbcon: inteldrmfb (fb0) is primary device
Nov 21 09:25:04 box user.info kernel: [ 14.884996] Console: switching to colour frame buffer device 160x50
Nov 21 09:25:04 box user.info kernel: [ 14.906011] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
Nov 21 09:25:05 box user.notice kernel: [ 15.202172] random: fc-cache: uninitialized urandom read (8 bytes read)
Nov 21 09:25:05 box user.notice kernel: [ 15.202708] random: fc-cache: uninitialized urandom read (8 bytes read)
Nov 21 09:25:05 box user.notice kernel: [ 15.203109] random: fc-cache: uninitialized urandom read (8 bytes read)
Nov 21 09:25:14 box user.err kernel: [ 24.601669] [drm:0xffffffffa031711f] *ERROR* CPU pipe A FIFO underrun
Nov 21 09:25:19 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/chown -R root.staff /usr/local/tce.installed
Nov 21 09:25:19 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 775 /usr/local/tce.installed
Nov 21 14:25:24 box daemon.err udevd[144]: unknown key 'RUN{program}' in /etc/udev/rules.d/73-seat-late.rules:17
Nov 21 14:25:24 box daemon.err udevd[144]: unknown key 'RUN{program}' in /etc/udev/rules.d/73-seat-late.rules:17
Nov 21 14:25:25 box daemon.err udevd[144]: unknown key 'RUN{program}' in /etc/udev/rules.d/73-seat-late.rules:17
Nov 21 14:25:25 box daemon.err udevd[144]: unknown key 'RUN{program}' in /etc/udev/rules.d/73-seat-late.rules:17
Nov 21 09:25:26 box daemon.notice haveged: haveged starting up
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/sh
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/cp /usr/local/share/wbar/dot.wbar /usr/local/tce
.icons
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/chown root.staff /usr/local/tce.icons
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/chmod g+w /usr/local/tce.icons
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/mv /tmp/wbar.7053 /usr/local/tce.icons
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/chmod g+w /usr/local/tce.icons
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/mv /tmp/wbar.7061 /usr/local/tce.icons
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/chmod g+w /usr/local/tce.icons
Nov 21 09:25:26 box authpriv.notice sudo: root : TTY=console ; PWD=/ ; USER=root ; COMMAND=/bin/tar -C / -zxf /mnt/sda2/tce/mydata.tgz
Nov 21 09:25:27 box user.notice kernel: [ 37.064760] random: crng init done
Nov 21 09:25:27 box user.notice kernel: [ 37.064789] random: 6 urandom warning(s) missed due to ratelimiting
Nov 21 14:25:27 box daemon.info init: starting pid 7265, tty '/dev/tty1': '/sbin/getty -nl /sbin/autologin 38400 tty1'
Nov 21 14:25:27 box auth.info login[7265]: root login on 'tty1'
Nov 21 09:25:27 box daemon.notice haveged: haveged starting up
--- End code ---

Navigation

[0] Message Index

[#] Next page

Go to full version