[yavdr-bionic] Restart des VDR beim 1. Öffnen des OSD nach einem Neustart

Paulaner · 25. Oktober 2020

Heute ist mir das innerhalb der letzten Tage zum 2. Mal passiert, dass ich kurz nach dem der VDR gebootet hat und das TV-Bild zusehen war, der VDR sich neu gestartet hat.

Passiert ist das, nach dem ich das OSD öffnen wollte. Da war dann schlagartig das TV-Bild weg und es war dann das "vaVDR"-Startbild zu sehen und der VDR hat sich neu gestartet.

Danach war dann alles gut, der VDR lies sich fehlerfrei bedienen und es gab keinerlei Probleme mehr.

Hier mal der relevante Auszug aus dem syslog um 15:55:45 Uhr , als das passierte:

Code

...
Oct 25 15:55:34 yaVDR-PC yavdr-frontend[1321]: DEBUG:VDRFrontend:user is active: True
Oct 25 15:55:34 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:check_state(): got status code: 912
Oct 25 15:55:34 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:status: softhddevice is detached
Oct 25 15:55:34 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:check_state(): got status code: 912
Oct 25 15:55:34 yaVDR-PC kiosk-browser[1521]: AT-SPI: Error retrieving accessibility bus address: org.freedesktop.DBus.Error.ServiceUnknown: The name org.a11y.Bus was not provided by any .service files
Oct 25 15:55:34 yaVDR-PC WebKitWebProces[1528]: AT-SPI: Error retrieving accessibility bus address: org.freedesktop.DBus.Error.ServiceUnknown: The name org.a11y.Bus was not provided by any .service files
Oct 25 15:55:35 yaVDR-PC vdr: osd2web: Client 'localhost/::1' connected (0x7fcbec008750), ping time set to (60)
Oct 25 15:55:35 yaVDR-PC vdr: osd2web: Client login 'localhost/::1' (0x7fcbec008750) [{"event":"login","object":{"type":1,"tftprio":100}}]
Oct 25 15:55:35 yaVDR-PC vdr: osd2web: Info: Cast to cEpgTimer_Interface_V1 failed - aussume epg2vdr not loaded
Oct 25 15:55:35 yaVDR-PC vdr: message repeated 4 times: [ osd2web: Info: Cast to cEpgTimer_Interface_V1 failed - aussume epg2vdr not loaded]
Oct 25 15:55:35 yaVDR-PC vdr: osd2web: lowest prio is (100)
Oct 25 15:55:35 yaVDR-PC vdr: [1378] EPGSearch: timer conflict check started
Oct 25 15:55:35 yaVDR-PC vdr: [1377] EPGSearch: search timer update started
Oct 25 15:55:35 yaVDR-PC vdr: [1378] EPGSearch: timer conflict check finished
Oct 25 15:55:35 yaVDR-PC vdr: [1377] timer 0 (4 1947-2030 'Thomas Junker unterwegs - Europas einsame Inseln') set to event Mo. 16.11.2020 19:50-20:15 (VPS: 16.11. 19:50) 'Thomas Junker unterwegs - Europas einsame Inseln'
Oct 25 15:55:35 yaVDR-PC vdr: [1377] timer 0 (4 1947-2030 'Thomas Junker unterwegs - Europas einsame Inseln') set to event Di. 17.11.2020 19:50-20:15 (VPS: 17.11. 19:50) 'Thomas Junker unterwegs - Europas einsame Inseln'
Oct 25 15:55:35 yaVDR-PC vdr: [1377] timer 0 (4 1947-2030 'Thomas Junker unterwegs - Europas einsame Inseln') set to event Mi. 18.11.2020 19:50-20:15 (VPS: 18.11. 19:50) 'Thomas Junker unterwegs - Europas einsame Inseln'
Oct 25 15:55:35 yaVDR-PC vdr: [1377] timer 0 (4 1947-2030 'Thomas Junker unterwegs - Europas einsame Inseln') set to event Do. 19.11.2020 19:50-20:15 (VPS: 19.11. 19:50) 'Thomas Junker unterwegs - Europas einsame Inseln'
Oct 25 15:55:35 yaVDR-PC vdr: [1377] EPGSearch: search timer update finished
Oct 25 15:55:37 yaVDR-PC vdr: [1383] VNSI: Requesting clients to reload recordings list (1)
Oct 25 15:55:40 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:change_state with command atta and options "-d :0" to attached
Oct 25 15:55:40 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:check_state(): got status code: 910
Oct 25 15:55:40 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:softhddevice successfully attached
Oct 25 15:55:40 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:current PrimaryDevice is softhddevice-openglosd (Index: 7, Number: 7, hasDecoder: True, isPrimary: True)
Oct 25 15:55:40 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:softhddevice is the primary device
Oct 25 15:55:40 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:needed 0.001 s to switch primary device
Oct 25 15:55:41 yaVDR-PC kernel: [   31.024904] snd_hda_intel 0000:01:00.1: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj.
Oct 25 15:55:45 yaVDR-PC vdr[1235]: [xcb] Unknown sequence number while processing reply
Oct 25 15:55:45 yaVDR-PC vdr[1235]: [xcb] Most likely this is a multi-threaded client and XInitThreads has not been called
Oct 25 15:55:45 yaVDR-PC vdr[1235]: [xcb] Aborting, sorry about that.
Oct 25 15:55:45 yaVDR-PC vdr[1235]: vdr: ../../src/xcb_io.c:641: _XReply: Zusicherung »!xcb_xlib_threads_sequence_lost« nicht erfüllt.
Oct 25 15:55:45 yaVDR-PC vdr: video: fatal i/o error
Oct 25 15:55:45 yaVDR-PC yavdr-frontend[1321]: INFO:pydbus2vdr:VDR Status: stopped
Oct 25 15:55:45 yaVDR-PC yavdr-frontend[1321]: DEBUG:softhddevice:g-dbus-error-quark: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name de.tvdr.vdr was not provided by any .service files (2)
Oct 25 15:55:45 yaVDR-PC systemd[1]: vdr.service: Main process exited, code=killed, status=6/ABRT
Oct 25 15:55:45 yaVDR-PC systemd[1]: vdr.service: Failed with result 'signal'.
Oct 25 15:55:45 yaVDR-PC systemd[1]: Starting Ruecksetzen Zoomlevel im softhddevice-Plugin auf 0% nach Start VDR...
Oct 25 15:55:45 yaVDR-PC systemd[1]: vdr.service: Scheduled restart job, restart counter is at 1.
Oct 25 15:55:45 yaVDR-PC systemd[1]: Stopped Video Disk Recorder.
Oct 25 15:55:45 yaVDR-PC systemd[1]: Starting Video Disk Recorder...
Oct 25 15:55:45 yaVDR-PC vdr: [1644] VDR version 2.4.5 started
Oct 25 15:55:45 yaVDR-PC vdr: [1644] switched to user 'vdr'
Oct 25 15:55:45 yaVDR-PC vdr: [1644] codeset is 'UTF-8' - known
Oct 25 15:55:45 yaVDR-PC vdr: [1644] loading plugin: /usr/lib/vdr/plugins/libvdr-skindesigner.so.2.4.5
Oct 25 15:55:45 yaVDR-PC vdr: [1644] loading plugin: /usr/lib/vdr/plugins/libvdr-conflictcheckonly.so.2.4.5
Oct 25 15:55:45 yaVDR-PC vdr: [1644] loading plugin: /usr/lib/vdr/plugins/libvdr-dbus2vdr.so.2.4.5
Oct 25 15:55:45 yaVDR-PC vdr: [1644] dbus2vdr: use shutdown-hooks in /usr/share/vdr/shutdown-hooks
Oct 25 15:55:45 yaVDR-PC vdr: [1644] dbus2vdr: use shutdown-hooks-wrapper /usr/share/vdr-plugin-dbus2vdr/shutdown-wrapper
Oct 25 15:55:45 yaVDR-PC vdr: [1644] loading plugin: /usr/lib/vdr/plugins/libvdr-desktop.so.2.4.5
Oct 25 15:55:45 yaVDR-PC vdr: [1644] loading plugin: /usr/lib/vdr/plugins/libvdr-devstatus.so.2.4.5
Oct 25 15:55:45 yaVDR-PC vdr: [1644] loading plugin: /usr/lib/vdr/plugins/libvdr-epgsearch.so.2.4.5
Oct 25 15:55:45 yaVDR-PC vdr: [1644] loading plugin: /usr/lib/vdr/plugins/libvdr-epgsearchonly.so.2.4.5

