PS sometimes not waking up HTPC for recordings (1 Viewer)

[The_Stig]

Hey guys,

powerscheduler++ is unregularly not waking up my htpc. In the logs I havent found any clue why though I have quite a few error messages in there. Anyone an idea? For the attached logs there was a schedule planned yesterday for 19.30 with a pre-record time of 7 minutes but htpc did not wake up.

@mm1352000, do you maybe see anything?

 

[mm1352000]

Hello mate

It looks like the TV service crashed a few minutes before 19:30. Here's the critical sequence in the logs:
[collapse]
[2015-03-08 19:21:33,121] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread received WM_POWERBROADCAST 4
[2015-03-08 19:21:33,124] [Log ] [PowerEventThread] [DEBUG] - OnPowerEvent: PowerStatus: Suspend
[2015-03-08 19:21:33,126] [Log ] [PowerEventThread] [DEBUG] - OnPowerEventHandler: PowerStatus: Suspend
[2015-03-08 19:21:33,128] [Log ] [PowerEventThread] [INFO ] - TvController.OnSuspend()
[2015-03-08 19:21:33,140] [Log ] [PowerEventThread] [INFO ] - Scheduler: stopped
[2015-03-08 19:21:33,189] [Log ] [PowerEventThread] [DEBUG] - Scheduler: thread stopped.
[2015-03-08 19:21:33,192] [Log ] [PowerEventThread] [INFO ] - Stopcard
[2015-03-08 19:21:33,195] [Log ] [PowerEventThread] [INFO ] - Stopcard
[2015-03-08 19:21:33,197] [Log ] [PowerEventThread] [INFO ] - FreeSubChannel MD: tried to free non existing sub channel : 0
[2015-03-08 19:21:33,201] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0
[2015-03-08 19:21:33,204] [Log ] [PowerEventThread] [INFO ] - DVB subch:0 Decompose()
[2015-03-08 19:21:33,206] [Log ] [PowerEventThread] [INFO ] - FreeSubChannel CA: freeing sub channel : 0
[2015-03-08 19:21:33,208] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph
[2015-03-08 19:21:33,210] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph called
[2015-03-08 19:21:33,212] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-03-08 19:21:33,214] [Log ] [PowerEventThread] [INFO ] - mdplug: FreeAllChannels
[2015-03-08 19:21:33,217] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph
[2015-03-08 19:21:33,224] [Log ] [PowerEventThread] [INFO ] - debug: IMediaControl stopped! hr = 0x0
[2015-03-08 19:21:33,237] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph called
[2015-03-08 19:21:33,240] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-03-08 19:21:33,243] [Log ] [PowerEventThread] [INFO ] - mdplug: FreeAllChannels
[2015-03-08 19:21:33,245] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph filterstate already stopped, returning.
[2015-03-08 19:21:33,247] [Log ] [PowerEventThread] [INFO ] - Stopcard
[2015-03-08 19:21:33,250] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph called
[2015-03-08 19:21:33,252] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-03-08 19:21:33,254] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph filterstate already stopped, returning.
[2015-03-08 19:21:33,256] [Log ] [PowerEventThread] [INFO ] - Stopcard
[2015-03-08 19:21:33,259] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph called
[2015-03-08 19:21:33,262] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-03-08 19:21:33,265] [Log ] [PowerEventThread] [INFO ] - dvbtopGraph filterstate already stopped, returning.
[2015-03-08 19:21:33,267] [Log ] [PowerEventThread] [DEBUG] - PS: SUSPEND
[2015-03-08 19:21:33,269] [Log ] [PowerEventThread] [DEBUG] - PS: System is going to suspend
[2015-03-08 19:21:33,271] [Log ] [PowerEventThread] [DEBUG] - PS: Signal "Suspend" to StandbyWakeupThread
[2015-03-08 19:21:33,273] [Log ] [PowerEventThread] [DEBUG] - PS: Stop EPG grabbing and TV controller
[2015-03-08 19:21:33,275] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread suspended
[2015-03-08 19:21:33,278] [Log ] [PowerEventThread] [INFO ] - Controller: epg stop
[2015-03-08 19:21:33,281] [EPG ] [PowerEventThread] [INFO ] - EPG: grabber stopped..
[2015-03-08 19:21:33,284] [EPG ] [PowerEventThread] [INFO ] - EpgCard: card: 3 stop grabbing
[2015-03-08 19:21:33,286] [EPG ] [PowerEventThread] [INFO ] - EpgGrabbing: Stop - user epg
[2015-03-08 19:21:33,288] [Log ] [PowerEventThread] [INFO ] - user:epg remove
[2015-03-08 19:21:33,290] [Log ] [PowerEventThread] [DEBUG] - PS: DeInit controller
[2015-03-08 19:21:33,292] [Log ] [PowerEventThread] [INFO ] - Controller: DeInit.
[2015-03-08 19:21:33,295] [Log ] [PowerEventThread] [INFO ] - Controller: stop streamer...
[2015-03-08 19:21:33,297] [Log ] [PowerEventThread] [INFO ] - RTSP: stop streamer
[2015-03-08 19:21:33,301] [Log ] [PowerEventThread] [INFO ] - RTSP: stop all streams (0)
[2015-03-08 19:21:33,304] [Log ] [PowerEventThread] [INFO ] - Controller: streamer stopped...
[2015-03-08 19:21:33,306] [Log ] [PowerEventThread] [INFO ] - Controller: stop thumb processor...
[2015-03-08 19:21:33,308] [Log ] [PowerEventThread] [INFO ] - ThumbProcessor.Stop()
[2015-03-08 19:21:33,310] [Log ] [PowerEventThread] [INFO ] - Controller: thumb processor stopped...
[2015-03-08 19:21:33,313] [Log ] [PowerEventThread] [INFO ] - Controller: stop scheduler...
[2015-03-08 19:21:33,315] [Log ] [PowerEventThread] [INFO ] - Scheduler: stopped
[2015-03-08 19:21:33,319] [Log ] [PowerEventThread] [INFO ] - Controller: scheduler stopped...
[2015-03-08 19:21:33,322] [Log ] [PowerEventThread] [INFO ] - Controller: epg stop
[2015-03-08 19:21:33,325] [Log ] [PowerEventThread] [INFO ] - Controller: dispose card:RadioWebStream Card (builtin)
[2015-03-08 19:21:33,327] [Log ] [PowerEventThread] [INFO ] - RadioWebStreamispose()
[2015-03-08 19:21:33,329] [Log ] [PowerEventThread] [INFO ] - Controller: dispose card:MediaPortal IPTV Source Filter
[2015-03-08 19:21:33,331] [Log ] [PowerEventThread] [INFO ] - Controller: dispose card:FireDTV BDA Tuner DVBC
[2015-03-08 19:21:33,333] [Log ] [PowerEventThread] [INFO ] - dvbecompose
[2015-03-08 19:21:33,335] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-03-08 19:21:33,338] [Log ] [PowerEventThread] [INFO ] - stop
[2015-03-08 19:21:33,442] [Log ] [PowerEventThread] [INFO ] - Closing MDAPI Plugins
[2015-03-08 19:21:33,445] [Log ] [PowerEventThread] [DEBUG] - Closing MDAPI plugin instance : FireDTV BDA Receiver DVBC0
[2015-03-08 19:21:33,447] [Log ] [PowerEventThread] [INFO ] - Disposing ConditionalAccess
[2015-03-08 19:21:33,449] [Log ] [PowerEventThread] [INFO ] - free...
[2015-03-08 19:21:33,451] [Log ] [PowerEventThread] [INFO ] - free pins...
[2015-03-08 19:21:33,454] [Log ] [PowerEventThread] [INFO ] - free graph...
[2015-03-08 19:21:33,457] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: BDA MPEG2 Transport Information Filter
[2015-03-08 19:21:33,460] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: MediaPortal Ts Analyzer
[2015-03-08 19:21:33,468] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: MPEG2-Demultiplexer
[2015-03-08 19:21:33,470] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: Inf Tee
[2015-03-08 19:21:33,472] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: mdapifilter0
[2015-03-08 19:21:33,476] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: Inf Tee Second
[2015-03-08 19:21:33,479] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: FireDTV BDA Receiver DVBC
[2015-03-08 19:21:33,483] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: FireDTV BDA Tuner DVBC
[2015-03-08 19:21:33,486] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: DVBC Network Provider
[2015-03-08 19:21:33,488] [Log ] [PowerEventThread] [INFO ] - free devices...
[2015-03-08 19:21:33,491] [Log ] [PowerEventThread] [INFO ] - decompose done...
[2015-03-08 19:21:33,493] [Log ] [PowerEventThread] [INFO ] - Controller: dispose card:TBS 6981 BDA DVBS/S2 B Tuner/Demod
[2015-03-08 19:21:33,495] [Log ] [PowerEventThread] [INFO ] - dvbecompose
[2015-03-08 19:21:33,498] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-03-08 19:21:33,501] [Log ] [PowerEventThread] [INFO ] - stop
[2015-03-08 19:21:33,605] [Log ] [PowerEventThread] [INFO ] - Disposing ConditionalAccess
[2015-03-08 19:21:33,607] [Log ] [PowerEventThread] [INFO ] - free...
[2015-03-08 19:21:33,609] [Log ] [PowerEventThread] [INFO ] - free pins...
[2015-03-08 19:21:33,611] [Log ] [PowerEventThread] [INFO ] - free graph...
[2015-03-08 19:21:33,614] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: BDA MPEG2 Transport Information Filter
[2015-03-08 19:21:33,616] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: MediaPortal Ts Analyzer
[2015-03-08 19:21:33,625] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: MPEG2-Demultiplexer
[2015-03-08 19:21:33,627] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: Inf Tee
[2015-03-08 19:21:33,630] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: TBS 6981 DVBS/S2 B AVStream TS Capture
[2015-03-08 19:21:33,632] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: TBS 6981 BDA DVBS/S2 B Tuner/Demod
[2015-03-08 19:21:33,635] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: Generic Network Provider
[2015-03-08 19:21:33,638] [Log ] [PowerEventThread] [INFO ] - free devices...
[2015-03-08 19:21:33,641] [Log ] [PowerEventThread] [INFO ] - decompose done...
[2015-03-08 19:21:33,644] [Log ] [PowerEventThread] [INFO ] - Controller: dispose card:TBS 6981 BDA DVBS/S2 A Tuner/Demod
[2015-03-08 19:21:33,646] [Log ] [PowerEventThread] [INFO ] - dvbecompose
[2015-03-08 19:21:33,648] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
[2015-03-08 19:21:33,650] [Log ] [PowerEventThread] [INFO ] - stop
[2015-03-08 19:21:33,753] [Log ] [PowerEventThread] [INFO ] - Disposing ConditionalAccess
[2015-03-08 19:21:33,755] [Log ] [PowerEventThread] [INFO ] - free...
[2015-03-08 19:21:33,758] [Log ] [PowerEventThread] [INFO ] - free pins...
[2015-03-08 19:21:33,761] [Log ] [PowerEventThread] [INFO ] - free graph...
[2015-03-08 19:21:33,764] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: BDA MPEG2 Transport Information Filter
[2015-03-08 19:21:33,766] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: MediaPortal Ts Analyzer
[2015-03-08 19:21:33,773] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: MPEG2-Demultiplexer
[2015-03-08 19:21:33,775] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: Inf Tee
[2015-03-08 19:21:33,779] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: TBS 6981 DVBS/S2 A AVStream TS Capture
[2015-03-08 19:21:33,782] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: TBS 6981 BDA DVBS/S2 A Tuner/Demod
[2015-03-08 19:21:33,785] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: Generic Network Provider
[2015-03-08 19:21:33,787] [Log ] [PowerEventThread] [INFO ] - free devices...
[2015-03-08 19:21:33,789] [Log ] [PowerEventThread] [INFO ] - decompose done...
[2015-03-08 19:21:33,791] [Log ] [PowerEventThread] [INFO ] - Stopping async device detection...
[2015-03-08 19:21:33,795] [Log ] [PowerEventThread] [INFO ] - Stopping async device detection...
[2015-03-08 19:21:33,799] [Log ] [PowerEventThread] [INFO ] - RadioWebStreamispose()
[2015-03-08 19:21:33,802] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: DVB-T Network Provider
[2015-03-08 19:21:33,805] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: DVB-S Network Provider
[2015-03-08 19:21:33,807] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: DVB-C Network Provider
[2015-03-08 19:21:33,810] [Log ] [PowerEventThread] [INFO ] - Remove filter from graph: ATSC Network Provider
[2015-03-08 19:21:33,813] [Log ] [PowerEventThread] [DEBUG] - PS: Run external command
[2015-03-08 19:21:33,815] [Log ] [PowerEventThread] [DEBUG] - PS: Send "EnteringStandby" event
[2015-03-08 19:21:34,568] [Log ] [34 ] [DEBUG] - PS: UnregisterRemote StandbyHandler 3
[2015-03-08 19:21:34,571] [Log ] [34 ] [DEBUG] - PS: UnregisterRemote WakeupHandler 3
[2015-03-08 19:21:34,763] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files \\Htpc\C\timeshift\live3-0.ts
[2015-03-08 19:21:34,766] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer
[2015-03-08 19:21:34,770] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer
[2015-03-08 19:21:34,772] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer1.ts
[2015-03-08 19:21:34,791] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer1.ts
[2015-03-08 19:21:34,793] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer2.ts
[2015-03-08 19:21:34,810] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer2.ts
[2015-03-08 19:21:34,812] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer3.ts
[2015-03-08 19:21:34,830] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer3.ts
[2015-03-08 19:21:34,832] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer4.ts
[2015-03-08 19:21:34,850] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer4.ts
[2015-03-08 19:21:34,852] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer5.ts
[2015-03-08 19:21:34,869] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer5.ts
[2015-03-08 19:21:34,873] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer6.ts
[2015-03-08 19:21:34,889] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer6.ts
[2015-03-08 19:21:34,892] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer7.ts
[2015-03-08 19:21:34,908] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer7.ts
[2015-03-08 19:21:34,911] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete \\Htpc\C\timeshift\live3-0.ts.tsbuffer8.ts
[2015-03-08 19:21:34,925] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file \\Htpc\C\timeshift\live3-0.ts.tsbuffer8.ts
[2015-03-08 19:21:41,760] [Log ] [DVB EPG timer] [DEBUG] - WaitForPMT: Timed out waiting for PMT after 10.0135118 seconds. Increase the PMT timeout value?
[2015-03-08 19:21:41,760] [Log ] [DVB EPG timer] [DEBUG] - Setting to 0 to search for new PMT.
[2015-03-08 19:21:41,760] [Log ] [DVB EPG timer] [INFO ] - subch:0 SetupPmtGrabberid 0 sid:175
[2015-03-08 19:21:41,760] [Log ] [DVB EPG timer] [DEBUG] - FireDTV: Starting new CI handler thread
[2015-03-08 19:21:41,760] [Log ] [DVB EPG timer] [INFO ] - subch:0 set pmt grabber pmt:0 sid:175
[2015-03-08 19:21:41,760] [Log ] [FireDTV CiMenuHandler] [DEBUG] - FireDTV: CI handler thread start polling status
[2015-03-08 19:21:41,760] [Log ] [DVB EPG timer] [INFO ] - tvcard:FreeSubChannel: subchannels count 0 subch#0
[2015-03-08 19:21:41,776] [Log ] [DVB EPG timer] [INFO ] - tvcard:FreeSubChannel :0 - sub channel not found
[2015-03-08 19:21:41,776] [Log ] [DVB EPG timer] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph
[2015-03-08 19:21:41,776] [Log ] [DVB EPG timer] [INFO ] - dvbtopGraph called
[2015-03-08 19:21:41,776] [Log ] [DVB EPG timer] [INFO ] - tvcard:FreeAllSubChannels
[2015-03-08 19:21:41,791] [Log ] [DVB EPG timer] [ERROR] - Exception ystem.Runtime.InteropServices.InvalidComObjectException: COM object that has been separated from its underlying RCW cannot be used.
at System.StubHelpers.StubHelpers.GetCOMIPFromRCW(Object objSrc, IntPtr pCPCMD, IntPtr& ppTarget, Boolean& pfNeedsRelease)
at TvLibrary.Interfaces.Analyzer.ITsFilter.PmtSetCallBack(Int32 handle, IPMTCallback callback)
at TvLibrary.Implementations.DVB.TvDvbChannel.SetupPmtGrabber(Int32 pmtPid, Int32 serviceId)
at TvLibrary.Implementations.DVB.TvDvbChannel.WaitForPMT()
at TvLibrary.Implementations.DVB.TvDvbChannel.OnGraphStarted()
at TvLibrary.Implementations.DVB.TvCardDvbBase.RunGraph(Int32 subChannel)
at TvLibrary.Implementations.DVB.TvCardDvbBase.DoTune(Int32 subChannelId, IChannel channel, Boolean ignorePMT)
at TvLibrary.Implementations.DVB.TvCardDVBC.Tune(Int32 subChannelId, IChannel channel)
[2015-03-08 19:21:41,823] [Log ] [FireDTV CiMenuHandler] [INFO ] - Tvservice stopped due to an unhandled app domain exception System.Runtime.InteropServices.InvalidComObjectException: COM object that has been separated from its underlying RCW cannot be used.
at TvLibrary.Implementations.DVB.DigitalEverywhere.CiMenuHandler()
at System.Threading.ThreadHelper.ThreadStart_Context(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.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
at System.Threading.ThreadHelper.ThreadStart()
[2015-03-08 19:21:41,823] [Log ] [DVB EPG timer] [ERROR] - Exception ystem.Runtime.InteropServices.InvalidComObjectException: COM object that has been separated from its underlying RCW cannot be used.
at System.StubHelpers.StubHelpers.GetCOMIPFromRCW(Object objSrc, IntPtr pCPCMD, IntPtr& ppTarget, Boolean& pfNeedsRelease)
at TvLibrary.Interfaces.Analyzer.ITsFilter.PmtSetCallBack(Int32 handle, IPMTCallback callback)
at TvLibrary.Implementations.DVB.TvDvbChannel.SetupPmtGrabber(Int32 pmtPid, Int32 serviceId)
at TvLibrary.Implementations.DVB.TvDvbChannel.WaitForPMT()
at TvLibrary.Implementations.DVB.TvDvbChannel.OnGraphStarted()
at TvLibrary.Implementations.DVB.TvCardDvbBase.RunGraph(Int32 subChannel)
at TvLibrary.Implementations.DVB.TvCardDvbBase.DoTune(Int32 subChannelId, IChannel channel, Boolean ignorePMT)
at TvLibrary.Implementations.DVB.TvCardDVBC.Tune(Int32 subChannelId, IChannel channel)
at TvService.CardTuner.Tune(IUser& user, IChannel channel, Int32 idChannel)
[2015-03-08 19:21:41,838] [Log ] [TVService] [INFO ] - TV Service is being stopped
[2015-03-08 19:21:41,838] [Log ] [DVB EPG timer] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=16, tuningdetails=DVBC:tv: Sky Sport 13 Freq:378000 ONID:133 TSID:4 SID:373 PMT:0x6A FTA:True LCN:893 SymbolRate:6900 Modulation:Mod256Qam
[2015-03-08 19:21:41,838] [Log ] [TVService] [INFO ] - TV Service: stopping
[2015-03-08 19:21:41,838] [Log ] [DVB EPG timer] [INFO ] - card: SignalTuneEvent card: 3 / subch: 0
[2015-03-08 19:21:41,838] [Log ] [TVService] [INFO ] - TV Service: Stop plugins
[2015-03-08 19:21:41,854] [EPG ] [DVB EPG timer] [INFO ] - Epg: card:3 could not tune to channel:UnknownError
[2015-03-08 19:21:41,854] [Log ] [TVService] [DEBUG] - BackupSettings Server: Plugin BackupSettings stopped
[2015-03-08 19:21:41,854] [Log ] [TVService] [DEBUG] - BackupSettings Server: ImportWatcher disabled
[2015-03-08 19:21:41,854] [EPG ] [DVB EPG timer] [INFO ] - EpgCard: unable to grab epg transponder: 0 ch: Sky Sport 13 started on -1
[2015-03-08 19:21:41,854] [Log ] [TVService] [DEBUG] - BackupSettings Server: AutomatedExport disabled
[2015-03-08 19:21:41,854] [Log ] [TVService] [DEBUG] - BackupSettings Server: ScheduledExport disabled
[2015-03-08 19:21:41,869] [EPG ] [DVB EPG timer] [INFO ] - DVBC:tv: Sky Sport 13 Freq:378000 ONID:133 TSID:4 SID:373 PMT:0x6A FTA:True LCN:893 SymbolRate:6900 Modulation:Mod256Qam
[2015-03-08 19:21:41,869] [Log ] [TVService] [INFO ] - PS: Stopping PowerScheduler server plugin...
[2015-03-08 19:21:41,885] [Log ] [TVService] [DEBUG] - PS: UnRegistered IPowerEventHandler from GlobalServiceProvider
[2015-03-08 19:21:41,885] [Log ] [TVService] [DEBUG] - PS: Removed standby/wakeup handlers
[2015-03-08 19:21:41,901] [Log ] [TVService] [INFO ] - PS: PowerScheduler server plugin stopped
[2015-03-08 19:21:42,916] [Log ] [TVService] [INFO ] - TV Service: Plugins stopped
[2015-03-08 19:21:42,916] [Log ] [TVService] [INFO ] - TV service StopRemoting
[2015-03-08 19:21:42,932] [Log ] [TVService] [INFO ] - Remoting stopped
[2015-03-08 19:21:42,932] [Log ] [TVService] [INFO ] - Controller: DeInit.
[2015-03-08 19:21:42,932] [Log ] [TVService] [INFO ] - Controller: dispose card:RadioWebStream Card (builtin)
[2015-03-08 19:21:42,932] [Log ] [TVService] [INFO ] - RadioWebStreamispose()
[2015-03-08 19:21:42,932] [Log ] [TVService] [INFO ] - Controller: dispose card:MediaPortal IPTV Source Filter
[2015-03-08 19:21:42,932] [Log ] [TVService] [INFO ] - Controller: dispose card:FireDTV BDA Tuner DVBC
[2015-03-08 19:21:42,948] [Log ] [TVService] [INFO ] - Controller: dispose card:TBS 6981 BDA DVBS/S2 B Tuner/Demod
[2015-03-08 19:21:42,948] [Log ] [TVService] [INFO ] - Controller: dispose card:TBS 6981 BDA DVBS/S2 A Tuner/Demod
[2015-03-08 19:21:42,948] [Log ] [TVService] [INFO ] - Stopping async device detection...
[2015-03-08 19:21:42,948] [Log ] [TVService] [INFO ] - Stopping async device detection...
[2015-03-08 19:21:42,948] [Log ] [TVService] [DEBUG] - TV Service: OnStop asking PowerEventThread to exit
[2015-03-08 19:21:42,963] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread finished
[2015-03-08 19:21:42,963] [Log ] [TVService] [INFO ] - TV Service: stopped[/collapse]

Before I say more, I should say the server was definitely awake (you were watching Sky Bundesliga from 18:42 to 19:21) but it didn't record due to the previously mentioned crash. TV service was not running between 19:21 and 20:11... so no recording.

This situation seems to be caused by bad timing.
Immediately after you stop watching Sky Bundesliga, TV Server starts grabbing EPG for Sky Sport 13.
3 seconds later (still 19:21) PS decides it wants to suspend the PC. It knows about the recording, but I guess it thinks it is okay to sleep for 2 minutes.
So the suspend process starts while the EPG grabber is still tuning. The suspend process tells the EPG grabber to cancel grabbing and disposes the tuner. Note it doesn't wait for the EPG to stop before disposing the tuner. Just tells it to cancel, then immediately carries on.
The problem happens when the EPG grabber receives that cancelation. As part of the stop/cancel process, the EPG grabber (TV library) tries to access resources that are no longer available (because the suspend process cleaned up the tuner). This causes the crash.

 

[Vasilich]

So the suspend process starts while the EPG grabber is still tuning. The suspend process tells the EPG grabber to cancel grabbing and disposes the tuner. Note it doesn't wait for the EPG to stop before disposing the tuner. Just tells it to cancel, then immediately carries on.
The problem happens when the EPG grabber receives that cancelation. As part of the stop/cancel process, the EPG grabber (TV library) tries to access resources that are no longer available (because the suspend process cleaned up the tuner). This causes the crash.

can it be fixed without big rework? maybe some not-null checks in the epg cleanup code? It seems for me as the issue that should be fixed even in code-freezed TVE3.
I had similar issues in the past, that is why i have disabled idle EPG scanner, that has been crashing my TVService every 3-4 days.

 

[The_Stig]

Thanks for your replys guys!

Before I say more, I should say the server was definitely awake

Guess I should enable the power led then again. I was dead sure HTPC was powered off.

TV service was not running between 19:21 and 20:11... so no recording.

But I have in the TVServer-service configuration activated to restart service if faulty/crash. Shouldn't it simply restart the service then?

This causes the crash.

Do you see any workaround for this not beeing not using EPG? Because for these Sky-Channels I don't get EPG-Info elsewhere (at least I don't know a source)

