Freezes on encrypted channels - good ECM

Anthony Thomas

I've recently started getting a number of drops/freezes in XBMC on encrypted channels even though the ECM times are more than suitable (<200ms).

Currently using TVH 3.9.1094~g9a6940c and OSCam r9812.
Ubuntu 12.04.4
DVB Cards
2 x TBS 8920
1 x TBS 6981
Drivers - lja open-source
CPU - Intel Xeon E3110

Network - CAT6 - 1GBit.

Frontends - OpenElec 4.0.7

CSA method is OSCam Mode 4 - /tmp/camd.socket

OSCam logs show the ECMs being received on schedule (every ~7 secs) and within a reasonable speed (<200ms). There's no disconnections, timeouts or failed ECMs.

I thought it might be a driver or signal issue so tried across all the DVB-S2 cards but it's the same on them all.

Only thing that pops up in the TVH logs is


[   INFO]:capmt: Removing CAPMT Server from service

then


[   INFO]:capmt: Starting CAPMT server for service

I'll check if I can see anything else.

Jos E

I got the same problem since I updated my ~1089 build to the newer ones (running 1133 now).

Unencrypted channels work fine, but encrypted channels simply stop/freeze.

I am not using CAPMT but newcamd (Code Word) protocol to Oscam

In the log I see:

[code]
014-08-02 09:10:26.585 subscription: "192.168.1.100 [ xbmc | XBMC Media Center ]" subscribing on "FOX HD", weight: 150, adapter: "HDHomeRun DVB-C : 3", network: "Cable", mux: "682000", provider: "Ziggo", service: "FOX HD", hostname: "192.168.1.100", username: "xbmc", client: "XBMC Media Center"
2014-08-02 09:10:31.481 cwc: Can not descramble service "FOX HD", access denied (seqno: 50 Req delay: 380 ms) from cwc-192.168.1.134-15050
2014-08-02 09:10:42.007 mpegts: 682000 in Cable - tuning on HDHomeRun DVB-C : 2
2014-08-02 09:10:56.520 cwc: Can not descramble service "FOX HD", access denied (seqno: 54 Req delay: 376 ms) from cwc-192.168.1.134-15050
2014-08-02 09:11:04.005 mpegts: 682000 in Cable - tuning on HDHomeRun DVB-C : 1

2014-08-02 09:11:21.494 cwc: Can not descramble service "FOX HD", access denied (seqno: 58 Req delay: 380 ms) from cwc-192.168.1.134-15050[/code]

An old copy of TvHeadend on my Synology works perfectly fine with the same Oscam-server.

In the Revisions-log on TvHeadend I notice Jaroslav Kysela made quite some changes to the descrambler/cwc-interface. Perhaps this is causing the issues we have..

Jos E

Oscam produces when I switch to a channel which I do have a subscription too (and have valid entitlement):

