VDR kommt nach XBMC Exit manchmal nicht hoch (softhddevice/vdr-frontend DBus Problem)

maec21 · Dec 18th 2013

Hi,

seit meinem Update auf 2.0.3 funktioniert nach einem XBMC Exit die Aktivierung des softhddevice manchmal nicht mehr. Das Verhalten kann ich nicht forcieren, manchmal bleibt einfach Bild und Ton dunkel bzw. weg. DBus scheint irgendetwas nicht mehr laden zu können:

Code

Error org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.

Ich bin jetzt sogar auf Testing (2.0.4) gegangen, dort tritt aber das gleiche Problem auf. Das Ändern der Reihenfolge der Plugins in der order.conf hat in keiner Konstellation eine Verbeserung gebracht.

Hier meine Infos/Logs:

/var/log/upstart/vdr-frontend.log

Code

wait-for-job-state stop/waiting
wait-for-job-state stop/waiting
wait-for-job-state stop/waiting
method return sender=:1.69 -> dest=:1.72 reply_serial=2
int32 900
string "SoftHdDevice already detached"
wait-for-job-state (vdr-frontendvdr) stop/killed, process 1038
wait-for-job-state stop/waiting
wait-for-job-state stop/waiting
Error org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
wait-for-job-state stop/waiting
wait-for-job-state stop/waiting
wait-for-job-state stop/waiting

Display More

/var/log/syslog

Code

Dec 18 16:57:33 vdr vdr: [1112] executing command '/usr/share/vdr/menuorg-appswitcher standalone=yes app=xbmc &> /dev/null '
Dec 18 16:57:33 vdr kernel: [55.991608] init: vdr-frontend main process (1644) killed by TERM signal
Dec 18 16:57:33 vdr vdr: [1520][softhddev]SetPlayMode: 0
Dec 18 16:57:33 vdr vdr: [1520][softhddev]SetVideoDisplayFormat: 1 [softhddev]GetSpuDecoder:
Dec 18 16:57:33 vdr vdr: [1520][softhddev]SetPlayMode: 1
Dec 18 16:57:33 vdr vdr: video: fatal i/o error
Dec 18 16:57:33 vdr vdr: [1906] TS buffer on device 1 thread ended (pid=1112, tid=1906)
Dec 18 16:57:33 vdr vdr: [1904] buffer stats: 63168 (1%) used
Dec 18 16:57:33 vdr vdr: [1904] receiver on device 1 thread ended (pid=1112, tid=1904)
Dec 18 16:57:35 vdr kernel: [58.113996] Bluetooth: Core ver 2.16
Dec 18 16:57:35 vdr kernel: [58.114012] NET: Registered protocol family 31
Dec 18 16:57:35 vdr kernel: [58.114014] Bluetooth: HCI device and connection manager initialized
Dec 18 16:57:35 vdr kernel: [58.114016] Bluetooth: HCI socket layer initialized
Dec 18 16:57:35 vdr kernel: [58.114018] Bluetooth: L2CAP socket layer initialized
Dec 18 16:57:35 vdr kernel: [58.114022] Bluetooth: SCO socket layer initialized
Dec 18 16:57:35 vdr kernel: [58.129939] Bluetooth: RFCOMM TTY layer initialized
Dec 18 16:57:35 vdr kernel: [58.129944] Bluetooth: RFCOMM socket layer initialized
Dec 18 16:57:35 vdr kernel: [58.129945] Bluetooth: RFCOMM ver 1.11
Dec 18 16:57:39 vdr vdr: [1519] loading /var/lib/vdr/plugins/xvdr/allowed_hosts.conf
Dec 18 16:57:39 vdr vdr: [1519] XVDR: Client 127.0.0.1:9918 with ID 0 connected.
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Welcome client 'XVDR Client' with protocol version '5'
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Preferred language: deu,ger / type: 1
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Channellist filter:
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Free To Air channels: Yes
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Only native language: No
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Enabled CaIDs:
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Setting channel update method: 3
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Connected to wirbelscan plugin ...
Dec 18 16:57:39 vdr vdr: [2176] XVDR: wirbelscan plugin version: 0.0.7
Dec 18 16:57:39 vdr vdr: [2176] XVDR: Connected to wirbelscan plugin ...
Dec 18 16:57:39 vdr vdr: [2176] XVDR: wirbelscan plugin version: 0.0.7
Dec 18 16:57:49 vdr vdr: [1519] XVDR: Checking for channel updates ...
Dec 18 16:57:49 vdr vdr: [1519] XVDR: Client 0: 42 channels, no change
Dec 18 16:57:49 vdr vdr: [1519] XVDR: Done.
Dec 18 16:57:49 vdr vdr: [1519] XVDR: Client with ID 0 seems to be disconnected, removing from client list
Dec 18 16:57:51 vdr LCDd: sock_send: socket write error
Dec 18 16:57:54 vdr XBMC: XBMC exit with exit status , , ENV: TERM=linux#012PATH=/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/sbin:/sbin:/bin#012PWD=/#012JOB=xbmc#012RESULT=ok#012SHLVL=1#012HOME=/var/lib/vdr#012UPSTART_INSTANCE=#012UPSTART_EVENTS=stopped#012UPSTART_JOB=xbmc-exit#012INSTANCE=#012DISPLAY=:1#012_=/usr/bin/env.
Dec 18 16:57:54 vdr /usr/bin/signal-event.real[2214]: processing signal reset-display-rate
Dec 18 16:57:54 vdr /usr/bin/signal-event.real[2214]: processing action /usr/share/yavdr/events/reset-display-rate/10_reset-display-rate
reset-display-rate
Dec 18 16:57:55 vdr /usr/bin/signal-event.real[2214]: finished processing signal reset-display-rate
Dec 18 16:57:55 vdr vdr-frontend[2249]: acip-wakeup.time.old hatte Wert: Thu Dec 19 05:55:00 2013
Dec 18 16:57:55 vdr vdr-frontend[2249]: vergleiche mit jetzt: Wed Dec 18 15:57:55 2013
Dec 18 16:57:55 vdr vdr-frontend[2249]: assuming manual start
Dec 18 16:57:55 vdr vdr: [2207] dbus2vdr: use of deprecated interface: 'List' should be called with the interface 'de.tvdr.vdr.pluginmanager'!
Dec 18 16:57:55 vdr vdr-frontend[2249]: watching "MCE IR Keyboard/Mouse (nuvoton-cir)": /dev/input/event3
Dec 18 16:57:55 vdr vdr-frontend[2249]: started udev monitoring of input devices
Dec 18 16:57:55 vdr vdr-frontend[2249]: conntected to Lirc-Socket on /var/run/lirc/lircd
Dec 18 17:05:02 vdr CRON[3560]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) etc/cron.hourly)

