NextPVR 2.5.9 + XBMC Frodo : LiveTV buffering forever after a few channel changes

[rookie1]

Hi,

I'm using NPVR 2.5.9 with a HD Homerun and connecting to that with XBMC Frodo (tried both Beta 2 and Beta 3) with sub's NextPVR plugin for XBMC.
I also installed the patch from here: http://forums.nextpvr.com/showthread.php...er-changes

To get LiveTV streaming in XBMC working at all I have to disable Timeshift. But even with timeshift disabled I run into problems:
The first channel tune usually works fine but after the second or third channel change at latest I see the buffering dialogue showing up and stuck at 0% or 2%.
I have to completely stop/quite XBMC and reopen it to get it working.

In the example below I tuned successfully to SYFD (StartStream(7546:SYFYD)) and then to TBS (StartStream(7545:TBS HD) - failed) and then A&E HD (StartStream(7544:A&E HD) - also failed).

In the nrecord log i see the following:

2012-12-10 21:24:41.118 [DEBUG][20] Allocated port: 36318
2012-12-10 21:24:41.118 [DEBUG][20] HDHomeRunRecorder@3
2012-12-10 21:24:41.118 [DEBUG][20] SetTarget@2
2012-12-10 21:24:41.121 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/vchannel 737
2012-12-10 21:24:41.224 [DEBUG][20] NIC: Microsoft Virtual Machine Bus Network Adapter
2012-12-10 21:24:41.225 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/target rtp://192.168.1.127:36318
2012-12-10 21:24:41.226 [DEBUG][20] SetTarget@2
2012-12-10 21:24:41.226 [DEBUG][20] SetTarget
2012-12-10 21:24:41.226 [DEBUG][20] SetTarget@2
2012-12-10 21:24:41.432 [DEBUG][20] SetTarget@exit
2012-12-10 21:24:41.432 [DEBUG][20] stopping previous handle
2012-12-10 21:24:41.633 [DEBUG][20] Renew lease ok (0x140003)
2012-12-10 21:24:41.634 [DEBUG][20] Renew lease ok (0x140003)
2012-12-10 21:24:46.648 [DEBUG][20] Renew lease ok (0x140003)
2012-12-10 21:24:51.709 [DEBUG][20] Renew lease ok (0x140003)
2012-12-10 21:24:56.728 [DEBUG][20] Renew lease ok (0x140003)
2012-12-10 21:25:01.836 [DEBUG][20] Renew lease ok (0x140003)
2012-12-10 21:25:06.302 [DEBUG][20] StartStream(7546:SYFYD)
2012-12-10 21:25:06.303 [DEBUG][20] checking if previous capture source can be reused
2012-12-10 21:25:06.303 [DEBUG][20] checking if previous capture source can be reused
2012-12-10 21:25:06.303 [DEBUG][20] HDHomeRunRecorder@1
2012-12-10 21:25:06.303 [DEBUG][20] HDHomeRunRecorder@2.0
2012-12-10 21:25:06.303 [DEBUG][20] HDHomeRunRecorder@2.1
2012-12-10 21:25:06.303 [DEBUG][20] HDHomeRunRecorder@2.2 nextPort=36319
2012-12-10 21:25:06.303 [DEBUG][20] HDHomeRunRecorder@2.2.0
2012-12-10 21:25:06.303 [DEBUG][20] HDHomeRunRecorder@2.2.0@allocated
2012-12-10 21:25:06.303 [DEBUG][20] HDHomeRunRecorder@2.3
2012-12-10 21:25:06.303 [DEBUG][20] Allocated port: 36319
2012-12-10 21:25:06.303 [DEBUG][20] HDHomeRunRecorder@3
2012-12-10 21:25:06.303 [DEBUG][20] SetTarget@2
2012-12-10 21:25:06.303 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/vchannel 736
2012-12-10 21:25:06.391 [DEBUG][20] NIC: Microsoft Virtual Machine Bus Network Adapter
2012-12-10 21:25:06.392 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/target rtp://192.168.1.127:36319
2012-12-10 21:25:06.393 [DEBUG][20] SetTarget@2
2012-12-10 21:25:06.393 [DEBUG][20] SetTarget
2012-12-10 21:25:06.393 [DEBUG][20] SetTarget@2
2012-12-10 21:25:06.647 [DEBUG][20] SetTarget@exit
2012-12-10 21:25:06.647 [DEBUG][20] stopping previous handle
2012-12-10 21:25:06.849 [DEBUG][20] Renew lease ok (0x140004)
2012-12-10 21:25:06.850 [DEBUG][20] Renew lease ok (0x140004)
2012-12-10 21:25:11.861 [DEBUG][20] Renew lease ok (0x140004)
2012-12-10 21:25:17.240 [DEBUG][20] Renew lease ok (0x140004)
2012-12-10 21:25:22.276 [DEBUG][20] Renew lease ok (0x140004)
2012-12-10 21:25:27.305 [DEBUG][20] Renew lease ok (0x140004)
2012-12-10 21:25:32.707 [DEBUG][20] StartStream(7545:TBS HD)
2012-12-10 21:25:32.707 [DEBUG][20] checking if previous capture source can be reused
2012-12-10 21:25:32.707 [DEBUG][20] checking if previous capture source can be reused
2012-12-10 21:25:32.707 [DEBUG][20] HDHomeRunRecorder@1
2012-12-10 21:25:32.707 [DEBUG][20] HDHomeRunRecorder@2.0
2012-12-10 21:25:32.707 [DEBUG][20] HDHomeRunRecorder@2.1
2012-12-10 21:25:32.707 [DEBUG][20] HDHomeRunRecorder@2.2 nextPort=36320
2012-12-10 21:25:32.707 [DEBUG][20] HDHomeRunRecorder@2.2.0
2012-12-10 21:25:32.707 [DEBUG][20] HDHomeRunRecorder@2.2.0@allocated
2012-12-10 21:25:32.707 [DEBUG][20] HDHomeRunRecorder@2.3
2012-12-10 21:25:32.707 [DEBUG][20] Allocated port: 36320
2012-12-10 21:25:32.707 [DEBUG][20] HDHomeRunRecorder@3
2012-12-10 21:25:32.707 [DEBUG][20] SetTarget@2
2012-12-10 21:25:32.707 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/vchannel 735
2012-12-10 21:25:32.768 [DEBUG][20] NIC: Microsoft Virtual Machine Bus Network Adapter
2012-12-10 21:25:32.769 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/target rtp://192.168.1.127:36320
2012-12-10 21:25:32.770 [DEBUG][20] SetTarget@2
2012-12-10 21:25:32.770 [DEBUG][20] SetTarget
2012-12-10 21:25:32.770 [DEBUG][20] SetTarget@2
2012-12-10 21:25:33.024 [DEBUG][20] SetTarget@exit
2012-12-10 21:25:33.024 [DEBUG][20] stopping previous handle
2012-12-10 21:25:33.227 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:25:33.227 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:25:38.229 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:25:43.233 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:25:48.273 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:25:53.606 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:25:58.609 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:26:04.017 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:26:09.102 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:26:14.112 [DEBUG][20] Renew lease ok (0x140005)
2012-12-10 21:26:16.722 [DEBUG][20] StartStream(7544:A&E HD)
2012-12-10 21:26:16.722 [DEBUG][20] checking if previous capture source can be reused
2012-12-10 21:26:16.722 [DEBUG][20] checking if previous capture source can be reused
2012-12-10 21:26:16.722 [DEBUG][20] HDHomeRunRecorder@1
2012-12-10 21:26:16.722 [DEBUG][20] HDHomeRunRecorder@2.0
2012-12-10 21:26:16.722 [DEBUG][20] HDHomeRunRecorder@2.1
2012-12-10 21:26:16.722 [DEBUG][20] HDHomeRunRecorder@2.2 nextPort=36321
2012-12-10 21:26:16.722 [DEBUG][20] HDHomeRunRecorder@2.2.0
2012-12-10 21:26:16.722 [DEBUG][20] HDHomeRunRecorder@2.2.0@allocated
2012-12-10 21:26:16.722 [DEBUG][20] HDHomeRunRecorder@2.3
2012-12-10 21:26:16.722 [DEBUG][20] Allocated port: 36321
2012-12-10 21:26:16.722 [DEBUG][20] HDHomeRunRecorder@3
2012-12-10 21:26:16.722 [DEBUG][20] SetTarget@2
2012-12-10 21:26:16.722 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/vchannel 734
2012-12-10 21:26:16.922 [DEBUG][20] NIC: Microsoft Virtual Machine Bus Network Adapter
2012-12-10 21:26:16.923 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/target rtp://192.168.1.127:36321
2012-12-10 21:26:16.924 [DEBUG][20] SetTarget@2
2012-12-10 21:26:16.924 [DEBUG][20] SetTarget
2012-12-10 21:26:16.924 [DEBUG][20] SetTarget@2
2012-12-10 21:26:17.115 [DEBUG][20] SetTarget@exit
2012-12-10 21:26:17.115 [DEBUG][20] stopping previous handle
2012-12-10 21:26:17.317 [DEBUG][20] Renew lease ok (0x140006)
2012-12-10 21:26:17.318 [DEBUG][20] Renew lease ok (0x140006)
2012-12-10 21:26:22.555 [DEBUG][20] Renew lease ok (0x140006)
2012-12-10 21:26:30.787 [DEBUG][20] SetTarget@2
2012-12-10 21:26:34.787 [DEBUG][20] Graph stopping...
2012-12-10 21:26:34.947 [DEBUG][20] Graph stopped
2012-12-10 21:26:34.947 [DEBUG][20] Removing filter NPVR Writer
2012-12-10 21:26:34.947 [DEBUG][20] Removing filter NPVR Net Push Source
2012-12-10 21:26:35.048 [DEBUG][20] Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 13126458 set /tuner0/channel none

