Hi,
wir haben hier seit ein paar Wochen das Problem, dass das Detachen/Attachen des softhddevice mit der Fernbedienung nicht zuverlässig funktioniert.
Mit einer Ur-yavdr-Installation 0.5 ging das mehrere Monate sehr zuverlässig - und spart zwischen 7-10W
Folgene Phänomene treten dabei auf:
- VDR reagiert gar nicht mehr auf Fernbedienung -
- VDR reagiert noch auf den ersten FB Befehl, Bild kommt zurück, aber danach weder Ton noch weitere Reaktion auf FB
- VDR reagiert auf ersten FB-Befehl im ersten Moment nicht wie erwartet, falsches Menü geöffnet (selten)
Syslog - so sieht es aus, wenn "DETA" noch funktioniert, danach aber die FB gar nichts mehr bewirkt (kein "ATTA" o.ä.): :
Apr 29 16:02:38 vdr vdr: [25415] dbus2vdr: new message, object /Plugins/softhddevice, interface de.tvdr.vdr.plugin, member SVDRPCommand
Apr 29 16:02:38 vdr vdr: [25415] dbus2vdr: 1 idle message handler, reusing 0x7f3ee8001be0
Apr 29 16:02:38 vdr vdr: [30931] dbus2vdr message handler thread started (pid=25327, tid=30931, prio=high)
Apr 29 16:02:38 vdr vdr: [30931] dbus2vdr: invoking softhddevice.SVDRPCommand("STAT", "None")
Apr 29 16:02:38 vdr vdr: [30931] dbus2vdr: moving message handler 0x7f3ee8001be0 from active to finished
Apr 29 16:02:38 vdr vdr: [30931] dbus2vdr message handler thread ended (pid=25327, tid=30931)
Apr 29 16:02:38 vdr vdr: [25415] dbus2vdr: connection has messages to send, flushing
Apr 29 16:02:38 vdr vdr: [25415] dbus2vdr: done flushing
Apr 29 16:02:38 vdr vdr: [30933] Text2Skin: menu display update thread started (pid=25327, tid=30933, prio=high)
Apr 29 16:02:38 vdr vdr: [25327] loading menuorg config file from /var/lib/vdr/plugins/menuorg.xml
Apr 29 16:02:38 vdr vdr: [25415] dbus2vdr: new message, object /Plugins/softhddevice, interface de.tvdr.vdr.plugin, member SVDRPCommand
Apr 29 16:02:38 vdr vdr: [25415] dbus2vdr: 1 idle message handler, reusing 0x7f3ee8001be0
Apr 29 16:02:38 vdr vdr: video: speed up video, droping frame
Apr 29 16:02:38 vdr vdr: video: 11:29:09.174 -15 303 0/\ms 5+5 v-buf
Apr 29 16:02:38 vdr vdr: [30934] dbus2vdr message handler thread started (pid=25327, tid=30934, prio=high)
Apr 29 16:02:38 vdr vdr: [30934] dbus2vdr: invoking softhddevice.SVDRPCommand("DETA", "None")
Apr 29 16:02:38 vdr vdr: [30934] [softhddev]SetPlayMode: 0
Apr 29 16:02:38 vdr vdr: [30934] [softhddev]SetVideoDisplayFormat: 1
Apr 29 16:02:38 vdr vdr: [30934] [softhddev]GetSpuDecoder:
Apr 29 16:02:38 vdr vdr: [30934] [softhddev]SetPlayMode: 1
Apr 29 16:02:38 vdr vdr: video: fatal i/o error
Apr 29 16:04:49 vdr vdr: [25327] [softhddev]SetVolumeDevice: 80
Apr 29 16:04:49 vdr vdr: [25327] [softhddev]SetVolumeDevice: 0
Apr 29 16:04:50 vdr vdr: [25327] [softhddev]SetVolumeDevice: 80
Apr 29 16:08:00 vdr vdr: [25405] channel 1 (Das Erste) event Mon 29.04.2013 16:00-16:10 (VPS: 29.04. 16:00) 'Tagesschau' status 1
Apr 29 16:08:01 vdr vdr: [25405] channel 1 (Das Erste) event Mon 29.04.2013 16:10-17:00 (VPS: 29.04. 16:10) 'Leopard, Seebär & Co. (138)' status 2
Apr 29 16:08:59 vdr vdr: [25405] channel 1 (Das Erste) event Mon 29.04.2013 16:10-17:00 (VPS: 29.04. 16:10) 'Leopard, Seebär & Co. (138)' status 4
Alles anzeigen
Das sind die logs, wenn es funktioniert wie es soll:
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: new message, object /Plugins/softhddevice, interface de.tvdr.vdr.plugin, member SVDRPCommand
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: 1 idle message handler, reusing 0x7f8184004ac0
Apr 29 16:23:17 vdr vdr: [31733] dbus2vdr message handler thread started (pid=31269, tid=31733, prio=high)
Apr 29 16:23:17 vdr vdr: [31733] dbus2vdr: invoking softhddevice.SVDRPCommand("STAT", "None")
Apr 29 16:23:17 vdr vdr: [31733] dbus2vdr: moving message handler 0x7f8184004ac0 from active to finished
Apr 29 16:23:17 vdr vdr: [31733] dbus2vdr message handler thread ended (pid=31269, tid=31733)
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: connection has messages to send, flushing
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: done flushing
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: new message, object /Plugins/softhddevice, interface de.tvdr.vdr.plugin, member SVDRPCommand
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: 1 idle message handler, reusing 0x7f8184004ac0
Apr 29 16:23:17 vdr vdr: [31735] dbus2vdr message handler thread started (pid=31269, tid=31735, prio=high)
Apr 29 16:23:17 vdr vdr: [31735] dbus2vdr: invoking softhddevice.SVDRPCommand("ATTA", "-d :1.0")
Apr 29 16:23:17 vdr vdr: [31735] [softhddev]SetPlayMode: 0
Apr 29 16:23:17 vdr vdr: [31735] [softhddev]SetVideoDisplayFormat: 1
Apr 29 16:23:17 vdr vdr: [31735] [softhddev]GetSpuDecoder:
Apr 29 16:23:17 vdr vdr: [31735] [softhddev]~cSoftHdControl: dummy player stopped
Apr 29 16:23:17 vdr vdr: video/vdpau: VDPAU API version: 1
Apr 29 16:23:17 vdr vdr: video/vdpau: VDPAU information: NVIDIA VDPAU Driver Shared Library 304.88 Wed Mar 27 14:49:27 PDT 2013
Apr 29 16:23:17 vdr vdr: video/vdpau: highest supported high quality scaling 1
Apr 29 16:23:17 vdr vdr: video/vdpau: feature deinterlace temporal supported
Apr 29 16:23:17 vdr vdr: video/vdpau: feature deinterlace temporal spatial supported
Apr 29 16:23:17 vdr vdr: video/vdpau: attribute skip chroma deinterlace supported
Apr 29 16:23:17 vdr vdr: video/vdpau: 4:2:0 chroma format with 4096x4096 supported
Apr 29 16:23:17 vdr vdr: video/vdpau: 4:2:2 chroma format with 4096x4096 supported
Apr 29 16:23:17 vdr vdr: video/vdpau: 8bit BGRA format with 16384x16384 supported
Apr 29 16:23:17 vdr vdr: video/vdpau: 10bit RGBA format with 16384x16384 supported
Apr 29 16:23:17 vdr vdr: audio: 'alsa' output module used
Apr 29 16:23:17 vdr vdr: audio/alsa: supports pause: no
Apr 29 16:23:17 vdr vdr: audio: 44100Hz supports 1 2 3 4 5 6 7 8 channels
Apr 29 16:23:17 vdr vdr: audio: 48000Hz supports 1 2 3 4 5 6 7 8 channels
Apr 29 16:23:17 vdr vdr: audio: 192000Hz supports 0 0 0 0 0 0 0 0 channels
Apr 29 16:23:17 vdr vdr: [31735] dbus2vdr: moving message handler 0x7f8184004ac0 from active to finished
Apr 29 16:23:17 vdr vdr: [31735] dbus2vdr message handler thread ended (pid=31269, tid=31735)
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: connection has messages to send, flushing
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: done flushing
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: new message, object /Remote, interface de.tvdr.vdr.remote, member Enable
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: 1 idle message handler, reusing 0x7f8184004ac0
Apr 29 16:23:17 vdr vdr: [31737] dbus2vdr message handler thread started (pid=31269, tid=31737, prio=high)
Apr 29 16:23:17 vdr vdr: [31737] dbus2vdr: moving message handler 0x7f8184004ac0 from active to finished
Apr 29 16:23:17 vdr vdr: [31737] dbus2vdr message handler thread ended (pid=31269, tid=31737)
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: connection has messages to send, flushing
Apr 29 16:23:17 vdr vdr: [31357] dbus2vdr: done flushing
Apr 29 16:23:18 vdr vdr: [31269] switching to channel 1
Apr 29 16:23:18 vdr vdr: [31715] osdteletext-receiver thread ended (pid=31269, tid=31715)
Apr 29 16:23:18 vdr vdr: [31269] buffer stats: 0 (0%) used
Apr 29 16:23:18 vdr vdr: [31716] TS buffer on device 1 thread ended (pid=31269, tid=31716)
Apr 29 16:23:18 vdr vdr: [31714] buffer stats: 139308 (2%) used
Apr 29 16:23:18 vdr vdr: [31714] receiver on device 1 thread ended (pid=31269, tid=31714)
Apr 29 16:23:18 vdr vdr: [31738] receiver on device 1 thread started (pid=31269, tid=31738, prio=high)
Apr 29 16:23:18 vdr vdr: [31740] osdteletext-receiver thread started (pid=31269, tid=31740, prio=high)
Apr 29 16:23:18 vdr vdr: [31739] TS buffer on device 1 thread started (pid=31269, tid=31739, prio=high)
Apr 29 16:23:19 vdr vdr: [31269] [softhddev]SetPlayMode: 1
Apr 29 16:23:19 vdr vdr: audio/alsa: broken driver -5 state 'OPEN'
Apr 29 16:23:19 vdr vdr: [softhddev] invalid PES video packet
Apr 29 16:23:19 vdr vdr: [softhddev] invalid PES video packet
Apr 29 16:23:19 vdr vdr: codec: buggy libav, use ffmpeg
Apr 29 16:23:20 vdr vdr: audio/alsa: broken driver -5 state 'OPEN'
Apr 29 16:23:27 vdr: last message repeated 63 times
Apr 29 16:23:27 vdr vdr: [31738] [softhddev]Clear:
Apr 29 16:23:27 vdr vdr: audio/alsa: using device 'default'
Apr 29 16:23:27 vdr vdr: [31738] ERROR: TS packet not accepted in Transfer Mode
Apr 29 16:23:27 vdr vdr: audio/alsa: start delay 336ms
Apr 29 16:23:27 vdr vdr: video: slow down video, duping frame
Apr 29 16:23:27 vdr vdr: video: decoder buffer empty, duping frame (1/282) 0 v-buf
Apr 29 16:23:27 vdr vdr: video: --:--:--.--- +0 0 0/\ms 0+5 v-buf
Apr 29 16:23:27 vdr vdr: video: decoder buffer empty, duping frame (13/282) 3 v-buf
Apr 29 16:23:27 vdr vdr: video: slow down video, duping frame
Apr 29 16:23:27 vdr vdr: video: 11:49:58.294 +105 312 0/\ms 3+7 v-buf
Apr 29 16:23:52 vdr vdr: [31357] dbus2vdr: new message, object /Plugins/softhddevice, interface de.tvdr.vdr.plugin, member SVDRPCommand
Apr 29 16:23:52 vdr vdr: [31357] dbus2vdr: 1 idle message handler, reusing 0x7f8184004ac0
Apr 29 16:23:52 vdr vdr: [31741] dbus2vdr message handler thread started (pid=31269, tid=31741, prio=high)
Apr 29 16:23:52 vdr vdr: [31741] dbus2vdr: invoking softhddevice.SVDRPCommand("STAT", "None")
Apr 29 16:23:52 vdr vdr: [31741] dbus2vdr: moving message handler 0x7f8184004ac0 from active to finished
Apr 29 16:23:52 vdr vdr: [31741] dbus2vdr message handler thread ended (pid=31269, tid=31741)
Apr 29 16:23:52 vdr vdr: [31720] Text2Skin: menu display update thread ended (pid=31269, tid=31720)
Apr 29 16:23:52 vdr vdr: [31357] dbus2vdr: connection has messages to send, flushing
Apr 29 16:23:52 vdr vdr: [31357] dbus2vdr: done flushing
Apr 29 16:23:52 vdr vdr: [31357] dbus2vdr: new message, object /Plugins/softhddevice, interface de.tvdr.vdr.plugin, member SVDRPCommand
Apr 29 16:23:52 vdr vdr: [31357] dbus2vdr: 1 idle message handler, reusing 0x7f8184004ac0
Apr 29 16:23:52 vdr vdr: [31743] dbus2vdr message handler thread started (pid=31269, tid=31743, prio=high)
Apr 29 16:23:52 vdr vdr: [31743] dbus2vdr: invoking softhddevice.SVDRPCommand("DETA", "None")
Apr 29 16:23:52 vdr vdr: [31743] [softhddev]SetPlayMode: 0
Apr 29 16:23:52 vdr vdr: [31743] [softhddev]SetVideoDisplayFormat: 1
Apr 29 16:23:52 vdr vdr: [31743] [softhddev]GetSpuDecoder:
Apr 29 16:23:52 vdr vdr: [31743] [softhddev]SetPlayMode: 1
Apr 29 16:23:53 vdr vdr: [31743] dbus2vdr: moving message handler 0x7f8184004ac0 from active to finished
Apr 29 16:23:53 vdr vdr: [31743] dbus2vdr message handler thread ended (pid=31269, tid=31743)
Apr 29 16:23:53 vdr vdr: [31357] dbus2vdr: connection has messages to send, flushing
Apr 29 16:23:53 vdr vdr: [31357] dbus2vdr: done flushing
Apr 29 16:23:53 vdr vdr: [31357] dbus2vdr: new message, object /Remote, interface de.tvdr.vdr.remote, member Disable
Apr 29 16:23:53 vdr vdr: [31357] dbus2vdr: 1 idle message handler, reusing 0x7f8184004ac0
Apr 29 16:23:53 vdr vdr: [31744] dbus2vdr message handler thread started (pid=31269, tid=31744, prio=high)
Apr 29 16:23:53 vdr vdr: [31744] dbus2vdr: moving message handler 0x7f8184004ac0 from active to finished
Apr 29 16:23:53 vdr vdr: [31744] dbus2vdr message handler thread ended (pid=31269, tid=31744)
Apr 29 16:23:53 vdr vdr: [31357] dbus2vdr: connection has messages to send, flushing
Apr 29 16:23:53 vdr vdr: [31357] dbus2vdr: done flushing
Alles anzeigen
Man kann nach dem VDR restart normalerweise mehrere Male attachen und detachen, ohne dass sich das Verhalten provozieren lässt.
Nach ein paar Stunden ist dann aber die FB nicht mehr als Trigger zu benutzen. Irgendwo ist da der Wurm drin.
Irgendeine Idee, wo sich ansetzen ließe?
vg, aragorn