Issue with kodi 15.0, TVheadend and pvr.hts

Helly 1206

Hi,

Until last month is was running Kodi 14.2 (kodibuntu 14.04) and tvheadend 4.0.5 and everything worked perfectly. However after upgrading to kodi 15 (pvr.hts 2.1.17) some strange issues came up.

It seems that kodi sometimes freezes for a few seconds. This is very annoying when zapping, moving through the menu or the EPG. I looked into this in some more detail at it seems that tvheadend doesn't give an immediate answer to htsp requests from pvr.hts. Please check the added logfiles.

I checked my system load, but this doesn't cause the issue (is only at about 10%). I tried to change the timeout in pvr.hts to 10s. This reduced the number of errors but didn't solve this freezes. I tried the tvheadend 4.1 from the unstable release, but this release has problems shutting down kodi. I also tried the latest development release from tvheadend, but this version still has the freezing issue.

Can someone help me solving this issue? Everthing worked fine with kodi 14.2 and I hope to get everything working with 15.0.

Thanks.

Helly.

Kodi.log: 09:59:03 T:140147014215424 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command getDiskSpace failed: No response received 09:59:40 T:140146478814976 ERROR: Previous line repeats 1 times. 09:59:40 T:140146478814976 NOTICE: Thread LanguageInvoker start, auto delete: false 09:59:40 T:140146478814976 NOTICE: Previous line repeats 1 times. 09:59:40 T:140146478814976 NOTICE: -->Python Interpreter Initialized<-- 09:59:48 T:140146470422272 NOTICE: Previous line repeats 1 times. 09:59:48 T:140146470422272 NOTICE: Thread JobWorker start, auto delete: true 10:00:00 T:140148633216768 NOTICE: Previous line repeats 1 times. 10:00:00 T:140148633216768 NOTICE: Thread BackgroundLoader start, auto delete: false 10:00:09 T:140147014215424 NOTICE: Previous line repeats 1 times. 10:00:09 T:140147014215424 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command getDiskSpace failed: No response received 10:00:09 T:140146512385792 NOTICE: Thread BackgroundLoader start, auto delete: false 10:00:14 T:140148633216768 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command hello failed: No response received 10:00:14 T:140148633216768 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to send hello 10:00:21 T:140149706332096 ERROR: GetDirectory - Error getting addons://user/ 10:00:24 T:140148633216768 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command hello failed: No response received 10:00:24 T:140148633216768 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to send hello 10:00:34 T:140148633216768 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command hello failed: No response received 10:00:34 T:140148633216768 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to send hello 10:00:37 T:140149706332096 ERROR: CGUIMediaWindow::GetDirectory(addons://user/) failed 10:00:37 T:140146512385792 NOTICE: Thread BackgroundLoader start, auto delete: false 10:00:42 T:140148633216768 NOTICE: Thread LanguageInvoker start, auto delete: false 10:00:42 T:140148633216768 NOTICE: Previous line repeats 1 times. 10:00:42 T:140148633216768 NOTICE: -->Python Interpreter Initialized<-- 10:01:04 T:140147014215424 NOTICE: Previous line repeats 1 times. 10:01:04 T:140147014215424 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command getDiskSpace failed: No response received


tvheadend logging (from syslog), deleted some scanning information from this log to make it smaller
Aug 8 09:59:02 helly-desktop tvheadend[2295]: subscription: 001E: "scan" subscribing to mux "682.75MHz", weight: 5, adapter: "Sundtek DVB-C (III) : DVB-C #0", network: "Roermond", service: "Raw PID Subscription"
Aug 8 09:59:02 helly-desktop tvheadend[2295]: mpegts: 682.75MHz in Roermond - scan no data, failed
Aug 8 09:59:02 helly-desktop tvheadend[2295]: subscription: 001E: "scan" unsubscribing
Aug 8 09:59:02 helly-desktop tvheadend[2295]: mpegts: 802.75MHz in Roermond - tuning on Sundtek DVB-C (III) : DVB-C #0
Aug 8 09:59:03 helly-desktop tvheadend[2295]: subscription: 0021: "scan" subscribing to mux "802.75MHz", weight: 5, adapter: "Sundtek DVB-C (III) : DVB-C #0", network: "Roermond", service: "Raw PID Subscription"
Aug 8 09:59:03 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Helly | Kodi Media Center ]: Disconnected
Aug 8 09:59:03 helly-desktop tvheadend[2295]: htsp: Got connection from 127.0.0.1
Aug 8 09:59:03 helly-desktop tvheadend[2295]: htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv18)
Aug 8 09:59:03 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Kodi Media Center ]: Identified as user Helly
Aug 8 09:59:03 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Helly | Kodi Media Center ]: Privileges raised
Aug 8 09:59:12 helly-desktop tvheadend[2295]: mpegts: 802.75MHz in Roermond scan complete
Aug 8 09:59:12 helly-desktop tvheadend[2295]: subscription: 0021: "scan" unsubscribing
Aug 8 09:59:12 helly-desktop tvheadend[2295]: mpegts: 786MHz in Roermond - tuning on Sundtek DVB-C (III) : DVB-C #0
Aug 8 09:59:13 helly-desktop tvheadend[2295]: subscription: 0024: "scan" subscribing to mux "786MHz", weight: 5, adapter: "Sundtek DVB-C (III) : DVB-C #0", network: "Roermond", service: "Raw PID Subscription"
Aug 8 09:59:13 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401

