Hohe Cpu Last

Sisko78 · 29. März 2007

Hallo,

nach einem Update von Debian stable auf etch habe ich so meine Probleme mit dem VDR, die CPU arbeitet dauerhaft am Limit, wenn der VDR läuft. Die VDR Pakete sind von Tobi.
Folgenndes irritiert mich ein wenig:

strace -p 14449

Code

Process 14449 attached - interrupt to quit
clock_gettime(CLOCK_REALTIME, {1175124152, 831806798}) = 0
futex(0x81dcca4, FUTEX_WAIT, 1123, {0, 370838202}) = -1 ETIMEDOUT (Connection timed out)
gettimeofday({1175124153, 207093}, NULL) = 0
futex(0x81dcc78, FUTEX_WAKE, 1)         = 0
time(NULL)                              = 1175124153
time(NULL)                              = 1175124153
time(NULL)                              = 1175124153
time(NULL)                              = 1175124153
time(NULL)                              = 1175124153
alarm(60)                               = 59
time(NULL)                              = 1175124153
time(NULL)                              = 1175124153
time(NULL)                              = 1175124153
time(NULL)                              = 1175124153
time(NULL)                              = 1175124153
stat64("/diskB/.update", 0xbff97dc0)    = -1 ENOENT (No such file or directory)
time(NULL)                              = 1175124153
gettid()                                = 14449
time(NULL)                              = 1175124153
accept(14, 0xbff95e48, [16])            = -1 EAGAIN (Resource temporarily unavailable)
gettimeofday({1175124153, 211562}, NULL) = 0

Alles anzeigen

usw. immer wieder das Gleiche
Ansonsten kann ich nichts ungewöhnliches feststellen. Wäre super, wenn mir jemand weiterhelfen könnte.

Gruß
Sisko

Sisko78 · 29. März 2007

und hier noch 10 Minuten aus /var/log/debug:

Code

Mar 29 01:42:55 voyager vdr: [14597] reading EPG data from /var/cache/vdr/epg.data
Mar 29 01:42:55 voyager vdr: [14598] video directory scanner thread started (pid=14597, tid=14598)
Mar 29 01:42:55 voyager vdr: [14599] video directory scanner thread started (pid=14597, tid=14599)
Mar 29 01:42:57 voyager vdr: [14597] probing /dev/dvb/adapter0/frontend0
Mar 29 01:42:57 voyager vdr: [14597] probing /dev/dvb/adapter1/frontend0
Mar 29 01:42:57 voyager vdr: [14601] tuner on device 1 thread started (pid=14597, tid=14601)
Mar 29 01:42:57 voyager vdr: [14602] section handler thread started (pid=14597, tid=14602)
Mar 29 01:42:57 voyager vdr: [14603] streamdev server thread started (pid=14597, tid=14603)
Mar 29 01:42:57 voyager vdr: [14597] setting watchdog timer to 60 seconds
Mar 29 01:43:00 voyager vdr: [14602] changing portal name of channel 49 from '' to 'Portal'
Mar 29 01:43:00 voyager vdr: [14602] changing name of channel 109 from 'Euroliga ENG,;' to 'Euroliga ITA,;'
Mar 29 01:43:00 voyager vdr: [14602] linking channel 49 from none to 108 109
Mar 29 01:43:07 voyager vdr: [14597] max. latency time 1 seconds
Mar 29 01:45:21 voyager vdr: [14602] changing pids of channel 20 from 1279+1279:1280=deu,1281=deu:32 to 1279+1279:1280=deu:32
Mar 29 01:46:27 voyager vdr: [14602] changing pids of channel 125 from 901+901:902:204 to 701+701:702:204
Mar 29 01:46:35 voyager vdr: [14602] changing pids of channel 125 from 701+701:702:204 to 901+901:902:204
Mar 29 01:47:49 voyager vdr: [14602] changing pids of channel 26 from 1791+1791:1792=deu,1793=deu:32 to 1791+1791:1792=deu:32
Mar 29 01:47:50 voyager vdr: [14602] changing portal name of channel 17 from '' to 'Direkt Portal'
Mar 29 01:47:50 voyager vdr: [14602] changing name of channel 105 from 'D 8 - 20:30,;' to 'D 1 - 00:45,;'
Mar 29 01:47:50 voyager vdr: [14602] changing name of channel 98 from 'D 1 - 23:15,;' to 'D 1 - 02:00,;'
Mar 29 01:47:50 voyager vdr: [14602] changing name of channel 99 from 'D 2 - 23:00,;' to 'D 2 - 01:30,;'
Mar 29 01:47:50 voyager vdr: [14602] changing name of channel 107 from 'D 5 - 23:00,;' to 'D 5 - 01:30,;'
Mar 29 01:47:50 voyager vdr: [14602] linking channel 17 from none to 97 105 98 99 101 107
Mar 29 01:48:00 voyager vdr: [14602] changing portal name of channel 131 from '' to 'Info Tafel'
Mar 29 01:48:00 voyager vdr: [14602] linking channel 131 from none to 407 408 409 410 411
Mar 29 01:49:54 voyager vdr: [14602] changing name of channel 499 from 'NDR 90,3;ARD NDR' to 'NDR 90,3,;ARD NDR'
Mar 29 01:49:54 voyager vdr: [14602] changing name of channel 510 from 'radioBERLIN 88,8;ARD rbb' to 'radioBERLIN 88,8,;ARD rbb'

