Posts by Fourty2

Fourty2 · Sep 28th 2021

Hallo kfb77,

habe da eben zufällig noch einen Lock-Fehler im Log gefunden, den es mehrfach in der Vergangenheit (immer dieselbe Stelle gibt):

Code

Sep 28 16:43:28 vdr: [2852] stopping recording due to modification of channel 100 (...)
Sep 28 16:43:28 vdr: [3058] recording thread ended (pid=2852, tid=3058)
Sep 28 16:43:28 vdr: [2852] SendCaPmts CAM 1: [1] actives in CAM: 2 -> 1 (3 pids)
Sep 28 16:43:28 vdr: [2852] CAM 1: unassigned from device 2
Sep 28 16:43:28 vdr: [2852] NALU fill dumper: 1314622 of 23057021 packets dropped, 5%
Sep 28 16:43:28 vdr: [2852] buffer stats: 499516 (0%) used
Sep 28 16:43:28 vdr: [2852] timer 1 (100 1445-1705 '[..]') stop
Sep 28 16:43:28 vdr: [2852] removing /srv/vdr/video/local/[..]/2021-09-28.14.45.100-0.rec/.timer
Sep 28 16:43:28 vdr: [3672] EPGSearch: recdone thread started (pid=2852, tid=3672, prio=high)
Sep 28 16:43:28 vdr: [2852] markad: cStatusMarkAd::Recording(): index 0, pid 3061, filename /srv/vdr/video/local/[..]/2021-09-28.14.45.100-0.rec: recording stopped
Sep 28 16:43:28 vdr: [3672] EPGSearch: recdone thread ended (pid=2852, tid=3672)
Sep 28 16:43:28 vdr: [2852] --- begin invalid lock sequence report
Sep 28 16:43:28 vdr: [2852] 2920 - U - - - - - - - - U
Sep 28 16:43:28 vdr: [2852] 2920 - U - - - - - - - - U
Sep 28 16:43:28 vdr: [2852] 2924 - W - - - - - - - - L
Sep 28 16:43:28 vdr: [2852] 2924 - U - - - - - - - - U
Sep 28 16:43:28 vdr: [2852] 2924 - W - - - - - - - - L
Sep 28 16:43:28 vdr: [2852] 2924 - * - - W - - - - - L
Sep 28 16:43:28 vdr: [2852] 2924 - * - - U - - - - - U
Sep 28 16:43:28 vdr: [2852] 2924 - U - - - - - - - - U
Sep 28 16:43:28 vdr: [2852] 2920 - W - - - - - - - - L
Sep 28 16:43:28 vdr: [2852] 2920 - * - - W - - - - - L
Sep 28 16:43:28 vdr: [2852] 2920 - * - - U - - - - - U
Sep 28 16:43:28 vdr: [2852] 2920 - U - - - - - - - - U
Sep 28 16:43:28 vdr: [2852] 2852 R - - - - - - - - - L
Sep 28 16:43:28 vdr: [2852] 2852 U - - - - - - - - - U
Sep 28 16:43:28 vdr: [2852] 2852 R - - - - - - - - - L
Sep 28 16:43:28 vdr: [2852] 2852 U - - - - - - - - - U
Sep 28 16:43:28 vdr: [2852] 2852 - R - - - - - - - - L
Sep 28 16:43:28 vdr: [2852] 3672 R R - - - - - - - - L
Sep 28 16:43:28 vdr: [2852] 3672 U - - - - - - - - - U
Sep 28 16:43:28 vdr: [2852] 2852 R R - - - - - - - - L
Sep 28 16:43:28 vdr: [2852] 2852 invalid lock sequence: 1 Timers
Sep 28 16:43:28 vdr: [2852] full backtrace:
Sep 28 16:43:28 vdr: [3044] device 2 TS buffer thread ended (pid=2852, tid=3044)
Sep 28 16:43:28 vdr: [3043] buffer stats: 261884 (0%) used
Sep 28 16:43:28 vdr: [3043] device 2 receiver thread ended (pid=2852, tid=3043)
Sep 28 16:43:29 vdr: [2852] /usr/bin/vdr cStateLock::Lock(cStateKey&, bool, int) calling ?? at ??:0
Sep 28 16:43:29 vdr: [2852] /usr/bin/vdr cTimers::GetTimersRead(cStateKey&, int) calling ?? at ??:0
Sep 28 16:43:29 vdr: [2852] /usr/lib/vdr/plugins/libvdr-markad.so.2.4.7 cStatusMarkAd::RunningRecording() at status.cpp:494
Sep 28 16:43:29 vdr: [2852] /usr/lib/vdr/plugins/libvdr-markad.so.2.4.7 cStatusMarkAd::Recording(cDevice const*, char const*, char const*, bool) at status.cpp:766 at cStatusMarkAd::Recording(cDevice const*, char const*, char const*, bool) at status.cpp:699
Sep 28 16:43:29 vdr: [2852] /usr/bin/vdr cStatus::MsgRecording(cDevice const*, char const*, char const*, bool) calling ?? at ??:0
Sep 28 16:43:29 vdr: [2852] /usr/bin/vdr cRecordControl::Stop(bool) calling ?? at ??:0
Sep 28 16:43:29 vdr: [2852] /usr/bin/vdr cRecordControls::ChannelDataModified(cChannel const*) calling ?? at ??:0
Sep 28 16:43:29 vdr: [2852] /usr/bin/vdr main calling ?? at ??:0
Sep 28 16:43:29 vdr: [2852] /lib/x86_64-linux-gnu/libc.so.6 __libc_start_main at libc-start.c:342
Sep 28 16:43:29 vdr: [2852] /usr/bin/vdr _start calling ?? at ??:0
Sep 28 16:43:29 vdr: [2852] --- end invalid lock sequence report
Sep 28 16:43:29 vdr: [2852] --- THERE WILL BE NO FURTHER REPORTS UNTIL VDR IS RESTARTED!
Sep 28 16:43:29 vdr: [2852] markad: cStatusMarkAd::Continue(): index 0, pid 3061, filename /srv/vdr/video/local/[..]/2021-09-28.14.45.100-0.rec: resume markad process
Sep 28 16:43:29 vdr: epg2vdr: Recording of '(null)' has 'stopped' [/srv/vdr/video/local/[..]/2021-09-28.14.45.100-0.rec]
Sep 28 16:43:29 vdr: [2852] executing '/usr/lib/vdr/vdr-recordingaction after "/srv/vdr/video/local/[..]/2021-09-28.14.45.100-0.rec"'
Sep 28 16:43:29 vdr: epg2vdr: Info: Finished (2982) '[..]' (not complete! - recorded only 85%); VPS No
Sep 28 16:43:29 vdr: epg2vdr: Try to lookup timer with id 2982
Sep 28 16:43:29 vdr: epg2vdr: Found timer 2982

Display More

Version ist noch 3.0.12.

Grüße,

Stefan

Fourty2 · Jul 9th 2021

Das kenne ich doch irgendwoher...

Habe den VDSB Neustart deaktiviert und den Watchdog auf 300s gesetzt. Das reicht in der Regel, bis epgsearch, epg2vdr, scraper2vdr und der vdr mit dem Streit um das Aufnahmen-Lock durch sind.

Alternativ Aufnamestart bei manuell erzeugten Timer auf +/- 1 Min legen.

Stefan

Fourty2 · Jul 2nd 2021

Na, der Abspann ist schon dran, danach hab ich es halt beendet...

