Sehr suspektes Verhalten

Bilbo · 16. November 2004

Hi Leute,

ich hab da ein sehr suspektes Verhalten vom Mplayer (und ich könnte schwören erst seit ich auf 1.3.12 umgestiegen bin, unter 1.2.6 war das nicht)

Also es ist folgendes. Ich hab diverse DivX und XviD Filme auf meinem server.
Wenn ich versuche mir die mit MPlayer anzusehen können drei Fälle eintreten:
[list=1]
[*]Der Film wird ganz normal abgespielt
[*]Mplayer startet den Film, das GLCD zeigt das entsprechende Logo aber nach ca. 2 Sekunden startet der VDR neu (kein reboot) und ich sehe danach ein Fernsehbild.
Starte ich den gleichen Film jetzt noch einmal mit MPlayer läuft er ganz normal und ich kann ihn bis zum Ende durch sehen.
[*]Auch in dem Fall startet der DVR neu aber jedes mal, wenn ich
versuche den Film zu starten.
ABER und jetzt kommt es, wenn ich zuerst einen Film zum Laufen bekomme, der zwei mal gsetartet werden will, den dann stoppe und einen der Filme starte, bei dem der VDR immer abschmiert dann kann ich mir den ansehen!
[/list=1]
Bei all dem scheint es egal zu sein, welcher Codec benutzt wird, oder welche Auflösung der Film hat oder ob er mit Analog- oder AC3 Ton ausgestattet ist.

Da ich bei der Sache absolut nicht weiter komme meine Frage an die Spezialisten, welche Logs und Config- und Versionsinfos soll ich heute Abend mal raus suchen, damit dieses Verhalten analysiert werden kann.
Im Forum hab ich so einen Fall nicht auftreiben können.

Bilbo

rockclimber · 16. November 2004

ich habe hier auch ein merkwürdiges Verhalten:

Abspielen, spulen und Pause - klappt alles wunderbar. Wenn ich aber die Wiedergabe beende, bzw. das Video zuende ist, startet vdr jedesmal neu.

hier meine config:

vdr-1.3.10
mp3-0.9.3
MPlayer-1.0pre2

benutze immer noch das 'alte' mp3-plugin, da es mit neueren Versionen auch beim mp3-Abspielen Abstürze gibt (beim direkten Anspringen von Titeln aus der playlist und beim Beenden)

kann das jemand bestätigen?

Gruß
Clemens

jha · 17. November 2004

Bilbo:

Was meinst du mit "VDR startet neu, (kein Reboot)"?
Das sich VDR beendet und vom runvdr-Script bzw init neu gestartet wird? Oder einfach nur, daß anstatt des Films wieder das Fernsehbild kommt?

Ansonsten für Bilbo und rockclimber:
Ohne Auszüge aus euren Logs kann ich nur die Kristallkugel anwerfen, aber die ist nicht sonderlich gut...

Ansonsten wäre auch noch eure Kernel-Version interessant.

Juri

rockclimber · 17. November 2004

Code

Nov 17 18:15:16 vdr logger: *** DEBUG: Variable CMDLINE has value "/usr/local/bin/mplayer -vo mpegpes -ao mpegpes -framedrop -cache 4096 -slave -nolirc -quiet"
Nov 17 18:15:32 vdr vdr[31242]: mplayer: player thread ended (pid=31242)  
Nov 17 18:15:33 vdr su: pam_unix2: session finished for user root, service su  
Nov 17 18:15:56 vdr su: (to root) root on none  
Nov 17 18:15:56 vdr su: pam_unix2: session started for user root, service su  
Nov 17 18:15:57 vdr vdr[31511]: VDR version 1.3.10 started

leider geht nicht viel aus dem log hervor.
Kernel habe ich einen 2.4'er Suse
Das Verhalten tritt bei verschiedenen Video-Formaten auf (DIVX habe ich nicht getestet, da Rechner zu lahm).

Clemens

jha · 17. November 2004

Bitte mal in der mplayer.sh.conf (ganz unten) DEBUG="true" setzen.

Gruß,
Juri

rockclimber · 17. November 2004

hab ich schon gemacht. daher auch in der ersten Zeile vom log: '*** DEBUG:'
Version mplayer.sh ist die 0.8.5

Clemens

jha · 17. November 2004

Ahh, sorry, hatte ich nicht gesehen und nur aufgrund der kurzen Angabe auf noDebug geschlossen.

