[fixed] Client Freeze (1 Viewer)

[wjw]

OK, I had the problem again last night. I recorded two shows finishing at 10.00pm. Recording setting is for +15 mins then shutdown via Powersheduler after 6 mins. We were also watching a recording but finished this just before 10.00. The system shut down OK (at the least, the front panel LED was not on this morning). I have just switched the machine on and see that MP client screen is showing a time of 10.20 last night - and it is frozen (I waited a reasonable time to be quite sure). Task manager tells that MP is not responding so I terminated. As before, debug mode crashes during the collecting system event log data.

This time I have collected all the logs by simply copying the 2 log folders in the MP and TVServer folders see attached zip file.

Hope you can give some idea of what is going on.

 

[jameson_uk]

could do with the event & system log details. if you collect logs via debug mode and choose export existing logs it will do this for you.

It does however appear like your machine shut down before it was ready.

2011-05-07 00:39:13.317000 [PowerEventThread(9)]: Stopcard
2011-05-07 08:15:06.938400 [PowerScheduler poll thread(14)]: ActiveSharesHandler: have not found any matching connections - will allow standby
2011-05-07 08:15:06.954000 [PowerScheduler poll thread(14)]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate
2011-05-07 08:15:06.954000 [PowerScheduler poll thread(14)]: PowerScheduler: Manual system suspend requested by 
2011-05-07 08:15:06.954000 [PowerScheduler poll thread(14)]: PowerScheduler: Concurrent shutdown was ignored: Suspend ; force: False
2011-05-07 08:15:06.954000 [PowerEventThread(9)]: PowerScheduler: System is going to suspend

You have configured power scheduler in both client and TV server with the same settings?

Edit: are you also sure that windows power handling is turned off and there is no other software other than power scheduler that is trying to handle standby??

You have

2011-05-07 00:37:12.957000 [PowerScheduler poll thread(14)]: PowerScheduler: Manual system suspend requested by

which normally means that someone has requested the machine to go to sleep rather than letting powerscheduler put the machine to sleep

 

[Seidelin]

Yes, please use the Debug Mode shortcut to collect logs. It shows an option to collect existing logs. That gives us what we need.

Based on jameson_uk's comments, could you try setting this in Mediaportal Configuration -> Plugins -> Powerscheduler client plugin -> (deactivate) Shutdown client after being idle (x) minutes.
This way TV-Server will handle standby.

And tell us what your setting were here.

 

[wjw]

OK, 2 different events here. First, this morning 8.15 was when I switched on to check. I notice that as soon as I terminate the client with Task Manager, the system suspends, so I restarted but not MP.

What the 00.39.13 time event is - I have absolutely no idea. My Windows power management settings are both never (screen and system). I have powersheduler set for 5 mins in TVServer and 6 in Client. This has never been a problem before. As for other things - this is my HTPC and doesn't get used for anything else.

 

[Seidelin]

Another thing. There is an unhandled exception on resume in your MediaPortal.log. I tried handling that exception - maybe this fixes your issue. Try replacing attached .dll

 

[wjw]

Seidelin

I keep trying to use the debug mode! It crashes every time with the "unhandled exception" error and just hangs there. Just to be clear - I start MP in debug mode and choose the third option - export all available logs. It crashes.

 

[Seidelin]

Sorry, I wasn't aware of this. If this is a general issue happening every time you start MediaPortal Debug Mode, that should be a separate bug report. This is a critical component for us to have working.

 

[wjw]

Every time without fail. Just to absolutely clear about this I have just shut down MP and then from the start menu selected Team MediaPortal>MediaPortal>debug mode. Selected option 3 - export all available logs. The crash occurred again while collecting system eventlog data. The details of the crash are as I reported above in the very first post in this thread.

Edit: I should also have added that I have replaced the dll with your new one and de-activated the Powersheduler shutdown action in the client - I left the plugin active so that the "only when idle on home screen" is still working

 

[elliottmc]

Every time without fail. Just to absolutely clear about this I have just shut down MP and then from the start menu selected Team MediaPortal>MediaPortal>debug mode. Selected option 3 - export all available logs. The crash occurred again while collecting system eventlog data.

