Skip behaviour

[Bobins]

On V4 during playback if Skip forward or backward was used, the skip was usually smooth and audio/video in sync.

On my new V6 build I'm seeing different behaviour (for which I'm getting verbal grief from the main system user[wife  ])

When skip is pressed the playback jumps and audio starts but the video is out-of-sync for 2-5 seconds.

Sometimes when pressing skip, the playback freezes for 20-30 seconds then suddenly jumps to the correct point.

Obviously if playback hangs like this the user (not being technical) starts pressing buttons on the remote (thinking playback has been paused) and this leads to other issues.

I have the latest LAV (0.77.2) installed and selected for playback (which is pretty well the same as was in use on my old V4 system).

Are there any tweaks that I should make to the LAV config to improve this or is this likely to be a NVPVR issue?

I've had a look through the logs and can't see anything that looks odd around the Skip timings although I'll will get the wife to note the exact time when it happens again so I can extract the relevant section from the log if that will help.

[mvallevand]

We like to look at the logs too. We can't really do anything without them.

Martin

[Bobins]

  NPVR.zip (Size: 5.07 KB / Downloads: 2)
It is happening again this morning. Pressing Skip forwards (or back) the playback freezes for 30-40 seconds then resumes at the skip point.

I can't see anything odd in the logs but have attached.

the Skip was triggered at about 11:53:18

I also see "File in use. Maybe still recording." in the log but this was a completed recording from yesterday.

[mvallevand]

It doesn't look like you have the LAV Video drivers installed.

Martin

[Bobins]

What's in the log that indicates that?

I definitely have the LAV software installed and it's shown/selected in the setup screen

It didn't show up there when I did the build until I installed LAV.

[mvallevand]

It shows you using Microsoft video. Maybe you installed LAV 64 bit.

Code:

023-04-29 11:52:27.226    [DEBUG][1]    Graph filter list:
2023-04-29 11:52:27.226    [DEBUG][1]     - NPVR PES Collector (Teletext)
2023-04-29 11:52:27.226    [DEBUG][1]     - NPVR PES Collector (DVBSubs)
2023-04-29 11:52:27.226    [DEBUG][1]     - EVR Renderer
2023-04-29 11:52:27.226    [DEBUG][1]     - AC3 Audio Renderer
2023-04-29 11:52:27.226    [DEBUG][1]     - Microsoft DTV-DVD Video Decoder
2023-04-29 11:52:27.226    [DEBUG][1]     - LAV Audio Decoder
2023-04-29 11:52:27.227    [DEBUG][1]     - NPVR TS Reader 5

Martin

[Bobins]

I didn't chose a specific LAV 32/64 bit. The installer decided so I don't necessarily know which one's there.

Why would this need 32bit on a 64bit machine?

Can I manually install 32 bit as well as 64bit and if I do, will NVPVR select the right one?

[mvallevand]

NextPVR.exe is a 32 bit application and can only use 32 bit decoders. If it is installed and configured for all video types NextPVR should use it.

Martin

[Bobins]

I'll go back and manually check the LAV install (once it's finished recording Celebrity Mastermind) Fiddling with the system at the moment would get me shot 

[Bobins]

Ok double checked everything and the LAV install.
Looks like 32bit & 64 bit LAV are installed.

The playback now shows

Graph filter list:
2023-04-29 18:45:49.199 [DEBUG][1] - NPVR PES Collector (Teletext)
2023-04-29 18:45:49.199 [DEBUG][1] - EVR Renderer
2023-04-29 18:45:49.199 [DEBUG][1] - AC3 Audio Renderer
2023-04-29 18:45:49.199 [DEBUG][1] - LAV Video Decoder
2023-04-29 18:45:49.199 [DEBUG][1] - LAV Audio Decoder
2023-04-29 18:45:49.199 [DEBUG][1] - NPVR TS Reader 5

Still getting this intermittent freeze on skip.

Extract of log shows Skip at 19:19:17 where playback of audio/video freezes.
Gave up waiting for recovery at 19:21:23 and pressed the Stop key.
Can't see any obvious error in the log.

Is LAV used for playback of .TS files?

