kurze Audio/Bild Aussetzer / Zusammenhang mit EPGD ?

horchi · 5. Mai 2014

bevor wir etwas einbauen sollten wir die Ursache genauer eingrenzen. Daher ein paar Fragen:

- hilft es denn auch wenn man statt scraper2vdr epg2vdr deaktiviert?
- hilft es wenn beide Plugins geladen sind jedoch der epgd nicht läuft?
- die 'kritische/auffällige' CPU Load kommt immer vom mysqld oder auch mal VDR oder vom epgd?

LG Jörg

goldbär · 5. Mai 2014

Zitat von louis

Ich denke, das beste wäre es, wir bauen erst mal noch ein bisschen mehr Debugausgaben ein, damit wir sehen können, in welcher Konstellation es zu der hohen Last kommt.

Wäre dann auch gerne bereit zu testen

goldbär · 5. Mai 2014

Zitat von horchi

bevor wir etwas einbauen sollten wir die Ursache genauer eingrenzen. Daher ein paar Fragen:
- hilft es denn auch wenn man statt scraper2vdr epg2vdr deaktiviert?
LG Jörg

nein, bei mir nicht

louis · 5. Mai 2014

Moin,

ich komme aktuell leider nicht dazu...aber die Debugausgaben im scraper2vdr einzubauen ist easy, vielleicht kann sich dem jemand annehmen und einen kleinen Patch erstellen.

Alle Tasks werden im Main Loop gestartet, der befindet sich hier: http://projects.vdr-developer.…ster/entry/update.c#L1176

Ist sogar schön kommentiert

Ciao Louis

goldbär · 5. Mai 2014

Zitat von horchi

bevor wir etwas einbauen sollten wir die Ursache genauer eingrenzen. Daher ein paar Fragen:
- hilft es wenn beide Plugins geladen sind jedoch der epgd nicht läuft?
LG Jörg

Habe jetzt folgendes gemacht:

Code

nano /etc/vdr/plugins/order.conf 
...
scraper2vdr
epg2vdr


sudo stop vdr-backend
sudo killall epgd
sudo start vdr-backend

Ergebnis kommt später

Edit: keine Aussetzer

Perlbo · 5. Mai 2014

hatte gerade einen Aussetzer 13:05. Hier der Syslog dazu. (das ist alles was zu 13:05 drin stand.)

Code