Aug 8 09:59:23 helly-desktop tvheadend[2295]: subscription: 002A: "scan" subscribing to mux "698MHz", weight: 5, adapter: "Sundtek DVB-C (III) : DVB-C #0", network: "Roermond", service: "Raw PID Subscription"
Aug 8 09:59:23 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Helly | Kodi Media Center ]: Disconnected
Aug 8 09:59:23 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401
Aug 8 09:59:23 helly-desktop tvheadend[2295]: htsp: Got connection from 127.0.0.1
Aug 8 09:59:23 helly-desktop tvheadend[2295]: htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv18)
Aug 8 09:59:23 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Kodi Media Center ]: Identified as user Helly
Aug 8 09:59:23 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Helly | Kodi Media Center ]: Privileges raised
Aug 8 09:59:24 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401

Aug 8 10:00:08 helly-desktop tvheadend[2295]: mpegts: 474MHz in Roermond - tuning on Sundtek DVB-C (III) : DVB-C #0
Aug 8 10:00:14 helly-desktop tvheadend[2295]: subscription: 0042: "scan" subscribing to mux "474MHz", weight: 5, adapter: "Sundtek DVB-C (III) : DVB-C #0", network: "Roermond", service: "Raw PID Subscription"
Aug 8 10:00:14 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Helly | Kodi Media Center ]: Disconnected
Aug 8 10:00:14 helly-desktop tvheadend[2295]: mpegts: 474MHz in Roermond - scan no data, failed
Aug 8 10:00:14 helly-desktop tvheadend[2295]: subscription: 0042: "scan" unsubscribing
Aug 8 10:00:14 helly-desktop tvheadend[2295]: mpegts: 554MHz in Roermond - tuning on Sundtek DVB-C (III) : DVB-C #0
Aug 8 10:00:14 helly-desktop tvheadend[2295]: subscription: 0045: "scan" subscribing to mux "554MHz", weight: 5, adapter: "Sundtek DVB-C (III) : DVB-C #0", network: "Roermond", service: "Raw PID Subscription"
Aug 8 10:00:14 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401
Aug 8 10:00:14 helly-desktop tvheadend[2295]: htsp: Got connection from 127.0.0.1
Aug 8 10:00:14 helly-desktop tvheadend[2295]: htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv18)
Aug 8 10:00:14 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Kodi Media Center ]: Disconnected
Aug 8 10:00:14 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401
Aug 8 10:00:14 helly-desktop tvheadend[2295]: message repeated 2 times: [ http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401]

Aug 8 10:00:35 helly-desktop tvheadend[2295]: htsp: Got connection from 127.0.0.1
Aug 8 10:00:35 helly-desktop tvheadend[2295]: htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv18)
Aug 8 10:00:35 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Kodi Media Center ]: Disconnected