Alles anzeigen

Mir sagen die Meldungen leider nicht viel, und der Fehler kam bisher ja nur 2 mal vor und dann eben nicht bei jedem Neustart.

Liegt das evtl. am von mir verwendeten vdr-plugin-softhddevice-cuvid, was ja vor ein paar Tagen eine Aktualisierung bekam?

Mein System ist auf dem aktuellsten stand, d.h. es hat alle aktuellen Updates von yavdr-bionic und auch von Ubuntu, falls das wichtig ist.

Vielleicht kann einer der Profis damit etwas anfangen und erkennt, was die Ursache sein könnte.

lnj · 25. Oktober 2020

[xcb] Most likely this is a multi-threaded client and XInitThreads has not been called

I don’t know yet how to fix this error, it’s not reproduced with me now.

Before the update, there was never an error?

If it has to do with the grab code, I made new commits.

Paulaner · 25. Oktober 2020

Zitat von lnj

Before the update, there was never an error?

I'm not sure about this.

But I've got this failure in the last time 2 times.

447377 · 25. Oktober 2020

I just tested your Version softhddevice 1.0.0 - same error.

Jojos softhdcuvid had the same problem in the past. I try to look after the thread. Perhaps we can ask jojo61 after his solution.

Stefan

447377 · 26. Oktober 2020

