Woher kommt Log-Spam "loading marks"

MegaV0lt · 22. Mai 2019

Die Meldungen "loading marks" beim abspielen einer Aufnahme hatte ich auch schon früher mehrfach im Log. Seit der Version 2.4.0 kommen die Maeldungen aber teilweise gleich 20 mal hintereinander.

Die Frage ist, wer die Meldungen absetzt? Ist es der VDR oder ein Plugin?
Kann das jemand bestätigen, oder ist es bei mir ein Einzelfall?

Kurzes Beispiel:

Code

May 21 18:30:37 vdr01 vdr[4043]: [4043] replay /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec
May 21 18:30:37 vdr01 vdr[4043]: [4043] playing '/video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/00001.ts'
May 21 18:30:37 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:37 vdr01 vdr[4043]: [4043] [softhddev]CreateOsd: 0, 0, 0
May 21 18:30:37 vdr01 vdr[4043]: [4043] skinflatplus: create osd SUCCESS left: 0 top: 0 width: 1920 height: 1080
May 21 18:30:37 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:37 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:37 vdr01 vdr[4043]: audio/alsa: using device 'hw:NVidia,7'
May 21 18:30:37 vdr01 vdr[4043]: [4043] [softhddev]SetPlayMode: 1
May 21 18:30:37 vdr01 vdr[4043]: [32290] dvbplayer thread started (pid=4043, tid=32290, prio=high)
May 21 18:30:37 vdr01 vdr[4043]: [32291] non blocking file reader thread started (pid=4043, tid=32291, prio=high)
May 21 18:30:37 vdr01 vdr[4043]: [32292] video directory scanner thread started (pid=4043, tid=32292, prio=low)
May 21 18:30:37 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:37 vdr01 vdr[4043]: [32292] video directory scanner thread ended (pid=4043, tid=32292)
May 21 18:30:37 vdr01 vdr[4043]: audio/alsa: start delay 336ms
May 21 18:30:37 vdr01 vdr[4043]: audio/alsa: using device 'hw:NVidia,7'
May 21 18:30:37 vdr01 vdr[4043]: video/vdpau: missed frame (20/12)
May 21 18:30:37 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:37 vdr01 vdr[4043]: audio/alsa: start delay 336ms
May 21 18:30:37 vdr01 vdr[4043]: audio/alsa: using pass-through device 'hw:NVidia,7'
May 21 18:30:37 vdr01 vdr[4043]: video/vdpau: missed frame (23/18)
May 21 18:30:37 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:37 vdr01 vdr[4043]: video: 10:49:51.152+1251 2720   0/\ms  51+7+2 v-buf
May 21 18:30:37 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:37 vdr01 vdr[4043]: video/vdpau: missed frame (24/20)
May 21 18:30:38 vdr01 vdr[4043]: video/vdpau: missed frame (25/20)
May 21 18:30:38 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:38 vdr01 vdr[4043]: video: 10:49:51.192+1291 3008   0/\ms  57+7+4 v-buf
May 21 18:30:38 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:38 vdr01 vdr[4043]: audio/alsa: start delay 336ms
May 21 18:30:38 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:38 vdr01 vdr[4043]: video/vdpau: missed frame (26/22)
May 21 18:30:38 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:38 vdr01 vdr[4043]: video: 10:49:51.232+1304 3493   0/\ms  67+7+2 v-buf
May 21 18:30:38 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:38 vdr01 epgd[4456]: State now 'busy (match)'
May 21 18:30:38 vdr01 vdr[4043]: [5096] SVDRP vdr01 < 192.168.178.68:52234 client connection accepted
May 21 18:30:38 vdr01 vdr[4043]: [5096] SVDRP vdr01 > 192.168.178.68:52234 server created
May 21 18:30:38 vdr01 epgd[4456]: Send 'PLUG epg2vdr STATE busy (match)' to '192.168.178.68:6419'
May 21 18:30:38 vdr01 vdr[4043]: epg2vdr: Got epgd state 'busy (match)' (4)
May 21 18:30:38 vdr01 vdr[4043]: epg2vdr: Change handler state to 'standby'
May 21 18:30:38 vdr01 vdr[4043]: [5096] SVDRP vdr01 < 192.168.178.68:52234 lost connection to client
May 21 18:30:38 vdr01 vdr[4043]: [5096] SVDRP vdr01 < 192.168.178.68:52234 connection closed
May 21 18:30:38 vdr01 vdr[4043]: [5096] SVDRP vdr01 < 192.168.178.68:52234 server destroyed
May 21 18:30:38 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:38 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:38 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:38 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:38 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:39 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:39 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:39 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:39 vdr01 vdr[4043]: [4043] [softhddev]Clear:
May 21 18:30:39 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:39 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:39 vdr01 vdr[4043]: video: decoder buffer empty, duping frame (1329/58) 5 v-buf
May 21 18:30:39 vdr01 vdr[4043]: video: --:--:--.---   +0    0   0/\ms   5+5+4 v-buf
May 21 18:30:39 vdr01 vdr[4043]: video: decoder buffer empty, duping frame (1330/6) 23 v-buf
May 21 18:30:39 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:39 vdr01 vdr[4043]: video: 10:50:51.792+1027 1376   0/\ms  24+7+3 v-buf
May 21 18:30:39 vdr01 vdr[4043]: audio/alsa: using pass-through device 'hw:NVidia,7'
May 21 18:30:39 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:39 vdr01 vdr[4043]: audio/alsa: start delay 336ms
May 21 18:30:39 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:39 vdr01 vdr[4043]: [4043] [softhddev]Clear:
May 21 18:30:40 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:40 vdr01 vdr[4043]: video: decoder buffer empty, duping frame (1340/16) 0 v-buf
May 21 18:30:40 vdr01 vdr[4043]: video: --:--:--.---   +0    0   0/\ms   0+5+4 v-buf
May 21 18:30:40 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:40 vdr01 vdr[4043]: video: decoder buffer empty, duping frame (1341/16) 27 v-buf
May 21 18:30:40 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:40 vdr01 vdr[4043]: video: 10:51:51.112 +603 1504   0/\ms  32+7+3 v-buf
May 21 18:30:40 vdr01 vdr[4043]: audio/alsa: using pass-through device 'hw:NVidia,7'
May 21 18:30:40 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:40 vdr01 vdr[4043]: audio/alsa: start delay 336ms
May 21 18:30:40 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:40 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:40 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:40 vdr01 vdr[4043]: [4043] [softhddev]Clear:
May 21 18:30:40 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:40 vdr01 vdr[4043]: audio/alsa: using pass-through device 'hw:NVidia,7'
May 21 18:30:40 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:41 vdr01 vdr[4043]: audio/alsa: start delay 336ms
May 21 18:30:41 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:41 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:41 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:41 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:41 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:41 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks
May 21 18:30:41 vdr01 vdr[4043]: [4043] [softhddev]Clear:
May 21 18:30:41 vdr01 vdr[4043]: video: slow down video, duping frame
May 21 18:30:41 vdr01 vdr[4043]: video: decoder buffer empty, duping frame (1380/36) 0 v-buf
May 21 18:30:41 vdr01 vdr[4043]: video: --:--:--.---   +0    0   0/\ms   0+5+4 v-buf
May 21 18:30:41 vdr01 vdr[4043]: [4043] loading /video/Chicago_Med/Code_Orange__(S04E07)/2019-04-15.21.40.23-0.rec/marks

