Sep 4 21:55:06.241 : Version: 0.0.2-77M/77 Sep 4 21:55:06.261 CAM: initializing plugin: SoftCam (0.9.0-Unknown): A software emulated CAM Sep 4 21:55:06.277 CAM(general.info): SC version 0.9.0-Unknown initializing Sep 4 21:55:06.294 CAM: starting plugin: Sep 4 21:55:06.311 CAM(general.info): SC version 0.9.0-Unknown starting Sep 4 21:55:06.328 CAM(core.load): ** Plugin config: Sep 4 21:55:06.344 CAM(core.load): ** Key updates (AU) are enabled (active CAIDs) (no prestart) Sep 4 21:55:06.361 CAM(core.load): ** Local systems DON'T take priority over cached remote Sep 4 21:55:06.388 CAM(core.load): ** Concurrent FF recordings are NOT allowed Sep 4 21:55:06.413 CAM(core.load): ** Force transfermode with digital audio Sep 4 21:55:06.438 CAM(core.load): ** ScCaps are 1 2 0 0 0 0 0 0 0 0 Sep 4 21:55:06.463 CAM(core.load): ** Ignored CAIDs 0000 0000 0000 0000 0000 0000 0000 0000 0000 0000 0000 0000 0000 0000 0000 0000 Sep 4 21:55:06.488 CAM(general.info): loading cardclient config from /etc/open-sasc-ng/cardclient.conf Sep 4 21:55:06.513 CAM(cardclient.newcamd): now using protocol version 525 (cdLen=8) Sep 4 21:55:06.538 CAM(cardclient.core): hostname=192.168.1.33 port=13000 emm=1 emmCaids 0100/ffff Sep 4 21:55:06.564 CAM(cardclient.core): Newcamd: username=mythtv password=mythtv key=0102030405060708091011121314 Sep 4 21:55:06.589 CAM(core.net): netwatch up Sep 4 21:55:06.623 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:55:06.651 CAM(cardclient.login): Newcamd: CaID=0100 admin=1 srvUA=0000000022112171 provider 00006A/00000000002F504D 000000/00000000110890B8 Sep 4 21:55:06.672 CAM(cardclient.core): client 'Newcamd' ready Sep 4 21:55:06.697 CAM(general.info): loading keys from /etc/open-sasc-ng/SoftCam.Key Sep 4 21:55:06.723 CAM(core.load): loaded 0 keys from /etc/open-sasc-ng/SoftCam.Key Sep 4 21:55:06.748 CAM(general.info): loading smartcard data from /etc/open-sasc-ng/smartcard.conf Sep 4 21:55:06.773 CAM(core.load): loaded 0 smartcard data from /etc/open-sasc-ng/smartcard.conf Sep 4 21:55:06.798 CAM(general.error): no keys loaded for softcam! Sep 4 21:55:06.823 CAM(core.load): ** registered systems: Sep 4 21:55:06.848 CAM(core.load): ** Cryptoworks (pri -10) Sep 4 21:55:06.873 CAM(core.load): ** Viaccess (pri -10) Sep 4 21:55:06.898 CAM(core.load): ** ConstCW (pri -20) Sep 4 21:55:06.924 CAM(core.load): ** SC-Conax (pri -5) Sep 4 21:55:06.948 CAM(core.load): ** SC-Cryptoworks (pri -5) Sep 4 21:55:06.974 CAM(core.load): ** SC-VideoGuard2 (pri -5) Sep 4 21:55:06.999 CAM(core.load): ** Nagra (pri -10) Sep 4 21:55:07.024 CAM(core.load): ** Nagra2 (pri -10) Sep 4 21:55:07.049 CAM(core.load): ** SC-Viaccess (pri -5) Sep 4 21:55:07.074 CAM(core.load): ** Seca (pri -10) Sep 4 21:55:07.099 CAM(core.load): ** SC-Seca (pri -5) Sep 4 21:55:07.124 CAM(core.load): ** SC-Irdeto (pri -5) Sep 4 21:55:07.149 CAM(core.load): ** @SHL (pri -10) Sep 4 21:55:07.174 CAM(core.load): ** Cardclient (pri -15) Sep 4 21:55:07.191 CAM(core.load): ** Irdeto (pri -10) Sep 4 21:55:07.208 CAM(core.load): ** SC-Nagra (pri -5) Sep 4 21:55:07.225 CAM(core.load): ** Conax (pri -10) Sep 4 21:55:07.241 CAM(general.warn): no smartcard interface defined! Sep 4 21:55:08.262 demux: Starting thread on /dev/dvb/adapter1/demux1 The thread scheduling parameters indicate: policy = 0 priority = 0 Sep 4 21:55:08.282 dvr: Starting thread on /dev/dvb/adapter1/dvr1 The thread scheduling parameters indicate: policy = 1 priority = 99 Sep 4 21:55:08.262 frontend: Starting thread on /dev/dvb/adapter1/frontend1 The thread scheduling parameters indicate: policy = 0 priority = 0 Sep 4 21:55:08.342 : Listening on port 5456 Sep 4 21:57:06.979 CHANNEL: Tuning frontend (new) Sep 4 21:57:07.014 CSA: Got command(1): I idx: 0 pid: 0 Sep 4 21:57:07.453 CHANNEL: Clearing tuning cache due to switch cmd Sep 4 21:57:07.487 CHANNEL: Clearing tuning cache due to switch cmd Sep 4 21:57:07.523 CHANNEL: Clearing tuning cache due to switch cmd Sep 4 21:57:07.563 CHANNEL: Clearing tuning cache due to switch cmd Sep 4 21:57:07.614 CAM(core.net): idle timeout, disconnected 192.168.1.33:13000 Sep 4 21:57:07.642 CAM(core.net): netwatch down Sep 4 21:57:07.682 CHANNEL: Clearing tuning cache due to switch cmd Sep 4 21:57:07.725 CHANNEL: Tuning frontend (new) Sep 4 21:57:07.750 CSA: Got command(1): I idx: 0 pid: 0 Sep 4 21:57:07.961 CHANNEL: start: read pes returned err: 11 start: read pes returned: Resource temporarily unavailable Sep 4 21:57:08.493 CHANNEL: start: read pes returned err: 11 start: read pes returned: Resource temporarily unavailable Sep 4 21:57:08.542 CHANNEL: read_nit expected table 0x40 or 0x41 but got 0x00 Sep 4 21:57:08.768 CHANNEL: start: read pes returned err: 11 start: read pes returned: Resource temporarily unavailable Sep 4 21:57:08.818 CHANNEL: read_nit expected table 0x40 or 0x41 but got 0x00 Sep 4 21:57:09.043 CHANNEL: start: read pes returned err: 11 start: read pes returned: Resource temporarily unavailable Sep 4 21:57:09.093 CHANNEL: read_nit expected table 0x40 or 0x41 but got 0x00 Sep 4 21:57:09.310 CHANNEL: start: read pes returned err: 11 start: read pes returned: Resource temporarily unavailable Sep 4 21:57:09.343 CHANNEL: read_nit expected table 0x40 or 0x41 but got 0x00 Sep 4 21:57:09.560 CHANNEL: start: read pes returned err: 11 start: read pes returned: Resource temporarily unavailable Sep 4 21:57:09.594 CHANNEL: read_nit expected table 0x40 or 0x41 but got 0x00 Sep 4 21:57:09.610 CHANNEL: start: giving up reading nit Sep 4 21:57:09.720 CHANNEL: Already seen sid: 4091 Sep 4 21:57:09.735 CHANNEL: Already seen sid: 4047 Sep 4 21:57:09.752 CHANNEL: Already seen sid: 4035 Sep 4 21:57:09.857 CAM(core.pids): 0: now tuned to source 8000 transponder fab Sep 4 21:57:09.869 CAM: SC completed Tune cmd Sep 4 21:57:09.887 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:09.903 CAM(core.pids): 0: pids typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 typ5=00e4 typ5=00e5 Sep 4 21:57:09.922 CAM(core.pids): 0: SID=4011 zero=0 | sid=-/-/- zero=-/-/- idle=-/-/- Sep 4 21:57:09.936 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=1 idx=1) Sep 4 21:57:09.953 CAM(core.ecm): 0.1: setting new SID 4011 Sep 4 21:57:09.970 CAM(core.pids): 0.1: pids on entry Sep 4 21:57:09.987 CAM(core.pids): 0.1: pids after delete Sep 4 21:57:10.003 CAM(core.pids): 0: descrambling pid 0205 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.054 CSA: Got command(1): P idx: 1 pid: 517 Sep 4 21:57:10.071 CAM(core.pids): 0: descrambling pid 0058 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.121 CSA: Got command(1): P idx: 1 pid: 88 Sep 4 21:57:10.138 CAM(core.pids): 0: descrambling pid 0022 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.188 CSA: Got command(1): P idx: 1 pid: 34 Sep 4 21:57:10.213 CAM(core.pids): 0: descrambling pid 00d0 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.288 CSA: Got command(1): P idx: 1 pid: 208 Sep 4 21:57:10.314 CAM(core.pids): 0: descrambling pid 00fa on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.389 CSA: Got command(1): P idx: 1 pid: 250 Sep 4 21:57:10.414 CAM(core.pids): 0: descrambling pid 00e0 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.489 CSA: Got command(1): P idx: 1 pid: 224 Sep 4 21:57:10.514 CAM(core.pids): 0: descrambling pid 00e2 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.590 CSA: Got command(1): P idx: 1 pid: 226 Sep 4 21:57:10.615 CAM(core.pids): 0: descrambling pid 00e3 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.690 CSA: Got command(1): P idx: 1 pid: 227 Sep 4 21:57:10.715 CAM(core.pids): 0: descrambling pid 00e4 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.791 CSA: Got command(1): P idx: 1 pid: 228 Sep 4 21:57:10.816 CAM(core.pids): 0: descrambling pid 00e5 on index 1 Called cSascDvbDevice::SetCaPid Sep 4 21:57:10.892 CSA: Got command(1): P idx: 1 pid: 229 Sep 4 21:57:10.917 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 typ5=00e4 typ5=00e5 Sep 4 21:57:10.943 CAM(core.ecm): 0.1: is no longer idle Sep 4 21:57:10.968 MSG: Got unprocessed message type: 1 Sep 4 21:57:11.129 CAM(core.ecm): 0.1: triggered SID -1/4011 idx -1/1 mode -1/0 - Sep 4 21:57:11.143 CAM(core.ecm): 0.1: got CaDescriptors for SID 4011 (len=158) Sep 4 21:57:11.160 CAM(core.ecm): 0.1: descriptor 06 22 e5 67 Sep 4 21:57:11.177 CAM(core.ecm): 0.1: found 0622 (Irdeto) id 0000 with ecm 567 (new) Sep 4 21:57:11.193 CAM(core.ecm): 0.1: descriptor 01 00 e6 43 00 6a ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:11.210 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 643 (new) Sep 4 21:57:11.227 CAM(core.ecm): 0.1: descriptor 01 00 e6 61 00 6c ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:11.244 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 661 (new) Sep 4 21:57:11.260 CAM(core.ecm): 0.1: descriptor 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:11.277 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 70b (new) Sep 4 21:57:11.294 CAM(core.ecm): 0.1: descriptor 01 00 e7 1f 00 6c ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:11.311 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 71f (new) Sep 4 21:57:11.328 CAM(core.ecm): 0.1: descriptor 01 00 e6 43 00 6a ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:11.344 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 643 (already present) Sep 4 21:57:11.361 CAM(core.ecm): 0.1: descriptor 01 00 e6 61 00 6c ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:11.378 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 661 (already present) Sep 4 21:57:11.395 CAM(core.ecm): 0.1: descriptor 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:11.411 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 70b (already present) Sep 4 21:57:11.428 CAM(core.ecm): 0.1: descriptor 01 00 e7 1f 00 6c ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:11.445 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 71f (already present) Sep 4 21:57:11.463 CAM(core.ecm): 0.1: try system Irdeto (0622) id 0000 with ecm 567 (pri=-10) Sep 4 21:57:11.696 CAM(core.au): 0: chain caid 0622 -> Irdeto(-10) [0136-82/ff/00] Sep 4 21:57:11.712 CAM(core.au): 0: chain caid 0100 -> Seca(-10) Cardclient(-15) [00b6-82/ff/00] [00b7-84/ff/00] [00b9-84/ff/00] [00ba-84/ff/00] [00bb-84/ff/00] Sep 4 21:57:11.729 CAM(core.au): 0: chain caid 0d02 -> none available Sep 4 21:57:11.741 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:11.746 CAM(core.au): 0: starting chain 0622 Sep 4 21:57:11.771 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:12.045 CAM(irdeto.ecm): last message repeated 5 times Sep 4 21:57:12.055 CAM(core.au): 0: starting chain 0100 Sep 4 21:57:12.072 CAM(core.ecm): 0.1: try system Seca (0100) id 006a with ecm 643 (pri=-10) Sep 4 21:57:12.096 CAM(general.error): can't stat filemap /etc/open-sasc-ng/seca/s2_sse.bin: No such file or directory Sep 4 21:57:12.121 CAM(general.error): can't stat filemap /etc/open-sasc-ng/seca/s2_sse_006a.bin: No such file or directory Sep 4 21:57:12.147 CAM(general.error): can't stat filemap /etc/open-sasc-ng/seca/s2_cw_006a.bin: No such file or directory Sep 4 21:57:12.172 CAM(seca.ecm): Seca2 provider 006a not supported Sep 4 21:57:12.258 CAM(core.ecm): 0.1: try system Seca (0100) id 006c with ecm 661 (pri=-10) Sep 4 21:57:12.380 CAM(seca.ecm): Seca2 provider 006c not supported Sep 4 21:57:12.562 CAM(core.ecm): 0.1: try system Seca (0100) id 006a with ecm 70b (pri=-10) Sep 4 21:57:12.668 CAM(core.ecm): system: no key found for S 006A 0E Sep 4 21:57:12.865 CAM(core.ecm): 0.1: try system Seca (0100) id 006c with ecm 71f (pri=-10) Sep 4 21:57:12.988 CAM(core.ecm): system: no key found for S 006C 0E Sep 4 21:57:13.184 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006a with ecm 643 (pri=-15) Sep 4 21:57:13.261 CAM(cardclient.core): cc-loop Sep 4 21:57:13.281 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:13.306 CAM(core.net): netwatch up Sep 4 21:57:13.331 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:13.359 CAM(cardclient.login): Newcamd: CaID=0100 admin=1 srvUA=0000000022112171 provider 00006A/00000000002F504D 000000/00000000110890B8 Sep 4 21:57:13.386 CAM(cardclient.core): recv error. reconnecting... Sep 4 21:57:13.407 CAM(core.net): netwatch down Sep 4 21:57:13.432 CAM(cardclient.newcamd): warning an unexpected error occurred Sep 4 21:57:13.457 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (151 ms) Sep 4 21:57:13.482 CAM(cardclient.core): cc-loop Sep 4 21:57:13.507 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:13.532 CAM(core.net): netwatch up Sep 4 21:57:13.558 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:13.583 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:13.608 CAM(core.net): netwatch down Sep 4 21:57:13.633 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:13.658 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006c with ecm 661 (pri=-15) Sep 4 21:57:13.683 CAM(cardclient.core): cc-loop Sep 4 21:57:13.708 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:13.733 CAM(core.net): netwatch up Sep 4 21:57:13.759 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:13.783 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:13.808 CAM(core.net): netwatch down Sep 4 21:57:13.834 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:13.852 CAM(cardclient.core): cc-loop Sep 4 21:57:13.868 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:13.885 CAM(core.net): netwatch up Sep 4 21:57:13.902 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:13.919 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:13.936 CAM(core.net): netwatch down Sep 4 21:57:13.953 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (68 ms) Sep 4 21:57:13.969 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006a with ecm 70b (pri=-15) Sep 4 21:57:14.066 CAM(cardclient.core): cc-loop Sep 4 21:57:14.078 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:14.095 CAM(core.net): netwatch up Sep 4 21:57:14.112 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:14.128 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:14.145 CAM(core.net): netwatch down Sep 4 21:57:14.162 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (67 ms) Sep 4 21:57:14.187 CAM(cardclient.core): cc-loop Sep 4 21:57:14.203 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:14.220 CAM(core.net): netwatch up Sep 4 21:57:14.237 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:14.254 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:14.271 CAM(core.net): netwatch down Sep 4 21:57:14.287 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (67 ms) Sep 4 21:57:14.304 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006c with ecm 71f (pri=-15) Sep 4 21:57:14.385 CAM(cardclient.core): cc-loop Sep 4 21:57:14.404 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:14.430 CAM(core.net): netwatch up Sep 4 21:57:14.455 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:14.480 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:14.505 CAM(core.net): netwatch down Sep 4 21:57:14.530 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:14.555 CAM(cardclient.core): cc-loop Sep 4 21:57:14.580 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:14.605 CAM(core.net): netwatch up Sep 4 21:57:14.631 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:14.656 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:14.681 CAM(core.net): netwatch down Sep 4 21:57:14.706 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:14.731 CAM(core.ecm): 0.1: got CaDescriptors for SID 4011 (len=158) Sep 4 21:57:14.756 CAM(core.ecm): 0.1: descriptor 06 22 e5 67 Sep 4 21:57:14.781 CAM(core.ecm): 0.1: found 0622 (Irdeto) id 0000 with ecm 567 (already present) Sep 4 21:57:14.806 CAM(core.ecm): 0.1: descriptor 01 00 e6 43 00 6a ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:14.831 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 643 (already present) Sep 4 21:57:14.856 CAM(core.ecm): 0.1: descriptor 01 00 e6 61 00 6c ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:14.881 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 661 (already present) Sep 4 21:57:14.907 CAM(core.ecm): 0.1: descriptor 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:14.932 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 70b (already present) Sep 4 21:57:14.957 CAM(core.ecm): 0.1: descriptor 01 00 e7 1f 00 6c ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:14.982 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 71f (already present) Sep 4 21:57:15.007 CAM(core.ecm): 0.1: descriptor 01 00 e6 43 00 6a ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:15.032 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 643 (already present) Sep 4 21:57:15.057 CAM(core.ecm): 0.1: descriptor 01 00 e6 61 00 6c ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:15.082 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 661 (already present) Sep 4 21:57:15.107 CAM(core.ecm): 0.1: descriptor 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:15.132 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 70b (already present) Sep 4 21:57:15.158 CAM(core.ecm): 0.1: descriptor 01 00 e7 1f 00 6c ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:15.183 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 71f (already present) Sep 4 21:57:15.208 CAM(core.ecm): 0.1: try system Irdeto (0622) id 0000 with ecm 567 (pri=-10) Sep 4 21:57:15.372 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:15.646 CAM(irdeto.ecm): last message repeated 7 times Sep 4 21:57:15.666 CAM(core.ecm): 0.1: try system Seca (0100) id 006a with ecm 643 (pri=-10) Sep 4 21:57:15.707 CAM(seca.ecm): Seca2 provider 006a not supported Sep 4 21:57:15.859 CAM(core.ecm): 0.1: try system Seca (0100) id 006c with ecm 661 (pri=-10) Sep 4 21:57:15.965 CAM(seca.ecm): Seca2 provider 006c not supported Sep 4 21:57:16.163 CAM(core.ecm): 0.1: try system Seca (0100) id 006a with ecm 70b (pri=-10) Sep 4 21:57:16.184 CAM(core.ecm): system: no key found for S 006A 0E Sep 4 21:57:16.406 CAM(core.ecm): 0.1: try system Seca (0100) id 006c with ecm 71f (pri=-10) Sep 4 21:57:16.466 CAM(core.ecm): system: no key found for S 006C 0E Sep 4 21:57:16.664 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006a with ecm 643 (pri=-15) Sep 4 21:57:16.785 CAM(cardclient.core): cc-loop Sep 4 21:57:16.801 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:16.892 CAM(cardclient.core): cc-loop Sep 4 21:57:16.901 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:16.998 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006c with ecm 661 (pri=-15) Sep 4 21:57:17.074 CAM(cardclient.core): cc-loop Sep 4 21:57:17.085 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:17.165 CAM(cardclient.core): cc-loop Sep 4 21:57:17.178 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:17.272 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006a with ecm 70b (pri=-15) Sep 4 21:57:17.378 CAM(cardclient.core): cc-loop Sep 4 21:57:17.395 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:17.484 CAM(cardclient.core): cc-loop Sep 4 21:57:17.495 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:17.591 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006c with ecm 71f (pri=-15) Sep 4 21:57:17.712 CAM(cardclient.core): cc-loop Sep 4 21:57:17.729 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:17.773 CAM(cardclient.core): cc-loop Sep 4 21:57:17.796 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:17.865 CAM(core.ecm): 0.1: got CaDescriptors for SID 4011 (len=158) Sep 4 21:57:17.888 CAM(core.ecm): 0.1: descriptor 06 22 e5 67 Sep 4 21:57:17.913 CAM(core.ecm): 0.1: found 0622 (Irdeto) id 0000 with ecm 567 (already present) Sep 4 21:57:17.938 CAM(core.ecm): 0.1: descriptor 01 00 e6 43 00 6a ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:17.963 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 643 (already present) Sep 4 21:57:17.988 CAM(core.ecm): 0.1: descriptor 01 00 e6 61 00 6c ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:18.013 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 661 (already present) Sep 4 21:57:18.038 CAM(core.ecm): 0.1: descriptor 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:18.063 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 70b (already present) Sep 4 21:57:18.088 CAM(core.ecm): 0.1: descriptor 01 00 e7 1f 00 6c ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:18.114 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 71f (already present) Sep 4 21:57:18.139 CAM(core.ecm): 0.1: descriptor 01 00 e6 43 00 6a ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:18.164 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 643 (already present) Sep 4 21:57:18.189 CAM(core.ecm): 0.1: descriptor 01 00 e6 61 00 6c ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:18.214 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 661 (already present) Sep 4 21:57:18.239 CAM(core.ecm): 0.1: descriptor 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:18.264 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 70b (already present) Sep 4 21:57:18.289 CAM(core.ecm): 0.1: descriptor 01 00 e7 1f 00 6c ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:18.314 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 71f (already present) Sep 4 21:57:18.340 CAM(core.ecm): 0.1: try system Irdeto (0622) id 0000 with ecm 567 (pri=-10) Sep 4 21:57:18.365 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:18.670 CAM(irdeto.ecm): last message repeated 7 times Sep 4 21:57:18.690 CAM(core.ecm): 0.1: try system Seca (0100) id 006a with ecm 643 (pri=-10) Sep 4 21:57:18.776 CAM(seca.ecm): Seca2 provider 006a not supported Sep 4 21:57:18.973 CAM(core.ecm): 0.1: try system Seca (0100) id 006c with ecm 661 (pri=-10) Sep 4 21:57:19.064 CAM(seca.ecm): Seca2 provider 006c not supported Sep 4 21:57:19.262 CAM(core.ecm): 0.1: try system Seca (0100) id 006a with ecm 70b (pri=-10) Sep 4 21:57:19.282 CAM(core.ecm): system: no key found for S 006A 0E Sep 4 21:57:19.490 CAM(core.ecm): 0.1: try system Seca (0100) id 006c with ecm 71f (pri=-10) Sep 4 21:57:19.596 CAM(core.ecm): system: no key found for S 006C 0E Sep 4 21:57:19.763 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006a with ecm 643 (pri=-15) Sep 4 21:57:19.870 CAM(cardclient.core): cc-loop Sep 4 21:57:19.891 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:19.961 CAM(cardclient.core): cc-loop Sep 4 21:57:19.983 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:20.067 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006c with ecm 661 (pri=-15) Sep 4 21:57:20.091 CAM(cardclient.core): cc-loop Sep 4 21:57:20.116 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:20.173 CAM(cardclient.core): cc-loop Sep 4 21:57:20.191 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:20.265 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006a with ecm 70b (pri=-15) Sep 4 21:57:20.295 CAM(cardclient.core): cc-loop Sep 4 21:57:20.317 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:20.371 CAM(cardclient.core): cc-loop Sep 4 21:57:20.392 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:20.477 CAM(core.ecm): 0.1: try system Cardclient (0100) id 006c with ecm 71f (pri=-15) Sep 4 21:57:20.568 CAM(cardclient.core): cc-loop Sep 4 21:57:20.584 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:20.675 CAM(cardclient.core): cc-loop Sep 4 21:57:20.684 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:20.781 CAM(core.ecm): 0.1: got CaDescriptors for SID 4011 (len=158) Sep 4 21:57:20.793 CAM(core.ecm): 0.1: descriptor 06 22 e5 67 Sep 4 21:57:20.810 CAM(core.ecm): 0.1: found 0622 (Irdeto) id 0000 with ecm 567 (already present) Sep 4 21:57:20.826 CAM(core.ecm): 0.1: descriptor 01 00 e6 43 00 6a ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:20.843 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 643 (already present) Sep 4 21:57:20.860 CAM(core.ecm): 0.1: descriptor 01 00 e6 61 00 6c ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:20.877 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 661 (already present) Sep 4 21:57:20.893 CAM(core.ecm): 0.1: descriptor 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:20.910 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 70b (already present) Sep 4 21:57:20.927 CAM(core.ecm): 0.1: descriptor 01 00 e7 1f 00 6c ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:20.944 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 71f (already present) Sep 4 21:57:20.961 CAM(core.ecm): 0.1: descriptor 01 00 e6 43 00 6a ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:20.977 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 643 (already present) Sep 4 21:57:20.996 CAM(core.ecm): 0.1: descriptor 01 00 e6 61 00 6c ff 00 00 00 00 00 00 02 14 27 24 Sep 4 21:57:21.021 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 661 (already present) Sep 4 21:57:21.046 CAM(core.ecm): 0.1: descriptor 01 00 e7 0b 00 6a ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:21.071 CAM(core.ecm): 0.1: found 0100 (Seca) id 006a with ecm 70b (already present) Sep 4 21:57:21.096 CAM(core.ecm): 0.1: descriptor 01 00 e7 1f 00 6c ff ff e0 00 00 00 00 00 00 27 24 Sep 4 21:57:21.121 CAM(core.ecm): 0.1: found 0100 (Seca) id 006c with ecm 71f (already present) Sep 4 21:57:21.146 CAM(core.ecm): 0.1: try system Irdeto (0622) id 0000 with ecm 567 (pri=-10) Sep 4 21:57:21.171 CAM(core.ecm): 0.1: stopping message log until valid key is found Sep 4 21:57:23.546 CAM(cardclient.core): cc-loop Sep 4 21:57:23.571 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:23.596 CAM(core.net): netwatch up Sep 4 21:57:23.622 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:23.647 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:23.672 CAM(core.net): netwatch down Sep 4 21:57:23.697 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:23.722 CAM(cardclient.core): cc-loop Sep 4 21:57:23.747 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:23.772 CAM(core.net): netwatch up Sep 4 21:57:23.797 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:23.823 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:23.848 CAM(core.net): netwatch down Sep 4 21:57:23.873 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:23.956 CAM(cardclient.core): cc-loop Sep 4 21:57:23.981 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:24.006 CAM(core.net): netwatch up Sep 4 21:57:24.032 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:24.056 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:24.082 CAM(core.net): netwatch down Sep 4 21:57:24.107 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:24.132 CAM(cardclient.core): cc-loop Sep 4 21:57:24.157 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:24.182 CAM(core.net): netwatch up Sep 4 21:57:24.207 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:24.232 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:24.257 CAM(core.net): netwatch down Sep 4 21:57:24.282 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (100 ms) Sep 4 21:57:24.321 CAM(cardclient.core): cc-loop Sep 4 21:57:24.341 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:24.366 CAM(core.net): netwatch up Sep 4 21:57:24.391 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:24.416 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:24.441 CAM(core.net): netwatch down Sep 4 21:57:24.467 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:24.483 CAM(cardclient.core): cc-loop Sep 4 21:57:24.500 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:24.517 CAM(core.net): netwatch up Sep 4 21:57:24.534 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:24.555 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:24.569 CAM(core.net): netwatch down Sep 4 21:57:24.584 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (67 ms) Sep 4 21:57:24.625 CAM(cardclient.core): cc-loop Sep 4 21:57:24.634 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:24.651 CAM(core.net): netwatch up Sep 4 21:57:24.668 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:24.684 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:24.701 CAM(core.net): netwatch down Sep 4 21:57:24.718 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (67 ms) Sep 4 21:57:24.735 CAM(cardclient.core): cc-loop Sep 4 21:57:24.751 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:24.768 CAM(core.net): netwatch up Sep 4 21:57:24.785 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:24.802 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:24.819 CAM(core.net): netwatch down Sep 4 21:57:24.836 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (68 ms) Sep 4 21:57:25.050 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:26.448 CAM(irdeto.ecm): last message repeated 7 times Sep 4 21:57:26.469 CAM(cardclient.core): cc-loop Sep 4 21:57:26.486 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:26.554 CAM(cardclient.core): cc-loop Sep 4 21:57:26.577 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:26.767 CAM(cardclient.core): cc-loop Sep 4 21:57:26.778 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:26.858 CAM(cardclient.core): cc-loop Sep 4 21:57:26.870 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:26.995 CAM(cardclient.core): cc-loop Sep 4 21:57:27.020 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:27.101 CAM(cardclient.core): cc-loop Sep 4 21:57:27.121 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:27.208 CAM(cardclient.core): cc-loop Sep 4 21:57:27.230 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:27.299 CAM(cardclient.core): cc-loop Sep 4 21:57:27.322 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:27.466 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:28.772 CAM(irdeto.ecm): last message repeated 7 times Sep 4 21:57:28.805 CAM(cardclient.core): cc-loop Sep 4 21:57:28.831 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:28.863 CAM(cardclient.core): cc-loop Sep 4 21:57:28.889 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:28.986 CAM(cardclient.core): cc-loop Sep 4 21:57:29.006 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:29.061 CAM(cardclient.core): cc-loop Sep 4 21:57:29.081 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:29.213 CAM(cardclient.core): cc-loop Sep 4 21:57:29.231 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:29.319 CAM(cardclient.core): cc-loop Sep 4 21:57:29.340 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:29.487 CAM(cardclient.core): cc-loop Sep 4 21:57:29.507 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:29.593 CAM(cardclient.core): cc-loop Sep 4 21:57:29.615 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:29.851 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:31.082 CAM(irdeto.ecm): last message repeated 7 times Sep 4 21:57:31.115 CAM(cardclient.core): cc-loop Sep 4 21:57:31.141 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:31.158 CAM(cardclient.core): cc-loop Sep 4 21:57:31.174 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:31.279 CAM(cardclient.core): cc-loop Sep 4 21:57:31.291 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:31.370 CAM(cardclient.core): cc-loop Sep 4 21:57:31.383 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:31.583 CAM(cardclient.core): cc-loop Sep 4 21:57:31.600 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:31.705 CAM(cardclient.core): cc-loop Sep 4 21:57:31.717 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:31.811 CAM(cardclient.core): cc-loop Sep 4 21:57:31.825 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:31.887 CAM(cardclient.core): cc-loop Sep 4 21:57:31.900 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:32.252 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:33.771 CAM(irdeto.ecm): last message repeated 7 times Sep 4 21:57:33.792 CAM(cardclient.core): cc-loop Sep 4 21:57:33.809 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:33.862 CAM(cardclient.core): cc-loop Sep 4 21:57:33.876 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:33.984 CAM(cardclient.core): cc-loop Sep 4 21:57:34.001 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:34.090 CAM(cardclient.core): cc-loop Sep 4 21:57:34.101 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:34.241 CAM(cardclient.core): cc-loop Sep 4 21:57:34.251 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:34.268 CAM(core.net): netwatch up Sep 4 21:57:34.285 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:34.302 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:34.318 CAM(core.net): netwatch down Sep 4 21:57:34.336 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (68 ms) Sep 4 21:57:34.352 CAM(cardclient.core): cc-loop Sep 4 21:57:34.377 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:34.402 CAM(core.net): netwatch up Sep 4 21:57:34.427 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:34.453 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:34.478 CAM(core.net): netwatch down Sep 4 21:57:34.503 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:34.530 CAM(cardclient.core): cc-loop Sep 4 21:57:34.553 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:34.578 CAM(core.net): netwatch up Sep 4 21:57:34.603 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:34.629 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:34.654 CAM(core.net): netwatch down Sep 4 21:57:34.678 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (100 ms) Sep 4 21:57:34.704 CAM(cardclient.core): cc-loop Sep 4 21:57:34.729 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:34.755 CAM(core.net): netwatch up Sep 4 21:57:34.781 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:34.806 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:34.831 CAM(core.net): netwatch down Sep 4 21:57:34.856 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:36.263 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:37.660 CAM(irdeto.ecm): last message repeated 6 times Sep 4 21:57:37.681 CAM(cardclient.core): cc-loop Sep 4 21:57:37.706 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:37.731 CAM(core.net): netwatch up Sep 4 21:57:37.756 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:37.782 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:37.806 CAM(core.net): netwatch down Sep 4 21:57:37.832 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (101 ms) Sep 4 21:57:37.848 CAM(cardclient.core): cc-loop Sep 4 21:57:37.873 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:37.890 CAM(core.net): netwatch up Sep 4 21:57:37.907 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:37.924 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:37.941 CAM(core.net): netwatch down Sep 4 21:57:37.957 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (67 ms) Sep 4 21:57:38.010 CAM(cardclient.core): cc-loop Sep 4 21:57:38.024 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:38.041 CAM(core.net): netwatch up Sep 4 21:57:38.058 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:38.075 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:38.091 CAM(core.net): netwatch down Sep 4 21:57:38.108 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (67 ms) Sep 4 21:57:38.125 CAM(cardclient.core): cc-loop Sep 4 21:57:38.141 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:38.158 CAM(core.net): netwatch up Sep 4 21:57:38.175 CAM(core.net): connecting to 192.168.1.33:13000/tcp (192.168.1.33) Sep 4 21:57:38.192 CAM(general.error): socket: connect failed (late): Connection refused Sep 4 21:57:38.209 CAM(core.net): netwatch down Sep 4 21:57:38.225 CAM(cardclient.core): client Newcamd (192.168.1.33:13000) ECM failed (67 ms) Sep 4 21:57:38.329 CAM(cardclient.core): cc-loop Sep 4 21:57:38.342 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:38.435 CAM(cardclient.core): cc-loop Sep 4 21:57:38.451 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:38.557 CAM(cardclient.core): cc-loop Sep 4 21:57:38.568 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:38.663 CAM(cardclient.core): cc-loop Sep 4 21:57:38.676 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:38.936 CAM(irdeto.ecm): incomplete ECM structure Sep 4 21:57:40.380 CAM(irdeto.ecm): last message repeated 7 times Sep 4 21:57:40.401 CAM(cardclient.core): cc-loop Sep 4 21:57:40.426 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:40.456 CAM(cardclient.core): cc-loop Sep 4 21:57:40.477 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:40.608 CAM(cardclient.core): cc-loop Sep 4 21:57:40.627 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:40.628 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:40.677 CAM(core.pids): 0: pids typ5=00e4 typ5=00e3 typ5=00e2 typ5=00e0 typ5=00fa typ5=00d0 typ5=0022 typ5=0058 typ5=0205 Sep 4 21:57:40.702 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:40.727 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:40.752 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 typ5=00e4 typ5=00e5 Sep 4 21:57:40.778 CAM(core.pids): 0.1: pids after delete typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 typ5=00e4 Sep 4 21:57:40.803 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 typ5=00e4 Sep 4 21:57:40.828 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:40.853 CAM(core.pids): 0: pids typ5=00e3 typ5=00e2 typ5=00e0 typ5=00fa typ5=00d0 typ5=0022 typ5=0058 typ5=0205 Sep 4 21:57:40.878 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:40.903 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:40.928 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 typ5=00e4 Sep 4 21:57:40.953 CAM(core.pids): 0.1: pids after delete typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 Sep 4 21:57:40.978 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 Sep 4 21:57:41.004 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:41.029 CAM(core.pids): 0: pids typ5=00e2 typ5=00e0 typ5=00fa typ5=00d0 typ5=0022 typ5=0058 typ5=0205 Sep 4 21:57:41.054 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:41.079 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:41.104 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 typ5=00e3 Sep 4 21:57:41.129 CAM(core.pids): 0.1: pids after delete typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 Sep 4 21:57:41.154 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 Sep 4 21:57:41.179 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:41.204 CAM(core.pids): 0: pids typ5=00e0 typ5=00fa typ5=00d0 typ5=0022 typ5=0058 typ5=0205 Sep 4 21:57:41.229 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:41.256 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:41.281 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 typ5=00e2 Sep 4 21:57:41.306 CAM(core.pids): 0.1: pids after delete typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 Sep 4 21:57:41.331 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 Sep 4 21:57:41.348 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:41.365 CAM(core.pids): 0: pids typ5=00fa typ5=00d0 typ5=0022 typ5=0058 typ5=0205 Sep 4 21:57:41.382 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:41.398 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:41.415 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa typ5=00e0 Sep 4 21:57:41.432 CAM(core.pids): 0.1: pids after delete typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa Sep 4 21:57:41.449 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa Sep 4 21:57:41.466 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:41.482 CAM(core.pids): 0: pids typ5=00d0 typ5=0022 typ5=0058 typ5=0205 Sep 4 21:57:41.499 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:41.516 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:41.533 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 typ5=0022 typ5=00d0 typ5=00fa Sep 4 21:57:41.550 CAM(core.pids): 0.1: pids after delete typ5=0205 typ5=0058 typ5=0022 typ5=00d0 Sep 4 21:57:41.566 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 typ5=0022 typ5=00d0 Sep 4 21:57:40.699 CAM(cardclient.core): cc-loop Sep 4 21:57:41.600 CAM(cardclient.core): now trying client Newcamd (192.168.1.33:13000) Sep 4 21:57:41.617 CAM(core.ecm): 0.1: triggered SID 4011/4011 idx 1/1 mode 3/0 - Sep 4 21:57:41.583 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:41.650 CAM(core.pids): 0: pids typ5=0022 typ5=0058 typ5=0205 Sep 4 21:57:41.667 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:41.684 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:41.701 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 typ5=0022 typ5=00d0 Sep 4 21:57:41.717 CAM(core.pids): 0.1: pids after delete typ5=0205 typ5=0058 typ5=0022 Sep 4 21:57:41.734 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 typ5=0022 Sep 4 21:57:41.751 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:41.768 CAM(core.pids): 0: pids typ5=0058 typ5=0205 Sep 4 21:57:41.784 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:41.801 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:41.818 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 typ5=0022 Sep 4 21:57:41.835 CAM(core.pids): 0.1: pids after delete typ5=0205 typ5=0058 Sep 4 21:57:41.860 CAM(core.pids): 0.1: pids after add typ5=0205 typ5=0058 Sep 4 21:57:41.885 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=0) Sep 4 21:57:41.910 CAM(core.pids): 0: pids typ5=0205 Sep 4 21:57:41.935 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:41.960 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:41.985 CAM(core.pids): 0.1: pids on entry typ5=0205 typ5=0058 Sep 4 21:57:42.010 CAM(core.pids): 0.1: pids after delete typ5=0205 Sep 4 21:57:42.035 CAM(core.pids): 0.1: pids after add typ5=0205 Sep 4 21:57:42.061 CAM(core.pids): 0: update SID 4011 (zero=0 noshift=1) Sep 4 21:57:42.086 CAM(core.pids): 0: SID=4011 zero=0 | sid=1/4011/0 zero=-/-/- idle=-/-/- Sep 4 21:57:42.111 CAM(core.pids): 0: found handler for SID 4011 (0.1 idle=0 idx=1) Sep 4 21:57:42.136 CAM(core.pids): 0.1: pids on entry typ5=0205 Sep 4 21:57:42.161 CAM(core.pids): 0.1: pids after delete Sep 4 21:57:42.186 CAM(core.pids): 0.1: pids after add Sep 4 21:57:42.211 CAM(core.ecm): 0.1: is idle now Sep 4 21:57:42.236 CAM(core.ecm): 0.1: stop Sep 4 21:57:42.262 CAM(core.au): 0: stopping chain 0622 Sep 4 21:57:42.287 CAM(core.au): 0: stopping chain 0100 Sep 4 21:57:42.312 CSA: Got command(1): I idx: 0 pid: 0 Sep 4 21:57:42.322 CAM(core.ecm): 0.1: triggered SID 4011/-1 idx 1/1 mode 4/0 - Sep 4 21:57:42.705 CAM(core.au): 0: chain caid 0622 -> Irdeto(-10) [0136-82/ff/00] Sep 4 21:57:42.737 CAM(core.au): 0: chain caid 0100 -> Seca(-10) Cardclient(-15) [00b6-82/ff/00] [00b7-84/ff/00] [00b9-84/ff/00] [00ba-84/ff/00] [00bb-84/ff/00] Sep 4 21:57:42.762 CAM(core.au): 0: chain caid 0d02 -> none available Sep 4 21:58:11.493 CAM(core.auStats): EMM packet load average (1/4/10min) 75 18 7 pks/s Sep 4 22:03:00.462 CAM(core.pids): 0: removing 0.1 on cw index 1