[confirm] Lockup when stopping LiveTV (1 Viewer)

[doveman]

MediaPortal Version: v1.2RC
MediaPortal Skin: StreamedMP
Windows Version: Win7 Ult 64
CPU Type: Athlon II X4 630
HDD: 2TB Samsung F4
Memory: 4GB DDR2 1066Mhz
Motherboard: Biostar TA790GXBE
Video Card: HD6950 2GB
Video Card Driver: 11.7
Sound Card: onboard Realtek AC97 (ALC662)
Sound Card AC3: no AC3
Sound Card Driver: 6.01.6410
1. TV Card: Hauppauge Nova-T 500
1. TV Card Type: DVB-T
1. TV Card Driver: 4.3.27240
MPEG2 Video Codec: Cyberlink PDVD11
MPEG2 Audio Codec: ffdshow
h.264 Video Codec: ffdshow
Satelite/CableTV Provider:
HTPC Case: Custom
Cooling: Scythe 120mm Bottom Intake Fan, Stock CPU HSF
Power Supply: OCZ StealthStream 400W
Remote: Nova-T 500
TV: Sony XBR800 36"
TV - HTPC Connection: DVI

When stopping LiveTV at 00:16:24 the picture froze whilst the sound continued and this carried on for some time and MP would not respond to the remote or keyboard. Then it stopped but displayed what appeared to be the Home screen but with only the right pop-up menu visible (it was still not responsive) and eventually it seemed like it reset the skin and showed the normal Home screen and I was able to minimise MP at 00:16:58, some 34 seconds later.

 

[mm1352000]

Hi doveman

TV Server stopped:

[COLLAPSE]2011-09-16 00:16:24.756290 [(3)]: Controller: StopTimeShifting 2

2011-09-16 00:16:24.760290 [(3)]: card Nova-DT DVB-T Tuner (Dev1 Path0) : StopTimeShifting user:X4 sub:0

2011-09-16 00:16:24.762291 [(3)]: card: StopTimeShifting user:X4 sub:0

2011-09-16 00:16:24.763291 [(3)]: card not IDLE - removing user: X4

2011-09-16 00:16:24.764291 [(3)]: user:X4 remove

2011-09-16 00:16:25.035306 [(3)]: subch:0-0 tswriter StopTimeshifting...

