Tvheadend

Hello,

I could manage to have tvheadend and oscam to work together, from the log, apparently it should work, but the channel is not decoded.
The official card is recognize, when tvheadend request oscam, it seems to work and it receives an answer, but there is no decoding.

I have no message on XBMC, and here are the logs of tvheadend & oscam

Tvheadend

Mar 15 08:41:37 Service: Subscription "127.0.0.1 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_ST_STV0297_DVB_C219000000" without stealing for service "ST STV0297 DVB-C/219,000 kHz/FRANCE 5 HD"
Mar 15 08:41:37 dvb: "/dev/dvb/adapter0" tuning to "219,000 kHz" (Transport start)
Mar 15 08:41:37 cwc: ST STV0297 DVB-C/219,000 kHz/FRANCE 5 HD using CWC 192.168.0.2:13000
Mar 15 08:41:37 subscription: "127.0.0.1 [ xbmc | XBMC Media Center ]" subscribing on "France 5 HD", weight: 150, adapter: "ST STV0297 DVB-C", network: "", mux: "219,000 kHz", provider: "", service: "FRANCE 5 HD", quality: 100
Mar 15 08:41:37 dvb: "/dev/dvb/adapter1" tuning to "TNT COFDM BB: 698,000 kHz" (Autoscan)
Mar 15 08:41:37 Service: ST STV0297 DVB-C/219,000 kHz/FRANCE 5 HD: Status changed to [Hardware input]
Mar 15 08:41:37 Service: ST STV0297 DVB-C/219,000 kHz/FRANCE 5 HD: Status changed to [Hardware input] [Input on service]
Mar 15 08:41:37 Service: ST STV0297 DVB-C/219,000 kHz/FRANCE 5 HD: Status changed to [Hardware input] [Input on service] [Demuxed packets]
Mar 15 08:41:37 cwc: Sending ECM section=0/0, for service FRANCE 5 HD (seqno: 19485) PID 6676
Mar 15 08:41:38 Service: ST STV0297 DVB-C/219,000 kHz/FRANCE 5 HD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets]
Mar 15 08:41:38 cwc: Received ECM reply for service "FRANCE 5 HD" even: 21.23.c5.09.ed.88.bc.31 odd: 04.da.c6.a4.11.bb.49.15 (seqno: 19485 Req delay: 352 ms)
Mar 15 08:41:38 cwc: Obtained key for service "FRANCE 5 HD" in 352 ms, from 192.168.0.2:13000
Mar 15 08:41:41 cwc: Sending ECM section=0/0, for service FRANCE 5 HD (seqno: 19486) PID 6676
Mar 15 08:41:42 cwc: Received ECM reply for service "FRANCE 5 HD" even: ca.40.69.73.fe.66.ac.10 odd: 04.da.c6.a4.11.bb.49.15 (seqno: 19486 Req delay: 348 ms)
Mar 15 08:41:43 dvb: "TNT COFDM BB: 698,000 kHz" on adapter "DiBcom 7000MA/MB/PA/PB/MC", status changed to OK
Mar 15 08:41:51 cwc: Sending ECM section=0/0, for service FRANCE 5 HD (seqno: 19487) PID 6676
Mar 15 08:41:52 cwc: Received ECM reply for service "FRANCE 5 HD" even: ca.40.69.73.fe.66.ac.10 odd: 94.f9.a8.35.4c.3c.71.f9 (seqno: 19487 Req delay: 344 ms)
Mar 15 08:41:57 dvb: "/dev/dvb/adapter1" tuning to "TNT COFDM BB: 714,000 kHz" (Autoscan)
Mar 15 08:42:01 cwc: Sending ECM section=0/0, for service FRANCE 5 HD (seqno: 19488) PID 6676
Mar 15 08:42:02 cwc: Received ECM reply for service "FRANCE 5 HD" even: 15.fd.e2.f4.d4.88.2e.8a odd: 94.f9.a8.35.4c.3c.71.f9 (seqno: 19488 Req delay: 344 ms)

OSCAM

