1.10.0 TV Service not recording weirdness (1 Viewer)

[rahewitt]

Hi,

I have been using MediaPortal since at least December 2012 and am full of admiration for it and the developers and supporters.

Recently I have been experiencing some weirdness with it failing to record after a while of the PC being in use without a reboot.

I have just updated to 1.10.0 after quite some time frozen at 1.9.pre. The problem I'm encountering also occurred with 1.9.pre.

MediaPortal is fine for a while, going to hibernation, waking up recording, etc. If however I use it to watch lots of recordings, then delete them, watch a few more, exit to the OS (Win 7-32bit), reload MediaPortal for example, this is when the fun starts.

Last night the PC failed to wake up to record a programme. I didn't think anything of it (as it was a repeat) and woke the PC this morning to see the list of scheduled recording events. Sure enough there was one in 5 minutes time. This never recorded. The TV service looked OK as it usually generates errors when viewing the EPG if there are issues. I went to Mediaportal TV server config and tried to manually stop the service and the application crashed. I have posted the resultant logs.

Please can anyone give me any advice or pointers how to cure this issue. I am happy with the system, but could do with losing this issue. I am also having issues with MPEG2 playback stuttering, but not MPEG4 wierdly and I think I am also rarely having the issue referred to here: https://forum.team-mediaportal.com/threads/mp1-4578-crashes-after-standby.128997/ but haven't applied the hotfix yet. These are really just side issues though.

 

[mm1352000]

Hello

First, thanks for the report.

Last night the PC failed to wake up to record a programme. I didn't think anything of it (as it was a repeat) and woke the PC this morning to see the list of scheduled recording events. Sure enough there was one in 5 minutes time. This never recorded.

Please can you specify the channel name, program name, date and time of the recordings that you want us to look at.

As far as I can see there were 6 successful recordings since 29 December 5:30 PM:
[2014-12-29 17:59:35,685] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record Channel 4 HD 17:59-20:00 The Italian Job
[2014-12-29 19:28:10,707] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record ITV HD 19:28-20:00 Coronation Street
[2014-12-29 19:58:04,467] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record Channel 4 HD 19:58-21:00 Mini Cooper: For the Love of Cars
[2014-12-29 19:58:05,400] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record BBC FOUR HD 19:58-21:00 New: Royal Institution Christmas...
[2014-12-29 20:28:14,495] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record ITV HD 20:28-21:00 Coronation Street
[2014-12-29 21:58:10,667] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record BBC THREE HD 21:58-22:30 Bad Education

I went to Mediaportal TV server config and tried to manually stop the service and the application crashed. I have posted the resultant logs.