Here jojo61 writes that he has built in the XInitThread:

softhdcuvid with hevc and UHD

It must have happened at this point - but I don't understand this...

https://github.com/jojo61/vdr-…59b4a7989fc9fd8d9e6ab1683

I don't understand what he's doing. Maybe it will help you.

Please tell me if I can do anything else.

Stefan

Before that, I found the following contributions with the error:

softhdcuvid with hevc and UHD

Paulaner · 30. Oktober 2020

Heute war es wieder einmal soweit, das der VDR einen Restart gemacht hatte, nach dem ich kurz nach dem Neustart das OSD-Menü öffnen wollte.

Diesmal sieht der Fehler allerdings etwas anders aus, aber es scheint immer noch das vdr-plugin-softhddevice-cuvid der Auslöser des Problems zu sein (ab 11:27:24)

Code

...
Oct 30 11:27:21 yaVDR-PC vdr: [1362] EPGSearch: search timer update finished
Oct 30 11:27:23 yaVDR-PC vdr: [1370] VNSI: Requesting clients to reload recordings list (1)
Oct 30 11:27:24 yaVDR-PC kernel: [   25.794315] show_signal_msg: 1 callbacks suppressed
Oct 30 11:27:24 yaVDR-PC kernel: [   25.794319] softhddev video[1551]: segfault at 10 ip 00007f4f002a3c78 sp 00007f4e9f078db0 error 4 in libGLX.so.0.0.0[7f4f002a2000+1b000]
Oct 30 11:27:24 yaVDR-PC kernel: [   25.794329] Code: ff 83 cf ff e8 c9 e7 ff ff 66 0f 1f 84 00 00 00 00 00 f3 0f 1e fa 41 56 41 89 ce 41 55 49 89 d5 41 54 49 89 f4 55 48 89 fd 53 <8b> 76 10 e8 e0 59 00 00 48 85 c0 74 43 4c 89 e6 48 89 c3 4c 89 ea
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]: WARNING:softhddevice:DBus communication failed!
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]: DEBUG:softhddevice:g-dbus-error-quark: GDBus.Error:org.freedesktop.DBus.Error.NoReply: Message recipient disconnected from message bus without replying (4)
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]: DEBUG:softhddevice:change_state with command atta and options "-d :0" to attached
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]: DEBUG:softhddevice:softhddevice could not be attached: None None
Oct 30 11:27:25 yaVDR-PC systemd[1]: vdr.service: Main process exited, code=killed, status=11/SEGV
Oct 30 11:27:25 yaVDR-PC systemd[1]: vdr.service: Failed with result 'signal'.
Oct 30 11:27:25 yaVDR-PC systemd[1]: Starting Ruecksetzen Zoomlevel im softhddevice-Plugin auf 0% nach Start VDR...
Oct 30 11:27:25 yaVDR-PC systemd[1]: vdr.service: Scheduled restart job, restart counter is at 1.
Oct 30 11:27:25 yaVDR-PC systemd[1]: Stopped Video Disk Recorder.
Oct 30 11:27:25 yaVDR-PC systemd[1]: Starting Video Disk Recorder...
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]: Traceback (most recent call last):
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/pydbus/subscription.py", line 52, in <lambda>
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     callback = (lambda con, sender, object, iface, signal, params: signal_fired(sender, object, iface, signal, params.unpack())) if signal_fired is not None else lambda *args: None
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/pydbus2vdr.py", line 526, in _startup
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     self._on_status_change()
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/pydbus2vdr.py", line 541, in _on_status_change
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     callback(self.vdr_isrunning)
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/yavdr_frontend/vdrfrontend.py", line 58, in on_vdr_status_change
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     self.start()
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/yavdr_frontend/vdrfrontend.py", line 253, in _startup
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     return self.start(startup=True)
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/yavdr_frontend/vdrfrontend.py", line 191, in _start
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     self.frontend.start()
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/yavdr_frontend/frontends/genericfrontend.py", line 159, in start
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     self.make_primary()
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/yavdr_frontend/frontends/genericfrontend.py", line 221, in make_primary
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     ) = self.dbus2vdr.Devices.GetPrimary()
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/pydbus2vdr.py", line 130, in GetPrimary
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     return self._proxy.GetPrimary()
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:   File "/usr/lib/python3/dist-packages/pydbus/proxy_method.py", line 72, in __call__
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]:     ret = instance._bus.con.call_sync(
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]: gi.repository.GLib.Error: g-dbus-error-quark: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name de.tvdr.vdr was not provided by any .service files (2)
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]: INFO:pydbus2vdr:VDR Status: stopped
Oct 30 11:27:25 yaVDR-PC yavdr-frontend[1313]: DEBUG:softhddevice:g-dbus-error-quark: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name de.tvdr.vdr was not provided by any .service files (2)
Oct 30 11:27:25 yaVDR-PC vdr: [1586] VDR version 2.4.5 started
Oct 30 11:27:25 yaVDR-PC vdr: [1586] switched to user 'vdr'
Oct 30 11:27:25 yaVDR-PC vdr: [1586] codeset is 'UTF-8' - known
Oct 30 11:27:25 yaVDR-PC vdr: [1586] loading plugin: /usr/lib/vdr/plugins/libvdr-dvb***.so.2.4.5
Oct 30 11:27:25 yaVDR-PC vdr: [1586] loading plugin: /usr/lib/vdr/plugins/libvdr-skindesigner.so.2.4.5
Oct 30 11:27:25 yaVDR-PC vdr: [1586] loading plugin: /usr/lib/vdr/plugins/libvdr-conflictcheckonly.so.2.4.5
Oct 30 11:27:25 yaVDR-PC vdr: [1586] loading plugin: /usr/lib/vdr/plugins/libvdr-dbus2vdr.so.2.4.5
Oct 30 11:27:25 yaVDR-PC vdr: [1586] dbus2vdr: use shutdown-hooks in /usr/share/vdr/shutdown-hooks
Oct 30 11:27:25 yaVDR-PC vdr: [1586] dbus2vdr: use shutdown-hooks-wrapper /usr/share/vdr-plugin-dbus2vdr/shutdown-wrapper
Oct 30 11:27:25 yaVDR-PC vdr: [1586] loading plugin: /usr/lib/vdr/plugins/libvdr-desktop.so.2.4.5
Oct 30 11:27:25 yaVDR-PC vdr: [1586] loading plugin: /usr/lib/vdr/plugins/libvdr-devstatus.so.2.4.5
Oct 30 11:27:25 yaVDR-PC vdr: [1586] loading plugin: /usr/lib/vdr/plugins/libvdr-epgsearch.so.2.4.5
...

Alles anzeigen

Vielleicht hilft es bei der weiteren Fehlersuche.

Das vdr-plugin-softhddevice-cuvid ist das aktuellste mit Version-1.0.7

[yavdr-bionic] Restart des VDR beim 1. Öffnen des OSD nach einem Neustart

Jetzt mitmachen!

Teilen