[1] MLD-5.x / General / Aufnahmen geteilt
 

Offline FunThomas

  • Newbie
  • *
  • Posts: 20
    • View Profile
Aufnahmen geteilt
« on: January 02, 2019, 16:52:43 »
Hallo,

ich habe MLD x64 mit einer DD Max S8 laufen und hatte jetzt testweise einige Aufnahmen laufen.

Die Aufnahmen sind aber leider alle gesplittet und zwischendurch fehlen einige wenige Sekunden.
Gibt es einen Log für die Aufnahmen wo Inkonsistenzen waren und gibt es eine Möglichkeit nicht zu splitten ?

Danke im Voraus

Offline baltic

  • MLD-Debugger
  • Expert Member
  • *****
  • Posts: 724
    • View Profile
Aufnahmen geteilt
« Reply #1 on: January 02, 2019, 16:55:45 »
Hallo,

wie groß sind denn die Files, die da entstehen?

Gruß
baltic
MLD 5.5 – unstable  Asus M3N78-EM  SkyStar2

Offline FunThomas

  • Newbie
  • *
  • Posts: 20
    • View Profile
Aufnahmen geteilt
« Reply #2 on: January 02, 2019, 20:03:13 »
Hallo,

das ist ganz unterschiedlich ca. 150-300 MB.

Ich weiß leider nicht ob es an irgendwelchen Empfangsproblemen liegt oder an irgendwelches Plugins die nicht richtig konfiguriert sind.
Im /var/log/messages sehe ich aktuell nix brauchbares.

Ich suche ein Logfile für die Aufnahme selber

Offline clausmuus

  • Administrator
  • Expert Member
  • ********
  • Posts: 20414
    • View Profile
    • ClausMuus.de
Aufnahmen geteilt
« Reply #3 on: January 02, 2019, 20:06:08 »
Sind im /var/log/vdr restarts des VDR zu sehen?
Normal ist das ja nicht. Ich glaube der VDR ist bei uns so voreingestellt, dass pro Aufnahme nur eine Datei angelegt wird.
MLD 5.5 - Raspberry PI - 7" Touch TFT - Squeeze Play
MLD 5.5 - lirc yaUsbIR - OctopusNet - XFX GeForce 9300 mit Intel E3200 - 2GB RAM - 12TB HDD - Lian Li PC-C37B - Samsung LE40A559

Offline FunThomas

  • Newbie
  • *
  • Posts: 20
    • View Profile
Aufnahmen geteilt
« Reply #4 on: January 02, 2019, 21:22:18 »
Hallo,

in /var/log/vdr

sehe ich viele Zeilen für die Slots 1-8 (Wahrscheinlich alle Tuner).

Zwischendurch kommt öfters z.B.

Error in my_thread_global_end(): 9 threads didn't exit
VDR exits at Mi Jan  2 21:07:45 CET 2019

und das passt mit den Attributen/Datumsangaben der gesplitteten Dateien zusammen.

ich finde zu diesem Zeitpunkt auch die Meldungen in /var/log/messages
Jan  2 21:07:44 (MLD) user.info vdr: [19180] DVBAPI: plugin stopped
Jan  2 21:07:45 (MLD) user.err vdr: [19180] emergency exit!

aber auch FreeTV Aufnahmen werden gesplittet, da schaue ich morgen gleich mal weiter.

Wo konnte man noch gleich den Log-Level hochschrauben ?
Kann man in den vdr logs einen Zeitstempel mit ausgeben lassen ?



« Last Edit: January 02, 2019, 22:02:56 by FunThomas »

Offline clausmuus

  • Administrator
  • Expert Member
  • ********
  • Posts: 20414
    • View Profile
    • ClausMuus.de
