[solved] Systemd hangs sometimes on boot with linux-ck

SiD · 2012-09-03 16:39:43

Hi i have linux-ck kernel installes and a Systemd-only init.
Sometimes it hangs on boot and then, instead of starting X / LXDM, drops to a commandline login prompt. It does not happen on every boot and never happend with arch stock kernel so far. I cant't login to search in systemd journal for errors because the keyboard does not work corret, e.g. if I press "o" it writes a "6"

here a shot from last time it happend. In the shot the last messages from Systemd are visible, but sometimes the screen is cleard before the login prompt appears.

Last edited by SiD (2012-09-08 07:51:58)

graysky · 2012-09-03 16:56:54

Very odd, never seen it on several linux-ck machines... What are your services you enabled?

SiD · 2012-09-03 17:02:47

ok I activated syslog-ng and found this in the logs from last boot that not worked

Xorg log

# grep -e EE -e WW /var/log/Xorg.0.log.old 
[     1.932] Current Operating System: Linux sunny 3.5.3-2-ck #1 SMP PREEMPT Mon Aug 27 15:48:39 EDT 2012 x86_64
	(WW) warning, (EE) error, (NI) not implemented, (??) unknown.
[     1.942] (WW) The directory "/usr/share/fonts/OTF/" does not exist.
[     1.944] (WW) `fonts.dir' not found (or not valid) in "/usr/share/fonts/100dpi/".
[     1.944] (WW) `fonts.dir' not found (or not valid) in "/usr/share/fonts/75dpi/".
[     1.948] (II) Loading extension MIT-SCREEN-SAVER
[     2.133] (EE) NVIDIA(0): Failed to initialize the NVIDIA kernel module. Please see the
[     2.133] (EE) NVIDIA(0):     system's kernel log for additional error messages and
[     2.133] (EE) NVIDIA(0):     consult the NVIDIA README for details.
[     2.133] (EE) NVIDIA(0):  *** Aborting ***
[     2.133] (EE) NVIDIA(0): Failing initialization of X screen 0
[     2.133] (EE) Screen(s) found, but none have a usable configuration.

lxdm log

# cat /var/log/lxdm.log.old 
** Message: find greeter (nil)

** Message: find idle (nil)

** Message: add xserver watch


X.Org X Server 1.12.4
Release Date: 2012-08-27
X Protocol Version 11, Revision 0
Build Operating System: Linux 3.4.8-1-ARCH x86_64 
Current Operating System: Linux sunny 3.5.3-2-ck #1 SMP PREEMPT Mon Aug 27 15:48:39 EDT 2012 x86_64
Kernel command line: root=UUID=3e32c396-fffb-4e00-b513-08db5c5da177 i915.i915_enable_rc6=1 ro initrd=../initramfs-linux-ck.img BOOT_IMAGE=../vmlinuz-linux-ck 
Build Date: 27 August 2012  08:04:39AM
 
Current version of pixman: 0.26.2
	Before reporting problems, check http://wiki.x.org
	to make sure that you have the latest version.
Markers: (--) probed, (**) from config file, (==) default setting,
	(++) from command line, (!!) notice, (II) informational,
	(WW) warning, (EE) error, (NI) not implemented, (??) unknown.
(==) Log file: "/var/log/Xorg.0.log", Time: Mon Sep  3 18:45:01 2012
(==) Using config directory: "/etc/X11/xorg.conf.d"
NVIDIA: could not open the device file /dev/nvidiactl (No such device or address).

Fatal server error:
no screens found

Please consult the The X.Org Foundation support 
	 at http://wiki.x.org
 for help. 
Please also check the log file at "/var/log/Xorg.0.log" for additional information.

Server terminated with error (1). Closing log file.
** Message: exit cb

** Message: free session

arc 1

and in everything log