can it be fixed without big rework?

I guess I know the answer from mm to this And I can understand it...

EDIT: And:

3 seconds later (still 19:21) PS decides it wants to suspend the PC. It knows about the recording, but I guess it thinks it is okay to sleep for 2 minutes.

I have a "Pre-No-Standby-Time" of 300 seconds...shouldn't try to sleep then, or?

 

[mm1352000]

can it be fixed without big rework? maybe some not-null checks in the epg cleanup code? It seems for me as the issue that should be fixed even in code-freezed TVE3.
I had similar issues in the past, that is why i have disabled idle EPG scanner, that has been crashing my TVService every 3-4 days.

Yes, it's a nasty issue, but it cannot be fixed quickly and reliably with only not-null checks. It really requires locking to properly protect all access to _tsFilterInterface. Locking is necessary because this issue will only be observed when multiple threads are interacting with the tuner/sub-channel. You have one EPG grabber thread that is in the middle of tuning (in this case waiting for PMT, but it could really be anywhere in the tuning process) and another from PS that is telling the service to suspend. Handling this properly is not so straightforward. Yes it would only take 30 minutes or so to add lock () {} and if ( != null) {} around things... but you have to take time to consider the potential for deadlocks, which are especially possible due to the call backs from TsWriter.

Before I say more, I should say the server was definitely awake