Fourty2 · Jul 2nd 2021

Da ist noch ein kleiner Bug in der Timerverwaltung:

Code

Thu Jul 1 18:25:03 [3084] INFO: starting markad v3.0.5 (5e56e72) (64bit)
Thu Jul 1 18:25:03 [3084] INFO: using libavcodec.so.58.91.100 with -1 threads
Thu Jul 1 18:25:03 [3084] INFO: on /srv/vdr/video/local/####/2021-07-01.18.25.107-0.rec
Thu Jul 1 18:25:04 [3084] INFO: paused by signal

Alle Aufnahmen danach wurden bereits markiert. Was war hier anders?

Habe, nachdem die Credits durch waren, den Timer gelöscht, damit die Karte frei wurde, also den Nachlauf abgebrochen. Die Aufnahme wird dann nicht mehr bearbeitet (aber vermutlich auch nicht beachtet)

Stefan

Fourty2 · Jun 30th 2021

kfb77

Balken kommen vom Himmel, wie es halt gerade paßt:

Dezent.

So lala:

HD in 4:3 (Zack Synders Justice League) oder, Moment ... "Lucy in the Sky"

mal breit:

mal links und rechts:

obwohl, vorher war besser:

nee, gucken wir nochmal ...

Wenn Du Anschauungsmaterial brauchst, melde dich.

Stefan

Fourty2 · Jun 28th 2021

Hab die Aufnahme mal als tar.gz auf den Webserver gelegt. Keine Ahnung, ob der mit 5 GB am Stück klar kommt.

System ist Debian GNU/Linux 10 (buster) 5.10.43-cherrytrail

Der Kernel hat einen march=native patch mit O2 Optimierung...

FFMPEG:

built with gcc 8 (Debian 8.3.0-6)

configuration: --prefix=/usr --extra-version='1~hit1u0' --toolchain=hardened --libdir=/usr/lib/x86_64-linux-gnu --incdir=/usr/include/x86_64-linux-gnu --arch=amd64 --enable-gpl --disable-stripping --enable-avresample --disable-filter=resample --enable-libdc1394 --enable-libdrm --enable-libiec61883 --enable-chromaprint --enable-frei0r --enable-libx264 --enable-shared

libavutil 56. 51.100 / 56. 51.100

libavcodec 58. 91.100 / 58. 91.100

libavformat 58. 45.100 / 58. 45.100

libavdevice 58. 10.100 / 58. 10.100

libavfilter 7. 85.100 / 7. 85.100

libavresample 4. 0. 0 / 4. 0. 0

libswscale 5. 7.100 / 5. 7.100

libswresample 3. 7.100 / 3. 7.100

libpostproc 55. 7.100 / 55. 7.100

Hyper fast Audio and Video encoder

Fourty2 · Jun 28th 2021

Habe gerade mal wieder einen MarkAd Segfault. Passiert selten, meist gibt es dann keine Endmarken.

Ideen? Aufnahme war nach vdr-2.5.x Routinen, vdr-checkts und ffmpeg i.O.

Code

Mon Jun 28 11:09:59 [3464] INFO: starting markad v3.0.5 (d5f11c5) (64bit)
Mon Jun 28 11:09:59 [3464] INFO: using libavcodec.so.58.91.100 with -1 threads
Mon Jun 28 11:09:59 [3464] INFO: on /srv/vdr/video/local/Die_Freunde_des_Bräutigams/2021-06-28.11.00.107-0.rec
Mon Jun 28 11:10:00 [3464] INFO: paused by signal
Mon Jun 28 13:10:00 [3464] INFO: continued by signal
Mon Jun 28 13:10:00 [3464] INFO: broadcast aspect ratio 16:9 (from vdr info)
Mon Jun 28 13:10:00 [3464] INFO: no logo found in logo directory, trying to find logo in recording directory
Mon Jun 28 13:10:00 [3464] INFO: no logo found in recording directory, trying to extract logo from recording
Mon Jun 28 13:10:30 [3464] INFO: no logo found in recording, retry in 2nd recording part
Mon Jun 28 13:11:03 [3464] ERROR: segmentation fault
Mon Jun 28 13:11:03 [3464] ERROR: [bt] Execution path:
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(+0xb901) [0x560d3c14b901]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /lib/x86_64-linux-gnu/libc.so.6(+0x37840) [0x7fcd97069840]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/lib/x86_64-linux-gnu/libavcodec.so.58(avcodec_is_open+0x14) [0x7fcd9850f8a4]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/lib/x86_64-linux-gnu/libavcodec.so.58(avcodec_close+0x27) [0x7fcd97ee15af]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/lib/x86_64-linux-gnu/libavcodec.so.58(avcodec_free_context+0x29) [0x7fcd983db619]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(_ZN8cDecoder10DecodeFileEPKc+0xa9) [0x560d3c15d399]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(_ZN8cDecoder9DecodeDirEPKc+0x4d) [0x560d3c15d6dd]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(_ZN8cDecoder11SeekToFrameEP14sMarkAdContexti+0xff) [0x560d3c15ea4f]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(_ZN12cExtractLogo10SearchLogoEP14sMarkAdContexti+0x59a) [0x560d3c165eca]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(_ZN17cMarkAdStandalone9CheckLogoEv+0x1b2) [0x560d3c156072]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(_ZN17cMarkAdStandaloneC1EPKcP13sMarkAdConfigP6cIndex+0x748) [0x560d3c157508]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(main+0xda7) [0x560d3c14aba7]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0xeb) [0x7fcd9705609b]
Mon Jun 28 13:11:03 [3464] ERROR: [bt] /usr/bin/markad(_start+0x2a) [0x560d3c14b3ba]

Display More

Fourty2 · Jun 28th 2021

Wunderbar!

Funktioniert hier auch:

Code

