[fixed] TV Server crashes with unknown error (related to KNC tuners) (2 Viewers)

[Lightning303]

Hi,
so i updated to pre release 1.6. However, i cant watch tv anymore. When i am in Mediaportal and want to start a channel i get "unknown error occured" and then "lost connection to tv server".

So for my logs, here is what i did.

Started SetupTV, it asks me if i want to start the tv service, as it isnt running because of the crash. I say yes. Then go to manual control and try to start a timehift. After a few seconds i get unknown error and the service crashes .

Tried it a few times with same results, tried restarting pc, same results, other channels, same results...

 

[mm1352000]

Hello

Did you stop the service before upgrading as recommended in the release news?
Please could you post full log files using the watchdog:
http://wiki.team-mediaportal.com/1_MEDIAPORTAL_1/16_Support/3_Forums/2_Log_Files

You can use option 1 or option 3 - I don't mind. What I want to see is your system log files so I can see the source of the crash.

mm

 

[Lightning303]

Well...
frist of all, thanks for your reply .

I actually did not stop the service before install, as i never needed to. But since you brought it up, i thought well, that could be the problem right there. So i stoped the service and tried reinstalling mediaportal and the tv server. Sadly it failed and i got an error during the installation (will come back to this error later). So with the mediaportal uninstaller i did a product cleanup thing (last option which would delete everything), rebooted and tried a fresh install.
I get the same error, it seems that the installation succeeded but cant start the service.



I tried installing about 5 more times, but wouldnt work. Logs to the installation are attached (install logs.zip).

So, as installation did "succeed", i tried starting setuptv. It told me service wasnt running, and if i wanted to start it. I said yes and got the same error... There are some more logs after this, so i added them aswell (starting setuptv.zip).

I also made a full log with watchdog, however just of this problem. Not my initial, and as i cant even get tvservice to start, i cant at the moment...

Hope this helps you to help me

Thanks for you time.

 

[mm1352000]

Thanks - the logs are helpful.

I'm going to be completely honest here: I don't have a solution and I don't really understand the problem [yet], but it seems to be something to do with .NET. I'll leave the installer issues for the moment because it looks like the TV service is installed okay now, and I think if we can understand the reason why the TV service is crashing then we might also understand why the installer had problems.