Guess I should enable the power led then again. I was dead sure HTPC was powered off.

Oh, no. What I meant was that the PC was on shortly before the recording. It suspended at ~19:21 and would have remained suspended until some later time.

TV service was not running between 19:21 and 20:11... so no recording.

But I have in the TVServer-service configuration activated to restart service if faulty/crash. Shouldn't it simply restart the service then?

By "it" you mean Windows, right?
I can only tell you what I read in the log files, and what I read is that the service crashed on suspend at 19:21 and did not get restarted until 20:11. Perhaps Windows doesn't automatically restart until resume. I don't know. I didn't write the Windows code.

Do you see any workaround for this not beeing not using EPG? Because for these Sky-Channels I don't get EPG-Info elsewhere (at least I don't know a source)

Use the time-shifting EPG grabber only, and schedule a fake recording every day?

I guess I know the answer from mm to this And I can understand it...

Yeah, the answer is no. I'm not going to do it.

I have a "Pre-No-Standby-Time" of 300 seconds...shouldn't try to sleep then, or?

I'm not able to help you with PS[++] questions.

 

[The_Stig]

By "it" you mean Windows, right?
I can only tell you what I read in the log files, and what I read is that the service crashed on suspend at 19:21 and did not get restarted until 20:11. Perhaps Windows doesn't automatically restart until resume. I don't know. I didn't write the Windows code.

