Apr  1 21:05:30 raspiVDR systemd[1]: Starting Video Disk Recorder...
Apr  1 21:05:30 raspiVDR vdr: [3545] VDR version 2.4.0 started
Apr  1 21:05:30 raspiVDR vdr: [3545] switched to user 'vdr'
Apr  1 21:05:30 raspiVDR vdr: [3545] codeset is 'UTF-8' - known
Apr  1 21:05:31 raspiVDR vdr: [3545] found 28 locales in /usr/share/locale
Apr  1 21:05:31 raspiVDR vdr: [3545] loading plugin: /usr/lib/vdr/plugins/libvdr-satip.so.2.4.0
Apr  1 21:05:31 raspiVDR vdr: [3545] loading plugin: /usr/lib/vdr/plugins/libvdr-live.so.2.4.0
Apr  1 21:05:31 raspiVDR vdr: [3545] loading plugin: /usr/lib/vdr/plugins/libvdr-epg2vdr.so.2.4.0
Apr  1 21:05:31 raspiVDR vdr: [3545] loading plugin: /usr/lib/vdr/plugins/libvdr-skindesigner.so.2.4.0
Apr  1 21:05:31 raspiVDR vdr: [3545] loading plugin: /usr/lib/vdr/plugins/libvdr-scraper2vdr.so.2.4.0
Apr  1 21:05:31 raspiVDR vdr: [3545] loading plugin: /usr/lib/vdr/plugins/libvdr-softhddevice-drm.so.2.4.0
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/setup.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/sources.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/diseqc.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/scr.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/channels.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/timers.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/commands.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/reccmds.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/svdrphosts.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/keymacros.conf
Apr  1 21:05:31 raspiVDR vdr: [3545] registered source parameters for 'A - ATSC'
Apr  1 21:05:31 raspiVDR vdr: [3546] video directory scanner thread started (pid=3545, tid=3546, prio=low)
Apr  1 21:05:31 raspiVDR vdr: [3545] registered source parameters for 'C - DVB-C'
Apr  1 21:05:31 raspiVDR vdr: [3545] registered source parameters for 'S - DVB-S'
Apr  1 21:05:31 raspiVDR vdr: [3545] registered source parameters for 'T - DVB-T'
Apr  1 21:05:31 raspiVDR vdr: [3547] epg data reader thread started (pid=3545, tid=3547, prio=high)
Apr  1 21:05:31 raspiVDR vdr: [3547] reading EPG data from /var/cache/vdr/epg.data
Apr  1 21:05:31 raspiVDR vdr: [3545] no DVB device found
Apr  1 21:05:31 raspiVDR vdr: [3546] video directory scanner thread ended (pid=3545, tid=3546)
Apr  1 21:05:31 raspiVDR vdr: [3545] initializing plugin: satip (2.4.1-GIT-ba0b04b): SAT>IP Geräte
Apr  1 21:05:31 raspiVDR vdr: [3548] SATIP poller thread started (pid=3545, tid=3548, prio=high)
Apr  1 21:05:31 raspiVDR vdr: [3545] cTimeMs: using monotonic clock (resolution is 1 ns)
Apr  1 21:05:31 raspiVDR vdr: [3545] SATIP: Adding server '192.168.176.2|DVBS2-4|OctupusNet' Bind: default Filters: none CI: no Quirks: none
Apr  1 21:05:31 raspiVDR vdr: [3545] new device number 1
Apr  1 21:05:31 raspiVDR vdr: [3545] SATIP: Creating device CardIndex=0 DeviceNumber=0 [device 0]
Apr  1 21:05:31 raspiVDR vdr: [3549] SATIP#0 tuner thread started (pid=3545, tid=3549, prio=high)
Apr  1 21:05:31 raspiVDR vdr: [3545] initializing plugin: live (3.0.6): Live Interactive VDR Environment
Apr  1 21:05:31 raspiVDR vdr[3545]: [softhddev]Initialize:
Apr  1 21:05:31 raspiVDR vdr[3545]: [softhddev]cSoftHdDevice:
Apr  1 21:05:31 raspiVDR vdr[3545]: [softhddev]MakePrimaryDevice: 1
Apr  1 21:05:31 raspiVDR vdr[3545]: Start(void):
Apr  1 21:05:31 raspiVDR vdr: [3551] device 1 section handler thread started (pid=3545, tid=3551, prio=low)
Apr  1 21:05:31 raspiVDR vdr: [3545] initializing plugin: epg2vdr (1.1.118-GIT21ac459): epg2vdr plugin
Apr  1 21:05:31 raspiVDR vdr: [3545] initializing plugin: skindesigner (1.2.14): Skin Designer
Apr  1 21:05:31 raspiVDR vdr: [3545] initializing plugin: scraper2vdr (1.0.11-GITd9f6cb4): 'scraper2vdr' plugin
Apr  1 21:05:31 raspiVDR vdr: scraper2vdr: using image directory /var/cache/vdr/plugins/scraper2vdr
Apr  1 21:05:31 raspiVDR vdr: [3545] initializing plugin: softhddevice-drm (0.0.1rc1-GITcc2e59f): A software and GPU emulated HD device
Apr  1 21:05:31 raspiVDR vdr: [3545] new device number 2
Apr  1 21:05:31 raspiVDR vdr: [3545] setting primary device to 2
Apr  1 21:05:31 raspiVDR vdr: [3545] [softhddev]MakePrimaryDevice: 1
Apr  1 21:05:31 raspiVDR vdr: audio/alsa: using device 'default'
Apr  1 21:05:31 raspiVDR vdr: [3550] SATIP#0 section handler thread started (pid=3545, tid=3550, prio=high)
Apr  1 21:05:31 raspiVDR vdr: audio/alsa: supports pause: no
Apr  1 21:05:31 raspiVDR vdr: audio/alsa: mixer default - PCM open
Apr  1 21:05:31 raspiVDR vdr: audio/alsa: PCM mixer found 0 - 255 ratio 255000
Apr  1 21:05:31 raspiVDR vdr[3545]: AlsaFlushBuffers: AlsaFlushBuffers
Apr  1 21:05:31 raspiVDR vdr[3545]: AudioPlayHandlerThread: pthread_cond_wait
Apr  1 21:05:31 raspiVDR vdr: audio: wait on start condition
Apr  1 21:05:31 raspiVDR vdr: audio/alsa: flush state OPEN
Apr  1 21:05:31 raspiVDR vdr: FindDevice: DRM have 2 connectors, 6 crtcs, 3 encoders
Apr  1 21:05:31 raspiVDR vdr: FindDevice: Found Monitor Mode 1920x1080@50
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 47 crtc_id 0 possible_crtcs 1 possible CRTC 53 type primary plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 54 crtc_id 0 possible_crtcs 2 possible CRTC 60 type primary plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 61 crtc_id 0 possible_crtcs 4 possible CRTC 67 type primary plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 68 crtc_id 74 possible_crtcs 8 possible CRTC 74 type primary plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24GetPropertyValue: Unable to find value for property 'zpos'.
Apr  1 21:05:31 raspiVDR vdr[3545]:  AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 75 crtc_id 0 possible_crtcs 16 possible CRTC 81 type primary plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 82 crtc_id 0 possible_crtcs 32 possible CRTC 88 type primary plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 89 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 95 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 101 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 107 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 113 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 119 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 125 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 131 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 137 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 143 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 149 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 155 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 161 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 167 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 173 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 179 crtc_id 0 possible_crtcs 63 possible CRTC 53 type overlay plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 185 crtc_id 0 possible_crtcs 1 possible CRTC 53 type cursor plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 191 crtc_id 0 possible_crtcs 2 possible CRTC 60 type cursor plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 197 crtc_id 0 possible_crtcs 4 possible CRTC 67 type cursor plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr: FindDevice: DRM setup CRTC: 74 video_plane: 68 osd_plane 89 use_zpos 0
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 203 crtc_id 0 possible_crtcs 8 possible CRTC 74 type cursor plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats XR24 AR24 AB24 XB24 RG16 BG16 AR15 XR15 RG24 BG24 YU16 YV16 YU12 YV12 NV12 NV21 NV16 NV61 P030
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 209 crtc_id 0 possible_crtcs 16 possible CRTC 81 type cursor plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: Plane id 215 crtc_id 0 possible_crtcs 32 possible CRTC 88 type cursor plane
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: PixelFormats
Apr  1 21:05:31 raspiVDR vdr[3545]: Init: VIDEO PLANE on PRIMARY plane_id 68 with zpos -1 (zpos_overlay), OSD PLANE on OVERLAY plane_id 89 with zpos -1 (zpos_primary)
Apr  1 21:05:31 raspiVDR vdr[3545]: FindDevice: DRM setup CRTC: 74 video_plane: 68 osd_plane 89 use_zpos 0
Apr  1 21:05:31 raspiVDR vdr[3545]: SetupFB: fb_id 224 width 1920 height 1080 pix_fmt AR24
Apr  1 21:05:31 raspiVDR vdr[3545]: SetupFB: fb_id 225 width 720 height 576 pix_fmt NV12
Apr  1 21:05:31 raspiVDR vdr[3545]: [softhddev]cSoftOsdProvider:
Apr  1 21:05:31 raspiVDR vdr[3545]: [softhddev]GetSpuDecoder:
Apr  1 21:05:31 raspiVDR vdr: [3545] [softhddev]SetVideoFormat: 0
Apr  1 21:05:31 raspiVDR vdr: [3545] [softhddev]SetVideoDisplayFormat: 1
Apr  1 21:05:31 raspiVDR vdr: [3545] [softhddev]GetSpuDecoder:
Apr  1 21:05:31 raspiVDR vdr: [3545] [softhddev]SetVolumeDevice: 255
Apr  1 21:05:31 raspiVDR vdr: [3545] assuming manual start of VDR
Apr  1 21:05:31 raspiVDR vdr: [3545] skin "shady_KISS" not available - using "lcars" instead
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/themes/lcars-default.theme
Apr  1 21:05:31 raspiVDR vdr: [3545] starting plugin: satip
Apr  1 21:05:31 raspiVDR vdr: [3545] SATIP: Using CURL 7.64.0 rtsp
Apr  1 21:05:31 raspiVDR vdr: [3545] starting plugin: live
Apr  1 21:05:31 raspiVDR vdr: [3545] live: initial file cache has 82 entries and needs 376715 bytes of data!
Apr  1 21:05:31 raspiVDR vdr: [3545] starting plugin: epg2vdr
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: Info: Calling mysql_library_init()
Apr  1 21:05:31 raspiVDR vdr: [3553] live: INFO: attempt to listen on ip = ''
Apr  1 21:05:31 raspiVDR vdr: [3553] live: ERROR: Unable to load cert/key (/var/lib/vdr/plugins/live/live.pem//var/lib/vdr/plugins/live/live-key.pem): Datei oder Verzeichnis nicht gefunden
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: Set locale to 'en_GB.UTF-8'
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: detected UTF-8
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: Dictionary '/var/lib/vdr/plugins/epg2vdr//epg.dat' loaded
Apr  1 21:05:31 raspiVDR vdr: [3545] starting plugin: skindesigner
Apr  1 21:05:31 raspiVDR vdr: [3561] epg2vdr-update thread started (pid=3545, tid=3561, prio=high)
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: Calling mysql_init(3561)
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: TrueColor OSD found
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using libskindesigner API Version 0.1.2
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: plugin setup uses libskindesigner API Version 0.1.2
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: plugin setup has registered 1 menus
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: skinsetup template successfully registered at skindesigner, id 0
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using Skin Directory /var/lib/vdr/plugins/skindesigner/skins/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using Installer Skin Directory /var/lib/vdr/plugins/skindesigner/installerskins/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using common ChannelLogo Directory /var/lib/vdr/plugins/skindesigner/logos/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using EPG Images Directory /var/cache/vdr/plugins/skindesigner/epgimages/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner 4 skins found in /var/lib/vdr/plugins/skindesigner/skins/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner 3 skins found in /var/lib/vdr/plugins/skindesigner/installerskins/
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: SQL client character now 'utf8'
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: skin metrixhd started
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: skin shady_KISS started
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: skin estuary4vdr started
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: skin reufer started
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: Statement 'update recordingdirs set updsp = ? where directory = ? and vdruuid = ?;' with (3) in parameters and (0) out bindings prepared
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: reading XML Template /var/lib/vdr/plugins/skindesigner/installerskins/Holo/setup.xml failed
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: skin Holo started
Apr  1 21:05:31 raspiVDR vdr: [3545] starting plugin: scraper2vdr
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: Info: Skipping calling mysql_library_init(), it's already done!
Apr  1 21:05:31 raspiVDR vdr: scraper2vdr: Set locale to 'en_GB.UTF-8'
Apr  1 21:05:31 raspiVDR vdr: scraper2vdr: detected UTF-8
Apr  1 21:05:31 raspiVDR vdr[3545]: [softhddev]Start:
Apr  1 21:05:31 raspiVDR kernel: [ 6510.840039] input: vc4 (lircd bypass) as /devices/virtual/input/input10
Apr  1 21:05:31 raspiVDR vdr[3545]: Start(void):
Apr  1 21:05:31 raspiVDR vdr: [3545] starting plugin: softhddevice-drm
Apr  1 21:05:31 raspiVDR systemd[1]: Started Video Disk Recorder.
Apr  1 21:05:31 raspiVDR lircd[749]: lircd-0.10.1[749]: Notice: accepted new client on /var/run/lirc/lircd
Apr  1 21:05:31 raspiVDR lircd[749]: lircd-0.10.1[749]: Info: initializing '/dev/input/event0'
Apr  1 21:05:31 raspiVDR lircd[749]: lircd-0.10.1[749]: Info: Using device: /dev/input/event0
Apr  1 21:05:31 raspiVDR vdr: [3545] setting current skin to "shady_KISS"
Apr  1 21:05:31 raspiVDR vdr: [3545] loading /var/lib/vdr/themes/shady_KISS-default.theme
Apr  1 21:05:31 raspiVDR vdr: [3562] scraper2vdr-update thread started (pid=3545, tid=3562, prio=low)
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: Statement 'select distinct channelid, channelname from channelmap where channelid = ?' with (1) in parameters and (2) out bindings prepared
Apr  1 21:05:31 raspiVDR lircd-0.10.1[749]: Notice: accepted new client on /var/run/lirc/lircd
Apr  1 21:05:31 raspiVDR lircd-0.10.1[749]: Info: initializing '/dev/input/event0'
Apr  1 21:05:31 raspiVDR lircd-0.10.1[749]: Info: Using device: /dev/input/event0
Apr  1 21:05:31 raspiVDR vdr: [3545] remote control LIRC - learning keys
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: initializing skin shady_KISS
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using channel logo path /var/lib/vdr/plugins/skindesigner/logos/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using icon path /var/lib/vdr/plugins/skindesigner/skins/shady_KISS/themes/default/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using skinparts path /var/lib/vdr/plugins/skindesigner/skins/shady_KISS/themes/default/skinparts/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using svgtemplate path /var/lib/vdr/plugins/skindesigner/skins/shady_KISS/svgtemplates/
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: using language de_DE
Apr  1 21:05:31 raspiVDR vdr: epg2vdr: Statement 'update channelmap set unknownatvdr = ? where channelid = ?' with (2) in parameters and (0) out bindings prepared
Apr  1 21:05:31 raspiVDR vdr: [3563] LIRC remote control thread started (pid=3545, tid=3563, prio=high)
Apr  1 21:05:31 raspiVDR vdr: [3545] skindesigner: templates successfully validated and parsed
Apr  1 21:05:31 raspiVDR systemd-udevd[3524]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Apr  1 21:05:32 raspiVDR vdr: [3547] epg data reader thread ended (pid=3545, tid=3547)
Apr  1 21:05:32 raspiVDR vdr: epg2vdr: Handler: Finished reading external id's from db, got 73 id's
Apr  1 21:05:38 raspiVDR vdr: [3545] skindesigner: templates and images cached
Apr  1 21:05:38 raspiVDR vdr: [3545] skindesigner: cached 36 icons - size internal mem 0.68MB, high level mem 1.99MB
Apr  1 21:05:38 raspiVDR vdr[3545]: [softhddev]CreateOsd:
Apr  1 21:05:38 raspiVDR vdr[3545]: [softhddev]cSoftOsd:
Apr  1 21:05:38 raspiVDR vdr: [3545] skindesigner: cached 201 logos - size 2816.72MB internal mem
Apr  1 21:05:38 raspiVDR vdr: [3545] skindesigner: cached 185 skinparts - size internal mem 90.44MB, high level mem 27.15MB
Apr  1 21:05:38 raspiVDR vdr: [3545] skindesigner: templates loaded and caches created - needed 7415 ms
Apr  1 21:05:39 raspiVDR vdr[3545]: VideoSetOutputPosition 0 0 0 0
Apr  1 21:05:39 raspiVDR vdr: [3635] animator thread thread started (pid=3545, tid=3635, prio=high)
Apr  1 21:05:41 raspiVDR vdr: scraper2vdr: Got UUID 'C507A625-AD66-4382-A2A2-C9C463272B79' by epg2vdr
Apr  1 21:05:41 raspiVDR vdr: scraper2vdr: Trying to re-connect to database!
Apr  1 21:05:41 raspiVDR vdr: scraper2vdr: Calling mysql_init(3562)
Apr  1 21:05:41 raspiVDR vdr: scraper2vdr: Connection established successfull!
Apr  1 21:05:41 raspiVDR vdr: scraper2vdr: Got 12936 new scraped Events from Database
Apr  1 21:05:41 raspiVDR vdr: scraper2vdr: Loading Movies information from Database...
Apr  1 21:05:41 raspiVDR vdr: scraper2vdr: Got 816 new/updated Movies in 0s from Database (new max scrsp: 1617301917)
Apr  1 21:05:41 raspiVDR vdr: scraper2vdr: Loading Movies content from Database...
Apr  1 21:05:43 raspiVDR vdr: scraper2vdr: Got 0 new/updated Image information in 2s from Database
Apr  1 21:05:43 raspiVDR vdr: scraper2vdr: Loading Series information from Database...
Apr  1 21:05:43 raspiVDR vdr: scraper2vdr: Got 542 new/updated Series in 0s from Database (new max scrsp: 1617301917)
Apr  1 21:05:43 raspiVDR vdr: scraper2vdr: Loading Series content from Database...
Apr  1 21:05:49 raspiVDR vdr: [3635] animator thread thread ended (pid=3545, tid=3635)
Apr  1 21:05:49 raspiVDR vdr[3545]: VideoSetOutputPosition 0 0 0 0
Apr  1 21:05:49 raspiVDR vdr[3545]: [softhddev]~cSoftOsd:
Apr  1 21:05:49 raspiVDR vdr: [3545] loading /var/cache/vdr/cam.data
Apr  1 21:05:49 raspiVDR vdr: [3545] switching to channel 2 S19.2E-1-1011-11110 (ZDF HD)
Apr  1 21:05:49 raspiVDR vdr[3545]: [softhddev]CanReplay:
Apr  1 21:05:49 raspiVDR vdr: [3545] [softhddev]SetVolumeDevice: 255
Apr  1 21:05:49 raspiVDR vdr: [3639] device 1 receiver thread started (pid=3545, tid=3639, prio=high)
Apr  1 21:05:49 raspiVDR vdr: [3545] setting watchdog timer to 60 seconds
Apr  1 21:05:49 raspiVDR vdr: [3640] SVDRP server handler thread started (pid=3545, tid=3640, prio=low)
Apr  1 21:05:49 raspiVDR vdr: [3640] SVDRP raspiVDR opening port 6419/tcp
Apr  1 21:05:49 raspiVDR vdr: [3640] SVDRP raspiVDR listening on port 6419/tcp
Apr  1 21:05:49 raspiVDR vdr[3545]: [softhddev]CanReplay:
Apr  1 21:05:49 raspiVDR vdr: [3641] SVDRP client handler thread started (pid=3545, tid=3641, prio=low)
Apr  1 21:05:49 raspiVDR vdr: [3641] SVDRP raspiVDR opening port 6419/udp
Apr  1 21:05:49 raspiVDR vdr: [3641] SVDRP raspiVDR listening on port 6419/udp
Apr  1 21:05:49 raspiVDR vdr[3545]: [softhddev]SetPlayMode: 1
Apr  1 21:05:49 raspiVDR vdr[3545]: SetPlayMode: play_mode 1
Apr  1 21:05:49 raspiVDR vdr[3545]: VideoThreadWakeup: VideoThreadWakeup
Apr  1 21:05:49 raspiVDR vdr: [3641] SVDRP raspiVDR > 255.255.255.255:6419 send dgram 'SVDRP:discover name:raspiVDR port:6419 vdrversion:20400 apiversion:20400 timeout:300 host:homeserver'
Apr  1 21:05:49 raspiVDR vdr: [3545] [softhddev]SetPlayMode: 1
Apr  1 21:05:49 raspiVDR vdr: video: display thread started
Apr  1 21:05:49 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56118 client connection accepted
Apr  1 21:05:49 raspiVDR vdr: [3640] SVDRP raspiVDR > 192.168.176.1:56118 server created
Apr  1 21:05:49 raspiVDR vdr: [3640] SVDRP raspiVDR > 192.168.176.1:6419 server connection established
Apr  1 21:05:49 raspiVDR vdr: [3640] SVDRP raspiVDR > 192.168.176.1:6419 client created for 'homeserver'
Apr  1 21:05:49 raspiVDR vdr: [3545] OSD size changed to 1920x1080 @ 1.77778
Apr  1 21:05:49 raspiVDR vdr[3545]: [softhddev]CreateOsd:
Apr  1 21:05:49 raspiVDR vdr[3545]: [softhddev]cSoftOsd:
Apr  1 21:05:49 raspiVDR vdr: epg2vdr: Answer 'Epg2Vdr_Timer_Service-v1.0' call with 0 timers, duration was (39 ms)
Apr  1 21:05:49 raspiVDR vdr: [3545] --- begin invalid lock sequence report
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  R  -  -  R  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  U  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  R  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  U  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  R  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  U  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  -  *  -  -  -  -  -  -  -  -  U
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545  R  *  -  -  -  -  -  -  -  -  L
Apr  1 21:05:49 raspiVDR vdr: [3545]  3545 invalid lock sequence: 1 Timers
Apr  1 21:05:49 raspiVDR vdr: [3545] full backtrace:
Apr  1 21:05:50 raspiVDR vdr: scraper2vdr: Got 2649 new/updated Episodes and 569 new/updated Image information (including 541 possible not available season poster) in 7s from Database
Apr  1 21:05:50 raspiVDR vdr: scraper2vdr: Loading Image content from Database...
Apr  1 21:05:50 raspiVDR vdr: [3545] /usr/bin/vdr cStateLock::Lock(cStateKey&, bool, int) at ??:?
Apr  1 21:05:50 raspiVDR vdr: [3545] /usr/bin/vdr cTimers::GetTimersRead(cStateKey&, int) at ??:?
Apr  1 21:05:50 raspiVDR vdr[3545]: invalid lock sequence at Do. 01.04. 21:05
Apr  1 21:05:50 raspiVDR vdr: [3545] /usr/lib/vdr/plugins/libvdr-skindesigner.so.2.4.0 cGlobalTimers::SetLocalTimers() at timers.h:216 at cGlobalTimers::SetLocalTimers() at globaltimers.c:87
Apr  1 21:05:50 raspiVDR vdr: [3545] /usr/lib/vdr/plugins/libvdr-skindesigner.so.2.4.0 cGlobalTimers::LoadTimers() at globaltimers.c:36
Apr  1 21:05:50 raspiVDR vdr: [3545] /usr/lib/vdr/plugins/libvdr-skindesigner.so.2.4.0 cSDDisplayChannel::Flush() at displaychannel.c:99 at cSDDisplayChannel::Flush() at displaychannel.c:95
Apr  1 21:05:50 raspiVDR vdr: [3545] /usr/bin/vdr cDisplayChannel::cDisplayChannel(int, bool) at ??:?
Apr  1 21:05:50 raspiVDR vdr: [3545] /usr/bin/vdr main at ??:?
Apr  1 21:05:50 raspiVDR vdr: [3545] /lib/arm-linux-gnueabihf/libc.so.6 __libc_start_main at libc-start.c:342
Apr  1 21:05:50 raspiVDR vdr: [3545] --- end invalid lock sequence report
Apr  1 21:05:50 raspiVDR vdr: [3545] --- THERE WILL BE NO FURTHER REPORTS UNTIL VDR IS RESTARTED!
Apr  1 21:05:50 raspiVDR vdr: audio/demux: new channel id
Apr  1 21:05:50 raspiVDR vdr[3545]: CodecAudioClose
Apr  1 21:05:50 raspiVDR vdr: audio/alsa: buffer size 7200 150ms, period size 1800 37ms
Apr  1 21:05:50 raspiVDR vdr: audio/alsa: state PREPARED
Apr  1 21:05:50 raspiVDR vdr: audio/alsa: start delay 450ms
Apr  1 21:05:50 raspiVDR vdr[3545]: AudioFilterInit: ch_layout stereo sample_fmt s16p sample_rate 48000 channels 2
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb   24ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb   48ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb   72ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb   96ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: [3672] animator thread thread started (pid=3545, tid=3672, prio=high)
Apr  1 21:05:50 raspiVDR vdr: video: not detected
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  120ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  144ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  168ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  192ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  216ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: video: not detected
Apr  1 21:05:50 raspiVDR vdr: [3545] max. latency time 1 seconds
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  240ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  264ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  288ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  312ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  336ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: video: not detected
Apr  1 21:05:50 raspiVDR vdr: scraper2vdr: Got 31 new/updated Images (found 538 not available images) in 0s from Database
Apr  1 21:05:50 raspiVDR vdr: video: not detected
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  360ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  384ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  408ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  432ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  456ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: video: not detected
Apr  1 21:05:50 raspiVDR vdr: video: not detected
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  480ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  504ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  528ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  552ms to skip 0ms
Apr  1 21:05:50 raspiVDR vdr: audio: start? in Rb  576ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: video: not detected
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  600ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  624ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  648ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  672ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  696ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: video: H264 detected
Apr  1 21:05:51 raspiVDR vdr[3545]: [h264_v4l2m2m @ 0x963f8ea0] Using device /dev/video10
Apr  1 21:05:51 raspiVDR vdr[3545]: [h264_v4l2m2m @ 0x963f8ea0] driver 'bcm2835-codec' on card 'bcm2835-codec-decode' in mplane mode
Apr  1 21:05:51 raspiVDR vdr[3545]: [h264_v4l2m2m @ 0x963f8ea0] requesting formats: output=H264 capture=YU12
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  720ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  744ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  768ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  792ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  816ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  840ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  864ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  888ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  912ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  936ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:51 raspiVDR vdr[3545]: SetupFB: 1280 x 720 nb_objects 1 nb_layers 1 nb_planes 3 size 1382400 pix_fmt YU12 modifier 0
Apr  1 21:05:51 raspiVDR vdr[3545]: SetupFB: plane 0 pitch 1280 offset 0
Apr  1 21:05:51 raspiVDR vdr[3545]: SetupFB: plane 1 pitch 640 offset 921600
Apr  1 21:05:51 raspiVDR vdr[3545]: SetupFB: plane 2 pitch 640 offset 1152000
Apr  1 21:05:51 raspiVDR vdr[3545]: Frame2Display: start PTS  2:22:55.799
Apr  1 21:05:51 raspiVDR vdr: AudioVideoReady: RB 936ms skip 936ms to skip 362ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 362ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 338ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 314ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 290ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 266ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 242ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 218ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 194ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 170ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 146ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 122ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 98ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 74ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 50ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 26ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   24ms to skip 2ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   46ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   70ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb   94ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  118ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  142ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  166ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  190ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  214ms to skip 0ms
Apr  1 21:05:51 raspiVDR vdr: audio: start? in Rb  238ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  262ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  286ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  310ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  334ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  358ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  382ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  406ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  430ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr[3545]: AudioPlayHandlerThread: nach pthread_cond_wait ----> 454ms start
Apr  1 21:05:52 raspiVDR vdr: audio: start? in Rb  454ms to skip 0ms
Apr  1 21:05:52 raspiVDR vdr: audio: ----> 454ms start
Apr  1 21:05:54 raspiVDR vdr[3545]: CodecVideoReceiveFrame: receive_frame ret: Die Ressource ist zur Zeit nicht verfügbar
Apr  1 21:05:55 raspiVDR vdr: [3672] animator thread thread ended (pid=3545, tid=3672)
Apr  1 21:05:55 raspiVDR vdr[3545]: [softhddev]~cSoftOsd:
Apr  1 21:05:55 raspiVDR vdr[3545]: Frame2Display: cannot page flip to FB 232 (34): Das numerische Ergebnis ist außerhalb des gültigen Bereiches
Apr  1 21:05:59 raspiVDR vdr: [3639] [softhddev]Clear:
Apr  1 21:05:59 raspiVDR vdr[3545]: [softhddev]Clear:
Apr  1 21:05:59 raspiVDR vdr[3545]: Clear(void)
Apr  1 21:05:59 raspiVDR vdr[3545]: ClearVideo()
Apr  1 21:05:59 raspiVDR vdr[3545]: CodecVideoFlushBuffers: VideoCtx 0x963f8ea0
Apr  1 21:05:59 raspiVDR vdr[3545]: VideoSetClosing: buffers 20 StartCounter 187
Apr  1 21:05:59 raspiVDR vdr[3545]: VideoSetClosing: pthread_cond_wait
Apr  1 21:05:59 raspiVDR vdr[3545]: AlsaPlayer: ring buffer empty
Apr  1 21:05:59 raspiVDR vdr[3545]: AlsaFlushBuffers: AlsaFlushBuffers
Apr  1 21:05:59 raspiVDR vdr: audio: wait on start condition
Apr  1 21:05:59 raspiVDR vdr[3545]: AudioPlayHandlerThread: pthread_cond_wait
Apr  1 21:05:59 raspiVDR vdr: audio/alsa: flush state RUNNING
Apr  1 21:05:59 raspiVDR vdr: [3548] ERROR: 1 ring buffer overflow (752 bytes dropped)
Apr  1 21:06:00 raspiVDR vdr: [3548] SATIP: Detected 1 RTP packet error [device 0]
Apr  1 21:06:05 raspiVDR vdr: [3548] ERROR: 500 ring buffer overflows (658000 bytes dropped)
Apr  1 21:06:11 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:06:17 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:06:23 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:06:29 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:06:35 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:06:36 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56126 client connection accepted
Apr  1 21:06:36 raspiVDR vdr: [3640] SVDRP raspiVDR > 192.168.176.1:56126 server created
Apr  1 21:06:36 raspiVDR vdr: epg2vdr: Got epgd state 'busy (match)' (4)
Apr  1 21:06:36 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56126 lost connection to client
Apr  1 21:06:36 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56126 connection closed
Apr  1 21:06:36 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56126 server destroyed
Apr  1 21:06:41 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:06:46 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56130 client connection accepted
Apr  1 21:06:46 raspiVDR vdr: [3640] SVDRP raspiVDR > 192.168.176.1:56130 server created
Apr  1 21:06:46 raspiVDR vdr: epg2vdr: Got epgd state 'standby' (1)
Apr  1 21:06:46 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56130 lost connection to client
Apr  1 21:06:46 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56130 connection closed
Apr  1 21:06:46 raspiVDR vdr: [3640] SVDRP raspiVDR < 192.168.176.1:56130 server destroyed
Apr  1 21:06:47 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:06:53 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:06:59 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:07:05 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:07:11 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:07:17 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:07:23 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:07:29 raspiVDR vdr: [3548] ERROR: 593 ring buffer overflows (780388 bytes dropped)
Apr  1 21:07:30 raspiVDR vdr: [3545] PANIC: watchdog timer expired - exiting!
Apr  1 21:07:31 raspiVDR vdr[3545]: ReadHWPlatform: bcm2711 found
Apr  1 21:07:31 raspiVDR vdr[3545]: exception occured: pthread_mutex_lock failed: errno 22: Das Argument ist ungültig
Apr  1 21:07:31 raspiVDR vdr: [3545] ERROR: ListGarbageCollector destroyed without prior Purge()!
Apr  1 21:07:31 raspiVDR vdr: [3561] epg2vdr-update thread ended (pid=3545, tid=3561)
Apr  1 21:07:31 raspiVDR lircd[749]: lircd-0.10.1[749]: Info: removed client
Apr  1 21:07:31 raspiVDR lircd[749]: lircd-0.10.1[749]: Info: closing '/dev/input/event0'
Apr  1 21:07:31 raspiVDR lircd-0.10.1[749]: Info: removed client
Apr  1 21:07:31 raspiVDR lircd-0.10.1[749]: Info: closing '/dev/input/event0'
Apr  1 21:07:32 raspiVDR systemd[1]: vdr.service: Main process exited, code=exited, status=1/FAILURE
Apr  1 21:07:32 raspiVDR systemd[1]: vdr.service: Failed with result 'exit-code'.
Apr  1 21:07:32 raspiVDR systemd[1]: vdr.service: Service RestartSec=100ms expired, scheduling restart.
Apr  1 21:07:32 raspiVDR systemd[1]: vdr.service: Scheduled restart job, restart counter is at 3.
Apr  1 21:07:32 raspiVDR systemd[1]: Stopped Video Disk Recorder.
Apr  1 21:07:32 raspiVDR systemd[1]: Starting Video Disk Recorder...