2011-09-16 00:16:25.037306 [(3)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False

2011-09-16 00:16:25.038306 [(3)]: DVB subch:0 Decompose()

2011-09-16 00:16:25.040306 [(3)]: FreeSubChannel CA: freeing sub channel : 0

2011-09-16 00:16:25.040306 [(3)]: tvcard:FreeSubChannel : no subchannels present, pausing graph

2011-09-16 00:16:25.041307 [(3)]: dvbauseGraph called

2011-09-16 00:16:25.041307 [(3)]: tvcard:FreeAllSubChannels

2011-09-16 00:16:25.042307 [(3)]: dvbauseGraph

2011-09-16 00:16:25.046307 [(3)]: dvbauseGraph called

2011-09-16 00:16:25.046307 [(3)]: tvcard:FreeAllSubChannels

2011-09-16 00:16:25.047307 [TS_File_Cleanup(19)]: card: delete timeshift files R:\\live2-0.ts

2011-09-16 00:16:25.047307 [(3)]: dvbauseGraph filterstate already paused, returning.

2011-09-16 00:16:25.048307 [(3)]: user:X4 remove

2011-09-16 00:16:25.048307 [TS_File_Cleanup(19)]: card: trying to delete R:\live2-0.ts.tsbuffer

2011-09-16 00:16:25.049307 [(3)]: Controller:Timeshifting stopped on card:2

2011-09-16 00:16:25.049307 [(3)]: RTSP: remove stream stream2.0[/COLLAPSE]

MP stopped too:

[COLLAPSE]2011-09-16 00:16:24.383269 [Info.][MPMain(1)]: Main: Stopping media

2011-09-16 00:16:24.383269 [Debug][MPMain(1)]: g_Player.doStop() keepTimeShifting = False keepExclusiveModeOn = False

2011-09-16 00:16:24.383269 [Info.][MPMain(1)]: g_Player.OnStopped()

2011-09-16 00:16:24.384269 [Info.][MPMain(1)]: RadioRecorded:OnPlayRecordingBackStopped TV R:\\live2-0.ts.tsbuffer

2011-09-16 00:16:24.389269 [Info.][MPMain(1)]: TVHome:turn tv off

2011-09-16 00:16:25.299321 [Info.][MPMain(1)]: TvRecorded:OnPlayRecordingBackStopped TV R:\\live2-0.ts.tsbuffer

2011-09-16 00:16:25.299321 [Debug][MPMain(1)]: g_Player.doStop() - stop

2011-09-16 00:16:25.300321 [Debug][MPMain(1)]: TSReaderPlayer: Saving subtitle index: 0

2011-09-16 00:16:25.300321 [Info.][MPMain(1)]: TSReaderPlayer: cleanup DShow graph False

2011-09-16 00:16:25.596338 [Debug][MPMain(1)]: VMR9: Dispose

2011-09-16 00:16:25.596338 [Debug][MPMain(1)]: SubtitleRenderer: starting cleanup

2011-09-16 00:16:25.597338 [Debug][MPMain(1)]: SubtitleRenderer: cleanup done

2011-09-16 00:16:25.597338 [Debug][MPMain(1)]: VMR9: Inactive

2011-09-16 00:16:25.597338 [Info.][MPMain(1)]: Disconnecting all pins from filter MediaPortal DVBSub2

2011-09-16 00:16:25.598338 [Debug][MPMain(1)]: Remove filter from graph: MediaPortal DVBSub2 2

2011-09-16 00:16:25.598338 [Info.][MPMain(1)]: Disconnecting all pins from filter Enhanced Video Renderer

2011-09-16 00:16:25.610339 [Debug][MPMain(1)]: Remove filter from graph: Enhanced Video Renderer 2

2011-09-16 00:16:25.610339 [Info.][MPMain(1)]: Disconnecting all pins from filter Default DirectSound Device

2011-09-16 00:16:25.611339 [Debug][MPMain(1)]: Remove filter from graph: Default DirectSound Device 0

2011-09-16 00:16:25.611339 [Info.][MPMain(1)]: Disconnecting all pins from filter Line 21 Decoder 2

2011-09-16 00:16:25.612339 [Info.][MPMain(1)]: Disconnecting pin XForm Out

2011-09-16 00:16:25.612339 [Info.][MPMain(1)]: Not connected

2011-09-16 00:16:25.612339 [Debug][MPMain(1)]: Remove filter from graph: Line 21 Decoder 2 0

2011-09-16 00:16:25.613339 [Info.][MPMain(1)]: Disconnecting all pins from filter ffdshow Audio Decoder

2011-09-16 00:16:25.613339 [Info.][MPMain(1)]: Disconnecting pin Out

2011-09-16 00:16:25.613339 [Info.][MPMain(1)]: Not connected

2011-09-16 00:16:25.624340 [Debug][MPMain(1)]: Remove filter from graph: ffdshow Audio Decoder 0

2011-09-16 00:16:25.624340 [Info.][MPMain(1)]: Disconnecting all pins from filter CyberLink Video Decoder (PDVD11)

2011-09-16 00:16:25.624340 [Info.][MPMain(1)]: Disconnecting pin Video Out

2011-09-16 00:16:25.625340 [Info.][MPMain(1)]: Not connected

2011-09-16 00:16:25.625340 [Info.][MPMain(1)]: Disconnecting pin ~Closed Caption Out

2011-09-16 00:16:25.625340 [Info.][MPMain(1)]: Not connected

2011-09-16 00:16:25.627340 [Debug][MPMain(1)]: Remove filter from graph: CyberLink Video Decoder (PDVD11) 0

2011-09-16 00:16:25.627340 [Info.][MPMain(1)]: Disconnecting all pins from filter TsReader

2011-09-16 00:16:25.628340 [Info.][MPMain(1)]: Disconnecting pin Audio

2011-09-16 00:16:25.628340 [Info.][MPMain(1)]: Not connected

2011-09-16 00:16:25.628340 [Info.][MPMain(1)]: Disconnecting pin Video

2011-09-16 00:16:25.628340 [Info.][MPMain(1)]: Not connected

2011-09-16 00:16:25.629340 [Info.][MPMain(1)]: Disconnecting pin Subtitle

2011-09-16 00:16:25.629340 [Info.][MPMain(1)]: Not connected

2011-09-16 00:16:25.633340 [Debug][MPMain(1)]: Remove filter from graph: TsReader 0

2011-09-16 00:16:25.707345 [Info.][MPMain(1)]: TSReaderPlayer: Disabling DX9 exclusive mode

2011-09-16 00:16:25.707345 [Info.][MPMain(1)]: TSReaderPlayer: Disabling DX9 exclusive mode[/COLLAPSE]

The sluggishness seems to be here:

[COLLAPSE]2011-09-16 00:16:25.707345 [Info.][MPMain(1)]: TSReaderPlayer: Disabling DX9 exclusive mode

2011-09-16 00:16:25.710345 [Debug][MPMain(1)]: Windowmanager: Goto previous window

2011-09-16 00:16:25.710345 [Debug][MPMain(1)]: Windowmanager: Goto previous window

2011-09-16 00:16:25.710345 [Debug][MPMain(1)]: Windowmanager: Goto previous window

2011-09-16 00:16:25.711345 [Debug][MPMain(1)]: Windowmanager: Goto previous window

2011-09-16 00:16:25.711345 [Debug][MPMain(1)]: Windowmanager: Goto previous window

2011-09-16 00:16:25.947358 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Home.GUIHome deinit

2011-09-16 00:16:25.949358 [Debug][MPMain(1)]: TextureManager: CleanupThumbs()

2011-09-16 00:16:26.183372 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 200 (window property: lblDate)

2011-09-16 00:16:26.184372 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 201 (window property: lblTime)

2011-09-16 00:16:26.184372 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 99 (window property: videoWindow)

2011-09-16 00:16:26.198373 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Home.GUIHome init

2011-09-16 00:16:26.198373 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Home.GUIHome deinit

2011-09-16 00:16:26.199373 [Debug][MPMain(1)]: TextureManager: CleanupThumbs()

2011-09-16 00:16:26.208373 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 200 (window property: lblDate)

2011-09-16 00:16:26.209373 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 201 (window property: lblTime)

2011-09-16 00:16:26.209373 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 99 (window property: videoWindow)

2011-09-16 00:16:26.224374 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Home.GUIHome init

2011-09-16 00:16:26.224374 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Home.GUIHome deinit

2011-09-16 00:16:26.226374 [Debug][MPMain(1)]: TextureManager: CleanupThumbs()

2011-09-16 00:16:26.235375 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 200 (window property: lblDate)

2011-09-16 00:16:26.236375 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 201 (window property: lblTime)

2011-09-16 00:16:26.236375 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\myHome.xml' is missing control id 99 (window property: videoWindow)

2011-09-16 00:16:26.416385 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Home.GUIHome init

2011-09-16 00:16:26.416385 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Home.GUIHome deinit

2011-09-16 00:16:26.418385 [Debug][MPMain(1)]: TextureManager: CleanupThumbs()

2011-09-16 00:16:29.754576 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Pictures.GUIPictures init

2011-09-16 00:16:29.780578 [Debug][MPMain(1)]: TextureManager: CleanupThumbs()

2011-09-16 00:16:29.782578 [Info.][MPMain(1)]: open folderdatabase

2011-09-16 00:16:49.507706 [Debug][MPMain(1)]: TextureManager: CleanupThumbs()[/COLLAPSE]

My guess is that an HDD went to sleep while you were watching and had to be rewoken/spun up. Did you check the HDD activity?

mm

 

[doveman]

Hi

Thanks for taking a look at the logs. I don't believe my (only) HDD went to sleep but I was extracting files from an ISO at the time, so perhaps that was enough to slow MP down. I have my timeshifting files on a RAMdisk though, so don't really understand why TV should have struggled to stop and didn't think that MP needed much HD access to just display the EPG or Home screen.

If other programs accessing the drive will cause so much trouble for MP, I guess I should avoid doing other things whilst MP is running, or get another drive to run other programs from, although I'm sure I've had Usenet downloads running in the background before without MP being affected. I guess extracting from an ISO was more intensive though as it had to read and write to the same HD simultaneously.

 

[mm1352000]

Hi doveman

Yes, extracting an ISO is quite intensive - significantly more intensive than a download anyway. It seems like it was the access to the video DB that was the slow thing, not the stopping of timeshifting (so no problem with the RAM drive).

mm

 

[SilentException]

Isn't the correct question to ask here "why was My Pictures plugin opened after playback"? Fact is that database open lasted a while but take a look at all these

Window: XXX init
Window: XXX deinit

and

Windowmanager: Goto previous window

log lines. They shouldn't be there. Should be TVFullScreen deinit, XXX init, finito. Because of all the "Goto previous window" log lines I suspect it might be a stuck key?

 

[doveman]

Isn't the correct question to ask here "why was My Pictures plugin opened after playback"? Fact is that database open lasted a while but take a look at all these

Window: XXX init
Window: XXX deinit

and

Windowmanager: Goto previous window

log lines. They shouldn't be there. Should be TVFullScreen deinit, XXX init, finito. Because of all the "Goto previous window" log lines I suspect it might be a stuck key?

I think that can probably be explained by my pressing various keys (mainly Stop and Home) to try and get a response from MP. I don't believe I pressed the Pictures button but occasisionally the remote sensor picks up a phantom/incorrect command.

Nonetheless, I still don't really understand why TV playback didn't stop promptly and the picture froze whilst sound continued for some time.

 

[doveman]

This might be connected, which is why I'm posting here rather than starting a new thread.

Firstly I should say that I've noticed a load of MySQL errors in the Application_eventlog.csv, so perhaps that indicates the source of the problem. There's a lot of errors in the tvserver_error.log as well.

LiveTV was working fine last night. I left the PC on overnight but stopped LiveTV. When I went to start it again this morning, I got sound but a blank screen. I then stopped it and minimised MP to the tray and when I tried to restore it, I got a blank background menu with no text, etc. I then had to kill MP from the taskmanager and restart it, after which LiveTV worked OK.

EDIT: I think I've sorted this by running "mysqlcheck mptvdb --auto-repair -e -u root -p" so fingers crossed that will reduce the problems I've been having.