Invalid Lock Sequence Report mit LCARS-Skin bei fehlgeschlagenen Kanalwechsel

seahawk1986 · 7. Juli 2020

Hallo,

ich habe die yavdr-ansible Pakete auf einem Raspberry Pi 2B mit Ubuntu Server 20.04 und VDR 2.4.3 ausprobiert und bin dabei über folgende Meldung gestolpert, als ich den Kanal wechseln wollte (der VDR konnte den beim Start gewählten Sender nicht tunen) und die Streamdev-Verbindung zum Server in ein Timeout gelaufen ist:

Code

Jul 07 11:07:27 ubuntu vdr[1395]: [1395] assuming manual start of VDR
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] setting current skin to "lcars"
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] loading /var/lib/vdr/themes/lcars-default.theme
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] starting plugin: dbus2vdr
Jul 07 11:07:27 ubuntu vdr[1395]: [1521] dbus2vdr: mainloop started
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] starting plugin: devstatus
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] starting plugin: rpihddevice
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] rpihddevice: display PAR=1.000, setting video render PAR=1/1
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] starting plugin: streamdev-client
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] remote control LIRC - keys known
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] remote control KBD - keys known
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] loading /var/cache/vdr/cam.data
Jul 07 11:07:27 ubuntu vdr[1395]: [1395] switching to channel 1 C-1-1051-11100 (Das Erste HD)
Jul 07 11:07:27 ubuntu vdr[1395]: [1526] KBD remote control thread started (pid=1395, tid=1526, prio=high)
Jul 07 11:07:27 ubuntu vdr[1395]: [1525] LIRC remote control thread started (pid=1395, tid=1525, prio=high)
Jul 07 11:07:27 ubuntu vdr[1395]: [1521] dbus2vdr: System: connected with unique name :1.18
Jul 07 11:07:27 ubuntu vdr[1395]: [1521] dbus2vdr: thread-pool for handling signal-emits started
Jul 07 11:07:29 ubuntu vdr[1395]: [1395] ERROR: streamdev-client: Couldn't connect to 192.168.1.104:2004: Connection timed out
Jul 07 11:07:29 ubuntu vdr[1395]: [1395] info: Channel not available!
Jul 07 11:07:29 ubuntu vdr[1395]: [1519] rpihddevice: loading /usr/share/fonts/truetype/dejavu/DejaVuSans.ttf ...
Jul 07 11:07:31 ubuntu systemd[1]: Started Video Disk Recorder.
Jul 07 11:07:31 ubuntu vdr[1395]: [1579] SVDRP server handler thread started (pid=1395, tid=1579, prio=low)
Jul 07 11:07:31 ubuntu vdr[1395]: [1579] SVDRP ubuntu opening port 6419/tcp
Jul 07 11:07:31 ubuntu vdr[1395]: [1579] SVDRP ubuntu listening on port 6419/tcp
Jul 07 11:07:31 ubuntu vdr[1395]: [1395] switching to channel 1 C-1-1051-11100 (Das Erste HD)
Jul 07 11:07:33 ubuntu vdr[1395]: [1395] info: Channel not available!
Jul 07 11:07:35 ubuntu vdr[1395]: [1395] OSD size changed to 1280x1024 @ 1
Jul 07 11:07:35 ubuntu vdr[1395]: [1519] rpihddevice: loading /usr/share/fonts/truetype/dejavu/DejaVuSans-Bold.ttf ...
Jul 07 11:07:35 ubuntu vdr[1395]: [1521] dbus2vdr: thread-pool for handling method-calls started
Jul 07 11:07:36 ubuntu vdr[1395]: [1505] video directory scanner thread ended (pid=1395, tid=1505)
Jul 07 11:07:42 ubuntu vdr[1395]: [1395] switching to channel 1 C-1-1051-11100 (Das Erste HD)
Jul 07 11:07:44 ubuntu vdr[1395]: [1395] ERROR: streamdev-client: Couldn't connect to 192.168.1.104:2004: Connection timed out
Jul 07 11:07:44 ubuntu vdr[1395]: [1395] info: Channel not available!
Jul 07 11:07:53 ubuntu vdr[1395]: [1395] switching to channel 1 C-1-1051-11100 (Das Erste HD)
Jul 07 11:07:55 ubuntu vdr[1395]: [1395] ERROR: streamdev-client: Couldn't connect to 192.168.1.104:2004: Connection timed out
Jul 07 11:07:55 ubuntu vdr[1395]: [1395] info: Channel not available!
Jul 07 11:07:55 ubuntu vdr[1395]: [1395] --- begin invalid lock sequence report
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  R  -  -  -  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  U  -  -  -  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  W  -  -  -  -  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  *  -  -  -  R  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  *  -  -  -  U  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  U  -  -  -  -  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  W  -  -  -  -  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  U  -  -  -  -  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  R  -  -  -  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  *  -  -  R  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  *  -  -  U  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  U  -  -  -  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  R  -  -  -  -  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  U  -  -  -  -  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  R  -  -  -  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  *  -  -  -  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  *  -  -  R  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  *  -  -  U  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  -  *  -  -  -  -  -  -  -  -  U
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395  R  *  -  -  -  -  -  -  -  -  L
Jul 07 11:07:55 ubuntu vdr[1395]: [1395]  1395 invalid lock sequence: 1 Timers
Jul 07 11:07:55 ubuntu vdr[1395]: [1395] full backtrace:
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] /usr/bin/vdr cStateLock::Lock(cStateKey&, bool, int) calling ?? at ??:0
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] /usr/bin/vdr cTimers::GetTimersRead(cStateKey&, int) calling ?? at ??:0
Jul 07 11:07:56 ubuntu vdr[1395]: invalid lock sequence at Tue 07.07. 11:07
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] /usr/bin/vdr cSkinLCARSDisplayMenu::DrawTimers() calling ?? at ??:0
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] /usr/bin/vdr cSkinLCARSDisplayMenu::Flush() calling ?? at ??:0
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] /usr/bin/vdr cSkins::Message(eMessageType, char const*, int) calling ?? at ??:0
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] /usr/bin/vdr cDevice::SwitchChannel(cChannel const*, bool) calling ?? at ??:0
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] /usr/bin/vdr main calling ?? at ??:0
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] /lib/arm-linux-gnueabihf/libc.so.6 __libc_start_main at ??:?
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] --- end invalid lock sequence report
Jul 07 11:07:56 ubuntu vdr[1395]: [1395] --- THERE WILL BE NO FURTHER REPORTS UNTIL VDR IS RESTARTED!

Alles anzeigen

Wenn ich das richtig sehe, hängt da kein anderes Plugin mit drin.

Lohnt es sich das noch mal mit einem vanilla-VDR zu provozieren (wobei ich jetzt nicht wüsste, dass die Patches in den aufgerufenen Methoden mit hinein spielen) oder reicht das eventuell schon, um dem Locking-Problem auf die Spur zu kommen?

kls · 7. Juli 2020

Das ist bei mir auch schon mal aufgetreten, es liegt also am "vanilla" VDR ;-).

Ich werde das bei Gelegenheit debuggen.

Invalid Lock Sequence Report mit LCARS-Skin bei fehlgeschlagenen Kanalwechsel

Jetzt mitmachen!

Teilen

Benutzer online in diesem Thema