Server stürzt ab wenn Client während dem Import lokaler Medienquellen beendet wird (1 Viewer)

[rico123]

Wenn der Client gerade Importieren lokaler Medienquellen ist und ich diesen beende (ab und zu will ich auch mal was anderes außer TV und Video etc.) dann stürzt der Server ab. Bei mir läuft MP2.1 in einer getrennten Client-Server Umgebung. Logs im Anhang.

Grüße, Rico.

 

[HTPCSourcer]

Das Log gibt nichts her, es endet einfach. Kannst du mal die Ereignisanzeige auf dem Server prüfen, was dort ausgeworfen worden ist?

 

[rico123]

Hab was gefunden was auf den letzten Absturz passt:

Protokollname: Application
Quelle:        Application Error
Datum:         11.05.2017 17:55:07
Ereignis-ID:   1000
Aufgabenkategorie:(100)
Ebene:         Fehler
Schlüsselwörter:Klassisch
Benutzer:      Nicht zutreffend
Computer:      TV-Server
Beschreibung:
Name der fehlerhaften Anwendung: MP2-Server.exe, Version: 2.1.1705.10177, Zeitstempel: 0x5908d2e4
Name des fehlerhaften Moduls: KERNELBASE.dll, Version: 10.0.14393.1198, Zeitstempel: 0x5902845a
Ausnahmecode: 0xe0434352
Fehleroffset: 0x000da9f2
ID des fehlerhaften Prozesses: 0xeb0
Startzeit der fehlerhaften Anwendung: 0x01d2ca3663e1c2fe
Pfad der fehlerhaften Anwendung: C:\Program Files (x86)\Team MediaPortal\MP2-Server\MP2-Server.exe
Pfad des fehlerhaften Moduls: C:\Windows\System32\KERNELBASE.dll
Berichtskennung: dc370ad8-ee07-497b-b75a-aba42c0db4b7
Vollständiger Name des fehlerhaften Pakets:
Anwendungs-ID, die relativ zum fehlerhaften Paket ist:
Ereignis-XML:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Application Error" />
    <EventID Qualifiers="0">1000</EventID>
    <Level>2</Level>
    <Task>100</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2017-05-11T15:55:07.349008200Z" />
    <EventRecordID>4965</EventRecordID>
    <Channel>Application</Channel>
    <Computer>TV-Server</Computer>
    <Security />
  </System>
  <EventData>
    <Data>MP2-Server.exe</Data>
    <Data>2.1.1705.10177</Data>
    <Data>5908d2e4</Data>
    <Data>KERNELBASE.dll</Data>
    <Data>10.0.14393.1198</Data>
    <Data>5902845a</Data>
    <Data>e0434352</Data>
    <Data>000da9f2</Data>
    <Data>eb0</Data>
    <Data>01d2ca3663e1c2fe</Data>
    <Data>C:\Program Files (x86)\Team MediaPortal\MP2-Server\MP2-Server.exe</Data>
    <Data>C:\Windows\System32\KERNELBASE.dll</Data>
    <Data>dc370ad8-ee07-497b-b75a-aba42c0db4b7</Data>
    <Data>
    </Data>
    <Data>
    </Data>
  </EventData>
</Event>

Protokollname: Application
Quelle:        .NET Runtime
Datum:         11.05.2017 17:55:07
Ereignis-ID:   1026
Aufgabenkategorie:Keine
Ebene:         Fehler
Schlüsselwörter:Klassisch
Benutzer:      Nicht zutreffend
Computer:      TV-Server
Beschreibung:
Anwendung: MP2-Server.exe
Frameworkversion: v4.0.30319
Beschreibung: Der Prozess wurde aufgrund einer unbehandelten Ausnahme beendet.
Ausnahmeinformationen: System.Data.SQLite.SQLiteException
   bei System.Data.SQLite.SQLite3.Prepare(System.Data.SQLite.SQLiteConnection, System.String, System.Data.SQLite.SQLiteStatement, UInt32, System.String ByRef)
   bei System.Data.SQLite.SQLiteCommand.BuildNextCommand()
   bei System.Data.SQLite.SQLiteCommand.GetStatement(Int32)
   bei System.Data.SQLite.SQLiteDataReader.NextResult()
   bei System.Data.SQLite.SQLiteDataReader..ctor(System.Data.SQLite.SQLiteCommand, System.Data.CommandBehavior)
   bei System.Data.SQLite.SQLiteCommand.ExecuteReader(System.Data.CommandBehavior)
   bei System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(System.Data.CommandBehavior)
   bei System.Data.SQLite.SQLiteCommand.ExecuteNonQuery()
   bei System.Data.SQLite.SQLiteConnection.Open()
   bei Gentle.Provider.SQLite.SQLiteProvider.GetConnection()

Ausnahmeinformationen: Gentle.Common.GentleException
   bei Gentle.Common.Check.FailWith(Gentle.Common.Severity, Gentle.Common.Error, System.Exception, System.String)
   bei Gentle.Common.Check.Fail(System.Exception, Gentle.Common.Error, System.Object[])
   bei Gentle.Provider.SQLite.SQLiteProvider.GetConnection()
   bei Gentle.Framework.PersistenceBroker.Execute(Gentle.Framework.SqlStatement, System.Data.IDbConnection, System.Data.IDbTransaction)
   bei TvDatabase.TvBusinessLayer.GetSetting(System.String, System.String)
   bei TvEngine.PowerScheduler.PowerScheduler.LoadSettings()
   bei TvEngine.PowerScheduler.PowerScheduler.StandbyWakeupThread()
   bei System.Threading.ThreadHelper.ThreadStart_Context(System.Object)
   bei System.Threading.ExecutionContext.RunInternal(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object, Boolean)
   bei System.Threading.ExecutionContext.Run(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object, Boolean)
   bei System.Threading.ExecutionContext.Run(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object)
   bei System.Threading.ThreadHelper.ThreadStart()


Ereignis-XML:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name=".NET Runtime" />
    <EventID Qualifiers="0">1026</EventID>
    <Level>2</Level>
    <Task>0</Task>
    <Keywords>0x80000000000000</Keywords>
    <TimeCreated SystemTime="2017-05-11T15:55:07.048711700Z" />
    <EventRecordID>4964</EventRecordID>
    <Channel>Application</Channel>
    <Computer>TV-Server</Computer>
    <Security />
  </System>
  <EventData>
    <Data>Anwendung: MP2-Server.exe
Frameworkversion: v4.0.30319
Beschreibung: Der Prozess wurde aufgrund einer unbehandelten Ausnahme beendet.
Ausnahmeinformationen: System.Data.SQLite.SQLiteException
   bei System.Data.SQLite.SQLite3.Prepare(System.Data.SQLite.SQLiteConnection, System.String, System.Data.SQLite.SQLiteStatement, UInt32, System.String ByRef)
   bei System.Data.SQLite.SQLiteCommand.BuildNextCommand()
   bei System.Data.SQLite.SQLiteCommand.GetStatement(Int32)
   bei System.Data.SQLite.SQLiteDataReader.NextResult()
   bei System.Data.SQLite.SQLiteDataReader..ctor(System.Data.SQLite.SQLiteCommand, System.Data.CommandBehavior)
   bei System.Data.SQLite.SQLiteCommand.ExecuteReader(System.Data.CommandBehavior)
   bei System.Data.SQLite.SQLiteCommand.ExecuteNonQuery(System.Data.CommandBehavior)
   bei System.Data.SQLite.SQLiteCommand.ExecuteNonQuery()
   bei System.Data.SQLite.SQLiteConnection.Open()
   bei Gentle.Provider.SQLite.SQLiteProvider.GetConnection()

