Autor Thema: mutex lock timed out after 5 seconds released  (Gelesen 10402 mal)

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
mutex lock timed out after 5 seconds released
« am: Januar 28, 2016, 10:48:18 Nachmittag »
Hallo,

ich habe hier einen niegelnagelneuen Sky Ultimate 5 an meinem Server hängen. Verwendet werden soll der Stick im Netzwerkmodus von einer auf demselben Server laufenden VM, in der MLD (vdr-Distribution) läuft.

Das Problem: Die Verbindung zum Stick klappt nur in 50% der Fälle. Ca. die Hälfte der Aufnahmen schlägt mit 0 Bytes Größe fehl. Im Fehlerfall findet sich auf dem VM-Client (MLD) in der mediaclient.log:

2016-01-28 21:21:05 [1642] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 1091, acquired in: net_close)
Im Client-vdr kommt dann nichts an, und im vdr-Log findet sich "video data stream broken". Auch die Umschaltzeiten sind manchmal sehr lang (vermutlich, wenn die Verbindung nicht im ersten Anlauf, aber dann doch noch klappt).

sundtek.conf auf dem Client:
device_attach=/etc/init.d/dvb-sundtek ready
pluginpath=/usr/bin
[NETWORK]
device=[server-ip]:0

Auf dem Server (C612 Chipsatz, Xeon, Ubuntu 14.04) sieht es so aus:

# /opt/bin/mediaclient -e
**** List of Media Hardware Devices ****
device 0: [Sundtek SkyTV Ultimate V (2015)]  DVB-S/S2, ANALOG-TV, REMOTE-CONTROL, OSS-AUDIO
  [INFO]:
     STATUS: ACTIVE
  [BUS]:
     ID: 3-1
  [SERIAL]:
     ID: U160122174820
  [DVB-S/S2]:
     FESTATUS: ACTIVE
     LNBVOLTAGE: ENABLED
     LNBSUPPLY: ACADAPTER
     LNBSTATUS: OK
     FRONTEND: /dev/dvb/adapter1/frontend0
     DVR: /dev/dvb/adapter1/dvr0
     DMX: /dev/dvb/adapter1/demux0
  [ANALOG-TV]:
     VIDEO0: /dev/video0
     VBI0: /dev/vbi0
  [REMOTECONTROL]:
     INPUT0: /dev/mediainput0
  [OSS]:
     OSS0: /dev/dsp0

Der Stick steckt in einem USB2 Port, das Netzteil ist angeschlossen. Die sundtek.conf auf dem Server enthält nur:

ir_disabled=1
Ich bin mir sicher, das waren nicht genug Infos. Aber ich warte jetzt mal auf konkrete Nachfragen, ehe ich hier auf Verdacht was reinkippe - zumal sich der Fehler nicht einfach so reproduzieren lässt; manche Aufnahmen klappen, manche nicht.

Danke!

Sundtek

  • Administrator
  • Hero Member
  • *****
  • Beiträge: 8512
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #1 am: Januar 28, 2016, 11:07:09 Nachmittag »
was zeigt denn lsusb -t auf dem Server an?

Wird der Tuner nativ verwendet oder in einer Virtuellen Maschine?

Wenn so ein Timeout vorkommt dann gibt es auf jeden Fall Probleme, ich tippe fast das der Tuner in einer virtuellen Maschine läuft und es dann zu Problemen mit dem "USB Controller" kommt.
« Letzte Änderung: Januar 28, 2016, 11:12:00 Nachmittag von Sundtek »
Failure is a good thing! I'll fix it

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #2 am: Januar 29, 2016, 12:25:20 Vormittag »
Der Tuner wird nativ verwendet.

lsusb -t:

root@alpha:/etc# lsusb -t
/:  Bus 04.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/6p, 5000M
/:  Bus 03.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/15p, 480M
    |__ Port 1: Dev 35, If 0, Class=Vendor Specific Class, Driver=usbfs, 480M
    |__ Port 1: Dev 35, If 1, Class=Vendor Specific Class, Driver=, 480M
    |__ Port 1: Dev 35, If 2, Class=Vendor Specific Class, Driver=usbfs, 480M
    |__ Port 1: Dev 35, If 3, Class=Vendor Specific Class, Driver=, 480M
    |__ Port 1: Dev 35, If 4, Class=Vendor Specific Class, Driver=, 480M
    |__ Port 1: Dev 35, If 5, Class=Vendor Specific Class, Driver=, 480M
    |__ Port 1: Dev 35, If 6, Class=Vendor Specific Class, Driver=, 480M
    |__ Port 2: Dev 22, If 0, Class=Vendor Specific Class, Driver=ftdi_sio, 12M
    |__ Port 3: Dev 3, If 0, Class=Hub, Driver=hub/4p, 480M
        |__ Port 1: Dev 24, If 0, Class=Hub, Driver=hub/4p, 480M
        |__ Port 4: Dev 6, If 0, Class=Hub, Driver=hub/4p, 12M
            |__ Port 1: Dev 7, If 0, Class=Human Interface Device, Driver=usbhid, 12M
            |__ Port 1: Dev 7, If 1, Class=Human Interface Device, Driver=usbhid, 12M
    |__ Port 6: Dev 4, If 0, Class=Vendor Specific Class, Driver=usbfs, 12M
/:  Bus 02.Port 1: Dev 1, Class=root_hub, Driver=ehci-pci/2p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/8p, 480M
/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=ehci-pci/2p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/6p, 480M

Sundtek

  • Administrator
  • Hero Member
  • *****
  • Beiträge: 8512
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #3 am: Januar 29, 2016, 12:29:43 Vormittag »
XHCI = USB 3.0
EHCI = USB 2.0

Stecke den Tuner mal um damit er mit USB 2.0 verwendet wird, der Port an dem er aktuell steckt gehört zum XHCI Controller.

Eventuell kann man als Workaround bei USB 3.0 (XHCI) auch auf dem Server /opt/bin/mediaclient --cat /dev/dvb/adapter0/dvr0 > /dev/null im Hintergrund laufen lassen (wir vermuten das einige Controller Probleme beim Deinitialisieren des Datenstroms haben -- was ein Treiberbug im Linux USB 3.0 Controller Treiber ist).
« Letzte Änderung: Januar 29, 2016, 12:32:26 Vormittag von Sundtek »
Failure is a good thing! I'll fix it

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #4 am: Januar 29, 2016, 01:38:38 Vormittag »
Ok, strange. Der Tuner steckte in einem schwarzen USB Port, der auch lt. specs USB 2 ist. Ich habe jetzt mal USB3 im BIOS insgesamt ausgeschaltet.

# lsusb -t
/:  Bus 02.Port 1: Dev 1, Class=root_hub, Driver=ehci-pci/2p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/8p, 480M
        |__ Port 1: Dev 3, If 0, Class=Vendor Specific Class, Driver=, 480M
        |__ Port 1: Dev 3, If 1, Class=Vendor Specific Class, Driver=, 480M
        |__ Port 1: Dev 3, If 2, Class=Vendor Specific Class, Driver=usbfs, 480M
        |__ Port 1: Dev 3, If 3, Class=Vendor Specific Class, Driver=, 480M
        |__ Port 1: Dev 3, If 4, Class=Vendor Specific Class, Driver=, 480M
        |__ Port 1: Dev 3, If 5, Class=Vendor Specific Class, Driver=, 480M
        |__ Port 1: Dev 3, If 6, Class=Vendor Specific Class, Driver=, 480M
        |__ Port 2: Dev 4, If 0, Class=Vendor Specific Class, Driver=ftdi_sio, 12M
        |__ Port 3: Dev 5, If 0, Class=Hub, Driver=hub/4p, 480M
            |__ Port 1: Dev 6, If 0, Class=Hub, Driver=hub/4p, 480M
            |__ Port 4: Dev 7, If 0, Class=Hub, Driver=hub/4p, 12M
                |__ Port 1: Dev 8, If 0, Class=Human Interface Device, Driver=usbhid, 12M
                |__ Port 1: Dev 8, If 1, Class=Human Interface Device, Driver=usbhid, 12M
/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=ehci-pci/2p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/6p, 480M
        |__ Port 2: Dev 3, If 0, Class=Vendor Specific Class, Driver=usbfs, 12M

/opt/bin/mediaclient --cat /dev/dvb/adapter0/dvr0 hatte ich vorher in einem parallelen Terminal versucht (Ausgabe nicht nach /dev/null, sondern nach stdout, um zu gucken, ob was passiert). Das hatte aber nichts geändert.

Mal schauen, ob es jetzt - USB 2.0 - besser geht.

Danke.

Sundtek

  • Administrator
  • Hero Member
  • *****
  • Beiträge: 8512
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #5 am: Januar 29, 2016, 01:52:17 Vormittag »
Der Port hat zwar nur 480mbit, wurde aber trotzdem vom Linux XHCI (USB 3.0) Treiber verwaltet.
Der Fehler liegt ja im XHCI Linux USB 3.0 Treiber.

Der cat Befehl müsste von Anfang an bei USB 3.0 mitlaufen.
/opt/bin/mediaclient --cat /dev/dvb/adapter0/dvr0 | /opt/bin/mediaclient --pipecount=1000 zeigt dir den Datendurchsatz an (aber nur wenn die Hardwarefilter ausgeschalten sind und die Ausgabe hängt auch davon ab ob im Hintergrund eine Applikation auf den Tuner zugreift)
« Letzte Änderung: Januar 29, 2016, 01:58:16 Vormittag von Sundtek »
Failure is a good thing! I'll fix it

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #6 am: Januar 29, 2016, 09:59:22 Vormittag »
Gut, dann müsste die Config ja jetzt (mit ehci, letzte lsusb -t Ausgabe) passen.

Das mediaclient.log enthält allerdings nach wie vor Fehler um Aufnahmen herum:

2016-01-29 00:52:24 [1485] mutex lock timed out after 5 seconds released, No such file or directory, RV 110 (orig pid: 980, acquired in: net_close)
2016-01-29 03:17:08 [14559] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 12191, acquired in: net_close)
2016-01-29 03:17:56 [14772] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 14744, acquired in: net_close)
2016-01-29 03:18:54 [14783] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 14744, acquired in: net_close)
2016-01-29 03:19:08 [14985] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 14958, acquired in: net_close)
2016-01-29 03:20:07 [14993] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 14958, acquired in: net_close)
2016-01-29 03:20:21 [15197] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 15169, acquired in: net_close)
2016-01-29 03:21:19 [15203] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 15169, acquired in: net_close)
2016-01-29 03:21:34 [15406] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 15378, acquired in: net_close)
2016-01-29 03:22:32 [15412] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 15378, acquired in: net_close)
2016-01-29 03:22:46 [15615] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 15587, acquired in: net_close)
2016-01-29 03:23:44 [15622] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 15587, acquired in: net_close)
2016-01-29 03:23:59 [15825] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 15797, acquired in: net_close)
2016-01-29 03:24:57 [15833] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 15797, acquired in: net_close)
2016-01-29 03:25:11 [16035] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 16008, acquired in: net_close)
2016-01-29 03:26:09 [16042] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 16008, acquired in: net_close)
2016-01-29 03:26:23 [16245] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 16217, acquired in: net_close)
2016-01-29 03:27:21 [16251] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 16217, acquired in: net_close)
2016-01-29 03:27:34 [16453] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 16426, acquired in: net_close)
2016-01-29 04:11:32 [16506] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 16426, acquired in: net_close)
2016-01-29 04:11:43 [16511] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 16426, acquired in: net_close)
2016-01-29 04:11:56 [16713] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 16686, acquired in: net_close)
2016-01-29 04:13:20 [16965] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 16938, acquired in: net_close)
2016-01-29 04:14:52 [17215] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 17188, acquired in: net_close)
2016-01-29 04:16:18 [17447] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 17420, acquired in: net_close)
2016-01-29 04:16:43 [17456] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 17420, acquired in: net_close)
2016-01-29 04:17:38 [17677] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 17649, acquired in: net_close)
2016-01-29 04:18:05 [17700] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 17649, acquired in: net_close)
2016-01-29 04:20:52 [17910] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 17883, acquired in: net_close)
2016-01-29 04:21:14 [17921] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 17883, acquired in: net_close)
2016-01-29 04:22:02 [17938] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 17883, acquired in: net_close)
2016-01-29 04:22:21 [18143] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 18115, acquired in: net_close)
2016-01-29 04:22:59 [18151] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 18115, acquired in: net_close)
2016-01-29 04:23:10 [18156] mutex lock timed out after 5 seconds released, Bad file descriptor, RV 110 (orig pid: 18115, acquired in: net_close)
2016-01-29 04:23:56 [18369] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 18342, acquired in: net_close)
2016-01-29 07:32:05 [19019] mutex lock timed out after 5 seconds released, Success, RV 110 (orig pid: 18342, acquired in: net_close)