Alles anzeigen

schmirl · 29. März 2007

Könnte von Streamdev verursacht worden sein: streamdev mit 99% automatisch killen [help wanted]

Sisko78 · 29. März 2007

Danke, werde ich mal überprüfen

Egalus · 29. März 2007

Ich nehme an, dass der Bug immer noch nicht aus streamdev raus ist.
Jede Verbindung per Streamdev, die nicht ordentlich beendet wird, sondern - durch welchen Grund auch immer - abbricht führt dazu, dass der streamdevserver auf ewig versucht dem nicht mehr existenten Client Daten zu schicken - und dabei landet er dauerhauft bei 100% CPU-Last (auf 1 CPU Systemen).

Eigentlich müsste in den Code nur rein, dass bei Übertragungsfehlern nach einem Timeout einfach aufgehört wird Daten an diesen Client zu schicken und alles wäre gut.

schmirl · 30. März 2007

Zitat

Eigentlich müsste in den Code nur rein, dass bei Übertragungsfehlern nach einem Timeout einfach aufgehört wird Daten an diesen Client zu schicken und alles wäre gut.

Genau das macht der Patch aus dem oben genannten Thread ;). Wenn bis Montag keiner sein Veto einlegt wird der Patch eingecheckt - Tester willkommen...

Sisko78 · 30. März 2007

Bei mir tritt das Problem aber schon auf, wenn ich den Server starte ohne überhaupt schojn eine Client Verbindung aufgebaut zu haben

Sisko78 · 30. März 2007

Nachdem ich die Plugins in der order.conf mal deaktiviert habe, habe ich immer noch das gleiche Problem. Hat wohl nichts mit den Plugins zu tun.

Egalus · 30. März 2007

Zitat

Genau das macht der Patch aus dem oben genannten Thread ;). Wenn bis Montag keiner sein Veto einlegt wird der Patch eingecheckt - Tester willkommen...

Oh, da hab ich wohl was verpasst.
Zu dumm nur, dass ich momentan nur einen Debian-VDR Server laufen habe und am WE auch keine Zeit da ist um vdr per sourcen zu installieren um den Patch zu testen. Aber ich freu mich drauf wenn es endlich in ein Release einzug hält und ein Hard-Power-Off des VDR-Streamdev-Clients meiner Frau nicht gleich die Stromrechnung hochtreibt

Sisko78 · 4. April 2007

Leider konnte ich das Problem noch nicht lösen, niemand ne Idee, woran das liegen könnte?

SHF · 4. April 2007

Bist du sicher, dass die CPU-Last von einem der VDR-Prozesse kommt?

Zitat

Nachdem ich die Plugins in der order.conf mal deaktiviert habe, habe ich immer noch das gleiche Problem. Hat wohl nichts mit den Plugins zu tun.

Hast du alle Plugins deaktiviert?

Sisko78 · 5. April 2007

So sieht das die meiste Zeit aus:

Code

PID USER     PR NI VIRT  RES SHR S %CPU %MEM  TIME+ COMMAND
5085 root      15   0 84380  27m 1744 S 93.4  2.7   4:23.01 vdr

Ein strace bringt die oben erwähnten clock/time geschichten (kenne mich da nicht aus)

Ich habe den vdr jetzt testweise mal mit so wenigen Optionen wie möglich gestartet also auch ohne Plugins:

root 5085 1 84 00:04 ? 00:06:51 /usr/bin/vdr -c /var/lib/vdr -E /var/lib/vdr/epg.data -v /diskB -d

So sieht der Start in /var/log/syslog aus:
(Die unbekannten Konfigurationsparameter habe ich eben erst bemerkt, muss ich mich mal mit beschäftigen, aber mit dem wirds nichts zu tun haben.)

Code

