Severe stuttering and buffering in Kodi

[maeslin]

I can't seem to be able to watch live TV through Kodi. The channels select but there is extreme buffering and stuttering (plays 5 seconds, buffers 5 seconds, plays 5 seconds, etc).
It works just fine through NextPVR itself, without any lag or buffering.

I've done the
<minvideocachelevel>40</minvideocachelevel>
<minaudiocachelevel>40</minaudiocachelevel>
recommended chances to avancedsettings.xml. This only increased the length of time it would play and buffer (increased both to about 20 seconds play, 20 seconds buffer)

I've replaced the NextPVR NShared.dll with the latest one I could find (size is 664,064 bytes), no change.

Enabling timeshifting in the Kodi-NextPVR plugin causes Kodi to hang when attempting to open a channel, giving a 'Tuner no longer available' error after a few minutes of trying. Partial Log file is provided below, full one attached.

Surprisingly, it also works without any trouble with X-NEWA but I find it to be a bit unwieldy.

OS: Win7 x64
Kodi version: 14.1
NextPVR Frontend for Kodi: 1.9.20
NextPVR version: 3.3.8

NextPVR and Kodi are running on the same system.

As a side comment, when a new version of NShared.dll is released, incrementing the version number could be helpful for troubleshooting.

interesting part of the log:

Code:

20:33:03 T:3952   DEBUG: CPVRChannelGroup - PVR::CPVRChannelGroup::Persist - persisting channel group 'All channels' with 4 channels
20:33:03 T:3952   DEBUG: PVRFile - XFILE::CPVRFile::Open - playback has started on filename pvr://channels/tv/All channels/2.pvr
20:33:03 T:3952   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/All channels/2.pvr
20:33:03 T:3952  NOTICE: Creating Demuxer
20:33:05 T:1696   DEBUG: ------ Window Deinit (Pointer.xml) ------
20:33:12 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:12 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.recurring.list&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:12 T:1236  NOTICE: Thread FileCache start, auto delete: false
20:33:12 T:1236    INFO: CFileCache::Process - Hit eof.
20:33:12 T:1236   DEBUG: Thread FileCache 1236 terminating
20:33:12 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:12 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.list&filter=pending&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:12 T:3812  NOTICE: Thread FileCache start, auto delete: false
20:33:12 T:3812    INFO: CFileCache::Process - Hit eof.
20:33:12 T:3812   DEBUG: Thread FileCache 3812 terminating
20:33:12 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:12 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.list&filter=ready&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:12 T:2684  NOTICE: Thread FileCache start, auto delete: false
20:33:12 T:2684    INFO: CFileCache::Process - Hit eof.
20:33:12 T:2684   DEBUG: Thread FileCache 2684 terminating
20:33:18 T:896   DEBUG: Thread JobWorker 896 terminating (autodelete)
20:33:19 T:1696   DEBUG: Keyboard: scancode: 0x38, sym: 0x0134, unicode: 0x0000, modifier: 0x100
20:33:19 T:1696   DEBUG: CApplication::OnKey: alt-leftalt (0x4f0d4) pressed, action is
20:33:19 T:1696   DEBUG: CWinEventsWin32::WndProcWindow is inactive
20:33:22 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:22 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.recurring.list&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:23 T:3152  NOTICE: Thread FileCache start, auto delete: false
20:33:23 T:3152    INFO: CFileCache::Process - Hit eof.
20:33:23 T:3152   DEBUG: Thread FileCache 3152 terminating
20:33:23 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:23 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.list&filter=pending&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:23 T:748  NOTICE: Thread FileCache start, auto delete: false
20:33:23 T:748    INFO: CFileCache::Process - Hit eof.
20:33:23 T:748   DEBUG: Thread FileCache 748 terminating
20:33:23 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:23 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.list&filter=ready&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:23 T:2676  NOTICE: Thread FileCache start, auto delete: false
20:33:23 T:2676    INFO: CFileCache::Process - Hit eof.
20:33:23 T:2676   DEBUG: Thread FileCache 2676 terminating
20:33:33 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:33 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.lastupdated&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:33 T:836   ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 404
20:33:33 T:836  NOTICE: CCurlFile::FillBuffer - Reconnect, (re)try 1
20:33:33 T:836   ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 404
20:33:33 T:836   ERROR: CCurlFile::CReadState::Connect, didn't get any data from stream.
20:33:33 T:836   ERROR: XFILE::CFileCache::Open - failed to open source <http://127.0.0.1:8866/service?method=recording.lastupdated&sid=06104c9d81a944d7b484637111dc2238>
20:33:33 T:836  NOTICE: AddOnLog: NextPVR PVR Client: Disabling recording update.  Update NextPVR to v3.4
20:33:33 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:33 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.recurring.list&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:33 T:1756  NOTICE: Thread FileCache start, auto delete: false
20:33:33 T:1756    INFO: CFileCache::Process - Hit eof.
20:33:33 T:1756   DEBUG: Thread FileCache 1756 terminating
20:33:33 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:33 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.list&filter=pending&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:33 T:676  NOTICE: Thread FileCache start, auto delete: false
20:33:33 T:676    INFO: CFileCache::Process - Hit eof.
20:33:34 T:676   DEBUG: Thread FileCache 676 terminating
20:33:34 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:33:34 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.list&filter=ready&sid=06104c9d81a944d7b484637111dc2238[/url]
20:33:34 T:1760  NOTICE: Thread FileCache start, auto delete: false
20:33:34 T:1760    INFO: CFileCache::Process - Hit eof.
20:33:34 T:1760   DEBUG: Thread FileCache 1760 terminating
20:33:44 T:836   DEBUG: CFileCache::Open - opening <service> using cache
...
(more of the same 3 sets of method=recording)
...
20:34:45 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.recurring.list&sid=06104c9d81a944d7b484637111dc2238[/url]
20:34:45 T:3888  NOTICE: Thread FileCache start, auto delete: false
20:34:45 T:3888    INFO: CFileCache::Process - Hit eof.
20:34:45 T:3888   DEBUG: Thread FileCache 3888 terminating
20:34:45 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:34:45 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.list&filter=pending&sid=06104c9d81a944d7b484637111dc2238[/url]
20:34:46 T:3896  NOTICE: Thread FileCache start, auto delete: false
20:34:46 T:3896    INFO: CFileCache::Process - Hit eof.
20:34:46 T:3896   DEBUG: Thread FileCache 3896 terminating
20:34:46 T:836   DEBUG: CFileCache::Open - opening <service> using cache
20:34:46 T:836   DEBUG: CurlFile::Open(09DBABB8) [url]http://127.0.0.1:8866/service?method=recording.list&filter=ready&sid=06104c9d81a944d7b484637111dc2238[/url]
20:34:46 T:2012  NOTICE: Thread FileCache start, auto delete: false
20:34:46 T:2012    INFO: CFileCache::Process - Hit eof.
20:34:46 T:2012   DEBUG: Thread FileCache 2012 terminating
20:34:49 T:3952   DEBUG: POParser: PO file has Win Style Line Endings. Converted in memory to Linux LF for file: C:\Program Files (x86)\Kodi\addons\pvr.nextpvr\resources\language\English\strings.po
20:34:49 T:3952   DEBUG: POParser: loaded 26 strings from file C:\Program Files (x86)\Kodi\addons\pvr.nextpvr\resources\language\English\strings.po
20:34:49 T:3952   DEBUG: CAddonCallbacksAddon - ADDON::CAddonCallbacksAddon::QueueNotification - NextPVR PVR Client - Error Message : 'Tuner no longer available'
20:34:49 T:3952   DEBUG: ffmpeg[F70]: [mpegts] Could not detect TS packet size, defaulting to non-FEC/DVHS
20:34:49 T:3952    INFO: ffmpeg[F70]: Input #0, mpegts, from 'pvr://channels/tv/All channels/2.pvr':
20:34:49 T:3952    INFO: ffmpeg[F70]:   Duration: N/A, bitrate: N/A
20:34:49 T:3952   ERROR: CSelectionStreams::Get - failed to get stream