Jun 28 00:50:02 roadrunner vdr: [30864] markad: cStatusMarkAd::Recording(): recording <Der große Crash - Margin Call> [/srv/vdr/video/local/Der_große_Crash_-_Margin_Call/2021-06-28.00.45.1-0.rec] started
Jun 28 00:50:02 roadrunner vdr: [30864] markad: cStatusMarkAd::RunningRecording(): running local recoring: Der große Crash - Margin Call
Jun 28 00:50:02 roadrunner vdr: [30864] markad: cStatusMarkAd::RunningRecording(): 1 local running recording(s)
Jun 28 00:50:02 roadrunner vdr: [30864] markad: cStatusMarkAd::GetEventID(): eventID 796839 from event for recording <Der große Crash - Margin Call> timer <Der große Crash - Margin Call> timer start 1624833900 stop 1624840860
Jun 28 00:50:04 roadrunner vdr: [30864] markad: cStatusMarkAd::Start(): executing "/usr/bin"/markad -I -G -R --autologo=2 -l "/var/lib/markad" --online=2 before "/srv/vdr/video/local/Der_große_Crash_-_Margin_Call/2021-06-28.00.45.1-0.rec"
Jun 28 00:50:04 roadrunner vdr: [30864] markad: cStatusMarkAd::Start(): index 0, pid 31338, filename /srv/vdr/video/local/Der_große_Crash_-_Margin_Call/2021-06-28.00.45.1-0.rec: running markad stored in list
Jun 28 00:50:04 roadrunner vdr: [30864] markad: cStatusMarkAd::Pause(): called with filename <NULL>
Jun 28 00:50:04 roadrunner vdr: [30864] markad: cStatusMarkAd::Pause(): index 0, pid 31338, filename /srv/vdr/video/local/Der_große_Crash_-_Margin_Call/2021-06-28.00.45.1-0.rec: pause markad process
Jun 28 01:20:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Recording(): recording <Inheritance> [/srv/vdr/video/local/Inheritance/2021-06-28.01.20.101-0.rec] started
Jun 28 01:20:00 roadrunner vdr: [30864] markad: cStatusMarkAd::RunningRecording(): running local recoring: Inheritance
Jun 28 01:20:00 roadrunner vdr: [30864] markad: cStatusMarkAd::RunningRecording(): running local recoring: Der große Crash - Margin Call
Jun 28 01:20:00 roadrunner vdr: [30864] markad: cStatusMarkAd::RunningRecording(): 2 local running recording(s)
Jun 28 01:20:00 roadrunner vdr: [30864] markad: cStatusMarkAd::GetEventID(): eventID 797050 from event for recording <Inheritance> timer <Inheritance> timer start 1624836000 stop 1624844700
Jun 28 01:20:02 roadrunner vdr: [30864] markad: cStatusMarkAd::Start(): executing "/usr/bin"/markad -I -G -R --autologo=2 -l "/var/lib/markad" --online=2 before "/srv/vdr/video/local/Inheritance/2021-06-28.01.20.101-0.rec"
Jun 28 01:20:02 roadrunner vdr: [30864] markad: cStatusMarkAd::Start(): index 1, pid 31559, filename /srv/vdr/video/local/Inheritance/2021-06-28.01.20.101-0.rec: running markad stored in list
Jun 28 01:20:02 roadrunner vdr: [30864] markad: cStatusMarkAd::Pause(): called with filename <NULL>
Jun 28 01:20:02 roadrunner vdr: [30864] markad: cStatusMarkAd::Pause(): index 0, pid 31338, filename /srv/vdr/video/local/Der_große_Crash_-_Margin_Call/2021-06-28.00.45.1-0.rec: pause markad process
Jun 28 01:20:02 roadrunner vdr: [30864] markad: cStatusMarkAd::Pause(): index 1, pid 31559, filename /srv/vdr/video/local/Inheritance/2021-06-28.01.20.101-0.rec: pause markad process
Jun 28 02:41:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Recording(): setup->ProcessDuring 0, setup->whileRecording 0, setup->whileReplaying 1
Jun 28 02:41:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Recording(): index 0, pid 31338, filename /srv/vdr/video/local/Der_große_Crash_-_Margin_Call/2021-06-28.00.45.1-0.rec: recording stopped
Jun 28 02:41:00 roadrunner vdr: [30864] markad: cStatusMarkAd::RunningRecording(): running local recoring: Inheritance
Jun 28 02:41:00 roadrunner vdr: [30864] markad: cStatusMarkAd::RunningRecording(): 1 local running recording(s)
Jun 28 02:41:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Recording(): resume not possible, still 1 running recording(s)
Jun 28 03:27:27 roadrunner vdr: [30864] markad: cStatusMarkAd::Replaying called, start recording <NULL>
Jun 28 03:45:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Recording(): setup->ProcessDuring 0, setup->whileRecording 0, setup->whileReplaying 1
Jun 28 03:45:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Recording(): index 1, pid 31559, filename /srv/vdr/video/local/Inheritance/2021-06-28.01.20.101-0.rec: recording stopped
Jun 28 03:45:00 roadrunner vdr: [30864] markad: cStatusMarkAd::RunningRecording(): 0 local running recording(s)
Jun 28 03:45:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Continue(): called with filename <NULL>
Jun 28 03:45:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Continue(): index 0, pid 31338, filename /srv/vdr/video/local/Der_große_Crash_-_Margin_Call/2021-06-28.00.45.1-0.rec: resume markad process
Jun 28 03:45:00 roadrunner vdr: [30864] markad: cStatusMarkAd::Continue(): index 1, pid 31559, filename /srv/vdr/video/local/Inheritance/2021-06-28.01.20.101-0.rec: resume markad process
Jun 28 04:01:29 roadrunner vdr: [30864] markad: cStatusMarkAd::Remove(): index 0, pid 31338, filename /srv/vdr/video/local/Der_große_Crash_-_Margin_Call/2021-06-28.00.45.1-0.rec: remove from list
Jun 28 04:01:29 roadrunner vdr: [30864] markad: cStatusMarkAd::getStatus(): markad terminated for index 0, recording <NULL>
Jun 28 04:01:47 roadrunner vdr: [30864] markad: cStatusMarkAd::Remove(): index 1, pid 31559, filename /srv/vdr/video/local/Inheritance/2021-06-28.01.20.101-0.rec: remove from list
Jun 28 04:01:47 roadrunner vdr: [30864] markad: cStatusMarkAd::getStatus(): markad terminated for index 1, recording <NULL>

Display More

Danke,

Stefan

Fourty2 · Jun 27th 2021

So manuell beendet, Film durch:

Code