Apr  5 00:23:36 voyager vdr: [5273] VDR version 1.4.6 started
Apr  5 00:23:36 voyager vdr: [5273] running as daemon (tid=5273)
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/setup.conf
Apr  5 00:23:36 voyager vdr: [5273] ERROR: unknown config parameter: D
vdDisplayMode = 0
Apr  5 00:23:36 voyager vdr: [5273] ERROR: unknown config parameter: D
vdDisplayZeros = 1
Apr  5 00:23:36 voyager vdr: [5273] ERROR: unknown config parameter: D
vdSpeedLimit = 0
Apr  5 00:23:36 voyager vdr: [5273] ERROR: unknown config parameter: D                                 vdTrayMode = 0
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/sources.conf
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/diseqc.conf
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/channels.conf
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/timers.conf
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/commands.conf
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/reccmds.conf
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/svdrphosts.conf
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/remote.conf
Apr  5 00:23:36 voyager vdr: [5273] loading /var/lib/vdr/keymacros.conf
Apr  5 00:23:36 voyager vdr: [5273] reading EPG data from /var/lib/vdr                                 /epg.data
Apr  5 00:23:36 voyager vdr: [5274] video directory scanner thread sta                                 rted (pid=5273, tid=5274)
Apr  5 00:23:36 voyager vdr: [5275] video directory scanner thread sta                                 rted (pid=5273, tid=5275)
Apr  5 00:23:42 voyager vdr: [5273] probing /dev/dvb/adapter0/frontend0
Apr  5 00:23:42 voyager vdr: [5273] probing /dev/dvb/adapter1/frontend0
Apr  5 00:23:42 voyager vdr: [5273] found 1 video device
Apr  5 00:23:42 voyager vdr: [5273] ERROR: invalid primary device number: 2
Apr  5 00:23:42 voyager vdr: [5273] ERROR: no primary device found - using first device!
Apr  5 00:23:42 voyager vdr: [5273] setting primary device to 1
Apr  5 00:23:42 voyager vdr: [5273] setting current skin to "sttng"
Apr  5 00:23:42 voyager vdr: [5273] loading /var/lib/vdr/themes/sttng-default.theme
Apr  5 00:23:42 voyager vdr: [5277] tuner on device 1 thread started (pid=5273, tid=5277)
Apr  5 00:23:42 voyager vdr: [5278] section handler thread started (pid=5273, tid=5278)
Apr  5 00:23:42 voyager vdr: [5273] ERROR: /dev/lircd: Datei oder Verzeichnis nicht gefunden
Apr  5 00:23:42 voyager vdr: [5273] ERROR: remote control LIRC not ready!
Apr  5 00:23:42 voyager vdr: [5273] switching to channel 1
Apr  5 00:23:42 voyager vdr: [5273] ERROR: no OSD provider available - using dummy OSD!
Apr  5 00:23:46 voyager vdr: [5278] changing portal name of channel 49 from '' to 'Portal'
Apr  5 00:23:46 voyager vdr: [5278] linking channel 49 from none to 108 109

Alles anzeigen

Ich habe noch einen 1.4.3 auf der Kiste gefunden, da schien die CPU Last in Ordnung zu sein.

Sollte ich mal auf Kernel/Treiberebene weiterforschen?
Es ist ein 2.6.18er Kernel mit den dort enthaltenen Treibern für meine Skystar 2 PCI (2.6d)

Ich bin auch schon von den Binärpaketen weg und habe es selbst kompiliert (2 verschiedene 1.4.6er)

Gruß
Sisko

SHF · 5. April 2007

Zitat

[...] meine Skystar 2 PCI (2.6d)

Ist das die einzige Karte im Sytem?

Code

Apr  5 00:23:42 voyager vdr: [5273] probing /dev/dvb/adapter0/frontend0
Apr  5 00:23:42 voyager vdr: [5273] probing /dev/dvb/adapter1/frontend0
Apr  5 00:23:42 voyager vdr: [5273] found 1 video device
Apr  5 00:23:42 voyager vdr: [5273] ERROR: invalid primary device number: 2
Apr  5 00:23:42 voyager vdr: [5273] ERROR: no primary device found - using first device!

Er scheint nur eine Karte zu finden und die ist anscheinend auch keine FF.

Wenn du eine FF-Karte im Rechner hast wird es wohl irgendwo an deren Treiber hängen.
Wenn du ein Budget-only-System hast währen da ein paar mehr Details (z.B. womit dekodiert werden soll) nicht vekert.

goldfisch · 6. April 2007

Hallo sisko78,

ich hatte einmal ein ähnliches Problem. Es lag daran, dass im video-Verzeichnis (/var/lib/video.00) verschiedene Dateien eines Backups lagen, mit denen vdr nichts anfangen konnte.

Nach dem Entfernen der Dateien war die CPU-Last wieder normal.

Gruß

Gerhard

Sisko78 · 6. April 2007

Super, das wars. Hatte aus Platzgründen einen Teil der Video Partition mit anderen Daten belegt. Vielen Dank goldfisch. Jetzt kann ich den VDR ruhigen Gewissens laufen lassen.

Weil die Frage aufkam: da ist nur eine budget drin, es läuft das xineliboutput Plugin, Clientseitig benutze ich xine mit libxine-xvdr

Gruß
Sisko

Hohe Cpu Last

Jetzt mitmachen!

Teilen

Benutzer online in diesem Thema