Any idea?

[Lewi11]

I am having the same issue.

[typoknig]

Same issue here. Tried using XBMC 12 RC1 and all nightlies after it. Using latest NPVR with the most recent patches. Anyone found a solution to this problem yet?

[sub]

If you two guys can also post your logs, I'll take a look.

What type of capture device are you using?

[mvallevand]

I think the problem is that there are two approaches to PVRs in XBMC, one uses a sophisticated demux in the client and the other like NextPVR relies on the general XBMC player to demux the ts stream. I don't think in live tv the demux is properly cleared between different channel formats using the remote or OSD arrows (I tried to highligth that in some videos yesterday). On the digital tuner going between 480i 720p and 1080i I have issues on linux. It almost always works when you stop and start a channel from the guide. If there was a way for pvr.nextpvr to reset the demux on changes it would probably be more reliable.

Martin

[sub]

If there was a way for pvr.nextpvr to reset the demux on changes it would probably be more reliable.

NextPVR has no visibility of the demux that XBMC uses. It just passes it transport stream data when requested.

[mvallevand]

I know what I saying is the more advanced clients do.

Martin

[sub]

I know what I saying is the more advanced clients do.

None using the XBMC demux have control over it.

When I implemented the XBMC addon, I had the choice of using of either using the built-in XBMC demux and getting timeshift support in the near future, or my own demux with only support for live tv and no plans for timeshift. I obviously went for the former, which provides more features users want.

They've since added support for timeshifting when you implement your own demux. I think only TvHeadEnd supports this at this stage. I will probably move to this approach, but it was added way too late in the Frodo release cycle, so I'm not going to be able to add it until post-Frodo.

[bassic]

FWIW timeshifting seems ok for me with the latest xbmc nightly, no buffering issues either. Running hdhomerun tuners on win7 with npvr up to date. There seems to be rapid improvements in the xbmc nightlies recently along with the introduction of the odd new bug occasionally.

[typoknig]

If you two guys can also post your logs, I'll take a look.

What type of capture device are you using?

I'm using a HDHomeRun Prime (HDHR3-CC).

I went to generate a log for you but now I cannot get the problem to occur. In fact, I have never had channel switching using NPVR as a backend for XBMC work as well as it is working right now.

I'm using the attached patches with XBMC nightly build found here.

Thanks for all your hard work on this Sub. Now if I can just get EPG to work correctly