Nov 28 22:22:20.489 [general.debug] logfile '/var/log/vdr-sc' opened
Nov 28 22:22:20.489 [general.debug] capturing device 0/0 (subsystem ID 13c2101c)
Nov 28 22:22:20.489 [general.debug] creating standard device 0/0
Nov 28 22:22:20.490 [general.debug] capturing device 1/0 (subsystem ID d4709022)
Nov 28 22:22:20.490 [general.debug] creating standard device 1/0
Nov 28 22:22:20.524 [general.info] SC version 1.0.0pre-AR-29b7b5f231c8 initializing (VDR 1.7.27)
Nov 28 22:22:20.525 [general.info] SC version 1.0.0pre-AR-29b7b5f231c8 starting (VDR 1.7.27)
Nov 28 22:22:20.525 [core.load] ** Plugin config:
Nov 28 22:22:20.525 [core.load] ** Key updates (AU) are enabled (active CAIDs) (no prestart)
Nov 28 22:22:20.525 [core.load] ** Local systems DON'T take priority over cached remote
Nov 28 22:22:20.525 [core.load] ** Concurrent FF recordings are NOT allowed
Nov 28 22:22:20.525 [core.load] ** Force transfermode with digital audio
Nov 28 22:22:20.525 [core.load] ** ECM cache is set to enabled
Nov 28 22:22:20.525 [core.load] ** TsBufferSize is 4 MB
Nov 28 22:22:20.525 [core.load] ** ScCaps are 1 2 0 0 0 0 0 0 0 0
Nov 28 22:22:20.525 [general.info] loading cardclient config from /var/lib/vdr/plugins/sc/cardclient.conf
Nov 28 22:22:20.525 [cardclient.cccam2extra] cmd 0c crypt mode now 0
Nov 28 22:22:20.525 [cardclient.cccam2] logout from server initiated
Nov 28 22:22:20.525 [cardclient.cccam2extra] reader thread stopped
Nov 28 22:22:20.525 [cardclient.cccam2extra] network shut down
Nov 28 22:22:20.525 [cardclient.cccam2extra] cmd 0c crypt mode now 0
Nov 28 22:22:20.525 [cardclient.cccam2extra] logout done
Nov 28 22:22:20.525 [cardclient.core] hostname=192.168.0.56 port=56073 emm=1 emmCaids 098c/ffff
Nov 28 22:22:20.525 [cardclient.core] cccam2: username=
nas password=password
Nov 28 22:22:20.525 [cardclient.core] our nodeid: 6c 91 d3 63 6b a6 3a 0c
Nov 28 22:22:20.525 [cardclient.core] pretended CCcam version '2.0.11' build '2892'
Nov 28 22:22:20.525 [cardclient.core] client 'cccam2' ready
Nov 28 22:22:20.525 [cardclient.cccam2] logout from server initiated
Nov 28 22:22:20.525 [cardclient.cccam2extra] reader thread stopped
Nov 28 22:22:20.525 [cardclient.cccam2extra] network shut down
Nov 28 22:22:20.525 [cardclient.cccam2extra] cmd 0c crypt mode now 0
Nov 28 22:22:20.525 [cardclient.cccam2extra] logout done
Nov 28 22:22:20.525 [core.net] connecting to 192.168.0.56:56073/tcp (192.168.0.56)
Nov 28 22:22:20.528 [cardclient.cccam2extra] welcome checksum correct
Nov 28 22:22:20.530 [cardclient.login] CCcam login succeed
Nov 28 22:22:20.530 [general.error] failed open /var/lib/vdr/plugins/sc/override.conf: Datei oder Verzeichnis nicht gefunden
Nov 28 22:22:20.530 [core.load] ** registered systems:
Nov 28 22:22:20.530 [core.load] ** Cardclient (pri -15)
Nov 28 22:22:20.530 [general.info] Using software decryption on card 1/0
Nov 28 22:22:20.530 [core.ci] 1/0: no active CAIDs
Nov 28 22:22:20.530 [core.ci] 1/0: reset of slot 0 requested
Nov 28 22:22:20.531 [general.info] Using software decryption on card 0/0
Nov 28 22:22:20.531 [core.ci] 0/0: no active CAIDs
Nov 28 22:22:20.531 [core.ci] card 0/0, slot 0 (v= 1) caids:
Nov 28 22:22:20.531 [core.ci] 0/0: reset of slot 0 requested
Nov 28 22:22:20.533 [cardclient.login] cccam2: server version 2.1.1 build 2971 nodeid 06 df 31 ed 10 bf 15 06
Nov 28 22:22:20.533 [cardclient.cccam2extra] cmd 0B aes key 06 df 31 ed 10 bf 15 06 32 2e 31 2e 31 00 00 00
Nov 28 22:22:21.486 [core.ci] 1/0.0: doReply changed, reset triggered
Nov 28 22:22:21.487 [core.ci] 1/0.0: status 'present'
Nov 28 22:22:21.587 [core.ci] 1/0.0: status 'reset'
Nov 28 22:22:21.588 [core.ci] 0/0.0: doReply changed, reset triggered
Nov 28 22:22:21.588 [core.ci] 0/0.0: now using CAIDs version 1
Nov 28 22:22:21.588 [core.ci] 0/0.0: status 'present'
Nov 28 22:22:21.589 [core.ci] 0/0.0: status 'reset'
Nov 28 22:22:21.991 [cardclient.cccam2shares] ADD share 00000064 hops 0 maxdown 10 caid 098c ua 0000000000642057 prov 000000/00642000 (EMM)
Nov 28 22:22:21.991 [cardclient.cccam2] enabled EMM processing
Nov 28 22:22:22.241 [core.ci] 1/0.0: status 'present'
Nov 28 22:22:22.595 [core.ci] 0/0.0: status 'present'
Nov 28 22:22:31.556 [core.ci] card 1/0, slot 0 (v= 1) caids: 098c
Nov 28 22:22:31.556 [core.ci] 1/0.0: now using CAIDs version 1
Nov 28 22:22:31.657 [core.ci] 1/0.0: status 'ready'
Nov 28 22:22:31.659 [core.ci] card 0/0, slot 0 (v= 2) caids: 098c
Nov 28 22:22:31.659 [core.ci] 0/0.0: now using CAIDs version 2
Nov 28 22:22:31.659 [core.ci] 0/0.0: status 'ready'
Nov 28 22:22:31.707 [core.ci] 1/0.0 -> 00 01 82 01 01
Nov 28 22:22:31.707 [core.ci] 1/0.0 <- 00 01 83 01 01 80 02 01 80
Nov 28 22:22:31.707 [core.ci] 1/0.0 -> 00 01 81 01 01
Nov 28 22:22:31.708 [core.ci] 1/0.0 <- 00 01 a0 07 01 91 04 00 03 00 41 80 02 01 00
Nov 28 22:22:31.708 [core.ci] 1/0.0 -> 00 01 a0 0a 01 92 07 00 00 03 00 41 00 01
Nov 28 22:22:31.708 [core.ci] 1/0.0 -> 00 01 a0 09 01 90 02 00 01 9f 80 30 00
Nov 28 22:22:31.708 [core.ci] 1/0.0 sending CA info
Nov 28 22:22:31.708 [core.ci] 1/0.0 -> 00 01 81 01 01
Nov 28 22:22:31.708 [core.ci] 1/0.0 <- 00 01 a0 0b 01 90 02 00 01 9f 80 31 02 09 8c 80 02 01 00
Nov 28 22:22:31.709 [core.ci] 0/0.0 -> 00 01 82 01 01
Nov 28 22:22:31.710 [core.ci] 0/0.0 <- 00 01 83 01 01 80 02 01 80
Nov 28 22:22:31.710 [core.ci] 0/0.0 -> 00 01 81 01 01
Nov 28 22:22:31.710 [core.ci] 0/0.0 <- 00 01 a0 07 01 91 04 00 03 00 41 80 02 01 00
Nov 28 22:22:31.710 [core.ci] 0/0.0 -> 00 01 a0 0a 01 92 07 00 00 03 00 41 00 01
Nov 28 22:22:31.710 [core.ci] 0/0.0 -> 00 01 a0 09 01 90 02 00 01 9f 80 30 00
Nov 28 22:22:31.710 [core.ci] 0/0.0 sending CA info
Nov 28 22:22:31.710 [core.ci] 0/0.0 -> 00 01 81 01 01
Nov 28 22:22:31.710 [core.ci] 0/0.0 <- 00 01 a0 0b 01 90 02 00 01 9f 80 31 02 09 8c 80 02 01 00
Nov 28 22:22:32.715 [core.ci] 1/0.0 -> 00 01 a0 10 01 90 02 00 01 9f 80 32 07 03 00 00 01 00 01 03
Nov 28 22:22:32.715 [core.ci] 1/0.0 CA_PMT decoding len=7 lm=3 prg=0 len=1/1 ci_cmd(G)=03
Nov 28 22:22:32.715 [core.ci] 1/0.0 got CA pmt ciCmd=3 caLm=3
Nov 28 22:22:32.715 [core.ci] 1/0.0 answer to query
Nov 28 22:22:32.715 [core.ci] 1/0.0 no action taken
Nov 28 22:22:32.715 [core.ci] 1/0.0 -> 00 01 81 01 01
Nov 28 22:22:32.715 [core.ci] 1/0.0 <- 00 01 a0 0d 01 90 02 00 01 9f 80 33 04 00 00 00 81 80 02 01 00
Nov 28 22:22:32.716 [core.ci] 0/0.0 -> 00 01 a0 10 01 90 02 00 01 9f 80 32 07 03 00 00 01 00 01 03
Nov 28 22:22:32.717 [core.ci] 0/0.0 CA_PMT decoding len=7 lm=3 prg=0 len=1/1 ci_cmd(G)=03
Nov 28 22:22:32.717 [core.ci] 0/0.0 got CA pmt ciCmd=3 caLm=3
Nov 28 22:22:32.717 [core.ci] 0/0.0 answer to query
Nov 28 22:22:32.717 [core.ci] 0/0.0 no action taken
Nov 28 22:22:32.717 [core.ci] 0/0.0 -> 00 01 81 01 01
Nov 28 22:22:32.717 [core.ci] 0/0.0 <- 00 01 a0 0d 01 90 02 00 01 9f 80 33 04 00 00 00 81 80 02 01 00
Nov 28 22:22:32.866 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b4dd
Nov 28 22:23:21.818 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b148
Nov 28 22:23:21.818 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b2e4
Nov 28 22:23:42.830 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b48e
Nov 28 22:23:42.830 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b4dd
Nov 28 22:24:03.998 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b503
Nov 28 22:24:03.998 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b52a
Nov 28 22:24:05.053 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b48e
Nov 28 22:24:05.053 [general.info] Forcing transfermode on card 1/0
Nov 28 22:24:20.541 [cardclient.cccam2] server PONG
Nov 28 22:24:24.990 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b552
Nov 28 22:24:25.029 [core.ci] 1/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 28 22:24:25.029 [core.ci] 1/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 28 22:24:25.029 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 28 22:24:25.029 [core.ci] 1/0.0 answer to query surpressed
Nov 28 22:24:25.029 [core.ci] 1/0.0 stop decrypt
Nov 28 22:24:25.029 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b578
Nov 28 22:24:46.041 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b59f
Nov 28 22:24:46.064 [core.ci] 1/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 28 22:24:46.064 [core.ci] 1/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 28 22:24:46.064 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 28 22:24:46.064 [core.ci] 1/0.0 answer to query surpressed
Nov 28 22:24:46.064 [core.ci] 1/0.0 stop decrypt
Nov 28 22:24:46.064 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b5c6
Nov 28 22:25:07.076 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b5ed
Nov 28 22:25:07.109 [core.ci] 1/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 28 22:25:07.109 [core.ci] 1/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 28 22:25:07.109 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 28 22:25:07.109 [core.ci] 1/0.0 answer to query surpressed
Nov 28 22:25:07.109 [core.ci] 1/0.0 stop decrypt
Nov 28 22:25:07.109 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b614
Nov 28 22:25:07.159 [core.ci] 1/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 28 22:25:07.159 [core.ci] 1/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 28 22:25:07.159 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 28 22:25:07.159 [core.ci] 1/0.0 answer to query surpressed
Nov 28 22:25:07.159 [core.ci] 1/0.0 stop decrypt
Nov 28 22:25:28.172 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b63c
Nov 28 22:25:28.172 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b662
Nov 28 22:25:28.187 [core.ci] 1/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 28 22:25:28.187 [core.ci] 1/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 28 22:25:28.187 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 28 22:25:28.187 [core.ci] 1/0.0 answer to query surpressed
Nov 28 22:25:28.187 [core.ci] 1/0.0 stop decrypt
Nov 28 22:25:49.200 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b689
Nov 28 22:25:49.200 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b6b0
Nov 28 22:25:54.504 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b6b0
Nov 28 22:25:54.554 [core.ci] 0/0.0 -> 00 01 a0 1e 01 90 02 00 01 9f 80 32 15 04 00 7e 01 00 00 02 01 ff 00 00 06 02 03 00 00 06 02 04 00 00
Nov 28 22:25:54.555 [core.ci] 0/0.0 CA_PMT decoding len=15 lm=4 prg=126 len=0/f pid=2,1ff len=0/a pid=6,203 len=0/5 pid=6,204 len=0/0
Nov 28 22:25:54.555 [core.ci] 0/0.0 got CA pmt ciCmd=-1 caLm=4
Nov 28 22:25:54.555 [core.ci] 0/0.0 answer to query surpressed
Nov 28 22:25:54.555 [core.ci] 0/0.0 set CAM decrypt (prg 126)
Nov 28 22:25:54.555 [core.pids] 0/0: add SID 126 (
zero=0 noshift=1)
Nov 28 22:25:54.555 [core.pids] 0/0: pids VIDEO=01ff DOLBY=0203 DOLBY=0204
Nov 28 22:25:54.555 [core.pids] Ca descriptors after simplify (pidCa=0)
Nov 28 22:25:54.555 [core.pids] prgca: <empty>
Nov 28 22:25:54.555 [core.pids] pidca 01ff: <empty>
Nov 28 22:25:54.555 [core.pids] pidca 0203: <empty>
Nov 28 22:25:54.555 [core.pids] pidca 0204: <empty>
Nov 28 22:25:54.555 [core.pids] 0/0: SID=126
zero=0 | sid=-/-/-
zero=-/-/- idle=-/-/-
Nov 28 22:25:54.555 [core.pids] 0/0: found handler for SID 126 (0/0.1 idle=1 idx=1)
Nov 28 22:25:54.555 [core.ecm] 0/0.1: setting new SID 126
Nov 28 22:25:54.555 [core.pids] 0/0.1: pids on entry
Nov 28 22:25:54.555 [core.pids] 0/0.1: pids after delete
Nov 28 22:25:54.555 [core.pids] 0/0: descrambling pid 01ff on index 1
Nov 28 22:25:54.555 [core.pids] 0/0: descrambling pid 0203 on index 1
Nov 28 22:25:54.555 [core.pids] 0/0: descrambling pid 0204 on index 1
Nov 28 22:25:54.555 [core.pids] 0/0.1: pids after add VIDEO=01ff DOLBY=0203 DOLBY=0204
Nov 28 22:25:54.555 [core.ecm] 0/0.1: is no longer idle
Nov 28 22:25:54.616 [core.ecm] 0/0.1: triggered SID -1/126 idx -1/1 mode -1/0 -
Nov 28 22:25:56.817 [core.ci] 0/0.0 -> 00 01 a0 16 01 90 02 00 01 9f 80 32 0d 05 00 7e 01 00 07 01 09 04 09 8c fa 52
Nov 28 22:25:56.817 [core.ci] 0/0.0 CA_PMT decoding len=d lm=5 prg=126 len=7/7 ci_cmd(G)=01
Nov 28 22:25:56.817 [core.ci] 0/0.0 got CA pmt ciCmd=1 caLm=5
Nov 28 22:25:56.817 [core.ci] 0/0.0 answer to query surpressed
Nov 28 22:25:56.817 [core.ci] 0/0.0 set CAM decrypt (prg 126)
Nov 28 22:25:56.817 [core.pids] 0/0: update SID 126 (
zero=0 noshift=1)
Nov 28 22:25:56.817 [core.pids] 0/0: SID=126
zero=0 | sid=1/126/0
zero=-/-/- idle=-/-/-
Nov 28 22:25:56.817 [core.pids] 0/0: found handler for SID 126 (0/0.1 idle=0 idx=1)
Nov 28 22:25:56.817 [core.pids] 0/0.1: pids on entry VIDEO=01ff DOLBY=0203 DOLBY=0204
Nov 28 22:25:56.817 [core.pids] 0/0.1: pids after delete
Nov 28 22:25:56.817 [core.pids] 0/0.1: pids after add
Nov 28 22:25:56.817 [core.ecm] 0/0.1: is idle now
Nov 28 22:25:56.868 [core.pids] 0/0: tune to same source/transponder
Nov 28 22:25:56.918 [core.ci] 0/0.0 -> 00 01 a0 25 01 90 02 00 01 9f 80 32 1c 04 00 7e 01 00 07 01 09 04 09 8c fa 52 02 01 ff 00 00 06 02 03 00 00 06 02 04 00 00
Nov 28 22:25:56.919 [core.ci] 0/0.0 CA_PMT decoding len=1c lm=4 prg=126 len=7/16 ci_cmd(G)=01 pid=2,1ff len=0/a pid=6,203 len=0/5 pid=6,204 len=0/0
Nov 28 22:25:56.919 [core.ci] 0/0.0 got CA pmt ciCmd=1 caLm=4
Nov 28 22:25:56.919 [core.ci] 0/0.0 answer to query surpressed
Nov 28 22:25:56.919 [core.ci] 0/0.0 set CAM decrypt (prg 126)
Nov 28 22:25:56.919 [core.pids] 0/0: add SID 126 (
zero=0 noshift=1)
Nov 28 22:25:56.919 [core.pids] 0/0: pids VIDEO=01ff DOLBY=0203 DOLBY=0204
Nov 28 22:25:56.919 [core.pids] Ca descriptors after simplify (pidCa=0)
Nov 28 22:25:56.919 [core.pids] prgca: 09 04 09 8C FA 52
Nov 28 22:25:56.919 [core.pids] pidca 01ff: <empty>
Nov 28 22:25:56.919 [core.pids] pidca 0203: <empty>
Nov 28 22:25:56.919 [core.pids] pidca 0204: <empty>
Nov 28 22:25:56.919 [core.pids] 0/0: SID=126
zero=0 | sid=1/126/1
zero=-/-/- idle=1/126/1
Nov 28 22:25:56.919 [core.pids] 0/0: found handler for SID 126 (0/0.1 idle=1 idx=1)
Nov 28 22:25:56.919 [core.pids] 0/0.1: pids on entry
Nov 28 22:25:56.919 [core.pids] 0/0.1: pids after delete
Nov 28 22:25:56.919 [core.pids] 0/0: descrambling pid 01ff on index 1
Nov 28 22:25:56.919 [core.pids] 0/0: descrambling pid 0203 on index 1
Nov 28 22:25:56.919 [core.pids] 0/0: descrambling pid 0204 on index 1
Nov 28 22:25:56.919 [core.pids] 0/0.1: pids after add VIDEO=01ff DOLBY=0203 DOLBY=0204
Nov 28 22:25:56.919 [core.ecm] 0/0.1: is no longer idle
Nov 28 22:25:56.964 [core.ecm] 0/0.1: triggered SID 126/126 idx 1/1 mode 1/0 -
Nov 28 22:25:56.965 [core.ecm] 0/0.1: new caDescr: 09 04 09 8C FA 52
Nov 28 22:25:56.965 [core.ecm] 0/0.1: CA descriptors for SID 126 (len=6)
Nov 28 22:25:56.965 [core.ecm] 0/0.1: descriptor 09 8c fa 52
Nov 28 22:25:56.965 [core.ecm] 0/0.1: found 098c(0000) (Cardclient) id 0000 with ecm 1a52/80 (new)
Nov 28 22:25:56.965 [core.ecm] 0/0.1: try system Cardclient (098c) id 0000 with ecm 1a52 (pri=-15)
Nov 28 22:25:57.032 [core.au] 0/0: chain caid 1833 -> none available
Nov 28 22:25:57.033 [core.au] 0/0: chain caid 09c4 -> none available
Nov 28 22:25:57.033 [core.au] 0/0: chain caid 09af -> none available
Nov 28 22:25:57.033 [core.au] 0/0: chain caid 098c -> Cardclient(-15) [1008-82/ff]
Nov 28 22:25:57.033 [core.au] 0/0: starting chain 098c
Nov 28 22:25:57.064 [cardclient.core] cc-loop
Nov 28 22:25:57.064 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:25:57.064 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:25:57.064 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:25:57.064 [cardclient.cccam2shares] shareid 00000064 hops 0 lag 1000
Nov 28 22:25:57.064 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:25:57.216 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:25:57.216 [cardclient.cccam2extra] wait returned after 1
Nov 28 22:25:57.216 [cardclient.cccam2extra] no CW from this share
Nov 28 22:25:57.216 [cardclient.cccam2shares] added shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:25:57.216 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:25:57.216 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (152 ms)
Nov 28 22:25:57.314 [cardclient.core] cc-loop
Nov 28 22:25:57.314 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:25:57.314 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:25:57.314 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:25:57.314 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:25:57.315 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 500
Nov 28 22:25:57.315 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:25:57.417 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:25:57.418 [cardclient.cccam2extra] wait returned after 1
Nov 28 22:25:57.418 [cardclient.cccam2extra] no CW from this share
Nov 28 22:25:57.418 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:25:57.418 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:25:57.418 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (103 ms)
Nov 28 22:25:57.418 [core.ecm] 0/0.1: CA descriptors for SID 126 (len=6)
Nov 28 22:25:57.418 [core.ecm] 0/0.1: descriptor 09 8c fa 52
Nov 28 22:25:57.418 [core.ecm] 0/0.1: found 098c(0000) (Cardclient) id 0000 with ecm 1a52/80 (already present)
Nov 28 22:25:57.419 [core.ecm] 0/0.1: try system Cardclient (098c) id 0000 with ecm 1a52 (pri=-15)
Nov 28 22:25:57.565 [cardclient.core] cc-loop
Nov 28 22:25:57.565 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:25:57.565 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM already cached as failed
Nov 28 22:25:57.815 [cardclient.core] cc-loop
Nov 28 22:25:57.815 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:25:57.815 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM already cached as failed
Nov 28 22:25:57.820 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:25:57.821 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:25:58.066 [core.ecm] 0/0.1: CA descriptors for SID 126 (len=6)
Nov 28 22:25:58.066 [core.ecm] 0/0.1: descriptor 09 8c fa 52
Nov 28 22:25:58.066 [core.ecm] 0/0.1: found 098c(0000) (Cardclient) id 0000 with ecm 1a52/80 (already present)
Nov 28 22:25:58.066 [core.ecm] 0/0.1: try system Cardclient (098c) id 0000 with ecm 1a52 (pri=-15)
Nov 28 22:25:58.825 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:25:59.628 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:25:59.830 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:00.633 [cardclient.cccam2extra] EMM ack (pending 4)
Nov 28 22:26:00.634 [cardclient.cccam2extra] EMM ack (pending 5)
Nov 28 22:26:00.634 [cardclient.cccam2extra] EMM ack (pending 4)
Nov 28 22:26:00.635 [cardclient.cccam2extra] EMM ack (pending 3)
Nov 28 22:26:00.636 [cardclient.cccam2extra] EMM ack (pending 2)
Nov 28 22:26:00.636 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:00.637 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:02.444 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:02.445 [cardclient.cccam2extra] EMM ack (pending 3)
Nov 28 22:26:02.446 [cardclient.cccam2extra] EMM ack (pending 2)
Nov 28 22:26:02.447 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:02.448 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:02.814 [cardclient.core] cc-loop
Nov 28 22:26:02.814 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:26:02.814 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:26:02.814 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:02.814 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:26:02.814 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 375
Nov 28 22:26:02.815 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:26:02.849 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:26:02.850 [cardclient.cccam2extra] wait returned after 1
Nov 28 22:26:02.850 [cardclient.cccam2extra] no CW from this share
Nov 28 22:26:02.850 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:02.850 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:26:02.850 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (35 ms)
Nov 28 22:26:03.064 [cardclient.core] cc-loop
Nov 28 22:26:03.065 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:26:03.065 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:26:03.065 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:03.065 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:26:03.065 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 281
Nov 28 22:26:03.065 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:26:03.252 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:26:03.252 [cardclient.cccam2extra] wait returned after 1
Nov 28 22:26:03.252 [cardclient.cccam2extra] no CW from this share
Nov 28 22:26:03.252 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:03.252 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:26:03.252 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (187 ms)
Nov 28 22:26:03.315 [core.ecm] 0/0.1: CA descriptors for SID 126 (len=6)
Nov 28 22:26:03.315 [core.ecm] 0/0.1: descriptor 09 8c fa 52
Nov 28 22:26:03.315 [core.ecm] 0/0.1: found 098c(0000) (Cardclient) id 0000 with ecm 1a52/80 (already present)
Nov 28 22:26:03.316 [core.ecm] 0/0.1: try system Cardclient (098c) id 0000 with ecm 1a52 (pri=-15)
Nov 28 22:26:03.316 [core.ecm] 0/0.1: stopping message log until valid key is found
Nov 28 22:26:03.454 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:04.256 [cardclient.cccam2extra] EMM ack (pending 5)
Nov 28 22:26:04.257 [cardclient.cccam2extra] EMM ack (pending 4)
Nov 28 22:26:04.258 [cardclient.cccam2extra] EMM ack (pending 3)
Nov 28 22:26:04.258 [cardclient.cccam2extra] EMM ack (pending 2)
Nov 28 22:26:04.259 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:04.260 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:05.264 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:05.266 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:08.278 [cardclient.cccam2extra] last message repeated 4 times
Nov 28 22:26:08.278 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:08.279 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:09.283 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:09.284 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:09.818 [cardclient.core] cc-loop
Nov 28 22:26:09.818 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:26:09.818 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:26:09.818 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:09.818 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:26:09.818 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 211
Nov 28 22:26:09.818 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:26:09.887 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:26:09.887 [cardclient.cccam2extra] wait returned after 2
Nov 28 22:26:09.887 [cardclient.cccam2extra] no CW from this share
Nov 28 22:26:09.887 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:09.887 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:26:09.887 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (69 ms)
Nov 28 22:26:10.068 [cardclient.core] cc-loop
Nov 28 22:26:10.069 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:26:10.088 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:26:10.088 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:10.088 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:26:10.088 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 158
Nov 28 22:26:10.088 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:26:10.088 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:10.089 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:26:10.090 [cardclient.cccam2extra] wait returned after 1
Nov 28 22:26:10.090 [cardclient.cccam2extra] no CW from this share
Nov 28 22:26:10.090 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:10.090 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:26:10.090 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (21 ms)
Nov 28 22:26:10.093 [core.ci] 1/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
Nov 28 22:26:10.093 [core.ci] 1/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
Nov 28 22:26:10.093 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
Nov 28 22:26:10.093 [core.ci] 1/0.0 answer to query surpressed
Nov 28 22:26:10.093 [core.ci] 1/0.0 stop decrypt
Nov 28 22:26:10.093 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b6fe
Nov 28 22:26:10.491 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:10.494 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:11.096 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:11.097 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:11.700 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:11.701 [cardclient.cccam2extra] EMM ack (pending 2)
Nov 28 22:26:11.702 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:11.702 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:14.515 [cardclient.cccam2extra] last message repeated 4 times
Nov 28 22:26:14.515 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:14.517 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:15.721 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:15.724 [cardclient.cccam2extra] last message repeated 2 times
Nov 28 22:26:15.724 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:16.326 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:16.327 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:16.810 [cardclient.core] cc-loop
Nov 28 22:26:16.811 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:26:16.811 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:26:16.811 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:16.811 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:26:16.811 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 118
Nov 28 22:26:16.811 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:26:16.930 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:26:16.930 [cardclient.cccam2extra] wait returned after 2
Nov 28 22:26:16.931 [cardclient.cccam2extra] no CW from this share
Nov 28 22:26:16.931 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:16.931 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:26:16.931 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (120 ms)
Nov 28 22:26:17.061 [cardclient.core] cc-loop
Nov 28 22:26:17.061 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:26:17.061 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:26:17.061 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:17.061 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:26:17.061 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 89
Nov 28 22:26:17.061 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:26:17.133 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:26:17.133 [cardclient.cccam2extra] wait returned after 1
Nov 28 22:26:17.133 [cardclient.cccam2extra] no CW from this share
Nov 28 22:26:17.133 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:17.133 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:26:17.133 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (71 ms)
Nov 28 22:26:17.334 [cardclient.cccam2extra] EMM ack (pending 3)
Nov 28 22:26:17.335 [cardclient.cccam2extra] EMM ack (pending 2)
Nov 28 22:26:17.335 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:17.336 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:20.750 [cardclient.cccam2extra] last message repeated 3 times
Nov 28 22:26:20.750 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:20.752 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:21.957 [cardclient.cccam2extra] last message repeated 2 times
Nov 28 22:26:21.957 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:21.959 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:23.814 [cardclient.cccam2extra] last message repeated 2 times
Nov 28 22:26:23.814 [cardclient.core] cc-loop
Nov 28 22:26:23.814 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:26:23.967 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:26:23.967 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:23.967 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:26:23.967 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 67
Nov 28 22:26:23.967 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:26:23.968 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:23.969 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:26:23.969 [cardclient.cccam2extra] wait returned after 2
Nov 28 22:26:23.969 [cardclient.cccam2extra] no CW from this share
Nov 28 22:26:23.969 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:23.969 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:26:23.969 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (155 ms)
Nov 28 22:26:24.065 [cardclient.core] cc-loop
Nov 28 22:26:24.065 [cardclient.core] now trying client cccam2 (192.168.0.56:56073)
Nov 28 22:26:24.065 [cardclient.cccam2] ECM caid 098c prov 0000 sid 126 pid 1a52
Nov 28 22:26:24.065 [cardclient.cccam2shares] shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:24.065 [cardclient.cccam2shares] share try list for caid 098c prov 000000 pid 1a52
Nov 28 22:26:24.065 [cardclient.cccam2shares] shareid 00000064 hops 0 - lag 50
Nov 28 22:26:24.065 [cardclient.cccam2extra] now try shareid 00000064
Nov 28 22:26:24.172 [cardclient.cccam2] server can't decode this ecm, (0 pending)
Nov 28 22:26:24.172 [cardclient.cccam2extra] wait returned after 1
Nov 28 22:26:24.172 [cardclient.cccam2extra] no CW from this share
Nov 28 22:26:24.172 [cardclient.cccam2shares] updated shareid 00000064 for 1a52/5300ff40/1b6b0 status -1
Nov 28 22:26:24.172 [cardclient.ecm] cccam2: unable to decode the channel
Nov 28 22:26:24.172 [cardclient.core] client cccam2 (192.168.0.56:56073) ECM failed (107 ms)
Nov 28 22:26:24.774 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:24.775 [cardclient.cccam2extra] EMM ack (pending 2)
Nov 28 22:26:24.776 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:24.776 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:26.585 [cardclient.cccam2extra] last message repeated 2 times
Nov 28 22:26:26.585 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:26.586 [cardclient.cccam2extra] EMM ack (pending 2)
Nov 28 22:26:26.587 [cardclient.cccam2extra] EMM ack (pending 3)
Nov 28 22:26:26.587 [cardclient.cccam2extra] EMM ack (pending 2)
Nov 28 22:26:26.588 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:26.589 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:27.592 [cardclient.cccam2extra] EMM ack (pending 1)
Nov 28 22:26:27.593 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:28.597 [core.au] 0/0: stopping chain 098c
Nov 28 22:26:28.597 [cardclient.cccam2extra] EMM ack (pending 0)
Nov 28 22:26:28.699 [cardclient.cccam2] logout from server initiated
Nov 28 22:26:28.802 [cardclient.cccam2extra] reader thread stopped
Nov 28 22:26:28.803 [cardclient.cccam2extra] network shut down
Nov 28 22:26:28.803 [cardclient.cccam2extra] cmd 0c crypt mode now 0
Nov 28 22:26:28.803 [cardclient.cccam2extra] logout done
Nov 28 22:26:28.803 [general.debug] SC cleanup done