-29 19:19:13.313 [DEBUG][1] Force update on element: Timeline:00:26:58:00:59:01
2023-04-29 19:19:14.312 [DEBUG][1] Redrawn timeline
2023-04-29 19:19:14.312 [DEBUG][1] Force update on element: Timeline:00:26:59:00:59:01
2023-04-29 19:19:14.691 [DEBUG][1] Hiding OSD.
2023-04-29 19:19:17.553 [DEBUG][1] appcommand: 50 +(lParam: 10320000, wParam: 180344)
2023-04-29 19:19:17.553 [DEBUG][1] Skipping to position: 1612.2586256
2023-04-29 19:19:17.553 [DEBUG][1] Duration: 3541
2023-04-29 19:19:17.553 [DEBUG][1] SetPosition(1612.258667)
2023-04-29 19:19:18.046 [DEBUG][1] SetPosition@exit
2023-04-29 19:19:18.047 [DEBUG][1] Redrawn timeline
2023-04-29 19:19:18.048 [DEBUG][1] Force update on element: Timeline:00:26:52:00:59:01
2023-04-29 19:19:18.127 [DEBUG][1] raw message: 0xB4
2023-04-29 19:19:18.127 [DEBUG][1] Not processing: 0xB4
2023-04-29 19:19:18.128 [DEBUG][1] raw message: 0x0
2023-04-29 19:19:18.128 [DEBUG][1] Not processing: 0x0
2023-04-29 19:19:18.809 [DEBUG][1] Redrawn timeline
2023-04-29 19:19:18.809 [DEBUG][1] Force update on element: Timeline:00:26:53:00:59:01
2023-04-29 19:19:19.804 [DEBUG][1] Redrawn timeline
2023-04-29 19:19:19.804 [DEBUG][1] Force update on element: Timeline:00:26:54:00:59:01
2023-04-29 19:19:20.813 [DEBUG][1] Redrawn timeline
2023-04-29 19:19:20.813 [DEBUG][1] Force update on element: Timeline:00:26:55:00:59:01
2023-04-29 19:19:21.804 [DEBUG][1] Redrawn timeline
2023-04-29 19:19:21.805 [DEBUG][1] Force update on element: Timeline:00:26:56:00:59:01
2023-04-29 19:19:22.058 [DEBUG][1] Hiding OSD.
2023-04-29 19:19:49.400 [DEBUG][13] PlaybackPositionHelper.DeletePlaybackPosition(R:\Recordings\Harlots\Harlots_20200916_21502245.ts)
2023-04-29 19:19:49.604 [DEBUG][1] PreventMonitorStandby() needs implementation
2023-04-29 19:20:49.413 [DEBUG][20] PlaybackPositionHelper.DeletePlaybackPosition(R:\Recordings\Harlots\Harlots_20200916_21502245.ts)
2023-04-29 19:20:49.617 [DEBUG][1] PreventMonitorStandby() needs implementation
2023-04-29 19:21:18.263 [DEBUG][1] appcommand: 47 +(lParam: 102F0000, wParam: 180344)
2023-04-29 19:21:18.263 [DEBUG][1] PreventMonitorStandby() needs implementation
2023-04-29 19:21:18.266 [DEBUG][1] Redrawn timeline
2023-04-29 19:21:18.267 [DEBUG][1] Force update on element: MetaData
2023-04-29 19:21:18.267 [DEBUG][1] Force update on element: Timeline:00:28:52:00:59:01
2023-04-29 19:21:18.405 [DEBUG][1] Pause()
2023-04-29 19:21:18.465 [DEBUG][1] GetEventCode() returned: 0x000e
2023-04-29 19:21:18.465 [DEBUG][1] raw message: 0xB1
2023-04-29 19:21:18.465 [DEBUG][1] Not processing: 0xB1
2023-04-29 19:21:18.710 [DEBUG][1] raw message: 0x0
2023-04-29 19:21:18.710 [DEBUG][1] Not processing: 0x0
2023-04-29 19:21:20.773 [DEBUG][1] appcommand: 46 +(lParam: 102E0000, wParam: 180344)
2023-04-29 19:21:20.773 [DEBUG][1] Play()
2023-04-29 19:21:20.793 [DEBUG][1] raw message: 0xB0
2023-04-29 19:21:20.793 [DEBUG][1] Not processing: 0xB0
2023-04-29 19:21:21.181 [DEBUG][1] Redrawn timeline
2023-04-29 19:21:21.181 [DEBUG][1] Force update on element: Timeline:00:28:53:00:59:01
2023-04-29 19:21:21.242 [DEBUG][1] raw message: 0x0
2023-04-29 19:21:21.242 [DEBUG][1] Not processing: 0x0
2023-04-29 19:21:22.184 [DEBUG][1] Redrawn timeline
2023-04-29 19:21:22.184 [DEBUG][1] Force update on element: Timeline:00:28:54:00:59:01
2023-04-29 19:21:23.193 [DEBUG][1] raw message: 0xB7
2023-04-29 19:21:23.193 [DEBUG][1] Not processing: 0xB7
2023-04-29 19:21:23.193 [DEBUG][1] appcommand: 13 +(lParam: D0000, wParam: 180344)
2023-04-29 19:21:23.193 [DEBUG][1] About to store playback position information
2023-04-29 19:21:23.193 [DEBUG][1] PlaybackPositionHelper.DeletePlaybackPosition(R:\Recordings\Harlots\Harlots_20200916_21502245.ts)
2023-04-29 19:21:23.205 [DEBUG][1] Stop@0
2023-04-29 19:21:23.205 [DEBUG][1] Stopping graph@1
2023-04-29 19:21:23.273 [DEBUG][1] Stopping graph@2
2023-04-29 19:21:23.273 [DEBUG][1] Stopping graph@3
2023-04-29 19:21:23.318 [DEBUG][1] Removing filter  NPVR PES Collector (Teletext)
2023-04-29 19:21:23.318 [DEBUG][1] Removing filter  EVR Renderer
2023-04-29 19:21:23.334 [DEBUG][1] Removing filter  AC3 Audio Renderer
2023-04-29 19:21:23.335 [DEBUG][1] Removing filter  LAV Video Decoder
2023-04-29 19:21:23.335 [DEBUG][1] Removing filter  LAV Audio Decoder
2023-04-29 19:21:23.335 [DEBUG][1] Removing filter  NPVR TS Reader 5
2023-04-29 19:21:23.350 [DEBUG][1] PlaybackPositionHelper.GetLastPlaybackDate(R:\Recordings\Harlots\Harlots_20200916_21502245.ts)
2023-04-29 19:21:23.742 [DEBUG][1] raw message: 0x0
2023-04-29 19:21:23.742 [DEBUG][1] Not processing: 0x0
2023-04-29 19:21:26.217 [DEBUG][1] ShowDetailsPopup()
2023-04-29 19: