TVServer stürtz regelmäßig ab (1 Viewer)

[frankteb]

Hi,

nachdem ich ein anderes MP-Problem gelöst habe, taucht plötzlich das nächste auf, nachdem ich die letzten Jahre eigentlich kaum Probleme hatte.

Die TVServer stürtzt regelmäßig ab. Kann ihn unter Manual Control über Start Service auch leicht wieder starten, was allerdings nervig ist, vor allen Dingen wenn er nachts eigentlich was aufnehmen soll.

In der TVService Error.log Datei bekomme ich nur folgendes angezeigt.

[2015-02-19 05:05:42,111] [Log ] [19 ] [ERROR] - card: unable to connect to slave controller at:TVSERVER-PC

In der TVService.log-Datei

[2015-02-18 23:55:12,535] [Log ] [13 ] [DEBUG] - Detect BDA source devices
[2015-02-19 05:05:42,048] [Log ] [14 ] [INFO ] - Tvservice stopped due to an unhandled app domain exception System.NullReferenceException: Object reference not set to an instance of an object.
at MySql.Data.MySqlClient.MySqlCommand.TimeoutExpired(Object commandObject)
at System.Threading.TimerQueueTimer.CallCallbackInContext(Object state)
at System.Threading.ExecutionContext.RunInternal(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)
at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state, Boolean preserveSyncCtx)
at System.Threading.TimerQueueTimer.CallCallback()
at System.Threading.TimerQueueTimer.Fire()
at System.Threading.TimerQueue.FireNextTimers()
at System.Threading.TimerQueue.AppDomainTimerCallback()
[2015-02-19 05:05:42,048] [Log ] [TVService] [INFO ] - TV Service is being stopped
[2015-02-19 05:05:42,048] [Log ] [TVService] [INFO ] - TV Service: stopping
[2015-02-19 05:05:42,064] [Log ] [TVService] [INFO ] - TV Service: Stop plugins
[2015-02-19 05:05:42,064] [Log ] [TVService] [DEBUG] - TVMovie: background import timer stopped
[2015-02-19 05:05:42,064] [Log ] [TVService] [INFO ] - TV Service: Plugins stopped
[2015-02-19 05:05:42,080] [Log ] [TVService] [INFO ] - TV service StopRemoting
[2015-02-19 05:05:42,080] [Log ] [TVService] [INFO ] - Remoting stopped
[2015-02-19 05:05:42,080] [Log ] [TVService] [INFO ] - Controller: DeInit.
[2015-02-19 05:05:42,095] [Log ] [TVService] [INFO ] - Controller: stop streamer...
[2015-02-19 05:05:42,095] [Log ] [TVService] [INFO ] - RTSP: stop streamer
[2015-02-19 05:05:42,111] [Log ] [19 ] [ERROR] - card: unable to connect to slave controller at:TVSERVER-PC
[2015-02-19 05:05:45,309] [Log ] [TVService] [INFO ] - RTSP: stop all streams (0)
[2015-02-19 05:05:45,309] [Log ] [18 ] [DEBUG] - Detecting BDA/WDM devices...
[2015-02-19 05:05:45,324] [Log ] [PowerEventThread] [DEBUG] - Scheduler: thread stopped.
[2015-02-19 05:05:45,324] [Log ] [TVService] [INFO ] - Controller: streamer stopped...
[2015-02-19 05:05:45,324] [Log ] [18 ] [DEBUG] - Detect legacy AM filter devices
[2015-02-19 05:05:45,324] [Log ] [TVService] [INFO ] - Controller: stop thumb processor...
[2015-02-19 05:05:45,340] [Log ] [PowerEventThread] [INFO ] - Stopcard
[2015-02-19 05:05:45,340] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph called
[2015-02-19 05:05:45,340] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-02-19 05:05:45,340] [Log ] [PowerEventThread] [INFO ] - mdplug: FreeAllChannels
[2015-02-19 05:05:45,340] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph filterstate already stopped, returning.
[2015-02-19 05:05:45,340] [Log ] [TVService] [INFO ] - ThumbProcessor.Stop()
[2015-02-19 05:05:45,356] [Log ] [TVService] [INFO ] - Controller: thumb processor stopped...
[2015-02-19 05:05:45,356] [Log ] [TVService] [INFO ] - Controller: stop scheduler...
[2015-02-19 05:05:45,356] [Log ] [TVService] [INFO ] - Scheduler: stopped
[2015-02-19 05:05:45,356] [Log ] [PowerEventThread] [INFO ] - Stopcard
[2015-02-19 05:05:45,356] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph called
[2015-02-19 05:05:45,356] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-02-19 05:05:45,356] [Log ] [PowerEventThread] [INFO ] - mdplug: FreeAllChannels
[2015-02-19 05:05:45,356] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph filterstate already stopped, returning.
[2015-02-19 05:05:45,356] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread received WM_POWERBROADCAST 18
[2015-02-19 05:05:45,371] [Log ] [PowerEventThread] [DEBUG] - OnPowerEvent: PowerStatus: ResumeAutomatic
[2015-02-19 05:05:45,371] [Log ] [PowerEventThread] [DEBUG] - OnPowerEventHandler: PowerStatus: ResumeAutomatic
[2015-02-19 05:05:45,371] [Log ] [PowerEventThread] [INFO ] - TvController.OnResume()
[2015-02-19 05:05:45,371] [Log ] [PowerEventThread] [INFO ] - Controller: setup HeartBeat Monitor
[2015-02-19 05:05:45,371] [Log ] [HeartBeatMonitor] [INFO ] - Controller: Heartbeat Monitor initiated, max timeout allowed is 30 sec.
[2015-02-19 05:05:45,371] [Log ] [PowerEventThread] [INFO ] - Scheduler: started
[2015-02-19 05:05:45,387] [Log ] [18 ] [DEBUG] - Detect AM KS crossbar devices
[2015-02-19 05:05:45,387] [Log ] [18 ] [DEBUG] - Detect AM KS TV tuner devices
[2015-02-19 05:05:45,387] [Log ] [18 ] [DEBUG] - Detect BDA source devices
[2015-02-19 05:05:45,418] [Log ] [PowerEventThread] [INFO ] - Scheduler: loaded 44 schedules
[2015-02-19 05:05:45,418] [Log ] [PowerEventThread] [DEBUG] - Scheduler: thread started.
[2015-02-19 05:05:45,558] [Log ] [PowerEventThread] [INFO ] - DiskManagement: started
[2015-02-19 05:05:45,558] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread received WM_POWERBROADCAST 7
[2015-02-19 05:05:45,558] [Log ] [PowerEventThread] [DEBUG] - OnPowerEvent: PowerStatus: ResumeSuspend
[2015-02-19 05:05:45,558] [Log ] [PowerEventThread] [DEBUG] - OnPowerEventHandler: PowerStatus: ResumeSuspend
[2015-02-19 05:05:45,558] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread 30
[2015-02-19 05:05:45,558] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread 30
[2015-02-19 05:05:45,621] [Log ] [TV Movie importer] [DEBUG] - TVMovie: Last update was at 17.02.2015 01:50:19 - new import scheduled
[2015-02-19 05:05:45,668] [Log ] [scheduler thread] [DEBUG] - DeleteAllCancelledSeries: Removing 14.02.2015 01:05:00
[2015-02-19 05:05:45,886] [Log ] [TVService] [INFO ] - Controller: scheduler stopped...
[2015-02-19 05:05:45,886] [Log ] [TVService] [INFO ] - Controller: epg stop
[2015-02-19 05:05:45,902] [Log ] [TVService] [INFO ] - Controller: dispose card:RadioWebStream Card (builtin)
[2015-02-19 05:05:45,902] [Log ] [TVService] [INFO ] - RadioWebStreamispose()
[2015-02-19 05:05:45,902] [Log ] [TVService] [INFO ] - Controller: dispose card:MediaPortal IPTV Source Filter
[2015-02-19 05:05:45,917] [Log ] [TVService] [INFO ] - Controller: dispose cardigital Devices DVB-S/S2 Tuner 1
[2015-02-19 05:05:45,917] [Log ] [TVService] [INFO ] - dvbecompose
[2015-02-19 05:05:45,917] [Log ] [TVService] [INFO ] - tvcard:FreeAllSubChannels
[2015-02-19 05:05:45,933] [Log ] [TVService] [INFO ] - stop
[2015-02-19 05:05:45,948] [Log ] [scheduler thread] [DEBUG] - DeleteAllCancelledSeries: Removing 14.02.2015 03:15:00
[2015-02-19 05:05:46,042] [Log ] [TVService] [INFO ] - Closing MDAPI Plugins
[2015-02-19 05:05:46,042] [Log ] [TVService] [DEBUG] - Closing MDAPI plugin instance : Digital Devices Digital Video Capture 10
[2015-02-19 05:05:46,058] [Log ] [TVService] [INFO ] - Disposing ConditionalAccess
[2015-02-19 05:05:46,058] [Log ] [TVService] [INFO ] - free...
[2015-02-19 05:05:46,058] [Log ] [TVService] [INFO ] - free pins...
[2015-02-19 05:05:46,104] [Log ] [TVService] [INFO ] - free graph...
[2015-02-19 05:05:46,120] [Log ] [TVService] [INFO ] - Remove filter from graph: BDA MPEG2 Transport Information Filter
[2015-02-19 05:05:46,120] [Log ] [TVService] [INFO ] - Remove filter from graph: MediaPortal Ts Analyzer
[2015-02-19 05:05:46,151] [Log ] [TVService] [INFO ] - Remove filter from graph: MPEG2-Demultiplexer
[2015-02-19 05:05:46,151] [Log ] [TVService] [INFO ] - Remove filter from graph: Inf Tee
[2015-02-19 05:05:46,151] [Log ] [TVService] [INFO ] - Remove filter from graph: mdapifilter0
[2015-02-19 05:05:46,198] [Log ] [TVService] [INFO ] - Remove filter from graph: Inf Tee Second
[2015-02-19 05:05:46,214] [Log ] [TVService] [INFO ] - Remove filter from graph: Digital Devices Digital Video Capture 1
[2015-02-19 05:05:46,214] [Log ] [TVService] [INFO ] - Remove filter from graph: Digital Devices DVB-S/S2 Tuner 1
[2015-02-19 05:05:46,214] [Log ] [TVService] [INFO ] - Remove filter from graph: Generic Network Provider
[2015-02-19 05:05:46,229] [Log ] [TVService] [INFO ] - free devices...
[2015-02-19 05:05:46,229] [Log ] [TVService] [INFO ] - decompose done...
[2015-02-19 05:05:46,229] [Log ] [TVService] [INFO ] - Controller: dispose cardigital Devices DVB-S/S2 Tuner 2
[2015-02-19 05:05:46,245] [Log ] [TVService] [INFO ] - dvbecompose
[2015-02-19 05:05:46,245] [Log ] [TVService] [INFO ] - tvcard:FreeAllSubChannels
[2015-02-19 05:05:46,245] [Log ] [TVService] [INFO ] - stop
[2015-02-19 05:05:46,260] [Log ] [scheduler thread] [DEBUG] - DeleteAllCancelledSeries: Removing 14.02.2015 03:35:00
[2015-02-19 05:05:46,370] [Log ] [TVService] [INFO ] - Closing MDAPI Plugins
[2015-02-19 05:05:46,370] [Log ] [TVService] [DEBUG] - Closing MDAPI plugin instance : Digital Devices Digital Video Capture 20
[2015-02-19 05:05:46,370] [Log ] [TVService] [INFO ] - Disposing ConditionalAccess
[2015-02-19 05:05:46,370] [Log ] [TVService] [INFO ] - free...
[2015-02-19 05:05:46,370] [Log ] [TVService] [INFO ] - free pins...
[2015-02-19 05:05:46,385] [Log ] [TVService] [INFO ] - free graph...
[2015-02-19 05:05:46,385] [Log ] [TVService] [INFO ] - Remove filter from graph: BDA MPEG2 Transport Information Filter
[2015-02-19 05:05:46,385] [Log ] [TVService] [INFO ] - Remove filter from graph: MediaPortal Ts Analyzer
[2015-02-19 05:05:46,416] [Log ] [TVService] [INFO ] - Remove filter from graph: MPEG2-Demultiplexer
[2015-02-19 05:05:46,416] [Log ] [TVService] [INFO ] - Remove filter from graph: Inf Tee
[2015-02-19 05:05:46,416] [Log ] [TVService] [INFO ] - Remove filter from graph: mdapifilter0
[2015-02-19 05:05:46,494] [Log ] [TVService] [INFO ] - Remove filter from graph: Inf Tee Second
[2015-02-19 05:05:46,510] [Log ] [TVService] [INFO ] - Remove filter from graph: Digital Devices Digital Video Capture 2
[2015-02-19 05:05:46,510] [Log ] [TVService] [INFO ] - Remove filter from graph: Digital Devices DVB-S/S2 Tuner 2
[2015-02-19 05:05:46,510] [Log ] [TVService] [INFO ] - Remove filter from graph: Generic Network Provider
[2015-02-19 05:05:46,526] [Log ] [TVService] [INFO ] - free devices...
[2015-02-19 05:05:46,526] [Log ] [TVService] [INFO ] - decompose done...
[2015-02-19 05:05:46,526] [Log ] [TVService] [INFO ] - Stopping async device detection...
[2015-02-19 05:05:46,572] [Log ] [TVService] [INFO ] - Stopping async device detection...
[2015-02-19 05:05:46,572] [Log ] [TVService] [INFO ] - RadioWebStreamispose()
[2015-02-19 05:05:46,588] [Log ] [TVService] [INFO ] - Remove filter from graph: DVB-T Network Provider
[2015-02-19 05:05:46,635] [Log ] [TVService] [INFO ] - Remove filter from graph: DVB-S Network Provider
[2015-02-19 05:05:46,635] [Log ] [TVService] [INFO ] - Remove filter from graph: DVB-C Network Provider
[2015-02-19 05:05:46,635] [Log ] [TVService] [INFO ] - Remove filter from graph: ATSC Network Provider
[2015-02-19 05:05:46,650] [Log ] [TVService] [DEBUG] - TV Service: OnStop asking PowerEventThread to exit
[2015-02-19 05:05:46,650] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread finished
[2015-02-19 05:05:46,650] [Log ] [TVService] [INFO ] - TV Service: stopped