Sep  3 18:45:03 localhost systemd[1]: Startup finished in 1s 157ms 589us (kernel) + 2s 645ms 840us (userspace) = 3s 803ms 429us.
Sep  3 18:45:04 localhost dbus-daemon[317]: dbus[317]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Sep  3 18:45:04 localhost dbus[317]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Sep  3 18:45:04 localhost dbus[317]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service failed to load: No such file or directory. See system logs and 'systemctl status dbus-org.freedesktop.Avahi.service' for details.
Sep  3 18:45:04 localhost dbus-daemon[317]: dbus[317]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service failed to load: No such file or directory. See system logs and 'systemctl status dbus-org.freedesktop.Avahi.service' for details.
Sep  3 18:45:06 localhost systemd[1]: lxdm.service: main process exited, code=exited, status=1
Sep  3 18:45:06 localhost systemd[1]: Unit lxdm.service entered failed state.
Sep  3 18:45:06 localhost dbus-daemon[317]: dbus[317]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Sep  3 18:45:06 localhost dbus[317]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Sep  3 18:45:06 localhost dbus[317]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service failed to load: No such file or directory. See system logs and 'systemctl status dbus-org.freedesktop.Avahi.service' for details.
Sep  3 18:45:06 localhost dbus-daemon[317]: dbus[317]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service failed to load: No such file or directory. See system logs and 'systemctl status dbus-org.freedesktop.Avahi.service' for details.

Seems to me there is a problem loading the Nvidia driver. Don't know if the dbus errors matter ...

EDIT:
Makes sence to me, without a graphics driver loaded X / LXDM can not start.

Last edited by SiD (2012-09-03 17:12:23)

SiD · 2012-09-03 17:04:14

the enabled services

# systemctl list-unit-files | grep enabled
acpid.service                           enabled 
cronie.service                          enabled 
cups.service                            enabled 
dhcpcd@.service                         enabled 
display-manager.service                 enabled 
dkms.service                            enabled 
fancontrol.service                      enabled 
getty@.service                          enabled 
lircd.service                           enabled 
lxdm.service                            enabled 
ntpd.service                            enabled 
syslog-ng.service                       enabled 
syslog.service                          enabled 
acpid.socket                            enabled 
remote-fs.target                        enabled

p.s.
syslog-ng just enabled to get the logs...

Stebalien · 2012-09-03 17:39:27

1. How are you loading the nvidia module?
2. Are you using the nvidia-ck module?
3. Boot to multi-user.target (console) and list the loaded modules.

SiD · 2012-09-03 18:13:51

1.

I have a file in /etc/X11/xorg.conf.d

$ cat /etc/X11/xorg.conf.d/30-nvidia.conf 
Section "Device"
   Identifier     "Device0"
   Driver         "nvidia"
   VendorName     "NVIDIA Corporation"
   Option	  "Coolbits" "4"
EndSection

2.
Yes nvidia-ck-corex is installed, and if booting works it is loaded. (outputs from a session booting into X worked)

$ pacman -Qs nvidia
Password: 
local/libvdpau 0.4.1-2
    Nvidia VDPAU library
local/nvidia 304.43-1
    NVIDIA drivers for linux.
local/nvidia-ck-corex 304.43-1 (ck-corex)
    NVIDIA drivers for linux-ck. Intel Core2/Newer Xeon and Intel Core i3/i5/i7 optimized.
local/nvidia-lts 304.43-1
    NVIDIA drivers for linux-lts
local/nvidia-utils 304.43-1
    NVIDIA drivers utilities and libraries.

$ lsmod | grep nvidia
nvidia              11195110  40 
i2c_core               20508  11 i2c_i801,dib0070,dib0090,dib8000,dvb_usb,dvb_usb_dib0700,nvidia,dib3000mc,dibx000_common,dib7000m,dib7000p

$ modinfo nvidia
filename:       /usr/lib/modules/3.5.3-2-ck/extramodules/nvidia.ko.gz
alias:          char-major-195-*
version:        304.43
supported:      external
license:        NVIDIA
...
...
...

3.
Do you think in cases loading the module fails, X is trying to load the stock Nvidia module?

Why should I boot into console? Isn't the Nvidia module loaded by Xorg?

Stebalien · 2012-09-03 21:43:24

There is an nvidia Xorg driver and an nvidia kernel module. I was trying to make sure that the nvidia kernel module was getting loaded (it is). But if your system hangs only sometimes, you might have some sort of race condition. Try blacklisting the nouveau kernel module if you haven't already (using /etc/modprobe.d/) and manually enabling the nvidia kernel module (using /etc/modules-load.d/).