Display More

/etc/vdr/plugins/order.conf

Code

firstplugin
secondplugin
*lastplugin
-thirdplugin
-fourthplugin
*anotherlastplugin
softhddevice
*dynamite
-dvbhddevice
-dvbsddevice
-pvr350
-dummydevice
-xineliboutput
lcdproc
-graphtftng
-xine
-targavfd

Display More

VDR Übersicht

Code

**************************************
* VDR Übersicht *
**************************************
VDR : 2.0.4-1yavdr0~0.5
Kernel: 3.2.0-57-generic
ABI : vdr-abi-2.0.0-yavdr1
XBMC : 2:12.2~git20130502.1706-frodo-0precise
Plugins:
( N = Native Plugin )
( D = Debian Plugin )
( - = Deaktiviert )
--------------------------------------
D burn
Package: vdr-plugin-burn
Version: 0.2.2-4yavdr10~precise
D channellists
Package: vdr-plugin-channellists
Version: 0.0.5-0yavdr2~precise
D dbus2vdr
Package: vdr-plugin-dbus2vdr
Version: 20131027103016testing-0yavdr0~precise
- dummydevice
Package: vdr-plugin-dummydevice
Version: 1.0.3-0yavdr22~precise
- dvbhddevice
Package: vdr-plugin-dvbhddevice
Version: 2.0.4-1yavdr0~0.5
D dynamite
Package: vdr-plugin-dynamite
Version: 20131027103028testing-0yavdr0~precise
D conflictcheckonly
Package: vdr-plugin-epgsearch
Version: 1.0.1.beta5~git20130911-0yavdr2~precise
D epgsearch
Package: vdr-plugin-epgsearch
Version: 1.0.1.beta5~git20130911-0yavdr2~precise
D epgsearchonly
Package: vdr-plugin-epgsearch
Version: 1.0.1.beta5~git20130911-0yavdr2~precise
D quickepgsearch
Package: vdr-plugin-epgsearch
Version: 1.0.1.beta5~git20130911-0yavdr2~precise
D extrecmenu
Package: vdr-plugin-extrecmenu
Version: 1.2.3-git20131022-0yavdr0~precise
D femon
Package: vdr-plugin-femon
Version: 2.0.0-0yavdr4~precise
D lcdproc
Package: vdr-plugin-lcdproc
Version: 0.0.10-jw9+git20130918-0yavdr1~precise
D live
Package: vdr-plugin-live
Version: 0.3.0+git20130915-3yavdr1~precise
D markad
Package: vdr-plugin-markad
Version: 0.1.4.git20131115-0yavdr0~precise
D menuorg
Package: vdr-plugin-menuorg
Version: 0.5.1-4yavdr4~precise
D restfulapi
Package: vdr-plugin-restfulapi
Version: 20131027103045testing-0yavdr0~precise
D scheduler
Package: vdr-plugin-scheduler
Version: 0.1.6-0yavdr1~precise
D softhddevice
Package: vdr-plugin-softhddevice
Version: 1
D streamdev-server
Package: vdr-plugin-streamdev-server
Version: 0.6.1.git20131129-0yavdr0~precise
D systeminfo
Package: vdr-plugin-systeminfo
Version: 0.1.2-1yavdr36~precise
D text2skin
Package: vdr-plugin-text2skin
Version: 1.3.2+git20130504-0yavdr3~precise
D undelete
Package: vdr-plugin-undelete
Version: 0.0.6-60yavdr4~precise
D vdrmanager
Package: vdr-plugin-vdrmanager
Version: 0.9-3yavdr4~precise
D wirbelscan
Package: vdr-plugin-wirbelscan
Version: 0.0.7-3yavdr18~precise
- xineliboutput
Package: vdr-plugin-xineliboutput
Version: 1.0.7+cvs20130117-1yavdr6~precise
- xine
Package: vdr-plugin-xine
Version: 0.9.4-13yavdr4~precise
D xvdr
Package: vdr-plugin-xvdr
Version: 0.9.9.git20131104-0yavdr1~precise
D yaepghd
Package: vdr-plugin-yaepghd
Version: 0.0.5pre1+git20130414-1yavdr4~precise
Addon Packages:
--------------------------------------
vdr-addon-acpiwakeup (0.0.10-2yavdr2~precise)
vdr-addon-avahi-mounter (20131206183239stable-0yavdr0~precise)
vdr-addon-lifeguard (0.0.4-3yavdr0~precise)
vdr-genindex (0.1.3-1ubuntu1)
vdr-markad (0.1.4.git20131115-0yavdr0~precise)
vdr-skin-anthra-1920-fst (20130420-0yavdr0~precise)
vdr-skin-anthra-1920-ost (20130413-1yavdr0~precise)
vdr-skins-anthra (20100904-4yavdr1~precise)
More Packages:
--------------------------------------
linux-firmware-yavdr (1.16-1yavdr0~precise)
vdrsymbols-ttf (20080905-7yavdr0~precise)
yavdr-base (20131209092033stable-0yavdr0~precise)
yavdr-i18n (20120927012007stable-0yavdr0~precise)
yavdr-plymouth-theme (20120927012042stable-0yavdr0~precise)
yavdr-remote (20130905102255stable-0yavdr0~precise)
yavdr-utils (20131211131912stable-0yavdr0~precise)
yavdr-webfrontend (20130905102307stable-0yavdr0~precise)
xbmc (2:12.2~git20130502.1706-frodo-0precise)
xbmc-addon-xvdr (0.9.8~git201304291220-7yavdr0)
xbmc-bin (2:12.2~git20130502.1706-frodo-0precise)