Jun 27 20:09:16 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:09:16 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:09:16 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:09:16 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:09:18 roadrunner vdr: [22467] confirm: Timer löschen?
Jun 27 20:09:18 roadrunner vdr: [22467] warning: Timer löschen?
Jun 27 20:09:19 roadrunner vdr: [22467] confirmed
Jun 27 20:09:19 roadrunner vdr: [22467] confirm: Timer zeichnet auf - trotzdem löschen?
Jun 27 20:09:19 roadrunner vdr: [22467] warning: Timer zeichnet auf - trotzdem löschen?
Jun 27 20:09:21 roadrunner vdr: [22467] confirmed
Jun 27 20:09:21 roadrunner vdr: [22467] timer 1 (101 1805-2013 'Inheritance') set to no event
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cRecordControl::Stop called
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cRecorder destructor called
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cReceiver::Detach() called
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cDevice::Detach called
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cDevice::Detach delete receiver 1
Jun 27 20:09:21 roadrunner vdr: [22627] recording thread ended (pid=22467, tid=22627)
Jun 27 20:09:21 roadrunner vdr: [22467] SendCaPmts CAM 1: [1] actives in CAM: 1 -> 0 (0 pids)
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cDevice::Detach called
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cDevice::Detach delete receiver 0
Jun 27 20:09:21 roadrunner vdr: [22467] SendCaPmts CAM 1: [0] actives in CAM: 1 -> 0 (0 pids)
Jun 27 20:09:21 roadrunner vdr: [22467] CAM 1: unassigned from device 2
Jun 27 20:09:21 roadrunner vdr: [22467] NALU fill dumper: 3478978 of 28779115 packets dropped, 12%
Jun 27 20:09:21 roadrunner vdr: [22467] buffer stats: 1222188 (0%) used
Jun 27 20:09:21 roadrunner vdr: [22467] timer 1 (101 1805-2013 'Inheritance') stop
Jun 27 20:09:21 roadrunner vdr: [22467] removing /srv/vdr/video/local/Inheritance/2021-06-27.18.05.101-0.rec/.timer
Jun 27 20:09:21 roadrunner vdr: [22467] markad: cStatusMarkAd::Recording(): setup->ProcessDuring 0, setup->whileRecording 0, setup->whileReplaying 1
Jun 27 20:09:21 roadrunner vdr: [22467] markad: cStatusMarkAd::Recording(): index 1, pid 22630, filename /srv/vdr/video/local/Inheritance/2021-06-27.18.05.101-0.rec: recording stopped
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:09:21 roadrunner vdr: [22467] markad: cStatusMarkAd::RunningRecording(): at least one recording running on device 0
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 20:09:21 roadrunner vdr: [23430] EPGSearch: recdone thread started (pid=22467, tid=23430, prio=high)
Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 2
Jun 27 20:09:21 roadrunner vdr: [22467] markad: cStatusMarkAd::RunningRecording(): 1 devices with at least one running recordings
Jun 27 20:09:21 roadrunner vdr: [22467] markad: cStatusMarkAd::Recording(): resume not possible, still 1 decives with running recordings
Jun 27 20:09:21 roadrunner vdr: epg2vdr: Recording of '(null)' has 'stopped' [/srv/vdr/video/local/Inheritance/2021-06-27.18.05.101-0.rec]
Jun 27 20:09:21 roadrunner vdr: [22614] device 2 TS buffer thread ended (pid=22467, tid=22614)
Jun 27 20:09:21 roadrunner vdr: [22612] buffer stats: 1218804 (2%) used
Jun 27 20:09:21 roadrunner vdr: [22612] device 2 receiver thread ended (pid=22467, tid=22612)
Jun 27 20:09:22 roadrunner vdr: [22467] deleted timer 1 (101 1805-2013 'Inheritance')
Jun 27 20:09:22 roadrunner vdr: [22467] markad: cStatusMarkAd::Remove(): index 1, pid 22630, filename /srv/vdr/video/local/Inheritance/2021-06-27.18.05.101-0.rec: remove from list
Jun 27 20:09:22 roadrunner vdr: [22467] markad: cStatusMarkAd::Remove(): index 1, pid 22630: killing markad process
Jun 27 20:09:22 roadrunner vdr: [23430] EPGSearch: recdone thread ended (pid=22467, tid=23430)
Jun 27 20:09:22 roadrunner vdr: epg2vdr: Try to lookup timer with id 1876
Jun 27 20:09:22 roadrunner vdr: epg2vdr: Found timer 1876
Jun 27 20:09:22 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:09:22 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:09:22 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 20:09:22 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 20:09:22 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 20:09:22 roadrunner vdr: [22467] switching device 2 to channel 7 S19.2E-1-1107-17501 (ProSieben)
Jun 27 20:09:22 roadrunner vdr: [22467] timer 3 (7 1959-2306 'Star Trek Beyond') start
Jun 27 20:09:22 roadrunner vdr: [22467] Title: 'Star Trek Beyond' Subtitle: 'Star Trek Beyond (Sci-Fi-Abenteuer, USA 2016)'
Jun 27 20:09:22 roadrunner vdr: [22467] executing '/usr/lib/vdr/vdr-recordingaction before "/srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec"'
Jun 27 20:09:22 roadrunner vdr: [22467] recording to '/srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec/00001.ts'
Jun 27 20:09:22 roadrunner vdr: [23462] device 2 receiver thread started (pid=22467, tid=23462, prio=high)
Jun 27 20:09:22 roadrunner vdr: [22467] markad: cStatusMarkAd::Recording(): recording <Star Trek Beyond> [/srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec] started
Jun 27 20:09:22 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:09:22 roadrunner vdr: [22467] markad: cStatusMarkAd::RunningRecording(): at least one recording running on device 0
Jun 27 20:09:22 roadrunner vdr: [23461] recording thread started (pid=22467, tid=23461, prio=high)
Jun 27 20:09:22 roadrunner vdr: [22467] markad debug: 1 receiver(s) runnning on device 1
Jun 27 20:09:22 roadrunner vdr: [22467] markad: cStatusMarkAd::RunningRecording(): at least one recording running on device 1
Jun 27 20:09:22 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 2
Jun 27 20:09:22 roadrunner vdr: [22467] markad: cStatusMarkAd::RunningRecording(): 2 devices with at least one running recordings
Jun 27 20:09:22 roadrunner vdr: [22467] markad: cStatusMarkAd::GetEventID(): eventID 798105 from event for recording <Star Trek Beyond> timer <Star Trek Beyond> timer start 1624816740 stop 1624827960
Jun 27 20:09:22 roadrunner vdr: [23463] device 2 TS buffer thread started (pid=22467, tid=23463, prio=high)
Jun 27 20:09:22 roadrunner vdr: epg2vdr: Try to lookup timer with id 1913
Jun 27 20:09:22 roadrunner vdr: epg2vdr: Found timer 1913
Jun 27 20:09:22 roadrunner vdr: epg2vdr: Cleanup deleted recordings at database (forced)
Jun 27 20:09:23 roadrunner vdr: [22467] markad: cStatusMarkAd::Start(): executing "/usr/bin"/markad -I -G -R --autologo=2 -l "/var/lib/markad" --online=2 before "/srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec"
Jun 27 20:09:24 roadrunner vdr: [22467] markad: cStatusMarkAd::Start(): index 1, pid 23466, filename /srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec: running markad stored in list
Jun 27 20:09:24 roadrunner vdr: [22467] markad: cStatusMarkAd::Pause(): called with filename <NULL>
Jun 27 20:09:24 roadrunner vdr: [22467] markad: cStatusMarkAd::Pause(): index 0, pid 22552, filename /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec: pause markad process
Jun 27 20:09:24 roadrunner vdr: [22467] markad: cStatusMarkAd::Pause(): index 1, pid 23466, filename /srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec: pause markad process
Jun 27 20:09:24 roadrunner vdr: [22467] markad: cStatusMarkAd::Pause(): index 2, pid 22852, filename /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Hitze_und_Hurrikans_(Doku,_GB_2015)/2021-06-27.18.35.10-0.rec: pause markad process
Jun 27 20:09:24 roadrunner vdr: [22467] markad: cStatusMarkAd::Pause(): index 3, pid 23139, filename /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Berge_der_Tiefsee_(Doku,_GB_2015)/2021-06-27.19.20.10-0.rec: pause markad process
Jun 27 20:09:24 roadrunner vdr: epg2vdr: Recording of 'Star Trek Beyond' has 'started' [/srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec]
Jun 27 20:09:24 roadrunner vdr: [22467] writing timer id '3@roadrunner' to /srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec/.timer
Jun 27 20:09:24 roadrunner vdr: [23461] executing '/usr/lib/vdr/vdr-recordingaction started "/srv/vdr/video/local/Star_Trek_Beyond/2021-06-27.19.59.7-0.rec"'
Jun 27 20:09:24 roadrunner vdr: epg2vdr: Info: Marked 0 recordings as deleted
Jun 27 20:09:24 roadrunner vdr: epg2vdr: Updating recording list table

Display More

Vielleicht besser den hier?

Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cRecordControl::Stop called

Jun 27 20:09:21 roadrunner vdr: [22467] markad debug: cRecorder destructor called

Fourty2 · Jun 27th 2021

Och, ich hätte auch einen Patch für einen gut gepflegten Kodi Bug ...

Aber die gesuchte Funktion müßte im VDR bereits vorhanden sein:

Code

Jun 27 20:03:20 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:20 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:20 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:20 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:22 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:22 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:22 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:22 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:25 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:25 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:25 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:25 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:26 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:26 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:27 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:27 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:27 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:27 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:29 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:29 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 20:03:29 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 20:03:29 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1

Display More

Denn der VDR sucht gerade ein freies Device für die dritte Aufnahme (Pro7), das sollte gegen 20:13 frei werden.

Fourty2 · Jun 27th 2021

Von "Live" 3sat HD auf die andere Aufnahme geschaltet:

Code

Jun 27 19:08:05 roadrunner vdr: [22467] switching to channel 101
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: cReceiver::Detach() called
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: cDevice::Detach called
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: cDevice::Detach delete receiver 0
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: cReceiver::Detach() called
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: cDevice::Detach called
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: cDevice::Detach delete receiver 1
Jun 27 19:08:05 roadrunner vdr: [22516] VAAPI: video/vaapi: closing eof
Jun 27 19:08:05 roadrunner vdr: [22513] osdteletext-receiver thread ended (pid=22467, tid=22513)
Jun 27 19:08:05 roadrunner vdr: [22467] buffer stats: 0 (0%) used
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: 1 receiver(s) runnning on device 0
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: 1 receiver(s) runnning on device 0
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 19:08:05 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 19:08:05 roadrunner vdr: [22467] SendCaPmts CAM 1: [0] actives in CAM: 1 -> 1 (3 pids)
Jun 27 19:08:05 roadrunner vdr: [22467] SendCaPmts CAM 1: [0] actives in CAM: 1 -> 1 (3 pids)
Jun 27 19:08:05 roadrunner vdr: [22480] VAAPI: audio/alsa: using pass-through device 'hdmi:CARD=PCH,DEV=2,AES0=0x06'
Jun 27 19:08:05 roadrunner vdr: [22480] VAAPI: audio/alsa: start delay 336ms
Jun 27 19:08:05 roadrunner vdr: [22467] VAAPI: video: set trick-speed 0
Jun 27 19:08:05 roadrunner vdr: [22612] VAAPI: codec: using audio codec ID 0x15003 (ac3)
Jun 27 19:08:05 roadrunner vdr: [22612] VAAPI: codec: audio 'ATSC A/52A (AC-3)'
Jun 27 19:08:05 roadrunner vdr: [22612] VAAPI: codec/audio: format change fltp 48000Hz *6 channels PCM AC-3 pass-through
Jun 27 19:08:05 roadrunner vdr: [22612] VAAPI: codec/audio: resample fltp 48000Hz *6 -> s16 48000Hz *2
Jun 27 19:08:05 roadrunner vdr: [22516] VAAPI: codec: using video codec ID 0x001b (h264)
Jun 27 19:08:05 roadrunner vdr: [22516] VAAPI: codec: video 'H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10'
Jun 27 19:08:05 roadrunner vdr: [22480] VAAPI: audio/alsa: using pass-through device 'hdmi:CARD=PCH,DEV=2,AES0=0x06'
Jun 27 19:08:05 roadrunner vdr: [22480] VAAPI: audio/alsa: start delay 336ms
Jun 27 19:08:06 roadrunner vdr: [22516] VAAPI: video/vaapi: created image 1920x1088 with id 0x0a000001 and buffer id 0x08000005
Jun 27 19:08:07 roadrunner vdr: [22516] VAAPI: video/vaapi: synced after 73 frames

Display More

und wieder zurück

Code

Jun 27 19:08:42 roadrunner vdr: [22467] switching to channel 10 S19.2E-1-1010-11150 (3sat HD)
Jun 27 19:08:42 roadrunner vdr: [22467] markad debug: cReceiver::Detach() called
Jun 27 19:08:42 roadrunner vdr: [22467] markad debug: cDevice::Detach called
Jun 27 19:08:42 roadrunner vdr: [22467] markad debug: cDevice::Detach delete receiver 2
Jun 27 19:08:42 roadrunner vdr: [22516] VAAPI: video/vaapi: closing eof
Jun 27 19:08:42 roadrunner vdr: [22467] SendCaPmts CAM 1: [0] actives in CAM: 1 -> 1 (3 pids)
Jun 27 19:08:42 roadrunner vdr: [22467] markad debug: 1 receiver(s) runnning on device 0
Jun 27 19:08:42 roadrunner vdr: [22467] markad debug: 1 receiver(s) runnning on device 0
Jun 27 19:08:42 roadrunner vdr: [22467] markad debug: 1 receiver(s) runnning on device 0
Jun 27 19:08:42 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 19:08:42 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 1
Jun 27 19:08:42 roadrunner vdr: [23055] osdteletext-receiver thread started (pid=22467, tid=23055, prio=high)
Jun 27 19:08:42 roadrunner vdr: [22467] VAAPI: video: set trick-speed 0
Jun 27 19:08:42 roadrunner vdr: [22467] VAAPI: video/vaapi: clear image
Jun 27 19:08:42 roadrunner vdr: [22480] VAAPI: audio/alsa: using pass-through device 'hdmi:CARD=PCH,DEV=2,AES0=0x06'
Jun 27 19:08:42 roadrunner vdr: [22480] VAAPI: audio/alsa: start delay 336ms
Jun 27 19:08:42 roadrunner vdr: [22511] VAAPI: codec: using audio codec ID 0x15003 (ac3)
Jun 27 19:08:42 roadrunner vdr: [22511] VAAPI: codec: audio 'ATSC A/52A (AC-3)'
Jun 27 19:08:42 roadrunner vdr: [22511] VAAPI: codec/audio: format change fltp 48000Hz *2 channels PCM AC-3 pass-through
Jun 27 19:08:42 roadrunner vdr: [22511] VAAPI: codec/audio: resample fltp 48000Hz *2 -> s16 48000Hz *2
Jun 27 19:08:42 roadrunner vdr: [22480] VAAPI: audio/alsa: using pass-through device 'hdmi:CARD=PCH,DEV=2,AES0=0x06'
Jun 27 19:08:42 roadrunner vdr: [22480] VAAPI: audio/alsa: start delay 336ms
Jun 27 19:08:42 roadrunner vdr: [22516] VAAPI: codec: using video codec ID 0x001b (h264)
Jun 27 19:08:42 roadrunner vdr: [22516] VAAPI: codec: video 'H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10'
Jun 27 19:08:43 roadrunner vdr: [22516] VAAPI: video/vaapi: created image 1280x720 with id 0x0a000001 and buffer id 0x08000008
Jun 27 19:08:44 roadrunner vdr: [22516] VAAPI: video/vaapi: synced after 70 frames

Display More

Fourty2 · Jun 27th 2021

Das ist einmal osdteletext, soweit, wie auf 3sat Videotext gesendet wird:

Code

Jun 27 17:51:21 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 0
Jun 27 17:51:21 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 0
Jun 27 17:51:21 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 0
Jun 27 17:51:21 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:51:21 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:51:21 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:51:21 roadrunner vdr: [22511] device 1 receiver thread started (pid=22467, tid=22511, prio=high)
Jun 27 17:51:21 roadrunner vdr: [22467] creating directory /var/cache/vdr/vtx/S19.2E-1-1010-11150
Jun 27 17:51:21 roadrunner vdr: [22512] device 1 TS buffer thread started (pid=22467, tid=22512, prio=high)
Jun 27 17:51:21 roadrunner vdr: [22513] osdteletext-receiver thread started (pid=22467, tid=22513, prio=high)

und vermutlich die Live-Ausgabe per vaapidevice:

Code

