[fixed] Import stops and shutdown MP2 Server (not related to ffmpeg...) (1 Viewer)

[Lehmden]

Hi.
Since I've testing trakt plugin I got problems with VideoThumbnailer plugin. Really often (but not always) the import stops on creating Thumbs for newly added episodes. After a while (a minute or so) the server shut down. I've changed ffmpeg.exe to an older one (from 2012) I just got laying around. With this this issue did not appear any longer. But I need to test this a lot more.
In attached logfiles you can see server.log with old ffmpeg.exe and server.2.log with new one. Server.2.log did not show anything after the attempt to create the thumb for an series episode. In server.log you can see the creation of a thumb for exact the same file with success by old ffmpeg.exe.

 

[Lehmden]

Hi.
Sorry, but this has nothing to do with ffmpeg. I now had same issue with the other ffmpeg.exe. I told I needed to test further... Now I think it's probably caused by new refresh function for series cache as this happenes right at the beginning or in the middle of the refresh action.
I'll added lots of logfiles. The most recent has a successful import, the others are mostly ended with shutdown of MP2 Server. Sadly there are no entries after this "crash" happened, nothing. Logfiles simply ended... I've added the Server.Error logfiles also they are completely empty (just to proof, there is nothing else to see). If other things are needed I will provide them asap.

BTW, it'S great to have the cache refreshed now. But to be perfect we need a way to refresh the ML also, not only the cache. Episodes that were added with e.G. Englsh title and description stays with English also German titles are available in meantime and were downloaded during cache refresh. Only new added episodes benefits from cache refresh...

 

[morpheus_xx]

Strange, the update block runs in a try/catch block, managed exceptions should be caught.

Can you check your app/system protocol if there are more details?

 

[Lehmden]

Hi.
Something from last Crash:
A .NET error few seconds before the crash:

Protokollname: Application
Quelle: .NET Runtime
Datum: 11.03.2014 17:14:01
Ereignis-ID: 1026
Aufgabenkategorie:Keine
Ebene: Fehler
Schlüsselwörter:Klassisch
Benutzer: Nicht zutreffend
Computer: Sulu
Beschreibung:
Anwendung: MP2-Server.exe
Frameworkversion: v4.0.30319
Beschreibung: Der Prozess wurde aufgrund eines Ausnahmefehlers beendet.
Ausnahmeinformationen: System.ObjectDisposedException
Stapel:
bei System.Runtime.InteropServices.SafeHandle.DangerousAddRef(Boolean ByRef)
bei System.StubHelpers.StubHelpers.SafeHandleAddRef(System.Runtime.InteropServices.SafeHandle, Boolean ByRef)
bei Microsoft.Win32.Win32Native.SetEvent(Microsoft.Win32.SafeHandles.SafeWaitHandle)
bei System.Threading.EventWaitHandle.Set()
bei MediaPortal.Utilities.Process.ProcessUtils+<>c__DisplayClass2.<PrepareProcess>b__1(System.Object, System.Diagnostics.DataReceivedEventArgs)
bei System.Diagnostics.Process.OutputReadNotifyUser(System.String)
bei System.Diagnostics.AsyncStreamReader.FlushMessageQueue()
bei System.Diagnostics.AsyncStreamReader.ReadBuffer(System.IAsyncResult)
bei System.IO.Stream+ReadWriteTask.InvokeAsyncCallback(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.IO.Stream+ReadWriteTask.System.Threading.Tasks.ITaskCompletionAction.Invoke(System.Threading.Tasks.Task)
bei System.Threading.Tasks.Task.FinishContinuations()
bei System.Threading.Tasks.Task.FinishStageThree()
bei System.Threading.Tasks.Task.FinishStageTwo()
bei System.Threading.Tasks.Task.Finish(Boolean)
bei System.Threading.Tasks.Task.ExecuteWithThreadLocal(System.Threading.Tasks.Task ByRef)
bei System.Threading.Tasks.Task.ExecuteEntry(Boolean)
bei System.Threading.Tasks.Task.System.Threading.IThreadPoolWorkItem.ExecuteWorkItem()
bei System.Threading.ThreadPoolWorkQueue.Dispatch()
bei System.Threading._ThreadPoolWaitCallback.PerformWaitCallback()

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="2014-03-11T16:14:01.000000000Z" />
<EventRecordID>11683</EventRecordID>
<Channel>Application</Channel>
<Computer>Sulu</Computer>
<Security />
</System>
<EventData>
<Data>Anwendung: MP2-Server.exe
Frameworkversion: v4.0.30319
Beschreibung: Der Prozess wurde aufgrund eines Ausnahmefehlers beendet.
Ausnahmeinformationen: System.ObjectDisposedException
Stapel:
bei System.Runtime.InteropServices.SafeHandle.DangerousAddRef(Boolean ByRef)
bei System.StubHelpers.StubHelpers.SafeHandleAddRef(System.Runtime.InteropServices.SafeHandle, Boolean ByRef)
bei Microsoft.Win32.Win32Native.SetEvent(Microsoft.Win32.SafeHandles.SafeWaitHandle)
bei System.Threading.EventWaitHandle.Set()
bei MediaPortal.Utilities.Process.ProcessUtils+&lt;&gt;c__DisplayClass2.&lt;PrepareProcess&gt;b__1(System.Object, System.Diagnostics.DataReceivedEventArgs)
bei System.Diagnostics.Process.OutputReadNotifyUser(System.String)
bei System.Diagnostics.AsyncStreamReader.FlushMessageQueue()
bei System.Diagnostics.AsyncStreamReader.ReadBuffer(System.IAsyncResult)
bei System.IO.Stream+ReadWriteTask.InvokeAsyncCallback(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.IO.Stream+ReadWriteTask.System.Threading.Tasks.ITaskCompletionAction.Invoke(System.Threading.Tasks.Task)
bei System.Threading.Tasks.Task.FinishContinuations()
bei System.Threading.Tasks.Task.FinishStageThree()
bei System.Threading.Tasks.Task.FinishStageTwo()
bei System.Threading.Tasks.Task.Finish(Boolean)
bei System.Threading.Tasks.Task.ExecuteWithThreadLocal(System.Threading.Tasks.Task ByRef)
bei System.Threading.Tasks.Task.ExecuteEntry(Boolean)
bei System.Threading.Tasks.Task.System.Threading.IThreadPoolWorkItem.ExecuteWorkItem()
bei System.Threading.ThreadPoolWorkQueue.Dispatch()
bei System.Threading._ThreadPoolWaitCallback.PerformWaitCallback()
</Data>
</EventData>
</Event>

and the crash itself:

Protokollname: Application
Quelle: Application Error
Datum: 11.03.2014 17:14:13
Ereignis-ID: 1000
Aufgabenkategorie100)
Ebene: Fehler
Schlüsselwörter:Klassisch
Benutzer: Nicht zutreffend
Computer: Sulu
Beschreibung:
Name der fehlerhaften Anwendung: MP2-Server.exe, Version: 2.0.0.1403, Zeitstempel: 0x531af7cd
Name des fehlerhaften Moduls: KERNELBASE.dll, Version: 6.1.7601.18229, Zeitstempel: 0x51fb1116
Ausnahmecode: 0xe0434352
Fehleroffset: 0x0000c41f
ID des fehlerhaften Prozesses: 0x130c
Startzeit der fehlerhaften Anwendung: 0x01cf3d3f7fa1c24c
Pfad der fehlerhaften Anwendung: C:\Program Files (x86)\Team MediaPortal\MP2-Server\MP2-Server.exe
Pfad des fehlerhaften Moduls: C:\Windows\syswow64\KERNELBASE.dll
Berichtskennung: 2f08c05a-a938-11e3-8e4a-d43d7e54020a
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="2014-03-11T16:14:13.000000000Z" />
<EventRecordID>11684</EventRecordID>
<Channel>Application</Channel>
<Computer>Sulu</Computer>
<Security />
</System>
<EventData>
<Data>MP2-Server.exe</Data>
<Data>2.0.0.1403</Data>
<Data>531af7cd</Data>
<Data>KERNELBASE.dll</Data>
<Data>6.1.7601.18229</Data>
<Data>51fb1116</Data>
<Data>e0434352</Data>
<Data>0000c41f</Data>
<Data>130c</Data>
<Data>01cf3d3f7fa1c24c</Data>
<Data>C:\Program Files (x86)\Team MediaPortal\MP2-Server\MP2-Server.exe</Data>
<Data>C:\Windows\syswow64\KERNELBASE.dll</Data>
<Data>2f08c05a-a938-11e3-8e4a-d43d7e54020a</Data>
</EventData>
</Event>

Hope this helps.

 

[Lehmden]

The same scheme is on all crashes I had. First a.NET error and then an APP error. Do you need more of those "copy and paste"?

 

[morpheus_xx]

Thanks, this is enough. The problem comes from my process handling fix Need to check this

 

[morpheus_xx]

Fixed the problem: I cancel async reading now and also catch exceptions.

Place the file in server (and optional client) root folders

 

[Lehmden]

Tried it, seems to fix the issue. Thanks.
I will test it today and tonite and report back, if all went well...

 

[Lehmden]

Hi.
With the new version of MPutils.dll the server don't crash any more. That's the good news. The bad news is, there are no thumbs generated any more...

[2014-03-11 18:50:48,056] [1264023] [Importer ] [INFO ] - ImporterWorker: Importing '\\PILLE\Pille - Daten\Serien\Die Besucher\' (40 more resources pending)
[2014-03-11 18:50:53,610] [1269577] [Importer ] [ERROR] - VideoThumbnailer: Exception reading resource '{03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////PILLE/Pille - Daten/Serien/Die Besucher/S01E02 - Stufen in die Vergangenheit.mkv' (Text: 'Für den Stream wird kein asynchroner Lesevorgang ausgeführt.')
System.InvalidOperationException: Für den Stream wird kein asynchroner Lesevorgang ausgeführt.
bei System.Diagnostics.Process.CancelOutputRead()
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, IntPtr token, Boolean redirectInputOutput, String& result, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_AutoImpersonate(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.ExtractThumbnail(ILocalFsResourceAccessor lfsra, IDictionary`2 extractedAspectData)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.TryExtractMetadata(IResourceAccessor mediaItemAccessor, IDictionary`2 extractedAspectData, Boolean forceQuickMode)
[2014-03-11 18:50:54,702] [1270669] [Importer ] [DEBUG] - SeriesTvDbMatcher: Try to lookup series "tt0165019" from cache: True
[2014-03-11 18:50:54,811] [1270778] [Importer ] [INFO ] - Loaded series 82903 in 102 milliseconds
[2014-03-11 18:50:59,023] [1274990] [Importer ] [ERROR] - VideoThumbnailer: Exception reading resource '{03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////PILLE/Pille - Daten/Serien/Die Besucher/S01E03 - Kontakt.mkv' (Text: 'Für den Stream wird kein asynchroner Lesevorgang ausgeführt.')
System.InvalidOperationException: Für den Stream wird kein asynchroner Lesevorgang ausgeführt.
bei System.Diagnostics.Process.CancelOutputRead()
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, IntPtr token, Boolean redirectInputOutput, String& result, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_AutoImpersonate(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.ExtractThumbnail(ILocalFsResourceAccessor lfsra, IDictionary`2 extractedAspectData)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.TryExtractMetadata(IResourceAccessor mediaItemAccessor, IDictionary`2 extractedAspectData, Boolean forceQuickMode)
[2014-03-11 18:51:02,798] [1278765] [Importer ] [ERROR] - VideoThumbnailer: Exception reading resource '{03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////PILLE/Pille - Daten/Serien/Die Besucher/S01E04 - Aktion Heft 1.mkv' (Text: 'Für den Stream wird kein asynchroner Lesevorgang ausgeführt.')
System.InvalidOperationException: Für den Stream wird kein asynchroner Lesevorgang ausgeführt.
bei System.Diagnostics.Process.CancelOutputRead()
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, IntPtr token, Boolean redirectInputOutput, String& result, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_AutoImpersonate(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.ExtractThumbnail(ILocalFsResourceAccessor lfsra, IDictionary`2 extractedAspectData)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.TryExtractMetadata(IResourceAccessor mediaItemAccessor, IDictionary`2 extractedAspectData, Boolean forceQuickMode)
[2014-03-11 18:51:06,683] [1282649] [Importer ] [ERROR] - VideoThumbnailer: Exception reading resource '{03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////PILLE/Pille - Daten/Serien/Die Besucher/S01E05 - Nur nicht auffallen.mkv' (Text: 'Für den Stream wird kein asynchroner Lesevorgang ausgeführt.')
System.InvalidOperationException: Für den Stream wird kein asynchroner Lesevorgang ausgeführt.
bei System.Diagnostics.Process.CancelOutputRead()
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, IntPtr token, Boolean redirectInputOutput, String& result, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_AutoImpersonate(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.ExtractThumbnail(ILocalFsResourceAccessor lfsra, IDictionary`2 extractedAspectData)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.TryExtractMetadata(IResourceAccessor mediaItemAccessor, IDictionary`2 extractedAspectData, Boolean forceQuickMode)
[2014-03-11 18:51:10,099] [1286066] [Importer ] [ERROR] - VideoThumbnailer: Exception reading resource '{03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////PILLE/Pille - Daten/Serien/Die Besucher/S01E06 - Das Geheimnis des großen Lehrers.mkv' (Text: 'Für den Stream wird kein asynchroner Lesevorgang ausgeführt.')
System.InvalidOperationException: Für den Stream wird kein asynchroner Lesevorgang ausgeführt.
bei System.Diagnostics.Process.CancelOutputRead()
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, IntPtr token, Boolean redirectInputOutput, String& result, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_AutoImpersonate(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.ExtractThumbnail(ILocalFsResourceAccessor lfsra, IDictionary`2 extractedAspectData)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.TryExtractMetadata(IResourceAccessor mediaItemAccessor, IDictionary`2 extractedAspectData, Boolean forceQuickMode)
[2014-03-11 18:51:13,562] [1289529] [Importer ] [ERROR] - VideoThumbnailer: Exception reading resource '{03dd2da6-4da8-4d3e-9e55-80e3165729a3}:////PILLE/Pille - Daten/Serien/Die Besucher/S01E07 - Kirmes um Mitternacht.mkv' (Text: 'Für den Stream wird kein asynchroner Lesevorgang ausgeführt.')
System.InvalidOperationException: Für den Stream wird kein asynchroner Lesevorgang ausgeführt.
bei System.Diagnostics.Process.CancelOutputRead()
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, IntPtr token, Boolean redirectInputOutput, String& result, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_Impersonated(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Utilities.Process.ProcessUtils.TryExecute_AutoImpersonate(String executable, String arguments, ProcessPriorityClass priorityClass, Int32 maxWaitMs)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.ExtractThumbnail(ILocalFsResourceAccessor lfsra, IDictionary`2 extractedAspectData)
bei MediaPortal.Extensions.MetadataExtractors.VideoThumbnailer.VideoThumbnailer.TryExtractMetadata(IResourceAccessor mediaItemAccessor, IDictionary`2 extractedAspectData, Boolean forceQuickMode)

If you need full logs I can provide them later...

 

[morpheus_xx]

Stupid error, will fix this later today