May  5 12:55:47 ubuntu vdr: [1928] timer 12 (8 2012-2123 'Rizzoli & Isles~(S04E16) Der Feind in deinem Bett') set to event Mit 07.05.2014 20:15-21:15 'Rizzoli & Isles'
May  5 12:55:47 ubuntu vdr: EPG2VDR: Updated changes since '05.05.2014 12:53:16'; 38 channels, 1160 events (0 deletions) in 0.513 seconds
May  5 12:56:28 ubuntu vdr: video:  6:16:45.885  +32  344   0/\ms  12+7 v-buf
May  5 12:57:08 ubuntu vdr: [1078] [softhddev]SetPlayMode: 0
May  5 12:57:08 ubuntu vdr: [1078] [softhddev]SetVideoDisplayFormat: 1
May  5 12:57:08 ubuntu vdr: [1078] [softhddev]GetSpuDecoder:
May  5 12:57:08 ubuntu vdr: [1078] [extrecmenu] starting replay of recording
May  5 12:57:08 ubuntu vdr: [1078] replay /srv/vdr/video.00/New_Girl/(S03E11)_Wie_man_sich_entscheidet/2014-04-30.21.11.5-0.rec
May  5 12:57:08 ubuntu vdr: [1078] playing '/srv/vdr/video.00/New_Girl/(S03E11)_Wie_man_sich_entscheidet/2014-04-30.21.11.5-0.rec/00001.ts'
May  5 12:57:08 ubuntu vdr: [1960] TS buffer on device 1 thread ended (pid=1078, tid=1960)
May  5 12:57:08 ubuntu vdr: [1959] buffer stats: 78208 (1%) used
May  5 12:57:08 ubuntu vdr: [1959] receiver on device 1 thread ended (pid=1078, tid=1959)
May  5 12:57:08 ubuntu vdr: [1078] loading /srv/vdr/video.00/New_Girl/(S03E11)_Wie_man_sich_entscheidet/2014-04-30.21.11.5-0.rec//marks
May  5 12:57:08 ubuntu vdr: [1078] loading /srv/vdr/video.00/New_Girl/(S03E11)_Wie_man_sich_entscheidet/2014-04-30.21.11.5-0.rec//marks
May  5 12:57:08 ubuntu vdr: [1078] ERROR: attempt to open OSD while it is already open - using dummy OSD!
May  5 12:57:08 ubuntu vdr: [1078] [softhddev]SetPlayMode: 1
May  5 12:57:08 ubuntu vdr: [2638] dvbplayer thread started (pid=1078, tid=2638, prio=high)
May  5 12:57:08 ubuntu vdr: [2638] resuming replay at index 10760 (0:07:10.11)
May  5 12:57:08 ubuntu vdr: [2639] non blocking file reader thread started (pid=1078, tid=2639, prio=high)
May  5 12:57:08 ubuntu vdr: audio/alsa: using device 'default'
May  5 12:57:08 ubuntu vdr: audio/alsa: start delay 336ms
May  5 12:57:08 ubuntu vdr: audio/alsa: using pass-through device 'default'
May  5 12:57:08 ubuntu vdr: audio/alsa: start delay 336ms
May  5 12:57:09 ubuntu vdr: video/vdpau: missed frame (2/6)
May  5 12:57:10 ubuntu vdr: video/vdpau: missed frame (3/8)
May  5 12:57:10 ubuntu vdr: video: slow down video, duping frame
May  5 12:57:10 ubuntu vdr: video: 25:30:57.723 +105 2568   0/\ms 182+7 v-buf
May  5 12:57:10 ubuntu vdr: video/vdpau: synced after 85 frames
May  5 12:57:14 ubuntu vdr: [1078] [softhddev]Freeze:
May  5 12:57:14 ubuntu vdr: video: slow down video, duping frame
May  5 12:57:14 ubuntu vdr: video: decoder buffer empty, duping frame (9/194) 182 v-buf
May  5 12:57:14 ubuntu vdr: video: 25:31:01.483+8888    0   0/\ms 182+5 v-buf
May  5 12:58:08 ubuntu vdr: video: decoder buffer empty, duping frame (2740/194) 182 v-buf
May  5 12:58:08 ubuntu vdr: video: 25:31:01.483+8888    0   0/\ms 182+5 v-buf
May  5 12:58:17 ubuntu vdr: [1078] [softhddev]Play:
May  5 12:58:17 ubuntu vdr: audio/alsa: wait underrun error? 'Daten√ºbergabe unterbrochen (broken pipe)'
May  5 12:58:58 ubuntu vdr: [1903] changing pids of channel 123 from 3327+3327=2:3328=deu@3:0:32 to 3327+3327=2:3328=deu@3,3329=eng@3:0:32
May  5 12:59:08 ubuntu vdr: video: decoder buffer empty, duping frame (3186/194) 182 v-buf
May  5 12:59:08 ubuntu vdr: video: 25:31:52.543  +12 2439   0/\ms 182+7 v-buf
May  5 12:59:41 ubuntu vdr: EPG2VDR: Trying to re-connect to database!
May  5 12:59:41 ubuntu vdr: EPG2VDR: Start reading hashes from db
May  5 12:59:41 ubuntu vdr: EPG2VDR: Finished reading hashes from db, got 4727 hashes (in 0 seconds)
May  5 12:59:41 ubuntu vdr: EPG2VDR: Start reading external ids from db
May  5 12:59:41 ubuntu vdr: EPG2VDR: Finished reading external id's from db, got 38 id's
May  5 12:59:41 ubuntu vdr: EPG2VDR: Connection established successfull!
May  5 12:59:43 ubuntu vdr: [1794] changing pids of channel 191 from 501+501=2:502=deu@3:0:0 to 401+401=2:402=deu@3:0:0
May  5 13:00:08 ubuntu vdr: video: 25:32:52.543  +12 2471   0/\ms 182+7 v-buf
May  5 13:00:35 ubuntu vdr: scraper2vdr: Deleted 1 outdated series image folders
May  5 13:00:35 ubuntu vdr: scraper2vdr: Deleted 10 outdated movie image folders
May  5 13:00:51 ubuntu vdr: [1918] frontend 3/0 timed out while tuning to channel 12, tp 610
May  5 13:01:08 ubuntu vdr: video: 25:33:52.543  +12 2375   0/\ms 182+7 v-buf
May  5 13:01:57 ubuntu epgd: 732 DVB pending, mergeepg done after 0.492 seconds
May  5 13:01:58 ubuntu vdr: EPG2VDR: Updated changes since '05.05.2014 12:55:47'; 38 channels, 665 events (1 deletions) in 0.382 seconds
May  5 13:02:08 ubuntu vdr: [1903] changing pids of channel 279 from 511+511=27:0;515=deu@106:0:32 to 511+511=27:0;515=deu@106,516=eng@106:0:32
May  5 13:02:08 ubuntu vdr: [1919] changing pids of channel 114 from 2815+2815=2:2816=deu@3,2817=eng@3;2819=deu@106:0:32 to 2815+2815=2:2816=deu@3;2819=deu@106:0:32
May  5 13:02:08 ubuntu vdr: video: 25:34:52.543  +12 2375   0/\ms 182+7 v-buf
May  5 13:02:52 ubuntu vdr: [1919] changing pids of channel 191 from 401+401=2:402=deu@3:0:0 to 501+501=2:502=deu@3:0:0
May  5 13:03:08 ubuntu vdr: video: 25:35:52.543  +12 2823   0/\ms 182+7 v-buf
May  5 13:04:08 ubuntu vdr: video: 25:36:52.543  +12 2439   0/\ms 182+7 v-buf
May  5 13:04:22 ubuntu vdr: [1918] frontend 3/0 timed out while tuning to channel 305, tp 618May  5 13:05:02 ubuntu vdr: audio/alsa: wait underrun error? 'Daten√ºbergabe unterbrochen (broken pipe)'
May  5 13:05:02 ubuntu epgd: 519 DVB pending, mergeepg done after 0.485 seconds
May  5 13:05:07 ubuntu vdr: [1078] [softhddev]Freeze:
May  5 13:05:08 ubuntu vdr: video: decoder buffer empty, duping frame (3242/20692) 182 v-buf
May  5 13:05:08 ubuntu vdr: video: 25:37:51.443+8888    0   0/\ms 182+5 v-buf
May  5 13:05:09 ubuntu vdr: [1928] timer 47 (8 2012-2113 'Grimm~(S03E01) Absturz') set to event Mon 05.05.2014 20:15-21:05 'Grimm'
May  5 13:05:09 ubuntu vdr: [1928] timer 23 (8 2157-2303 'Bates Motel~Erst der Traum, dann der Tod') set to event Mon 05.05.2014 22:00-22:55 'Bates Motel'
May  5 13:05:10 ubuntu vdr: EPG2VDR: Updated changes since '05.05.2014 13:01:58'; 38 channels, 525 events (1 deletions) in 0.420 seconds