Jun 27 17:51:21 roadrunner vdr: [22514] SVDRP server handler thread started (pid=22467, tid=22514, prio=low)
Jun 27 17:51:21 roadrunner vdr: [22514] SVDRP roadrunner opening port 6419/tcp
Jun 27 17:51:21 roadrunner vdr: [22514] SVDRP roadrunner listening on port 6419/tcp
Jun 27 17:51:21 roadrunner vdr: [22515] SVDRP client handler thread started (pid=22467, tid=22515, prio=low)
Jun 27 17:51:21 roadrunner vdr: [22467] VAAPI: video: set trick-speed 0
Jun 27 17:51:21 roadrunner vdr: [22515] SVDRP roadrunner opening port 6419/udp
Jun 27 17:51:21 roadrunner vdr: [22515] SVDRP roadrunner listening on port 6419/udp
Jun 27 17:51:21 roadrunner vdr: [22515] SVDRP roadrunner > 255.255.255.255:6419 send dgram 'SVDRP:discover name:roadrunner port:6419 vdrversion:20407 apiversion:20407 timeout:300'
Jun 27 17:51:21 roadrunner vdr: [22516] VAAPI: video: display thread started
[... unwichtiges vaapi gelogge..]
Jun 27 17:51:21 roadrunner vdr: [22467] OSD size changed to 1920x1080 @ 1
Jun 27 17:51:21 roadrunner vdr: [22467] nopacity: osd Size changed
Jun 27 17:51:23 roadrunner vdr: [22516] VAAPI: video/vaapi: synced after 59 frames
Jun 27 17:51:31 roadrunner vdr: [22467] VAAPI: video/vaapi: clear image
Jun 27 17:51:32 roadrunner vdr: [22491] EPGSearch: search timer update started
Jun 27 17:51:32 roadrunner vdr: [22492] EPGSearch: timer conflict check started
Jun 27 17:51:32 roadrunner vdr: [22492] EPGSearch: timer conflict check finished
Jun 27 17:51:33 roadrunner vdr: [22511] VAAPI: codec/audio: drift( 0) 744us 357
Jun 27 17:51:33 roadrunner vdr: [22491] timer 0 (114 2100-2220 'His Dark Materials') set to event Mi. 30.06.2021 21:15-22:05 'His Dark Materials'
Jun 27 17:51:33 roadrunner vdr: [22491] timer 0 (114 2150-2315 'His Dark Materials') set to event Mi. 30.06.2021 22:05-23:00 'His Dark Materials'
Jun 27 17:51:33 roadrunner vdr: [22491] timer 0 (114 2100-2220 'His Dark Materials') set to event Mi. 07.07.2021 21:15-22:05 'His Dark Materials'
Jun 27 17:51:33 roadrunner vdr: [22491] timer 0 (114 2150-2310 'His Dark Materials') set to event Mi. 07.07.2021 22:05-22:55 'His Dark Materials'
Jun 27 17:51:33 roadrunner vdr: [22491] timer 0 (114 2240-0000 'His Dark Materials') set to event Mi. 07.07.2021 22:55-23:45 'His Dark Materials'
Jun 27 17:51:34 roadrunner vdr: [22491] EPGSearch: search timer update finished
Jun 27 17:52:03 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 0

Display More

Es läuft ein streamdev-server, aber der belegt erst, wenn in Betrieb.

Plugins:

Code

ii vdr 10:2.4.7+git20210524-3u4 amd64 Video Disk Recorder for DVB cards
ii vdr-plugin-ddci2 10:1.0.5+git20190511-1u52 amd64 A VDR plugin, ci-support for DD/Cine/L4M ci-adapter
ii vdr-plugin-devstatus 0.4.1-1u80 amd64 <insert up to 60 chars description>
ii vdr-plugin-dvd 0.3.7-20u74 amd64 DVD playback plugin for VDR
ii vdr-plugin-dvdswitch 0.2.3+git20170722-1u72 amd64 VDR plugin to create, burn, mount and manage DVD iso images
ii vdr-plugin-epg2vdr 10:1.1.118+git20201122-3u17 amd64 VDR EPG2VDR Plugin
ii vdr-plugin-epgborder 10:1.0.0+git20181229-1u42 amd64 VDR Plugin for limiting the epg to channels above a given channel number
ii vdr-plugin-epgsearch 2.4.1+git20210626-1u4 amd64 VDR plugin that provides extensive EPG searching capabilities
ii vdr-plugin-externalplayer 0.3.5+git20210207-1u13 amd64 Plugin to VDR that allows usage of external players
ii vdr-plugin-femon 2.4.0-1+git20191224-1u37 amd64 DVB frontend status monitor plugin for VDR
ii vdr-plugin-live 2.3.1+git20201221-1u15 amd64 Web administration plugin for VDR
ii vdr-plugin-markad 3.0.5-24-g7b75f44+git20210627-1u0 amd64 Plugin for VDR to mark advertisements in recordings
ii vdr-plugin-osdteletext 0.9.7+git20180523-2u44 amd64 Teletext plugin for VDR
ii vdr-plugin-radio 1.1.1+git20181207-2u44 amd64 VDR Plugin that displays images for radio channels
ii vdr-plugin-recsearch 0.3.7-1u72 amd64 A VDR plugin for searching through your recordings
ii vdr-plugin-remote 0.7.0-1u97 amd64 VDR Plugin to support the built-in remote control port of DVB-Cards
ii vdr-plugin-scraper2vdr 10:1.0.11+git20201122-2u14 amd64 VDR SCRAPER2VDR Plugin
ii vdr-plugin-skinnopacity 1.1.8+git20210626-1u3 amd64 nOpacity skin for VDR
ii vdr-plugin-streamdev-server 0.6.1+git20191224-1u36 amd64 VDR Plugin to stream Live-TV to other VDR's - server part
ii vdr-plugin-suspendoutput 2.1.0-1u67 amd64 vdr plugin for suspending output
ii vdr-plugin-tvguide 10:1.3.5+git20210523-1u7 amd64 Advanced EPG viewer plugin for VDR
ii vdr-plugin-vaapidevice 1.0.0+git20190915-5u17 amd64 VAAPI HD output device plugin for VDR

Display More

Fourty2 · Jun 27th 2021

Ohne Aufnahmen kommt:

roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 0 vielfach

roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1 einfach

Pause - Repeat.

Mit 1x Aufnahme(n) ist größtenteils, mit Aufnahmen auf beiden Karten völlige Ruhe.

Device 0 = DVB-S (Eingang 1 einer Karte)

Device 1 = DVB-S (Eingang 2 einer Karte)

Device 2 = vaapidevice (Ausgabe, primär)

Aufnahmeprogramm:

Log dann anschließend.

Fourty2 · Jun 27th 2021

So... läuft:

Und die Gewitter sind auch pünktlich eingetroffen. Grrr... :-/

Code

