MP 1.14.0 Screen Freeze (1 Viewer)

[macmr]

Hi,
After recently fixing a tuner issue I have had a couple of moments where the screen would freeze and nothing can be done within MP about it but a recording being done at the same time still worked ok

I have attached the log of the last problem detailed below so hoping someone can analyse. I couldn't see anything in the windows event log:

1. Approx 1314 I was watching a recording and pausing then going forward or back when the screen froze
2. I already had a another recording started so I didn't touch anything for about 2 hours as I had a suspicion based on a previous freeze that the recording was
still working even though the screen was frozen
3. Hit ctrl+alt+del to see that MP was not responding and closed and this then started MP debug
4. Restarted MP and checked that the previous recording had been completed which it had ok

 

[mm1352000]

Hi

1. Approx 1314 I was watching a recording and pausing then going forward or back when the screen froze

Mmmmm, I think the detail of what you were doing may be important to understanding what happened. So, let me unpack this a bit.

I see you start viewing 7TWO:
[collapse][2016-09-03 13:08:29,780] [Log ] [MPMain ] [INFO ] - TVHome.ViewChannelAndCheck(): View channel=7TWO

[2016-09-03 13:08:29,850] [Log ] [11 ] [INFO ] - Controller: find free card for channel 7TWO
...
[2016-09-03 13:08:30,280] [Log ] [11 ] [INFO ] - card: Tune on card 45 to subchannel 7TWO Wollongong
[2016-09-03 13:08:30,290] [Log ] [11 ] [DEBUG] - card: user: HTPC:45:-1 tune DVBT:tvRIME 7TWO Wollongong Freq:599500 ONID:12929 TSID:2463 SID:2402 PMT:0x1CE FTA:True LCN:62 BandWidth:7
...
[2016-09-03 13:08:30,840] [Log ] [11 ] [INFO ] - card: StartTimeShifting 45 F:\Recorded Tv\Timeshift\live45-0.ts
...
[2016-09-03 13:08:31,090] [Log ] [11 ] [INFO ] - card: WaitForFile - video and audio are seen after 0.2400003 seconds

[2016-09-03 13:08:32,060] [Log ] [MPMain ] [INFO ] - g_Player.OnStarted() F:\Recorded Tv\Timeshift\live45-0.ts.tsbuffer media:TV
[2016-09-03 13:08:32,067] [Log ] [MPMain ] [WARN ] - tvhome:startplay. Phase 2 - 918 ms - Done starting g_Player.Play()
[2016-09-03 13:08:32,071] [Log ] [MPMain ] [DEBUG] - g_Player: ShowFullScreenWindow
[2016-09-03 13:08:32,071] [Log ] [MPMain ] [INFO ] - g_Player: ShowFullScreenWindow switching to fullscreen tv
[2016-09-03 13:08:32,071] [Log ] [MPMain ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 50[/collapse]

You start recording that channel:
[collapse][2016-09-03 13:11:41,834] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+R (10082), Handled: True

[2016-09-03 13:11:44,752] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record 7TWO 1:11 PM-1:29 PM WA Weekender
...
[2016-09-03 13:11:44,805] [Log ] [scheduler thread] [INFO ] - card: Tune on card 45 to subchannel 7TWO Wollongong
[2016-09-03 13:11:44,806] [Log ] [scheduler thread] [DEBUG] - card: user: scheduler52959:45:-1 tune DVBT:tvRIME 7TWO Wollongong Freq:599500 ONID:12929 TSID:2463 SID:2402 PMT:0x1CE FTA:True LCN:62 BandWidth:7
...
[2016-09-03 13:11:44,904] [Log ] [scheduler thread] [INFO ] - card: StartRecording 45 F:\Recorded Tv\WA Weekender - 7TWO - 2016-09-03.ts
...
[2016-09-03 13:11:45,014] [Log ] [scheduler thread] [INFO ] - card: WaitForFile - video and audio are seen after 0.1070061 seconds[/collapse]

To be clear: the effect of doing this is that TV Server now has two streams for the same channel. You're still viewing the live TV stream. The recording is a completely independent stream.

Now I see you pause and play multiple times:
[collapse][2016-09-03 13:12:31,138] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:32,378] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:34,258] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:35,042] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:40,594] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:43,210] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:43,858] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:45,546] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:46,226] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:47,202] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:47,698] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True
...
[2016-09-03 13:12:51,498] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+P (10080), Handled: True[/collapse]

At the end of this series of play/pause actions the stream should be playing.