Alles anzeigen

gda · 5. Mai 2014

Na ja, die Ursache für den Overrun wird ja wohl eher davor zu suchen sein.

Gerald

Perlbo · 5. Mai 2014

habe korrigiert und Einträge davor mit drin ... hoffe das reicht von der Zeit ..

utiltiy · 5. Mai 2014

Eventuell bekomme ich das Problem hier mit mehr Debugausgaben auch raus. Wobei ich die Server - Client Variante nutze. Denn VPS Signal hin oder her, es funktioniert ja mit der xmltv/grabber Variante nur nicht mit epg2vdr.

Perlbo · 5. Mai 2014

20:03 erneuter kurzer Aussetzer

Code

May  5 20:00:57 ubuntu vdr: [1960] changing pids of channel 254 from 301+301=2:302=deu@3:0:504 to 501+501=2:502=deu@3,503=mis@3:505=deu:504
May  5 20:01:13 ubuntu epgd: 564 DVB pending, mergeepg done after 0.481 seconds
May  5 20:01:22 ubuntu vdr: EPG2VDR: Updated changes since '05.05.2014 19:49:20'; 38 channels, 562 events (5 deletions) in 0.378 seconds
May  5 20:01:31 ubuntu vdr: video: 22:25:36.865  +23 3370   0/\ms 182+7 v-buf
May  5 20:01:39 ubuntu vdr: [3250] non blocking file reader thread ended (pid=1115, tid=3250)
May  5 20:01:39 ubuntu vdr: [3249] dvbplayer thread ended (pid=1115, tid=3249)
May  5 20:01:39 ubuntu vdr: [1115] [softhddev]SetPlayMode: 0
May  5 20:01:39 ubuntu vdr: audio/alsa: using pass-through device 'default'
May  5 20:01:39 ubuntu vdr: [1115] [softhddev]SetVideoDisplayFormat: 1
May  5 20:01:39 ubuntu vdr: [1115] [softhddev]GetSpuDecoder:
May  5 20:01:39 ubuntu vdr: [1115] switching to channel 12
May  5 20:01:39 ubuntu vdr: [6631] receiver on device 1 thread started (pid=1115, tid=6631, prio=high)
May  5 20:01:39 ubuntu vdr: [6632] TS buffer on device 1 thread started (pid=1115, tid=6632, prio=high)
May  5 20:01:39 ubuntu vdr: [1988] VNSI: Recordings state changed (530)
May  5 20:01:39 ubuntu vdr: [1988] VNSI: Requesting clients to reload recordings list
May  5 20:01:39 ubuntu vdr: audio/alsa: start delay 336ms
May  5 20:01:39 ubuntu vdr: video: decoder buffer empty, duping frame (8873/90444) 0 v-buf
May  5 20:01:39 ubuntu vdr: video: --:--:--.---   +0    0   0/\ms   0+5 v-buf
May  5 20:01:40 ubuntu vdr: [1115] [softhddev]SetPlayMode: 1
May  5 20:01:40 ubuntu vdr: audio/alsa: using pass-through device 'default'
May  5 20:01:40 ubuntu vdr: audio/alsa: start delay 336ms
May  5 20:01:40 ubuntu vdr: codec: buggy libav, use ffmpeg
May  5 20:01:40 ubuntu vdr: audio/alsa: using device 'default'
May  5 20:01:40 ubuntu vdr: audio/alsa: start delay 336ms
May  5 20:01:41 ubuntu vdr: video: decoder buffer empty, duping frame (8932/90444) 0 v-buf
May  5 20:01:41 ubuntu vdr: video: slow down video, duping frame
May  5 20:01:41 ubuntu vdr: video: 13:21:58.705 +104  412   0/\ms  14+7 v-buf
May  5 20:01:41 ubuntu vdr: video/vdpau: synced after 39 frames
May  5 20:02:40 ubuntu vdr: video: slow down video, duping frame
May  5 20:02:40 ubuntu vdr: video: 13:22:58.005  +24  384   0/\ms  19+7 v-buf
May  5 20:03:24 ubuntu vdr: [1952] changing pids of channel 175 from 501+501=2:502=deu@3,503=mis@3:0:504 to 601+601=2:602=deu@3,603=mis@3:0:604
May  5 20:03:40 ubuntu vdr: video: 13:23:58.005  +24  360   0/\ms  18+7 v-buf
May  5 20:03:45 ubuntu vdr: [1967] changing pids of channel 203 from 255+255=27:0;259=deu@106:0:32 to 255+255=27:0;259=deu@106,260=eng@106:0:32
May  5 20:03:47 ubuntu vdr: audio/alsa: wait underrun error? 'Daten√ºbergabe unterbrochen (broken pipe)'
May  5 20:03:47 ubuntu epgd: 1402 DVB pending, mergeepg done after 0.455 seconds
May  5 20:03:47 ubuntu vdr: video: speed up video, droping frame
May  5 20:03:47 ubuntu vdr: video: 13:24:04.865  -82  282   0/\ms  13+5 v-buf
May  5 20:03:47 ubuntu vdr: video: speed up video, droping frame
May  5 20:03:47 ubuntu vdr: video/vdpau: missed frame (5/6322)
May  5 20:03:47 ubuntu vdr: video: speed up video, droping frame
May  5 20:03:47 ubuntu vdr: video: 13:24:04.985  -23  532   0/\ms  18+5 v-buf
May  5 20:03:53 ubuntu vdr: EPG2VDR: Updated changes since '05.05.2014 20:01:22'; 38 channels, 1193 events (0 deletions) in 0.385 seconds
May  5 20:03:53 ubuntu vdr: [6781] epg data writer thread started (pid=1115, tid=6781, prio=low)
May  5 20:03:54 ubuntu vdr: [6781] epg data writer thread ended (pid=1115, tid=6781)
May  5 20:04:40 ubuntu vdr: video: 13:24:58.065  +11  528   0/\ms  28+6 v-buf