In the TV service log:
[collapse][2013-11-20 23:52:33,331] [Log ] [DVB EPG timer] [INFO ] - card: Tune on card 4 to subchannel Sky Sport HD 1
[2013-11-20 23:52:33,347] [Log ] [DVB EPG timer] [DEBUG] - card: user: epg:4:-1 tune DVBC:tvKY Sky Sport HD 1 Freq:522000 ONID:133 TSID:6 SID:129 PMT:0x62 FTA:False LCN:10000 SymbolRate:6900 Modulation:Mod256Qam
[2013-11-20 23:52:33,362] [Log ] [DVB EPG timer] [INFO ] - dvbc: TuneVBC:tvKY Sky Sport HD 1 Freq:522000 ONID:133 TSID:6 SID:129 PMT:0x62 FTA:False LCN:10000 SymbolRate:6900 Modulation:Mod256Qam
[2013-11-20 23:52:33,362] [Log ] [DVB EPG timer] [INFO ] - dvbc:BuildGraph
[2013-11-20 23:52:33,362] [Log ] [DVB EPG timer] [INFO ] - dvb:AddNetworkProviderFilter
[2013-11-20 23:52:33,362] [Log ] [DVB EPG timer] [INFO ] - dvb:AddNetworkProviderFilter
[2013-11-20 23:52:33,378] [Log ] [DVB EPG timer] [INFO ] - dvb:Add Generic Network Provider
[2013-11-20 23:52:33,378] [Log ] [DVB EPG timer] [INFO ] - dvb: Add Mediaportal TsWriter filter
[2013-11-20 23:52:33,378] [Log ] [DVB EPG timer] [INFO ] - dvbc:CreateTuningSpace()
[2013-11-20 23:52:33,394] [Log ] [DVB EPG timer] [INFO ] - dvbc:Found correct tuningspace MediaPortal DVBC TuningSpace
[2013-11-20 23:52:33,394] [Log ] [DVB EPG timer] [INFO ] - dvb:Add MPEG2 Demultiplexer filter
[2013-11-20 23:52:33,394] [Log ] [DVB EPG timer] [INFO ] - dvb:AddAndConnectBDABoardFilters
[2013-11-20 23:52:33,409] [Log ] [DVB EPG timer] [INFO ] - dvb: find bda tuner
[2013-11-20 23:52:33,425] [Log ] [DVB EPG timer] [INFO ] - dvb: using [Tuner]: KNC BDA DVB-C
[2013-11-20 23:52:33,425] [Log ] [DVB EPG timer] [INFO ] - dvb: Render [Network provider]->[Tuner] OK
[2013-11-20 23:52:33,425] [Log ] [DVB EPG timer] [INFO ] - dvb: Setting lastFilter to Tuner filter
[2013-11-20 23:52:33,440] [Log ] [DVB EPG timer] [INFO ] - dvb: tuner filter has capture filter output
[2013-11-20 23:52:33,456] [Log ] [DVB EPG timer] [INFO ] - dvb: WinTv CI module not detected.
[2013-11-20 23:52:33,456] [Log ] [DVB EPG timer] [INFO ] - dvb:add Inf Tee filter
[2013-11-20 23:52:33,456] [Log ] [DVB EPG timer] [INFO ] - dvb: Render ...->[inftee]
[2013-11-20 23:52:33,456] [Log ] [DVB EPG timer] [INFO ] - dvb: Setting lastFilter to Inf Tee
[2013-11-20 23:52:33,472] [Log ] [DVB EPG timer] [INFO ] - dvb: Render [inftee]->[demux]
[2013-11-20 23:52:33,472] [Log ] [DVB EPG timer] [INFO ] - dvb: AddTransportStreamFiltersToGraph
[2013-11-20 23:52:33,472] [Log ] [DVB EPG timer] [INFO ] - add BDA MPEG2 Transport Information Filter filter
[2013-11-20 23:52:33,472] [Log ] [DVB EPG timer] [INFO ] - pinTif:name:IB Input [3/0] Direction:Input Connected:False
[2013-11-20 23:52:33,487] [Log ] [DVB EPG timer] [INFO ] - Connect tif and mpeg2 sections and tables
[2013-11-20 23:52:33,487] [Log ] [DVB EPG timer] [INFO ] - dvb:try tif:name:001 [5/0] Direction:Output Connected:False
[2013-11-20 23:52:33,487] [Log ] [DVB EPG timer] [INFO ] - tif connected
[2013-11-20 23:52:33,487] [Log ] [DVB EPG timer] [INFO ] - dvb: Render ..->[TsWriter]
[2013-11-20 23:52:33,487] [Log ] [DVB EPG timer] [INFO ] - dvb: Checking for hardware specific extensions
[2013-11-20 23:52:33,503] [Log ] [DVB EPG timer] [INFO ] - CAM is Default model
[2013-11-20 23:52:33,503] [Log ] [DVB EPG timer] [INFO ] - Check for KNC
[2013-11-20 23:52:33,518] [Log ] [DVB EPG timer] [DEBUG] - KNC: card 0 detected: KNC BDA DVB-C
[2013-11-20 23:52:33,518] [Log ] [DVB EPG timer] [DEBUG] - KNC: card 0 detected without CAM
[2013-11-20 23:52:33,518] [Log ] [DVB EPG timer] [INFO ] - KNC: IsKNC True
[2013-11-20 23:52:33,518] [Log ] [DVB EPG timer] [INFO ] - KNC card detected
[2013-11-20 23:52:33,550] [Log ] [DVB EPG timer] [INFO ] - dvb: GetTunerSignalStatistics()
[2013-11-20 23:52:33,550] [Log ] [DVB EPG timer] [INFO ] - dvb:GetNewSubChannel:0 #0
[2013-11-20 23:52:33,565] [Log ] [DVB EPG timer] [INFO ] - TvDvbChannel ctor new subchIndex:0
[2013-11-20 23:52:33,565] [Log ] [DVB EPG timer] [INFO ] - card: AddTuneEvent card: 4 / subch: 0
[2013-11-20 23:52:33,565] [Log ] [DVB EPG timer] [INFO ] - dvbubmiting tunerequest Channelky Sport HD 1 subChannel:0
[2013-11-20 23:52:33,565] [Log ] [DVB EPG timer] [INFO ] - dvb:using existing subchannel:0
[2013-11-20 23:52:33,581] [Log ] [DVB EPG timer] [INFO ] - dvbubmit tunerequest size:1 new:0
[2013-11-20 23:52:33,581] [Log ] [DVB EPG timer] [INFO ] - subch:0 OnBeforeTune
[2013-11-20 23:52:33,581] [Log ] [DVB EPG timer] [INFO ] - dvbubmit tunerequest calling put_TuneRequest
[2013-11-20 23:52:33,581] [Log ] [DVB EPG timer] [INFO ] - dvbubmit tunerequest done calling put_TuneRequest
[2013-11-20 23:52:33,581] [Log ] [DVB EPG timer] [INFO ] - subch:0 OnAfterTune
[2013-11-20 23:52:33,596] [Log ] [DVB EPG timer] [INFO ] - RunGraph
[2013-11-20 23:52:33,596] [Log ] [DVB EPG timer] [INFO ] - subch:0 OnGraphStart
[2013-11-20 23:52:33,596] [Log ] [DVB EPG timer] [INFO ] - dvb: RunGraph[/collapse]