-------------------------------------------------------------------------------
#>> OSCam << cardserver started at Fri Mar 15 08:38:55 2013
-------------------------------------------------------------------------------
2013/03/15 08:38:55 0 s >> OSCam << cardserver started, version 1.20-unstable_svn, build r8359 (i686-linux-gnu)
2013/03/15 08:38:55 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 393
2013/03/15 08:38:55 0 s ERROR: Cannot open file "/usr/local/etc/oscam.services" (errno=2 No such file or directory)
2013/03/15 08:38:55 0 s userdb reloaded: 1 accounts loaded, 0 expired, 0 disabled
2013/03/15 08:38:55 0 s signal handling initialized
2013/03/15 08:38:55 0 s ERROR: Cannot open file "/usr/local/etc/oscam.srvid" (errno=2 No such file or directory)
2013/03/15 08:38:55 0 s ERROR: Cannot open file "/usr/local/etc/oscam.tiers" (errno=2 No such file or directory)
2013/03/15 08:38:55 0 s ERROR: Cannot open file "/usr/local/etc/oscam.provid" (errno=2 No such file or directory)
2013/03/15 08:38:55 0 s ERROR: Cannot open file "/usr/local/etc/oscam.guess" (errno=2 No such file or directory)
2013/03/15 08:38:55 0 s ERROR: Cannot open file "/usr/local/etc/oscam.ird" (errno=2 No such file or directory)
2013/03/15 08:38:55 0 s ERROR: Cannot open file "/usr/local/etc/oscam.whitelist" (errno=2 No such file or directory)
2013/03/15 08:38:55 0 s ERROR: Cannot open file "/usr/local/etc/oscam.cacheex" (errno=2 No such file or directory)
2013/03/15 08:38:55 0 s monitor: disabled
2013/03/15 08:38:55 0 s camd35: disabled
2013/03/15 08:38:55 0 s cs378x: disabled
2013/03/15 08:38:55 0 s newcamd: initialized (fd=5, port=13000)
2013/03/15 08:38:55 0 s cs378x: disabled
2013/03/15 08:38:55 0 s newcamd: initialized (fd=5, port=13000)
2013/03/15 08:38:55 0 s > CAID: 0100 PROVID: 00A821
2013/03/15 08:38:55 0 s pandora: disabled
2013/03/15 08:38:55 0 s csp: disabled
2013/03/15 08:38:55 0 s radegast: disabled
2013/03/15 08:38:55 0 s Numericable [mouse] creating thread for device /dev/ttyS5
2013/03/15 08:38:55 0 s waiting for local card init
2013/03/15 08:38:55 B7400468 h dvbapi: HTTP Server listening on port 16200
2013/03/15 08:38:55 8DEB4A0 r Numericable [mouse] Reader initialized (device=/dev/ttyS5, detect=cd, mhz=500, cardmhz=500)
2013/03/15 08:38:56 8DEB4A0 r Numericable [mouse] card detected
2013/03/15 08:38:57 8DEB4A0 r Numericable [mouse] ATR: 3B F7 11 00 01 40 96 70 70 0A 0E 6C B6 D6
2013/03/15 08:38:57 8DEB4A0 r Numericable [mouse] Init card protocol T0, FI=1, F=372, D=1, N=1
2013/03/15 08:38:57 8DEB4A0 r Numericable [mouse] Setting baudrate to 13440 bps
2013/03/15 08:38:57 8DEB4A0 r Numericable [mouse] Calculated work ETU is 74.40 us
2013/03/15 08:38:57 8DEB4A0 r Numericable [mouse] ATR Fsmax is: 5 Mhz, clocking card to wanted user cardspeed of 5.00 Mhz (specified in reader->mhz)
2013/03/15 08:39:19 8DEB4A0 r Numericable [mouse] card system not supported
2013/03/15 08:39:19 8DEB4A0 r Numericable [mouse] Normal mode failed, reverting to Deprecated Mode
2013/03/15 08:39:21 8DEB4A0 r Numericable [mouse] ATR: 3B F7 11 00 01 40 96 70 70 0A 0E 6C B6 D6
2013/03/15 08:39:21 8DEB4A0 r Numericable [mouse] Init card protocol T0, FI=1, F=372, D=1, N=1
2013/03/15 08:39:21 8DEB4A0 r Numericable [mouse] Calculated work ETU is 74.40 us
2013/03/15 08:39:21 8DEB4A0 r Numericable [mouse] Init card protocol T0, FI=1, F=372, D=1, N=1
2013/03/15 08:39:21 8DEB4A0 r Numericable [mouse] Calculated work ETU is 74.40 us
2013/03/15 08:39:21 8DEB4A0 r Numericable [mouse] ATR Fsmax is: 5 Mhz, clocking card to wanted user cardspeed of 5.00 Mhz (specified in reader>mhz)
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] type: SECA, caid: 0100, serial: #########, card: Mediaguard v10.0
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] provider 1: 0000, valid: 1, name: ADMIN, expiry date: 2013/04/21
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] SA: ########
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] no PBM for provider 1
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] provider 2: A821, valid: 1, name: OFFRE1, expiry date: 2013/04/24
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] SA: ########
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] PBM for provider 2: 8000400701300000
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] provider 3: A825, valid: 0, name: CANAL+, expiry date: 1992/01/01
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] PBM for provider 3: 8000000000000000
2013/03/15 08:39:22 8DEB4A0 r Numericable [mouse] provider 4: A829, valid: 0, name: OFFRE2, expiry date: 1992/01/01
2013/03/15 08:39:23 8DEB4A0 r Numericable [mouse] PBM for provider 4: 8000000000000000
2013/03/15 08:39:23 8DEB4A0 r Numericable [mouse] provider 5: A82D, valid: 0, name: OFFRE3, expiry date: 1991/01/01
2013/03/15 08:39:23 8DEB4A0 r Numericable [mouse] PBM for provider 5: 8000000000000000
2013/03/15 08:39:23 8DEB4A0 r Numericable [mouse] providers: 5 (0000,A821,A825,A829,A82D)
2013/03/15 08:39:23 8DEB4A0 r Numericable [mouse] parental locked
2013/03/15 08:39:23 8DEB4A0 r Numericable [mouse] ready for requests
2013/03/15 08:39:23 8DEB4A0 r Numericable [mouse] found card system seca
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] provider 1: 0000, valid: 1, name: ADMIN, expiry date: 2013/04/21
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] SA: ########
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] provider 1: 0000, valid: 1, name: ADMIN, expiry date: 2013/04/21
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] SA: ########
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] no PBM for provider 1
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] provider 2: A821, valid: 1, name: OFFRE1, expiry date: 2013/04/24
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] SA: ########
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] PBM for provider 2: 8000400701300000
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] provider 3: A825, valid: 0, name: CANAL+, expiry date: 1992/01/01
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] PBM for provider 3: 8000000000000000
2013/03/15 08:39:23 8DEB4A0 r Numericable [seca] provider 4: A829, valid: 0, name: OFFRE2, expiry date: 1992/01/01
2013/03/15 08:39:24 8DEB4A0 r Numericable [seca] PBM for provider 4: 8000000000000000
2013/03/15 08:39:24 8DEB4A0 r Numericable [seca] provider 5: A82D, valid: 0, name: OFFRE3, expiry date: 1991/01/01
2013/03/15 08:39:24 8DEB4A0 r Numericable [seca] PBM for provider 5: 8000000000000000
2013/03/15 08:39:24 0 s init for all local cards done
2013/03/15 08:39:24 0 s anti cascading disabled
2013/03/15 08:39:24 8DED318 c client connected to 13000 port
2013/03/15 08:39:24 0 --- Skipped 1 duplicated log lines ---
2013/03/15 08:39:24 8DF0AE8 c anonymous disconnected from 192.168.0.2
2013/03/15 08:39:24 0 --- Skipped 1 duplicated log lines ---
2013/03/15 08:39:24 8DF5378 c client connected to 13000 port
2013/03/15 08:39:24 0 --- Skipped 1 duplicated log lines ---
2013/03/15 08:39:24 8DF3B48 c anonymous disconnected from 192.168.0.2
2013/03/15 08:39:24 8DF2318 c client connected to 13000 port
2013/03/15 08:39:24 8DF2318 c anonymous disconnected from 192.168.0.2
2013/03/15 08:39:24 8DF2318 c client connected to 13000 port
2013/03/15 08:39:24 8DF2318 c anonymous disconnected from 192.168.0.2
2013/03/15 08:39:24 8DF5378 c encrypted newcamd:13000-client 192.168.0.2 granted (tvheadend, au=off)
2013/03/15 08:39:24 8DF5378 c user tvheadend authenticated successfully (Tvheadend)
2013/03/15 08:39:24 8DF5378 c AU disabled for user tvheadend
2013/03/15 08:39:44 8DF5378 c tvheadend disconnected from 192.168.0.2
2013/03/15 08:39:46 8DED318 c client connected to 13000 port
2013/03/15 08:39:46 8DED318 c encrypted newcamd:13000-client 192.168.0.2 granted (tvheadend, au=off)
2013/03/15 08:39:46 8DED318 c user tvheadend authenticated successfully (Tvheadend)
2013/03/15 08:39:46 8DED318 c AU disabled for user tvheadend
2013/03/15 08:40:54 8DED318 c tvheadend (0100&00A821/0000/18CF/64:73229A3B0B60299F5223436C4184415D): found (353 ms) by Numericable
2013/03/15 08:41:02 8DED318 c tvheadend (0100&00A821/0000/18CF/64:FE477266AE154EB1DC55EBA3E85307A8): found (349 ms) by Numericable
2013/03/15 08:41:12 8DED318 c tvheadend (0100&00A821/0000/18CF/64:5ADED7B8628754FF3A5AC4CEF710C1FA): found (344 ms) by Numericable
2013/03/15 08:41:22 8DED318 c tvheadend (0100&00A821/0000/18CF/64:C57FB67EE361325FC414FCD3A1794EDE): found (344 ms) by Numericable
2013/03/15 08:41:38 8DED318 c tvheadend (0100&00A821/0000/18CF/64:36397A163897A5DDAAA5FAC617F05580): found (349 ms) by Numericable
2013/03/15 08:41:42 8DED318 c tvheadend (0100&00A821/0000/18CF/64:30013448419D584C38B3D2659C374200): found (345 ms) by Numericable
2013/03/15 08:41:52 8DED318 c tvheadend (0100&00A821/0000/18CF/64:D7D0D4AFA4D6A3964A8A86F867BC8F27): found (345 ms) by Numericable
2013/03/15 08:42:02 8DED318 c tvheadend (0100&00A821/0000/18CF/64:3FB9D7FD3245FFDE4C59951BF5A12DAC): found (344 ms) by Numericable
2013/03/15 08:42:12 8DED318 c tvheadend (0100&00A821/0000/18CF/64:6B01690DAE7DD78DFC60A276B5D927A8): found (349 ms) by Numericable
2013/03/15 08:42:22 8DED318 c tvheadend (0100&00A821/0000/18CF/64:9468336ABA9C8E9773F87288E67C91A0): found (344 ms) by Numericable

Any idea?

Val