Also, unless I somehow ended up with a future-dated version of the plugin, I have no damn clue where this comes from. :confused:
20:33:33 T:836 NOTICE: AddOnLog: NextPVR PVR Client: Disabling recording update. Update NextPVR to v3.4

[mvallevand]

I added the 3.4 message because recording updating won't refresh automatically until 3.4.x is replaced. The Kodi release cycle is so pitiful it wasn't worth holding the fix given the user complaints after Helix stopped doing refreshes like Gotham did

You need the server-side logs too but you have to catch them right away.

Martin

[maeslin]

Log following in a few minutes.

[mvallevand]

Can't quite figure out where the server-side logs are, actually. NextPVR doesn't seem to have any entries in %appdata% subfolders.

I give up. Does anyone bother to read the wiki, or the johnsonx42 sticky before posting?

Martin

[maeslin]

Sorry about that, braindead-grade derp today. Here's the web.log from trying to open a channel to 'tuner no longer available' error.

[mvallevand]

Sorry about that, braindead-grade derp today. Here's the web.log from trying to open a channel to 'tuner no longer available' error.

Does it work better if you let Kodi settle done for a couple of minutes there is a lot going on with the server on startup. Next time upload all the server logs web logs don't show enough about the capture devices.

Martin

[maeslin]

Does it work better if you let Kodi settle done for a couple of minutes there is a lot going on with the server on startup. Next time upload all the server logs web logs don't show enough about the capture devices.

Martin

Gave it about 10 minutes between starting Kodi and trying to load a channel; still no joy. Here are all the log files.

PC startup at 22:42PM
NPVR recording service restarted at 22:44 (to start new log files)
Kodi startup w/ logging enabled at 22:46
First attempt to open TV channel at 22:55
'Tuner no longer available' error at 22:57

[mvallevand]

Does it work better if you turn off EPG updates during live tv?

Martin

[maeslin]

No difference. Both by disabling EPG updates during playback in Kodi and setting EPC source to 'None' in NPVR settings.

edit: Oddly enough, there's no issue whatsoever with listening to radio channels (through the analog tuner, thanks again for the help in sorting that out), both with timeshift enabled or disabled.

[mvallevand]

None is ok but the setting on NextPVR's Setup->Misc is better. You might need to restart the recording service to see.

Martin