Dazu passend von vdr:

Jan 29 03:17:38 (MLD) user.err vdr: [14566] ERROR: video data stream broken
Jan 29 03:17:38 (MLD) user.err vdr: [14566] initiating emergency exit
Jan 29 03:17:38 (MLD) user.err vdr: [12191] emergency exit requested - shutting down
Jan 29 03:17:41 (MLD) user.err vdr: [12191] emergency exit!

Jan 29 03:18:26 (MLD) user.err vdr: [14778] ERROR: video data stream broken
Jan 29 03:18:26 (MLD) user.err vdr: [14778] initiating emergency exit
Jan 29 03:18:26 (MLD) user.err vdr: [14744] emergency exit requested - shutting down

Jan 29 03:19:38 (MLD) user.err vdr: [14991] ERROR: video data stream broken
Jan 29 03:19:38 (MLD) user.err vdr: [14991] initiating emergency exit
Jan 29 03:19:38 (MLD) user.err vdr: [14958] emergency exit requested - shutting down

Sundtek

  • Administrator
  • Hero Member
  • *****
  • Beiträge: 8512
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #7 am: Januar 29, 2016, 10:08:22 Vormittag »
110 = Connection Timed out.

Funktioniert der Tuner denn dann nach einem Neustart des Clients oder startest Du dann den Server neu?

Probleme sind in dem Bereich nicht bekannt (und an dieser Stelle wurde auch schon jahrelang nichts mehr geändert), daher sind wir noch etwas skeptisch ob das nicht irgendwo ein Problem auf Deiner Seite ist.

Schalte die Logfile auf dem Server ein

/etc/sundtek.conf
loglevel=min

Starte den Treiber neu und füge die Logfile hier ggf. an.
/opt/bin/mediaclient --shutdown
/opt/bin/mediaclient --start

(auf dem Server)

Du kannst dich aber auch via Chat melden damit wir da mal drüberschauen

http://chat.sundtek.de

(im Anschluss würden wir hier die Zusammenfassung posten)
« Letzte Änderung: Januar 29, 2016, 01:43:06 Nachmittag von Sundtek »
Failure is a good thing! I'll fix it

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #8 am: Januar 30, 2016, 09:47:30 Vormittag »
Nochmal vielen Dank für die Erklärungen im Chat. Nun hat es in der letzten Nacht wieder unvollständige Aufnahmen gegeben. Eine sollte lt. vdr um 03:02 starten. Die beiden mediasrv.log zeigen, dass es offenbar Probleme am Anfang der Aufnahme gibt:

mediasrv.log auf dem Client:
2016-01-29 19:03:17 [782] Enabling tuning delay
2016-01-30 03:00:59 [782] Setting Frequency: 1612000
2016-01-30 03:00:59 [782] Enabling tuning delay
2016-01-30 03:01:03 [782] Shutting down Transfer 0
2016-01-30 03:01:05 [782] Setting Frequency: 1431000
2016-01-30 03:01:05 [782] Enabling tuning delay
2016-01-30 03:01:08 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:01:08 [782] Starting Transfer: 1
2016-01-30 03:02:00 [782] Shutting down Transfer 0
2016-01-30 03:02:00 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:02:00 [782] Starting Transfer: 1
2016-01-30 03:04:01 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:04:01 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:04:06 [782] Setting Frequency: 1431000
2016-01-30 03:04:06 [782] Enabling tuning delay
2016-01-30 03:04:13 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:07:41 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:07:50 [782] Setting Frequency: 1431000
2016-01-30 03:07:50 [782] Enabling tuning delay
2016-01-30 03:08:05 [782] Setting Frequency: 1431000
2016-01-30 03:08:05 [782] Enabling tuning delay
2016-01-30 03:08:21 [782] Setting Frequency: 1431000
2016-01-30 03:08:21 [782] Enabling tuning delay
2016-01-30 03:08:35 [782] Setting Frequency: 1431000
2016-01-30 03:08:35 [782] Enabling tuning delay
2016-01-30 03:08:41 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:08:53 [782] Setting Frequency: 1431000
2016-01-30 03:08:53 [782] Enabling tuning delay
2016-01-30 03:09:07 [782] Setting Frequency: 1431000
2016-01-30 03:09:07 [782] Enabling tuning delay
2016-01-30 03:09:22 [782] Setting Frequency: 1431000
2016-01-30 03:09:22 [782] Enabling tuning delay
2016-01-30 03:09:23 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:09:31 [782] Setting Frequency: 1431000
2016-01-30 03:09:31 [782] Enabling tuning delay
2016-01-30 03:09:44 [782] Setting Frequency: 1431000
2016-01-30 03:09:44 [782] Enabling tuning delay
2016-01-30 03:10:00 [782] Setting Frequency: 1431000
2016-01-30 03:10:00 [782] Enabling tuning delay
2016-01-30 03:10:04 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:10:16 [782] Setting Frequency: 1431000
2016-01-30 03:10:16 [782] Enabling tuning delay
2016-01-30 03:10:28 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:10:33 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:10:33 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:10:33 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:10:33 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:10:33 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:10:33 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:10:36 [782] Setting Frequency: 1431000
2016-01-30 03:10:36 [782] Enabling tuning delay
2016-01-30 03:10:51 [782] Setting Frequency: 1431000
2016-01-30 03:10:51 [782] Enabling tuning delay
2016-01-30 03:11:10 [782] Setting Frequency: 1431000
2016-01-30 03:11:10 [782] Enabling tuning delay
2016-01-30 03:11:23 [782] Setting Frequency: 1431000
2016-01-30 03:11:23 [782] Enabling tuning delay
2016-01-30 03:11:30 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:12:00 [782] Setting Frequency: 1431000
2016-01-30 03:12:00 [782] Enabling tuning delay
2016-01-30 03:12:15 [782] Setting Frequency: 1431000
2016-01-30 03:12:15 [782] Enabling tuning delay
2016-01-30 03:12:19 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:12:31 [782] Setting Frequency: 1431000
2016-01-30 03:12:31 [782] Enabling tuning delay
2016-01-30 03:12:40 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:12:51 [782] Setting Frequency: 1431000
2016-01-30 03:12:51 [782] Enabling tuning delay
2016-01-30 03:17:13 [782] Setting Frequency: 1431000
2016-01-30 03:17:13 [782] Enabling tuning delay
2016-01-30 03:17:15 [782] Transport error indicator is set, clearing buffers
2016-01-30 03:17:48 [782] Setting Frequency: 1431000
2016-01-30 03:17:48 [782] Enabling tuning delay
2016-01-30 03:18:09 [782] Setting Frequency: 1431000
2016-01-30 03:18:09 [782] Enabling tuning delay
2016-01-30 03:18:42 [782] Setting Frequency: 1431000
2016-01-30 03:18:42 [782] Enabling tuning delay
2016-01-30 03:18:57 [782] Setting Frequency: 1431000
2016-01-30 03:18:57 [782] Enabling tuning delay
2016-01-30 03:19:10 [782] Shutting down Transfer 0
2016-01-30 03:19:15 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:19:15 [782] Starting Transfer: 2
2016-01-30 03:19:15 [782] Setting Frequency: 1023000
2016-01-30 03:19:15 [782] Enabling tuning delay
2016-01-30 03:19:16 [782] Setting Frequency: 1431000
2016-01-30 03:19:16 [782] Enabling tuning delay
2016-01-30 03:19:27 [782] Shutting down Transfer 0
2016-01-30 03:19:27 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:19:27 [782] Starting Transfer: 1
2016-01-30 03:19:31 [782] Setting Frequency: 1431000
2016-01-30 03:19:31 [782] Enabling tuning delay
2016-01-30 03:19:46 [782] Setting Frequency: 1431000
2016-01-30 03:19:46 [782] Enabling tuning delay
2016-01-30 03:20:01 [782] Setting Frequency: 1431000
2016-01-30 03:20:01 [782] Enabling tuning delay
2016-01-30 03:20:14 [782] Shutting down Transfer 0
2016-01-30 03:20:17 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:20:17 [782] Starting Transfer: 2
2016-01-30 03:20:17 [782] Setting Frequency: 1431000
2016-01-30 03:20:17 [782] Enabling tuning delay
2016-01-30 03:20:31 [782] Setting Frequency: 1431000
2016-01-30 03:20:31 [782] Enabling tuning delay
2016-01-30 03:20:46 [782] Setting Frequency: 1431000
2016-01-30 03:20:46 [782] Enabling tuning delay
2016-01-30 03:21:01 [782] Setting Frequency: 1431000
2016-01-30 03:21:01 [782] Enabling tuning delay
2016-01-30 03:21:12 [782] Shutting down Transfer 0
2016-01-30 03:21:19 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:21:19 [782] Starting Transfer: 1
2016-01-30 03:21:19 [782] Setting Frequency: 1431000
2016-01-30 03:21:19 [782] Enabling tuning delay
2016-01-30 03:21:34 [782] Setting Frequency: 1431000
2016-01-30 03:21:34 [782] Enabling tuning delay
2016-01-30 03:21:49 [782] Setting Frequency: 1431000
2016-01-30 03:21:49 [782] Enabling tuning delay
2016-01-30 03:22:04 [782] Setting Frequency: 1431000
2016-01-30 03:22:04 [782] Enabling tuning delay
2016-01-30 03:22:15 [782] Shutting down Transfer 0
2016-01-30 03:22:21 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:22:21 [782] Starting Transfer: 3
2016-01-30 03:22:21 [782] Setting Frequency: 1431000
2016-01-30 03:22:21 [782] Enabling tuning delay
2016-01-30 03:22:36 [782] Setting Frequency: 1431000
2016-01-30 03:22:36 [782] Enabling tuning delay
2016-01-30 03:22:51 [782] Setting Frequency: 1431000
2016-01-30 03:22:51 [782] Enabling tuning delay
2016-01-30 03:23:05 [782] Setting Frequency: 1431000
2016-01-30 03:23:05 [782] Enabling tuning delay
2016-01-30 03:23:16 [782] Shutting down Transfer 0
2016-01-30 03:23:21 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:23:21 [782] Starting Transfer: 1
2016-01-30 03:23:22 [782] Setting Frequency: 1431000
2016-01-30 03:23:22 [782] Enabling tuning delay
2016-01-30 03:23:36 [782] Setting Frequency: 1431000
2016-01-30 03:23:36 [782] Enabling tuning delay
2016-01-30 03:23:51 [782] Setting Frequency: 1431000
2016-01-30 03:23:51 [782] Enabling tuning delay
2016-01-30 03:24:06 [782] Setting Frequency: 1431000
2016-01-30 03:24:06 [782] Enabling tuning delay
2016-01-30 03:24:16 [782] Shutting down Transfer 0
2016-01-30 03:24:22 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:24:22 [782] Starting Transfer: 2
2016-01-30 03:24:22 [782] Setting Frequency: 1431000
2016-01-30 03:24:22 [782] Enabling tuning delay
2016-01-30 03:24:37 [782] Setting Frequency: 1431000
2016-01-30 03:24:37 [782] Enabling tuning delay
2016-01-30 03:24:52 [782] Setting Frequency: 1431000
2016-01-30 03:24:52 [782] Enabling tuning delay
2016-01-30 03:25:07 [782] Setting Frequency: 1431000
2016-01-30 03:25:07 [782] Enabling tuning delay
2016-01-30 03:25:17 [782] Shutting down Transfer 0
2016-01-30 03:25:23 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:25:23 [782] Starting Transfer: 3
2016-01-30 03:25:24 [782] Setting Frequency: 1431000
2016-01-30 03:25:24 [782] Enabling tuning delay
2016-01-30 03:25:38 [782] Setting Frequency: 1431000
2016-01-30 03:25:38 [782] Enabling tuning delay
2016-01-30 03:25:53 [782] Setting Frequency: 1431000
2016-01-30 03:25:53 [782] Enabling tuning delay
2016-01-30 03:26:08 [782] Setting Frequency: 1431000
2016-01-30 03:26:08 [782] Enabling tuning delay
2016-01-30 03:26:19 [782] Shutting down Transfer 0
2016-01-30 03:26:23 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:26:23 [782] Starting Transfer: 3
2016-01-30 03:26:24 [782] Setting Frequency: 1431000
2016-01-30 03:26:24 [782] Enabling tuning delay
2016-01-30 03:26:38 [782] Setting Frequency: 1431000
2016-01-30 03:26:38 [782] Enabling tuning delay
2016-01-30 03:26:53 [782] Setting Frequency: 1431000
2016-01-30 03:26:53 [782] Enabling tuning delay
2016-01-30 03:27:08 [782] Setting Frequency: 1431000
2016-01-30 03:27:08 [782] Enabling tuning delay
2016-01-30 03:27:18 [782] Shutting down Transfer 0
2016-01-30 03:27:24 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:27:24 [782] Starting Transfer: 1
2016-01-30 03:27:25 [782] Setting Frequency: 1431000
2016-01-30 03:27:25 [782] Enabling tuning delay
2016-01-30 03:27:39 [782] Setting Frequency: 1431000
2016-01-30 03:27:39 [782] Enabling tuning delay
2016-01-30 03:27:41 [782] Shutting down Transfer 0
2016-01-30 03:27:42 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:27:42 [782] Starting Transfer: 2
2016-01-30 03:27:58 [782] Shutting down Transfer 0
2016-01-30 03:27:58 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:27:58 [782] Starting Transfer: 1
2016-01-30 03:28:29 [782] Setting Frequency: 1431000
2016-01-30 03:28:29 [782] Enabling tuning delay
2016-01-30 03:28:44 [782] Setting Frequency: 1431000
2016-01-30 03:28:44 [782] Enabling tuning delay
2016-01-30 03:28:57 [782] Shutting down Transfer 0
2016-01-30 03:29:03 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:29:03 [782] Starting Transfer: 3
2016-01-30 03:29:03 [782] Setting Frequency: 1431000
2016-01-30 03:29:03 [782] Enabling tuning delay
2016-01-30 03:29:18 [782] Setting Frequency: 1431000
2016-01-30 03:29:18 [782] Enabling tuning delay
2016-01-30 03:29:32 [782] Setting Frequency: 1431000
2016-01-30 03:29:32 [782] Enabling tuning delay
2016-01-30 03:29:47 [782] Setting Frequency: 1431000
2016-01-30 03:29:47 [782] Enabling tuning delay
2016-01-30 03:29:58 [782] Shutting down Transfer 0
2016-01-30 03:30:04 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:30:04 [782] Starting Transfer: 3
2016-01-30 03:30:04 [782] Setting Frequency: 1431000
2016-01-30 03:30:04 [782] Enabling tuning delay
2016-01-30 03:30:19 [782] Setting Frequency: 1431000
2016-01-30 03:30:19 [782] Enabling tuning delay
2016-01-30 03:30:34 [782] Setting Frequency: 1431000
2016-01-30 03:30:34 [782] Enabling tuning delay
2016-01-30 03:30:48 [782] Setting Frequency: 1431000
2016-01-30 03:30:48 [782] Enabling tuning delay
2016-01-30 03:30:59 [782] Shutting down Transfer 0
2016-01-30 03:31:04 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:31:04 [782] Starting Transfer: 3
2016-01-30 03:31:05 [782] Setting Frequency: 1431000
2016-01-30 03:31:05 [782] Enabling tuning delay
2016-01-30 03:31:20 [782] Setting Frequency: 1431000
2016-01-30 03:31:20 [782] Enabling tuning delay
2016-01-30 03:31:34 [782] Setting Frequency: 1431000
2016-01-30 03:31:34 [782] Enabling tuning delay
2016-01-30 03:31:49 [782] Setting Frequency: 1431000
2016-01-30 03:31:49 [782] Enabling tuning delay
2016-01-30 03:31:59 [782] Shutting down Transfer 0
2016-01-30 03:32:06 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:32:06 [782] Starting Transfer: 3
2016-01-30 03:32:07 [782] Setting Frequency: 1431000
2016-01-30 03:32:07 [782] Enabling tuning delay
2016-01-30 03:32:22 [782] Setting Frequency: 1431000
2016-01-30 03:32:22 [782] Enabling tuning delay
2016-01-30 03:32:36 [782] Setting Frequency: 1431000
2016-01-30 03:32:36 [782] Enabling tuning delay
2016-01-30 03:32:51 [782] Setting Frequency: 1431000
2016-01-30 03:32:51 [782] Enabling tuning delay
2016-01-30 03:33:01 [782] Shutting down Transfer 0
2016-01-30 03:33:08 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:33:08 [782] Starting Transfer: 1
2016-01-30 03:33:09 [782] Setting Frequency: 1431000
2016-01-30 03:33:09 [782] Enabling tuning delay
2016-01-30 03:33:23 [782] Setting Frequency: 1431000
2016-01-30 03:33:23 [782] Enabling tuning delay
2016-01-30 03:33:38 [782] Setting Frequency: 1431000
2016-01-30 03:33:38 [782] Enabling tuning delay
2016-01-30 03:33:53 [782] Setting Frequency: 1431000
2016-01-30 03:33:53 [782] Enabling tuning delay
2016-01-30 03:34:04 [782] Shutting down Transfer 0
2016-01-30 03:34:09 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:34:09 [782] Starting Transfer: 3
2016-01-30 03:34:09 [782] Setting Frequency: 1431000
2016-01-30 03:34:09 [782] Enabling tuning delay
2016-01-30 03:34:24 [782] Setting Frequency: 1431000
2016-01-30 03:34:24 [782] Enabling tuning delay
2016-01-30 03:34:38 [782] Setting Frequency: 1431000
2016-01-30 03:34:38 [782] Enabling tuning delay
2016-01-30 03:34:53 [782] Setting Frequency: 1431000
2016-01-30 03:34:53 [782] Enabling tuning delay
2016-01-30 03:35:04 [782] Shutting down Transfer 0
2016-01-30 03:35:11 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:35:11 [782] Starting Transfer: 1
2016-01-30 03:35:11 [782] Setting Frequency: 1431000
2016-01-30 03:35:11 [782] Enabling tuning delay
2016-01-30 03:35:37 [782] Shutting down Transfer 0
2016-01-30 03:35:37 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:35:37 [782] Starting Transfer: 1
2016-01-30 03:36:01 [782] Setting Frequency: 1431000
2016-01-30 03:36:01 [782] Enabling tuning delay
2016-01-30 03:36:16 [782] Setting Frequency: 1431000
2016-01-30 03:36:16 [782] Enabling tuning delay
2016-01-30 03:36:29 [782] Shutting down Transfer 0
2016-01-30 03:36:34 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:36:34 [782] Starting Transfer: 3
2016-01-30 03:36:35 [782] Setting Frequency: 1431000
2016-01-30 03:36:35 [782] Enabling tuning delay
2016-01-30 03:36:45 [782] Shutting down Transfer 0
2016-01-30 03:36:45 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:36:45 [782] Starting Transfer: 1
2016-01-30 03:37:04 [782] Shutting down Transfer 0
2016-01-30 03:37:04 [782] Connecting to: 192.168.1.24:35944
2016-01-30 03:37:04 [782] Starting Transfer: 1
2016-01-30 04:19:45 [16271] Set Voltage Horizontal (18V)
2016-01-30 04:19:45 [16271] Enabling High Tone (22khz)