[code]
2014/08/02 11:13:26 AEA4A0 c xbmc (0604&000000/000B/08A0/44:CBC8E66044102572A41D8791F3349A1C): found (176 ms) by reader1 - Veronica HD/Disney XD
2014/08/02 11:13:26 AEA4A0 c xbmc (0604&000000/270E/08A0/44:5A9F0B980FC454B719A688A5CFF021C8): not found (342 ms) by reader1 - Veronica HD/Disney XD (reader1 irdeto_do_cmd [2] 90
2014/08/02 11:13:30 AEA4A0 c xbmc (0604&000000/000B/08A0/44:E0414CEAA607F1A1C9703EBE4BCA72F8): found (173 ms) by reader1 - Veronica HD/Disney XD
2014/08/02 11:13:30 AEA4A0 c xbmc (0604&000000/270E/08A0/44:84FD1B2800DAF8E40448B06B7A70E719): not found (377 ms) by reader1 - Veronica HD/Disney XD (reader1 irdeto_do_cmd [2] 90
2014/08/02 11:13:42 AEA4A0 c xbmc (0604&000000/000B/08A0/44:E0414CEAA607F1A1C9703EBE4BCA72F8): cache1 (0 ms) by reader1 - Veronica HD/Disney XD
2014/08/02 11:13:42 AEA4A0 c xbmc (0604&000000/270E/08A0/44:84FD1B2800DAF8E40448B06B7A70E719): not found (203 ms) by reader1 - Veronica HD/Disney XD (reader1 irdeto_do_cmd [2] 90
2014/08/02 11:13:55 AEA4A0 c xbmc (0604&000000/000B/08A0/44:9D3F54D3E0BE5AF1041E205EBC82E072): found (173 ms) by reader1 - Veronica HD/Disney XD
2014/08/02 11:13:55 AEA4A0 c xbmc (0604&000000/270E/08A0/44:C98C31AFB2687D30944E30B15EB31208): not found (339 ms) by reader1 - Veronica HD/Disney XD (reader1 irdeto_do_cmd [2] 90
2014/08/02 11:14:07 AEA4A0 c xbmc (0604&000000/000B/08A0/44:9D3F54D3E0BE5AF1041E205EBC82E072): cache1 (0 ms) by reader1 - Veronica HD/Disney XD
2014/08/02 11:14:08 AEA4A0 c xbmc (0604&000000/270E/08A0/44:C98C31AFB2687D30944E30B15EB31208): not found (205 ms) by reader1 - Veronica HD/Disney XD (reader1 irdeto_do_cmd [2] 90
2014/08/02 11:14:20 AEA4A0 c xbmc (0604&000000/000B/08A0/44:CDB7EC4A88EDA18A251D27C51CD593F7): found (177 ms) by reader1 - Veronica HD/Disney XD
2014/08/02 11:14:20 AEA4A0 c xbmc (0604&000000/270E/08A0/44:2EA4A904C92DB616E89BE1E4B730A630): not found (342 ms) by reader1 - Veronica HD/Disney XD (reader1 irdeto_do_cmd [2] 90[/code]

Jos E

I pulled build 1070 from the Repository and my problem is not in that build. Perhaps this will work for you too, Anthony.

To do this, you need to build it yourselves. What I did:
I looked up a suitable build from :
https://tvheadend.org/projects/tvheadend/repository/revisions?page=4

where I saw a date where it was still working (before Jaroslav Kysela his fixes to the ECM / Descrambler code -> I picked 39b6bc1d

git clone https://github.com/tvheadend/tvheadend cd tvheadend/
git checkout 39b6bc1d

./Autobuild.sh (I'm running Ubuntu 14.04 - so that worked from this version on)

In your parent-directory you will now find a file called tvheadend_3.9.1070~g39b6bc1_amd64.deb

You can install this package by running

dpkg -i tvheadend_3.9.1070~g39b6bc1_amd64.deb

This will downgrade your current version to version 1070 (from 2014-07-10). This version works for me with encrypted channels fine.

Hope it works out for you as a temporary work-around.

Anthony Thomas

Thanks for finding a commit point that solved your issues.

Going to give it a try here too.

Anthony Thomas

Not done a huge amount of testing but the problem looks to have disappeared by reverting back to that commit.

Thomas Lobker

Same problem here, after upgrading tvheadend encrypted channels stopped working after a couple of seconds, with an access denied message. Rolling back to commit 39b6bc1d worked for me.

aptitude install git build-essential debhelper libavahi-client-dev libssl-dev zlib1g-dev libcurl4-gnutls-dev liburiparser-dev cd /usr/local/src/ git clone https://github.com/tvheadend/tvheadend cd tvheadend/ git checkout 39b6bc1d ./Autobuild.sh cd .. dpkg -i tvheadend_3.9.1070~g39b6bc1_amd64.deb

Hope it will be fixed in a future release :)

J H

Same here with Ziggo. Hopefully fixed soon.

Anthony Thomas

So far it looks like my issue has gone with g99ed831.

I haven't tested any earlier, just built from source earlier today.

Anthony Thomas

Scratch that. Still issues

http://pastebin.com/gDMv6cus

J H

Confirmed to be not fixed;

2014-08-26 22:33:09.731 cwc: Can not descramble service "NPO 3 HD", access denied (seqno: 20 Req delay: 368 ms) from cwc-127.0.0.1-15050

2014-08-26 22:33:43.212 subscription: No transponder available for subscription "192.168.1.107 [ huisman | XBMC Media Center ]" to channel "NPO 3 HD"

Anthony Thomas

Looking like issues are resolved for me.

It certainly looks that way.

J H

Not fixed for me :(

2014-09-13 15:51:37.764 cwc: Can not descramble service "NPO 3 HD", access denied (seqno: 642 Req delay: 372 ms) from cwc-127.0.0.1-15050

Jaroslav Kysela

Note that this problem should be fixed in v3.9-1529-gb2d0156 . If you have any problems, open a ticket (issue) and point to the trace log (descrambler,cwc).

J H

Not fixed for me, so I opened a ticket as you suggested. https://tvheadend.org/issues/2311

Thanks for helping out!

Jaroslav Kysela

OK, small bug was there and also ECM filtering was improved now. Try: v3.9-1537-g3ac7a43

Jaroslav Kysela

More minor tweaks: v3.9-1539-g286509b

Jos E

Jaroslav,

For me the build 3.9.1540~g962f99c is working properly. Thanks for the hard work, hopefully others can report it being fixed too :)

Thomas Lobker

Fixed in the latest release :)

Thanks for the effort and all the good work.