Jun 27 17:54:51 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 0
[ca. 50x]Jun 27 17:54:51 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 0
Jun 27 17:54:51 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:54:54 roadrunner vdr: [22511] VAAPI: codec/audio: drift( 0) 15622us 7498
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 0
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 0
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 2 receiver(s) runnning on device 0
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:55:00 roadrunner vdr: [22467] switching device 1 to channel 10 S19.2E-1-1010-11150 (3sat HD)
Jun 27 17:55:00 roadrunner vdr: [22467] timer 5 (10 1755-1855 'Atlantik - Ozean der Extreme~Strom des Lebens (Doku, GB 2015)') start
Jun 27 17:55:00 roadrunner vdr: [22467] Title: 'Atlantik - Ozean der Extreme' Subtitle: 'Strom des Lebens (Doku, GB 2015)'
Jun 27 17:55:00 roadrunner vdr: [22467] executing '/usr/lib/vdr/vdr-recordingaction before "/srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec"'
Jun 27 17:55:00 roadrunner recordingaction: executing /usr/share/vdr/recording-hooks/R60.copyepgimage before recording /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec as shell script
Jun 27 17:55:00 roadrunner recordingaction: executing /usr/share/vdr/recording-hooks/R70.copylogs before recording /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec
Jun 27 17:55:00 roadrunner recordingaction: executing /usr/share/vdr/recording-hooks/R90.custom before recording /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec as shell script
Jun 27 17:55:00 roadrunner vdr: [22467] record /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec
Jun 27 17:55:00 roadrunner vdr: [22467] Starting NALU fill dumper
Jun 27 17:55:00 roadrunner vdr: [22467] recording to '/srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec/00001.ts'
Jun 27 17:55:00 roadrunner vdr: [22467] markad: cStatusMarkAd::Recording(): recording <Atlantik - Ozean der Extreme~Strom des Lebens (Doku, GB 2015)> [/srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec] started
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 3 receiver(s) runnning on device 0
Jun 27 17:55:00 roadrunner vdr: [22467] markad: cStatusMarkAd::RunningRecording(): at least one recording running on device 0
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:55:00 roadrunner vdr: [22549] recording thread started (pid=22467, tid=22549, prio=high)
Jun 27 17:55:00 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 2
Jun 27 17:55:00 roadrunner vdr: [22467] markad: cStatusMarkAd::RunningRecording(): 1 devices with at least one running recordings
Jun 27 17:55:00 roadrunner vdr: [22467] markad: cStatusMarkAd::GetEventID(): eventID 796681 from event for recording <Atlantik - Ozean der Extreme~Strom des Lebens (Doku, GB 2015)> timer <Atlantik - Ozean der Extreme~Strom des Lebens (Doku, GB 2015)> timer start 1624809300 stop 1624812900
Jun 27 17:55:01 roadrunner vdr: [22467] markad: cStatusMarkAd::Start(): executing "/usr/bin"/markad -I -G -R --autologo=2 -l "/var/lib/markad" --online=2 before "/srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec"
Jun 27 17:55:02 roadrunner vdr: [22467] markad: cStatusMarkAd::Start(): index 0, pid 22552, filename /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec: running markad stored in list
Jun 27 17:55:02 roadrunner vdr: [22467] markad: cStatusMarkAd::Pause(): called with filename <NULL>
Jun 27 17:55:02 roadrunner vdr: [22467] markad: cStatusMarkAd::Pause(): index 0, pid 22552, filename /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec: pause markad process
Jun 27 17:55:02 roadrunner vdr: epg2vdr: Recording of 'Atlantik - Ozean der Extreme~Strom des Lebens (Doku, GB 2015)' has 'started' [/srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec]
Jun 27 17:55:04 roadrunner vdr: epg2vdr: Info: Found 4959 recordings; 0 inserted; 256 updated and 681 directories
Jun 27 17:55:04 roadrunner vdr: epg2vdr: Updating recording info in info.epg2vdr
Jun 27 17:55:04 roadrunner vdr: epg2vdr: Warning: Recording '/srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec' not found or missin recording info, can't create info.epg2vdr
Jun 27 17:55:04 roadrunner vdr: epg2vdr: Updated 0 pending info.epg2vdr files
Jun 27 17:55:04 roadrunner vdr: epg2vdr: Detected epgd state 'busy (match)' (4)
Jun 27 17:55:04 roadrunner vdr: [22549] executing '/usr/lib/vdr/vdr-recordingaction started "/srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec"'
Jun 27 17:55:04 roadrunner vdr: [22467] writing timer id '5@roadrunner' to /srv/vdr/video/local/Atlantik_-_Ozean_der_Extreme/Strom_des_Lebens_(Doku,_GB_2015)/2021-06-27.17.55.10-0.rec/.timer
Jun 27 17:55:04 roadrunner vdr: [22468] activated name checking for initial read of video directory
Jun 27 17:55:05 roadrunner vdr: epg2vdr: Updating table timers (and remove deleted and finished timers older than 2 days)
Jun 27 17:55:05 roadrunner vdr: epg2vdr: 'Update' timer for event 796681 'Atlantik - Ozean der Extreme' at database
Jun 27 17:55:06 roadrunner vdr: epg2vdr: 'Insert' timer for event 807560 'Die Freunde des Bräutigams' at database
Jun 27 17:55:06 roadrunner vdr: epg2vdr: 'Insert' timer for event 807569 'Die Freunde des Bräutigams' at database
Jun 27 17:55:06 roadrunner vdr: epg2vdr: 'Insert' timer for event 826902 'Party Animals ... Wilder geht's nicht!' at database
Jun 27 17:55:06 roadrunner vdr: epg2vdr: Updating table timers done
Jun 27 17:55:06 roadrunner vdr: [22467] max. latency time 6 seconds
Jun 27 17:55:12 roadrunner vdr: [22467] markad debug: 0 receiver(s) runnning on device 1
Jun 27 17:55:14 roadrunner vdr: scraper2vdr: epgd busy, trying again in 60 seconds ...
Jun 27 17:55:15 roadrunner vdr: [22468] video directory scanner thread ended (pid=22467, tid=22468)
Jun 27 17:55:22 roadrunner vdr: [22516] VAAPI: video: 19:15:23.325 +4 318 0/\ms 32+2 v-buf

Display More

Edit: Zum Aufnahmestart (FTA 3sat) hat es noch gereicht, das Wetter.

Fourty2 · Jun 27th 2021

Lief ja auch seit ziemlich exakt 5 Minuten ...

Na gut, nochmal...

Edit:

Hunk failed...

Diff