Habe den TVService um 5.21 dann manuell gestartet und um 8.25 stürtzt er wieder mit folgender Meldung ab (im TVService-Error.log erscheint dazu aber keine Meldung)

[2015-02-19 05:21:52,846] [Log ] [18 ] [DEBUG] - Detect BDA source devices
[2015-02-19 08:25:21,222] [Log ] [22 ] [DEBUG] - Detecting BDA/WDM devices...
[2015-02-19 08:25:23,905] [Log ] [22 ] [DEBUG] - Detect legacy AM filter devices
[2015-02-19 08:25:23,952] [Log ] [scheduler thread] [INFO ] - scheduler: SchedulerWorker inner exception Error: StatementErrorUnclassified
Gentle.Common.GentleException: select idProgram, idChannel, startTime, endTime, title, description, genre, state, originalAirDate, seriesNum, episodeNum, episodePart, episodeName, starRating, classification, parentalRating from Program where title = ?title and startTime <= ?startTime and endTime > ?endTime; ---> MySql.Data.MySqlClient.MySqlException: Timeout expired. The timeout period elapsed prior to completion of the operation or the server is not responding.
at MySql.Data.MySqlClient.MySqlDataReader.NextResult()
at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)
at MySql.Data.MySqlClient.MySqlCommand.ExecuteDbDataReader(CommandBehavior behavior)
at System.Data.Common.DbCommand.System.Data.IDbCommand.ExecuteReader()
at Gentle.Framework.SqlStatement.Execute(IDbConnection conn, IDbTransaction tr)
--- End of inner exception stack trace ---
at Gentle.Framework.SqlStatement.Execute(IDbConnection conn, IDbTransaction tr)
at Gentle.Framework.PersistenceBroker.Execute(SqlStatement stmt, IDbConnection dbConnection, IDbTransaction dbTransaction)
at TvDatabase.Program.RetrieveCurrentRunningByTitle(String title, Int32 preRec, Int32 postRec)
at TvService.Scheduler.IsTimeToRecordEveryTimeOnEveryChannel(Schedule schedule)
at TvService.Scheduler.IsTimeToRecord(Schedule schedule, DateTime currentTime, RecordingDetail& newRecording)
at TvService.Scheduler.StartAnyDueRecordings()
at TvService.Scheduler.DoScheduleWork()
at TvService.Scheduler.SchedulerWorker()MySql.Data.MySqlClient.MySqlException (0x80004005): Timeout expired. The timeout period elapsed prior to completion of the operation or the server is not responding.
at MySql.Data.MySqlClient.MySqlDataReader.NextResult()
at MySql.Data.MySqlClient.MySqlCommand.ExecuteReader(CommandBehavior behavior)
at MySql.Data.MySqlClient.MySqlCommand.ExecuteDbDataReader(CommandBehavior behavior)
at System.Data.Common.DbCommand.System.Data.IDbCommand.ExecuteReader()
at Gentle.Framework.SqlStatement.Execute(IDbConnection conn, IDbTransaction tr)
[2015-02-19 08:25:23,952] [Log ] [PowerEventThread] [DEBUG] - Scheduler: thread stopped.