Now I see some additional interactions which I'm not sure about:
[2016-09-03 13:13:04,490] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+G (10071), Handled: True
[2016-09-03 13:13:04,501] [Log ] [MPMain ] [INFO ] - KeyboardInputPlugin: MapAction thread: 1,MPMain
[2016-09-03 13:13:04,502] [Log ] [MPMain ] [DEBUG] - TvFullScreen:deinit->OSD:Off
[2016-09-03 13:13:04,503] [Log ] [MPMain ] [DEBUG] - Window: TvPlugin.TvFullScreen deinit
[2016-09-03 13:13:06,778] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+G (10071), Handled: True
...
[2016-09-03 13:13:09,290] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+G (10071), Handled: True
[2016-09-03 13:13:11,586] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Back (8), Handled: True
...
[2016-09-03 13:13:32,954] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Right (39), Handled: True
[2016-09-03 13:13:36,898] [Log ] [8 ] [DEBUG] - KeyboardInputPlugin: OnKeyDown: Ctrl+G (10071), Handled: True

It's at this point that the TsReader log starts to show the following line repeated over and over again:
[2016-09-03 13:13:31,778] [13d135e8] [1788] - Buffers : A/V = 0/617, RTSP = -1, A last : 285.901, V Last : 286.495

If I've interpretted the TsReader code and log correctly (I'm not a TsReader expert), it looks like a ton of video chunks/samples have piled up. I'd guess that means that the video decoder/codec has encountered a problem that has caused it to stop decoding the stream.

So in short: my diagnosis (which may be completely wrong!) is that it's a codec problem. It may be linked to a TsReader, video renderer, audio renderer, GPU driver and/or audio hardware driver problem.

Unfortunately that's all I can really say about the situation. Hopefully @Owlsroost can chime in with a confirmation/refutation and/or more detail.


Two questions for you:

1. What were you doing with the CTRL+G sequence?
2. Has this happened before? (If yes, any details you can share about how frequently it occurs and under what circumstances may be important.)

 

[Owlsroost]

It's at this point that the TsReader log starts to show the following line repeated over and over again:
[2016-09-03 13:13:31,778] [13d135e8] [1788] - Buffers : A/V = 0/617, RTSP = -1, A last : 285.901, V Last : 286.495

If I've interpretted the TsReader code and log correctly (I'm not a TsReader expert), it looks like a ton of video chunks/samples have piled up. I'd guess that means that the video decoder/codec has encountered a problem that has caused it to stop decoding the stream.

So in short: my diagnosis (which may be completely wrong!) is that it's a codec problem. It may be linked to a TsReader, video renderer, audio renderer, GPU driver and/or audio hardware driver problem.

Unfortunately that's all I can really say about the situation. Hopefully @Owlsroost can chime in with a confirmation/refutation and/or more detail.

Yes, that would be my diagnosis as well.

 

[macmr]

Ok thanks for the feedback

1. The ctrl+g sequence would be me trying to see if the guide would show after the screen froze. That is also what all the other key commands are around that time so just me trying to see if there is any response from MP
2. Yes I believe it has happened before to my partner so details are sketchy as I didn't have time to fault find. I think she was on the main tv screen and went to change to the screen where it shows all the options like tv on, active recordings etc. Then she selected the recorded programs but as it transitioned the screen froze with the reduced tv image. Same as before a recording still started in this state so only the main MP program seemed to be affected

If it is a codec is there any way to find out what one

Thanks

 

[mm1352000]

1. The ctrl+g sequence would be me trying to see if the guide would show after the screen froze. That is also what all the other key commands are around that time so just me trying to see if there is any response from MP

Right, okay.
If key-presses are still being logged, it suggests to me that only the user interface (ie. what you see) is frozen. Unfortunately this makes it more difficult to determine precisely when the user interface locked up.

Do you remember what was on the screen when it was locked up?
(For next time, a screenshot or photo would be really useful.)

Same as before a recording still started in this state so only the main MP program seemed to be affected

Yep, that's as expected. TV Server (the TV service) handles tuners and recording. It's a completely separate process/application. In the same way that recordings can be created while MP isn't open/running, recordings can also be created while MP is frozen.

If it is a codec is there any way to find out what one

As indicated in my previous reply: it looks like the video codec (in your case: the LAV video decoder codec) stopped decoding.

 

[macmr]

Ok thanks if it happens again I will get a screenshot. I believe I saw an update to the LAV decoders so I will grab that as well. Also just reading this - https://forum.team-mediaportal.com/threads/guide-perfect-playback-display-calibration.119610/ so might try a few things as well when I get time

Much appreciated

 

[Owlsroost]

Did you try pressing 'stop' (or 'b' on the keyboard) when playback froze ?

 

[macmr]

No I did not do that but I will keep it in mind for next time

Thanks