Also funktionierte die Aufnahme dann wohl ab 03:37 ohne Probleme.

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #9 am: Januar 30, 2016, 09:48:12 Vormittag »
mediasrv.log auf dem Server:
2016-01-29 19:03:05 [16271] Symbolrate: 22000
2016-01-30 03:00:59 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:00:59 [16271] Disabling High Tone (22khz)
2016-01-30 03:01:00 [16271] DTV CMD currently not handled: 42
2016-01-30 03:01:00 [16271] Setting Frequency: 1612000
2016-01-30 03:01:00 [16271] Frequency: 1612
2016-01-30 03:01:00 [16271] Symbolrate: 22000
2016-01-30 03:01:03 [16271] Shutting down IPTV
2016-01-30 03:01:05 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:01:05 [16271] Enabling High Tone (22khz)
2016-01-30 03:01:06 [16271] DTV CMD currently not handled: 42
2016-01-30 03:01:06 [16271] Setting Frequency: 1431000
2016-01-30 03:01:06 [16271] Frequency: 1431
2016-01-30 03:01:06 [16271] Symbolrate: 27500
2016-01-30 03:01:08 [16271] Starting up IPTV
2016-01-30 03:02:00 [16271] Shutting down IPTV
2016-01-30 03:02:00 [16271] Starting up IPTV
2016-01-30 03:04:06 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:04:06 [16271] Enabling High Tone (22khz)
2016-01-30 03:04:07 [16271] DTV CMD currently not handled: 42
2016-01-30 03:04:07 [16271] Setting Frequency: 1431000
2016-01-30 03:04:07 [16271] Frequency: 1431
2016-01-30 03:04:07 [16271] Symbolrate: 27500
2016-01-30 03:07:50 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:07:50 [16271] Enabling High Tone (22khz)
2016-01-30 03:07:50 [16271] DTV CMD currently not handled: 42
2016-01-30 03:07:50 [16271] Setting Frequency: 1431000
2016-01-30 03:07:50 [16271] Frequency: 1431
2016-01-30 03:07:50 [16271] Symbolrate: 27500
2016-01-30 03:08:04 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:08:05 [16271] Enabling High Tone (22khz)
2016-01-30 03:08:05 [16271] DTV CMD currently not handled: 42
2016-01-30 03:08:05 [16271] Setting Frequency: 1431000
2016-01-30 03:08:05 [16271] Frequency: 1431
2016-01-30 03:08:05 [16271] Symbolrate: 27500
2016-01-30 03:08:21 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:08:21 [16271] Enabling High Tone (22khz)
2016-01-30 03:08:21 [16271] DTV CMD currently not handled: 42
2016-01-30 03:08:21 [16271] Setting Frequency: 1431000
2016-01-30 03:08:21 [16271] Frequency: 1431
2016-01-30 03:08:21 [16271] Symbolrate: 27500
2016-01-30 03:08:35 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:08:35 [16271] Enabling High Tone (22khz)
2016-01-30 03:08:35 [16271] DTV CMD currently not handled: 42
2016-01-30 03:08:35 [16271] Setting Frequency: 1431000
2016-01-30 03:08:35 [16271] Frequency: 1431
2016-01-30 03:08:35 [16271] Symbolrate: 27500
2016-01-30 03:08:53 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:08:53 [16271] Enabling High Tone (22khz)
2016-01-30 03:08:53 [16271] DTV CMD currently not handled: 42
2016-01-30 03:08:53 [16271] Setting Frequency: 1431000
2016-01-30 03:08:53 [16271] Frequency: 1431
2016-01-30 03:08:53 [16271] Symbolrate: 27500
2016-01-30 03:09:07 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:09:07 [16271] Enabling High Tone (22khz)
2016-01-30 03:09:08 [16271] DTV CMD currently not handled: 42
2016-01-30 03:09:08 [16271] Setting Frequency: 1431000
2016-01-30 03:09:08 [16271] Frequency: 1431
2016-01-30 03:09:08 [16271] Symbolrate: 27500
2016-01-30 03:09:22 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:09:22 [16271] Enabling High Tone (22khz)
2016-01-30 03:09:22 [16271] DTV CMD currently not handled: 42
2016-01-30 03:09:22 [16271] Setting Frequency: 1431000
2016-01-30 03:09:22 [16271] Frequency: 1431
2016-01-30 03:09:22 [16271] Symbolrate: 27500
2016-01-30 03:09:30 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:09:31 [16271] Enabling High Tone (22khz)
2016-01-30 03:09:31 [16271] DTV CMD currently not handled: 42
2016-01-30 03:09:31 [16271] Setting Frequency: 1431000
2016-01-30 03:09:31 [16271] Frequency: 1431
2016-01-30 03:09:31 [16271] Symbolrate: 27500
2016-01-30 03:09:44 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:09:44 [16271] Enabling High Tone (22khz)
2016-01-30 03:09:45 [16271] DTV CMD currently not handled: 42
2016-01-30 03:09:45 [16271] Setting Frequency: 1431000
2016-01-30 03:09:45 [16271] Frequency: 1431
2016-01-30 03:09:45 [16271] Symbolrate: 27500
2016-01-30 03:10:00 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:10:00 [16271] Enabling High Tone (22khz)
2016-01-30 03:10:01 [16271] DTV CMD currently not handled: 42
2016-01-30 03:10:01 [16271] Setting Frequency: 1431000
2016-01-30 03:10:01 [16271] Frequency: 1431
2016-01-30 03:10:01 [16271] Symbolrate: 27500
2016-01-30 03:10:16 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:10:16 [16271] Enabling High Tone (22khz)
2016-01-30 03:10:16 [16271] DTV CMD currently not handled: 42
2016-01-30 03:10:16 [16271] Setting Frequency: 1431000
2016-01-30 03:10:16 [16271] Frequency: 1431
2016-01-30 03:10:16 [16271] Symbolrate: 27500
2016-01-30 03:10:36 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:10:36 [16271] Enabling High Tone (22khz)
2016-01-30 03:10:37 [16271] DTV CMD currently not handled: 42
2016-01-30 03:10:37 [16271] Setting Frequency: 1431000
2016-01-30 03:10:37 [16271] Frequency: 1431
2016-01-30 03:10:37 [16271] Symbolrate: 27500
2016-01-30 03:10:51 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:10:51 [16271] Enabling High Tone (22khz)
2016-01-30 03:10:52 [16271] DTV CMD currently not handled: 42
2016-01-30 03:10:52 [16271] Setting Frequency: 1431000
2016-01-30 03:10:52 [16271] Frequency: 1431
2016-01-30 03:10:52 [16271] Symbolrate: 27500
2016-01-30 03:11:09 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:11:09 [16271] Enabling High Tone (22khz)
2016-01-30 03:11:10 [16271] DTV CMD currently not handled: 42
2016-01-30 03:11:10 [16271] Setting Frequency: 1431000
2016-01-30 03:11:10 [16271] Frequency: 1431
2016-01-30 03:11:10 [16271] Symbolrate: 27500
2016-01-30 03:11:22 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:11:23 [16271] Enabling High Tone (22khz)
2016-01-30 03:11:23 [16271] DTV CMD currently not handled: 42
2016-01-30 03:11:23 [16271] Setting Frequency: 1431000
2016-01-30 03:11:23 [16271] Frequency: 1431
2016-01-30 03:11:23 [16271] Symbolrate: 27500
2016-01-30 03:12:00 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:12:00 [16271] Enabling High Tone (22khz)
2016-01-30 03:12:00 [16271] DTV CMD currently not handled: 42
2016-01-30 03:12:00 [16271] Setting Frequency: 1431000
2016-01-30 03:12:01 [16271] Frequency: 1431
2016-01-30 03:12:01 [16271] Symbolrate: 27500
2016-01-30 03:12:15 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:12:15 [16271] Enabling High Tone (22khz)
2016-01-30 03:12:15 [16271] DTV CMD currently not handled: 42
2016-01-30 03:12:15 [16271] Setting Frequency: 1431000
2016-01-30 03:12:15 [16271] Frequency: 1431
2016-01-30 03:12:15 [16271] Symbolrate: 27500
2016-01-30 03:12:31 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:12:31 [16271] Enabling High Tone (22khz)
2016-01-30 03:12:31 [16271] DTV CMD currently not handled: 42
2016-01-30 03:12:31 [16271] Setting Frequency: 1431000
2016-01-30 03:12:31 [16271] Frequency: 1431
2016-01-30 03:12:31 [16271] Symbolrate: 27500
2016-01-30 03:12:51 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:12:51 [16271] Enabling High Tone (22khz)
2016-01-30 03:12:51 [16271] DTV CMD currently not handled: 42
2016-01-30 03:12:51 [16271] Setting Frequency: 1431000
2016-01-30 03:12:51 [16271] Frequency: 1431
2016-01-30 03:12:51 [16271] Symbolrate: 27500
2016-01-30 03:17:13 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:17:13 [16271] Enabling High Tone (22khz)
2016-01-30 03:17:13 [16271] DTV CMD currently not handled: 42
2016-01-30 03:17:13 [16271] Setting Frequency: 1431000
2016-01-30 03:17:13 [16271] Frequency: 1431
2016-01-30 03:17:13 [16271] Symbolrate: 27500
2016-01-30 03:17:48 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:17:48 [16271] Enabling High Tone (22khz)
2016-01-30 03:17:48 [16271] DTV CMD currently not handled: 42
2016-01-30 03:17:48 [16271] Setting Frequency: 1431000
2016-01-30 03:17:48 [16271] Frequency: 1431
2016-01-30 03:17:48 [16271] Symbolrate: 27500
2016-01-30 03:18:09 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:18:09 [16271] Enabling High Tone (22khz)
2016-01-30 03:18:09 [16271] DTV CMD currently not handled: 42
2016-01-30 03:18:09 [16271] Setting Frequency: 1431000
2016-01-30 03:18:09 [16271] Frequency: 1431
2016-01-30 03:18:09 [16271] Symbolrate: 27500
2016-01-30 03:18:42 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:18:42 [16271] Enabling High Tone (22khz)
2016-01-30 03:18:42 [16271] DTV CMD currently not handled: 42
2016-01-30 03:18:42 [16271] Setting Frequency: 1431000
2016-01-30 03:18:42 [16271] Frequency: 1431
2016-01-30 03:18:42 [16271] Symbolrate: 27500
2016-01-30 03:18:57 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:18:57 [16271] Enabling High Tone (22khz)
2016-01-30 03:18:57 [16271] DTV CMD currently not handled: 42
2016-01-30 03:18:57 [16271] Setting Frequency: 1431000
2016-01-30 03:18:57 [16271] Frequency: 1431
2016-01-30 03:18:57 [16271] Symbolrate: 27500
2016-01-30 03:19:10 [16271] Shutting down IPTV
2016-01-30 03:19:15 [16271] Starting up IPTV
2016-01-30 03:19:15 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:19:15 [16271] Disabling High Tone (22khz)
2016-01-30 03:19:15 [16271] DTV CMD currently not handled: 42
2016-01-30 03:19:15 [16271] Setting Frequency: 1023000
2016-01-30 03:19:15 [16271] Frequency: 1023
2016-01-30 03:19:15 [16271] Symbolrate: 22000
2016-01-30 03:19:16 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:19:16 [16271] Enabling High Tone (22khz)
2016-01-30 03:19:17 [16271] DTV CMD currently not handled: 42
2016-01-30 03:19:17 [16271] Setting Frequency: 1431000
2016-01-30 03:19:17 [16271] Frequency: 1431
2016-01-30 03:19:17 [16271] Symbolrate: 27500
2016-01-30 03:19:27 [16271] Shutting down IPTV
2016-01-30 03:19:27 [16271] Starting up IPTV
2016-01-30 03:19:31 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:19:31 [16271] Enabling High Tone (22khz)
2016-01-30 03:19:32 [16271] DTV CMD currently not handled: 42
2016-01-30 03:19:32 [16271] Setting Frequency: 1431000
2016-01-30 03:19:32 [16271] Frequency: 1431
2016-01-30 03:19:32 [16271] Symbolrate: 27500
2016-01-30 03:19:46 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:19:46 [16271] Enabling High Tone (22khz)
2016-01-30 03:19:46 [16271] DTV CMD currently not handled: 42
2016-01-30 03:19:46 [16271] Setting Frequency: 1431000
2016-01-30 03:19:46 [16271] Frequency: 1431
2016-01-30 03:19:46 [16271] Symbolrate: 27500
2016-01-30 03:20:01 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:20:01 [16271] Enabling High Tone (22khz)
2016-01-30 03:20:01 [16271] DTV CMD currently not handled: 42
2016-01-30 03:20:01 [16271] Setting Frequency: 1431000
2016-01-30 03:20:01 [16271] Frequency: 1431
2016-01-30 03:20:01 [16271] Symbolrate: 27500
2016-01-30 03:20:14 [16271] Shutting down IPTV
2016-01-30 03:20:17 [16271] Starting up IPTV
2016-01-30 03:20:17 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:20:17 [16271] Enabling High Tone (22khz)
2016-01-30 03:20:17 [16271] DTV CMD currently not handled: 42
2016-01-30 03:20:17 [16271] Setting Frequency: 1431000
2016-01-30 03:20:17 [16271] Frequency: 1431
2016-01-30 03:20:17 [16271] Symbolrate: 27500
2016-01-30 03:20:31 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:20:31 [16271] Enabling High Tone (22khz)
2016-01-30 03:20:32 [16271] DTV CMD currently not handled: 42
2016-01-30 03:20:32 [16271] Setting Frequency: 1431000
2016-01-30 03:20:32 [16271] Frequency: 1431
2016-01-30 03:20:32 [16271] Symbolrate: 27500
2016-01-30 03:20:46 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:20:46 [16271] Enabling High Tone (22khz)
2016-01-30 03:20:47 [16271] DTV CMD currently not handled: 42
2016-01-30 03:20:47 [16271] Setting Frequency: 1431000
2016-01-30 03:20:47 [16271] Frequency: 1431
2016-01-30 03:20:47 [16271] Symbolrate: 27500
2016-01-30 03:21:01 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:21:01 [16271] Enabling High Tone (22khz)
2016-01-30 03:21:01 [16271] DTV CMD currently not handled: 42
2016-01-30 03:21:01 [16271] Setting Frequency: 1431000
2016-01-30 03:21:01 [16271] Frequency: 1431
2016-01-30 03:21:01 [16271] Symbolrate: 27500
2016-01-30 03:21:12 [16271] Shutting down IPTV
2016-01-30 03:21:19 [16271] Starting up IPTV
2016-01-30 03:21:19 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:21:19 [16271] Enabling High Tone (22khz)
2016-01-30 03:21:20 [16271] DTV CMD currently not handled: 42
2016-01-30 03:21:20 [16271] Setting Frequency: 1431000
2016-01-30 03:21:20 [16271] Frequency: 1431
2016-01-30 03:21:20 [16271] Symbolrate: 27500
2016-01-30 03:21:34 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:21:34 [16271] Enabling High Tone (22khz)
2016-01-30 03:21:35 [16271] DTV CMD currently not handled: 42
2016-01-30 03:21:35 [16271] Setting Frequency: 1431000
2016-01-30 03:21:35 [16271] Frequency: 1431
2016-01-30 03:21:35 [16271] Symbolrate: 27500
2016-01-30 03:21:49 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:21:49 [16271] Enabling High Tone (22khz)
2016-01-30 03:21:50 [16271] DTV CMD currently not handled: 42
2016-01-30 03:21:50 [16271] Setting Frequency: 1431000
2016-01-30 03:21:50 [16271] Frequency: 1431
2016-01-30 03:21:50 [16271] Symbolrate: 27500
2016-01-30 03:22:04 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:22:04 [16271] Enabling High Tone (22khz)
2016-01-30 03:22:04 [16271] DTV CMD currently not handled: 42
2016-01-30 03:22:04 [16271] Setting Frequency: 1431000
2016-01-30 03:22:04 [16271] Frequency: 1431
2016-01-30 03:22:04 [16271] Symbolrate: 27500
2016-01-30 03:22:15 [16271] Shutting down IPTV
2016-01-30 03:22:21 [16271] Starting up IPTV
2016-01-30 03:22:21 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:22:21 [16271] Enabling High Tone (22khz)
2016-01-30 03:22:22 [16271] DTV CMD currently not handled: 42
2016-01-30 03:22:22 [16271] Setting Frequency: 1431000
2016-01-30 03:22:22 [16271] Frequency: 1431
2016-01-30 03:22:22 [16271] Symbolrate: 27500
2016-01-30 03:22:36 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:22:36 [16271] Enabling High Tone (22khz)
2016-01-30 03:22:36 [16271] DTV CMD currently not handled: 42
2016-01-30 03:22:36 [16271] Setting Frequency: 1431000
2016-01-30 03:22:36 [16271] Frequency: 1431
2016-01-30 03:22:36 [16271] Symbolrate: 27500
2016-01-30 03:22:51 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:22:51 [16271] Enabling High Tone (22khz)
2016-01-30 03:22:51 [16271] DTV CMD currently not handled: 42
2016-01-30 03:22:51 [16271] Setting Frequency: 1431000
2016-01-30 03:22:51 [16271] Frequency: 1431
2016-01-30 03:22:51 [16271] Symbolrate: 27500
2016-01-30 03:23:05 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:23:05 [16271] Enabling High Tone (22khz)
2016-01-30 03:23:06 [16271] DTV CMD currently not handled: 42
2016-01-30 03:23:06 [16271] Setting Frequency: 1431000
2016-01-30 03:23:06 [16271] Frequency: 1431
2016-01-30 03:23:06 [16271] Symbolrate: 27500
2016-01-30 03:23:16 [16271] Shutting down IPTV
2016-01-30 03:23:21 [16271] Starting up IPTV
2016-01-30 03:23:21 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:23:21 [16271] Enabling High Tone (22khz)
2016-01-30 03:23:22 [16271] DTV CMD currently not handled: 42
2016-01-30 03:23:22 [16271] Setting Frequency: 1431000
2016-01-30 03:23:22 [16271] Frequency: 1431
2016-01-30 03:23:22 [16271] Symbolrate: 27500
2016-01-30 03:23:36 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:23:36 [16271] Enabling High Tone (22khz)
2016-01-30 03:23:37 [16271] DTV CMD currently not handled: 42
2016-01-30 03:23:37 [16271] Setting Frequency: 1431000
2016-01-30 03:23:37 [16271] Frequency: 1431
2016-01-30 03:23:37 [16271] Symbolrate: 27500
2016-01-30 03:23:51 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:23:51 [16271] Enabling High Tone (22khz)
2016-01-30 03:23:51 [16271] DTV CMD currently not handled: 42
2016-01-30 03:23:51 [16271] Setting Frequency: 1431000
2016-01-30 03:23:51 [16271] Frequency: 1431
2016-01-30 03:23:51 [16271] Symbolrate: 27500
2016-01-30 03:24:05 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:24:06 [16271] Enabling High Tone (22khz)
2016-01-30 03:24:06 [16271] DTV CMD currently not handled: 42
2016-01-30 03:24:06 [16271] Setting Frequency: 1431000
2016-01-30 03:24:06 [16271] Frequency: 1431
2016-01-30 03:24:06 [16271] Symbolrate: 27500
2016-01-30 03:24:16 [16271] Shutting down IPTV
2016-01-30 03:24:22 [16271] Starting up IPTV
2016-01-30 03:24:22 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:24:22 [16271] Enabling High Tone (22khz)
2016-01-30 03:24:23 [16271] DTV CMD currently not handled: 42
2016-01-30 03:24:23 [16271] Setting Frequency: 1431000
2016-01-30 03:24:23 [16271] Frequency: 1431
2016-01-30 03:24:23 [16271] Symbolrate: 27500
2016-01-30 03:24:37 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:24:37 [16271] Enabling High Tone (22khz)
2016-01-30 03:24:37 [16271] DTV CMD currently not handled: 42
2016-01-30 03:24:37 [16271] Setting Frequency: 1431000
2016-01-30 03:24:37 [16271] Frequency: 1431
2016-01-30 03:24:37 [16271] Symbolrate: 27500
2016-01-30 03:24:52 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:24:52 [16271] Enabling High Tone (22khz)
2016-01-30 03:24:52 [16271] DTV CMD currently not handled: 42
2016-01-30 03:24:52 [16271] Setting Frequency: 1431000
2016-01-30 03:24:52 [16271] Frequency: 1431
2016-01-30 03:24:52 [16271] Symbolrate: 27500
2016-01-30 03:25:06 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:25:07 [16271] Enabling High Tone (22khz)
2016-01-30 03:25:07 [16271] DTV CMD currently not handled: 42
2016-01-30 03:25:07 [16271] Setting Frequency: 1431000
2016-01-30 03:25:07 [16271] Frequency: 1431
2016-01-30 03:25:07 [16271] Symbolrate: 27500
2016-01-30 03:25:17 [16271] Shutting down IPTV
2016-01-30 03:25:23 [16271] Starting up IPTV
2016-01-30 03:25:24 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:25:24 [16271] Enabling High Tone (22khz)
2016-01-30 03:25:24 [16271] DTV CMD currently not handled: 42
2016-01-30 03:25:24 [16271] Setting Frequency: 1431000
2016-01-30 03:25:24 [16271] Frequency: 1431
2016-01-30 03:25:24 [16271] Symbolrate: 27500
2016-01-30 03:25:38 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:25:38 [16271] Enabling High Tone (22khz)
2016-01-30 03:25:39 [16271] DTV CMD currently not handled: 42
2016-01-30 03:25:39 [16271] Setting Frequency: 1431000
2016-01-30 03:25:39 [16271] Frequency: 1431
2016-01-30 03:25:39 [16271] Symbolrate: 27500
2016-01-30 03:25:53 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:25:53 [16271] Enabling High Tone (22khz)
2016-01-30 03:25:54 [16271] DTV CMD currently not handled: 42
2016-01-30 03:25:54 [16271] Setting Frequency: 1431000
2016-01-30 03:25:54 [16271] Frequency: 1431
2016-01-30 03:25:54 [16271] Symbolrate: 27500
2016-01-30 03:26:08 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:26:08 [16271] Enabling High Tone (22khz)
2016-01-30 03:26:08 [16271] DTV CMD currently not handled: 42
2016-01-30 03:26:08 [16271] Setting Frequency: 1431000
2016-01-30 03:26:08 [16271] Frequency: 1431
2016-01-30 03:26:08 [16271] Symbolrate: 27500
2016-01-30 03:26:19 [16271] Shutting down IPTV
2016-01-30 03:26:23 [16271] Starting up IPTV
2016-01-30 03:26:24 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:26:24 [16271] Enabling High Tone (22khz)
2016-01-30 03:26:24 [16271] DTV CMD currently not handled: 42
2016-01-30 03:26:24 [16271] Setting Frequency: 1431000
2016-01-30 03:26:24 [16271] Frequency: 1431
2016-01-30 03:26:24 [16271] Symbolrate: 27500
2016-01-30 03:26:38 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:26:38 [16271] Enabling High Tone (22khz)
2016-01-30 03:26:39 [16271] DTV CMD currently not handled: 42
2016-01-30 03:26:39 [16271] Setting Frequency: 1431000
2016-01-30 03:26:39 [16271] Frequency: 1431
2016-01-30 03:26:39 [16271] Symbolrate: 27500
2016-01-30 03:26:53 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:26:53 [16271] Enabling High Tone (22khz)
2016-01-30 03:26:53 [16271] DTV CMD currently not handled: 42
2016-01-30 03:26:53 [16271] Setting Frequency: 1431000
2016-01-30 03:26:53 [16271] Frequency: 1431
2016-01-30 03:26:53 [16271] Symbolrate: 27500
2016-01-30 03:27:07 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:27:08 [16271] Enabling High Tone (22khz)
2016-01-30 03:27:08 [16271] DTV CMD currently not handled: 42
2016-01-30 03:27:08 [16271] Setting Frequency: 1431000
2016-01-30 03:27:08 [16271] Frequency: 1431
2016-01-30 03:27:08 [16271] Symbolrate: 27500
2016-01-30 03:27:18 [16271] Shutting down IPTV
2016-01-30 03:27:24 [16271] Starting up IPTV
2016-01-30 03:27:24 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:27:24 [16271] Enabling High Tone (22khz)
2016-01-30 03:27:25 [16271] DTV CMD currently not handled: 42
2016-01-30 03:27:25 [16271] Setting Frequency: 1431000
2016-01-30 03:27:25 [16271] Frequency: 1431
2016-01-30 03:27:25 [16271] Symbolrate: 27500
2016-01-30 03:27:39 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:27:39 [16271] Enabling High Tone (22khz)
2016-01-30 03:27:40 [16271] DTV CMD currently not handled: 42
2016-01-30 03:27:40 [16271] Setting Frequency: 1431000
2016-01-30 03:27:40 [16271] Frequency: 1431
2016-01-30 03:27:40 [16271] Symbolrate: 27500
2016-01-30 03:27:41 [16271] Shutting down IPTV
2016-01-30 03:27:42 [16271] Starting up IPTV
2016-01-30 03:27:58 [16271] Shutting down IPTV
2016-01-30 03:27:58 [16271] Starting up IPTV
2016-01-30 03:28:29 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:28:29 [16271] Enabling High Tone (22khz)
2016-01-30 03:28:30 [16271] DTV CMD currently not handled: 42
2016-01-30 03:28:30 [16271] Setting Frequency: 1431000
2016-01-30 03:28:30 [16271] Frequency: 1431
2016-01-30 03:28:30 [16271] Symbolrate: 27500
2016-01-30 03:28:44 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:28:44 [16271] Enabling High Tone (22khz)
2016-01-30 03:28:44 [16271] DTV CMD currently not handled: 42
2016-01-30 03:28:44 [16271] Setting Frequency: 1431000
2016-01-30 03:28:44 [16271] Frequency: 1431
2016-01-30 03:28:44 [16271] Symbolrate: 27500
2016-01-30 03:28:57 [16271] Shutting down IPTV
2016-01-30 03:29:03 [16271] Starting up IPTV
2016-01-30 03:29:03 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:29:03 [16271] Enabling High Tone (22khz)
2016-01-30 03:29:03 [16271] DTV CMD currently not handled: 42
2016-01-30 03:29:03 [16271] Setting Frequency: 1431000
2016-01-30 03:29:03 [16271] Frequency: 1431
2016-01-30 03:29:03 [16271] Symbolrate: 27500
2016-01-30 03:29:17 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:29:18 [16271] Enabling High Tone (22khz)
2016-01-30 03:29:18 [16271] DTV CMD currently not handled: 42
2016-01-30 03:29:18 [16271] Setting Frequency: 1431000
2016-01-30 03:29:18 [16271] Frequency: 1431
2016-01-30 03:29:18 [16271] Symbolrate: 27500
2016-01-30 03:29:32 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:29:32 [16271] Enabling High Tone (22khz)
2016-01-30 03:29:33 [16271] DTV CMD currently not handled: 42
2016-01-30 03:29:33 [16271] Setting Frequency: 1431000
2016-01-30 03:29:33 [16271] Frequency: 1431
2016-01-30 03:29:33 [16271] Symbolrate: 27500
2016-01-30 03:29:47 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:29:47 [16271] Enabling High Tone (22khz)
2016-01-30 03:29:48 [16271] DTV CMD currently not handled: 42
2016-01-30 03:29:48 [16271] Setting Frequency: 1431000
2016-01-30 03:29:48 [16271] Frequency: 1431
2016-01-30 03:29:48 [16271] Symbolrate: 27500
2016-01-30 03:29:58 [16271] Shutting down IPTV
2016-01-30 03:30:04 [16271] Starting up IPTV
2016-01-30 03:30:04 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:30:04 [16271] Enabling High Tone (22khz)
2016-01-30 03:30:05 [16271] DTV CMD currently not handled: 42
2016-01-30 03:30:05 [16271] Setting Frequency: 1431000
2016-01-30 03:30:05 [16271] Frequency: 1431
2016-01-30 03:30:05 [16271] Symbolrate: 27500
2016-01-30 03:30:19 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:30:19 [16271] Enabling High Tone (22khz)
2016-01-30 03:30:19 [16271] DTV CMD currently not handled: 42
2016-01-30 03:30:19 [16271] Setting Frequency: 1431000
2016-01-30 03:30:19 [16271] Frequency: 1431
2016-01-30 03:30:19 [16271] Symbolrate: 27500
2016-01-30 03:30:33 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:30:33 [16271] Enabling High Tone (22khz)
2016-01-30 03:30:34 [16271] DTV CMD currently not handled: 42
2016-01-30 03:30:34 [16271] Setting Frequency: 1431000
2016-01-30 03:30:34 [16271] Frequency: 1431
2016-01-30 03:30:34 [16271] Symbolrate: 27500
2016-01-30 03:30:48 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:30:48 [16271] Enabling High Tone (22khz)
2016-01-30 03:30:49 [16271] DTV CMD currently not handled: 42
2016-01-30 03:30:49 [16271] Setting Frequency: 1431000
2016-01-30 03:30:49 [16271] Frequency: 1431
2016-01-30 03:30:49 [16271] Symbolrate: 27500
2016-01-30 03:30:59 [16271] Shutting down IPTV
2016-01-30 03:31:04 [16271] Starting up IPTV
2016-01-30 03:31:05 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:31:05 [16271] Enabling High Tone (22khz)
2016-01-30 03:31:05 [16271] DTV CMD currently not handled: 42
2016-01-30 03:31:05 [16271] Setting Frequency: 1431000
2016-01-30 03:31:05 [16271] Frequency: 1431
2016-01-30 03:31:05 [16271] Symbolrate: 27500
2016-01-30 03:31:19 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:31:19 [16271] Enabling High Tone (22khz)
2016-01-30 03:31:20 [16271] DTV CMD currently not handled: 42
2016-01-30 03:31:20 [16271] Setting Frequency: 1431000
2016-01-30 03:31:20 [16271] Frequency: 1431
2016-01-30 03:31:20 [16271] Symbolrate: 27500
2016-01-30 03:31:34 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:31:34 [16271] Enabling High Tone (22khz)
2016-01-30 03:31:35 [16271] DTV CMD currently not handled: 42
2016-01-30 03:31:35 [16271] Setting Frequency: 1431000
2016-01-30 03:31:35 [16271] Frequency: 1431
2016-01-30 03:31:35 [16271] Symbolrate: 27500
2016-01-30 03:31:49 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:31:49 [16271] Enabling High Tone (22khz)
2016-01-30 03:31:49 [16271] DTV CMD currently not handled: 42
2016-01-30 03:31:49 [16271] Setting Frequency: 1431000
2016-01-30 03:31:49 [16271] Frequency: 1431
2016-01-30 03:31:49 [16271] Symbolrate: 27500
2016-01-30 03:31:59 [16271] Shutting down IPTV
2016-01-30 03:32:06 [16271] Starting up IPTV
2016-01-30 03:32:07 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:32:07 [16271] Enabling High Tone (22khz)
2016-01-30 03:32:07 [16271] DTV CMD currently not handled: 42
2016-01-30 03:32:07 [16271] Setting Frequency: 1431000
2016-01-30 03:32:07 [16271] Frequency: 1431
2016-01-30 03:32:07 [16271] Symbolrate: 27500
2016-01-30 03:32:21 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:32:21 [16271] Enabling High Tone (22khz)
2016-01-30 03:32:22 [16271] DTV CMD currently not handled: 42
2016-01-30 03:32:22 [16271] Setting Frequency: 1431000
2016-01-30 03:32:22 [16271] Frequency: 1431
2016-01-30 03:32:22 [16271] Symbolrate: 27500
2016-01-30 03:32:36 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:32:36 [16271] Enabling High Tone (22khz)
2016-01-30 03:32:37 [16271] DTV CMD currently not handled: 42
2016-01-30 03:32:37 [16271] Setting Frequency: 1431000
2016-01-30 03:32:37 [16271] Frequency: 1431
2016-01-30 03:32:37 [16271] Symbolrate: 27500
2016-01-30 03:32:51 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:32:51 [16271] Enabling High Tone (22khz)
2016-01-30 03:32:51 [16271] DTV CMD currently not handled: 42
2016-01-30 03:32:51 [16271] Setting Frequency: 1431000
2016-01-30 03:32:51 [16271] Frequency: 1431
2016-01-30 03:32:51 [16271] Symbolrate: 27500
2016-01-30 03:33:01 [16271] Shutting down IPTV
2016-01-30 03:33:08 [16271] Starting up IPTV
2016-01-30 03:33:08 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:33:09 [16271] Enabling High Tone (22khz)
2016-01-30 03:33:09 [16271] DTV CMD currently not handled: 42
2016-01-30 03:33:09 [16271] Setting Frequency: 1431000
2016-01-30 03:33:09 [16271] Frequency: 1431
2016-01-30 03:33:09 [16271] Symbolrate: 27500
2016-01-30 03:33:23 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:33:23 [16271] Enabling High Tone (22khz)
2016-01-30 03:33:24 [16271] DTV CMD currently not handled: 42
2016-01-30 03:33:24 [16271] Setting Frequency: 1431000
2016-01-30 03:33:24 [16271] Frequency: 1431
2016-01-30 03:33:24 [16271] Symbolrate: 27500
2016-01-30 03:33:38 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:33:38 [16271] Enabling High Tone (22khz)
2016-01-30 03:33:39 [16271] DTV CMD currently not handled: 42
2016-01-30 03:33:39 [16271] Setting Frequency: 1431000
2016-01-30 03:33:39 [16271] Frequency: 1431
2016-01-30 03:33:39 [16271] Symbolrate: 27500
2016-01-30 03:33:53 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:33:53 [16271] Enabling High Tone (22khz)
2016-01-30 03:33:54 [16271] DTV CMD currently not handled: 42
2016-01-30 03:33:54 [16271] Setting Frequency: 1431000
2016-01-30 03:33:54 [16271] Frequency: 1431
2016-01-30 03:33:54 [16271] Symbolrate: 27500
2016-01-30 03:34:04 [16271] Shutting down IPTV
2016-01-30 03:34:09 [16271] Starting up IPTV
2016-01-30 03:34:09 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:34:09 [16271] Enabling High Tone (22khz)
2016-01-30 03:34:09 [16271] DTV CMD currently not handled: 42
2016-01-30 03:34:09 [16271] Setting Frequency: 1431000
2016-01-30 03:34:09 [16271] Frequency: 1431
2016-01-30 03:34:09 [16271] Symbolrate: 27500
2016-01-30 03:34:23 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:34:24 [16271] Enabling High Tone (22khz)
2016-01-30 03:34:24 [16271] DTV CMD currently not handled: 42
2016-01-30 03:34:24 [16271] Setting Frequency: 1431000
2016-01-30 03:34:24 [16271] Frequency: 1431
2016-01-30 03:34:24 [16271] Symbolrate: 27500
2016-01-30 03:34:38 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:34:38 [16271] Enabling High Tone (22khz)
2016-01-30 03:34:39 [16271] DTV CMD currently not handled: 42
2016-01-30 03:34:39 [16271] Setting Frequency: 1431000
2016-01-30 03:34:39 [16271] Frequency: 1431
2016-01-30 03:34:39 [16271] Symbolrate: 27500
2016-01-30 03:34:53 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:34:53 [16271] Enabling High Tone (22khz)
2016-01-30 03:34:54 [16271] DTV CMD currently not handled: 42
2016-01-30 03:34:54 [16271] Setting Frequency: 1431000
2016-01-30 03:34:54 [16271] Frequency: 1431
2016-01-30 03:34:54 [16271] Symbolrate: 27500
2016-01-30 03:35:04 [16271] Shutting down IPTV
2016-01-30 03:35:11 [16271] Starting up IPTV
2016-01-30 03:35:11 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:35:11 [16271] Enabling High Tone (22khz)
2016-01-30 03:35:12 [16271] DTV CMD currently not handled: 42
2016-01-30 03:35:12 [16271] Setting Frequency: 1431000
2016-01-30 03:35:12 [16271] Frequency: 1431
2016-01-30 03:35:12 [16271] Symbolrate: 27500
2016-01-30 03:35:37 [16271] Shutting down IPTV
2016-01-30 03:35:37 [16271] Starting up IPTV
2016-01-30 03:36:01 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:36:01 [16271] Enabling High Tone (22khz)
2016-01-30 03:36:02 [16271] DTV CMD currently not handled: 42
2016-01-30 03:36:02 [16271] Setting Frequency: 1431000
2016-01-30 03:36:02 [16271] Frequency: 1431
2016-01-30 03:36:02 [16271] Symbolrate: 27500
2016-01-30 03:36:16 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:36:16 [16271] Enabling High Tone (22khz)
2016-01-30 03:36:17 [16271] DTV CMD currently not handled: 42
2016-01-30 03:36:17 [16271] Setting Frequency: 1431000
2016-01-30 03:36:17 [16271] Frequency: 1431
2016-01-30 03:36:17 [16271] Symbolrate: 27500
2016-01-30 03:36:29 [16271] Shutting down IPTV
2016-01-30 03:36:34 [16271] Starting up IPTV
2016-01-30 03:36:34 [16271] Set Voltage Horizontal (18V)
2016-01-30 03:36:34 [16271] Enabling High Tone (22khz)
2016-01-30 03:36:35 [16271] DTV CMD currently not handled: 42
2016-01-30 03:36:35 [16271] Setting Frequency: 1431000
2016-01-30 03:36:35 [16271] Frequency: 1431
2016-01-30 03:36:35 [16271] Symbolrate: 27500
2016-01-30 03:36:45 [16271] Shutting down IPTV
2016-01-30 03:36:45 [16271] Starting up IPTV
2016-01-30 03:37:04 [16271] Shutting down IPTV
2016-01-30 03:37:04 [16271] Starting up IPTV
2016-01-30 04:19:45 [16271] Set Voltage Horizontal (18V)
2016-01-30 04:19:45 [16271] Enabling High Tone (22khz)

