> > Oct 12 13:44:18.059 [general.debug] logfile '/var/log/vdr-sc' opened
> >
> > Oct 12 13:44:18.059 [general.debug] capturing device 0/0 (subsystem ID dd010020)
> >
> > Oct 12 13:44:18.059 [general.debug] creating standard device 0/0
> >
> > Oct 12 13:44:18.075 [general.debug] capturing device 1/0 (subsystem ID dd010020)
> >
> > Oct 12 13:44:18.075 [general.debug] creating standard device 1/0
> >
> > Oct 12 13:44:18.090 [general.info] SC version 1.0.0pre-HG-29b7b5f231c8+ initializing (VDR 1.7.31)
> >
> > Oct 12 13:44:18.090 [general.info] SC version 1.0.0pre-HG-29b7b5f231c8+ starting (VDR 1.7.31)
> >
> > Oct 12 13:44:18.090 [core.load] ** Plugin config:
> >
> > Oct 12 13:44:18.090 [core.load] ** Key updates (AU) are enabled (active CAIDs) (no prestart)
> >
> > Oct 12 13:44:18.090 [core.load] ** Local systems DON'T take priority over cached remote
> >
> > Oct 12 13:44:18.090 [core.load] ** Concurrent FF recordings are NOT allowed
> >
> > Oct 12 13:44:18.090 [core.load] ** Force transfermode with digital audio
> >
> > Oct 12 13:44:18.090 [core.load] ** ECM cache is set to enabled
> >
> > Oct 12 13:44:18.090 [core.load] ** TsBufferSize is 4 MB
> >
> > Oct 12 13:44:18.090 [core.load] ** ScCaps are 1 2 0 0 0 0 0 0 0 0
> >
> > Oct 12 13:44:18.090 [general.info] loading cardclient config from /var/lib/vdr/plugins/sc/cardclient.conf
> >
> > Oct 12 13:44:18.090 [cardclient.newcamd] now using protocol version 525 (cdLen=8)
> >
> > Oct 12 13:44:18.090 [cardclient.core] hostname=xxx.xxx.xxx.xxx port=xxxxx emm=1 emmCaids 1700/ff00
> >
> > Oct 12 13:44:18.090 [cardclient.core] Newcamd: username=xxxxxxxxx password=xxxxxxxxxxx key=xxxxxxxxxxxxxxxxxxxxxxxxxx
> >
> > Oct 12 13:44:18.090 [cardclient.core] client 'Newcamd' ready
> >
> > Oct 12 13:44:18.090 [core.net] connecting to xxx.xxx.xxxx.xxx:xxxxx/tcp (xxx.xxxx.xxxx.xxxx)
> >
> > Oct 12 13:44:18.273 [cardclient.login] Newcamd: CaID=0000 admin=0 srvUA=0000000000000000 provider 000000/0000000000000000
> >
> > Oct 12 13:44:18.273 [general.error] failed open /var/lib/vdr/plugins/sc/override.conf: No such file or directory
> >
> > Oct 12 13:44:18.273 [general.error] failed open /var/lib/vdr/plugins/sc/smartcard.conf: No such file or directory
> >
> > Oct 12 13:44:18.273 [general.error] failed open /var/lib/vdr/plugins/sc/cardslot.conf: No such file or directory
> >
> > Oct 12 13:44:18.273 [general.error] failed open /var/lib/vdr/plugins/sc/SoftCam.Key: No such file or directory
> >
> > Oct 12 13:44:18.273 [general.error] no keys loaded for softcam!
> >
> > Oct 12 13:44:18.273 [core.load] ** registered systems:
> >
> > Oct 12 13:44:18.273 [core.load] ** Conax (pri -10)
> >
> > Oct 12 13:44:18.273 [core.load] ** SC-Seca (pri -5)
> >
> > Oct 12 13:44:18.273 [core.load] ** SC-Nagra (pri -5)
> >
> > Oct 12 13:44:18.274 [core.load] ** Cardclient (pri -15)
> >
> > Oct 12 13:44:18.274 [core.load] ** SC-VideoGuard2 (pri -5)
> >
> > Oct 12 13:44:18.274 [core.load] ** SC-Conax (pri -5)
> >
> > Oct 12 13:44:18.274 [core.load] ** SC-Viaccess (pri -5)
> >
> > Oct 12 13:44:18.274 [core.load] ** SC-Cryptoworks (pri -5)
> >
> > Oct 12 13:44:18.274 [core.load] ** Irdeto2 (pri -8)
> >
> > Oct 12 13:44:18.274 [core.load] ** Irdeto (pri -10)
> >
> > Oct 12 13:44:18.274 [core.load] ** Fake-NDS (pri -12)
> >
> > Oct 12 13:44:18.274 [core.load] ** ConstCW (pri -20)
> >
> > Oct 12 13:44:18.274 [core.load] ** Seca (pri -10)
> >
> > Oct 12 13:44:18.274 [core.load] ** Viaccess (pri -10)
> >
> > Oct 12 13:44:18.274 [core.load] ** Nagra2 (pri -10)
> >
> > Oct 12 13:44:18.274 [core.load] ** Nagra (pri -10)
> >
> > Oct 12 13:44:18.274 [core.load] ** Cryptoworks (pri -10)
> >
> > Oct 12 13:44:18.274 [core.load] ** SC-Irdeto (pri -5)
> >
> > Oct 12 13:44:18.274 [general.info] Using software decryption on card 1/0
> >
> > Oct 12 13:44:18.284 [core.ci] card 1/0, slot 0 (v= 1) caids: 0100 0500 09c4 1817 098c 1818 1833 1819 1811 0d00 1702 1863 0648 0d05 0d95 09af 1810 0622 0624 09c7 0b00 0d96 1834 1861 1722 1830 1837 1843 1860 0d03 0d70 0d02 0650 0666 09b2 0d0f 0d98 1812 1836
> >
> > Oct 12 13:44:18.284 [core.ci] 1/0: reset of slot 0 requested
> >
> > Oct 12 13:44:18.285 [general.info] Using software decryption on card 0/0
> >
> > Oct 12 13:44:18.312 [core.ci] card 0/0, slot 0 (v= 1) caids: 0100 0500 09c4 1817 098c 1818 1833 1819 1811 0d00 1702 1863 0648 0d05 0d95 09af 1810 0622 0624 09c7 0b00 0d96 1834 1861 1722 1830 1837 1843 1860 0d03 0d70 0d02 0650 0666 09b2 0d0f 0d98 1812 1836
> >
> > Oct 12 13:44:18.312 [core.ci] 0/0: reset of slot 0 requested
> >
> > Oct 12 13:44:19.167 [core.ci] 0/0.0: doReply changed, reset triggered
> >
> > Oct 12 13:44:19.167 [core.ci] 0/0.0: now using CAIDs version 1
> >
> > Oct 12 13:44:19.167 [core.ci] 0/0.0: status 'present'
> >
> > Oct 12 13:44:19.298 [core.ci] 1/0.0: doReply changed, reset triggered
> >
> > Oct 12 13:44:19.298 [core.ci] 1/0.0: now using CAIDs version 1
> >
> > Oct 12 13:44:19.298 [core.ci] 1/0.0: status 'present'
> >
> > Oct 12 13:44:19.348 [core.ci] 1/0.0: status 'reset'
> >
> > Oct 12 13:44:19.368 [core.ci] 0/0.0: status 'reset'
> >
> > Oct 12 13:44:19.919 [core.ci] 0/0.0: status 'ready'
> >
> > Oct 12 13:44:20.300 [core.ci] 1/0.0: status 'ready'
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 -> 00 01 82 01 01
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 <- 00 01 83 01 01 80 02 01 80
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 <- 00 01 a0 07 01 91 04 00 03 00 41 80 02 01 00
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 -> 00 01 a0 0a 01 92 07 00 00 03 00 41 00 01
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 -> 00 01 a0 09 01 90 02 00 01 9f 80 30 00
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 sending CA info
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:44:20.400 [core.ci] 1/0.0 <- 00 01 a0 57 01 90 02 00 01 9f 80 31 4e 01 00 05 00 09 c4 18 17 09 8c 18 18 18 33 18 19 18 11 0d 00 17 02 18 63 06 48 0d 05 0d 95 09 af 18 10 06 22 06 24 09 c7 0b 00 0d 96 18 34 18 61 17 22 18 30 18 37 18 43 18 60 0d 03 0d 70 0d 02 06 50 06 66 09 b2 0d 0f 0d 98 18 12 18 36 80 02 01 00
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 -> 00 01 82 01 01
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 <- 00 01 83 01 01 80 02 01 80
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 <- 00 01 a0 07 01 91 04 00 03 00 41 80 02 01 00
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 -> 00 01 a0 0a 01 92 07 00 00 03 00 41 00 01
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 -> 00 01 a0 09 01 90 02 00 01 9f 80 30 00
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 sending CA info
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:44:20.420 [core.ci] 0/0.0 <- 00 01 a0 57 01 90 02 00 01 9f 80 31 4e 01 00 05 00 09 c4 18 17 09 8c 18 18 18 33 18 19 18 11 0d 00 17 02 18 63 06 48 0d 05 0d 95 09 af 18 10 06 22 06 24 09 c7 0b 00 0d 96 18 34 18 61 17 22 18 30 18 37 18 43 18 60 0d 03 0d 70 0d 02 06 50 06 66 09 b2 0d 0f 0d 98 18 12 18 36 80 02 01 00
> >
> > Oct 12 13:44:21.402 [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
> >
> > Oct 12 13:44:21.402 [core.ci] 1/0.0 CA_PMT decoding len=7 lm=3 prg=0 len=1/1 ci_cmd(G)=03
> >
> > Oct 12 13:44:21.402 [core.ci] 1/0.0 got CA pmt ciCmd=3 caLm=3
> >
> > Oct 12 13:44:21.402 [core.ci] 1/0.0 answer to query
> >
> > Oct 12 13:44:21.402 [core.ci] 1/0.0 no action taken
> >
> > Oct 12 13:44:21.402 [core.ci] 1/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:44:21.402 [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
> >
> > Oct 12 13:44:21.422 [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
> >
> > Oct 12 13:44:21.422 [core.ci] 0/0.0 CA_PMT decoding len=7 lm=3 prg=0 len=1/1 ci_cmd(G)=03
> >
> > Oct 12 13:44:21.422 [core.ci] 0/0.0 got CA pmt ciCmd=3 caLm=3
> >
> > Oct 12 13:44:21.422 [core.ci] 0/0.0 answer to query
> >
> > Oct 12 13:44:21.422 [core.ci] 0/0.0 no action taken
> >
> > Oct 12 13:44:21.422 [core.ci] 0/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:44:21.422 [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
> >
> > Oct 12 13:44:21.603 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b097
> >
> > Oct 12 13:45:19.403 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b097
> >
> > Oct 12 13:45:19.686 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b52a
> >
> > Oct 12 13:45:19.720 [core.ci] 1/0.0 -> 00 01 a0 1e 01 90 02 00 01 9f 80 32 15 04 00 83 01 00 00 02 04 ff 00 00 06 05 03 00 00 06 05 04 00 00
> >
> > Oct 12 13:45:19.720 [core.ci] 1/0.0 CA_PMT decoding len=15 lm=4 prg=131 len=0/f pid=2,4ff len=0/a pid=6,503 len=0/5 pid=6,504 len=0/0
> >
> > Oct 12 13:45:19.720 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=4
> >
> > Oct 12 13:45:19.720 [core.ci] 1/0.0 answer to query surpressed
> >
> > Oct 12 13:45:19.720 [core.ci] 1/0.0 set CAM decrypt (prg 131)
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0: add SID 131 (
zero=0 noshift=1)
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0: pids VIDEO=04ff DOLBY=0503 DOLBY=0504
> >
> > Oct 12 13:45:19.720 [core.pids] Ca descriptors after simplify (pidCa=0)
> >
> > Oct 12 13:45:19.720 [core.pids] prgca: <empty>
> >
> > Oct 12 13:45:19.720 [core.pids] pidca 04ff: <empty>
> >
> > Oct 12 13:45:19.720 [core.pids] pidca 0503: <empty>
> >
> > Oct 12 13:45:19.720 [core.pids] pidca 0504: <empty>
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0: SID=131
zero=0 | sid=-/-/-
zero=-/-/- idle=-/-/-
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0: found handler for SID 131 (1/0.1 idle=1 idx=1)
> >
> > Oct 12 13:45:19.720 [core.ecm] 1/0.1: setting new SID 131
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0.1: pids on entry
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0.1: pids after delete
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0: descrambling pid 04ff on index 1
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0: descrambling pid 0503 on index 1
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0: descrambling pid 0504 on index 1
> >
> > Oct 12 13:45:19.720 [core.pids] 1/0.1: pids after add VIDEO=04ff DOLBY=0503 DOLBY=0504
> >
> > Oct 12 13:45:19.720 [core.ecm] 1/0.1: is no longer idle
> >
> > Oct 12 13:45:19.781 [core.ecm] 1/0.1: triggered SID -1/131 idx -1/1 mode -1/0 -
> >
> > Oct 12 13:45:39.804 [core.ecm] 1/0.1: no encryption system found
> >
> > Oct 12 13:46:19.191 [core.net] idle timeout, disconnected xxx.xxx.xxx.xxx:xxxxx
> >
> > Oct 12 13:46:19.953 [core.ecm] 1/0.1: no encryption system found
> >
> > Oct 12 13:47:00.153 [core.ecm] last message repeated 2 times
> >
> > Oct 12 13:47:40.416 [core.ecm] last message repeated 2 times
> >
> > Oct 12 13:48:20.774 [core.ecm] last message repeated 2 times
> >
> > Oct 12 13:48:25.648 [core.ci] 1/0.0 -> 00 01 a0 3a 01 90 02 00 01 9f 80 32 31 05 00 83 01 00 2b 01 09 04 18 33 f8 b6 09 04 18 34 fc b6 09 04 09 c4 fb b6 09 04 09 c7 fd b6 09 04 09 af ff b6 09 04 09 8c fa b6 09 04 18 61 f9 b6
> >
> > Oct 12 13:48:25.648 [core.ci] 1/0.0 CA_PMT decoding len=31 lm=5 prg=131 len=2b/2b ci_cmd(G)=01
> >
> > Oct 12 13:48:25.648 [core.ci] 1/0.0 got CA pmt ciCmd=1 caLm=5
> >
> > Oct 12 13:48:25.648 [core.ci] 1/0.0 answer to query surpressed
> >
> > Oct 12 13:48:25.648 [core.ci] 1/0.0 set CAM decrypt (prg 131)
> >
> > Oct 12 13:48:25.648 [core.pids] 1/0: update SID 131 (
zero=0 noshift=1)
> >
> > Oct 12 13:48:25.648 [core.pids] 1/0: SID=131
zero=0 | sid=1/131/0
zero=-/-/- idle=-/-/-
> >
> > Oct 12 13:48:25.648 [core.pids] 1/0: found handler for SID 131 (1/0.1 idle=0 idx=1)
> >
> > Oct 12 13:48:25.648 [core.pids] 1/0.1: pids on entry VIDEO=04ff DOLBY=0503 DOLBY=0504
> >
> > Oct 12 13:48:25.648 [core.pids] 1/0.1: pids after delete
> >
> > Oct 12 13:48:25.648 [core.pids] 1/0.1: pids after add
> >
> > Oct 12 13:48:25.648 [core.ecm] 1/0.1: is idle now
> >
> > Oct 12 13:48:29.210 [general.debug] SC cleanup done
> >
> > Oct 12 13:49:12.578 [general.debug] logfile '/var/log/vdr-sc' opened
> >
> > Oct 12 13:49:12.578 [general.debug] capturing device 0/0 (subsystem ID dd010020)
> >
> > Oct 12 13:49:12.578 [general.debug] creating standard device 0/0
> >
> > Oct 12 13:49:12.594 [general.debug] capturing device 1/0 (subsystem ID dd010020)
> >
> > Oct 12 13:49:12.594 [general.debug] creating standard device 1/0
> >
> > Oct 12 13:49:12.610 [general.info] SC version 1.0.0pre-HG-29b7b5f231c8+ initializing (VDR 1.7.31)
> >
> > Oct 12 13:49:12.610 [general.info] SC version 1.0.0pre-HG-29b7b5f231c8+ starting (VDR 1.7.31)
> >
> > Oct 12 13:49:12.610 [core.load] ** Plugin config:
> >
> > Oct 12 13:49:12.610 [core.load] ** Key updates (AU) are enabled (active CAIDs) (no prestart)
> >
> > Oct 12 13:49:12.611 [core.load] ** Local systems DON'T take priority over cached remote
> >
> > Oct 12 13:49:12.611 [core.load] ** Concurrent FF recordings are NOT allowed
> >
> > Oct 12 13:49:12.611 [core.load] ** Force transfermode with digital audio
> >
> > Oct 12 13:49:12.611 [core.load] ** ECM cache is set to enabled
> >
> > Oct 12 13:49:12.611 [core.load] ** TsBufferSize is 4 MB
> >
> > Oct 12 13:49:12.611 [core.load] ** ScCaps are 1 2 0 0 0 0 0 0 0 0
> >
> > Oct 12 13:49:12.611 [general.info] loading cardclient config from /var/lib/vdr/plugins/sc/cardclient.conf
> >
> > Oct 12 13:49:12.611 [cardclient.newcamd] now using protocol version 525 (cdLen=8)
> >
> > Oct 12 13:49:12.611 [cardclient.core] hostname=xxx.xxx.xxx.xxx port=xxxxx emm=1 emmCaids 0000/0000
> >
> > Oct 12 13:49:12.611 [cardclient.core] Newcamd: username=xxxxxxxx password=xxxxxxxxxx key=xxxxxxxxxxxxxxxxxxxxxxxxxx
> >
> > Oct 12 13:49:12.611 [cardclient.core] client 'Newcamd' ready
> >
> > Oct 12 13:49:12.611 [core.net] connecting to xxxx.xxx.xxx.xxx:xxx/tcp (xxx.xxx.xxx.xxx)
> >
> > Oct 12 13:49:12.791 [cardclient.login] Newcamd: CaID=0000 admin=0 srvUA=0000000000000000 provider 000000/0000000000000000
> >
> > Oct 12 13:49:12.791 [general.error] failed open /var/lib/vdr/plugins/sc/override.conf: No such file or directory
> >
> > Oct 12 13:49:12.791 [general.error] failed open /var/lib/vdr/plugins/sc/smartcard.conf: No such file or directory
> >
> > Oct 12 13:49:12.791 [general.error] failed open /var/lib/vdr/plugins/sc/cardslot.conf: No such file or directory
> >
> > Oct 12 13:49:12.791 [general.error] failed open /var/lib/vdr/plugins/sc/SoftCam.Key: No such file or directory
> >
> > Oct 12 13:49:12.791 [general.error] no keys loaded for softcam!
> >
> > Oct 12 13:49:12.791 [core.load] ** registered systems:
> >
> > Oct 12 13:49:12.791 [core.load] ** Conax (pri -10)
> >
> > Oct 12 13:49:12.791 [core.load] ** SC-Seca (pri -5)
> >
> > Oct 12 13:49:12.791 [core.load] ** SC-Nagra (pri -5)
> >
> > Oct 12 13:49:12.791 [core.load] ** Cardclient (pri -15)
> >
> > Oct 12 13:49:12.791 [core.load] ** SC-VideoGuard2 (pri -5)
> >
> > Oct 12 13:49:12.791 [core.load] ** SC-Conax (pri -5)
> >
> > Oct 12 13:49:12.791 [core.load] ** SC-Viaccess (pri -5)
> >
> > Oct 12 13:49:12.791 [core.load] ** SC-Cryptoworks (pri -5)
> >
> > Oct 12 13:49:12.791 [core.load] ** Irdeto2 (pri -8)
> >
> > Oct 12 13:49:12.791 [core.load] ** Irdeto (pri -10)
> >
> > Oct 12 13:49:12.791 [core.load] ** Fake-NDS (pri -12)
> >
> > Oct 12 13:49:12.791 [core.load] ** ConstCW (pri -20)
> >
> > Oct 12 13:49:12.791 [core.load] ** Seca (pri -10)
> >
> > Oct 12 13:49:12.791 [core.load] ** Viaccess (pri -10)
> >
> > Oct 12 13:49:12.791 [core.load] ** Nagra2 (pri -10)
> >
> > Oct 12 13:49:12.791 [core.load] ** Nagra (pri -10)
> >
> > Oct 12 13:49:12.791 [core.load] ** Cryptoworks (pri -10)
> >
> > Oct 12 13:49:12.791 [core.load] ** SC-Irdeto (pri -5)
> >
> > Oct 12 13:49:12.791 [general.info] Using software decryption on card 1/0
> >
> > Oct 12 13:49:12.802 [core.ci] card 1/0, slot 0 (v= 1) caids: 0100 0500 09c4 1817 098c 1818 1833 1819 1811 0d00 1702 1863 0648 0d05 0d95 09af 1810 0622 0624 09c7 0b00 0d96 1834 1861 1722 1830 1837 1843 1860 0d03 0d70 0d02 0650 0666 09b2 0d0f 0d98 1812 1836
> >
> > Oct 12 13:49:12.802 [core.ci] 1/0: reset of slot 0 requested
> >
> > Oct 12 13:49:12.802 [general.info] Using software decryption on card 0/0
> >
> > Oct 12 13:49:12.829 [core.ci] card 0/0, slot 0 (v= 1) caids: 0100 0500 09c4 1817 098c 1818 1833 1819 1811 0d00 1702 1863 0648 0d05 0d95 09af 1810 0622 0624 09c7 0b00 0d96 1834 1861 1722 1830 1837 1843 1860 0d03 0d70 0d02 0650 0666 09b2 0d0f 0d98 1812 1836
> >
> > Oct 12 13:49:12.829 [core.ci] 0/0: reset of slot 0 requested
> >
> > Oct 12 13:49:13.687 [core.ci] 0/0.0: doReply changed, reset triggered
> >
> > Oct 12 13:49:13.687 [core.ci] 0/0.0: now using CAIDs version 1
> >
> > Oct 12 13:49:13.687 [core.ci] 0/0.0: status 'present'
> >
> > Oct 12 13:49:13.818 [core.ci] 1/0.0: doReply changed, reset triggered
> >
> > Oct 12 13:49:13.818 [core.ci] 1/0.0: now using CAIDs version 1
> >
> > Oct 12 13:49:13.818 [core.ci] 1/0.0: status 'present'
> >
> > Oct 12 13:49:13.868 [core.ci] 1/0.0: status 'reset'
> >
> > Oct 12 13:49:13.888 [core.ci] 0/0.0: status 'reset'
> >
> > Oct 12 13:49:14.439 [core.ci] 0/0.0: status 'ready'
> >
> > Oct 12 13:49:14.819 [core.ci] 1/0.0: status 'ready'
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 -> 00 01 82 01 01
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 <- 00 01 83 01 01 80 02 01 80
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 <- 00 01 a0 07 01 91 04 00 03 00 41 80 02 01 00
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 -> 00 01 a0 0a 01 92 07 00 00 03 00 41 00 01
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 -> 00 01 a0 09 01 90 02 00 01 9f 80 30 00
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 sending CA info
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:49:14.920 [core.ci] 1/0.0 <- 00 01 a0 57 01 90 02 00 01 9f 80 31 4e 01 00 05 00 09 c4 18 17 09 8c 18 18 18 33 18 19 18 11 0d 00 17 02 18 63 06 48 0d 05 0d 95 09 af 18 10 06 22 06 24 09 c7 0b 00 0d 96 18 34 18 61 17 22 18 30 18 37 18 43 18 60 0d 03 0d 70 0d 02 06 50 06 66 09 b2 0d 0f 0d 98 18 12 18 36 80 02 01 00
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 -> 00 01 82 01 01
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 <- 00 01 83 01 01 80 02 01 80
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 <- 00 01 a0 07 01 91 04 00 03 00 41 80 02 01 00
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 -> 00 01 a0 0a 01 92 07 00 00 03 00 41 00 01
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 -> 00 01 a0 09 01 90 02 00 01 9f 80 30 00
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 sending CA info
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:49:14.940 [core.ci] 0/0.0 <- 00 01 a0 57 01 90 02 00 01 9f 80 31 4e 01 00 05 00 09 c4 18 17 09 8c 18 18 18 33 18 19 18 11 0d 00 17 02 18 63 06 48 0d 05 0d 95 09 af 18 10 06 22 06 24 09 c7 0b 00 0d 96 18 34 18 61 17 22 18 30 18 37 18 43 18 60 0d 03 0d 70 0d 02 06 50 06 66 09 b2 0d 0f 0d 98 18 12 18 36 80 02 01 00
> >
> > Oct 12 13:49:15.922 [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
> >
> > Oct 12 13:49:15.922 [core.ci] 1/0.0 CA_PMT decoding len=7 lm=3 prg=0 len=1/1 ci_cmd(G)=03
> >
> > Oct 12 13:49:15.922 [core.ci] 1/0.0 got CA pmt ciCmd=3 caLm=3
> >
> > Oct 12 13:49:15.922 [core.ci] 1/0.0 answer to query
> >
> > Oct 12 13:49:15.922 [core.ci] 1/0.0 no action taken
> >
> > Oct 12 13:49:15.922 [core.ci] 1/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:49:15.922 [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
> >
> > Oct 12 13:49:15.942 [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
> >
> > Oct 12 13:49:15.942 [core.ci] 0/0.0 CA_PMT decoding len=7 lm=3 prg=0 len=1/1 ci_cmd(G)=03
> >
> > Oct 12 13:49:15.942 [core.ci] 0/0.0 got CA pmt ciCmd=3 caLm=3
> >
> > Oct 12 13:49:15.942 [core.ci] 0/0.0 answer to query
> >
> > Oct 12 13:49:15.942 [core.ci] 0/0.0 no action taken
> >
> > Oct 12 13:49:15.942 [core.ci] 0/0.0 -> 00 01 81 01 01
> >
> > Oct 12 13:49:15.942 [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
> >
> > Oct 12 13:49:16.122 [core.pids] 0/0: now tuned to source 5300ff40(S19.2E) transponder 1b097
> >
> > Oct 12 13:49:20.451 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b52a
> >
> > Oct 12 13:49:20.736 [core.ci] 1/0.0 -> 00 01 a0 1e 01 90 02 00 01 9f 80 32 15 04 00 83 01 00 00 02 04 ff 00 00 06 05 03 00 00 06 05 04 00 00
> >
> > Oct 12 13:49:20.736 [core.ci] 1/0.0 CA_PMT decoding len=15 lm=4 prg=131 len=0/f pid=2,4ff len=0/a pid=6,503 len=0/5 pid=6,504 len=0/0
> >
> > Oct 12 13:49:20.736 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=4
> >
> > Oct 12 13:49:20.736 [core.ci] 1/0.0 answer to query surpressed
> >
> > Oct 12 13:49:20.736 [core.ci] 1/0.0 set CAM decrypt (prg 131)
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0: add SID 131 (
zero=0 noshift=1)
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0: pids VIDEO=04ff DOLBY=0503 DOLBY=0504
> >
> > Oct 12 13:49:20.736 [core.pids] Ca descriptors after simplify (pidCa=0)
> >
> > Oct 12 13:49:20.736 [core.pids] prgca: <empty>
> >
> > Oct 12 13:49:20.736 [core.pids] pidca 04ff: <empty>
> >
> > Oct 12 13:49:20.736 [core.pids] pidca 0503: <empty>
> >
> > Oct 12 13:49:20.736 [core.pids] pidca 0504: <empty>
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0: SID=131
zero=0 | sid=-/-/-
zero=-/-/- idle=-/-/-
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0: found handler for SID 131 (1/0.1 idle=1 idx=1)
> >
> > Oct 12 13:49:20.736 [core.ecm] 1/0.1: setting new SID 131
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0.1: pids on entry
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0.1: pids after delete
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0: descrambling pid 04ff on index 1
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0: descrambling pid 0503 on index 1
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0: descrambling pid 0504 on index 1
> >
> > Oct 12 13:49:20.736 [core.pids] 1/0.1: pids after add VIDEO=04ff DOLBY=0503 DOLBY=0504
> >
> > Oct 12 13:49:20.736 [core.ecm] 1/0.1: is no longer idle
> >
> > Oct 12 13:49:20.796 [core.ecm] 1/0.1: triggered SID -1/131 idx -1/1 mode -1/0 -
> >
> > Oct 12 13:49:40.865 [core.ecm] 1/0.1: no encryption system found
> >
> > Oct 12 13:50:21.005 [core.ecm] last message repeated 2 times
> >
> > Oct 12 13:50:59.604 [core.ci] 1/0.0 -> 00 01 a0 3a 01 90 02 00 01 9f 80 32 31 05 00 83 01 00 2b 01 09 04 18 33 f8 b6 09 04 18 34 fc b6 09 04 09 c4 fb b6 09 04 09 c7 fd b6 09 04 09 af ff b6 09 04 09 8c fa b6 09 04 18 61 f9 b6
> >
> > Oct 12 13:50:59.604 [core.ci] 1/0.0 CA_PMT decoding len=31 lm=5 prg=131 len=2b/2b ci_cmd(G)=01
> >
> > Oct 12 13:50:59.604 [core.ci] 1/0.0 got CA pmt ciCmd=1 caLm=5
> >
> > Oct 12 13:50:59.604 [core.ci] 1/0.0 answer to query surpressed
> >
> > Oct 12 13:50:59.604 [core.ci] 1/0.0 set CAM decrypt (prg 131)
> >
> > Oct 12 13:50:59.604 [core.pids] 1/0: update SID 131 (
zero=0 noshift=1)
> >
> > Oct 12 13:50:59.604 [core.pids] 1/0: SID=131
zero=0 | sid=1/131/0
zero=-/-/- idle=-/-/-
> >
> > Oct 12 13:50:59.604 [core.pids] 1/0: found handler for SID 131 (1/0.1 idle=0 idx=1)
> >
> > Oct 12 13:50:59.604 [core.pids] 1/0.1: pids on entry VIDEO=04ff DOLBY=0503 DOLBY=0504
> >
> > Oct 12 13:50:59.604 [core.pids] 1/0.1: pids after delete
> >
> > Oct 12 13:50:59.604 [core.pids] 1/0.1: pids after add
> >
> > Oct 12 13:50:59.604 [core.ecm] 1/0.1: is idle now
> >
> > Oct 12 13:51:13.738 [core.net] idle timeout, disconnected xxx.xxx.xxx.xxx:xxxxx
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 answer to query surpressed
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 stop decrypt
> >
> > Oct 12 13:51:16.634 [core.ecm] 1/0.1: stop
> >
> > Oct 12 13:51:16.634 [core.pids] 1/0: now tuned to source 5300ff40(S19.2E) transponder 1b097
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 -> 00 01 a0 0f 01 90 02 00 01 9f 80 32 06 03 00 00 01 00 00
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0/0
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 got CA pmt ciCmd=-1 caLm=3
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 answer to query surpressed
> >
> > Oct 12 13:51:16.634 [core.ci] 1/0.0 stop decrypt
> >
> > Oct 12 13:51:16.677 [core.ecm] 1/0.1: triggered SID 131/-1 idx 1/1 mode -1/-1 -