Er erwacht jeweils ordnungsgemäß für eine Aufnahme auf und stürtzt dann ab.

Vielleicht kann mir ja einer helfen.

 

[HTPCSourcer]

Bitte vollständige Logs posten. Watchdog, dritte Option, und bitte den Zeitpunkt/Datum des Crash angeben.

 

[frankteb]

OK. Dann anbei mal die kompletten Logs.

1. 2015-02-19 05:05:42,111
2. 2015-02-19 05:05:42,048
3. 2015-02-19 08:25:23,952

 

[Vasilich]

[2015-02-19 05:05:42,048] [Log ] [14 ] [INFO ] - Tvservice stopped due to an unhandled app domain exception System.NullReferenceException: Object reference not set to an instance of an object.
at MySql.Data.MySqlClient.MySqlCommand.TimeoutExpired(Object commandObject)

und was sagt Windows error log? Läuft zur Zeit MySQL schon?

[2015-02-19 05:05:42,111] [Log ] [19 ] [ERROR] - card: unable to connect to slave controller at:TVSERVER-PC

was steht in TVServer IP? hat TV Server eine statische IP? ist die Name TVSERVER-PC gültig?

Edit the contents of the MySQL database Servers table to match your current hostname/IP address settings.

 

[frankteb]

und was sagt Windows error log? Läuft zur Zeit MySQL schon?