Die Verbindung von Sundtek-Netzwerk-Treiber (Client) und Sundtek-Stick (Server) läuft auf derselben Maschine, über eine virtuelle Bridge. Da ist kein Switch dazwischen, aus dem ein putzendes Wesen (in diesem Raum eh nur ich) ein Kabel gezogen haben könnte.  ;)

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #10 am: Januar 30, 2016, 09:48:24 Vormittag »
messages (mit vdr-Meldungen) sagt für den fraglichen Zeitraum:
Jan 30 03:19:09 (MLD) user.err vdr: [12434] ERROR: video data stream broken
Jan 30 03:19:09 (MLD) user.err vdr: [12434] initiating emergency exit
Jan 30 03:19:09 (MLD) user.err vdr: [11538] emergency exit requested - shutting down
Jan 30 03:19:12 (MLD) user.err vdr: [11538] emergency exit!
Jan 30 03:19:13 (MLD) user.err vdr: [12627] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:19:13 (MLD) user.err vdr: [12637] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:19:57 (MLD) user.err vdr: [12663] ERROR: video data stream broken
Jan 30 03:19:57 (MLD) user.err vdr: [12663] initiating emergency exit
Jan 30 03:19:57 (MLD) user.err vdr: [12627] emergency exit requested - shutting down
Jan 30 03:20:04 (MLD) user.err vdr: [12627] ERROR: EPGSearch: searchtimer thread 12635 won't end (waited 6 seconds) - canceling it...
Jan 30 03:20:14 (MLD) user.err vdr: [12627] emergency exit!
Jan 30 03:20:15 (MLD) user.err vdr: [12848] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:20:15 (MLD) user.err vdr: [12858] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:20:57 (MLD) user.err vdr: [12882] ERROR: video data stream broken
Jan 30 03:20:57 (MLD) user.err vdr: [12882] initiating emergency exit
Jan 30 03:20:57 (MLD) user.err vdr: [12848] emergency exit requested - shutting down
Jan 30 03:21:04 (MLD) user.err vdr: [12848] ERROR: EPGSearch: searchtimer thread 12856 won't end (waited 6 seconds) - canceling it...
Jan 30 03:21:16 (MLD) user.err vdr: [12848] emergency exit!
Jan 30 03:21:17 (MLD) user.err vdr: [13066] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:21:17 (MLD) user.err vdr: [13076] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:22:00 (MLD) user.err vdr: [13100] ERROR: video data stream broken
Jan 30 03:22:00 (MLD) user.err vdr: [13100] initiating emergency exit
Jan 30 03:22:00 (MLD) user.err vdr: [13066] emergency exit requested - shutting down
Jan 30 03:22:06 (MLD) user.err vdr: [13066] ERROR: EPGSearch: searchtimer thread 13074 won't end (waited 6 seconds) - canceling it...
Jan 30 03:22:18 (MLD) user.err vdr: [13066] emergency exit!
Jan 30 03:22:19 (MLD) user.err vdr: [13284] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:22:19 (MLD) user.err vdr: [13294] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:23:02 (MLD) user.err vdr: [13318] ERROR: video data stream broken
Jan 30 03:23:02 (MLD) user.err vdr: [13318] initiating emergency exit
Jan 30 03:23:02 (MLD) user.err vdr: [13284] emergency exit requested - shutting down
Jan 30 03:23:08 (MLD) user.err vdr: [13284] ERROR: EPGSearch: searchtimer thread 13292 won't end (waited 6 seconds) - canceling it...
Jan 30 03:23:19 (MLD) user.err vdr: [13284] emergency exit!
Jan 30 03:23:19 (MLD) user.err vdr: [13501] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:23:20 (MLD) user.err vdr: [13511] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:24:02 (MLD) user.err vdr: [13535] ERROR: video data stream broken
Jan 30 03:24:02 (MLD) user.err vdr: [13535] initiating emergency exit
Jan 30 03:24:02 (MLD) user.err vdr: [13501] emergency exit requested - shutting down
Jan 30 03:24:08 (MLD) user.err vdr: [13501] ERROR: EPGSearch: searchtimer thread 13509 won't end (waited 6 seconds) - canceling it...
Jan 30 03:24:20 (MLD) user.err vdr: [13501] emergency exit!
Jan 30 03:24:20 (MLD) user.err vdr: [13719] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:24:20 (MLD) user.err vdr: [13729] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:25:03 (MLD) user.err vdr: [13753] ERROR: video data stream broken
Jan 30 03:25:03 (MLD) user.err vdr: [13753] initiating emergency exit
Jan 30 03:25:03 (MLD) user.err vdr: [13719] emergency exit requested - shutting down
Jan 30 03:25:09 (MLD) user.err vdr: [13719] ERROR: EPGSearch: searchtimer thread 13727 won't end (waited 6 seconds) - canceling it...
Jan 30 03:25:21 (MLD) user.err vdr: [13719] emergency exit!
Jan 30 03:25:22 (MLD) user.err vdr: [13936] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:25:22 (MLD) user.err vdr: [13946] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:26:04 (MLD) user.err vdr: [13970] ERROR: video data stream broken
Jan 30 03:26:04 (MLD) user.err vdr: [13970] initiating emergency exit
Jan 30 03:26:04 (MLD) user.err vdr: [13936] emergency exit requested - shutting down
Jan 30 03:26:11 (MLD) user.err vdr: [13936] ERROR: EPGSearch: searchtimer thread 13944 won't end (waited 6 seconds) - canceling it...
Jan 30 03:26:21 (MLD) user.err vdr: [13936] emergency exit!
Jan 30 03:26:22 (MLD) user.err vdr: [14153] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:26:22 (MLD) user.err vdr: [14163] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:27:04 (MLD) user.err vdr: [14187] ERROR: video data stream broken
Jan 30 03:27:04 (MLD) user.err vdr: [14187] initiating emergency exit
Jan 30 03:27:04 (MLD) user.err vdr: [14153] emergency exit requested - shutting down
Jan 30 03:27:11 (MLD) user.err vdr: [14153] ERROR: EPGSearch: searchtimer thread 14161 won't end (waited 6 seconds) - canceling it...
Jan 30 03:27:22 (MLD) user.err vdr: [14153] emergency exit!
Jan 30 03:27:22 (MLD) user.err vdr: [14370] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:27:23 (MLD) user.err vdr: [14380] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:28:56 (MLD) user.err vdr: [14420] ERROR: video data stream broken
Jan 30 03:28:56 (MLD) user.err vdr: [14420] initiating emergency exit
Jan 30 03:28:56 (MLD) user.err vdr: [14370] emergency exit requested - shutting down
Jan 30 03:29:00 (MLD) user.err vdr: [14370] emergency exit!
Jan 30 03:29:01 (MLD) user.err vdr: [14612] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:29:01 (MLD) user.err vdr: [14622] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:29:43 (MLD) user.err vdr: [14646] ERROR: video data stream broken
Jan 30 03:29:43 (MLD) user.err vdr: [14646] initiating emergency exit
Jan 30 03:29:43 (MLD) user.err vdr: [14612] emergency exit requested - shutting down
Jan 30 03:29:50 (MLD) user.err vdr: [14612] ERROR: EPGSearch: searchtimer thread 14620 won't end (waited 6 seconds) - canceling it...
Jan 30 03:30:01 (MLD) user.err vdr: [14612] emergency exit!
Jan 30 03:30:02 (MLD) user.err vdr: [14830] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:30:02 (MLD) user.err vdr: [14840] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:30:44 (MLD) user.err vdr: [14864] ERROR: video data stream broken
Jan 30 03:30:44 (MLD) user.err vdr: [14864] initiating emergency exit
Jan 30 03:30:45 (MLD) user.err vdr: [14830] emergency exit requested - shutting down
Jan 30 03:30:51 (MLD) user.err vdr: [14830] ERROR: EPGSearch: searchtimer thread 14838 won't end (waited 6 seconds) - canceling it...
Jan 30 03:31:01 (MLD) user.err vdr: [14830] emergency exit!
Jan 30 03:31:03 (MLD) user.err vdr: [15047] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:31:03 (MLD) user.err vdr: [15057] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:31:45 (MLD) user.err vdr: [15081] ERROR: video data stream broken
Jan 30 03:31:45 (MLD) user.err vdr: [15081] initiating emergency exit
Jan 30 03:31:45 (MLD) user.err vdr: [15047] emergency exit requested - shutting down
Jan 30 03:31:52 (MLD) user.err vdr: [15047] ERROR: EPGSearch: searchtimer thread 15055 won't end (waited 6 seconds) - canceling it...
Jan 30 03:32:03 (MLD) user.err vdr: [15047] emergency exit!
Jan 30 03:32:05 (MLD) user.err vdr: [15264] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:32:05 (MLD) user.err vdr: [15274] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:32:47 (MLD) user.err vdr: [15298] ERROR: video data stream broken
Jan 30 03:32:47 (MLD) user.err vdr: [15298] initiating emergency exit
Jan 30 03:32:47 (MLD) user.err vdr: [15264] emergency exit requested - shutting down
Jan 30 03:32:54 (MLD) user.err vdr: [15264] ERROR: EPGSearch: searchtimer thread 15272 won't end (waited 6 seconds) - canceling it...
Jan 30 03:33:05 (MLD) user.err vdr: [15264] emergency exit!
Jan 30 03:33:06 (MLD) user.err vdr: [15481] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:33:07 (MLD) user.err vdr: [15491] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:33:49 (MLD) user.err vdr: [15515] ERROR: video data stream broken
Jan 30 03:33:49 (MLD) user.err vdr: [15515] initiating emergency exit
Jan 30 03:33:49 (MLD) user.err vdr: [15481] emergency exit requested - shutting down
Jan 30 03:33:56 (MLD) user.err vdr: [15481] ERROR: EPGSearch: searchtimer thread 15489 won't end (waited 6 seconds) - canceling it...
Jan 30 03:34:06 (MLD) user.err vdr: [15481] emergency exit!
Jan 30 03:34:07 (MLD) user.err vdr: [15698] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:34:07 (MLD) user.err vdr: [15708] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:34:49 (MLD) user.err vdr: [15732] ERROR: video data stream broken
Jan 30 03:34:49 (MLD) user.err vdr: [15732] initiating emergency exit
Jan 30 03:34:49 (MLD) user.err vdr: [15698] emergency exit requested - shutting down
Jan 30 03:34:56 (MLD) user.err vdr: [15698] ERROR: EPGSearch: searchtimer thread 15706 won't end (waited 6 seconds) - canceling it...
Jan 30 03:35:08 (MLD) user.err vdr: [15698] emergency exit!
Jan 30 03:35:09 (MLD) user.err vdr: [15916] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:35:09 (MLD) user.err vdr: [15926] [live] INFO: attempt to listen on ip = '0.0.0.0'
Jan 30 03:36:28 (MLD) user.err vdr: [15965] ERROR: video data stream broken
Jan 30 03:36:28 (MLD) user.err vdr: [15965] initiating emergency exit
Jan 30 03:36:29 (MLD) user.err vdr: [15916] emergency exit requested - shutting down
Jan 30 03:36:32 (MLD) user.err vdr: [15916] emergency exit!
Jan 30 03:36:32 (MLD) user.err vdr: [16159] [live] INFO: validating server ip '0.0.0.0'
Jan 30 03:36:33 (MLD) user.err vdr: [16169] [live] INFO: attempt to listen on ip = '0.0.0.0'