Also, your everything.log/kernel.log should be a good deal longer (the message that your Xorg log references is missing).

SiD · 2012-09-04 09:02:25

I booted 3 times, first and third time the problem not occured, only on the escond boot. This is from kernel.log

# grep -i nvidia everything.log 
Sep  4 10:41:18 localhost kernel: [    1.539795] nvidia: module license 'NVIDIA' taints kernel.
Sep  4 10:41:18 localhost kernel: [    1.546234] NVRM: loading NVIDIA UNIX x86_64 Kernel Module  304.43  Sun Aug 19 20:14:03 PDT 2012
Sep  4 10:41:18 localhost kernel: [    2.291537] input: HDA NVidia HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input11
Sep  4 10:41:18 localhost kernel: [    2.291622] input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input12
Sep  4 10:41:18 localhost kernel: [    2.291721] input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input13
Sep  4 10:41:18 localhost kernel: [    2.291792] input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input14
Sep  4 10:41:51 localhost kernel: [    1.562892] nvidia: module license 'NVIDIA' taints kernel.
Sep  4 10:41:51 localhost kernel: [    2.231840] input: HDA NVidia HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input11
Sep  4 10:41:51 localhost kernel: [    2.231950] input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input12
Sep  4 10:41:51 localhost kernel: [    2.232287] input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input13
Sep  4 10:41:51 localhost kernel: [    2.232416] input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input14
Sep  4 10:41:51 localhost kernel: [    2.233113] NVRM: loading NVIDIA UNIX x86_64 Kernel Module  304.43  Sun Aug 19 20:14:03 PDT 2012
Sep  4 10:42:17 localhost kernel: [    1.658473] nvidia: module license 'NVIDIA' taints kernel.
Sep  4 10:42:17 localhost kernel: [    1.665833] NVRM: loading NVIDIA UNIX x86_64 Kernel Module  304.43  Sun Aug 19 20:14:03 PDT 2012
Sep  4 10:42:17 localhost kernel: [    2.438528] input: HDA NVidia HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input11
Sep  4 10:42:17 localhost kernel: [    2.438610] input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input12
Sep  4 10:42:17 localhost kernel: [    2.438679] input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input13
Sep  4 10:42:17 localhost kernel: [    2.438775] input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input14

The only difference I see is that on the "failed" boot (10:41:51) the line "NVRAM:loading NVIDIA UNIX x86_64 Kernel Module ....." is after the "HDA NVidia HDMI ..." lines and on first and second boot before.

EDIT:
I will try to put nvidia in modules-load.d and blacklist nouveau

Last edited by SiD (2012-09-04 09:05:51)

SiD · 2012-09-04 09:22:42

Ok, adding "nvidia" to my modules file in /etc/modules-load.d/ seems to solve it. ~10 boots without the Problem.

blacklisting nouveau seems not necessary, and blacklisting only nouveau without nvidia in /etc/modules-load.d/ does not work.

Thanks.

P.S.
I will have a look at this, and set the thread to solved if it doesn't occure anymore.

EDIT://
seems to be solved

Last edited by SiD (2012-09-08 07:51:38)

Arch Linux

#1 2012-09-03 16:39:43

[solved] Systemd hangs sometimes on boot with linux-ck

#2 2012-09-03 16:56:54

Re: [solved] Systemd hangs sometimes on boot with linux-ck

#3 2012-09-03 17:02:47

Re: [solved] Systemd hangs sometimes on boot with linux-ck

#4 2012-09-03 17:04:14

Re: [solved] Systemd hangs sometimes on boot with linux-ck

#5 2012-09-03 17:39:27

Re: [solved] Systemd hangs sometimes on boot with linux-ck

#6 2012-09-03 18:13:51

Re: [solved] Systemd hangs sometimes on boot with linux-ck

#7 2012-09-03 21:43:24

Re: [solved] Systemd hangs sometimes on boot with linux-ck

#8 2012-09-04 09:02:25

Re: [solved] Systemd hangs sometimes on boot with linux-ck

#9 2012-09-04 09:22:42

Re: [solved] Systemd hangs sometimes on boot with linux-ck

Board footer