Aufnahmen geteilt
« Reply #5 on: January 03, 2019, 15:58:12 »
In den Webif Einstellungen unter "TV / VDR" kannst Du das VDR Aufrufargument "-l 1" in "-l 3" ändern um den Loglevel hoch zu stellen.
Im VDR Log wird bei jedem Neustart das Datum geschrieben, und in /var/log/messages bei jeder Log Meldung.
MLD 5.5 - Raspberry PI - 7" Touch TFT - Squeeze Play
MLD 5.5 - lirc yaUsbIR - OctopusNet - XFX GeForce 9300 mit Intel E3200 - 2GB RAM - 12TB HDD - Lian Li PC-C37B - Samsung LE40A559

Offline FunThomas

  • Newbie
  • *
  • Posts: 20
    • View Profile
Aufnahmen geteilt
« Reply #6 on: January 03, 2019, 19:18:43 »
Vielen Dank für die Infos.

Das Problem mit den FreeTV Aufnahmen war dass der vdr nen kompletten Neustart jeweils gemacht hat wenn die dvbapi abgestürzt ist ;-)

Wieso auch immer funktioniert es jetzt. Allerdings wird jetzt bei ca. 2.097.881.284 Bytes also grob 2GB jeweils gesplittet und an XFS-Filesystem sollte es nicht liegen.

edit: habs gefunden
unter /etc/vdr/setup.conf
MaxVideoFileSize = 2000

sollte glaube helfen.

Offline FunThomas

  • Newbie
  • *
  • Posts: 20
    • View Profile
Aufnahmen geteilt
« Reply #7 on: January 03, 2019, 23:44:00 »
Heute abend trat das Problem wieder auf...

mir ist dabei jetzt aufgefallen, dass

Status
Mem: 7782220K used, 97728K free, 4532K shrd, 1928K buff, 6450088K cached
CPU:   5% usr   2% sys   0% nic  92% idle   0% io   0% irq   0% sirq
Up: 14:27,  load average: 1.45, 1.41, 1.38

Netzwerk

eth0      Link encap:Ethernet  HWaddr 70:85:C2:8C:D0:1B 
          inet addr:192.168.99.52  Bcast:192.168.99.255  Mask:255.255.255.0
          inet6 addr: fe80::7285:c2ff:fe8c:d01b/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:256422 errors:0 dropped:83061 overruns:0 frame:0
          TX packets:355230 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:63124135 (60.1 MiB)  TX bytes:423891201 (404.2 MiB)

lo        Link encap:Local Loopback 
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:12679762 errors:0 dropped:0 overruns:0 frame:0
          TX packets:12679762 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:5384248123 (5.0 GiB)  TX bytes:5384248123 (5.0 GiB)

wenn ich jetzt recht in der Annahme gehe, dass nic 92% heißt das das Netzwerk überlastet ist ?
Ich weiß nicht wieso ich soviel packet drops auf eth0 habe und wieso local loopback 5GiB an daten in beide Richtungen überträgt ?

Hat hier wer Ideen was hier schief läuft und wie ich das untersuchen kann ?

Danke im Voraus!


Offline clausmuus

  • Administrator
  • Expert Member
  • ********
  • Posts: 20414
    • View Profile
    • ClausMuus.de
Aufnahmen geteilt
« Reply #8 on: January 04, 2019, 10:03:20 »
Die 92% sind die ungenutzte CPU Zeit. Da ist also alles im grünen Bereich.
MLD 5.5 - Raspberry PI - 7" Touch TFT - Squeeze Play
MLD 5.5 - lirc yaUsbIR - OctopusNet - XFX GeForce 9300 mit Intel E3200 - 2GB RAM - 12TB HDD - Lian Li PC-C37B - Samsung LE40A559

Offline FunThomas

  • Newbie
  • *
  • Posts: 20
    • View Profile
Aufnahmen geteilt
« Reply #9 on: January 04, 2019, 10:24:38 »
Danke für das Augen öffnen, da war ich mal wieder blind ;-)