D.h. vdr hatte wohl "nur" zwischen 03:19 und 03:36 Probleme mit den TV-Daten.

Mir fehlen die Ideen, was ich probieren könnte, weil aus dem Log für mich nicht ersichtlich ist, was bis 03:37 eine stabile Verbindung verhindert hat. Ich weiß z.B. schon nicht, ob die Probleme zwischen Sundtek-Client und Sundtek-Server oder Sundtek-Server und dem Stick liegen (oder es einfach DVB-S2 Empfangsprobleme sind). Da vdr "weniger" Probleme (zeitlich kürzer) im Log meldet, scheint das Zusammenspiel zwischen vdr und dem Sundtek-Treiber gut zu klappen.

 ???

Sundtek

  • Administrator
  • Hero Member
  • *****
  • Beiträge: 8512
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #11 am: Januar 30, 2016, 12:04:30 Nachmittag »
Transport error indicator is set, clearing buffers

das sind Signalprobleme, lass mal folgenden Befehl mitlaufen:
while :; do date; /opt/bin/mediaclient --readsignal=60 -d /dev/dvb/adapter0/frontend0 --band universal; done

es handelt sich in Deinem Fall um diese Frequenz:
/opt/bin/mediaclient -m DVBS2 -f 12032000 -M QPSK -S 27500000 -E 3/4 -V H