Hmm, scheint wohl eher ein Problem im mplayer-/mp3-Plugin zu sein. kann ich hier nicht nachvollziehen - habe mp3-0.9.7 mit vdr-1.3.15.

Gruß,
Juri

rockclimber · 17. November 2004

ist halt schwierig, wenn gar keine Infos im log stehen.

werd mal die neuesten Versionen installieren und hoffen, dass es dann geht.

btw.,
welche Version von Mplayer setzt Du ein?
Hast Du Mplayer gepatcht?
hast Du keine Probleme wie ich beim mp3-Abspielen?

Gruß
Clemens

jha · 17. November 2004

Original von rockclimber

Zitat

welche Version von Mplayer setzt Du ein?

1.0pre5

Zitat

Hast Du Mplayer gepatcht?

Nur der Patch aus den mp3-Plugin-Sourcen.

Zitat

hast Du keine Probleme wie ich beim mp3-Abspielen?

Ich spiele so gut wie nie mp3s ab

Gruß,
Juri

pinky666 · 17. November 2004

Zitat

Original von Bilbo

[*]Der Film wird ganz normal abgespielt
[*]Mplayer startet den Film, das GLCD zeigt das entsprechende Logo aber nach ca. 2 Sekunden startet der VDR neu (kein reboot) und ich sehe danach ein Fernsehbild.
Starte ich den gleichen Film jetzt noch einmal mit MPlayer läuft er ganz normal und ich kann ihn bis zum Ende durch sehen.

same here. Allerding kein VDR Reboot sondern der mplayer geht einfach hops.
Das ganz tritt zum teil auch bei ganz normalen VDR Aufnahmen auf. Muß hier teilweise einen Film bis zu drei mal neu starten, bis er läuft. Hatte das auch schon mal gepostet. Antowrt damals: Ist normal. Denke aber das es eigentlich ein zu lösendes Problem ist.

Config bei mir:
VDR 1.3.15
MPlayer 1.0pre5-3.3.3
mplayer plugin 0.9.7
mplayer.sh 0.8.6

jha · 17. November 2004

Kernel-Version?
Log-Auszüge?

rockclimber · 17. November 2004

Zitat

Originally posted by jha

Nur der Patch aus den mp3-Plugin-Sourcen.

ich vermute mal den hier: 'mplayer-1.0pre2-slavemode.diff'
den hab ich drin

und den hier auch: 'vdr-1.3.10-nostop.diff' ?
den gab es bei der alten mp3-plugin-Version noch nicht. den werd ich mal testen.

Clemens

jha · 17. November 2004

Den vdr-1.3.10-nostop.diff habe ich nicht im VDR - VDR ist vanilla.

Gruß,
Juri

rockclimber · 17. November 2004

und ich hatte schon die Hoffnung, dass es daran liegen könnte

Clemens

Bilbo · 18. November 2004

So, hier ist mal ein log von einem Film, der zwei mal gestartet werden muss:

Zitat