Alles anzeigen

fnu · 22. Mai 2019

Zitat von MegaV0lt

Ist es der VDR oder ein Plugin?

Das ist ziemlich sicher ein Plugin ... ExtRecMenu ... ?

Regards

fnu

mini73 · 22. Mai 2019

Oder ein Skin?

Welcher Thread ist denn [4043]?

Lars.

MegaV0lt · 22. Mai 2019

PID 4043 ist der vdr

ExtRecMenu habe ich nicht am laufen

Plugins:

softhddevice
dvb***i

epgsearch

skinflatplus

markad

osdteletext

epg2vdr

scraper2vdr

fnu · 22. Mai 2019

Stell doch mal testweise auf einen VDR eingebauten Skin um ... ?

jrie · 22. Mai 2019

Oder mache eine Suche im Quelltext nach der Meldung.

helau · 22. Mai 2019

Wenn ich den Code richtig verstehe, lädt Skinflatplus in der UpdateInfo Prozedur immer das MarksFile neu ...

MegaV0lt · 22. Mai 2019

Ok, aber die Meldung wird vom VDR erzeugt? Oder täsche ich mich?

mini73 · 22. Mai 2019

Hängt davon ab. Das Skin wird sicherlich die vdr-Funktion benutzen, um die marks-Datei einzulesen. Und wenn das z.B. im MainThread passiert, bekommt die Meldung die TID vom vdr.

Vermutlich würdest du sie mit vdr-Loglevel 2 wegbekommen (wie auch einige andere Meldungen).

MegaV0lt · 22. Mai 2019

Ja den Loglevel vom VDR werde ich mal auf 2 stellen. Nächster Schritt wird dann sein sich mit dem Skin-Programmierer in Verbindung zu setzen

MegaV0lt · 14. Oktober 2020

Ich habe es jetzt so gelöst, dass ich in der syslog-ng.conf die Option suppress() aktiviert habe

Code

    # If several identical log messages would be sent to the destination without any other
    # messages between the identical messages (for example, an application repeated an
    # error message ten times), syslog-ng can suppress the repeated messages and send
    # the message only once, followed by the Last message repeated n times. message.
    # The parameter of this option specifies the number of seconds syslog-ng waits
    # for identical messages.
    suppress(1);

Vorteil ist, dass andere sich wiederholende Logzeilen auch gleich eingedampft werden

davie2000 · 14. Oktober 2020

Das Verhalten ist doch standardmässig aktiviert (yaVDR Ansible), oder?

Ich habe zumindest nichts wissentlich an der Datei geändert - die gibts bei mir gar nicht -, habe aber trotzdem öfters mal "This messages was repated x times" im syslog.

MegaV0lt · 14. Oktober 2020

default ist 0. Das ist deaktiviert.

Bei GEN2VDR ist es auf jeden Fall nicht drin.

Hängt von der Distribution ab

Woher kommt Log-Spam "loading marks"

Jetzt mitmachen!

Teilen