Alles anzeigen

TheChief · 5. Mai 2014

Nur um das auszuschliessen...auf Kanal 175 oder 203 warst du nicht gerade während des PID Wechsels? Vielleicht wurdenja gerade Audiospuren gewechselt?!

Was hast du denn in den Audioeinstellungen von softhddev?

Perlbo · 5. Mai 2014

Nein war nicht auf den Kanälen. Einstellungen bei softhddevice sind Audio driftkorrektur 0 . Passthrough pcm/ac3/eac3 auf ja. Lautstärke Hardware, Audio Puffer 0

TheChief · 5. Mai 2014

Hab 100ms Buffer, aber wer weiss, ob das was nützt.

BooStar · 5. Mai 2014

Hi zusammen,

Zitat von goldbär

Habe ich oben beschrieben

Hmm irgendwie steht da nicht wie man die Ruckler reproduzieren kann, wie oft kommen sie bei dir denn vor, goldbär?

BooStar · 5. Mai 2014

Zitat von louis

Moin,

ich denke auch dass das die Lösung wäre. scraper2vdr und epg2vdr sollten im Betrieb nicht parallel auf die DB zugreifen. Beim Starten sollte das kein Problem sein, aber im laufenden Betrieb sollten wir das synchronisieren. Laufen die Plugins parallel, ist das wohl in einigen Konstellationen "too much".