...and then CRASH.
At least we can see that the TV service started and the crash is occurring when the EPG grabber is attempting to tune a channel and start EPG grabbing. This is happening immediately after the TV service starts... which explains why it seems like the TV service is crashing immediately.

In the TsWriter log:
[collapse][2013-11-20 23:52:33,565] [6ca8590] [5a0] - debug: done AddChannel()
[2013-11-20 23:52:33,581] [6ca8590] [5a0] - epg: reset
[2013-11-20 23:52:33,581] [6ca8590] [5a0] - epg:ResetEPG()
[2013-11-20 23:52:33,581] [6ca8590] [5a0] - mhw reset
[2013-11-20 23:52:33,596] [6ca8590] [5a0] - CMpTsFilter:ause()
[2013-11-20 23:52:33,596] [6ca8590] [5a0] - Pause filter...
[2013-11-20 23:52:33,596] [6ca8590] [5a0] - HRESULT = 0x0[/collapse]

Due to changes in the TsWriter logging it is difficult to be sure that this is really the last log entry before the crash... but if it is, it means that one of the other filters in the graph could be causing the crash.

From the Windows application event log:
[collapse]"20.11.2013 23:52:33";"TV Server";"(0)";"Information";"Service started successfully.";"0"
"20.11.2013 23:52:34";"Application Error";"Anwendungsabsturzereignisse";"Error";"Name der fehlerhaften Anwendung: TVService.exe, Version: 1.5.100.0, Zeitstempel: 0x528797ba Name des fehlerhaften Moduls: clr.dll, Version: 4.0.30319.1008, Zeitstempel: 0x517a1be7 Ausnahmecode: 0xc0000409 Fehleroffset: 0x002b7330 ID des fehlerhaften Prozesses: 0x14c Startzeit der fehlerhaften Anwendung: 0x01cee643315ab6d1 Pfad der fehlerhaften Anwendung: C:\Program Files (x86)\Team MediaPortal\MediaPortal TV Server\TVService.exe Pfad des fehlerhaften Moduls: C:\Windows\Microsoft.NET\Framework\v4.0.30319\clr.dll Berichtskennung: 718eb7fb-5236-11e3-8e06-20cf30c42e71";"1000"
"20.11.2013 23:52:34";"Windows Error Reporting";"(0)";"Information";"Fehlerbucket , Typ 0 Ereignisname: BEX Antwort: Nicht verfügbar CAB-Datei-ID: 0 Problemsignatur: P1: TVService.exe P2: 1.5.100.0 P3: 528797ba P4: clr.dll P5: 4.0.30319.1008 P6: 517a1be7 P7: 002b7330 P8: c0000409 P9: 00000000 P10: Angefügte Dateien: Diese Dateien befinden sich möglicherweise hier: C:\ProgramData\Microsoft\Windows\WER\ReportQueue\AppCrash_TVService.exe_221ed08e7e97fe2527dbfdd0bd47eb4ce41c9b_0c58d864 Analysesymbol: Es wird erneut nach einer Lösung gesucht: 0 Berichts-ID: 718eb7fb-5236-11e3-8e06-20cf30c42e71 Berichtstatus: 4";"1001"
"20.11.2013 23:52:35";"Windows Error Reporting";"(0)";"Information";"Fehlerbucket , Typ 0 Ereignisname: BEX Antwort: Nicht verfügbar CAB-Datei-ID: 0 Problemsignatur: P1: TVService.exe P2: 1.5.100.0 P3: 528797ba P4: clr.dll P5: 4.0.30319.1008 P6: 517a1be7 P7: 002b7330 P8: c0000409 P9: 00000000 P10: Angefügte Dateien: Diese Dateien befinden sich möglicherweise hier: C:\ProgramData\Microsoft\Windows\WER\ReportQueue\AppCrash_TVService.exe_221ed08e7e97fe2527dbfdd0bd47eb4ce41c9b_0c58d864 Analysesymbol: Es wird erneut nach einer Lösung gesucht: 0 Berichts-ID: 718eb7fb-5236-11e3-8e06-20cf30c42e71 Berichtstatus: 0";"1001"[/collapse]

