ua0lnj / vdr-plugin-softhddevice

VDR SoftHDDevice Plug-in
11 stars 4 forks source link

VDR is crashing with cuvid enabled. #28

Closed schoebelh closed 3 years ago

schoebelh commented 3 years ago

Environment: Nvidia Driver 455, Ubuntu Focal fully updated. YaVDR ansible with PPA ppa:seahawk1986-hotmail/vdr-2.4.5-patches enabled.

vdr Plugins enabled:

dbus2vdr enabled 50
ddci2 enabled 50
desktop enabled 50
devstatus enabled 50
epg2vdr enabled 50
extrecmenung enabled 50
markad enabled 50
menuorg enabled 50
osd2web enabled 50
pulsecontrol enabled 50
scraper2vdr enabled 50
skindesigner enabled 50
softhddevice enabled 50
tvguideng enabled 50
vdrboblight enabled 50
vnsiserver enabled 50
weatherforecast enabled 50

Log with first 'crash':

Nov 4 10:11:23 yavdr vdr: video/cuvid: Start CUVID
Nov 4 10:11:23 yavdr vdr: video/glx: glx version 1.4
Nov 4 10:11:23 yavdr kernel: [ 948.949782] softhddev video[2688]: segfault at 10 ip 00007fe39b440c78 sp 00007fe34cae0db0 error 4 in libGLX.so.0.0.0[7fe39b43f000+1b000]
Nov 4 10:11:23 yavdr kernel: [ 948.949788] 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
Nov 4 10:11:24 yavdr systemd[1]: vdr.service: Main process exited, code=killed, status=11/SEGV

Full Log:

