Aug 6 18:00:41 vdr systemd[1]: Started NVIDIA Persistence Daemon. Aug 6 18:00:41 vdr nvidia-persistenced: Verbose syslog connection opened Aug 6 18:00:41 vdr nvidia-persistenced: Now running with user ID 112 and group ID 118 Aug 6 18:00:41 vdr nvidia-persistenced: Started (1094) Aug 6 18:00:41 vdr lxd.activate[768]: ==> LXD never started on this system, no need to start it now Aug 6 18:00:41 vdr systemd[1]: snap.lxd.activate.service: Succeeded. Aug 6 18:00:41 vdr systemd[1]: Finished Service for snap application lxd.activate. Aug 6 18:00:41 vdr systemd[1]: Stopping NVIDIA Persistence Daemon... Aug 6 18:00:41 vdr nvidia-persistenced: Received signal 15 Aug 6 18:00:41 vdr nvidia-persistenced: PID file unlocked. Aug 6 18:00:41 vdr nvidia-persistenced: PID file closed. Aug 6 18:00:41 vdr nvidia-persistenced: The daemon no longer has permission to remove its runtime data directory /var/run/nvidia-persistenced Aug 6 18:00:41 vdr nvidia-persistenced: Shutdown (1094) Aug 6 18:00:41 vdr systemd[1]: nvidia-persistenced.service: Succeeded. Aug 6 18:00:41 vdr systemd[1]: Stopped NVIDIA Persistence Daemon. Aug 6 18:00:41 vdr systemd[1]: Started X on vt7. Aug 6 18:00:41 vdr systemd[1]: Starting Video Disk Recorder... Aug 6 18:00:41 vdr systemd[1]: Started Direct X login for user vdr. Aug 6 18:00:41 vdr systemd[1]: Starting Start a X session and a systemd user session for the vdr user... Aug 6 18:00:41 vdr systemd[1]: Finished Start a X session and a systemd user session for the vdr user. Aug 6 18:00:41 vdr systemd[1]: Created slice User Slice of UID 666. Aug 6 18:00:41 vdr systemd[1]: Starting User Runtime Directory /run/user/666... Aug 6 18:00:42 vdr systemd[1]: Finished User Runtime Directory /run/user/666. Aug 6 18:00:42 vdr systemd[1]: Starting User Manager for UID 666... Aug 6 18:00:42 vdr vdr: [1127] VDR version 2.4.7 started Aug 6 18:00:42 vdr vdr: [1127] switched to user 'vdr' Aug 6 18:00:42 vdr vdr: [1127] codeset is 'UTF-8' - known Aug 6 18:00:42 vdr avahi-daemon[703]: Leaving mDNS multicast group on interface enp3s0.IPv6 with address fe80::9e5c:8eff:fe83:4cbd. Aug 6 18:00:42 vdr avahi-daemon[703]: Joining mDNS multicast group on interface enp3s0.IPv6 with address 2002:b203:26bd:0:9e5c:8eff:fe83:4cbd. Aug 6 18:00:42 vdr avahi-daemon[703]: Registering new address record for 2002:b203:26bd:0:9e5c:8eff:fe83:4cbd on enp3s0.*. Aug 6 18:00:42 vdr avahi-daemon[703]: Withdrawing address record for fe80::9e5c:8eff:fe83:4cbd on enp3s0. Aug 6 18:00:42 vdr systemd[1108]: Reached target Paths. Aug 6 18:00:42 vdr systemd[1108]: Reached target Timers. Aug 6 18:00:42 vdr systemd[1108]: Starting D-Bus User Message Bus Socket. Aug 6 18:00:42 vdr systemd[1108]: Listening on GnuPG network certificate management daemon. Aug 6 18:00:42 vdr systemd[1108]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Aug 6 18:00:42 vdr systemd[1108]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Aug 6 18:00:42 vdr systemd[1108]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Aug 6 18:00:42 vdr systemd[1108]: Listening on GnuPG cryptographic agent and passphrase cache. Aug 6 18:00:42 vdr systemd[1108]: Listening on debconf communication socket. Aug 6 18:00:42 vdr systemd[1108]: Listening on Sound System. Aug 6 18:00:42 vdr systemd[1108]: Listening on REST API socket for snapd user session agent. Aug 6 18:00:42 vdr vdr: [1127] found 28 locales in /usr/share/locale Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'alb,sqi' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'bos' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'bul' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'chi,zho' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'eus,baq' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'iri,gle' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'jpn' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'lav' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'ltz' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'mlt' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'por' Aug 6 18:00:42 vdr vdr: [1127] no locale for language code 'smi' Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-conflictcheckonly.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-dbus2vdr.so.2.4.7 Aug 6 18:00:42 vdr systemd[1108]: Listening on D-Bus User Message Bus Socket. Aug 6 18:00:42 vdr systemd[1108]: Reached target Sockets. Aug 6 18:00:42 vdr systemd[1108]: Reached target Basic System. Aug 6 18:00:42 vdr systemd[1]: Started User Manager for UID 666. Aug 6 18:00:42 vdr systemd[1]: Started Session 1 of user vdr. Aug 6 18:00:42 vdr systemd[1108]: Starting Sound Service... Aug 6 18:00:42 vdr systemd[1108]: Started exit window manager gracefully. Aug 6 18:00:42 vdr vdr: [1127] dbus2vdr: use shutdown-hooks in /usr/share/vdr/shutdown-hooks Aug 6 18:00:42 vdr vdr: [1127] dbus2vdr: use shutdown-hooks-wrapper /usr/share/vdr-plugin-dbus2vdr/shutdown-wrapper Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-desktop.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-devstatus.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-epg2vdr.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-epgsearch.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-epgsearchonly.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-markad.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-menuorg.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-pulsecontrol.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-quickepgsearch.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-satip.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-scraper2vdr.so.2.4.7 Aug 6 18:00:42 vdr systemd[1108]: Started D-Bus User Message Bus. Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-skindesigner.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-softhddevice.so.2.4.7 Aug 6 18:00:42 vdr dbus-daemon[1136]: [session uid=666 pid=1136] AppArmor D-Bus mediation is enabled Aug 6 18:00:42 vdr vdr: [1127] loading plugin: /usr/lib/vdr/plugins/libvdr-tvguideng.so.2.4.7 Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/setup.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/sources.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/diseqc.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/scr.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/channels.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/timers.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/commands.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/reccmds.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/svdrphosts.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/remote.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/keymacros.conf Aug 6 18:00:42 vdr vdr: [1127] ERROR: unknown plugin 'osdteletext' Aug 6 18:00:42 vdr vdr: [1127] ERROR: empty key macro Aug 6 18:00:42 vdr vdr: [1127] ERROR: unknown plugin 'xineliboutput' Aug 6 18:00:42 vdr vdr: [1127] registered source parameters for 'A - ATSC' Aug 6 18:00:42 vdr vdr: [1127] registered source parameters for 'C - DVB-C' Aug 6 18:00:42 vdr vdr: [1127] registered source parameters for 'S - DVB-S' Aug 6 18:00:42 vdr vdr: [1127] registered source parameters for 'T - DVB-T' Aug 6 18:00:42 vdr vdr: [1127] no DVB device found Aug 6 18:00:42 vdr vdr: [1139] epg data reader thread started (pid=1127, tid=1139, prio=high) Aug 6 18:00:42 vdr vdr: [1139] reading EPG data from /var/cache/vdr/epg.data Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: conflictcheckonly (0.0.1): Direkter Zugriff auf epgsearch's Konflikt-Prüfungs-Menü Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: dbus2vdr (31): Steuerung des VDR über D-Bus Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: desktop (0.0.3): desktop apps menu Aug 6 18:00:42 vdr vdr: [1138] video directory scanner thread started (pid=1127, tid=1138, prio=low) Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: devstatus (0.4.1): DVB-Gerätestatus Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: epg2vdr (1.1.118-GIT): epg2vdr plugin Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: epgsearch (2.4.1): Suche im EPG nach Wiederholungen und anderem Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: epgsearchonly (0.0.1): Direkter Zugriff auf epgsearch's Suchenmenu Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: markad (0.1.6): Markiere Werbung Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: menuorg (0.5.2): Reorganisiert das Haupmenü Aug 6 18:00:42 vdr vdr: [1127] loading menuorg config file from /var/lib/vdr/plugins/menuorg.xml Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: pulsecontrol (0.2.1): Pulseaudio über das OSD steuern Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: quickepgsearch (0.0.1): Schnelle Suche nach Sendungen Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: satip (2.4.1): SAT>IP Geräte Aug 6 18:00:42 vdr bash[1100]: Openbox-Message: Keine gültige Menü-Datei "/var/lib/openbox/debian-menu.xml" vorhanden Aug 6 18:00:42 vdr vdr: [1127] cTimeMs: using monotonic clock (resolution is 1 ns) Aug 6 18:00:42 vdr vdr: [1127] new device number 1 (card index 1) Aug 6 18:00:42 vdr vdr: [1127] SATIP: Creating device CardIndex=0 DeviceNumber=0 [device 0] Aug 6 18:00:42 vdr vdr: [1127] new device number 2 (card index 2) Aug 6 18:00:42 vdr vdr: [1127] SATIP: Creating device CardIndex=1 DeviceNumber=1 [device 1] Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: scraper2vdr (1.0.11-GIT): 'scraper2vdr' plugin Aug 6 18:00:42 vdr vdr: scraper2vdr: using image directory /var/cache/vdr/epgimages/ Aug 6 18:00:42 vdr vdr: [1143] SATIP#0 section handler thread started (pid=1127, tid=1143, prio=high) Aug 6 18:00:42 vdr vdr: [1142] SATIP#0 tuner thread started (pid=1127, tid=1142, prio=high) Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: skindesigner (1.2.17): Skin Designer Aug 6 18:00:42 vdr vdr: [1147] device 2 section handler thread started (pid=1127, tid=1147, prio=low) Aug 6 18:00:42 vdr vdr: [1146] SATIP#1 section handler thread started (pid=1127, tid=1146, prio=high) Aug 6 18:00:42 vdr vdr: [1145] SATIP#1 tuner thread started (pid=1127, tid=1145, prio=high) Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: softhddevice (1.2.2): A software and GPU emulated UHD device Aug 6 18:00:42 vdr vdr: [1127] new device number 3 (card index 3) Aug 6 18:00:42 vdr vdr: [1127] initializing plugin: tvguideng (0.3.3): TV Guide for Skindesigner Skins Aug 6 18:00:42 vdr vdr: [1127] setting primary device to 3 Aug 6 18:00:42 vdr vdr: [1127] [softhddev]stopping OpenGL Worker Thread Aug 6 18:00:42 vdr vdr: [1127] [softhddev]OpenGL Worker Thread stopped Aug 6 18:00:42 vdr vdr: [1127] assuming manual start of VDR Aug 6 18:00:42 vdr vdr: [1141] SATIP discover thread started (pid=1127, tid=1141, prio=high) Aug 6 18:00:42 vdr vdr: [1127] skin "estuary4vdr" not available - using "lcars" instead Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/themes/lcars-default.theme Aug 6 18:00:42 vdr vdr: [1144] device 1 section handler thread started (pid=1127, tid=1144, prio=low) Aug 6 18:00:42 vdr vdr: [1127] starting plugin: conflictcheckonly Aug 6 18:00:42 vdr vdr: [1127] starting plugin: dbus2vdr Aug 6 18:00:42 vdr vdr: [1149] dbus2vdr: mainloop started Aug 6 18:00:42 vdr vdr: [1127] starting plugin: desktop Aug 6 18:00:42 vdr vdr: [1127] starting plugin: devstatus Aug 6 18:00:42 vdr vdr: [1127] starting plugin: epg2vdr Aug 6 18:00:42 vdr vdr: epg2vdr: Info: Calling mysql_library_init() Aug 6 18:00:42 vdr vdr: epg2vdr: Set locale to 'de_DE.UTF-8' Aug 6 18:00:42 vdr vdr: epg2vdr: detected UTF-8 Aug 6 18:00:42 vdr vdr: epg2vdr: Dictionary '/var/lib/vdr/plugins/epg2vdr//epg.dat' loaded Aug 6 18:00:42 vdr vdr: [1127] starting plugin: epgsearch Aug 6 18:00:42 vdr vdr: [1140] SATIP poller thread started (pid=1127, tid=1140, prio=high) Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/plugins/epgsearch/epgsearchcats.conf Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/plugins/epgsearch/epgsearchmenu.conf Aug 6 18:00:42 vdr vdr: [1156] epg2vdr-update thread started (pid=1127, tid=1156, prio=high) Aug 6 18:00:42 vdr vdr: epg2vdr: SQL client character now 'utf8' Aug 6 18:00:42 vdr vdr: [1149] dbus2vdr: System: connected with unique name :1.35 Aug 6 18:00:42 vdr vdr: [1149] dbus2vdr: thread-pool for handling signal-emits started Aug 6 18:00:42 vdr avahi-linker[1032]: 2021-08-06 18:00:42,350 INFO VDR started Aug 6 18:00:42 vdr vdr: epg2vdr: Info: Last update was at '06.08.21 17:23:46' Aug 6 18:00:42 vdr vdr: epg2vdr: Handler: Start reading external ids from db Aug 6 18:00:42 vdr systemd[1108]: Reloading. Aug 6 18:00:42 vdr systemd[1108]: message repeated 5 times: [ Reloading.] Aug 6 18:00:42 vdr vdr: [1139] epg data reader thread ended (pid=1127, tid=1139) Aug 6 18:00:42 vdr vdr: [1127] EPGSearch: loading /var/lib/vdr/plugins/epgsearch/epgsearch.conf Aug 6 18:00:42 vdr vdr: epg2vdr: SQL-Error in 'execute(stmt_execute)' - Incorrect string value: '\xDCRTH-D...' for column `epg2vdr`.`channelmap`.`channelname` at row 1 (1366) 'Incorrect string value: '\xDCRTH-D...' for column `epg2vdr`.`channelmap`.`channelname` at row 1' [update channelmap set channelname = ?, format = ?, merge = ?, mergesp = ?, ord = ?, unknownatvdr = ?, updflg = ?, updsp = ?, visible = ?, vps = ? where channelid = ? and extid = ? and source = ?;] Aug 6 18:00:42 vdr vdr: epg2vdr: Handler: Finished reading external id's from db, got 26 id's Aug 6 18:00:42 vdr vdr: [1127] loading /var/lib/vdr/plugins/epgsearch/timersdone.conf Aug 6 18:00:43 vdr vdr: [1127] EPGSearch: loading /var/lib/vdr/plugins/epgsearch/epgsearchupdmail.templ Aug 6 18:00:43 vdr vdr: [1127] starting plugin: epgsearchonly Aug 6 18:00:43 vdr vdr: [1127] starting plugin: markad Aug 6 18:00:43 vdr vdr: [1127] starting plugin: menuorg Aug 6 18:00:43 vdr vdr: [1127] starting plugin: pulsecontrol Aug 6 18:00:43 vdr vdr: [1189] EPGSearch: conflictcheck thread started (pid=1127, tid=1189, prio=high) Aug 6 18:00:43 vdr vdr[1127]: vdr: error while reading '/var/lib/vdr/plugins/pulsecontrol/startup.script' Aug 6 18:00:43 vdr vdr: [1127] pulsecontrol: error on reading script /var/lib/vdr/plugins/pulsecontrol/startup.script Aug 6 18:00:43 vdr vdr: [1127] starting plugin: quickepgsearch Aug 6 18:00:43 vdr vdr: [1127] starting plugin: satip Aug 6 18:00:43 vdr vdr: [1127] SATIP: Using CURL 7.68.0 rtsp Aug 6 18:00:43 vdr vdr: [1127] starting plugin: scraper2vdr Aug 6 18:00:43 vdr vdr: epg2vdr: Info: Skipping calling mysql_library_init(), it's already done! Aug 6 18:00:43 vdr vdr: scraper2vdr: Set locale to 'de_DE.UTF-8' Aug 6 18:00:43 vdr vdr: scraper2vdr: detected UTF-8 Aug 6 18:00:43 vdr vdr: [1188] EPGSearch: searchtimer thread started (pid=1127, tid=1188, prio=high) Aug 6 18:00:43 vdr vdr: scraper2vdr: Dictionary '/var/lib/vdr/plugins/scraper2vdr/epg.dat' loaded Aug 6 18:00:43 vdr vdr: [1127] starting plugin: skindesigner Aug 6 18:00:43 vdr vdr: [1190] scraper2vdr-update thread started (pid=1127, tid=1190, prio=low) Aug 6 18:00:43 vdr vdr: [1127] skindesigner: TrueColor OSD found Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using libskindesigner API Version 0.1.2 Aug 6 18:00:43 vdr vdr: [1127] skindesigner: plugin setup uses libskindesigner API Version 0.1.2 Aug 6 18:00:43 vdr vdr: [1127] skindesigner: plugin setup has registered 1 menus Aug 6 18:00:43 vdr vdr: [1127] skindesigner: skinsetup template successfully registered at skindesigner, id 0 Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using Skin Directory /usr/share/vdr/plugins/skindesigner/skins/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using Installer Skin Directory /var/lib/vdr/plugins/skindesigner/installerskins/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using common ChannelLogo Directory /usr/share/vdr/plugins/skindesigner/logos/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using EPG Images Directory /var/cache/vdr/epgimages/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner 2 skins found in /usr/share/vdr/plugins/skindesigner/skins/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner 0 skins found in /var/lib/vdr/plugins/skindesigner/installerskins/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner: skin metrixhd started Aug 6 18:00:43 vdr systemd[1108]: Reloading. Aug 6 18:00:43 vdr vdr: [1127] skindesigner: skin estuary4vdr started Aug 6 18:00:43 vdr vdr: [1127] starting plugin: softhddevice Aug 6 18:00:43 vdr vdr: [softhddev] ready detached Aug 6 18:00:43 vdr vdr: [1127] starting plugin: tvguideng Aug 6 18:00:43 vdr vdr: [1127] skindesigner: plugin tvguideng uses libskindesigner API Version 0.1.2 Aug 6 18:00:43 vdr vdr: [1127] skindesigner: plugin tvguideng has registered 5 views with 23 viewelements and 11 viewgrids Aug 6 18:00:43 vdr vdr: [1127] tvguideng: successfully registered at skindesigner, id 1 Aug 6 18:00:43 vdr vdr: [1127] setting current skin to "estuary4vdr" Aug 6 18:00:43 vdr vdr: [1127] loading /var/lib/vdr/themes/estuary4vdr-default.theme Aug 6 18:00:43 vdr vdr: [1127] remote control XKeySym - keys known Aug 6 18:00:43 vdr vdr: [1127] remote control LIRC - keys known Aug 6 18:00:43 vdr vdr: [1127] loading /var/cache/vdr/cam.data Aug 6 18:00:43 vdr vdr: [1193] LIRC remote control thread started (pid=1127, tid=1193, prio=high) Aug 6 18:00:43 vdr systemd[1108]: Starting Detect second DISPLAY using xrandr... Aug 6 18:00:43 vdr systemd[1108]: Started LIRC command handler. Aug 6 18:00:43 vdr detect-second-display[1204]: Can't open display :0.1 Aug 6 18:00:43 vdr systemd[1108]: Finished Detect second DISPLAY using xrandr. Aug 6 18:00:43 vdr systemd[1108]: Condition check resulted in Start openbox on the second DISPLAY if it exists being skipped. Aug 6 18:00:43 vdr systemd[1108]: Started manage VDR frontends. Aug 6 18:00:43 vdr set-cpufreq[719]: Setting powersave scheduler for all CPUs Aug 6 18:00:43 vdr systemd[1]: ondemand.service: Succeeded. Aug 6 18:00:43 vdr vdr: [1141] SATIP: Adding server '192.168.4.24|DVBS2-4|minisatip' Bind: default Filters: none CI: yes Quirks: RtpOverTcp,CiXpmt Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:yaVDRFrontend:init lirc connection on /var/run/lirc/lircd Aug 6 18:00:43 vdr vdr: [1127] switching to channel 151 S19.2E-1-1003-13232 (Service 13232) Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:VDRFrontend:init VDRFrontend with name 'VDR-Frontend and fe_typevdr' Aug 6 18:00:43 vdr yavdr-frontend[1207]: INFO:pydbus2vdr:VDR Status: running Aug 6 18:00:43 vdr vdr: [1149] dbus2vdr: thread-pool for handling method-calls started Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:SystemdUnitFrontend:init SystemdUnit with name: kodi and fe_type: unit Aug 6 18:00:43 vdr systemd[1]: dmesg.service: Succeeded. Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:SystemdUnitFrontend:set_unit_name: kodi.service Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:SystemdUnitFrontend:init SystemdUnit with name: firefox and fe_type: app Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:SystemdUnitFrontend:set_unit_name: app@firefox.service Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:SystemdUnitFrontend:init SystemdUnit with name: debian-xterm.desktop and fe_type: app Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:SystemdUnitFrontend:set_unit_name: app@debian\x2dxterm.desktop.service Aug 6 18:00:43 vdr yavdr-frontend[1207]: DEBUG:yaVDRFrontend:set_background with options path: /usr/share/yavdr/images/yavdr_logo.png, fill: False Aug 6 18:00:43 vdr vdr: [1234] device 1 receiver thread started (pid=1127, tid=1234, prio=high) Aug 6 18:00:43 vdr systemd[1]: Started Video Disk Recorder. Aug 6 18:00:43 vdr vdr: [1235] SVDRP server handler thread started (pid=1127, tid=1235, prio=low) Aug 6 18:00:43 vdr systemd[1]: Starting vdr-epg-daemon manages EPG data in a MySQL database... Aug 6 18:00:43 vdr systemd[1]: Started vdr-net-monitor. Aug 6 18:00:43 vdr vdr: [1235] SVDRP vdr opening port 6419/tcp Aug 6 18:00:43 vdr vdr: [1235] SVDRP vdr listening on port 6419/tcp Aug 6 18:00:43 vdr vdr: [1127] OSD size changed to 1920x1080 @ 1 Aug 6 18:00:43 vdr vdr: [1127] skindesigner: initializing skin estuary4vdr Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using decimal point , Aug 6 18:00:43 vdr epgd: Set locale to 'de_DE.UTF-8' Aug 6 18:00:43 vdr epgd: Calling sd_notify(READY=1$STATUS=Ready$MAINPID=1236$) Aug 6 18:00:43 vdr systemd[1]: Started vdr-epg-daemon manages EPG data in a MySQL database. Aug 6 18:00:43 vdr systemd[1]: Starting epghttpd provides a webinterface for epg data... Aug 6 18:00:43 vdr epgd: Info: Systemd watchdog not configured, epgd won't be sending keep-alive messages! Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using channel logo path /usr/share/vdr/plugins/skindesigner/logos/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using icon path /usr/share/vdr/plugins/skindesigner/skins/estuary4vdr/themes/default/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using skinparts path /usr/share/vdr/plugins/skindesigner/skins/estuary4vdr/themes/default/skinparts/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using svgtemplate path /usr/share/vdr/plugins/skindesigner/skins/estuary4vdr/svgtemplates/ Aug 6 18:00:43 vdr vdr: [1127] skindesigner: using language de_DE Aug 6 18:00:43 vdr epgd: Loading uuid from '/etc/epgd/uuid' succeeded [BF504A79-8D28-478C-95FE-9B1F49ED27C7] Aug 6 18:00:43 vdr epgd: Dictionary '/etc/epgd/epg.dat' loaded Aug 6 18:00:43 vdr epgd: Initialize python script '/etc/epgd/recording.py' Aug 6 18:00:43 vdr epghttpd: Set locale to 'de_DE.UTF-8' Aug 6 18:00:43 vdr epghttpd: detected UTF-8 Aug 6 18:00:43 vdr epghttpd: Read 26 option from /etc/epgd/epgd.conf Aug 6 18:00:43 vdr epghttpd: Log level is set to (1) Aug 6 18:00:43 vdr epghttpd: Initialize python script '/etc/epgd/recording.py' Aug 6 18:00:43 vdr epgd: Loading plugin: /usr/lib/epgd/plugins/libepgd-epgdata.so Aug 6 18:00:43 vdr epghttpd: Initialize python script '/etc/epgd/recording.py' Aug 6 18:00:43 vdr epghttpd: Dictionary '/etc/epgd/epg.dat' loaded Aug 6 18:00:43 vdr epghttpd: Info: Calling mysql_library_init() Aug 6 18:00:43 vdr epghttpd: Connecting to database at 'localhost:3306' Aug 6 18:00:43 vdr epghttpd: Calling mysql_init(1238) Aug 6 18:00:43 vdr epghttpd: SQL client character now 'utf8' Aug 6 18:00:43 vdr epgd: Read 26 option from /etc/epgd/epgd.conf Aug 6 18:00:43 vdr epgd: Using syslog facility 'user' (8), log level set to (1) Aug 6 18:00:43 vdr epgd: Info: Calling mysql_library_init() Aug 6 18:00:43 vdr epgd: Info: Stylesheet '/etc/epgd/epgdata-utf-8.xsl' loaded Aug 6 18:00:43 vdr epgd: Checking database connection ... Aug 6 18:00:43 vdr epgd: Calling mysql_init(1236) Aug 6 18:00:43 vdr epgd: SQL client character now 'utf8' Aug 6 18:00:43 vdr epgd: Checking table structure and indices ... Aug 6 18:00:43 vdr epgd: Checking table 'analyse' Aug 6 18:00:43 vdr epgd: Checking table 'channelmap' Aug 6 18:00:43 vdr epgd: Checking table 'components' Aug 6 18:00:43 vdr epghttpd: Calling mysql_init(1238) Aug 6 18:00:43 vdr vdr: [1127] skindesigner: templates successfully validated and parsed Aug 6 18:00:43 vdr epgd: Checking table 'episodes' Aug 6 18:00:43 vdr epghttpd: Starting http server ... Aug 6 18:00:43 vdr epghttpd: Listener at port 9999 established, waiting for connections Aug 6 18:00:43 vdr systemd[1]: Started epghttpd provides a webinterface for epg data. Aug 6 18:00:43 vdr epghttpd: Calling sd_notify(READY=1$STATUS=Ready$MAINPID=1238$) Aug 6 18:00:43 vdr systemd[1]: Reached target Multi-User System. Aug 6 18:00:43 vdr epghttpd: Info: Systemd watchdog not configured, epgd won't be sending keep-alive messages! Aug 6 18:00:43 vdr systemd[1]: Reached target Graphical Interface. Aug 6 18:00:43 vdr systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 6 18:00:43 vdr epgd: Checking table 'events' Aug 6 18:00:43 vdr systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 6 18:00:43 vdr systemd[1]: Finished Update UTMP about System Runlevel Changes. Aug 6 18:00:43 vdr systemd[1]: Startup finished in 6.095s (kernel) + 10.719s (userspace) = 16.815s. Aug 6 18:00:43 vdr epgd: Checking table 'fileref' Aug 6 18:00:43 vdr epgd: Checking table 'imagerefs' Aug 6 18:00:43 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:43 vdr epgd: Checking table 'images' Aug 6 18:00:43 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:43 vdr vdr: message repeated 2 times: [ [1127] [softhddev]detached - OpenGl Worker Thread not tried to start] Aug 6 18:00:43 vdr epgd: Checking table 'messages' Aug 6 18:00:43 vdr epgd: Checking table 'movie' Aug 6 18:00:43 vdr epgd: Checking table 'movie_actor' Aug 6 18:00:43 vdr epgd: Checking table 'movie_actors' Aug 6 18:00:43 vdr epgd: Checking table 'movie_media' Aug 6 18:00:43 vdr epgd: Checking table 'parameters' Aug 6 18:00:43 vdr epgd: Checking table 'recordingdirs' Aug 6 18:00:43 vdr epgd: Checking table 'recordingimages' Aug 6 18:00:43 vdr epgd: Checking table 'recordinglist' Aug 6 18:00:43 vdr epgd: Checking table 'searchtimers' Aug 6 18:00:43 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:43 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:43 vdr epgd: Checking table 'series' Aug 6 18:00:43 vdr epgd: Checking table 'series_actor' Aug 6 18:00:43 vdr epgd: Checking table 'series_episode' Aug 6 18:00:43 vdr epgd: Checking table 'series_media' Aug 6 18:00:43 vdr epgd: Checking table 'snapshot' Aug 6 18:00:43 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:43 vdr vdr: message repeated 3 times: [ [1127] [softhddev]detached - OpenGl Worker Thread not tried to start] Aug 6 18:00:43 vdr epgd: Checking table 'timers' Aug 6 18:00:43 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:43 vdr vdr: message repeated 5 times: [ [1127] [softhddev]detached - OpenGl Worker Thread not tried to start] Aug 6 18:00:43 vdr epgd: Checking table 'timersdone' Aug 6 18:00:43 vdr epgd: Checking table 'useevents' Aug 6 18:00:43 vdr epgd: Checking table 'users' Aug 6 18:00:44 vdr epgd: Checking table 'vdrs' Aug 6 18:00:44 vdr epgd: Closing mysql connection and calling mysql_thread_end(1236) Aug 6 18:00:44 vdr epgd: Checking table structure and indices succeeded Aug 6 18:00:44 vdr epgd: Calling mysql_init(1236) Aug 6 18:00:44 vdr epgd: State now 'init' Aug 6 18:00:44 vdr epgd: Loading '/etc/epgd/channelmap.conf' Aug 6 18:00:44 vdr epgd: 26 channel mappings read. Aug 6 18:00:44 vdr epgd: Calling mysql_init(1236) Aug 6 18:00:44 vdr epgd: Using scraping language de Aug 6 18:00:44 vdr epgd: TVDB scraper connected Aug 6 18:00:44 vdr epgd: MOVIEDB scraper connected Aug 6 18:00:44 vdr epgd: Info: No external events on database, force initial check! Aug 6 18:00:44 vdr epgd: Scheduled next update in 10 second(s) Aug 6 18:00:44 vdr epgd: State now 'standby' Aug 6 18:00:44 vdr epgd: Error: SVDRPCL: Connecting to '192.168.4.154:6419' Connection refused failed Aug 6 18:00:44 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:44 vdr vdr: message repeated 7 times: [ [1127] [softhddev]detached - OpenGl Worker Thread not tried to start] Aug 6 18:00:44 vdr vdr: epg2vdr: Handler: Init handler instance for thread 1144 Aug 6 18:00:44 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:45 vdr vdr: message repeated 59 times: [ [1127] [softhddev]detached - OpenGl Worker Thread not tried to start] Aug 6 18:00:45 vdr epgd: TCC: Starting timer conflict check Aug 6 18:00:45 vdr epgd: TCC: Finished timer conflict check with (0) conflicts Aug 6 18:00:45 vdr vdr: [1127] skindesigner: templates and images cached Aug 6 18:00:45 vdr vdr: [1127] skindesigner: cached 76 icons - size internal mem 1,28MB, high level mem 0,00MB Aug 6 18:00:45 vdr vdr: [1127] skindesigner: cached 201 logos - size 7609,99MB internal mem Aug 6 18:00:45 vdr vdr: [1127] skindesigner: cached 8 skinparts - size internal mem 13,86MB, high level mem 0,00MB Aug 6 18:00:45 vdr vdr: [1127] skindesigner: templates loaded and caches created - needed 1728 ms Aug 6 18:00:45 vdr vdr: [1127] [softhddev]detached - OpenGl Worker Thread not tried to start Aug 6 18:00:45 vdr vdr: [1127] [softhddev]OpenGl Thread not started successfully, using Dummy OSD Aug 6 18:00:45 vdr vdr: [1127] timer 0 (148 2013-2115 'Der Lehrer~Der Lehrer~Schwing die Hufen Blondie!') set to event So. 08.08.2021 20:15-21:05 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (11 2013-2115 'Der Lehrer~Der Lehrer~Schwing die Hufen Blondie!') set to event So. 08.08.2021 20:15-21:05 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (11 2103-2210 'Der Lehrer~Der Lehrer~Hab ich ´nen bösen Zwilling?') set to event So. 08.08.2021 21:05-22:00 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (148 2103-2210 'Der Lehrer~Der Lehrer~Hab ich ´nen bösen Zwilling?') set to event So. 08.08.2021 21:05-22:00 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (11 2158-2300 'Der Lehrer~Der Lehrer~Verknallt? So´n Quatsch!') set to event So. 08.08.2021 22:00-22:50 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (148 2158-2300 'Der Lehrer~Der Lehrer~Verknallt? So´n Quatsch!') set to event So. 08.08.2021 22:00-22:50 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (11 0033-0130 'Der Lehrer~Der Lehrer~Schwing die Hufen Blondie!') set to event Mo. 09.08.2021 00:35-01:20 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (148 0033-0130 'Der Lehrer~Der Lehrer~Schwing die Hufen Blondie!') set to event Mo. 09.08.2021 00:35-01:20 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (11 0118-0215 'Der Lehrer~Der Lehrer~Hab ich ´nen bösen Zwilling?') set to event Mo. 09.08.2021 01:20-02:05 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (148 0118-0215 'Der Lehrer~Der Lehrer~Hab ich ´nen bösen Zwilling?') set to event Mo. 09.08.2021 01:20-02:05 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (11 0203-0300 'Der Lehrer~Der Lehrer~Verknallt? So´n Quatsch!') set to event Mo. 09.08.2021 02:05-02:50 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 0 (148 0203-0300 'Der Lehrer~Der Lehrer~Verknallt? So´n Quatsch!') set to event Mo. 09.08.2021 02:05-02:50 'Der Lehrer' Aug 6 18:00:45 vdr vdr: epg2vdr: Answer 'Epg2Vdr_Timer_Service-v1.0' call with 12 timers, duration was (3 ms) Aug 6 18:00:45 vdr vdr: [1349] animator thread thread started (pid=1127, tid=1349, prio=high) Aug 6 18:00:45 vdr vdr: [1127] timer 1 (11 2013-2115 'Der Lehrer~Der Lehrer~Schwing die Hufen Blondie!') set to event So. 08.08.2021 20:15-21:05 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 2 (148 2013-2115 'Der Lehrer~Der Lehrer~Schwing die Hufen Blondie!') set to event So. 08.08.2021 20:15-21:05 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 3 (11 2103-2210 'Der Lehrer~Der Lehrer~Hab ich ´nen bösen Zwilling?') set to event So. 08.08.2021 21:05-22:00 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 4 (148 2103-2210 'Der Lehrer~Der Lehrer~Hab ich ´nen bösen Zwilling?') set to event So. 08.08.2021 21:05-22:00 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 5 (148 0203-0300 'Der Lehrer~Der Lehrer~Verknallt? So´n Quatsch!') set to event Mo. 09.08.2021 02:05-02:50 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 6 (148 2158-2300 'Der Lehrer~Der Lehrer~Verknallt? So´n Quatsch!') set to event So. 08.08.2021 22:00-22:50 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 7 (148 0033-0130 'Der Lehrer~Der Lehrer~Schwing die Hufen Blondie!') set to event Mo. 09.08.2021 00:35-01:20 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 8 (148 0118-0215 'Der Lehrer~Der Lehrer~Hab ich ´nen bösen Zwilling?') set to event Mo. 09.08.2021 01:20-02:05 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 9 (11 2158-2300 'Der Lehrer~Der Lehrer~Verknallt? So´n Quatsch!') set to event So. 08.08.2021 22:00-22:50 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 10 (11 0033-0130 'Der Lehrer~Der Lehrer~Schwing die Hufen Blondie!') set to event Mo. 09.08.2021 00:35-01:20 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 11 (11 0118-0215 'Der Lehrer~Der Lehrer~Hab ich ´nen bösen Zwilling?') set to event Mo. 09.08.2021 01:20-02:05 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] timer 12 (11 0203-0300 'Der Lehrer~Der Lehrer~Verknallt? So´n Quatsch!') set to event Mo. 09.08.2021 02:05-02:50 'Der Lehrer' Aug 6 18:00:45 vdr vdr: [1127] skindesigner: w 0 h 0 mode changed to 1 Aug 6 18:00:45 vdr yavdr-frontend[1207]: INFO:pydbus2vdr:VDR Status: running Aug 6 18:00:45 vdr vdr: [1351] dbus2vdr: use of deprecated interface: 'List' should be called with the interface 'de.tvdr.vdr.pluginmanager'! Aug 6 18:00:45 vdr avahi-linker[1032]: 2021-08-06 18:00:45,382 INFO Update recdir via dbus: 0 update of recordings triggered Aug 6 18:00:45 vdr yavdr-frontend[1207]: DEBUG:softhddevice:False Aug 6 18:00:45 vdr yavdr-frontend[1207]: INFO:softhddevice:use_pasuspend is False Aug 6 18:00:45 vdr yavdr-frontend[1207]: DEBUG:VDRFrontend:start_t has value StartType.MANUAL Aug 6 18:00:45 vdr yavdr-frontend[1207]: DEBUG:VDRFrontend:attach_on_startup has value auto Aug 6 18:00:45 vdr yavdr-frontend[1207]: DEBUG:yaVDRFrontend:set_background with options path: /usr/share/yavdr/images/yavdr_logo.png, fill: False Aug 6 18:00:45 vdr yavdr-frontend[1207]: DEBUG:VDRFrontend:user is active: True Aug 6 18:00:45 vdr yavdr-frontend[1207]: DEBUG:softhddevice:check_state(): got status code: 912 Aug 6 18:00:45 vdr yavdr-frontend[1207]: DEBUG:softhddevice:status: softhddevice is detached Aug 6 18:00:45 vdr yavdr-frontend[1207]: DEBUG:softhddevice:check_state(): got status code: 912 Aug 6 18:00:45 vdr vdr: audio: 'alsa' output module used Aug 6 18:00:45 vdr systemd[1108]: Started Sound Service. Aug 6 18:00:45 vdr systemd[1108]: Reached target Main User Target. Aug 6 18:00:45 vdr vdr: audio/alsa: supports pause: yes Aug 6 18:00:45 vdr systemd[1108]: Reached target yaVDR Desktop. Aug 6 18:00:45 vdr systemd[1108]: Startup finished in 3.691s. Aug 6 18:00:45 vdr vdr: [1144] channel 148 (RTLplus Austria) event Fr. 06.08.2021 17:45-18:30 'Im Namen des Gesetzes' status 4 Aug 6 18:00:45 vdr vdr: audio: 44100Hz supports 1 2 3 4 5 6 7 8 channels Aug 6 18:00:45 vdr vdr: audio: 48000Hz supports 1 2 3 4 5 6 7 8 channels Aug 6 18:00:45 vdr vdr: audio: 192000Hz supports 1 2 3 4 5 6 7 8 channels Aug 6 18:00:46 vdr vdr: video/vdpau: VDPAU API version: 1 Aug 6 18:00:46 vdr vdr: video/vdpau: VDPAU information: NVIDIA VDPAU Driver Shared Library 390.144 Wed Jun 2 22:57:50 UTC 2021 Aug 6 18:00:46 vdr vdr: video/vdpau: highest supported high quality scaling 1 Aug 6 18:00:46 vdr vdr: video/vdpau: feature deinterlace temporal supported Aug 6 18:00:46 vdr vdr: video/vdpau: feature deinterlace temporal spatial supported Aug 6 18:00:46 vdr vdr: video/vdpau: attribute skip chroma deinterlace supported Aug 6 18:00:46 vdr vdr: video/vdpau: 4:2:0 chroma format with 4096x4096 supported Aug 6 18:00:46 vdr vdr: video/vdpau: 4:2:2 chroma format with 4096x4096 supported Aug 6 18:00:46 vdr vdr: video/vdpau: 8bit BGRA format with 16384x16384 supported Aug 6 18:00:46 vdr vdr: video/vdpau: 10bit RGBA format with 16384x16384 supported Aug 6 18:00:46 vdr vdr: video/vdpau: created osd output surface 1920x1080 with id 0x00000009 Aug 6 18:00:46 vdr yavdr-frontend[1207]: DEBUG:softhddevice:change_state with command atta and options "-d :0" to attached Aug 6 18:00:46 vdr yavdr-frontend[1207]: DEBUG:softhddevice:check_state(): got status code: 910 Aug 6 18:00:46 vdr yavdr-frontend[1207]: DEBUG:softhddevice:softhddevice successfully attached Aug 6 18:00:46 vdr yavdr-frontend[1207]: DEBUG:softhddevice:current PrimaryDevice is softhddevice-openglosd (Index: 2, Number: 2, hasDecoder: True, isPrimary: True) Aug 6 18:00:46 vdr yavdr-frontend[1207]: DEBUG:softhddevice:softhddevice is the primary device Aug 6 18:00:46 vdr yavdr-frontend[1207]: DEBUG:softhddevice:needed 0.001 s to switch primary device Aug 6 18:00:49 vdr vdr: [1138] video directory scanner thread ended (pid=1127, tid=1138) Aug 6 18:00:50 vdr vdr: [1349] animator thread thread ended (pid=1127, tid=1349) Aug 6 18:00:53 vdr vdr: scraper2vdr: Got UUID 'D6788319-C329-45F4-B8DE-A3213B2AC4C1' by epg2vdr Aug 6 18:00:53 vdr vdr: scraper2vdr: Trying to re-connect to database! Aug 6 18:00:53 vdr vdr: scraper2vdr: Calling mysql_init(1190) Aug 6 18:00:53 vdr vdr: scraper2vdr: Connection established successfull! Aug 6 18:00:53 vdr vdr: scraper2vdr: Loading Movies information from Database... Aug 6 18:00:53 vdr vdr: scraper2vdr: Got 189 new/updated Movies in 0s from Database (new max scrsp: 1627844588) Aug 6 18:00:53 vdr vdr: scraper2vdr: Loading Movies content from Database... Aug 6 18:00:53 vdr vdr: scraper2vdr: Got 0 new/updated Image information in 0s from Database Aug 6 18:00:53 vdr vdr: scraper2vdr: Loading Series information from Database... Aug 6 18:00:53 vdr vdr: scraper2vdr: Got 0 new/updated Series in 0s from Database (new max scrsp: 0) Aug 6 18:00:53 vdr vdr: scraper2vdr: Loading Series content from Database... Aug 6 18:00:53 vdr vdr: scraper2vdr: Got 0 new/updated Episodes and 0 new/updated Image information (including 0 possible not available season poster) in 0s from Database Aug 6 18:00:53 vdr vdr: scraper2vdr: Loading Image content from Database... Aug 6 18:00:53 vdr vdr: scraper2vdr: Got 0 new/updated Images (found 0 not available images) in 0s from Database Aug 6 18:00:54 vdr epgd: State now 'busy (events)' Aug 6 18:00:54 vdr epgd: Error: SVDRPCL: Connecting to '192.168.4.154:6419' Connection refused failed Aug 6 18:00:56 vdr vdr: [1189] EPGSearch: timer conflict check started Aug 6 18:00:56 vdr vdr: [1189] EPGSearch: timer conflict check finished Aug 6 18:00:56 vdr vdr: [1188] EPGSearch: search timer update started Aug 6 18:00:56 vdr vdr: [1188] timer 0 (11 2005-2115 'Der Lehrer') set to event So. 08.08.2021 20:15-21:05 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (148 2005-2115 'Der Lehrer') set to event So. 08.08.2021 20:15-21:05 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (11 2055-2210 'Der Lehrer') set to event So. 08.08.2021 21:05-22:00 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (148 2055-2210 'Der Lehrer') set to event So. 08.08.2021 21:05-22:00 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (11 2150-2300 'Der Lehrer') set to event So. 08.08.2021 22:00-22:50 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (148 2150-2300 'Der Lehrer') set to event So. 08.08.2021 22:00-22:50 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (11 0025-0130 'Der Lehrer') set to event Mo. 09.08.2021 00:35-01:20 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (148 0025-0130 'Der Lehrer') set to event Mo. 09.08.2021 00:35-01:20 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (11 0110-0215 'Der Lehrer') set to event Mo. 09.08.2021 01:20-02:05 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (148 0110-0215 'Der Lehrer') set to event Mo. 09.08.2021 01:20-02:05 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (11 0155-0300 'Der Lehrer') set to event Mo. 09.08.2021 02:05-02:50 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] timer 0 (148 0155-0300 'Der Lehrer') set to event Mo. 09.08.2021 02:05-02:50 'Der Lehrer' Aug 6 18:00:56 vdr vdr: [1188] EPGSearch: search timer update finished Aug 6 18:00:56 vdr epgd: Starting cleanup of events Aug 6 18:00:56 vdr epgd: Delete fileref [substr(name,1,8) <= '20210805' and source = 'epgdata'] Aug 6 18:00:56 vdr epgd: Delete events [starttime+duration < 1628244056] Aug 6 18:00:56 vdr epgd: Delete useevents [cnt_starttime+cnt_duration < 1628244056] Aug 6 18:00:56 vdr epgd: Cleanup of events finished Aug 6 18:00:56 vdr epgd: Starting cleanup of failed timer actions, older than 10 days Aug 6 18:00:56 vdr epgd: Cleanup of timer actions finished Aug 6 18:00:56 vdr epgd: Calling sd_notify(STATUS=Busy, started Update) Aug 6 18:00:56 vdr epgd: EPG Update started Aug 6 18:00:56 vdr epgd: EPG Update finished, loaded 0 files (0.000 KB), 0 non-updates skipped, 0 rejected due to format error. Aug 6 18:00:56 vdr epgd: Calling sd_notify(STATUS=Ready) Aug 6 18:00:56 vdr epgd: Starting 'update' episode download ... Aug 6 18:00:57 vdr epgd: Got 'Setting encoding to utf8' Aug 6 18:00:57 vdr epgd: Requesting episode changes of last 193 minutes Aug 6 18:00:57 vdr epgd: Received 0 episode files Aug 6 18:00:57 vdr epgd: Starting episode lookup ... Aug 6 18:00:58 vdr epgd: Lookup done for 2629 series, matched 0 parts by compare and 0 parts by lv in 1 seconds; Updated 0 Aug 6 18:00:58 vdr epgd: Calling 'mergeepg' Aug 6 18:00:58 vdr epgd: 'mergeepg' suceeded Aug 6 18:00:58 vdr epgd: State now 'busy (images)' Aug 6 18:00:58 vdr epgd: Error: SVDRPCL: Connecting to '192.168.4.154:6419' Connection refused failed Aug 6 18:00:58 vdr epgd: Starting cleanup of imagerefs Aug 6 18:00:58 vdr epgd: Starting cleanup of images Aug 6 18:00:58 vdr epgd: Image cleanup finished Aug 6 18:00:58 vdr epgd: Start download of new images Aug 6 18:00:58 vdr epgd: Loaded 0 images (0.000 KB), checked 0; 0 failed to load in 0 seconds Aug 6 18:00:58 vdr epgd: Checking timers against actual epg and searchtimer settings Aug 6 18:00:58 vdr epgd: Timers check done Aug 6 18:00:58 vdr epgd: AUTOTIMER: Updating searchtimers due to 'external epg update' (force) Aug 6 18:00:58 vdr epgd: AUTOTIMER: Update done after 1 ms, created (0) timers Aug 6 18:00:58 vdr epgd: State now 'busy (scraping)' Aug 6 18:00:58 vdr epgd: Error: SVDRPCL: Connecting to '192.168.4.154:6419' Connection refused failed Aug 6 18:01:00 vdr epgd: Scraping new series and episodes Aug 6 18:01:00 vdr epgd: Update of series and episodes done in 0 s, downloaded 0.000 KB Aug 6 18:01:00 vdr epgd: 0 new series events to scrap in db Aug 6 18:01:00 vdr epgd: 0 of 0 series episodes scraped in 0 s, downloaded 0.000 KB Aug 6 18:01:00 vdr epgd: Scraping new movies Aug 6 18:01:00 vdr epgd: 0 new movies to scrap in db Aug 6 18:01:00 vdr epgd: 0 of 0 movies scraped in 0 s, downloaded 0.000 KB Aug 6 18:01:00 vdr epgd: cleaning up series... Aug 6 18:01:00 vdr epgd: 0 outdated series deleted Aug 6 18:01:00 vdr epgd: cleaning up movies... Aug 6 18:01:00 vdr epgd: 0 outdated movies deleted Aug 6 18:01:00 vdr epgd: Scheduled next update in 12 hour(s) Aug 6 18:01:00 vdr epgd: State now 'standby' Aug 6 18:01:00 vdr epgd: Error: SVDRPCL: Connecting to '192.168.4.154:6419' Connection refused failed Aug 6 18:01:43 vdr vdr: epg2vdr: Cleanup deleted recordings at database (forced) Aug 6 18:01:43 vdr vdr: epg2vdr: Info: Marked 0 recordings as deleted Aug 6 18:01:43 vdr vdr: epg2vdr: Updating recording list table Aug 6 18:01:43 vdr vdr: epg2vdr: Info: Found 222 recordings; 0 inserted; 0 updated and 15 directories Aug 6 18:01:43 vdr vdr: epg2vdr: Detected epgd state 'standby' (1) Aug 6 18:01:43 vdr vdr: epg2vdr: Change handler state to 'active' Aug 6 18:01:43 vdr vdr: epg2vdr: Updating table timers (and remove deleted and finished timers older than 2 days) Aug 6 18:01:43 vdr vdr: epg2vdr: Updating table timers done Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111719-273' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111719-283' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111719-293' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111719-303' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111797-263' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-1-342' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-1-352' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-1-363' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-1-373' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-312' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-313' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-322' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-323' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-332' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-333' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-2-252' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-2-253' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-2-262' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-53-1097-2042' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-53-1097-2045' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Updated changes since '06.08.2021 17:23:46'; 26 channels, 95 events (0 deletions) in 36 ms Aug 6 18:01:43 vdr vdr: epg2vdr: Checking switch timer actions .. Aug 6 18:01:43 vdr vdr: epg2vdr: Checking pending timer actions .. Aug 6 18:01:43 vdr vdr: epg2vdr: .. nothing to do Aug 6 18:01:43 vdr vdr: epg2vdr: --- EPG 'update' started --- Aug 6 18:01:43 vdr vdr: epg2vdr: Exit image cleanup to avoid deleting of all images on empty imagerefs table Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111719-273' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111719-283' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111719-293' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111719-303' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-0-111797-263' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-1-342' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-1-352' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-1-363' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-1-373' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-312' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-313' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-322' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-323' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-332' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-17-333' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-2-252' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-2-253' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-133-2-262' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-53-1097-2042' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Error: Channel with ID 'S19.2E-53-1097-2045' don't exist on this VDR Aug 6 18:01:43 vdr vdr: epg2vdr: Updated changes since '06.08.2021 17:23:46'; 26 channels, 95 events (0 deletions) in 5 ms Aug 6 18:01:44 vdr systemd[1]: Created slice User Slice of UID 1000. Aug 6 18:01:44 vdr systemd[1]: Starting User Runtime Directory /run/user/1000... Aug 6 18:01:44 vdr systemd[1]: Finished User Runtime Directory /run/user/1000. Aug 6 18:01:44 vdr systemd[1]: Starting User Manager for UID 1000... Aug 6 18:01:44 vdr vdr: epg2vdr: Load images from database Aug 6 18:01:44 vdr vdr: epg2vdr: Got 0 images from database in 0 seconds (0 updates, 0 new) and created 0 links Aug 6 18:01:44 vdr vdr: epg2vdr: --- EPG update finished --- Aug 6 18:01:44 vdr systemd[1417]: Reached target Paths. Aug 6 18:01:44 vdr systemd[1417]: Reached target Timers. Aug 6 18:01:44 vdr systemd[1417]: Starting D-Bus User Message Bus Socket. Aug 6 18:01:44 vdr systemd[1417]: Listening on GnuPG network certificate management daemon. Aug 6 18:01:44 vdr systemd[1417]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Aug 6 18:01:44 vdr systemd[1417]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Aug 6 18:01:44 vdr systemd[1417]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Aug 6 18:01:44 vdr systemd[1417]: Listening on GnuPG cryptographic agent and passphrase cache. Aug 6 18:01:44 vdr systemd[1417]: Listening on debconf communication socket. Aug 6 18:01:44 vdr systemd[1417]: Listening on Sound System. Aug 6 18:01:44 vdr systemd[1417]: Listening on REST API socket for snapd user session agent. Aug 6 18:01:44 vdr systemd[1417]: Listening on D-Bus User Message Bus Socket. Aug 6 18:01:44 vdr systemd[1417]: Reached target Sockets. Aug 6 18:01:44 vdr systemd[1417]: Reached target Basic System. Aug 6 18:01:44 vdr systemd[1]: Started User Manager for UID 1000. Aug 6 18:01:44 vdr systemd[1417]: Starting Sound Service... Aug 6 18:01:44 vdr systemd[1]: Started Session 3 of user stefan. Aug 6 18:01:44 vdr systemd[1417]: Started D-Bus User Message Bus. Aug 6 18:01:44 vdr dbus-daemon[1453]: [session uid=1000 pid=1453] AppArmor D-Bus mediation is enabled Aug 6 18:01:44 vdr systemd[1417]: Started Sound Service. Aug 6 18:01:44 vdr systemd[1417]: Reached target Main User Target. Aug 6 18:01:44 vdr systemd[1417]: Startup finished in 186ms. Aug 6 18:02:06 vdr vdr: epg2vdr: Handler: Init handler instance for thread 1147 Aug 6 18:02:09 vdr vdr: [1147] changing pids of channel 82 (Test-R) from 501+501=2:502=deu@3:0:0 to 401+401=2:402=deu@3:0:0