Eurocardsharing

Go Back   Eurocardsharing > Sharing receivers > VDR and Kaffeine

VDR and Kaffeine Discussion, switching to an other channel takes too long at Sharing receivers forum; Hi to all! At the moment I'm playing with my linux pc (ubuntu 10.10), vdr (1.7.16), latest vdr-sc-plugin, vdr-vnsi-plugin and ...

Reply
 
LinkBack Thread Tools Display Modes
switching to an other channel takes too long
Old
  (#1)
bluebiker
Junior Member
 
bluebiker's Avatar
 
Offline
Posts: 5

Level: 1 [♥ Bé-Yêu ♥]
Life: 0 / 8
Magic: 1 / 173
Experience: 34%

Thanks: 0
Thanked 0 Times in 0 Posts
Join Date: Dec 2010
switching to an other channel takes too long - 20-February-2011, 22:26

Hi to all!

At the moment I'm playing with my linux pc (ubuntu 10.10), vdr (1.7.16), latest vdr-sc-plugin, vdr-vnsi-plugin and the xbmc as frontend.

I have one problem and no idea how to fix it.

I live in Germany and get my TV over cable. The channels are Nagra encrypted and have 5 different CAIDs (1722, 1801,1831,1838,1835). My server was able to provide the necessary information on caid 1722 for the sky channels and 1838 for many others.

If I switch to a sky channel everything works fine and fast enough, but if a switch to an other channel which needs to be decrypt with CAID 1838 it takes more 25 seconds because vdr or the sc-plugin tries 2 x 12 seconds the CAID 1722 before it tries the CAID 1838.

Here some lines form the log of vdr which exactly shows the problem
Code:
Feb 20 19:24:29.352 [core.ecm] 0.1: is no longer idle
Feb 20 19:24:29.412 [core.ecm] 0.1: triggered SID -1/12103 idx -1/1 mode -1/0 -
Feb 20 19:24:29.413 [core.ecm] from cache: system Cardclient (1722) id 0000 with ecm 227/80
Feb 20 19:24:29.413 [core.ecm] from cache: system Cardclient (1838) id 0000 with ecm 60e/80
Feb 20 19:24:29.413 [core.ecm] 0.1: try system Cardclient (1722) id 0000 with ecm 227 (cached) (pri=-15)
Feb 20 19:24:29.449 [core.au] 0: chain caid 1722 -> Cardclient(-15) [1500-82/ff/00]
Feb 20 19:24:29.449 [core.au] 0: chain caid 1801 -> none available
Feb 20 19:24:29.449 [core.au] 0: chain caid 1831 -> none available
Feb 20 19:24:29.449 [core.au] 0: chain caid 1838 -> Cardclient(-15) [1503-82/fe/00]
Feb 20 19:24:29.449 [core.au] 0: chain caid 1835 -> none available
Feb 20 19:24:29.449 [core.au] 0: starting chain 1722
Feb 20 19:24:29.469 [cardclient.core] cc-loop
Feb 20 19:24:29.469 [cardclient.core] now trying client cccam (127.0.0.1:9000)
Feb 20 19:24:29.469 [cardclient.cccam] 0: sending capmts for pid 0227
Feb 20 19:24:29.469 [cardclient.cccam] 0000: 9f 80 32 82 00 24 01 2f 47 00 00 19 01 81 08 00
Feb 20 19:24:29.469 [cardclient.cccam] 0010: 00 00 00 01 ba 00 00 82 02 01 00 84 02 02 27 09
Feb 20 19:24:29.469 [cardclient.cccam] 0020: 04 17 22 e2 27 01 0f 00 00 06
Feb 20 19:24:29.469 [cardclient.cccam] 0: using camd socket '/tmp/camd.socket'
Feb 20 19:24:29.469 [cardclient.cccam] 0: opened camd socket
Feb 20 19:24:35.469 [cardclient.cccam] 0: getting CW timedout, re-writing CAPMT
Feb 20 19:24:41.469 [cardclient.cccam] 0: getting CW timedout again, failed ECM
Feb 20 19:24:41.469 [cardclient.core] client cccam (127.0.0.1:9000) ECM failed (12000 ms)
Feb 20 19:24:41.469 [core.ecm] 0.1: filter flush (elapsed 12000)
Feb 20 19:24:41.568 [cardclient.core] cc-loop
Feb 20 19:24:41.568 [cardclient.core] now trying client cccam (127.0.0.1:9000)
Feb 20 19:24:41.568 [cardclient.cccam] 0: sending capmts for pid 0227
Feb 20 19:24:41.568 [cardclient.cccam] 0000: 9f 80 32 82 00 24 01 2f 47 01 00 19 01 81 08 00
Feb 20 19:24:41.568 [cardclient.cccam] 0010: 00 00 00 01 ba 00 00 82 02 01 00 84 02 02 27 09
Feb 20 19:24:41.568 [cardclient.cccam] 0020: 04 17 22 e2 27 01 0f 00 00 06
Feb 20 19:24:41.568 [cardclient.cccam] 0: using camd socket '/tmp/camd.socket'
Feb 20 19:24:41.568 [cardclient.cccam] 0: opened camd socket
Feb 20 19:24:47.568 [cardclient.cccam] 0: getting CW timedout, re-writing CAPMT
Feb 20 19:24:53.568 [cardclient.cccam] 0: getting CW timedout again, failed ECM
Feb 20 19:24:53.568 [cardclient.core] client cccam (127.0.0.1:9000) ECM failed (12001 ms)
Feb 20 19:24:53.568 [core.ecm] 0.1: filter flush (elapsed 12001)
Feb 20 19:24:53.568 [core.au] 0: starting chain 1838
Feb 20 19:24:53.568 [core.ecm] 0.1: try system Cardclient (1838) id 0000 with ecm 60e (cached) (pri=-15)
Feb 20 19:24:53.676 [cardclient.core] cc-loop
Feb 20 19:24:53.676 [cardclient.core] now trying client Newcamd (192.168.0.17:34001)
Feb 20 19:24:54.241 [core.ecm] invalidated cached prgId=12103 source=43000000 transponder=1ba ecm=227/80
Feb 20 19:24:54.241 [core.ecm] 0.1: correct key found
How can I manipulate this behaviour to reduce the time to an acceptable value?

Hope you can provide me some hints or better the solution ;)

