NextPVR intermittently breaks connection with Kodi clients due to data starvation

[jmooremcc]

I'm running NextPVR on a Windows 10 back-end.
Lately, I've been experiencing "Tuner No Longer Available" Errors sporadically when running Kodi on my Raspberry PIs with the NextPVR Addon.

PI#3: Kodi/17.6 (X11; Linux armv6l) LibreELEC/8.2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6-Git:a9a7a20
PI#1: Kodi/17.6 (X11; Linux armv7l) LibreELEC/8.2 HW_RaspberryPi/1.0 App_Bitness/32 Version/17.6-Git:a9a7a20

The error happened to me twice this morning (6/25/18 @ 7:40 ON pi#3 AND @9:01 AM ON PI#2). After the 2nd event I immediately went to my NextPVR Windows 10 server and zipped the log files.

I noticed the following in web.log for pi#1:
2018-06-25 09:00:03.415 [DEBUG][32] Waiting for more data (pos: 1671168, len: 1676960, avail: 5792
2018-06-25 09:00:03.415 [DEBUG][32] <Files>
<Length>1676960</Length>
<File offset="0">F:\Tmp\live-WBFS-TV-10064-7.ts</File>
<Duration>921</Duration>
<Complete>0</Complete>
</Files>

After many repetitions of the above error, I got the following:
2018-06-25 09:00:09.475 [DEBUG][32] Waiting for more data (pos: 1671168, len: 1676960, avail: 5792
2018-06-25 09:00:09.475 [DEBUG][32] <Files>
<Length>1676960</Length>
<File offset="0">F:\Tmp\live-WBFS-TV-10064-7.ts</File>
<Duration>921</Duration>
<Complete>0</Complete>
</Files>

2018-06-25 09:00:09.879 [DEBUG][32] reached end of rolling file. Dropping connection...
2018-06-25 09:00:10.880 [DEBUG][32] StopStream(1310739)...
2018-06-25 09:00:10.880 [DEBUG][32] clientHandle[XBMC-945db6bafd2f4d659af3bdb5e1fa2979] = 1310739
2018-06-25 09:00:10.880 [DEBUG][32] Calling StopStream()
2018-06-25 09:00:14.414 [DEBUG][33] ********** Checking VLC Heartbeat Dictionary
2018-06-25 09:00:14.414 [DEBUG][33] +++++No entries in VLC Heartbeat Dictionary.
2018-06-25 09:00:15.522 [DEBUG][32] StopStream() done
2018-06-25 09:00:15.522 [DEBUG][32] RollingFile.Close()
2018-06-25 09:00:15.522 [DEBUG][32] About to delete temp file: F:\Tmp\live-WBFS-TV-10064-7.ts
2018-06-25 09:00:15.528 [DEBUG][32] Cleaning up: F:\Tmp\live-WBFS-TV-10064-7.ts


In web.log.2 I got the following for pi#3:
2018-06-25 07:40:38.463 [DEBUG][5] Waiting for more data (pos: 7700480, len: 7714016, avail: 13536
2018-06-25 07:40:38.463 [DEBUG][5] <Files>
<Length>7714016</Length>
<File offset="0">F:\Tmp\live-WFOR-TV-10064-5.ts</File>
<Duration>4281</Duration>
<Complete>0</Complete>
</Files>

After many repetitions I got the following:
2018-06-25 07:40:44.528 [DEBUG][5] Waiting for more data (pos: 7700480, len: 7714016, avail: 13536
2018-06-25 07:40:44.528 [DEBUG][5] <Files>
<Length>7714016</Length>
<File offset="0">F:\Tmp\live-WFOR-TV-10064-5.ts</File>
<Duration>4281</Duration>
<Complete>0</Complete>
</Files>

2018-06-25 07:40:44.932 [DEBUG][5] reached end of rolling file. Dropping connection...
2018-06-25 07:40:45.933 [DEBUG][5] StopStream(1310737)...
2018-06-25 07:40:45.933 [DEBUG][5] clientHandle[XBMC-6302625cb302407d8ed41788a3912eb8] = 1310737
2018-06-25 07:40:45.933 [DEBUG][5] Calling StopStream()
2018-06-25 07:40:51.556 [DEBUG][5] StopStream() done
2018-06-25 07:40:51.556 [DEBUG][5] RollingFile.Close()
2018-06-25 07:40:51.556 [DEBUG][5] About to delete temp file: F:\Tmp\live-WFOR-TV-10064-5.ts
2018-06-25 07:40:51.561 [DEBUG][5] Cleaning up: F:\Tmp\live-WFOR-TV-10064-5.ts



I have a Hauppauge WinTV ATSC Quad Tuner card, model 1609
The drive holding the temporary ts files has 1.2 TB of free space

Any idea what's causing this problem?

I've attached the zipped log files for your inspection.

Thank you

[sub]

Wired or wireless? if wireless, maybe it's not keeping up with the bitrate. Since the US uses MPEG2 for its HD, it's very high bitrate, and sometimes a struggle for wireless connections.

Do you have a Windows PC running Kodi so you can check there, to see if it's only happening on your RPi devices?

[mvallevand]

Any idea what's causing this problem?

I've attached the zipped log files for your inspection.

Thank you

It is too hard to guess when decide to not provide all your logs. with Kodi you often need the Kodi debug too but this looks to be server side.

Martin

[jmooremcc]

The Pis are using 5Ghz WiFi dongles which literally have no interference from other devices. I've run internal speed tests and my network bandwidth to the Pis is at more than double the bandwidth Kodi indicates it is using for Mpeg2 video.

I've been using NextPVR and Kodi since 2015 and didn't have this particular problem until this year.
The only software differences have been upgrading to Windows 10 and of course all of the NextPVR and Kodi updates.
Hardware wise I upgraded my Hauppauge tuner from a dual to a quad tuner 2 years ago.

I did notice that after I reinstalled all my software after a Window's system crash, the WinTV server was running in the background along with the NextPVR server. I've disabled the Wintv server and so far have not encountered the problem but it's only been less than a week so far.

[sub]

Do you have a Windows PC running Kodi so you can check there, to see if it's only happening on your RPi devices?