Bei uns sieht das in etwa so aus:
Zitat
SIGNAL: [.............................    ] ( 89%)  BER:      0 FREQ: 12032000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_3_4 MOD: QPSK VOLTAGE: H(18V) TONE: ON
SIGNAL: [.............................    ] ( 89%)  BER:      0 FREQ: 12032000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_3_4 MOD: QPSK VOLTAGE: H(18V) TONE: ON
SIGNAL: [.............................    ] ( 89%)  BER:      0 FREQ: 12032000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_3_4 MOD: QPSK VOLTAGE: H(18V) TONE: ON
SIGNAL: [.............................    ] ( 89%)  BER:      0 FREQ: 12032000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_3_4 MOD: QPSK VOLTAGE: H(18V) TONE: ON
SIGNAL: [.............................    ] ( 89%)  BER:      0 FREQ: 12032000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_3_4 MOD: QPSK VOLTAGE: H(18V) TONE: ON
SIGNAL: [.............................    ] ( 89%)  BER:      0 FREQ: 12032000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_3_4 MOD: QPSK VOLTAGE: H(18V) TONE: ON
SIGNAL: [.............................    ] ( 89%)  BER:      0 FREQ: 12032000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_3_4 MOD: QPSK VOLTAGE: H(18V) TONE: ON