Nov 4 10:11:24 yavdr vdr: [2734] VDR version 2.4.5 started
Nov 4 10:11:24 yavdr vdr: [2734] switched to user 'vdr'
Nov 4 10:11:24 yavdr vdr: [2734] codeset is 'UTF-8' - known
Nov 4 10:11:24 yavdr vdr: [2734] override character table is 'ISO-8859-9' - known
Nov 4 10:11:24 yavdr vdr: [2734] found 28 locales in /usr/share/locale
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'alb,sqi'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'bos'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'bul'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'chi,zho'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'eus,baq'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'iri,gle'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'jpn'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'lav'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'ltz'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'mlt'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'por'
Nov 4 10:11:24 yavdr vdr: [2734] no locale for language code 'smi'
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-dbus2vdr.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] dbus2vdr: use shutdown-hooks in /usr/share/vdr/shutdown-hooks
Nov 4 10:11:24 yavdr vdr: [2734] dbus2vdr: use shutdown-hooks-wrapper /usr/share/vdr-plugin-dbus2vdr/shutdown-wrapper
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-ddci2.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-desktop.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-devstatus.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-epg2vdr.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-extrecmenung.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-markad.so.2.4.5
Nov 4 10:11:24 yavdr vdr[2734]: markad: unrecognized option '--autologo=2'
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-menuorg.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-osd2web.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-pulsecontrol.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-scraper2vdr.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-skindesigner.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-softhddevice.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-tvguideng.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-vdrboblight.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-vnsiserver.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading plugin: /usr/lib/vdr/plugins/libvdr-weatherforecast.so.2.4.5
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/setup.conf
Nov 4 10:11:24 yavdr vdr: [2734] ERROR: unknown config parameter: markad.AutoLogoExtraction = 2
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/sources.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/diseqc.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/scr.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/channels.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/timers.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/commands.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/reccmds.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/svdrphosts.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/remote.conf
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/keymacros.conf
Nov 4 10:11:24 yavdr vdr: [2734] ERROR: unknown plugin 'osdteletext'
Nov 4 10:11:24 yavdr vdr: [2734] ERROR: empty key macro
Nov 4 10:11:24 yavdr vdr: [2734] ERROR: unknown plugin 'xineliboutput'
Nov 4 10:11:24 yavdr vdr: [2734] registered source parameters for 'A - ATSC'
Nov 4 10:11:24 yavdr vdr: [2735] video directory scanner thread started (pid=2734, tid=2735, prio=low)
Nov 4 10:11:24 yavdr vdr: [2734] registered source parameters for 'C - DVB-C'
Nov 4 10:11:24 yavdr vdr: [2734] registered source parameters for 'S - DVB-S'
Nov 4 10:11:24 yavdr vdr: [2734] registered source parameters for 'T - DVB-T'
Nov 4 10:11:24 yavdr vdr: [2734] detected /dev/dvb/adapter4/frontend0
Nov 4 10:11:24 yavdr vdr: [2734] detected /dev/dvb/adapter3/frontend0
Nov 4 10:11:24 yavdr vdr: [2734] detected /dev/dvb/adapter1/frontend0
Nov 4 10:11:24 yavdr vdr: [2734] detected /dev/dvb/adapter0/frontend0
Nov 4 10:11:24 yavdr vdr: [2734] probing /dev/dvb/adapter0/frontend0
Nov 4 10:11:24 yavdr vdr: [2734] creating cDvbDevice
Nov 4 10:11:24 yavdr vdr: [2734] new device number 1 (card index 1)
Nov 4 10:11:24 yavdr vdr: [2734] DVB API version is 0x050B (VDR was built with 0x050B)
Nov 4 10:11:24 yavdr vdr: [2734] frontend 0/0 provides DVB-C,DVB-T with QPSK,QAM16,QAM32,QAM64,QAM128,QAM256 ("ST STV0367 DDB DVB-C/T")
Nov 4 10:11:24 yavdr vdr: [2734] cTimeMs: using monotonic clock (resolution is 1 ns)
Nov 4 10:11:24 yavdr vdr: [2734] probing /dev/dvb/adapter1/frontend0
Nov 4 10:11:24 yavdr vdr: [2738] frontend 0/0 tuner thread started (pid=2734, tid=2738, prio=high)
Nov 4 10:11:24 yavdr vdr: [2734] creating cDvbDevice
Nov 4 10:11:24 yavdr vdr: [2734] new device number 2 (card index 2)
Nov 4 10:11:24 yavdr vdr: [2736] epg data reader thread started (pid=2734, tid=2736, prio=high)
Nov 4 10:11:24 yavdr vdr: [2736] reading EPG data from /var/cache/vdr/epg.data
Nov 4 10:11:24 yavdr vdr: [2734] frontend 1/0 provides DVB-C,DVB-T with QPSK,QAM16,QAM32,QAM64,QAM128,QAM256 ("ST STV0367 DDB DVB-C/T")
Nov 4 10:11:24 yavdr vdr: [2734] probing /dev/dvb/adapter3/frontend0
Nov 4 10:11:24 yavdr vdr: [2734] creating cDvbDevice
Nov 4 10:11:24 yavdr vdr: [2734] new device number 3 (card index 3)
Nov 4 10:11:24 yavdr vdr: [2741] frontend 1/0 tuner thread started (pid=2734, tid=2741, prio=high)
Nov 4 10:11:24 yavdr vdr: [2742] device 2 section handler thread started (pid=2734, tid=2742, prio=low)
Nov 4 10:11:24 yavdr vdr: [2739] device 1 section handler thread started (pid=2734, tid=2739, prio=low)
Nov 4 10:11:24 yavdr vdr: [2735] video directory scanner thread ended (pid=2734, tid=2735)
Nov 4 10:11:24 yavdr vdr: [2734] frontend 3/0 provides DVB-T,DVB-T2,DVB-C with QPSK,QAM16,QAM32,QAM64,QAM128,QAM256 ("Sony CXD2843ER DVB-T/T2/C/C2 demodulator")
Nov 4 10:11:24 yavdr vdr: [2734] probing /dev/dvb/adapter4/frontend0
Nov 4 10:11:24 yavdr vdr: [2734] creating cDvbDevice
Nov 4 10:11:24 yavdr vdr: [2734] new device number 4 (card index 4)
Nov 4 10:11:24 yavdr vdr: [2745] frontend 3/0 tuner thread started (pid=2734, tid=2745, prio=high)
Nov 4 10:11:24 yavdr vdr: [2746] device 3 section handler thread started (pid=2734, tid=2746, prio=low)
Nov 4 10:11:24 yavdr vdr: [2734] frontend 4/0 provides DVB-T,DVB-T2,DVB-C with QPSK,QAM16,QAM32,QAM64,QAM128,QAM256 ("Sony CXD2843ER DVB-T/T2/C/C2 demodulator")
Nov 4 10:11:24 yavdr vdr: [2734] found 4 DVB devices
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: dbus2vdr (31): Steuerung des VDR über D-Bus
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: ddci2 (1.0.5): External Digital Devices CI-Adapter
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: desktop (0.0.3): desktop apps menu
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: devstatus (0.4.1): DVB-Gerätestatus
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: epg2vdr (1.1.118-GIT): epg2vdr plugin
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: extrecmenung (2.0.5): Erweitertes Aufzeichnungs-Menü
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: markad (0.1.6): Markiere Werbung
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: menuorg (0.5.2): Reorganisiert das Haupmenü
Nov 4 10:11:24 yavdr vdr: [2734] loading menuorg config file from /var/lib/vdr/plugins/menuorg.xml
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: osd2web (0.2.55-GIT): osd2web plugin
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: pulsecontrol (0.2.1): Pulseaudio über das OSD steuern
Nov 4 10:11:24 yavdr vdr: [2749] device 4 section handler thread started (pid=2734, tid=2749, prio=low)
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: scraper2vdr (1.0.11-GIT): 'scraper2vdr' plugin
Nov 4 10:11:24 yavdr vdr: scraper2vdr: using image directory /var/cache/vdr/epgimages/
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: skindesigner (1.2.8.5): Skin Designer
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: softhddevice (1.0.7): A software and GPU emulated UHD device
Nov 4 10:11:24 yavdr vdr: [2734] new device number 5 (card index 5)
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: tvguideng (0.3.2): TV Guide for Skindesigner Skins
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: vdrboblight (): Boblight with data from softhddevice
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: vnsiserver (1.8.0): VDR-Network-Streaming-Interface (VNSI) Server
Nov 4 10:11:24 yavdr vdr: [2734] initializing plugin: weatherforecast (0.2.0): Weatherforecast based on forecast.io
Nov 4 10:11:24 yavdr vdr: [2734] setting primary device to 5
Nov 4 10:11:24 yavdr vdr: [2734] [softhddev]stopping OpenGL Worker Thread
Nov 4 10:11:24 yavdr vdr: [2734] [softhddev]OpenGL Worker Thread stopped
Nov 4 10:11:24 yavdr vdr: [2734] assuming manual start of VDR
Nov 4 10:11:24 yavdr vdr: [2734] skin "estuary4vdr" not available - using "lcars" instead
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/themes/lcars-default.theme
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: dbus2vdr
Nov 4 10:11:24 yavdr vdr: [2748] frontend 4/0 tuner thread started (pid=2734, tid=2748, prio=high)
Nov 4 10:11:24 yavdr vdr: [2750] dbus2vdr: mainloop started
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: ddci2
Nov 4 10:11:24 yavdr vdr: [2734] DDCI-Inf: plugin version 1.0.5 initializing (compiled for VDR version 2.4.5)
Nov 4 10:11:24 yavdr vdr: [2734] DDCI-Inf: found 1 DD CI adapter
Nov 4 10:11:24 yavdr vdr: [2734] DDCI-Inf: Creating DdCiAdapter 0 (/dev/dvb/adapter2/ca0)
Nov 4 10:11:24 yavdr vdr: [2750] dbus2vdr: System: connected with unique name :1.52
Nov 4 10:11:24 yavdr vdr: [2750] dbus2vdr: thread-pool for handling signal-emits started
Nov 4 10:11:24 yavdr avahi-linker[1003]: 2020-11-04 10:11:24,389 INFO VDR started
Nov 4 10:11:24 yavdr vdr: [2736] epg data reader thread ended (pid=2734, tid=2736)
Nov 4 10:11:24 yavdr vdr: [2734] DDCI-Inf: plugin started
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: desktop
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: devstatus
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: epg2vdr
Nov 4 10:11:24 yavdr vdr: epg2vdr: Info: Calling mysql_library_init()
Nov 4 10:11:24 yavdr vdr: epg2vdr: Set locale to 'de_DE.UTF-8'
Nov 4 10:11:24 yavdr vdr: epg2vdr: detected UTF-8
Nov 4 10:11:24 yavdr vdr: [2755] DDCI adapter /dev/dvb/adapter2/ca0 thread started (pid=2734, tid=2755, prio=high)
Nov 4 10:11:24 yavdr vdr: epg2vdr: Dictionary '/var/lib/vdr/plugins/epg2vdr//epg.dat' loaded
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: extrecmenung
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: markad
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: menuorg
Nov 4 10:11:24 yavdr vdr[2734]: vdr: error while reading '/var/lib/vdr/plugins/pulsecontrol/startup.script'
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: osd2web
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: pulsecontrol
Nov 4 10:11:24 yavdr vdr: [2734] pulsecontrol: error on reading script /var/lib/vdr/plugins/pulsecontrol/startup.script
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: scraper2vdr
Nov 4 10:11:24 yavdr vdr: epg2vdr: Info: Skipping calling mysql_library_init(), it's already done!
Nov 4 10:11:24 yavdr vdr: scraper2vdr: Set locale to 'de_DE.UTF-8'
Nov 4 10:11:24 yavdr vdr: scraper2vdr: detected UTF-8
Nov 4 10:11:24 yavdr vdr: [2758] epg2vdr-update thread started (pid=2734, tid=2758, prio=high)
Nov 4 10:11:24 yavdr vdr: osd2web: osd2web plugin thread started (pid=2734)
Nov 4 10:11:24 yavdr vdr: scraper2vdr: Dictionary '/var/lib/vdr/plugins/scraper2vdr/epg.dat' loaded
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: skindesigner
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: TrueColor OSD found
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: using libskindesigner API Version 0.1.2
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: plugin setup uses libskindesigner API Version 0.1.2
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: plugin setup has registered 1 menus
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: skinsetup template successfully registered at skindesigner, id 0
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: using Skin Directory /usr/share/vdr/plugins/skindesigner/skins/
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: using Installer Skin Directory /var/lib/vdr/plugins/skindesigner/installerskins/
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: using common ChannelLogo Directory /usr/share/vdr/plugins/skindesigner/logos/
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: using EPG Images Directory /var/cache/vdr/epgimages/
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner 2 skins found in /usr/share/vdr/plugins/skindesigner/skins/
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner 1 skins found in /var/lib/vdr/plugins/skindesigner/installerskins/
Nov 4 10:11:24 yavdr vdr: [2757] DDCI Recv (/dev/dvb/adapter2/sec0) thread started (pid=2734, tid=2757, prio=high)
Nov 4 10:11:24 yavdr vdr: [2756] DDCI Send (/dev/dvb/adapter2/sec0) thread started (pid=2734, tid=2756, prio=high)
Nov 4 10:11:24 yavdr vdr: [2761] DDCI Recv Deliver (/dev/dvb/adapter2/sec0) thread started (pid=2734, tid=2761, prio=high)
Nov 4 10:11:24 yavdr vdr: [2760] scraper2vdr-update thread started (pid=2734, tid=2760, prio=low)
Nov 4 10:11:24 yavdr vdr: osd2web: Listener at port (4444) established
Nov 4 10:11:24 yavdr vdr: osd2web: using libwebsocket version '3.2.0 unknown-build-hash'
Nov 4 10:11:24 yavdr vdr: epg2vdr: SQL client character now 'utf8'
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: skin estuary4vdr started
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: skin metrixhd started
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: skin shady_KISS started
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: zapcockpit patch available
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: softhddevice
Nov 4 10:11:24 yavdr vdr: [softhddev] ready detached
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: tvguideng
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: plugin tvguideng uses libskindesigner API Version 0.1.2
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: plugin tvguideng has registered 5 views with 23 viewelements and 11 viewgrids
Nov 4 10:11:24 yavdr vdr: [2734] tvguideng: successfully registered at skindesigner, id 1
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: vdrboblight
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: vnsiserver
Nov 4 10:11:24 yavdr vdr: [2734] VNSI: Starting vnsi server at port=34890
Nov 4 10:11:24 yavdr vdr: [2734] VNSI: VNSI Server started
Nov 4 10:11:24 yavdr vdr: [2734] VNSI: Channel streaming timeout: 10 seconds
Nov 4 10:11:24 yavdr vdr: [2734] starting plugin: weatherforecast
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: plugin weatherforecast uses libskindesigner API Version 0.1.2
Nov 4 10:11:24 yavdr vdr: [2734] skindesigner: plugin weatherforecast has registered 4 menus
Nov 4 10:11:24 yavdr vdr: [2734] weatherforecast: successfully registered at skindesigner, id 2
Nov 4 10:11:24 yavdr vdr: [2734] setting current skin to "estuary4vdr"
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/lib/vdr/themes/estuary4vdr-default.theme
Nov 4 10:11:24 yavdr vdr: [2734] remote control XKeySym - keys known
Nov 4 10:11:24 yavdr vdr: [2734] remote control LIRC - keys known
Nov 4 10:11:24 yavdr vdr: [2734] loading /var/cache/vdr/cam.data
Nov 4 10:11:24 yavdr vdr: [2766] LIRC remote control thread started (pid=2734, tid=2766, prio=high)
Nov 4 10:11:24 yavdr vdr: [2765] weatherforecast: using cached forecast
Nov 4 10:11:24 yavdr vdr: [2764] VNSI Server thread started (pid=2734, tid=2764, prio=high)
Nov 4 10:11:24 yavdr vdr: BOBLIGHT: boblight Thread started (pid=2734)
Nov 4 10:11:24 yavdr boblightd[716]: (CClientsHandler::Process) 127.0.0.1:49698 connected
Nov 4 10:11:24 yavdr boblightd[716]: (CClientsHandler::ParseMessage) 127.0.0.1:49698 said hello
Nov 4 10:11:24 yavdr vdr: [2767] VNSIStatus thread started (pid=2734, tid=2767, prio=high)
Nov 4 10:11:24 yavdr vdr: BOBLIGHT: Connected to boblight
Nov 4 10:11:24 yavdr boblightd[716]: (CClientsHandler::ParseSet) 127.0.0.1:49698 priority set to 128
Nov 4 10:11:24 yavdr vdr: [2768] VNSITimers thread started (pid=2734, tid=2768, prio=high)
Nov 4 10:11:24 yavdr boblightd[716]: (CClientsHandler::ParseSet) 127.0.0.1:49698 priority set to 128
Nov 4 10:11:24 yavdr boblightd[716]: (CClientsHandler::Process) 127.0.0.1:49698 Connection closed
Nov 4 10:11:24 yavdr boblightd[716]: (CClientsHandler::RemoveClient) removing 127.0.0.1:49698
Nov 4 10:11:24 yavdr vdr: [2755] CAM 1: module present
Nov 4 10:11:24 yavdr vdr: epg2vdr: Info: Last update was at '04.11.20 09:56:56'
Nov 4 10:11:24 yavdr vdr: epg2vdr: Handler: Start reading external ids from db
Nov 4 10:11:24 yavdr vdr: epg2vdr: Handler: Finished reading external id's from db, got 27 id's
Nov 4 10:11:25 yavdr kernel: [ 950.991141] dvb_ca_en50221: dvb_ca adapter 2: DVB CAM detected and initialised successfully
Nov 4 10:11:26 yavdr vdr: [2755] CAM 1: module ready
Nov 4 10:11:27 yavdr vdr: [2755] CAM 1: , 01, 0001, 0001
Nov 4 10:11:28 yavdr vdr: [2755] CAM 1: system ids: 09C7 0100 0500 098C 09C4 0D95 0D98 1702 1830 1843 1860 186A 098E 1834 1861 1722 1831 1838 09AF 1833 1835 1801 0D00 0919 093B 09CD 0B00 0B02 0963 0D96 0D97 0D0F 0602 0604 0610 0624 0628 0D02 1800 1802 1803 1804 1805 1810 183D 183E 2600 093E 090F 4ABF 09C1 4AEE 0648 0650 0653 0666
Nov 4 10:11:28 yavdr vdr: [2755] CAM 1: replies to QUERY - multi channel decryption (MCD) possible
Nov 4 10:11:28 yavdr vdr: [2755] CAM 1: supports multi transponder decryption (MTD)
Nov 4 10:11:28 yavdr vdr: [2755] CAM 1: activating MTD support
Nov 4 10:11:28 yavdr vdr: [2734] CAM 1: ready, master ()
Nov 4 10:11:28 yavdr vdr: [2734] switching to channel 4 C-61441-10015-50018 (SAT.1 HD)
Nov 4 10:11:28 yavdr vdr: [2734] CAM 1/1: creating new MTD CAM slot
Nov 4 10:11:28 yavdr vdr: [2734] CAM 1: assigned to device 1
Nov 4 10:11:28 yavdr vdr: [2770] device 1 receiver thread started (pid=2734, tid=2770, prio=high)
Nov 4 10:11:28 yavdr vdr: [2771] device 1 TS buffer thread started (pid=2734, tid=2771, prio=high)
Nov 4 10:11:28 yavdr systemd[1]: Started Video Disk Recorder.
Nov 4 10:11:28 yavdr vdr: [2772] SVDRP server handler thread started (pid=2734, tid=2772, prio=low)
Nov 4 10:11:28 yavdr vdr: [2772] SVDRP yavdr opening port 6419/tcp
Nov 4 10:11:28 yavdr vdr: [2772] SVDRP yavdr listening on port 6419/tcp
Nov 4 10:11:28 yavdr vdr: [2773] SVDRP client handler thread started (pid=2734, tid=2773, prio=low)
Nov 4 10:11:28 yavdr vdr: [2773] SVDRP yavdr opening port 6419/udp
Nov 4 10:11:28 yavdr vdr: [2773] SVDRP yavdr listening on port 6419/udp
Nov 4 10:11:28 yavdr vdr: [2773] SVDRP yavdr > 255.255.255.255:6419 send dgram 'SVDRP:discover name:yavdr port:6419 vdrversion:20405 apiversion:20405 timeout:300'
Nov 4 10:11:28 yavdr vdr: [2734] OSD size changed to 1920x1080 @ 1
Nov 4 10:11:28 yavdr vdr: [2734] skindesigner: initializing skin estuary4vdr
Nov 4 10:11:28 yavdr vdr: [2734] skindesigner: using decimal point ,
Nov 4 10:11:28 yavdr vdr: [2734] skindesigner: using channel logo path /usr/share/vdr/plugins/skindesigner/logos/
Nov 4 10:11:28 yavdr vdr: [2734] skindesigner: using icon path /usr/share/vdr/plugins/skindesigner/skins/estuary4vdr/themes/default/
Nov 4 10:11:28 yavdr vdr: [2734] skindesigner: using skinparts path /usr/share/vdr/plugins/skindesigner/skins/estuary4vdr/themes/default/skinparts/
Nov 4 10:11:28 yavdr vdr: [2734] skindesigner: using svgtemplate path /usr/share/vdr/plugins/skindesigner/skins/estuary4vdr/svgtemplates/
Nov 4 10:11:28 yavdr vdr: [2734] skindesigner: using language de_DE
Nov 4 10:11:29 yavdr vdr: [2734] skindesigner: templates successfully validated and parsed
Nov 4 10:11:29 yavdr vdr: [2734] [softhddev]detached - OpenGl Worker Thread not tried to start
Nov 4 10:11:29 yavdr vdr: message repeated 24 times: [ [2734] [softhddev]detached - OpenGl Worker Thread not tried to start]
Nov 4 10:11:29 yavdr vdr: epg2vdr: Handler: Init handler instance for thread 2739
Nov 4 10:11:29 yavdr vdr: [2734] [softhddev]detached - OpenGl Worker Thread not tried to start
Nov 4 10:11:29 yavdr vdr: message repeated 4 times: [ [2734] [softhddev]detached - OpenGl Worker Thread not tried to start]
Nov 4 10:11:29 yavdr vdr: [2767] VNSI: Requesting clients to reload channel list
Nov 4 10:11:29 yavdr vdr: [2734] [softhddev]detached - OpenGl Worker Thread not tried to start
Nov 4 10:11:29 yavdr vdr: [2734] [softhddev]detached - OpenGl Worker Thread not tried to start
Nov 4 10:11:29 yavdr vdr: [2755] CAM 1: system ids: 09C7
Nov 4 10:11:29 yavdr vdr: [2734] [softhddev]detached - OpenGl Worker Thread not tried to start
Nov 4 10:11:30 yavdr vdr: message repeated 49 times: [ [2734] [softhddev]detached - OpenGl Worker Thread not tried to start]
Nov 4 10:11:30 yavdr vdr: [2734] skindesigner: templates and images cached
Nov 4 10:11:30 yavdr vdr: [2734] skindesigner: cached 74 icons - size internal mem 1,66MB, high level mem 0,00MB
Nov 4 10:11:30 yavdr vdr: [2734] skindesigner: cached 201 logos - size 9074,53MB internal mem
Nov 4 10:11:30 yavdr vdr: [2734] skindesigner: cached 8 skinparts - size internal mem 16,78MB, high level mem 0,00MB
Nov 4 10:11:30 yavdr vdr: [2734] skindesigner: templates loaded and caches created - needed 1298 ms
Nov 4 10:11:30 yavdr vdr: [2734] [softhddev]No hw driver or OpenGL Osd disabled - use soft OSD
Nov 4 10:11:30 yavdr vdr: [2734] [softhddev]detached - OpenGl Worker Thread not tried to start
Nov 4 10:11:30 yavdr vdr: [2844] animator thread thread started (pid=2734, tid=2844, prio=high)
Nov 4 10:11:30 yavdr yavdr-frontend[1708]: INFO:pydbus2vdr:VDR Status: running
Nov 4 10:11:30 yavdr vdr: [2750] dbus2vdr: thread-pool for handling method-calls started
Nov 4 10:11:30 yavdr vdr: [2848] video directory scanner thread started (pid=2734, tid=2848, prio=low)
Nov 4 10:11:30 yavdr avahi-linker[1003]: 2020-11-04 10:11:30,357 INFO Update recdir via dbus: 0 update of recordings triggered
Nov 4 10:11:30 yavdr vdr: [2847] dbus2vdr: use of deprecated interface: 'List' should be called with the interface 'de.tvdr.vdr.pluginmanager'!
Nov 4 10:11:30 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:False
Nov 4 10:11:30 yavdr yavdr-frontend[1708]: INFO:softhddevice:use_pasuspend is False
Nov 4 10:11:30 yavdr yavdr-frontend[1708]: DEBUG:yaVDRFrontend:set_background with options path: /usr/share/yavdr/images/yavdr_logo.png, fill: False
Nov 4 10:11:30 yavdr vdr: [2734] retuning due to modification of channel 4 (SAT.1 HD)
Nov 4 10:11:30 yavdr vdr: [2734] switching to channel 4 C-61441-10015-50018 (SAT.1 HD)
Nov 4 10:11:30 yavdr vdr: [2848] video directory scanner thread ended (pid=2734, tid=2848)
Nov 4 10:11:30 yavdr yavdr-frontend[1708]: DEBUG:VDRFrontend:user is active: True
Nov 4 10:11:30 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:check_state(): got status code: 912
Nov 4 10:11:30 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:status: softhddevice is detached
Nov 4 10:11:30 yavdr kiosk-browser[2846]: AT-SPI: Error retrieving accessibility bus address: org.freedesktop.DBus.Error.ServiceUnknown: The name org.a11y.Bus was not provided by any .service files
Nov 4 10:11:30 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:check_state(): got status code: 912
Nov 4 10:11:30 yavdr vdr: audio: 'alsa' output module used
Nov 4 10:11:30 yavdr vdr: audio/alsa: supports pause: yes
Nov 4 10:11:30 yavdr vdr: [2734] CAM 1: unassigned from device 1
Nov 4 10:11:30 yavdr vdr: [2734] CAM 1/1: reusing MTD CAM slot
Nov 4 10:11:30 yavdr vdr: [2767] VNSI: Requesting clients to reload timers
Nov 4 10:11:30 yavdr WebKitWebProces[2857]: AT-SPI: Error retrieving accessibility bus address: org.freedesktop.DBus.Error.ServiceUnknown: The name org.a11y.Bus was not provided by any .service files
Nov 4 10:11:30 yavdr vdr: [2771] device 1 TS buffer thread ended (pid=2734, tid=2771)
Nov 4 10:11:30 yavdr vdr: [2770] buffer stats: 129532 (0%) used
Nov 4 10:11:30 yavdr vdr: [2770] device 1 receiver thread ended (pid=2734, tid=2770)
Nov 4 10:11:30 yavdr vdr: [2734] CAM 1: assigned to device 1
Nov 4 10:11:30 yavdr vdr: [2885] device 1 receiver thread started (pid=2734, tid=2885, prio=high)
Nov 4 10:11:30 yavdr vdr: [2886] device 1 TS buffer thread started (pid=2734, tid=2886, prio=high)
Nov 4 10:11:30 yavdr vdr: osd2web: Client 'ip6-localhost/::1' connected (0x55799f20e0f0), ping time set to (60)
Nov 4 10:11:30 yavdr vdr: osd2web: Client login 'ip6-localhost/::1' (0x55799f20e0f0) [{"event":"login","object":{"type":1,"tftprio":100}}]
Nov 4 10:11:30 yavdr vdr: osd2web: lowest prio is (100)
Nov 4 10:11:32 yavdr vdr: [2767] VNSI: Requesting clients to reload recordings list (1)
Nov 4 10:11:33 yavdr vdr: audio: 44100Hz supports 1 2 3 4 5 6 7 8 channels
Nov 4 10:11:33 yavdr vdr: audio: 48000Hz supports 1 2 3 4 5 6 7 8 channels
Nov 4 10:11:33 yavdr vdr: audio: 192000Hz supports 1 2 3 4 5 6 7 8 channels
Nov 4 10:11:33 yavdr vdr: video/cuvid: Start CUVID
Nov 4 10:11:33 yavdr vdr: video/glx: glx version 1.4
Nov 4 10:11:33 yavdr vdr: video/cuvid: Start CUVID ok
Nov 4 10:11:33 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:change_state with command atta and options "-d :0" to attached
Nov 4 10:11:33 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:check_state(): got status code: 910
Nov 4 10:11:33 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:softhddevice successfully attached
Nov 4 10:11:33 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:current PrimaryDevice is softhddevice-openglosd (Index: 4, Number: 4, hasDecoder: True, isPrimary: True)
Nov 4 10:11:33 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:softhddevice is the primary device
Nov 4 10:11:33 yavdr yavdr-frontend[1708]: DEBUG:softhddevice:needed 0.001 s to switch primary device
Nov 4 10:11:33 yavdr boblightd[716]: (CClientsHandler::Process) 127.0.0.1:49702 connected
Nov 4 10:11:33 yavdr boblightd[716]: (CClientsHandler::ParseMessage) 127.0.0.1:49702 said hello
Nov 4 10:11:33 yavdr vdr: BOBLIGHT: Connected to boblight
Nov 4 10:11:33 yavdr boblightd[716]: (CClientsHandler::ParseSet) 127.0.0.1:49702 priority set to 128
Nov 4 10:11:33 yavdr boblightd[716]: (CClientsHandler::ParseSet) 127.0.0.1:49702 priority set to 128
Nov 4 10:11:33 yavdr vdr: audio/alsa: using pass-through device 'default'
Nov 4 10:11:33 yavdr vdr: audio/alsa: start delay 336ms
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Got UUID '28013E18-8CF2-4C2C-96AB-259FD437FF0F' by epg2vdr
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Trying to re-connect to database!
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Calling mysql_init(2760)
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Connection established successfull!
Nov 4 10:11:34 yavdr vdr: [2767] VNSI: Requesting clients to reload channel list
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Got 3451 new scraped Events from Database
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Loading Movies information from Database...
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Got 182 new/updated Movies in 0s from Database (new max scrsp: 1604476616)
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Loading Movies content from Database...
Nov 4 10:11:34 yavdr vdr: osd2web: Client 'xxx/192.168.10.67' connected (0x55799f28a840), ping time set to (60)
Nov 4 10:11:34 yavdr vdr: osd2web: Client login 'xxx/192.168.10.67' (0x55799f28a840) [{"event":"login","object":{"type":0,"tftprio":100}}]
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Got 0 new/updated Image information in 0s from Database
Nov 4 10:11:34 yavdr vdr: scraper2vdr: Loading Series information from Database...
Nov 4 10:11:35 yavdr vdr: scraper2vdr: Got 337 new/updated Series in 1s from Database (new max scrsp: 1604480222)
Nov 4 10:11:35 yavdr vdr: scraper2vdr: Loading Series content from Database...
Nov 4 10:11:35 yavdr vdr: scraper2vdr: Got 1199 new/updated Episodes and 611 new/updated Image information (including 584 possible not available season poster) in 0s from Database
Nov 4 10:11:35 yavdr vdr: scraper2vdr: Loading Image content from Database...
Nov 4 10:11:35 yavdr vdr: [2844] animator thread thread ended (pid=2734, tid=2844)
Nov 4 10:11:35 yavdr vdr: scraper2vdr: Got 28 new/updated Images (found 583 not available images) in 0s from Database
Nov 4 10:11:37 yavdr vdr: video/cuvid: synced after 78 frames
Nov 4 10:12:13 yavdr vdr[2734]: [xcb] Unknown sequence number while processing queue
Nov 4 10:12:13 yavdr vdr[2734]: [xcb] Most likely this is a multi-threaded client and XInitThreads has not been called
Nov 4 10:12:13 yavdr vdr[2734]: [xcb] Aborting, sorry about that.
Nov 4 10:12:13 yavdr vdr[2734]: vdr: ../../src/xcb_io.c:260: poll_for_event: Assertion `!xcb_xlib_threads_sequence_lost' failed.
Nov 4 10:12:14 yavdr boblightd[716]: (CClientsHandler::Process) 127.0.0.1:49702 Connection closed
Nov 4 10:12:14 yavdr boblightd[716]: (CClientsHandler::RemoveClient) removing 127.0.0.1:49702
Nov 4 10:12:14 yavdr yavdr-frontend[1708]: INFO:pydbus2vdr:VDR Status: stopped
Nov 4 10:12:14 yavdr yavdr-frontend[1708]: 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)
Nov 4 10:12:14 yavdr systemd[1]: vdr.service: Main process exited, code=killed, status=6/ABRT
Nov 4 10:12:14 yavdr systemd[1]: vdr.service: Failed with result 'signal'.
Nov 4 10:12:14 yavdr systemd[1]: vdr.service: Scheduled restart job, restart counter is at 2.
ua0lnj commented 3 years ago

Most likely this is a multi-threaded client and XInitThreads has not been called. I cannot reproduce such an error in myself, and I do not yet know how to fix it. I will try.

schoebelh commented 3 years ago

Am i right that you thought about any of my active plugins as source of crash...?

ua0lnj commented 3 years ago

No it's a GLX problem. Does VDR crash always or sometimes, or after switching from bionic to focal, for example?

schoebelh commented 3 years ago

Does VDR crash always or sometimes, or after switching from bionic to focal, for example?

VDR crashes always, shortly after starting the service.

ua0lnj commented 3 years ago

I'll clarify just in case. Have you vdr crash, if disable boblight plugin?

schoebelh commented 3 years ago

I tested now again - the crash is there when i only disable the boblight plugin (after using vdr with enabled boblight plugin and without "-v cuvid" as option for softhddevice) and then just restart vdr with softhddevice and enabled "-v cuvid". When i reboot the system after that i can start vdr without GLX-crashing. Hope this is not too much confusing described...

ua0lnj commented 3 years ago

With -v cuvid, have you crash in both case, with boblight and without it?

schoebelh commented 3 years ago

No. If i disable the boblight plugin i had to reboot the system to use the plugin (with -v cuvid) without any errors.

My "normal" usage here is WITH boblight-plugin and without the "-v" switch for softhddevice. For testing i just disabled the boblight plugin, switched to "-v cuvid" and restart vdr (without reboot) and THEN i had the crash again.

It seams like you supposed already - the boblight plugin does not like the "-v cuvid" mode :-/

ua0lnj commented 3 years ago

WIthout "-v cuvid" plugin must use VDPAU. With the latest commit, boblight should work with CUVID, but it doesn't seem to be.

schoebelh commented 3 years ago

Ok. I disable (ppa-purge) the "graphics driver ppa" and use now the "original" ubuntu nvidia driver 450. Now i can start with boblight plugin enabled AND "-v cuvid".

BUT: After some time vdr crashed again...

Nov 26 16:52:40 yavdr vdr: [2795] VNSI: Requesting clients to reload channel list
Nov 26 16:52:44 yavdr vdr[2761]: X Error of failed request:  GLXBadContextTag
Nov 26 16:52:44 yavdr vdr[2761]:   Major opcode of failed request:  149 (GLX)
Nov 26 16:52:44 yavdr vdr[2761]:   Minor opcode of failed request:  5 (X_GLXMakeCurrent)
Nov 26 16:52:44 yavdr vdr[2761]:   Serial number of failed request:  32693
Nov 26 16:52:44 yavdr vdr[2761]:   Current serial number in output stream:  32694
Nov 26 16:52:45 yavdr kernel: [  441.383921] softhddev video[2953]: segfault at 559b0000000a ip 00007f50c0accba4 sp 00007f50328f2590 error 4 in libc-2.31.so[7f50c0a59000+178000]
Nov 26 16:52:45 yavdr kernel: [  441.383937] Code: c9 0f 11 4b 20 48 89 ee 66 48 0f 6e c0 48 83 ce 01 0f 16 44 24 08 48 89 73 08 0f 11 43 10 49 89 2c 24 48 85 d2 74 8f 48 89 d3 <48> 8b 43 08 89 c2 c1 ea 04 83 ea 02 49 8d 54 d7 10 49 39 d5 0f 85
Nov 26 16:52:45 yavdr boblightd[715]: (CClientsHandler::Process)      127.0.0.1:33974 Connection closed

But i see also this crash:

Nov 26 16:55:13 yavdr vdr: message repeated 2 times: [ [3098] VNSI: Requesting clients to reload channel list]
Nov 26 16:55:16 yavdr vdr[3065]: X Error of failed request:  GLXBadContextTag
Nov 26 16:55:16 yavdr vdr[3065]:   Major opcode of failed request:  149 (GLX)
Nov 26 16:55:16 yavdr vdr[3065]:   Minor opcode of failed request:  5 (X_GLXMakeCurrent)
Nov 26 16:55:16 yavdr vdr[3065]:   Serial number of failed request:  26462
Nov 26 16:55:16 yavdr vdr[3065]:   Current serial number in output stream:  26463
Nov 26 16:55:16 yavdr kernel: [  592.193132] epg2vdr-update[3089]: segfault at 21 ip 00007f489970ccd4 sp 00007f484bffec30 error 4 in libvdr-epg2vdr.so.2.4.5[7f48996f6000+8c000]
Nov 26 16:55:16 yavdr kernel: [  592.193140] Code: 8b 5d 48 4c 8b 7c 24 20 48 85 db 0f 84 9d 00 00 00 4c 8d 65 40 eb 13 0f 1f 80 00 00 00 00 49 89 dc 48 8b 5b 10 48 85 db 74 19 <48> 8b 7b 20 4c 89 fe e8 40 be fe ff 85 c0 79 e4 48 8b 5b 18 48 85
Nov 26 16:55:16 yavdr boblightd[715]: (CClientsHandler::Process)      127.0.0.1:34010 Connection closed
Nov 26 16:55:16 yavdr boblightd[715]: (CClientsHandler::RemoveClient) removing 127.0.0.1:34010

Hope it helps. With boblight-plugin disabled it seems to be stable with "-v cuvid".

ua0lnj commented 3 years ago

Try now.

schoebelh commented 3 years ago

I use the packages from seahawk's repo - and there will be a new version if YOU release a new one... ;-). Do you think the changes are worth releasing a new version or is this to much unstable...?

ua0lnj commented 3 years ago

Seahawk use git, he doesn't wait for releases.

schoebelh commented 3 years ago

Seahawk use git, he doesn't wait for releases.

You are absolutely right - the changes are available in seahawks ppa since 3 hours :-). I will test and follow up.

schoebelh commented 3 years ago

After several hours of testing - no more crashes again with -v cuvid AND boblight plugin enabled. Very nice - thank you... !