Ausnahmeinformationen: Gentle.Common.GentleException
   bei Gentle.Common.Check.FailWith(Gentle.Common.Severity, Gentle.Common.Error, System.Exception, System.String)
   bei Gentle.Common.Check.Fail(System.Exception, Gentle.Common.Error, System.Object[])
   bei Gentle.Provider.SQLite.SQLiteProvider.GetConnection()
   bei Gentle.Framework.PersistenceBroker.Execute(Gentle.Framework.SqlStatement, System.Data.IDbConnection, System.Data.IDbTransaction)
   bei TvDatabase.TvBusinessLayer.GetSetting(System.String, System.String)
   bei TvEngine.PowerScheduler.PowerScheduler.LoadSettings()
   bei TvEngine.PowerScheduler.PowerScheduler.StandbyWakeupThread()
   bei System.Threading.ThreadHelper.ThreadStart_Context(System.Object)
   bei System.Threading.ExecutionContext.RunInternal(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object, Boolean)
   bei System.Threading.ExecutionContext.Run(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object, Boolean)
   bei System.Threading.ExecutionContext.Run(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object)
   bei System.Threading.ThreadHelper.ThreadStart()

</Data>
  </EventData>
</Event>

Hoffe das hilft weiter...
Gibt es eine Möglichkeit den Server vom Client aus neu zu starten wenn er abgeschossen wurde? Ist etwas mühsam immer über Remote auf den Server zu gehen usw...

Grüße.

 

[morpheus_xx]

Danke für die Logs! Leider deutet das (auch wieder) auf einen Fehler in SQLite hin, der bereits gemeldet wurde (@aspik @Brownard)

Wir sind gerade dabei, das Problem zu untersuchen

 

[aspik]

Könntest du bitte noch die Logs posten, die zu dem Zeitstempel der SQLiteException passen (11.05 17:55)? Die angehängten Logs enden am 10.05.

Kannst du den Fehler zu 100% reproduzieren? Jedes mal wenn der Server am importieren ist und du den Client schließt, stürzt der Server ab?

Gibt es eine Möglichkeit den Server vom Client aus neu zu starten wenn er abgeschossen wurde?

Du kannst es mal versuchen über die recovery options den Service automatisch neu starten zu lassen.

 

[rico123]

Leider kann ich das beschriebene Verhalten im Moment nicht reproduzieren. Aber im Anhang die Logs passend zur Exception.

Grüße.

 

[aspik]

Aber im Anhang die Logs passend zur Exception.

Die Exception im event Log passierte um 17:55, das Server Log endet aber um 17:53.

Der ImporterWorker endet um 11:49:

[2017-05-11 11:49:54,656] [2383835] [108      ] [INFO ] - ImporterWorker.ImportJob(#1): Completed
[2017-05-11 11:49:54,656] [2383835] [108      ] [INFO ] - ImporterWorker.ImportJob(#1): Disposed

Von 11:50 bis 17:53 gibt es keine Kommunikation mir der Datenbank. Seltsam von wo die SQLiteException kommt...

 

[rico123]

Hab wieder nen neuen Absturz im Zusammenhang mit Medienimport... Diesmal lief der Client die ganze Zeit.

 

[HTPCSourcer]

Hallo

interessant. Im Server-Log fällt zunächst folgende Fehlermeldung auf

Spoiler: Spoiler

[2017-05-14 10:46:44,203] [11565 ] [69 ] [DEBUG] - ImporterWorker.ImportJob(#1).DirectoryUnfoldBlock: Successfully processed 0 MediaItems; time elapsed: 00:00:00.0001319; MaxDegreeOfParallelism(InnerBlock) = 1
[2017-05-14 10:46:44,309] [11671 ] [69 ] [DEBUG] - ImporterWorker.ImportJob(#1).DirectorySaveBlock: Successfully processed 0 MediaItems; time elapsed: 00:00:00.0006749; MaxDegreeOfParallelism(InnerBlock) = 1
[2017-05-14 10:46:44,421] [11783 ] [66 ] [DEBUG] - ImporterWorker.ImportJob(#1).FileUnfoldBlock: Successfully processed 0 MediaItems; time elapsed: 00:00:00.0001392; MaxDegreeOfParallelism(InnerBlock) = 1
[2017-05-14 10:46:44,968] [12330 ] [20 ] [INFO ] - ImporterWorker.ImportJob(#1): Activated
[2017-05-14 10:46:44,970] [12332 ] [20 ] [INFO ] - ImporterWorker: Activated (2 ImportJobs pending)
[2017-05-14 10:46:47,425] [14787 ] [3 ] [ERROR] - ImporterWorker.ImportJob(#2): Could not create ResourceAccessor for resource '{03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////LS-WXLE76/Medien/Musik/Rock/Die Fantastischen Vier/Fornika/Die Fantastischen Vier/Fornika/': It is no filesystem resource
[2017-05-14 10:46:47,433] [14795 ] [3 ] [WARN ] - ImporterWorker.ImportJob(#2).MediaItemLoadBlock: Error while processing PendingImportResource '{03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////LS-WXLE76/Medien/Musik/Rock/Die Fantastischen Vier/Fornika/Die Fantastischen Vier/Fornika/' (parent directory={03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////LS-WXLE76/Medien/Musik/Rock/Die Fantastischen Vier/Fornika/Die Fantastischen Vier/)
System.NullReferenceException: Der Objektverweis wurde nicht auf eine Objektinstanz festgelegt.
bei MediaPortal.Common.Services.MediaManagement.ImportDataflowBlocks.MediaItemLoadBlock.<ProcessMediaItem>d__3.MoveNext()

Aber das scheint das System nicht zum Absturz zu bringen, denn es geht anschließend weiter und es wird erfolgreich auf Kika HD getunt.

Anschließend steht hier aber, dass Standby angefordert wurde:

[2017-05-14 10:47:14,069] [41430 ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(AwayModeRequested)

Wurde doch versucht, den Client abzuschalten?

Die Log-Aktivität endet dann 1 sek später mit

[2017-05-14 10:47:15,184] [42546 ] [16 ] [INFO ] - ImporterWorker: 2 % completed (2 ImportJob(s), in total 510 of 24055 so far identified resources processed)

Ich vermute, dass Im Event-Log wieder ein SQLite-Fehler steht. Kannst du das mal prüfen und vor allem die Zeiten vergleichen?

 

[rico123]

Der Client wurde zu der Zeit definitiv nicht abgeschaltet. Im Event-Log des Servers hab ich folgendes gefunden:

Protokollname: System
Quelle:        Service Control Manager
Datum:         14.05.2017 10:45:24
Ereignis-ID:   7031
Aufgabenkategorie:Keine
Ebene:         Fehler
Schlüsselwörter:Klassisch
Benutzer:      Nicht zutreffend
Computer:      TV-Server
Beschreibung:
Der Dienst "MediaPortal 2 Server Service" wurde unerwartet beendet. Dies ist bereits 1 Mal vorgekommen. Folgende Korrekturmaßnahmen werden in 60000 Millisekunden durchgeführt: Neustart des Diensts.
Ereignis-XML:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
  <System>
    <Provider Name="Service Control Manager" Guid="{555908d1-a6d7-4695-8e1e-26931d2012f4}" EventSourceName="Service Control Manager" />
    <EventID Qualifiers="49152">7031</EventID>
    <Version>0</Version>
    <Level>2</Level>
    <Task>0</Task>
    <Opcode>0</Opcode>
    <Keywords>0x8080000000000000</Keywords>
    <TimeCreated SystemTime="2017-05-14T08:45:24.488684600Z" />
    <EventRecordID>3766</EventRecordID>
    <Correlation />
    <Execution ProcessID="688" ThreadID="1984" />
    <Channel>System</Channel>
    <Computer>TV-Server</Computer>
    <Security />
  </System>
  <EventData>
    <Data Name="param1">MediaPortal 2 Server Service</Data>
    <Data Name="param2">1</Data>
    <Data Name="param3">60000</Data>
    <Data Name="param4">1</Data>
    <Data Name="param5">Neustart des Diensts</Data>
    <Binary>4D00500032002D005300650072007600650072000000</Binary>
  </EventData>
</Event>

Der Fehler, welcher dazu geführt hat ist in der ServerError1.log zu finden:
Error: SSDPClientController: Problem receiving multicast SSDP packets: 'Eine vorhandene Verbindung wurde vom Remotehost geschlossen'

Hoffe das hilft weiter.

Grüße.