Installed nvidiaG03 - Now openSUSE 12.3 hangs for 30secs on (re)login

Hi Guys,

Would appreciate some help :slight_smile:

Running openSUSE 12.3 (3.7.10-1.16-desktop x86_64), KDE 4.11.1 desktop.

Installed the nvidia proprietary driver via YaST from the nvidia repository - rebooted - logged in - all OK.

The problem occurs on subsequent logins:
Reach the graphical login screen OK, select user, login - after around 8-9 seconds the desktop appears (so far normal) - the system then hangs for around 35 seconds before the login sound is played. During those 35sec the mouse is responsive, but if I attempt to start an application the ‘bouncing cursor’ is displayed (until it times out) and the application doesn’t load until after the 35sec has elapsed.

A secondary problem is switching back and forth between the GUI and any other virtual terminal. From GUI on vt7 to any other vt the screen blanks for 2-3 seconds. Returning to the GUI on vt7 after 2-3 seconds the KDE panel appears but it takes another 2-3sec before the rest of the desktop appears.

This is what was installed:


nvidia-gfxG03-kmp-desktop-319.32_k3.7.10_1.1-15.1.x86_64
nvidia-computeG03-319.32-15.1.x86_64
x11-video-nvidiaG03-319.32-15.1.x86_64

Nouveau has been blacklisted:


~$ grep nouveau /etc/modprobe.d/*
/etc/modprobe.d/nvidia-desktop.conf:blacklist nouveau

Video card and kernel driver:


01:00.0 VGA compatible controller: NVIDIA Corporation G84 [GeForce 8600 GTS] (rev a1) (prog-if 00 [VGA controller])

        Kernel driver in use: nvidia

There is no old /etc/X11/xorg.conf lurking around. I have tried removing and reinstalling the nvidia driver, and tried the G02 version of the driver.

As a relative newcomer to openSUSE I’m somewhat at a loss as to know what to do… Any pointers would be appreciated.

In Xorg.0.log I noticed there are several drivers loaded (including nouvea), although they are subsequently unloaded, is that ‘normal’?

The only thing I thought was perhaps(?) relevant was this from .xsession-errors


kwin(1582) KWin::GlxBackend::present: 
It seems you are using the nvidia driver without triple buffering
You must export __GL_YIELD="USLEEP" to prevent large CPU overhead on synced swaps
Preferably, enable the TripleBuffer Option in the xorg.conf Device
For this reason, the tearing prevention has been disabled.
See https://bugs.kde.org/show_bug.cgi?id=322060

Attempted to include both log files but the forum software wasn’t having it - exceeded my character count.

Thanks in advance

Regards, Paul (at the bottom of a steep learning curve)

Time limit on editing… hmm.

Xorg.0.log after re-login: SUSE Paste

.xsession-errors after re-login: SUSE Paste

Regards, Paul

Hi Paul, welcome here.

From what I’ve read you need

Option "TripleBuffer" "1"


in /etc/X11/xorg.conf.d/50-device.conf

Mind, you’d have to uncomment some pieces of the file, the Section, Identifier and Endsection lines at least. Do keep a copy of the original :slight_smile: just in case.
BTW, is KMS enabled?

On Wed, 11 Sep 2013 19:56:02 +0000, tannington wrote:

> Time limit on editing… hmm.

https://forums.opensuse.org/faq.php?faq=novfor#faq_edit_time

Jim


Jim Henderson
openSUSE Forums Administrator
Forum Use Terms & Conditions at http://tinyurl.com/openSUSE-T-C

Hi,

@Knurpht

There was no existing ‘50-device-conf’ in /etc/X11/xorg.conf.d

Created one with the following, which I think is correct.

Section "Device"
  Identifier "Default Device"
  
  Option "TripleBuffer" "1"
  
EndSection

This has now suppressed the error message in .xsession-errors, but unfortunately the problem still remains.

KMS is enabled. I’ve tried booting with nomodeset - no different, still have the 30sec delay…

I find it odd that the initial login from a cold boot is OK, this delay only occurs on subsequent logins.

As an aside, I’ve used this hardware with the nvidia driver before on kubuntu 12.04 LTS with no problems. The driver would have been an earlier version though.

@hendersj

Yeah… I did actually read through the FAQ … albeit after my initial postings.

Thanks guys :slight_smile:

To be honest, I’ve had quite some trouble with G84 based cards at a customer. We were expecting better performance compared to the onboard 6xxx series, the results were disappointing. But this was some years ago, and a lot has changed.
Question: how did the card do on the nouveau driver? The one G84 card I had died last summer but it did a better job on nouveau than on the NVIDIA blob.

Hi,

@Knurpht

The nouveua driver’s fairly good. I’d much prefer to use the G03 driver, to me it appears to give better results, ‘smoother’. It also runs the GPU at a good 10°C lower than the nouveau driver.

I have now found what the cause of the delay was, but I don’t know why. :\

Reverted back to the nouveau driver and all was normal with the logins.

Re-installed the G03 driver - problem returns as before, part way through login it freezes for around 30secs, then resumes.

In an attempt (probably quite amateurish) to discover what was going on during the ‘freeze’ I logged out, from vt2 logged in as another user and set Top to log to a file every half second.

~$ top -i -d 0.5 -b > toplog.txt

Leaving that running, returned to the graphical login on vt7, logged in as myself, Waited until the login had completed, and logged out.

Back to vt2, stopped Top and looked through the file. I realise that in computing terms 500ms is a long time, but I hoped I might get some idea of what was going on.

I was able to see the approximate point at which my login had begun, 12:28:38

top - 12:28:37 up 48 min,  1 user,  load average: 0.48, 0.35, 0.26
Tasks:  91 total,   1 running,  90 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.0 us,  1.0 sy,  0.0 ni, 99.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,   966220 used,  3088304 free,    54240 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733056 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 4788 mags      20   0 11044 1432 1048 R  2.0  0.0   0:00.26 top

top - 12:28:37 up 48 min,  1 user,  load average: 0.48, 0.35, 0.26
Tasks:  91 total,   1 running,  90 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.0 us,  0.0 sy,  0.0 ni,100.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,   966220 used,  3088304 free,    54240 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733056 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND

top - 12:28:38 up 48 min,  2 users,  load average: 0.53, 0.36, 0.26
Tasks: 100 total,   2 running,  97 sleeping,   0 stopped,   1 zombie
%Cpu(s): 39.0 us, 22.0 sy,  0.0 ni, 37.0 id,  2.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,   960296 used,  3094228 free,    54252 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   727868 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 5252 paul      20   0  351m  43m  28m R 25.9  1.1   0:00.13 kdeinit4
 5228 paul      20   0 39540 7900 1220 S 11.9  0.2   0:00.06 ksplashx
 5130 paul      20   0 13052 1704 1360 S  9.9  0.0   0:00.05 startkde
 5105 root      20   0  122m  37m  19m S  6.0  1.0   0:00.63 Xorg
    3 root      20   0     0    0    0 S  2.0  0.0   0:00.33 ksoftirqd/0
 5109 root      20   0 82056 2532 1908 S  2.0  0.1   0:00.01 kdm
 5249 paul      20   0  129m 8876 7388 S  2.0  0.2   0:00.01 kdeinit4

The login then appeared to proceed normally until it reached this stage:

top - 12:28:47 up 48 min,  2 users,  load average: 0.64, 0.39, 0.27
Tasks: 123 total,   2 running, 121 sleeping,   0 stopped,   0 zombie
%Cpu(s):  4.0 us, 47.5 sy,  0.0 ni, 48.5 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192340 used,  2862184 free,    54348 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733752 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 5280 paul      20   0 2799m  72m  47m R  101  1.8   0:01.94 kwin

top - 12:28:48 up 48 min,  2 users,  load average: 0.67, 0.40, 0.28
Tasks: 123 total,   2 running, 121 sleeping,   0 stopped,   0 zombie
%Cpu(s):  2.0 us, 48.5 sy,  0.0 ni, 49.5 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192340 used,  2862184 free,    54348 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733752 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 5280 paul      20   0 2799m  72m  47m R 99.3  1.8   0:02.44 kwin

top - 12:28:48 up 48 min,  2 users,  load average: 0.67, 0.40, 0.28
Tasks: 123 total,   2 running, 121 sleeping,   0 stopped,   0 zombie
%Cpu(s):  4.0 us, 48.0 sy,  0.0 ni, 48.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192340 used,  2862184 free,    54348 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733752 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 5280 paul      20   0 2799m  72m  47m R 99.3  1.8   0:02.94 kwin
 5286 paul      20   0 3340m 129m  47m S  4.0  3.3   0:04.08 plasma-desktop

It seems around here that the ‘freeze’ begins:

top - 12:28:49 up 48 min,  2 users,  load average: 0.67, 0.40, 0.28
Tasks: 123 total,   1 running, 122 sleeping,   0 stopped,   0 zombie
%Cpu(s):  3.0 us,  2.0 sy,  0.0 ni, 95.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192340 used,  2862184 free,    54348 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733752 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 5280 paul      20   0 2794m  72m  47m S  6.0  1.8   0:03.19 kwin
 4788 mags      20   0 11044 1432 1048 R  2.0  0.0   0:00.33 top
 5105 root      20   0  142m  54m  25m S  2.0  1.4   0:01.30 Xorg
 5286 paul      20   0 3340m 129m  47m S  2.0  3.3   0:04.10 plasma-desktop

After which not a lot happens for a while, these are now typical:

top - 12:28:50 up 48 min,  2 users,  load average: 0.67, 0.40, 0.28
Tasks: 123 total,   1 running, 122 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.0 us,  1.0 sy,  0.0 ni, 99.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192324 used,  2862200 free,    54364 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733744 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND

top - 12:28:51 up 48 min,  2 users,  load average: 0.67, 0.40, 0.28
Tasks: 123 total,   1 running, 122 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.0 us,  0.0 sy,  0.0 ni,100.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192324 used,  2862200 free,    54364 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733744 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND

top - 12:28:51 up 48 min,  2 users,  load average: 0.67, 0.40, 0.28
Tasks: 123 total,   1 running, 122 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.0 us,  0.0 sy,  0.0 ni,100.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192324 used,  2862200 free,    54364 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733744 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 4788 mags      20   0 11044 1432 1048 R  2.0  0.0   0:00.34 top

top - 12:28:52 up 48 min,  2 users,  load average: 0.67, 0.40, 0.28
Tasks: 123 total,   1 running, 122 sleeping,   0 stopped,   0 zombie
%Cpu(s):  1.0 us,  1.0 sy,  0.0 ni, 98.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192324 used,  2862200 free,    54364 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733744 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 5286 paul      20   0 3340m 129m  47m S  4.0  3.3   0:04.12 plasma-desktop

top - 12:28:52 up 48 min,  2 users,  load average: 0.67, 0.40, 0.28
Tasks: 123 total,   1 running, 122 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.0 us,  0.0 sy,  0.0 ni,100.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192324 used,  2862200 free,    54364 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733744 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND

top - 12:28:53 up 48 min,  2 users,  load average: 0.62, 0.39, 0.28
Tasks: 123 total,   1 running, 122 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.0 us,  0.0 sy,  0.0 ni,100.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1192324 used,  2862200 free,    54364 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733752 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 4788 mags      20   0 11044 1432 1048 R  2.0  0.0   0:00.35 top

Until the ‘freeze’ ends and login resumes:

top - 12:29:11 up 48 min,  2 users,  load average: 0.48, 0.37, 0.27
Tasks: 123 total,   1 running, 122 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0.0 us,  0.0 sy,  0.0 ni,100.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1191920 used,  2862604 free,    54388 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733728 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND

top - 12:29:11 up 48 min,  2 users,  load average: 0.48, 0.37, 0.27
Tasks: 123 total,   9 running, 114 sleeping,   0 stopped,   0 zombie
%Cpu(s): 11.9 us,  3.0 sy,  0.0 ni, 83.2 id,  2.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1191920 used,  2862604 free,    54388 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733728 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 5269 paul      20   0  515m  45m  25m R  9.9  1.2   0:00.41 kcminit_startup
 5278 paul      20   0  521m  34m  15m S  6.0  0.9   0:00.35 ksmserver
 5301 paul      20   0  428m  38m  24m R  6.0  1.0   0:00.24 knotify4

top - 12:29:12 up 48 min,  3 users,  load average: 0.48, 0.37, 0.27
Tasks: 125 total,   7 running, 117 sleeping,   0 stopped,   1 zombie
%Cpu(s): 72.0 us, 28.0 sy,  0.0 ni,  0.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   4054524 total,  1191920 used,  2862604 free,    54388 buffers
KiB Swap:  7835644 total,        0 used,  7835644 free,   733728 cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM     TIME+ COMMAND
 5301 paul      20   0 1114m  46m  28m S 25.8  1.2   0:00.37 knotify4
 5318 paul      20   0  432m  32m  13m R 23.9  0.8   0:00.17 krunner
 5337 paul      20   0  426m  31m  12m R 23.9  0.8   0:00.17 klipper
 5255 paul      20   0 1154m  48m  23m R 21.9  1.2   0:00.94 kded4
 5338 paul      20   0  318m  27m  18m R 21.9  0.7   0:00.11 thunderbird-bin
 5329 paul      20   0  266m  16m  11m R 19.9  0.4   0:00.14 polkit-kde-auth
 5333 paul      20   0  430m  34m  13m R 19.9  0.9   0:00.16 kmix
 5343 paul      20   0     0    0    0 Z 13.9  0.0   0:00.07 thunderbird-bin
 5105 root      20   0  142m  54m  25m S  9.9  1.4   0:01.35 Xorg
    9 root      -2   0     0    0    0 S  2.0  0.0   0:00.77 rcuc/0
 4986 paul       9 -11  428m 7624 4576 S  2.0  0.2   0:00.26 pulseaudio

OK, this time it wasn’t 30secs, but from 12:28:49 to 12:29:11 (22 seconds) it looks as it was busy doing nothing, almost.

Looking through the various other logs (do I know what I’m doing?) I found repeated variations of this message at around the same time:

pulseaudio[1065]: [pulseaudio] pid.c: Daemon already running.
rtkit-daemon[1047]: Successfully made thread 1071 of process 1071 (/usr/bin/pulseaudio) owned by 'paul' high priority at nice level -11.
rtkit-daemon[1047]: Supervising 4 threads of 2 processes of 1 users.

No, I don’t know what I’m doing! But! For the fun of it, I disabled PulseAudio Support.

Rebooted - login - logout - login - out - in - out - in … Perfect! No freeze, no delay.

OK - So what’s the connection between using PulseAudio and the G03 driver… ? :?

Thanks

HDMI audio output???

I’ll have to come back to this later… Can’t mess around too much as this is a ‘working’ PC, probably go for a new clean install when 13.1 is released.

Thanks for the advice, appreciated.

Regards, Paul