Yeah, I meant windows. But then windows is probably going to resume too fast, so service could not be restartet.

Use the time-shifting EPG grabber only, and schedule a fake recording every day?

Thats a good idea! But I guess these channels are on different transponders. Guess I have to try if a recording on one channel is enough to get the EPG.

I'm not able to help you with PS[++] questions.

Who is this currently, @michael_t ?

 

[CyberSimian]

3 seconds later (still 19:21) PS decides it wants to suspend the PC. It knows about the recording, but I guess it thinks it is okay to sleep for 2 minutes.

I have a "Pre-No-Standby-Time" of 300 seconds...shouldn't try to sleep then, or?

I also have experienced a problem with PS not preventing sleep/hibernation when a recording is about to start (or has just started). There seems to be a small "window of opportunity" (of around one minute or so) during which sleep/hibernation is not prevented. See this post:

https://forum.team-mediaportal.com/...r-mediaportal-1-4.95463/page-115#post-1100656

My problem may (or may not) be the same problem as the one that you are experiencing. I use one minute of pre-padding and four minutes of pre-no-standby (so the HTPC should wake up five minutes before the published programme start time). I have adjusted the timeouts that my HIBERMON tool uses to avoid the window of opportunity when the HTPC first wakes to perform an unattended recording, but the window of opportunity recurs when there are two recordings that don't quite abut, i.e. there is a gap of (say) four minutes between the end of the post-padding for one recording, and the start of the pre-padding for the next recording. Unfortunately there is no adjustment that I can make to my HIBERMON tool to fix this case.