wo finde ich das windows error log?
Habe MySQL 5.6 laufen, oder meinst du ob die Datenbank zu dem Zeitpunkt nach dem Standby schon läuft?

was steht in TVServer IP? hat TV Server eine statische IP? ist die Name TVSERVER-PC gültig?

Die TVServer IP steht auf auto in der TVServer Konfiguration. Bekommt aber immer die gleiche "feste" IP zugewiesen. Der Name ist TVSERVER-PC. Das System läuft ja auch ansonsten einwandfrei, wenn nur nicht diese gelegentlichen Abstürze wären.

Die Abstürze sind zum einen, wenn er aus dem Standy erwacht und aufnehmen soll. Habe Standby auch mal deaktiviert, der TVServer stürzt dann trotzdem Nachts bei vor manchen Aufnahmen ab.

Zum anderen habe ich gelegentlich Abstürze, wenn ich am Client den Kanal wechsel. Der Fehler ist in den geposteten Logs nicht enthalten, da er nur selten Auftritt.


Für mich als Laie sieht das nach den Logs so aus, als wenn das mit den MySQL Datenbanken zusammenhängt. Gibt es da eine Möglichkeit mal die Datenbanken zu prüfen?

 

[HTPCSourcer]

@frankteb ,

ich hatte vorhin nur mal kurz ins Log geschaut:

• Was sehr schnell auffällt ist, dass Clickfinder nicht richtig funktioniert. Das hast du anscheinend falsch konfiguriert, im Eventlog finden sich dazu Fehler wie auch im TVServer-Log. Das Update wird allein vom TVServer (automatisch) angestoßen und braucht/soll nicht vom TVMovie-Zeitplaner gesteuert werden. In der dortigen Einrichtung also einen eventuell vorhanden Autostart in den Einstellungen von Clickfinder wieder entfernen. Weitere Fehler entstehen durch den Episodenscanner, der scheinbar unmotiviert ins Leere rennt.
• Die von dir weiter oben erwähnten Fehler 1-3 sind nicht immer dieselben. Bei 3 passiert etwas anderes als bei 1 und 2.
• Im Eventlog kann man oft sehen, dass der Aufruf der TVServer Config den Fehler "Dienst konnte nicht angehalten werden" abbricht

Da liegt einiges im Argen und die Frage ist, ob hier eine tiefschürfende Fehlersuche lohnt. Oft ist es einfacher, einfach alles (!) - einschließlich MySQL und Datenbank - zu löschen und einfach noch einmal neu zu installieren.

 

[frankteb]

Danke für eure Hilfe. Das mit dem neu installieren muss ich mir noch überlegen. Das System ist gerade mal 6 Wochen alt und es dauert immer solange bis unter Mediaportal alles wieder eingerichtet ist.