greetings from Germany

bluebiker
   
Reply With Quote
Old
  (#2)
himself
Member
 
himself's Avatar
 
Offline
Posts: 83

Level: 8 [♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥]
Life: 0 / 176
Magic: 27 / 4748
Experience: 6%

Thanks: 5
Thanked 15 Times in 15 Posts
Join Date: Oct 2008
Location: Луцьк
21-February-2011, 23:40

how you connect to your server? n-lines or c-lines? weird behaviour though shouldn't happen in any case! if i check the log it seems that you mixed them. that is why i am asking.

Last edited by himself; 21-February-2011 at 23:43.. Reason: typo
   
Reply With Quote
Sponsored Links
Old
  (#3)
bluebiker
Junior Member
 
bluebiker's Avatar
 
Offline
Posts: 5

Level: 1 [♥ Bé-Yêu ♥]
Life: 0 / 8
Magic: 1 / 173
Experience: 34%

Thanks: 0
Thanked 0 Times in 0 Posts
Join Date: Dec 2010
22-February-2011, 23:12

Hi himself,

I tried both ways.
  • Card with Caid 1722 over CCcam; Card with CAID 1838 over newcamd connection to oscam
  • only an CLine and CCcam handels the connection to oscam

In the log output form my first post I use both C and N line.

The log below is from a c line connection only

Start of VDR:
Code:
Feb 22 21:43:38.167 [general.debug] capturing device 0/0
Feb 22 21:43:38.727 [general.info] SC version 1.0.0pre-HG-61975953edd0+ initializing (VDR 1.7.16)
Feb 22 21:43:38.730 [general.info] SC version 1.0.0pre-HG-61975953edd0+ starting (VDR 1.7.16)
Feb 22 21:43:38.731 [core.load] ** Plugin config:
Feb 22 21:43:38.731 [core.load] ** Key updates (AU) are enabled (active CAIDs) (no prestart)
Feb 22 21:43:38.731 [core.load] ** Local systems DON'T take priority over cached remote
Feb 22 21:43:38.731 [core.load] ** Concurrent FF recordings are NOT allowed
Feb 22 21:43:38.731 [core.load] ** Force transfermode with digital audio
Feb 22 21:43:38.731 [core.load] ** ECM cache is set to enabled
Feb 22 21:43:38.731 [core.load] ** ScCaps are 1 2 0 0 0 0 0 0 0 0
Feb 22 21:43:38.731 [general.info] loading cardclient config from /etc/vdr/plugins/sc/cardclient.conf
Feb 22 21:43:38.731 [cardclient.core] hostname=127.0.0.1 port=9000 emm=0 emmCaids 1722/ffff 1838/ffff
Feb 22 21:43:38.731 [cardclient.core] cccam: socket=/tmp/camd.socket
Feb 22 21:43:38.731 [cardclient.core] client 'cccam' ready
Feb 22 21:43:38.739 [core.net] socket: binding to 127.0.0.1:9000/udp (127.0.0.1)
Feb 22 21:43:38.739 [cardclient.cccam] Bound to port 9000, starting UDP listener
Feb 22 21:43:38.739 [general.info] loading ecm cache from /etc/vdr/plugins/sc/ecm.cache
Feb 22 21:43:38.742 [general.error] failed open /etc/vdr/plugins/sc/override.conf: No such file or directory
Feb 22 21:43:38.742 [core.load] ** registered systems:
Feb 22 21:43:38.742 [core.load] ** Cardclient        (pri -15)
Feb 22 21:43:38.742 [general.info] Budget mode forced on card 0
Feb 22 21:43:38.743 [core.ci] card 0, slot 0 (v= 1) caids: 1838 1722
Feb 22 21:43:38.743 [core.ci] 0: reset of slot 0 requested
Feb 22 21:43:38.743 [general.info] Using software decryption on card 0
Feb 22 21:43:40.300 [core.ci] 0.0: doReply changed, reset triggered
Feb 22 21:43:40.300 [core.ci] 0.0: now using CAIDs version 1
Feb 22 21:43:40.300 [core.ci] 0.0: status 'present'
Feb 22 21:43:40.801 [core.ci] 0.0: status 'reset'
Feb 22 21:43:41.802 [core.ci] 0.0: status 'ready'
Switch to a channel which requires CAID 1722 to decrypt
Code:
Feb 22 21:43:41.852 [core.ci] 0.0 -> 00 01 82 01 01
Feb 22 21:43:41.852 [core.ci] 0.0 <- 00 01 83 01 01 80 02 01 80
Feb 22 21:43:41.852 [core.ci] 0.0 -> 00 01 81 01 01
Feb 22 21:43:41.852 [core.ci] 0.0 <- 00 01 a0 07 01 91 04 00 03 00 41 80 02 01 00
Feb 22 21:43:41.852 [core.ci] 0.0 -> 00 01 a0 0a 01 92 07 00 00 03 00 41 00 01
Feb 22 21:43:41.852 [core.ci] 0.0 -> 00 01 a0 09 01 90 02 00 01 9f 80 30 00
Feb 22 21:43:41.852 [core.ci] 0.0 sending CA info
Feb 22 21:43:41.852 [core.ci] 0.0 -> 00 01 81 01 01
Feb 22 21:43:41.852 [core.ci] 0.0 <- 00 01 a0 0d 01 90 02 00 01 9f 80 31 04 18 38 17 22 80 02 01 00
Feb 22 21:43:42.853 [core.ci] 0.0 -> 00 01 a0 10 01 90 02 00 01 9f 80 32 07 03 00 00 01 00 01 03
Feb 22 21:43:42.853 [core.ci] 0.0 CA_PMT decoding len=7 lm=3 prg=0 len=1/1 ci_cmd(G)=03
Feb 22 21:43:42.853 [core.ci] 0.0 got CA pmt ciCmd=3 caLm=3
Feb 22 21:43:42.853 [core.ci] 0.0 answer to query
Feb 22 21:43:42.853 [core.ci] 0.0 -> 00 01 81 01 01
Feb 22 21:43:42.853 [core.ci] 0.0 <- 00 01 a0 0d 01 90 02 00 01 9f 80 33 04 00 00 00 81 80 02 01 00
Feb 22 21:43:48.810 [core.pids] 0: now tuned to source 43000000(C) transponder 71
Feb 22 21:43:50.562 [core.pids] 0: tune to same source/transponder
Feb 22 21:44:38.827 [core.pids] 0: tune to same source/transponder
Feb 22 21:44:59.843 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:44:59.843 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:44:59.843 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:44:59.843 [core.pids] 0: now tuned to source 43000000(C) transponder 79
Feb 22 21:45:20.868 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:45:20.868 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:45:20.868 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:45:20.868 [core.pids] 0: now tuned to source 43000000(C) transponder 15a
Feb 22 21:45:41.892 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:45:41.892 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:45:41.892 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:45:41.892 [core.pids] 0: now tuned to source 43000000(C) transponder 162
Feb 22 21:46:02.917 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:46:02.917 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:46:02.917 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:46:02.917 [core.pids] 0: now tuned to source 43000000(C) transponder 16a
Feb 22 21:46:23.941 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:46:23.941 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:46:23.941 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:46:23.941 [core.pids] 0: now tuned to source 43000000(C) transponder 172
Feb 22 21:46:44.965 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:46:44.965 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:46:44.965 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:46:44.965 [core.pids] 0: now tuned to source 43000000(C) transponder 17a
Feb 22 21:47:05.991 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:47:05.991 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:47:05.991 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:47:05.991 [core.pids] 0: now tuned to source 43000000(C) transponder 182
Feb 22 21:47:27.017 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:47:27.017 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:47:27.017 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:47:27.017 [core.pids] 0: now tuned to source 43000000(C) transponder 18a
Feb 22 21:47:27.067 [core.ci] 0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Feb 22 21:47:27.067 [core.ci] 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Feb 22 21:47:27.067 [core.ci] 0.0 got CA pmt ciCmd=-1 caLm=3
Feb 22 21:47:50.502 [core.pids] 0: now tuned to source 43000000(C) transponder 16a
Feb 22 21:47:50.553 [core.ci] 0.0 -> 00 01 a0 30 01 90 02 00 01 9f 80 32 27 04 00 29 01 00 0d 01 09 04 17 22 f7 4c 09 04 18 38 fe 34 02 03 ff 00 00 04 04 00 00 00 04 04 01 00 00 06 04 03 00 00
Feb 22 21:47:50.553 [core.ci] 0.0 CA_PMT decoding len=27 lm=4 prg=41 len=d/21 ci_cmd(G)=01 pid=2,3ff len=0/f pid=4,400 len=0/a pid=4,401 len=0/5 pid=6,403 len=0/0
Feb 22 21:47:50.553 [core.ci] 0.0 got CA pmt ciCmd=1 caLm=4
Feb 22 21:47:50.553 [core.ci] 0.0 set CAM decrypt (prg 41)
Feb 22 21:47:50.553 [core.pids] 0: add SID 41 (zero=0 noshift=1)
Feb 22 21:47:50.553 [core.pids] 0: pids VIDEO=03ff AUDIO=0400 AUDIO=0401 DOLBY=0403
Feb 22 21:47:50.553 [core.pids] SimplyCa entry pidCa=0
Feb 22 21:47:50.553 [core.pids] prgca: 09 04 17 22 F7 4C 09 04 18 38 FE 34
Feb 22 21:47:50.553 [core.pids] pidca 03ff: <empty>
Feb 22 21:47:50.553 [core.pids] pidca 0400: <empty>
Feb 22 21:47:50.553 [core.pids] pidca 0401: <empty>
Feb 22 21:47:50.553 [core.pids] pidca 0403: <empty>
Feb 22 21:47:50.553 [core.pids] SimplyCa exit pidCa=0
Feb 22 21:47:50.553 [core.pids] prgca: 09 04 17 22 F7 4C 09 04 18 38 FE 34
Feb 22 21:47:50.553 [core.pids] pidca 03ff: <empty>
Feb 22 21:47:50.553 [core.pids] pidca 0400: <empty>
Feb 22 21:47:50.553 [core.pids] pidca 0401: <empty>
Feb 22 21:47:50.553 [core.pids] pidca 0403: <empty>
Feb 22 21:47:50.553 [core.pids] 0: SID=41 zero=0 | sid=-/-/- zero=-/-/- idle=-/-/-
Feb 22 21:47:50.553 [core.pids] 0: found handler for SID 41 (0.1 idle=1 idx=1)
Feb 22 21:47:50.553 [core.ecm] 0.1: setting new SID 41
Feb 22 21:47:50.553 [core.pids] 0.1: pids on entry
Feb 22 21:47:50.553 [core.pids] 0.1: pids after delete
Feb 22 21:47:50.553 [core.pids] 0: descrambling pid 03ff on index 1
Feb 22 21:47:50.553 [core.pids] 0: descrambling pid 0400 on index 1
Feb 22 21:47:50.553 [core.pids] 0: descrambling pid 0401 on index 1
Feb 22 21:47:50.553 [core.pids] 0: descrambling pid 0403 on index 1
Feb 22 21:47:50.553 [core.pids] 0.1: pids after add VIDEO=03ff AUDIO=0400 AUDIO=0401 DOLBY=0403
Feb 22 21:47:50.553 [core.ecm] 0.1: is no longer idle
Feb 22 21:47:50.613 [core.ecm] 0.1: triggered SID -1/41 idx -1/1 mode -1/0 -
Feb 22 21:47:50.613 [core.ecm] 0.1: new caDescr: 09 04 17 22 F7 4C 09 04 18 38 FE 34
Feb 22 21:47:50.613 [core.ecm] from cache: system Cardclient (1722) id 0000 with ecm 174c/80
Feb 22 21:47:50.613 [core.ecm] 0.1: CA descriptors for SID 41 (len=12)
Feb 22 21:47:50.613 [core.ecm] 0.1: descriptor 17 22 f7 4c
Feb 22 21:47:50.613 [core.ecm] 0.1: found 1722(0000) (Cardclient) id 0000 with ecm 174c/80 (already present)
Feb 22 21:47:50.613 [core.ecm] 0.1: descriptor 18 38 fe 34
Feb 22 21:47:50.613 [core.ecm] 0.1: found 1838(0000) (Cardclient) id 0000 with ecm 1e34/80 (new)
Feb 22 21:47:50.614 [core.ecm] 0.1: try system Cardclient (1722) id 0000 with ecm 174c (cached) (pri=-15)
Feb 22 21:47:50.654 [cardclient.core] cc-loop
Feb 22 21:47:50.654 [cardclient.core] now trying client cccam (127.0.0.1:9000)
Feb 22 21:47:50.654 [cardclient.cccam] 0: sending capmts for pid 174c
Feb 22 21:47:50.654 [cardclient.cccam] 0000: 9f 80 32 82 00 24 01 00 29 00 00 19 01 81 08 00
Feb 22 21:47:50.654 [cardclient.cccam] 0010: 00 00 00 01 6a 00 00 82 02 01 00 84 02 17 4c 09
Feb 22 21:47:50.654 [cardclient.cccam] 0020: 04 17 22 f7 4c 01 0f 00 00 06
Feb 22 21:47:50.654 [cardclient.cccam] 0: using camd socket '/tmp/camd.socket'
Feb 22 21:47:50.654 [cardclient.cccam] 0: opened camd socket
Feb 22 21:47:50.990 [core.au] 0: chain caid 1722 -> Cardclient(-15) [1500-82/ff/00]
Feb 22 21:47:50.990 [core.au] 0: chain caid 1801 -> none available
Feb 22 21:47:50.990 [core.au] 0: chain caid 1831 -> none available
Feb 22 21:47:50.990 [core.au] 0: chain caid 1838 -> Cardclient(-15) [1503-82/fe/00]
Feb 22 21:47:50.990 [core.au] 0: chain caid 1835 -> none available
Feb 22 21:47:50.990 [core.au] 0: starting chain 1722
Feb 22 21:47:51.738 [cardclient.cccam] got: 000f  c8 d1 96 2f b6 3d 8f 82 00 00 00 00 00 00 00 00
Feb 22 21:47:51.738 [cardclient.cccam] got: 000f  c8 d1 96 2f b6 3d 8f 82 0d 5c 1c 85 9c 36 56 28
Feb 22 21:47:51.738 [cardclient.cccam] 0: got a CW
Feb 22 21:47:51.738 [core.ecm] 0.1: correct key found
Switch to a channel which requires CAID 1838 to decrypt

Code:
Feb 22 21:52:13.386 [core.pids] 0: now tuned to source 43000000(C) transponder 1ba
Feb 22 21:52:13.436 [core.ci] 0.0 -> 00 01 a0 26 01 90 02 00 01 9f 80 32 1d 04 2f 49 01 00 0d 01 09 04 17 22 e2 3b 09 04 18 38 e6 22 02 02 33 00 00 04 02 34 00 00
Feb 22 21:52:13.436 [core.ci] 0.0 CA_PMT decoding len=1d lm=4 prg=12105 len=d/17 ci_cmd(G)=01 pid=2,233 len=0/5 pid=4,234 len=0/0
Feb 22 21:52:13.436 [core.ci] 0.0 got CA pmt ciCmd=1 caLm=4
Feb 22 21:52:13.436 [core.ci] 0.0 set CAM decrypt (prg 12105)
Feb 22 21:52:13.436 [core.pids] 0: add SID 12105 (zero=0 noshift=1)
Feb 22 21:52:13.436 [core.pids] 0: pids VIDEO=0233 AUDIO=0234
Feb 22 21:52:13.436 [core.pids] SimplyCa entry pidCa=0
Feb 22 21:52:13.436 [core.pids] prgca: 09 04 17 22 E2 3B 09 04 18 38 E6 22
Feb 22 21:52:13.436 [core.pids] pidca 0233: <empty>
Feb 22 21:52:13.436 [core.pids] pidca 0234: <empty>
Feb 22 21:52:13.436 [core.pids] SimplyCa exit pidCa=0
Feb 22 21:52:13.436 [core.pids] prgca: 09 04 17 22 E2 3B 09 04 18 38 E6 22
Feb 22 21:52:13.436 [core.pids] pidca 0233: <empty>
Feb 22 21:52:13.436 [core.pids] pidca 0234: <empty>
Feb 22 21:52:13.436 [core.pids] 0: SID=12105 zero=0 | sid=-/-/- zero=-/-/- idle=1/-1/1
Feb 22 21:52:13.436 [core.pids] 0: found handler for SID 12105 (0.1 idle=1 idx=1)
Feb 22 21:52:13.436 [core.ecm] 0.1: setting new SID 12105
Feb 22 21:52:13.436 [core.pids] 0.1: pids on entry
Feb 22 21:52:13.436 [core.pids] 0.1: pids after delete
Feb 22 21:52:13.436 [core.pids] 0: descrambling pid 0233 on index 1
Feb 22 21:52:13.436 [core.pids] 0: descrambling pid 0234 on index 1
Feb 22 21:52:13.436 [core.pids] 0.1: pids after add VIDEO=0233 AUDIO=0234
Feb 22 21:52:13.436 [core.ecm] 0.1: is no longer idle
Feb 22 21:52:13.443 [core.ecm] 0.1: triggered SID -1/12105 idx 1/1 mode -1/0 -
Feb 22 21:52:13.443 [core.ecm] 0.1: new caDescr: 09 04 17 22 E2 3B 09 04 18 38 E6 22
Feb 22 21:52:13.443 [core.ecm] from cache: system Cardclient (1722) id 0000 with ecm 23b/80
Feb 22 21:52:13.443 [core.ecm] from cache: system Cardclient (1838) id 0000 with ecm 622/80
Feb 22 21:52:13.443 [core.ecm] 0.1: CA descriptors for SID 12105 (len=12)
Feb 22 21:52:13.443 [core.ecm] 0.1: descriptor 17 22 e2 3b
Feb 22 21:52:13.443 [core.ecm] 0.1: found 1722(0000) (Cardclient) id 0000 with ecm 23b/80 (already present)
Feb 22 21:52:13.443 [core.ecm] 0.1: descriptor 18 38 e6 22
Feb 22 21:52:13.443 [core.ecm] 0.1: found 1838(0000) (Cardclient) id 0000 with ecm 622/80 (already present)
Feb 22 21:52:13.443 [core.ecm] 0.1: try system Cardclient (1722) id 0000 with ecm 23b (cached) (pri=-15)
Feb 22 21:52:13.500 [cardclient.core] cc-loop
Feb 22 21:52:13.500 [cardclient.core] now trying client cccam (127.0.0.1:9000)
Feb 22 21:52:13.500 [cardclient.cccam] 0: sending capmts for pid 023b
Feb 22 21:52:13.500 [cardclient.cccam] 0000: 9f 80 32 82 00 24 01 2f 49 01 00 19 01 81 08 00
Feb 22 21:52:13.500 [cardclient.cccam] 0010: 00 00 00 01 ba 00 00 82 02 01 00 84 02 02 3b 09
Feb 22 21:52:13.500 [cardclient.cccam] 0020: 04 17 22 e2 3b 01 0f 00 00 06
Feb 22 21:52:13.893 [core.au] 0: chain caid 1722 -> Cardclient(-15) [1500-82/ff/00]
Feb 22 21:52:13.893 [core.au] 0: chain caid 1801 -> none available
Feb 22 21:52:13.893 [core.au] 0: chain caid 1831 -> none available
Feb 22 21:52:13.893 [core.au] 0: chain caid 1838 -> Cardclient(-15) [1503-82/fe/00]
Feb 22 21:52:13.893 [core.au] 0: chain caid 1835 -> none available
Feb 22 21:52:13.893 [core.au] 0: starting chain 1722
Feb 22 21:52:19.500 [cardclient.cccam] 0: getting CW timedout, re-writing CAPMT
Feb 22 21:52:25.500 [cardclient.cccam] 0: getting CW timedout again, failed ECM
Feb 22 21:52:25.500 [cardclient.core] client cccam (127.0.0.1:9000) ECM failed (12001 ms)
Feb 22 21:52:25.500 [core.ecm] 0.1: filter flush (elapsed 12001)
Feb 22 21:52:25.592 [cardclient.core] cc-loop
Feb 22 21:52:25.592 [cardclient.core] now trying client cccam (127.0.0.1:9000)
Feb 22 21:52:25.592 [cardclient.cccam] 0: sending capmts for pid 023b
Feb 22 21:52:25.592 [cardclient.cccam] 0000: 9f 80 32 82 00 24 01 2f 49 02 00 19 01 81 08 00
Feb 22 21:52:25.592 [cardclient.cccam] 0010: 00 00 00 01 ba 00 00 82 02 01 00 84 02 02 3b 09
Feb 22 21:52:25.592 [cardclient.cccam] 0020: 04 17 22 e2 3b 01 0f 00 00 06
Feb 22 21:52:25.592 [cardclient.cccam] 0: using camd socket '/tmp/camd.socket'
Feb 22 21:52:25.592 [cardclient.cccam] 0: opened camd socket
Feb 22 21:52:31.592 [cardclient.cccam] 0: getting CW timedout, re-writing CAPMT
Feb 22 21:52:37.592 [cardclient.cccam] 0: getting CW timedout again, failed ECM
Feb 22 21:52:37.592 [cardclient.core] client cccam (127.0.0.1:9000) ECM failed (12001 ms)
Feb 22 21:52:37.592 [core.ecm] 0.1: filter flush (elapsed 12001)
Feb 22 21:52:37.592 [core.au] 0: starting chain 1838
Feb 22 21:52:37.592 [core.ecm] 0.1: try system Cardclient (1838) id 0000 with ecm 622 (cached) (pri=-15)
Feb 22 21:52:37.684 [cardclient.core] cc-loop
Feb 22 21:52:37.685 [cardclient.core] now trying client cccam (127.0.0.1:9000)
Feb 22 21:52:37.685 [cardclient.cccam] 0: sending capmts for pid 0622
Feb 22 21:52:37.685 [cardclient.cccam] 0000: 9f 80 32 82 00 24 01 2f 49 03 00 19 01 81 08 00
Feb 22 21:52:37.685 [cardclient.cccam] 0010: 00 00 00 01 ba 00 00 82 02 01 00 84 02 06 22 09
Feb 22 21:52:37.685 [cardclient.cccam] 0020: 04 18 38 e6 22 01 0f 00 00 06
Feb 22 21:52:37.685 [cardclient.cccam] 0: using camd socket '/tmp/camd.socket'
Feb 22 21:52:37.685 [cardclient.cccam] 0: opened camd socket
Feb 22 21:52:38.391 [cardclient.cccam] got: 000f  a4 d1 b9 2e 68 51 5d 16 00 00 00 00 00 00 00 00
Feb 22 21:52:38.391 [cardclient.cccam] got: 000f  a4 d1 b9 2e 68 51 5d 16 f8 ba 3a ec 97 7b b9 cb
Feb 22 21:52:38.391 [cardclient.cccam] 0: got a CW
Feb 22 21:52:38.391 [core.ecm] invalidated cached prgId=12105 source=43000000 transponder=1ba ecm=23b/80
Feb 22 21:52:38.391 [core.ecm] 0.1: correct key found
I don't understand why vdr-sc waits so long before it tries the other CAID.
I checked every configfile to find out how to reduce the timeout, but without any success.

Hope you have any further idea.

regards

bluebiker
   
Reply With Quote
Old
  (#4)
abuser
Junior Member
 
abuser's Avatar
 
Offline
Posts: 8

Level: 1 [♥ Bé-Yêu ♥]
Life: 0 / 18
Magic: 2 / 582
Experience: 73%

Thanks: 0
Thanked 1 Time in 1 Post
Join Date: Feb 2007
23-February-2011, 01:10

try to delete all sc libraries except libsc-cardclient-**.so.**** and of course libvdr-sc.so.***

you can also tell sc to ignore specific caids for specific freq. in override.conf

regards,
abuser
   
Reply With Quote
Old
  (#5)
bluebiker
Junior Member
 
bluebiker's Avatar
 
Offline
Posts: 5

Level: 1 [♥ Bé-Yêu ♥]
Life: 0 / 8
Magic: 1 / 173
Experience: 34%

Thanks: 0
Thanked 0 Times in 0 Posts
Join Date: Dec 2010
23-February-2011, 12:21

Hi abuser,

I have already deleted all unnecessary libs before I started to test.

I know the possibility to set this options in the override.conf, but I am not able to configure it for every channel because the cards in my share sometimes change and sometimes I have luck and an Card with CAID 1722 was able to encrypt and the next Day I have a Card with CAID 1838 which can do the job.

I also reduce the amount of valid CAIDs to make the debugging easier, in the cable the supported CAIDs are: 1722, 1801, 1831, 1835, 1838 and on my Server I have 3 of them.

regards

bluebiker
   
Reply With Quote
Old
  (#6)
himself
Member
 
himself's Avatar
 
Offline
Posts: 83

Level: 8 [♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥]
Life: 0 / 176
Magic: 27 / 4748
Experience: 6%

Thanks: 5
Thanked 15 Times in 15 Posts
Join Date: Oct 2008
Location: Луцьк
24-February-2011, 12:05

that is really weired. i just checked for my system and already the cccam server handles the caid. so it is not done by sc...
just 4 testing: get a cccam server running on your machine and make sc connect local. just want to figure out if the problem is there...
besides it might make sense for you make a cronjob which deletes the ecm info file from time to time. but that is just a shot in the dark...
   
Reply With Quote
Old
  (#7)
bluebiker
Junior Member
 
bluebiker's Avatar
 
Offline
Posts: 5

Level: 1 [♥ Bé-Yêu ♥]
Life: 0 / 8
Magic: 1 / 173
Experience: 34%

Thanks: 0
Thanked 0 Times in 0 Posts
Join Date: Dec 2010
24-February-2011, 15:32

H himself,

in the log of the local running cccam client/server (used just as client) i can see that vdr-sc just ask for caid 1722 (the first 12 seconds) and than it tries the 1838. So CCcam has no information about the other needed CAIDs.

My connection is the following at the moment:
vdr-sc over camd.socket -> local running cccam (as client) -> cccam server.

If a fine working dreambox make this request i can see (in the CCcam log of the dreambox (clientlog)) that it first ask for caid 1722 and in less than a second it asks for the other available caids.

Quote:
get a cccam server running on your machine and make sc connect local. just want to figure out if the problem is there...
Can you go a little more into detail? I am not quite sure what you mean
because CCcam is running local.

Quote:
besides it might make sense for you make a cronjob which deletes the ecm info file from time to time. but that is just a shot in the dark...
I will try this today after work. What is "from time to time" for you? every 10 minutes?

regards

bluebiker
   
Reply With Quote
Old
  (#8)
himself
Member
 
himself's Avatar
 
Offline
Posts: 83

Level: 8 [♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥♥ Bé-Yêu ♥]
Life: 0 / 176
Magic: 27 / 4748
Experience: 6%

Thanks: 5
Thanked 15 Times in 15 Posts
Join Date: Oct 2008
Location: Луцьк
24-February-2011, 21:57

first of i never did the ca preload thing. i assume that is what you mean by camd-socket.

what i meant is that you run an extra instance of ccca server on your htpc. basicly only to see how the system acts then.
actually i am pretty sure that the problem is that in the ecm.cache (if that name is correct) there is some data concerning channel caid ... that means if you change the caid for the same channel you might run into problems as sc first checks the history to see if the key is still valid.
so you should delete it whenever you change caid for the same provider.

i am not sure about all those caids. they are all sky de?

that really is a weired problem. can not mention it often enough...
   
Reply With Quote
Old
  (#9)
abuser
Junior Member
 
abuser's Avatar
 
Offline
Posts: 8

Level: 1 [♥ Bé-Yêu ♥]
Life: 0 / 18
Magic: 2 / 582
Experience: 73%

Thanks: 0
Thanked 1 Time in 1 Post
Join Date: Feb 2007
25-February-2011, 00:26

Quote:
Originally Posted by bluebiker View Post
H himself,

in the log of the local running cccam client/server (used just as client) i can see that vdr-sc just ask for caid 1722 (the first 12 seconds) and than it tries the 1838. So CCcam has no information about the other needed CAIDs.

My connection is the following at the moment:
vdr-sc over camd.socket -> local running cccam (as client) -> cccam server.

If a fine working dreambox make this request i can see (in the CCcam log of the dreambox (clientlog)) that it first ask for caid 1722 and in less than a second it asks for the other available caids.



Can you go a little more into detail? I am not quite sure what you mean
because CCcam is running local.



I will try this today after work. What is "from time to time" for you? every 10 minutes?

regards

bluebiker
try and compile ca.so from tvheadend cvs source, it is called capmt_ca.so
did you use self compiled vdr-sc, or deb from someones repo?
in override.conf you can also make ecmpriorities for specified transpoder with specified caid
deleting ecm.cache is not necessary (my opinion)

regards,
abuser
   
Reply With Quote
Reply

Bookmarks

Tags
chain, reduce, switching, timeout


Currently Active Users Viewing This Thread: 1 (0 members and 1 guests)
 
Thread Tools
Display Modes

Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts

BB code is On
Smilies are On
[IMG] code is On
HTML code is Off
Trackbacks are Off
Pingbacks are Off
Refbacks are Off

Forum Jump



ECS on RSS ECS on Twitter ECS on Facebook ECS on Youtube
Follow us on:

Powered by vBulletin
Copyright 2002 - 2010, Jelsoft Enterprises Ltd.
SEO by vBSEO ©2011, Crawlability, Inc.
Dreambox