Nov 15 23:40:47 (none) vdr[18940]: mplayer: mplayer child started (pid=18940)
Nov 15 23:40:47 (none) logger: *** Starting mplayer.sh Version 0.8.5
Nov 15 23:40:47 (none) vdr[18942]: mplayer: player thread started (pid=18942)
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable CFGFIL has value "/usr/local/bin/mplayer.sh.conf"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable USEAC3 has value "true"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable AC3AOUT has value "-ac hwac3"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable TV_ASPECT has value "4/3"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable PAL has value "true"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable NTSC has value "true"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable USE_SPEED has value "true"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable DETC_FILTER has value "detc=dr=2:am=1"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable MPLAYER has value "/usr/local/bin/mplayer"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable VOP has value "lavc=5000"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable VO has value "mpegpes"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable AO has value "mpegpes"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable CACHE has value "4096"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable CACHESTR has value "-cache 4096"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable FRAMEDROP has value "true"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable FDSTR has value "-framedrop"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable LIRCRC has value "/etc/lircrc.conf"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable LIRCSTR has value "-lircconf /etc/lircrc.conf"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable SUBTITLE has value " -subpos 80 -sub-bg-color 0 -sub-bg-alpha 0"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable REMOTE has value "-slave -nolirc"
Nov 15 23:40:47 (none) logger: *** Use Option USERDEF at your own risk!
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable USERDEF has value "-quiet"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable XResPAL has value "352 480 528 544 688 704 720"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable XResNTSC has value "352 480 512 640 704 720"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable SLOW_CPU has value "false"
Nov 15 23:40:47 (none) logger: *** DEBUG: *** Option DVDFiles not set correctly! You will not be able to play VCD/DVD ""
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable DVDFiles has value ""
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable DVD has value "/dev/hdc"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable DVDLANG has value "de"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable DVDOPTIONS has value "-aop list=volume:volume=170"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable VCDOPTIONS has value ""
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable MPEG_DIRECT has value "true"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable SUFFIX has value ".avi"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable MPLAYER_V1 has value "false"
Nov 15 23:40:47 (none) logger: *** DEBUG: Calling getvidxy function to analyze source video stream ...
Nov 15 23:40:47 (none) logger: *** DEBUG: OutputFromMPLAYER: "ID_FILENAME=/media/server/Das Boot/Das_Boot-Special_edition-CD2-German-DVDRiP-Divx-BnD.avi ID_VIDEO_FORMAT=DIV3 ID_VIDEO_BITRATE=785192 ID_VIDEO_WIDTH=640 ID_VIDEO_HEIGHT=368 ID_VIDEO_FPS=25.000 ID_VIDEO_ASPECT=0.0000 ID_AUDIO_CODEC=mp3 ID_AUDIO_FORMAT=85 ID_AUDIO_BITRATE=128000 ID_AUDIO_RATE=44100 ID_AUDIO_NCH=2 ID_LENGTH=6189"
Nov 15 23:40:47 (none) logger: *** DEBUG: MPLAYER_RETURN: "0"
Nov 15 23:40:47 (none) logger: *** DEBUG: parsed output for ORIG_X: "640"
Nov 15 23:40:47 (none) logger: *** DEBUG: parsed output for ORIG_Y: "368"
Nov 15 23:40:47 (none) logger: *** DEBUG: parsed output for ORIG_FPS: "25.000"
Nov 15 23:40:47 (none) logger: *** DEBUG: parsed output for ORIG_ASPECT: "0.0000"
Nov 15 23:40:47 (none) logger: *** DEBUG: parsed output for VIDEO_FORMAT: "DIV3"
Nov 15 23:40:47 (none) logger: *** DEBUG: parsed output for AUDIO_CODEC: "mp3"
Nov 15 23:40:47 (none) logger: *** INFO: Source Video has Resolution of 640 x 368 ...
Nov 15 23:40:47 (none) logger: *** DEBUG: PAL or unknown "25.000"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable MAX_X has value "768"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable NEW_Y has value "441"
Nov 15 23:40:47 (none) logger: *** INFO: For Sqare Pixels we would scale to 768 x 441 ...
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable XResTEMP has value "352 480 528 544 688 704 720"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable AnzahlVonXResTEMP has value "7"
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable NEW_X has value "688"
Nov 15 23:40:47 (none) logger: *** DEBUG: setting REAL_Y = FULL_Y ""
Nov 15 23:40:47 (none) logger: *** DEBUG: Variable CMDLINE has value "/usr/local/bin/mplayer -vo mpegpes -ao mpegpes -vop lavc=5000:25.000,expand=688:576:-1:-1:1,scale=688:441 -framedrop -cache 4096 -slave -nolirc -subpos 80 -sub-bg-color 0 -sub-bg-alpha 0 -quiet "
Nov 15 23:41:05 (none) vdr[18942]: mplayer: player thread ended (pid=18942)

Alles anzeigen

Dann der zweite Aufruf

Zitat