Aug 8 10:00:41 helly-desktop tvheadend[2295]: subscription: 0051: "scan" unsubscribing
Aug 8 10:00:41 helly-desktop tvheadend[2295]: mpegts: 394MHz in Roermond - tuning on Sundtek DVB-C (III) : DVB-C #0
Aug 8 10:00:42 helly-desktop tvheadend[2295]: subscription: 0054: "scan" subscribing to mux "394MHz", weight: 5, adapter: "Sundtek DVB-C (III) : DVB-C #0", network: "Roermond", service: "Raw PID Subscription"
Aug 8 10:00:42 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401
Aug 8 10:00:42 helly-desktop tvheadend[2295]: htsp: Got connection from 127.0.0.1
Aug 8 10:00:42 helly-desktop tvheadend[2295]: htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv18)
Aug 8 10:00:42 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401
Aug 8 10:00:42 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Kodi Media Center ]: Disconnected
Aug 8 10:00:42 helly-desktop tvheadend[2295]: htsp: Got connection from 127.0.0.1
Aug 8 10:00:42 helly-desktop tvheadend[2295]: htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv18)
Aug 8 10:00:42 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Kodi Media Center ]: Identified as user Helly
Aug 8 10:00:42 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Helly | Kodi Media Center ]: Privileges raised
Aug 8 10:00:42 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401
Aug 8 10:00:43 helly-desktop tvheadend[2295]: message repeated 2 times: [ http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401]

Aug 8 10:01:07 helly-desktop tvheadend[2295]: http: 127.0.0.1: HTTP/1.1 GET /status.xml -- 401
Aug 8 10:01:07 helly-desktop tvheadend[2295]: mpegts: 570.75MHz in Roermond - scan no data, failed
Aug 8 10:01:07 helly-desktop tvheadend[2295]: subscription: 0060: "scan" unsubscribing
Aug 8 10:01:07 helly-desktop tvheadend[2295]: mpegts: 578.75MHz in Roermond - tuning on Sundtek DVB-C (III) : DVB-C #0
Aug 8 10:01:08 helly-desktop tvheadend[2295]: subscription: 0063: "scan" subscribing to mux "578.75MHz", weight: 5, adapter: "Sundtek DVB-C (III) : DVB-C #0", network: "Roermond", service: "Raw PID Subscription"
Aug 8 10:01:08 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Helly | Kodi Media Center ]: Disconnected
Aug 8 10:01:08 helly-desktop tvheadend[2295]: htsp: Got connection from 127.0.0.1
Aug 8 10:01:08 helly-desktop tvheadend[2295]: htsp: 127.0.0.1: Welcomed client software: Kodi Media Center (HTSPv18)
Aug 8 10:01:08 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Kodi Media Center ]: Identified as user Helly
Aug 8 10:01:08 helly-desktop tvheadend[2295]: htsp: 127.0.0.1 [ Helly | Kodi Media Center ]: Privileges raised

Alexander Kamm

Dear Helly,

your problem seems similar to mine. Please see:

Kodi Media Center ]: Write error -- Broken pipe [solved]

What helped? In short: Compile tvheadend with transcoding disabled and resetting all values in kodi regarding Live-TV and kodi-pvr-hts.

Cheers

Alexander

P.S I have no idea about the reason for this issue

Helly 1206

Dear Alexander,

Thanks for your answer. However I tried to do this but it didn't solve my problem. Probably my problem is slightly different than yours.

It seems that tvheadend is hanging somewhere waiting for something. I tried to run tvheadend with a higher priority but that didn't solve the problem either.

It seems that after tuning to a channel (from kodi or the web interface) everything seems to work better (but not completely gone). Does this have something to do with this issue?

If someone can be of any help, I will appreciate it.

Thanks,
Ivo.

Helly 1206

Hello,

somewhat further. It seemed that the issue disappeared when I disabled initial scan and idle scanning from my dvb-c device (Sundtek). However this is not a very convenient solution, because I need this for EPG grabbing. Now disabled muxes that didn't contain a channel and switched on the scanning again. Now the system also seems to be more responsive. Probably some old muxes gave issues that freezed tvheadend for a while.

The problem is solved for now, but I don't feel like checking all the muxes all the time. Can something be automated (by disabling unused or suspicious muxes)? Can I change something in the priorities so the system doesn't freeze for a few seconds when something fails?

Thanks in advance for your help.

Ivo.

Alex A

I've been plagued by this for the last couple of weeks, tvheadend suddenly drops connection and kodi keeps reconnecting.

I would reset the router and the problem would go away, but I think this is just a coincidence as I have not had consistent results with it.

I'll try your suggestions and see if that works.

Alex A

Alex A wrote:
> I've been plagued by this for the last couple of weeks, tvheadend suddenly drops connection and kodi keeps reconnecting.
> I would reset the router and the problem would go away, but I think this is just a coincidence as I have not had consistent results with it.
>

> I'll try your suggestions and see if that works.

Neah, didn't work for me, same story. Broken pipe and good bye, nothing works anymore for at least 10 minutes. Even if I restart tvheadend or the server or the client.