Oder kann ich mit dem Backup-Plugin arbeiten, oder würde ich die Fehler/Probleme mir damit wieder importieren?

 

[HTPCSourcer]

Ich würde mich nicht auf Backup verlassen, da nicht klar ist, wo das Problem wirklich sitzt. Aber du kannst natürlichg damit erst einmal einen Versuch machen, unter dem Strich sparts du m. E.damit aber nicht soviel Zeit, denn die Clickfinder-Zuweisungen werden meines Wissens nicht gesichert.

Was bei einer Neueinrichtung tatsächlich dauert, ist das Zuweisen der TV-Kanäle. Du kannst aus der TVServer-Config Sender und Gruppen exportieren und nach vollständiger Neuinstallation wieder importieren. Das spart dir dann die Sendersuche auf den Tunern - aber nicht das Remapping im Clickfinder. Alles andere geht eigentlich sehr schnell, du solltest nur vorher in Appdata das TeamData-Verzeichnis wegsichern (bzw. einfach umbenennen). Dann kannst du nach Neuinstallation einfach deine Thumbs, Logos und Datenbanken in wenigen Sekunden wieder herstellen.[DOUBLEPOST=1424457605][/DOUBLEPOST]Falls du neu installierst: bitte schrittweise vorgehen, also zunächst nur die Grundfunktionalitäten ohne weitere Plugins - vielleicht mit Ausnahme von TVMovie, um wieder EPG zu kriegen. Aber bitte die Clickfinder-Einstellungen im Clickfinder-Setup korrigieren, wie oben beschrieben (kein automatisches Update durch Clickfinder selbst!). Dann testen, ob das System, stabil läuft oder wieder Fehler auftreten.

 

[frankteb]

Ist wahrscheinlich das beste. Mal schauen wann ich eine Nacht dafür erübrigen kann.


EDIT: Wird diese Nacht . Mache alles komplett neu inkl. Deinstallation von MySQL und neuem Senderscan usw. Die Uhr läuft mal schauen, wie lange es dauert.

 

[larry_S]

Und nach dem Deinstallieren von MYSQL musst du auch noch manuell die Datenbanken löschen.