Ciao Louis

Hmm.. Kann wirklich sein, das es meine HW (siehe Sig.) nicht wuppen kann?
CKone:
Kann ich die DB irgendwie stressen?

TheChief · 5. Mai 2014

3PO hatte mal irgendwo einen Benchmark in einem der Announce Threads gepostet.

[Announce] epgd-0.1.4 / epg2vdr-0.1.3 - Advent, Advent...

BooStar · 6. Mai 2014

Danke .. das probier ich mal ...

brucer · 6. Mai 2014

Grüss Euch

ich habe auch genau das gleiche Probleme denke ich also immer bei dem Update leichte Ton und Bildaussetzer:

Code

cat /log/messages
May 06 02:00:01 [cron] (root) CMD (test -x /usr/sbin/run-crons && /usr/sbin/run-crons)
May 06 02:00:57 [vdr] video: 11:52:32.686  +21  389 240/\ms  17+6 v-buf_
May 06 02:01:38 [epgd] 1298 DVB pending, mergeepg done after 57.284 seconds
May 06 02:01:57 [vdr] video: 11:53:32.686  +21  461 240/\ms  24+6 v-buf_
May 06 02:02:00 [vdr] EPG2VDR: Updated changes since '06.05.2014 01:59:41'; 245 channels, 1424 events (4 deletions) in 13.631 seconds
May 06 02:02:25 [vdr] [softhddev] invalid PES video packet_
May 06 02:02:25 [vdr] [softhddev] 10 invalid PES video packet(s)_
May 06 02:02:26 [vdr] audio/alsa: wait underrun error? 'Datenübergabe unterbrochen (broken pipe)'_
May 06 02:02:26 [vdr] video: slow down video, duping frame_
May 06 02:02:26 [vdr] video: 11:54:02.226 +443  360 240/\ms   4+8 v-buf_
May 06 02:02:57 [vdr] video: slow down video, duping frame_
May 06 02:02:57 [vdr] video: 11:54:32.706  +28  449 240/\ms  17+7 v-buf_
May 06 02:03:07 [vdr] [12428] [general.error] last message repeated 2 times
May 06 02:03:46 [epgd] 995 DVB pending, mergeepg done after 51.345 seconds
May 06 02:03:57 [vdr] video: 11:55:32.706  +30  403 240/\ms  10+7 v-buf_
May 06 02:03:59 [vdr] [12924] frontend 0/0 timed out while tuning to channel 361, tp 211817
May 06 02:04:08 [vdr] EPG2VDR: Updated changes since '06.05.2014 02:02:00'; 245 channels, 847 events (65 deletions) in 11.679 seconds
May 06 02:04:19 [vdr] [12927] frontend 1/0 timed out while tuning to channel 47, tp 212246
May 06 02:04:41 [vdr] [12927] frontend 1/0 timed out while tuning to channel 444, tp 212441
May 06 02:04:55 [vdr] [softhddev] invalid PES video packet_
May 06 02:04:55 [vdr] [softhddev] 8 invalid PES video packet(s)_
May 06 02:04:56 [vdr] video: decoder buffer empty, duping frame (175/65870) 0 v-buf_
May 06 02:04:56 [vdr] video: 11:56:32.006+8888    0 240/\ms   0+5 v-buf_
May 06 02:04:56 [vdr] audio/alsa: wait underrun error? 'Datenübergabe unterbrochen (broken pipe)'_
May 06 02:04:56 [vdr] video: decoder buffer empty, duping frame (179/65870) 0 v-buf_
May 06 02:04:56 [vdr] video: slow down video, duping frame_
May 06 02:04:56 [vdr] video: 11:56:32.266 +531  360 240/\ms   4+8 v-buf_
May 06 02:04:57 [vdr] video: slow down video, duping frame_
May 06 02:04:57 [vdr] video: 11:56:32.766 +103  440 240/\ms  13+6 v-buf_
May 06 02:05:57 [vdr] video: slow down video, duping frame_
May 06 02:05:57 [vdr] video: 11:57:32.686  +23  440 240/\ms  24+6 v-buf_
May 06 02:06:19 [epgd] 514 DVB pending, mergeepg done after 47.399 seconds
May 06 02:06:37 [vdr] EPG2VDR: Updated changes since '06.05.2014 02:04:08'; 245 channels, 404 events (42 deletions) in 15.155 seconds

Alles anzeigen

Gruß

TheChief · 6. Mai 2014

Habt ihr denn mal, wie von einem user vorgeschlagen,

Code

nice = -19

in der mysql.cnf versucht?

Bin der Meinung, dass sich dann nach einem Neustart von mysql und epgd MYSQL nicht mehr soviel Leistung genehmigt.

Perlbo · 6. Mai 2014

Habe es heute morgen geändert. Werde berichten.

kurze Audio/Bild Aussetzer / Zusammenhang mit EPGD ?

Jetzt mitmachen!

Teilen