Tvheadend

Hi,

I am trying to watch my pay tv subscription using tvheadend and oscam. The tvheadend server (3.9.277~g1dcb094-dirty) is running on a synology 213+ and the OScam server is running on a freetzed FritzBox 7390. From the logs of OSCam, it seems to work fine, here are some lines:

OSCam << cardserver started at Mon Jan 13 19:08:12 2014

-------------------------------------------------------------------------------
2014/01/13 19:08:12 0 s >> OSCam << cardserver started, version 1.20-unstable_svn, build r9072 (mips-linux-uclibc-libusb)
2014/01/13 19:08:12 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 12607
2014/01/13 19:08:12 0 s services reloaded: 0 services freed, 14 services loaded, rejected 0
2014/01/13 19:08:12 0 s userdb reloaded: 2 accounts loaded, 0 expired, 0 disabled
2014/01/13 19:08:12 0 s signal handling initialized
2014/01/13 19:08:12 0 s 5 service-id's loaded in 0ms
2014/01/13 19:08:12 0 s 22 tier-id's loaded
2014/01/13 19:08:12 0 s 3 provid's loaded
2014/01/13 19:08:12 0 s 22 lengths for caid guessing loaded
2014/01/13 19:08:12 0 s newcamd: initialized (fd=4, port=31125, ip=192.168.178.1)
2014/01/13 19:08:12 0 s > CAID: 098E PROVID: 000000
2014/01/13 19:08:12 530F70 h webif: decompressed 54564 bytes back into 155212 bytes
2014/01/13 19:08:12 530F70 h HTTP Server running. ip=192.168.178.1 port=7777
2014/01/13 19:08:12 0 s sky [smartreader] creating thread for device SRv2;Serial:12345678
2014/01/13 19:08:12 5606B8 r sky [smartreader] De SR_init
2014/01/13 19:08:12 5606B8 r sky [smartreader] initing smargo type SRv2
2014/01/13 19:08:12 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2014/01/13 19:08:12 0 s anti cascading disabled
2014/01/13 19:08:12 563AB0 c plain dvbapi-client granted (tvh, au=auto (1 reader))
2014/01/13 19:08:12 563AB0 c ERROR: Could not detect DVBAPI version.
2014/01/13 19:08:12 5606B8 r sky [smartreader] Using 0x02/0x81 as endpoint for smartreader hardware detection
2014/01/13 19:08:12 5606B8 r Found reader with serial 12345678 at 002:002
2014/01/13 19:08:12 5606B8 r Found smartreader device Serial:12345678
2014/01/13 19:08:12 5606B8 r sky [smartreader] reader type is 2
2014/01/13 19:08:12 5606B8 r sky [smartreader] Pthread Wordt gecreeerd
2014/01/13 19:08:12 5606B8 r sky [smartreader] clocking for smartreader with smartreader protocol
2014/01/13 19:08:12 5606B8 r sky [smartreader] Reader initialized (device=SRv2;Serial:12345678, detect=cd, mhz= AUTO, cardmhz=436)
2014/01/13 19:08:13 5606B8 r sky [smartreader] card detected
2014/01/13 19:08:14 568AF8 c client connected to 31125 port
2014/01/13 19:08:14 568AF8 c encrypted newcamd:31125-client 192.168.178.3 granted (tvheadend, au=on (1 reader))
2014/01/13 19:08:14 568AF8 c user tvheadend authenticated successfully (Tvheadend)
2014/01/13 19:08:14 568AF8 c AU disabled for user tvheadend
2014/01/13 19:08:17 5606B8 r sky [smartreader] ATR:
2014/01/13 19:08:17 5606B8 r sky [smartreader] Init card protocol T0, FI=1, F=372, D=8, N=3
2014/01/13 19:08:17 5606B8 r sky [smartreader] Calculated work ETU is 8.71 us reader mhz = 534
2014/01/13 19:08:17 5606B8 r sky [smartreader] autospeed = 1
2014/01/13 19:08:17 5606B8 r sky [smartreader] Effectif reader settings mhz =534 F= 372 D= 8 N=3 T=0 inv=1 parity=ODD
2014/01/13 19:08:18 5606B8 r sky [smartreader] ATR Fsmax is 5 MHz, clocking card to atr Fsmax for smartreader cardspeed off 5.34 MHz (specified in reader>mhz)
2014/01/13 19:08:19 5606B8 r sky [smartreader] Card type: KWTV
2014/01/13 19:08:19 5606B8 r sky [smartreader] Rom version: 10T1
2014/01/13 19:08:20 5606B8 r sky [smartreader] parental lock setting: FF FF FF FF
2014/01/13 19:08:20 5606B8 r sky [smartreader] type: VideoGuard Kabel BW (098E), caid: 098E
2014/01/13 19:08:20 5606B8 r sky [smartreader] serial: ########, BoxID: ########, baseyear: 2004
2014/01/13 19:08:20 5606B8 r sky [smartreader] ready for requests
2014/01/13 19:08:20 5606B8 r sky [smartreader] found card system videoguard2
2014/01/13 19:08:20 5606B8 r sky [videoguard2] THIS WAS A SUCCESFULL START ATTEMPT No 1 out of max alloted of 1
2014/01/13 19:08:20 5606B8 r sky [videoguard2] card detected
2014/01/13 19:08:20 5606B8 r sky [videoguard2] type: VideoGuard Kabel BW (098E)
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 007b, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 007d, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 0081, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 008e, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 00ac, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 00ad, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 00ae, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 00af, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 00b0, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 00b5, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 00b6, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:20 5606B8 r sky [videoguard2] tier: 00b8, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00ba, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00c9, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00ca, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00cb, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00cc, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00cd, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00ce, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00cf, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00d0, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00d1, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00d2, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00f3, expiry date: 2014/03/05-00:00:00
2014/01/13 19:08:21 5606B8 r sky [videoguard2] tier: 00ff, expiry date: 2014/03/05-00:00:00
2014/01/13 19:09:16 568AF8 c tvheadend disconnected from 192.168.178.3
2014/01/13 19:09:18 56B390 c client connected to 31125 port
2014/01/13 19:09:19 56B390 c encrypted newcamd:31125-client 192.168.178.3 granted (tvheadend, au=on (1 reader))
2014/01/13 19:09:19 56B390 c user tvheadend authenticated successfully (Tvheadend)
2014/01/13 19:09:19 56B390 c AU enabled for user tvheadend on reader sky
2014/01/13 19:09:52 5606B8 r sky [videoguard2] tvheadend emmtype=global, len=135, idx=0, cnt=1: written (71 ms)
2014/01/13 19:09:54 5606B8 r sky [videoguard2] tvheadend emmtype=global, len=135, idx=0, cnt=2: written (69 ms)
2014/01/13 19:09:54 5606B8 r sky [videoguard2] tvheadend emmtype=global, len=39, idx=1, cnt=1: written (58 ms)
2014/01/13 19:10:03 5606B8 r sky [videoguard2] tvheadend emmtype=global, len=135, idx=0, cnt=3: written (69 ms)
2014/01/13 19:10:04 5606B8 r sky [videoguard2] tvheadend emmtype=global, len=43, idx=2, cnt=1: written (34 ms)
2014/01/13 19:10:12 56B390 c tvheadend (098E&000000/1775/0008/97:04B9FF423E836052B70F8D4BCC282C77): found (297 ms) by sky
2014/01/13 19:10:12 5606B8 r sky [videoguard2] tvheadend emmtype=global, len=43, idx=3, cnt=1: written (35 ms)
2014/01/13 19:10:19 56B390 c tvheadend (098E&000000/1773/002B/97:8D45FF423E880A5275E572FBEF9098BCB): found (289 ms) by sky
2014/01/13 19:10:21 56B390 c tvheadend (098E&000000/1773/002B/97:0FDFF423E35EE1D90A3264A7F7321D4D): found (292 ms) by sky

But Tvheadend is not descrambling the encrypted channels. FTA channels work fine.
Of course, I have an code word entry for the OScam and as you can see in the log above, the user is correctly connected. In the status of tvheadend the bitrate is at 130kb/s when tuning in an encrypted channel.

Any hints? Any known probs?

Thanks!