Display More

Grüße,
Wolfgang

Razorblade · Feb 4th 2014

Ich habe das gleiche Problem vermehrt bei einem Systemstart (nutze gar kein XBMC).

vdr-frontend startet dann andauernd durch:

Code

[ 43.935735] init: vdr-frontend main process (1700) terminated with status 1
[ 43.935835] init: vdr-frontend main process ended, respawning
[ 70.015794] init: vdr-frontend main process (1791) terminated with status 1
[ 70.015895] init: vdr-frontend main process ended, respawning
[ 96.048570] init: vdr-frontend main process (1987) terminated with status 1
[ 96.048688] init: vdr-frontend main process ended, respawning
[ 122.062597] init: vdr-frontend main process (2022) terminated with status 1
[ 122.062723] init: vdr-frontend main process ended, respawning
[ 148.095675] init: vdr-frontend main process (2216) terminated with status 1
[ 148.095801] init: vdr-frontend main process ended, respawning
[ 174.106604] init: vdr-frontend main process (2250) terminated with status 1
[ 174.106748] init: vdr-frontend main process ended, respawning
[ 200.143572] init: vdr-frontend main process (2444) terminated with status 1
[ 200.143671] init: vdr-frontend main process ended, respawning
[ 226.154506] init: vdr-frontend main process (2478) terminated with status 1
[ 226.154630] init: vdr-frontend main process ended, respawning
[ 252.182666] init: vdr-frontend main process (2672) terminated with status 1