-- from CyberSimian in the UK

 

[The_Stig]

Had another issue yesterday, so guess were going the Powerscheduler-road currently, dunno if anybody in the team is still looking into these issues?!?

Yesterday at around 20.05 htpc was recording. I then turned on the whole system via Harmony Remote (AVR on, TV on, Signal for HTPC on too (tho it was on)). What happened was that htpc was put into standby and instantly turned on again. Shouldn't PS disallow standby whilst an active recording? logs attached.

 

[HTPCSourcer]

What have you configured in Windows power options for the power button? The Harmony will send a "Press Power Button", which, subject to your local settings, will put the HTPC into standby. The scheduler will immediately waje it up to continue recording.

My personal recommendation would be to have "Do nothing" mapped to the power button of the HTPC and let the MePo PowerScheduler manage the HTPC.

 

[CyberSimian]

Yesterday at around 20.05 htpc was recording. I then turned on the whole system via Harmony Remote (AVR on, TV on, Signal for HTPC on too (tho it was on)). What happened was that htpc was put into standby and instantly turned on again. Shouldn't PS disallow standby whilst an active recording? logs attached.

Are you using sleep (power state "S3") or hibernation (power state "S4")?

If you are using sleep, make sure that you have enabled "away mode". When the HTPC wakes up to perform an unattended recording, it will wake up in away mode. (This applies to Win 7/8; Vista does not get this right, and incorrectly wakes up in normal mode.) In away mode, the HTPC will be running, but video and audio output is suppressed. When you use your Harmony, it should switch the HTPC from away mode to normal mode, and enable video and audio output. Away mode can be enabled in the power plan:

Start > Control Panel > Power Options > ...
...Change plan settings > Change advanced power settings

or in PowerScheduler settings in "TV Server Config".

If you are using hibernation, when you used your Harmony it will have hibernated the system immediately. Away mode does not apply to hibernation, and PS cannot prevent the system hibernating (because you explicitly commanded the system to hibernate). Modify the power plan to disable the power button on the remote. You are probably not using hibernation, so I won't elaborate further. Ask if you want more details.

-- from CyberSimian in the UK