Die Bezeichnung CPU gilt für die ganze Zeile und die separaten Bezeichner stehen dahinter...

Ist das mit eth0 und den rx drops normal und der viele Traffic über local loopback ? Habe eine RTL8111H onboard.

lspci | grep Ethernet
01:00.0 Ethernet controller: Realtek Semiconductor Co., Ltd. RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller (                             rev 15)

ethtool -i eth0
driver: r8169
version: 2.3LK-NAPI
firmware-version: rtl8168h-2_0.0.2 02/26/15
expansion-rom-version:
bus-info: 0000:01:00.0
supports-statistics: yes
supports-test: no
supports-eeprom-access: no
supports-register-dump: yes
supports-priv-flags: no

ich hatte noch einige andere Befehle wie netstat probiert aber die sind trotz Tools Paket nicht mit drinnen :-(

Hast Du evtl. noch Befehle die ich zum Analysieren des vielen Traffics auf lo und den rx packetdrops nutzen kann ?
« Last Edit: January 04, 2019, 10:36:40 by FunThomas »

Offline baltic

  • MLD-Debugger
  • Expert Member
  • *****
  • Posts: 724
    • View Profile
Aufnahmen geteilt
« Reply #10 on: January 04, 2019, 12:04:00 »
Hallo,

es könnte sein, dass Dir IPv6 Probleme macht. Evtl. mal deaktivieren.

Gruß
baltic
MLD 5.5 – unstable  Asus M3N78-EM  SkyStar2

Offline FunThomas

  • Newbie
  • *
  • Posts: 20
    • View Profile
Aufnahmen geteilt
« Reply #11 on: January 04, 2019, 13:41:11 »
wie würde ich IPv6 deaktivieren über die Konsole ?

Ich hatte probiert über
echo "net.ipv6.conf.all.disable_ipv6 = 1" >> /etc/sysctl.conf
aber das funktioniert bei MLD nicht.

Also nach einem VDR Neustart über das WebIf laufen 5 Aufnahmen parallel ohne Probleme :-(
Die Reproduzierbarkeit des Fehlers ist echt schwierig. Ich glaube ich suche noch an der falschen Stelle ...

Kann man die max Größe der /var/log/messages und messages.0 verändern ?
Wenn ich im WebIf unter Konfiguration -> System -> Log Size und dort 6000000 angebe wird hier garkein Log mehr erstellt :(

Ich bekomme öfters Meldungen wie die hier:

Code: [Select]
Jan  4 16:46:46 (MLD) user.err epgd: Send 'PLUG epg2vdr STATE standby' to '192.168.99.52:6419'
Jan  4 16:46:46 (MLD) user.err vdr: epg2vdr: Got epgd state 'standby' (1)
Jan  4 16:46:46 (MLD) user.err vdr: epg2vdr: Change handler state to 'active'
Jan  4 16:46:46 (MLD) user.info vdr: [4023] SVDRP vdr-ubuntu < 192.168.99.52:37950 lost connection to client
Jan  4 16:46:46 (MLD) user.info vdr: [4023] SVDRP vdr-ubuntu < 192.168.99.52:37950 connection closed
Jan  4 16:46:46 (MLD) user.debug vdr: [4023] SVDRP vdr-ubuntu < 192.168.99.52:37950 server destroyed
Jan  4 16:46:46 (MLD) user.err vdr: epg2vdr: Error: Channel with ID '00'' don't exist on this VDR
Jan  4 16:46:46 (MLD) user.err vdr: epg2vdr: Error: Channel with ID '15'' don't exist on this VDR
Jan  4 16:46:46 (MLD) user.err vdr: epg2vdr: Error: Channel with ID '30'' don't exist on this VDR
Jan  4 16:46:46 (MLD) user.err vdr: epg2vdr: Error: Channel with ID '45'' don't exist on this VDR
Jan  4 16:46:46 (MLD) user.info vdr: [4014] timer 8 (20 1555-1714 'Klinik am Südring') set to no event
Jan  4 16:46:46 (MLD) user.info vdr: [4014] timer 8 (20 1555-1714 'Klinik am Südring') set to event Fr. 04.01.2019 16:00-16:59 'Klinik am Südring'
Jan  4 16:46:46 (MLD) user.info vdr: [4014] timer 9 (21 1626-1714 'The Big Bang Theory') set to no event
Jan  4 16:46:46 (MLD) user.info vdr: [4014] timer 9 (21 1626-1714 'The Big Bang Theory') set to event Fr. 04.01.2019 16:31-16:59 'The Big Bang Theory'
Jan  4 16:46:47 (MLD) user.info vdr: [4009] frontend 7/0 lost lock on channel 3 (PHOENIX HD), tp 111582
Jan  4 16:46:47 (MLD) user.err vdr: epg2vdr: Updated changes since '04.01.2019 16:45:45'; 321 channels, 3018 events (0 deletions) in 504 ms
Jan  4 16:46:49 (MLD) user.info vdr: [4009] frontend 7/0 timed out while tuning to channel 3 (PHOENIX HD), tp 111582
Jan  4 16:46:49 (MLD) user.info vdr: [4009] frontend 7/0 regained lock on channel 3 (PHOENIX HD), tp 111582
Jan  4 16:46:49 (MLD) user.info vdr: [3997] frontend 3/0 lost lock on channel 8 (ZDFinfo HD), tp 211347

... EPGUpdate ? ...

Jan  4 16:47:22 (MLD) user.info vdr: [3997] frontend 3/0 lost lock on channel 8 (ZDFinfo HD), tp 211347
Jan  4 16:47:22 (MLD) user.debug vdr: [4001] changing pids of channel 477 (Sky Cinema Family HD) from 767+767=27:0;771=deu@106:0:0 to 767+767=27:0;771=deu@106,772=eng@106:0:0
[b]Jan  4 16:47:23 (MLD) user.err vdr: [4010] ERROR: can't set filter (pid=65535, tid=02, mask=FF): Invalid argument
Jan  4 16:47:23 (MLD) user.err kernel: [  307.657405] dmxdev: DVB (dvb_dmxdev_filter_start): could not set feed
Jan  4 16:47:23 (MLD) user.err kernel: [  307.657407] dvb_demux: dvb_demux_feed_del: feed not in list (type=1 state=0 pid=ffff)[/b]
Jan  4 16:47:23 (MLD) user.info vdr: [3994] frontend 2/0 lost lock on channel 21 (ProSieben HD), tp 111464
Jan  4 16:47:23 (MLD) user.info vdr: [3988] frontend 0/0 lost lock on channel 1 (Das Erste HD), tp 111493
Jan  4 16:47:24 (MLD) user.info vdr: [3997] frontend 3/0 timed out while tuning to channel 8 (ZDFinfo HD), tp 211347

... EPGUpdate ? ...

Jan  4 16:47:45 (MLD) user.info vdr: [3988] frontend 0/0 regained lock on channel 1 (Das Erste HD), tp 111493
Jan  4 16:47:45 (MLD) user.info vdr: [4006] frontend 6/0 lost lock on channel 81 (13th Street HD), tp 111992
Jan  4 16:47:46 (MLD) user.info vdr: [4000] frontend 4/0 lost lock on channel 83 (Discovery HD), tp 111914
Jan  4 16:47:46 (MLD) user.info vdr: [4009] frontend 7/0 timed out while tuning to channel 64 (Discovery Channel OBSOLETE), tp 112031
Jan  4 16:47:47 (MLD) user.info vdr: [4009] frontend 7/0 regained lock on channel 64 (Discovery Channel OBSOLETE), tp 112031
Jan  4 16:47:47 (MLD) user.info vdr: [4006] frontend 6/0 timed out while tuning to channel 81 (13th Street HD), tp 111992
Jan  4 16:47:47 (MLD) user.debug vdr: [4010] changing pids of channel 502 (Sky Cinema Family) from 4095+4095=27:4096=deu@3:0:0 to 4095+4095=27:4096=deu@3,4097=eng@3:0:0
Jan  4 16:47:48 (MLD) user.info vdr: [3997] frontend 3/0 lost lock on channel 8 (ZDFinfo HD), tp 211347
Jan  4 16:47:48 (MLD) user.info vdr: [4006] frontend 6/0 regained lock on channel 81 (13th Street HD), tp 111992
Jan  4 16:47:48 (MLD) user.info vdr: [4000] frontend 4/0 timed out while tuning to channel 83 (Discovery HD), tp 111914
Jan  4 16:47:48 (MLD) user.info vdr: [4000] frontend 4/0 regained lock on channel 83 (Discovery HD), tp 111914
Jan  4 16:47:49 (MLD) user.info vdr: [3994] frontend 2/0 lost lock on channel 21 (ProSieben HD), tp 111464
Jan  4 16:47:49 (MLD) user.info vdr: [3988] frontend 0/0 lost lock on channel 1 (Das Erste HD), tp 111493
Jan  4 16:47:50 (MLD) user.info vdr: [3997] frontend 3/0 timed out while tuning to channel 8 (ZDFinfo HD), tp 211347
Jan  4 16:47:50 (MLD) user.info vdr: [3997] frontend 3/0 regained lock on channel 8 (ZDFinfo HD), tp 211347
Jan  4 16:47:51 (MLD) user.info vdr: [4009] frontend 7/0 lost lock on channel 64 (Discovery Channel OBSOLETE), tp 112031
Jan  4 16:47:51 (MLD) user.info vdr: [3994] frontend 2/0 timed out while tuning to channel 21 (ProSieben HD), tp 111464
Jan  4 16:47:51 (MLD) user.info vdr: [3994] frontend 2/0 regained lock on channel 21 (ProSieben HD), tp 111464
Jan  4 16:47:51 (MLD) user.info vdr: [3988] frontend 0/0 timed out while tuning to channel 1 (Das Erste HD), tp 111493
Jan  4 16:47:51 (MLD) user.info vdr: [3988] frontend 0/0 regained lock on channel 1 (Das Erste HD), tp 111493
[b]Jan  4 16:47:51 (MLD) user.err vdr: [4119] ERROR: video data stream broken
Jan  4 16:47:51 (MLD) user.err vdr: [4119] initiating emergency exit
Jan  4 16:47:52 (MLD) user.err vdr: [3984] emergency exit requested - shutting down
Jan  4 16:47:52 (MLD) user.err vdr: [4129] ERROR: video data stream broken
Jan  4 16:47:52 (MLD) user.err vdr: [4129] initiating emergency exit[/b]
Jan  4 16:47:52 (MLD) user.info vdr: [4006] frontend 6/0 lost lock on channel 81 (13th Street HD), tp 111992
Jan  4 16:47:52 (MLD) user.debug vdr: [4023] SVDRP server handler thread ended (pid=3984, tid=4023)
Jan  4 16:47:52 (MLD) user.debug vdr: [3984] saving /var/cache/vdr/cam.data
Jan  4 16:47:52 (MLD) user.debug vdr: [4119] recording thread ended (pid=3984, tid=4119)
Jan  4 16:47:53 (MLD) user.info vdr: [4000] frontend 4/0 lost lock on channel 83 (Discovery HD), tp 111914
Jan  4 16:47:53 (MLD) user.debug vdr: [3984] buffer stats: 447064 (2%) used
Jan  4 16:47:53 (MLD) user.info vdr: [3984] timer 6 (18 1555-1715 'Meine Geschichte - Mein Leben') stop
Jan  4 16:47:53 (MLD) user.debug vdr: [3984] removing /data/tv/Meine_Geschichte_-_Mein_Leben/2019-01-04.15.55.18-0.rec/.timer
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Recording of '(null)' has 'stopped' [/data/tv/Meine_Geschichte_-_Mein_Leben/2019-01-04.15.55.18-0.rec]
Jan  4 16:47:53 (MLD) user.info vdr: [3984] executing '/etc/vdr/recording.d/rc.action after "/data/tv/Meine_Geschichte_-_Mein_Leben/2019-01-04.15.55.18-0.rec"'
Jan  4 16:47:53 (MLD) user.debug vdr: [4294] EPGSearch: recdone thread started (pid=3984, tid=4294, prio=high)
Jan  4 16:47:53 (MLD) user.debug vdr: [4294] EPGSearch: recdone thread ended (pid=3984, tid=4294)
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Info: Finished (-1) 'Meine Geschichte - Mein Leben' (not complete! - recorded only 4%); VPS No
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Info: Recording 'Meine Geschichte - Mein Leben' finished - NOT complete (4%)
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Try to lookup timer with id -1
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Error: Can't lookup timer, id -1 not found!
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Try to lookup timer with id -1
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Error: Can't lookup timer, id -1 not found!
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Try to lookup timer with id -1
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Error: Can't lookup timer, id -1 not found!
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Try to lookup timer with id -1
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Error: Can't lookup timer, id -1 not found!
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Try to lookup timer with id -1
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Error: Can't lookup timer, id -1 not found!
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Cleanup deleted recordings at database (forced)
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Info: Marked 0 recordings as deleted
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Updating recording list table
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Info: Found 5 recordings; 0 inserted; 5 updated and 0 directories
Jan  4 16:47:53 (MLD) user.debug vdr: [4129] recording thread ended (pid=3984, tid=4129)
Jan  4 16:47:53 (MLD) user.debug vdr: [3984] CAM 1: unassigned from device 2
Jan  4 16:47:53 (MLD) user.debug vdr: [3984] buffer stats: 451764 (2%) used
Jan  4 16:47:53 (MLD) user.info vdr: [3984] timer 7 (19 1603-1720 'Hilf mir! Jung, pleite, verzweifelt...') stop
Jan  4 16:47:53 (MLD) user.debug vdr: [3984] removing /data/tv/Hilf_mir!_Jung,_pleite,_verzweifelt.../2019-01-04.16.03.19-0.rec/.timer
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Recording of '(null)' has 'stopped' [/data/tv/Hilf_mir!_Jung,_pleite,_verzweifelt.../2019-01-04.16.03.19-0.rec]
Jan  4 16:47:53 (MLD) user.info vdr: [3984] executing '/etc/vdr/recording.d/rc.action after "/data/tv/Hilf_mir!_Jung,_pleite,_verzweifelt.../2019-01-04.16.03.19-0.rec"'
Jan  4 16:47:53 (MLD) user.debug vdr: [4300] EPGSearch: recdone thread started (pid=3984, tid=4300, prio=high)
Jan  4 16:47:53 (MLD) user.debug vdr: [4300] EPGSearch: recdone thread ended (pid=3984, tid=4300)
Jan  4 16:47:53 (MLD) user.err vdr: epg2vdr: Info: Finished (-1) 'Hilf mir! Jung, pleite, verzweifelt...' (not complete! - recorded only 4%); VPS No

Der Fehler "user.err vdr: [4119] ERROR: video data stream broken" kommt öfter wenn das EPG-Update durchgeführt wird (soweit ich das beurteilen kann)

Hat noch jemand die Digital Devices Max S8 und kennt die Problematik? Wo finde ich die Einstellungen für den fmode der Karte ?



« Last Edit: January 04, 2019, 17:35:12 by FunThomas »

[1] MLD-5.x / General / Aufnahmen geteilt
 



Users Online Users Online

0 Members and 1 Guest are viewing this topic.