Display More

usw

im /var/log/upstart/vdr-frontend.log steht dann:

Code

Traceback (most recent call last):
File "<stdin>", line 436, in <module>
File "<stdin>", line 180, in __init__
File "<stdin>", line 54, in manualstart
File "/usr/lib/python2.7/dist-packages/dbus/proxies.py", line 70, in __call__
return self._proxy_method(*args, **keywords)
File "/usr/lib/python2.7/dist-packages/dbus/proxies.py", line 145, in __call__
**keywords)
File "/usr/lib/python2.7/dist-packages/dbus/connection.py", line 651, in call_blocking
message, timeout)
dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
Error org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
Error org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
wait-for-job-state stop/waiting
wait-for-job-state stop/waiting
wait-for-job-state stop/waiting

Display More

vdr scheint währenddessen zu laufen (komme aufs admin-gui, dort steht "VDR backend: running").

Gibt es evtl Probleme mit dem dbus-plugin? (dbus2vdr output sieht allerdings identisch aus zwischen normalen Start und einem Fehlerhaften)

mini73 · Feb 5th 2014

Moin!

Eine Lösung kann ich leider nicht geben, aber ich hatte auch manchmal solche Zeilen im syslog:

Code

Dec 18 16:57:33 vdr vdr: video: fatal i/o error

Wenn die kamen, dann war softhddevice in einem Zustand, der nicht mehr vernünftig auflösbar war.

Wie sehen eure Einstellungen zu ClearOnSwitch bei softhddevice aus? Wenn auf 1, dann bitte mal auf 0 stellen.
Ich hatte das Gefühl, dass es dann besser bei mir läuft. Mittlerweile hab ich aber ein ganz anderes Setup, was den Wechsel zwischen softhddevice und xbmc betrifft, deshalb kann ich in die Richtung nicht wirklich testen.

Lars.

Razorblade · Feb 5th 2014

Ja ClearOnSwitch habe ich auf "1" werde ich mal auf "0" stellen und beobachten.
Diese fatal i/o error habe ich auch manchmal im Log, aber anscheinend nicht zu dem Zeitpunkt zu dem das Problem auftritt.

FJe · Feb 6th 2014

moin,

hatte mal ein dbus-Problem , das ganz andere Ursachen hatte, schaut mal auf Euer .dbus-Dir, ob da alles korrekt ist.

bye
frank

Razorblade · Feb 6th 2014

Hmm, ich hatte wohl mit ClearOnSwitch mal rumgespielt, aber es war jetzt (auch während das Problem schon mehrfach auftrat) bereits auf "0" gesetzt, daran liegt es also nicht

Wegen dbus, unter yavdr liegt das vdr user home in /var/lib/vdr
Dort existiert gar kein .dbus ... sollte es?

mini73 · Feb 6th 2014

Nein, das hat nur was mit dem Sessionbus zu tun, der ist bei yavdr nicht aktiv.

Lars

Razorblade · Feb 10th 2014

Noch Vorschläge was ich beim nächsten mal nachgucken/probieren soll wenn es auftritt?

Bis jetzt behlfe ich mir mit einem "service vdr restart" (service vdr-frontend restart hilft nicht!)

mini73 · Feb 10th 2014

Die Ausgaben von softhddevice im syslog sind interessant und evtl. noch /var/log/upstart/vdr-frontend.log.
Im letzteren sind leider keine Zeitstempel, weshalb die Zuordnung da etwas schwierig ist.

Einfaches Kanalwechseln hilft auch nicht?
Kommt bei "Ok" die Kanalinfo? Geht das vdr-OSD in irgend einer Form?

Lars.

Razorblade · Feb 10th 2014

im vdr-frontend.log stand nur die DBus Meldung, die ich schon gepostet hatte. Es kommt gar nicht erst ein vdr OSD, es wird nichtmal ein Hintergrundbild im X angezeigt, einfach nur schwarz.
Laut Prozessliste scheinen aber sowohl X als auch vdr zu laufen.

X.log und syslog waren auch unauffällig, werde aber beim nächsten mal wenns passiert nochmal genauer nachschauen.

VDR kommt nach XBMC Exit manchmal nicht hoch (softhddevice/vdr-frontend DBus Problem)

Share

Tags