Windows application event logs say that TV Server config crashed due to a problem with the PowerScheduler plugin:
[collapse]"30/12/2014 10:39:58";".NET Runtime";"(0)";"Error";"Application: SetupTv.exe
Framework Version: v4.0.30319
Description: The process was terminated due to an unhandled exception.
Exception Info: System.NullReferenceException
Stack:
Server stack trace: at TvEngine.PowerScheduler.PowerScheduler.GetCurrentState(Boolean refresh, Boolean& unattended, Boolean& disAllowShutdown, String& disAllowShutdownHandler, DateTime& nextWakeupTime, String& nextWakeupHandler) at System.Runtime.Remoting.Messaging.StackBuilderSink._PrivateProcessMessage(IntPtr md, Object[] args, Object server, Object[]& outArgs) at System.Runtime.Remoting.Messaging.StackBuilderSink.SyncProcessMessage(IMessage msg)
at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessage(System.Runtime.Remoting.Messaging.IMessage, System.Runtime.Remoting.Messaging.IMessage)
at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(System.Runtime.Remoting.Proxies.MessageData ByRef, Int32)
at TvEngine.PowerScheduler.Interfaces.IPowerController.GetCurrentState(Boolean, Boolean ByRef, Boolean ByRef, System.String ByRef, System.DateTime ByRef, System.String ByRef)
at PowerScheduler.Setup.PowerSchedulerSetup.RefreshStatus()
at PowerScheduler.Setup.PowerSchedulerSetup.RefreshStatusThread()
at System.Threading.ThreadHelper.ThreadStart_Context(System.Object)
at System.Threading.ExecutionContext.RunInternal(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object, Boolean)
at System.Threading.ExecutionContext.Run(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object, Boolean)
at System.Threading.ExecutionContext.Run(System.Threading.ExecutionContext, System.Threading.ContextCallback, System.Object)
at System.Threading.ThreadHelper.ThreadStart()
";"1026"
"30/12/2014 10:40:00";"Application Error";"Application Crashing Events";"Error";"Faulting application name: SetupTv.exe, version: 1.10.0.0, time stamp: 0x548c1087 Faulting module name: KERNELBASE.dll, version: 6.1.7601.18409, time stamp: 0x531599f6 Exception code: 0xe0434352 Fault offset: 0x0000812f Faulting process id: 0x14d4 Faulting application start time: 0x01d0241ce424fbb5 Faulting application path: C:\Program Files\Team MediaPortal\MediaPortal TV Server\SetupTv.exe Faulting module path: C:\Windows\system32\KERNELBASE.dll Report Id: 34253ed2-9010-11e4-ac72-0016e68cb81c";"1000"
"30/12/2014 10:41:12";"Windows Error Reporting";"(0)";"Information";"Fault bucket 166510772, type 22 Event Name: AppHangB1 Response: Not available Cab Id: 0 Problem signature: P1: MediaPortal.exe P2: 1.10.0.0 P3: 548c1054 P4: bcb4 P5: 2048 P6: P7: P8: P9: P10: Attached files: C:\Users\Richard\AppData\Local\Temp\WERFEA7.tmp.appcompat.txt C:\Users\Richard\AppData\Local\Temp\WERFF83.tmp.WERInternalMetadata.xml These files may be available here: C:\Users\Richard\AppData\Local\Microsoft\Windows\WER\ReportArchive\Critical_MediaPortal.exe_9f66c741ff904e549563d8a990f32a4316cb972b_05670de3 Analysis symbol: Rechecking for solution: 0 Report Id: 5c52ffe3-9010-11e4-ac72-0016e68cb81c Report Status: 0";"1001"
"30/12/2014 10:41:12";"Application Hang";"(101)";"Error";"The program MediaPortal.exe version 1.10.0.0 stopped interacting with Windows and was closed. To see if more information about the problem is available, check the problem history in the Action Center control panel. Process ID: 16c0 Start Time: 01d0241d00c628a1 Termination Time: 16 Application Path: C:\Program Files\Team MediaPortal\MediaPortal\MediaPortal.exe Report Id: ";"1002"
"30/12/2014 10:41:27";"Windows Error Reporting";"(0)";"Information";"Fault bucket 166367341, type 22 Event Name: CLR20r3 Response: Not available Cab Id: 94536121777 Problem signature: P1: setuptv.exe P2: 1.10.0.0 P3: 548c1087 P4: mscorlib P5: 4.0.30319.18444 P6: 52717edc P7: 538a P8: 0 P9: System.NullReferenceException P10: Attached files: C:\Users\Richard\AppData\Local\Temp\WERF729.tmp.WERInternalMetadata.xml C:\Users\Richard\AppData\Local\Temp\WER1026.tmp.appcompat.txt C:\Users\Richard\AppData\Local\Temp\WER1120.tmp.mdmp These files may be available here: C:\ProgramData\Microsoft\Windows\WER\ReportArchive\AppCrash_setuptv.exe_b357c9ece129431ddba0981ef7442737a66fa3_cab_18934ac4 Analysis symbol: Rechecking for solution: 0 Report Id: 34253ed2-9010-11e4-ac72-0016e68cb81c Report Status: 8";"1001"[/collapse]

I'm not able to assist you with that.

Regards,
mm

 

[rahewitt]

Hi and thanks mm1352000,

Regarding the PowerSheduler plugin, should I even be still using this any more, I have a vague recollection of this being partially or fully replaced by MediaPortal functionality?

It should have woke to record a programme at 10:40 this morning 30/12/2014 called Great Continental Railway Journeys on BBC2 HD, however the PC was on with me looking at the scheduled recording list at this time. It should have woken up last night (29/12/2014) to record The Wrong Mans, BBC2 HD, but didn't.

Regards,
Richard

 

[mm1352000]

Hello again Richard

Regarding the PowerSheduler plugin, should I even be still using this any more, I have a vague recollection of this being partially or fully replaced by MediaPortal functionality?

In the past there was a "built-in" PS plugin, as well as a third party PS plugin with additional functionality. In a previous version of MP the "built-in" plugin was replaced with the third party plugin. Basically an upgrade. As long as you're not using an old version of the plugin, there's no reason not to be using it.

It should have woke to record a programme at 10:40 this morning 30/12/2014 called Great Continental Railway Journeys on BBC2 HD, however the PC was on with me looking at the scheduled recording list at this time.