The crash is in clr.dll, which means that the crash is in a .NET DLL (CLR = common language runtime).

@Owlsroost
How thoroughly was the async logging tested (ie. can we be sure it is not the cause of this crash)?
Is there an easy way to disable it (ie. remove buffering) so we can be sure we got all the log entries before the crash?

 

[Lightning303]

Thanks for your answer.

Im not 100% sure you understood my second post, so i will try again, just so we are all on the same page .

First Post Problem:
Installation went good without any problems. TVService starts with no problem, when trying to tune a channel, the service crashes. I could start the service again and repeat that problem.

Second Post Problem:
I tried reinstalling mediaportal and tv service. The installation itself worked, however my system cant start the tvservice anymore. I get an error message everytime i try to start tvservice (including during the installation).

So right now, with Problem #2, i cant even check if Problem #1 is still there, i have also no idea if these 2 problems are linked or indepented from eachother.

 

[Owlsroost]

@Owlsroost
How thoroughly was the async logging tested (ie. can we be sure it is not the cause of this crash)?
Is there an easy way to disable it (ie. remove buffering) so we can be sure we got all the log entries before the crash?

I've been running TsWriter versions with async logging for about 7 months on my HTPC without any problems at all, and it's basically the same code that's been in TsReader for much longer (and it's derived from the dshowhelper async logging code which has been in use for years).

I could easily make it poll much faster (or possibly fire off events to the logging thread whenever a new entry is created).

A TsWriter version built from the https://github.com/MediaPortal/MediaPortal-1/tree/BUG-3947-TSWriter-Deadlock-v2 branch would have the old logging and should be compatible with 1.6.0 (just for testing) ?

 

[Holzi]

@Lightning303
Can you try to run package-mediaportal.exe and package-tvengine.exe which you find in C:\Users\<yourusername>\AppData\Local\Temp\MediaPortal Installation\deploy manually?

Settings should be saved.

AppData is a hidden folder, so you need to make all folders visible.

This won't show the cause of the problem but maybe it works after you have done this.

 

[bribbon]

+1
After the upgrade, it can't start TVService.exe.
The same after I maked a clean install.

I'm getting the same dialog as Lightning303:

 

[Holzi]

Thanks!
Does a re-install help? -> http://wiki.team-mediaportal.com/1_MEDIAPORTAL_1/0_What's_New/1.5.x/Installer_(Deploy_Tool)

Before that please make sure you stopped TVService.exe manually!

 

[mm1352000]

@bribbon
Interesting that you also have a KNC tuner.
Could you try to temporarily remove that tuner, or would that be too much to ask?