--- device.c
+++ device.c
@@ -1904,6 +1906,7 @@ bool cDevice::AttachReceiver(cReceiver *Receiver)
void cDevice::Detach(cReceiver *Receiver)
{
+ dsyslog("markad debug: cDevice::Detach called");
if (parentDevice)
return parentDevice->Detach(Receiver);
if (!Receiver || Receiver->device != this)

Display More

sieht bei mir jetzt so aus - hoffe äquivalent:

Code

void cDevice::Detach(cReceiver *Receiver)
{
dsyslog("markad debug: cDevice::Detach called");
if (!Receiver || Receiver->device != this)
return;
bool receiversLeft = false;
mutexReceiver.Lock();
for (int i = 0; i < MAXRECEIVERS; i++) {
if (receiver[i] == Receiver) {
dsyslog("markad debug: cDevice::Detach delete receiver %d", i);
receiver[i] = NULL;
}
else if (receiver[i])
receiversLeft = true;
}
mutexReceiver.Unlock();
Receiver->device = NULL;
Receiver->Activate(false);
for (int n = 0; n < Receiver->numPids; n++)
DelPid(Receiver->pids[n]);
if (camSlot) {
if (Receiver->priority > MINPRIORITY) { // priority check to avoid an infinite loop with the CAM slot's caPidReceiver
camSlot->StartDecrypting();
if (!camSlot->IsDecrypting() && !camSlot->IsActivating())
camSlot->Assign(NULL);
}
}
if (!receiversLeft)
Cancel(-1);
}

Display More

Stefan

Fourty2 · Jun 27th 2021

kfb77 wrote:

Jetzt wissen wir wenigstens, dass der Patch von HelmutB nicht die Ursache ist.

Die einzige Unterbrechung ist ja gleich nach dem Restart wegen dem epg2vdr Crash (da solltest du auch mal nach der Ursache suchen, normal ist das nicht, ich nutze das Plugin auch und habe nie einen Crash).

Das epg2vdr läuft hier mit der MariaDB-Nonblocking-API. Die Probleme kamen mit dem vorletzten MairaDB Update vor ein paar Tagen, gestern gab es noch ein Update, vielleicht ein externes Problem. Wird sich dann zeigen, beim Recompile.

kfb77 wrote:

Verdacht: Aufnahme auf Device 0 bricht ab, wird auf dem gleichen Device wieder gestartet (macht ja auch Sinn, weil das Device schon auf den Channel getuned ist). Die abgebrochene Aufnahme wird aber nie aufgeräumt und somit bleibt immer eine laufende Aufnahme auf dem Device. Solange die erste Aufnahme (oder jede andere Aufnahme auf dem gleichen Transponder) noch läuft, sieht alles gut aus, ist es aber nicht. Das Problem wird erst sichtbar, wenn die letzte Aufnahme zu Ende ist. Reine Theorie ...

Sieht mir - derzeit - eher so aus, als wenn beim PID-Update, das jeweils den MarkAd-Prozess killed, was schief läuft. Nach Neustart (um 19:21) war noch alles exakt wie zuvor. Das ändert sich mit dem direkt anschließenden PID-Update.

kfb77 wrote:

Poste mal bitte noch das vollständige Log ab Restart bis 19:23, vielleicht sieht man da was.

Das Wesentliche anbei... (und noch ein weiteres PID-Update des Nachts am Ende einer Aufnahme)

kfb77 wrote:

Neuer Versuch, der Ursache für den falschen Status vom VDR näher zu kommen:

Dieser Branch für markad und zusätzlich der Patch in Anhang für den vdr.

Damit müssten aus dem VDR folgende zusätzliche Debug Meldungen kommen (nicht verwirren lassen, sind vdr debug Meldungen, ich habe nur wegen dem einfacherem grep "markad debug" davor gestellt:

Code

Jun 27 10:57:16 VDR-2004-Dev vdr: [50862] markad debug: 2 receiver(s) runnning on device 0

Jun 27 10:57:20 VDR-2004-Dev vdr: [50858] markad debug: 0 receiver(s) runnning on device 1

Spannend ist dann der Zeitpunkt (bitte gleich vollständiges syslog), wo die Summe der laufenden Aufnahmen nicht mehr mit der Summe der Receiver von allen Devicen zusammen passt.

Vorsicht: das schreibt intensiv ins syslog, den VDR Patch nach dem Test wieder entfernen.

Mach ich, sobald möglich. Das Syslog ist egal, das läuft zunächst ins reichliche vorhandene RAM ...

Stefan

Fourty2 · Jun 26th 2021

Leider gibt es das Problem noch. Es gab zwar einen epg2vdr gpf in der ersten von drei sich überschneidenden Aufnahmen, danach war die Zählung aber noch korrekt (meine ich).

(#0) XX:XX --- / ---

(#1) 18:05 Rec 1, Card A / --

(#2) 20:00 Rec 1, Card A / Rec 2, Card B

(#1) 20:30 --- / Rec 2, Card B

(#2) 21:40 Rec 3, Card A / Rec 2, Card B

(#1) 22:30 Rec 3, Card A / --- <--- Aufnahmezähler bleibt auf 2

(#0) 00:05 --- / --- <--- Aufnahmezähler bleibt auf 1

Log anbei.

Stefan

Fourty2 · Jun 26th 2021

Korrekt. Zumindest, soweit es ein VDR im reinen Aufnahmebetrieb ist.

Ansonsten stehen Aufnahmen mit dicken (Gewitter-) Wolken schonmal dem Wunsch entgegen, eine ältere Aufnahme abzuspielen. Und ja, die Technik bleibt hier an: die Lösung ist rot, vor dem Zähler angeklemmt und macht ein fröhliches, aber bestimmtes, Schlafende weckendes "Klong" im Falle des "Betriebs".

Stefan

Fourty2 · Jun 26th 2021

Nicht wirklich. Der VDSB erwirkt den Neustart. Das habe ich bei mir aus (Setup -> Notaustieg -> nein), damit, falls das Modul keine Lust zur Zweitdekodierung hat, nicht gleich beide Aufnahmen toast sind.

Stefan

Fourty2 · Jun 26th 2021

Eine einzelne Aufnahme ohne PID-Update bedingte Unterbrechung lief sauber durch:

Code

Jun 26 13:10:00 roadrunner vdr: [11060] markad: cStatusMarkAd::Recording(): recording <Inheritance> [/srv/vdr/video/local/Inheritance/2021-06-26.13.10.100-0.rec] started
Jun 26 13:10:00 roadrunner vdr: [11060] markad: cStatusMarkAd::GetEventID(): eventID 794979 from event for recording <Inheritance> timer <Inheritance> timer start 1624705800 stop 1624714500
Jun 26 13:10:01 roadrunner vdr: [11060] markad: cStatusMarkAd::Start(): executing "/usr/bin"/markad -I -G -R --autologo=2 -l "/var/lib/markad" --online=2 before "/srv/vdr/video/local/Inheritance/2021-06-26.13.10.100-0.rec"
Jun 26 13:10:02 roadrunner vdr: [11060] markad: cStatusMarkAd::Start(): index 0, pid 11569, filename /srv/vdr/video/local/Inheritance/2021-06-26.13.10.100-0.rec: running markad stored in list
Jun 26 13:10:02 roadrunner vdr: [11060] markad: cStatusMarkAd::Pause(): called with filename <NULL>
Jun 26 13:10:02 roadrunner vdr: [11060] markad: cStatusMarkAd::Pause(): index 0, pid 11569, filename /srv/vdr/video/local/Inheritance/2021-06-26.13.10.100-0.rec: pause markad process
Jun 26 14:12:51 roadrunner vdr: [11060] markad: cStatusMarkAd::Replaying called, start recording <NULL>
Jun 26 15:35:00 roadrunner vdr: [11060] markad: cStatusMarkAd::Recording(): setup->ProcessDuring 0, setup->whileRecording 0, setup->whileReplaying 1
Jun 26 15:35:00 roadrunner vdr: [11060] markad: cStatusMarkAd::Recording(): index 0, pid 11569, filename /srv/vdr/video/local/Inheritance/2021-06-26.13.10.100-0.rec: recording stopped
Jun 26 15:35:00 roadrunner vdr: [11060] markad: cStatusMarkAd::RunningRecording(): 0 running recordings
Jun 26 15:35:00 roadrunner vdr: [11060] markad: cStatusMarkAd::Continue(): called with filename <NULL>
Jun 26 15:35:00 roadrunner vdr: [11060] markad: cStatusMarkAd::Continue(): index 0, pid 11569, filename /srv/vdr/video/local/Inheritance/2021-06-26.13.10.100-0.rec: resume markad process
Jun 26 15:44:52 roadrunner vdr: [11060] markad: cStatusMarkAd::Remove(): index 0, pid 11569, filename /srv/vdr/video/local/Inheritance/2021-06-26.13.10.100-0.rec: remove from index
Jun 26 15:44:52 roadrunner vdr: [11060] markad: cStatusMarkAd::getStatus(): markad terminated for index 0, recording <NULL>

Display More

Stefan

Posts by Fourty2

[markad] überarbeiteter Decoder

Crash bei drei gleichzeitig startenden Aufnahmen

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder

[markad] überarbeiteter Decoder