Indeed, the wakeup time was set for 10:37 AM when the server went to sleep on 29 December:
[2014-12-29 23:00:16,420] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 30/12/2014 10:37:00

It should have woken up last night (29/12/2014) to record The Wrong Mans, BBC2 HD, but didn't.

The server was awake already. Log says the scheduler recognised it was time to record but blocked waiting for a cancelled tune process to complete:

[collapse][2014-12-29 22:58:13,501] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record BBC TWO HD 22:58-00:00 The Wrong Mans
[2014-12-29 22:58:13,501] [Log ] [scheduler thread] [INFO ] - Controller: find free card for channel BBC TWO HD
[2014-12-29 22:58:13,501] [Log ] [scheduler thread] [INFO ] - Controller: find card for channel BBC TWO HD
[2014-12-29 22:58:13,501] [Log ] [scheduler thread] [INFO ] - Controller: got 1 tuning details for BBC TWO HD
[2014-12-29 22:58:13,501] [Log ] [scheduler thread] [INFO ] - Controller: channel #1 DVBT:tv: BBC TWO HD Freq:626000 ONID:9018 TSID:16517 SID:17472 PMT:0x64 FTA:True LCN:102 BandWidth:8
[2014-12-29 22:58:13,501] [Log ] [scheduler thread] [INFO ] - Controller: card:1 type:RadioWebStream cannot tune to channel
[2014-12-29 22:58:13,501] [Log ] [scheduler thread] [INFO ] - Controller: card:2 typevbIP cannot tune to channel
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - Controller: card:9 typevbT can tune to channel
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - Controller: card:10 typevbT can tune to channel
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - Controller: card:5 typevbT can tune to channel
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - Controller: card:6 typevbT can tune to channel
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - Controller: found 4 card(s) for channel
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 8 msec
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - Controller: found 4 free card(s)
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 9 msec
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [DEBUG] - CardReservationBase.HasUserHigherPriorityThanBlockingUser: True - user 'scheduler2993' with prio=100 vs blocking user 'epg' with prio=1
[2014-12-29 22:58:13,511] [Log ] [scheduler thread] [INFO ] - card: WaitForCancelledTuneToFinish card: 5 / subch: 0[/collapse]

This explains why subsequent recordings also didn't start.
The tune process that the scheduler was waiting for relates to the DVB EPG grabber. The DVB EPG grabber also locked up here waiting for your tuner to respond to a command:
[2014-12-29 22:46:02,307] [Log ] [DVB EPG timer] [INFO ] - dvbtopGraph

In other words, a tuner lockup ("deadlock") caused the EPG grabber to lockup, which in turn caused the scheduler to lockup.
I've seen similar tuner lockups before:
https://forum.team-mediaportal.com/threads/tv-recordings-not-working-until-reboot.127547/
https://forum.team-mediaportal.com/threads/recording-failed-to-start.128150/
https://forum.team-mediaportal.com/threads/mp1-10-bug-radio-bug.128783/

In short, you can solve the tuner lockups (and by so doing, the missed recordings) by configuring the DVB EPG grabber appropriately or switching to a different EPG source. If you choose to stick with the DVB EPG grabber, the advice in this post may help:
https://forum.team-mediaportal.com/threads/mp1-10-bug-radio-bug.128783/#post-1115033

Regards,
mm

 

[rahewitt]

Thanks for the links and resources. Plenty of stuff for me to think about. Coincidently, the linked thread on the third link was to a thread I started on the TBS forums about BSODs (still unanswered) isn't the t'internet a small place?

Thanks again. I may be back for further clarification.

 

[rahewitt]

A quick update. Have made the changes suggested in the linked threads. Now only grabbing the EPG from a couple of channels, not ALL of them. Had no recurrence yet of the refusal to record.

Touch wood I've also had no BSODs either, so maybe all those channels trying to collect an EPG together was causing them? Shame really as I've been hassling TBS by email about them.

 

[mm1352000]

Thanks for reporting back. I'm always happy to hear positive results.

Touch wood I've also had no BSODs either, so maybe all those channels trying to collect an EPG together was causing them?

I don't know, but quite possibly. In theory trying to tune to the same channel over and over (or even tuning to different channels lots of times) as the EPG grabber was doing shouldn't cause a BSOD with a good driver. I think of the EPG grabber as unintentionally stress testing the tuner... and to get a BSOD is not a good outcome. If I were TBS I would want to know about this.