Working fine here. Will check more later.

Mark

 

[mm1352000]

From the MediaPortal log it looks to me like MediaPortal doesn't receive any notification that the system has started up again so it remains "frozen". On the occasion when the system woke at midnight we see:

[COLLAPSE]2011-05-07 00:32:11.105000 [Info.][MPMain(1)]: Main: WM_POWERBROADCAST: 18
2011-05-07 00:32:11.105000 [Info.][MPMain(1)]: Main: Windows has resumed from standby or hibernate mode to handle a requested event[/COLLAPSE]

On the TV Server side there is:

[COLLAPSE]2011-05-07 00:32:02.503000 [PowerScheduler Waiter(19)]: PowerScheduler: OnResume
2011-05-07 00:32:02.510000 [PowerEventThread(9)]: TV service PowerEventThread 30
2011-05-07 00:32:02.510000 [PowerEventThread(9)]: TV service PowerEventThread 30
2011-05-07 00:32:11.104000 [PowerEventThread(9)]: OnPowerEvent: PowerStatus: ResumeAutomatic
2011-05-07 00:32:11.105000 [PowerEventThread(9)]: OnPowerEventHandler: PowerStatus: ResumeAutomatic
2011-05-07 00:32:11.106000 [PowerEventThread(9)]: TvController.OnResume()
2011-05-07 00:32:11.107000 [PowerEventThread(9)]: Controller: setup HeartBeat Monitor
2011-05-07 00:32:11.110000 [PowerEventThread(9)]: Scheduler: started
2011-05-07 00:32:11.114000 [HeartBeatMonitor(10)]: Controller: Heartbeat Monitor initiated, max timeout allowed is 30 sec.
2011-05-07 00:32:11.114000 [PowerEventThread(9)]: Scheduler: loaded 45 schedules
2011-05-07 00:32:11.115000 [PowerEventThread(9)]: Scheduler: thread started.
2011-05-07 00:32:11.115000 [PowerEventThread(9)]: DiskManagement: started
2011-05-07 00:32:11.116000 [PowerEventThread(9)]: PowerScheduler: System has resumed automatically from standby[/COLLAPSE]

The TV Server side for the 8am wakeup looks like it is confused between going into suspend again straight away or waking up as planned. In particular:

[COLLAPSE]2011-05-07 00:39:13.317000 [PowerEventThread(9)]: Stopcard
2011-05-07 08:15:06.938400 [PowerScheduler poll thread(14)]: ActiveSharesHandler: have not found any matching connections - will allow standby
2011-05-07 08:15:06.954000 [PowerScheduler poll thread(14)]: PowerScheduler: System is unattended and idle - initiate suspend/hibernate
2011-05-07 08:15:06.954000 [PowerScheduler poll thread(14)]: PowerScheduler: Manual system suspend requested by
2011-05-07 08:15:06.954000 [PowerScheduler poll thread(14)]: PowerScheduler: Concurrent shutdown was ignored: Suspend ; force: False
2011-05-07 08:15:06.954000 [PowerEventThread(9)]: PowerScheduler: System is going to suspend
2011-05-07 08:15:06.954000 [PowerEventThread(9)]: Controller: epg stop
2011-05-07 08:15:06.954000 [PowerEventThread(9)]: PowerScheduler: SetWakeupTimer
2011-05-07 08:15:11.415600 [PowerEventThread(9)]: ActiveSharesHandler: have not found any matching connections - will allow standby
2011-05-07 08:15:11.680800 [PowerEventThread(9)]: PowerScheduler: Set wakeup timer to wakeup system in 43.80532 minutes
2011-05-07 08:15:11.680800 [PowerEventThread(9)]: OnPowerEvent: PowerStatus: ResumeAutomatic
2011-05-07 08:15:11.680800 [PowerEventThread(9)]: OnPowerEventHandler: PowerStatus: ResumeAutomatic
2011-05-07 08:15:11.680800 [PowerEventThread(9)]: TvController.OnResume()
[/COLLAPSE]

First line is a reference. Then we see that the ActiveSharesHandler thinks it is okay to suspend and is about to do it when PowerScheduler remembers that it has only just woken up...

Any thoughts?