Nov 15 23:41:13 (none) vdr[19124]: mplayer: mplayer child started (pid=19124)
Nov 15 23:41:13 (none) logger: *** Starting mplayer.sh Version 0.8.5
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable CFGFIL has value "/usr/local/bin/mplayer.sh.conf"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable USEAC3 has value "true"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable AC3AOUT has value "-ac hwac3"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable TV_ASPECT has value "4/3"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable PAL has value "true"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable NTSC has value "true"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable USE_SPEED has value "true"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable DETC_FILTER has value "detc=dr=2:am=1"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable MPLAYER has value "/usr/local/bin/mplayer"
Nov 15 23:41:13 (none) vdr[19136]: mplayer: player thread started (pid=19136)
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable VOP has value "lavc=5000"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable VO has value "mpegpes"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable AO has value "mpegpes"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable CACHE has value "4096"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable CACHESTR has value "-cache 4096"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable FRAMEDROP has value "true"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable FDSTR has value "-framedrop"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable LIRCRC has value "/etc/lircrc.conf"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable LIRCSTR has value "-lircconf /etc/lircrc.conf"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable SUBTITLE has value " -subpos 80 -sub-bg-color 0 -sub-bg-alpha 0"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable REMOTE has value "-slave -nolirc"
Nov 15 23:41:13 (none) logger: *** Use Option USERDEF at your own risk!
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable USERDEF has value "-quiet"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable XResPAL has value "352 480 528 544 688 704 720"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable XResNTSC has value "352 480 512 640 704 720"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable SLOW_CPU has value "false"
Nov 15 23:41:13 (none) logger: *** DEBUG: *** Option DVDFiles not set correctly! You will not be able to play VCD/DVD ""
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable DVDFiles has value ""
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable DVD has value "/dev/hdc"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable DVDLANG has value "de"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable DVDOPTIONS has value "-aop list=volume:volume=170"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable VCDOPTIONS has value ""
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable MPEG_DIRECT has value "true"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable SUFFIX has value ".avi"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable MPLAYER_V1 has value "false"
Nov 15 23:41:13 (none) logger: *** DEBUG: Calling getvidxy function to analyze source video stream ...
Nov 15 23:41:13 (none) logger: *** DEBUG: OutputFromMPLAYER: "ID_FILENAME=/media/server/Das Boot/Das_Boot-Special_edition-CD2-German-DVDRiP-Divx-BnD.avi ID_VIDEO_FORMAT=DIV3 ID_VIDEO_BITRATE=785192 ID_VIDEO_WIDTH=640 ID_VIDEO_HEIGHT=368 ID_VIDEO_FPS=25.000 ID_VIDEO_ASPECT=0.0000 ID_AUDIO_CODEC=mp3 ID_AUDIO_FORMAT=85 ID_AUDIO_BITRATE=128000 ID_AUDIO_RATE=44100 ID_AUDIO_NCH=2 ID_LENGTH=6189"
Nov 15 23:41:13 (none) logger: *** DEBUG: MPLAYER_RETURN: "0"
Nov 15 23:41:13 (none) logger: *** DEBUG: parsed output for ORIG_X: "640"
Nov 15 23:41:13 (none) logger: *** DEBUG: parsed output for ORIG_Y: "368"
Nov 15 23:41:13 (none) logger: *** DEBUG: parsed output for ORIG_FPS: "25.000"
Nov 15 23:41:13 (none) logger: *** DEBUG: parsed output for ORIG_ASPECT: "0.0000"
Nov 15 23:41:13 (none) logger: *** DEBUG: parsed output for VIDEO_FORMAT: "DIV3"
Nov 15 23:41:13 (none) logger: *** DEBUG: parsed output for AUDIO_CODEC: "mp3"
Nov 15 23:41:13 (none) logger: *** INFO: Source Video has Resolution of 640 x 368 ...
Nov 15 23:41:13 (none) logger: *** DEBUG: PAL or unknown "25.000"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable MAX_X has value "768"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable NEW_Y has value "441"
Nov 15 23:41:13 (none) logger: *** INFO: For Sqare Pixels we would scale to 768 x 441 ...
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable XResTEMP has value "352 480 528 544 688 704 720"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable AnzahlVonXResTEMP has value "7"
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable NEW_X has value "688"
Nov 15 23:41:13 (none) logger: *** DEBUG: setting REAL_Y = FULL_Y ""
Nov 15 23:41:13 (none) logger: *** DEBUG: Variable CMDLINE has value "/usr/local/bin/mplayer -vo mpegpes -ao mpegpes -vop lavc=5000:25.000,expand=688:576:-1:-1:1,scale=688:441 -framedrop -cache 4096 -slave -nolirc -subpos 80 -sub-bg-color 0 -sub-bg-alpha 0 -quiet "
Nov 15 23:41:17 (none) vdr[19136]: mplayer: player thread ended (pid=19136)

Alles anzeigen

Bilbo · 18. November 2004

Ich hab jetzt wieder die Version 1.0pre3 drauf und damit scheint es richtig zu funktionieren!

Sehr suspektes Verhalten

Jetzt mitmachen!

Teilen