Daneben noch auf dem Server:
Zitat
/opt/bin/mediaclient --cat /dev/dvb/adapter0/dvr0 | /opt/bin/mediaclient --pipecount=1000
[2016-01-30 11:23:46]    5652480 bytes |    5520.00 kb |       5.39 mb transferred
[2016-01-30 11:23:47]    5652480 bytes |    5520.00 kb |       5.39 mb transferred
[2016-01-30 11:23:48]    5672960 bytes |    5540.00 kb |       5.41 mb transferred
[2016-01-30 11:23:49]    5662720 bytes |    5530.00 kb |       5.40 mb transferred
[2016-01-30 11:23:50]    5642240 bytes |    5510.00 kb |       5.38 mb transferred
[2016-01-30 11:23:51]    5683200 bytes |    5550.00 kb |       5.42 mb transferred

bei uns greift keine andere Applikation auf den Tuner zu, versuch mal das einfachste, einfach nur den Sender so laufen zu lassen

/opt/bin/mediaclient --lc (kannst du zum Überprüfen ob eine andere Applikation mitläuft ausführen)

Ein perfektes Signal würde 91% entsprechen laut der aktuellen Skala.
« Letzte Änderung: Januar 30, 2016, 12:29:02 Nachmittag von Sundtek »
Failure is a good thing! I'll fix it

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #12 am: Januar 30, 2016, 01:10:39 Nachmittag »
Ok, das führt weiter:

SIGNAL: [..................               ] ( 56%)  BER:      0 FREQ: 12031000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_9_10 MOD: QPSK VOLTAGE: V(13V) TONE: ON
SIGNAL: [..................               ] ( 56%)  BER:      0 FREQ: 12031000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_9_10 MOD: QPSK VOLTAGE: V(13V) TONE: ON
SIGNAL: [..................               ] ( 56%)  BER:      0 FREQ: 12031000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_9_10 MOD: QPSK VOLTAGE: V(13V) TONE: ON
SIGNAL: [..................               ] ( 56%)  BER:      0 FREQ: 12031000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_9_10 MOD: QPSK VOLTAGE: V(13V) TONE: ON
SIGNAL: [..................               ] ( 56%)  BER:      0 FREQ: 12031000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_9_10 MOD: QPSK VOLTAGE: V(13V) TONE: ON
SIGNAL: [..................               ] ( 56%)  BER:      0 FREQ: 12031000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_9_10 MOD: QPSK VOLTAGE: V(13V) TONE: ON
SIGNAL: [..................               ] ( 56%)  BER:      0 FREQ: 12031000   Hz LOCKED: YES SYS: DVB-S2 SYM: 27500000 FEC: FEC_9_10 MOD: QPSK VOLTAGE: V(13V) TONE: ON

Server (dokumentiert, warum die Aufnahme nicht startet:)
[2016-01-30 12:02:34]          0 bytes |       0.00 kb |       0.00 mb transferred
[2016-01-30 12:02:35]          0 bytes |       0.00 kb |       0.00 mb transferred
[2016-01-30 12:02:36]          0 bytes |       0.00 kb |       0.00 mb transferred
[2016-01-30 12:02:37]          0 bytes |       0.00 kb |       0.00 mb transferred
[2016-01-30 12:02:38]          0 bytes |       0.00 kb |       0.00 mb transferred
[2016-01-30 12:02:39]          0 bytes |       0.00 kb |       0.00 mb transferred
[2016-01-30 12:02:40]          0 bytes |       0.00 kb |       0.00 mb transferred
[2016-01-30 12:02:41]          0 bytes |       0.00 kb |       0.00 mb transferred
[2016-01-30 12:02:42]          0 bytes |       0.00 kb |       0.00 mb transferred

# /opt/bin/mediaclient --lc
**** List of Media Clients ****
/dev/dvb/adapter1/frontend0:
  16271 ... mediasrv
/dev/dvb/adapter1/dvr0:
  16271 ... mediasrv
/dev/dvb/adapter1/demux0:
  16271 ... mediasrv (0012)
  16271 ... mediasrv (0014)
  16271 ... mediasrv (0000)
  16271 ... mediasrv (0011)
  16271 ... mediasrv (0010)
  16271 ... mediasrv (006a)
/dev/video0:
  No client connected
/dev/vbi0:
  No client connected
/dev/mediainput0:
  No client connected
/dev/dsp0:
  No client connected

... Was wohl heißt, dass sonst niemand zugreift.

Also ja: Signal scheint schwach zu sein.

Bevor ich mit Hilfe des Suntek Sticks nun versucht habe, den vdr in die VM zu verlagern, lief er auf dem Host ("jahrelang") mit einer Tevii S471. Problemlos. Alle Aufnahmen starteten sofort, Bildstörungen oder Datenfehler nur bei den dafür bekannten (Un-)Wetterlagen.

Sundtek

  • Administrator
  • Hero Member
  • *****
  • Beiträge: 8512
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #13 am: Januar 30, 2016, 01:17:30 Nachmittag »
Hmm also das kann der LNB sein oder die Verkabelung, ein Signal sollte nicht unter 80% liegen ansonsten ist die Anlage wirklich nicht in Ordnung.

Die Signalstärke sollte ähnlich (eher besser) sein wie bei der Tevii S471. Du kannst ja auch mal versuchen dich mit dem Kabel rumzuspielen eventuell liegt dort ja irgendwo dein Problem.

Zitat
  16271 ... mediasrv (0012)
  16271 ... mediasrv (0014)
  16271 ... mediasrv (0000)
  16271 ... mediasrv (0011)
  16271 ... mediasrv (0010)
  16271 ... mediasrv (006a)

dort sind Filter gesetzt, sobald unter demux etwas steht wird der Transponder gefiltert und es werden nicht mehr alle Daten gezählt. Es darf keine Demux Schnittstelle geöffnet sein, in dem Fall greifst du auf der anderen Seite mittels VDR auf den Tuner zu und das sorgt dafür das gefiltert wird.
« Letzte Änderung: Januar 30, 2016, 01:22:28 Nachmittag von Sundtek »
Failure is a good thing! I'll fix it

TV_Server

  • Newbie
  • *
  • Beiträge: 9
    • Profil anzeigen
Re:mutex lock timed out after 5 seconds released
« Antwort #14 am: Januar 30, 2016, 02:02:29 Nachmittag »
Die Signalstärke sollte ähnlich (eher besser) sein wie bei der Tevii S471. Du kannst ja auch mal versuchen dich mit dem Kabel rumzuspielen eventuell liegt dort ja irgendwo dein Problem.

Kabel dreimal ab und dran. Signalstärke ist durch Abziehen verlässlich auf 0 zu bringen, aber nicht auf über 56%.

Ich habe mal das Kabel an die Tevii gesteckt und femon laufen lassen.

status SCVYL | signal dea8 | snr a64f | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal dea8 | snr a64f | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal dea8 | snr a64f | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal dea8 | snr a64f | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal dea8 | snr a64f | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal dea8 | snr a64f | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal dea8 | snr a64f | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal dea8 | snr a64f | ber 00000000 | unc 00000000 | FE_HAS_LOCK

Ist das jetzt besser? Lt. Doku kommt es wohl vor allem auf die bit error rate an. Mit 0000000 kann ich da gut leben.  8)

Zitat
  16271 ... mediasrv (0012)
  16271 ... mediasrv (0014)
  16271 ... mediasrv (0000)
  16271 ... mediasrv (0011)
  16271 ... mediasrv (0010)
  16271 ... mediasrv (006a)

dort sind Filter gesetzt, sobald unter demux etwas steht wird der Transponder gefiltert und es werden nicht mehr alle Daten gezählt. Es darf keine Demux Schnittstelle geöffnet sein, in dem Fall greifst du auf der anderen Seite mittels VDR auf den Tuner zu und das sorgt dafür das gefiltert wird.

Sorry, so ganz verstehe ich das nicht. Die kommen alle von vdr. Wenn ich im Client vdr stoppe, sind die weg:

# mediaclient --lc
**** List of Media Clients ****
/dev/dvb/adapter1/frontend0:
  No client connected
/dev/dvb/adapter1/dvr0:
  No client connected
/dev/dvb/adapter1/demux0:
  No client connected
/dev/video0:
  No client connected
/dev/vbi0:
  No client connected
/dev/mediainput0:
  No client connected
/dev/dsp0:
  No client connected

Starte ich vdr wieder, sind sie wieder da:

# mediaclient --lc
**** List of Media Clients ****
/dev/dvb/adapter1/frontend0:
  16271 ... mediasrv
/dev/dvb/adapter1/dvr0:
  16271 ... mediasrv
/dev/dvb/adapter1/demux0:
  16271 ... mediasrv (0dff)
  16271 ... mediasrv (0e00)
  16271 ... mediasrv (0e01)
/dev/video0:
  No client connected
/dev/vbi0:
  No client connected
/dev/mediainput0:
  No client connected
/dev/dsp0:
  No client connected

Heißt das, ich muss an irgendwas im vdr schrauben?