keyfreaks
Hacker
- Registriert
- 19. Dezember 2012
- Beiträge
- 460
- Reaktionspunkte
- 3
- Punkte
- 18
AW: sky v13 dunkel. Karte gepairt?
in der log steht dauernd was von ecm aber nicht von emm?
in der log steht dauernd was von ecm aber nicht von emm?
-------------------------------------------------------------------------------
>> OSCam << cardserver log switched at Thu Aug 6 12:41:33 2015
-------------------------------------------------------------------------------
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 0 >> OSCam << cardserver log switched, version 1.20-unstable_svn, build r10660 (mipsel-unknown-linux-gnu)
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (ecm) [GET_CW] wait_time 0 caid 09C4 prov 000000 srvid 0086 rc 100 cacheex cl mode 0 ex1rdr 0
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2015/08/06 12:41:33 9DA9D8 c (ecm) request_cw stage=2 to reader Sky ecm hash=8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (work) start reader thread action 5
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_request] reader Sky --> SENT 1
2015/08/06 12:41:33 9A66D8 r (work) data from add_job action=5 client r Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) Client dvbapi ECM dump 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:33 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:33 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:33 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:33 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:33 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:33 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:33 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:33 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:33 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:33 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:33 9A66D8 r (reader) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [reader_get_ecm] ecm already sent to reader Sky (NOK)
2015/08/06 12:41:33 9A66D8 r (ecm) {client dvbapi, caid 09C4, srvid 0086} [write_ecm_answer] cyclecheck passed! Reader: Sky rc: 4
2015/08/06 12:41:33 9A66D8 r (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_answer] reader Sky rc 4, ecm time 4 ms (12 ms)
2015/08/06 12:41:33 9A66D8 r (work) start client thread action 32
2015/08/06 12:41:33 9DA9D8 c (work) data from add_job action=32 client c dvbapi
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [send_dcw] rc 4 from reader Sky
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 request controlword for ecm 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (ecm) Client dvbapi csphash 61DDB176 cw 00000000000000000000000000000000 rc 4 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 no controlword received for PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 CHID 11F9 VPID 00FF
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 not interested in response ecmhash 8BEA5E6367578D0BF8B65957253828A3 (delivered cw is empty!)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 stop Filter 1 (fd: 11 api: 0, caid: 09C4, provid: 000000, ecmpid: 1B83)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 21 ms inactivity
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 32
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 0 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:33 9DA9D8 c (ecm) dvbapi (09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3): not found (15 ms) by Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) cw:
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83 (found caid/provid in cache - weight: 1)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83:0000 (localrdr: Sky weight: 7)
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Sky
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 0 1833:000000:1883:10000 (no matching reader)
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Sky
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 2 098C:000000:1A83:10000 (no matching reader)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 sorting the ecmpids took 9 ms
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 restarting decodingrequests after 10 ms with 1 enabled and 2 disabled ecmpids!
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 ANY CHID PMTPID 0060 VPID 00FF
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 try to start new filter for caid: 09C4, provid: 000000, pid: 1B83
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Open device /dev/dvb/adapter0/demux3 (fd 11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 09C4 provid 000000 pid 1B83)
2015/08/06 12:41:33 0 (work) start client thread action 31
2015/08/06 12:41:33 9DA9D8 c (work) data from add_job action=31 client c dvbapi
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 1 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:33 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 450 ms inactivity
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 3
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Readed:
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:33 9DA9D8 c (dvbapi) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 53 12 E2
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 fetched ECM data (ecmlength = 0B3)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 09C4 PROVID 000000 ECMPID 1B83 FAKECHID 11F9 (unique part in ecm)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 set ecmtable to EVEN (CAID 09C4 PROVID 000000 FD 11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 11
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 get controlword!
2015/08/06 12:41:33 9DA9D8 c (ecm) get cw for ecm:
2015/08/06 12:41:33 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:33 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:33 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:33 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:33 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:33 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:33 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:33 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:33 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:33 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:33 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] NEW REQUEST!
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (ecm) [GET_CW] wait_time 0 caid 09C4 prov 000000 srvid 0086 rc 100 cacheex cl mode 0 ex1rdr 0
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2015/08/06 12:41:33 9DA9D8 c (ecm) request_cw stage=2 to reader Sky ecm hash=8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (work) start reader thread action 5
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_request] reader Sky --> SENT 1
2015/08/06 12:41:33 9A66D8 r (work) data from add_job action=5 client r Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) Client dvbapi ECM dump 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:33 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:33 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:33 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:33 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:33 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:33 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:33 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:33 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:33 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:33 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:33 9A66D8 r (reader) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [reader_get_ecm] ecm already sent to reader Sky (NOK)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 request controlword for ecm 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9A66D8 r (ecm) {client dvbapi, caid 09C4, srvid 0086} [write_ecm_answer] cyclecheck passed! Reader: Sky rc: 4
2015/08/06 12:41:33 9A66D8 r (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_answer] reader Sky rc 4, ecm time 4 ms (10 ms)
2015/08/06 12:41:33 9A66D8 r (work) start client thread action 32
2015/08/06 12:41:33 9DA9D8 c (work) data from add_job action=32 client c dvbapi
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [send_dcw] rc 4 from reader Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) Client dvbapi csphash 61DDB176 cw 00000000000000000000000000000000 rc 4 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 no controlword received for PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 CHID 11F9 VPID 00FF
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 not interested in response ecmhash 8BEA5E6367578D0BF8B65957253828A3 (delivered cw is empty!)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 stop Filter 1 (fd: 11 api: 0, caid: 09C4, provid: 000000, ecmpid: 1B83)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 17 ms inactivity
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 32
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 0 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83 (found caid/provid in cache - weight: 1)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83:0000 (localrdr: Sky weight: 7)
2015/08/06 12:41:33 9DA9D8 c (ecm) dvbapi (09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3): not found (13 ms) by Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) cw:
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Sky
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 0 1833:000000:1883:10000 (no matching reader)
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Sky
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 2 098C:000000:1A83:10000 (no matching reader)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 sorting the ecmpids took 10 ms
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 restarting decodingrequests after 10 ms with 1 enabled and 2 disabled ecmpids!
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 ANY CHID PMTPID 0060 VPID 00FF
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 try to start new filter for caid: 09C4, provid: 000000, pid: 1B83
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Open device /dev/dvb/adapter0/demux3 (fd 11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 09C4 provid 000000 pid 1B83)
2015/08/06 12:41:33 0 (work) start client thread action 31
2015/08/06 12:41:33 9DA9D8 c (work) data from add_job action=31 client c dvbapi
2015/08/06 12:41:33 0 (work) start reader thread action 13
2015/08/06 12:41:33 0 (-) -- Skipped 1 duplicated log lines --
2015/08/06 12:41:33 0 (work) add reader job action 13 queue length 1 Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 0 (work) add reader job action 13 queue length 1 Dorcel_&_Hustler
2015/08/06 12:41:33 0 (work) add reader job action 13 queue length 1 austria_sat
2015/08/06 12:41:33 93E140 r (work) data from add_job action=13 client r HD+_in_7020hd
2015/08/06 12:41:33 9A66D8 r (work) data from add_job action=13 client r Sky
2015/08/06 12:41:34 9A66D8 r (reader) Sky [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/08/06 12:41:34 93DC08 c (work) [OSCAM-WORK] new event 1 occurred on fd 17 after 6990 ms inactivity
2015/08/06 12:41:34 93DC08 c (cccam) cccam(s) Enzinger_Dm500: parse_msg=1
2015/08/06 12:41:34 93DC08 c (cccam) cccam(s) Enzinger_Dm500: ECM request from client: caid 1830 srvid 1581(146) prid 000000
2015/08/06 12:41:34 93DC08 c (ecm) get cw for ecm:
2015/08/06 12:41:34 93DC08 c (ecm) 81 30 8F 07 8D 64 6C 21 F6 CD 81 79 9F 88 C1 47
2015/08/06 12:41:34 93DC08 c (ecm) 5A 95 59 C2 6C FF 2B BC 02 5B 5F 53 43 EE E5 2C
2015/08/06 12:41:34 93DC08 c (ecm) 9E C3 E3 C9 73 C7 57 8D B9 B8 51 FD C3 2F A7 BA
2015/08/06 12:41:34 93DC08 c (ecm) 29 08 B9 91 9B 30 C8 F3 44 C0 70 11 6D 18 FB F9
2015/08/06 12:41:34 93DC08 c (ecm) 6B 3C 70 DB 36 55 0A 18 B3 6F 7B 47 48 DB 8C 4A
2015/08/06 12:41:34 93DC08 c (ecm) 24 C5 10 FB B3 83 01 26 74 15 77 08 DF 2A 7E CF
2015/08/06 12:41:34 93DC08 c (ecm) 4E 86 44 65 FE 0C C7 91 C1 5A 90 26 56 51 1D B3
2015/08/06 12:41:34 93DC08 c (ecm) 67 F0 0A 66 B1 A1 1C CE 7F C5 DD 2E 17 08 34 86
2015/08/06 12:41:34 93DC08 c (ecm) E9 F9 2F 15 A6 B8 CC BE B2 D2 33 46 F9 D9 46 29
2015/08/06 12:41:34 93DC08 c (ecm) B0 44
2015/08/06 12:41:34 93DC08 c (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [get_cw] NEW REQUEST!
2015/08/06 12:41:34 93DC08 c (chk) caid 1830 not found in caidlist reader Sky
2015/08/06 12:41:34 93DC08 c (chk) caid 1830 not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 93DC08 c (ecm) [GET_CW] wait_time 0 caid 1830 prov 000000 srvid 1581 rc 100 cacheex cl mode 0 ex1rdr 0
2015/08/06 12:41:34 93DC08 c (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2015/08/06 12:41:34 93DC08 c (ecm) request_cw stage=2 to reader HD+_in_7020hd ecm hash=6D203BB9E1B4B0EAB99312931995E57D
2015/08/06 12:41:34 93DC08 c (work) start reader thread action 5
2015/08/06 12:41:34 93DC08 c (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [write_ecm_request] reader HD+_in_7020hd --> SENT 1
2015/08/06 12:41:34 93DC08 c (ecm) Client Enzinger_Dm500 ECM dump 1830&000000/0000/1581/92:6D203BB9E1B4B0EAB99312931995E57D
2015/08/06 12:41:34 93DC08 c (ecm) 81 30 8F 07 8D 64 6C 21 F6 CD 81 79 9F 88 C1 47
2015/08/06 12:41:34 93DC08 c (ecm) 5A 95 59 C2 6C FF 2B BC 02 5B 5F 53 43 EE E5 2C
2015/08/06 12:41:34 93DC08 c (ecm) 9E C3 E3 C9 73 C7 57 8D B9 B8 51 FD C3 2F A7 BA
2015/08/06 12:41:34 93DC08 c (ecm) 29 08 B9 91 9B 30 C8 F3 44 C0 70 11 6D 18 FB F9
2015/08/06 12:41:34 93DC08 c (ecm) 6B 3C 70 DB 36 55 0A 18 B3 6F 7B 47 48 DB 8C 4A
2015/08/06 12:41:34 93DC08 c (ecm) 24 C5 10 FB B3 83 01 26 74 15 77 08 DF 2A 7E CF
2015/08/06 12:41:34 93DC08 c (ecm) 4E 86 44 65 FE 0C C7 91 C1 5A 90 26 56 51 1D B3
2015/08/06 12:41:34 93DC08 c (ecm) 67 F0 0A 66 B1 A1 1C CE 7F C5 DD 2E 17 08 34 86
2015/08/06 12:41:34 93DC08 c (ecm) E9 F9 2F 15 A6 B8 CC BE B2 D2 33 46 F9 D9 46 29
2015/08/06 12:41:34 93DC08 c (ecm) B0 44
2015/08/06 12:41:34 93E140 r (work) data from add_job action=5 client r HD+_in_7020hd
2015/08/06 12:41:34 93E140 r ecm:
2015/08/06 12:41:34 93E140 r 81 30 8F 07 8D 64 6C 21 F6 CD 81 79 9F 88 C1 47
2015/08/06 12:41:34 93E140 r 5A 95 59 C2 6C FF 2B BC 02 5B 5F 53 43 EE E5 2C
2015/08/06 12:41:34 93E140 r 9E C3 E3 C9 73 C7 57 8D B9 B8 51 FD C3 2F A7 BA
2015/08/06 12:41:34 93E140 r 29 08 B9 91 9B 30 C8 F3 44 C0 70 11 6D 18 FB F9
2015/08/06 12:41:34 93E140 r 6B 3C 70 DB 36 55 0A 18 B3 6F 7B 47 48 DB 8C 4A
2015/08/06 12:41:34 93E140 r 24 C5 10 FB B3 83 01 26 74 15 77 08 DF 2A 7E CF
2015/08/06 12:41:34 93E140 r 4E 86 44 65 FE 0C C7 91 C1 5A 90 26 56 51 1D B3
2015/08/06 12:41:34 93E140 r 67 F0 0A 66 B1 A1 1C CE 7F C5 DD 2E 17 08 34 86
2015/08/06 12:41:34 93E140 r E9 F9 2F 15 A6 B8 CC BE B2 D2 33 46 F9 D9 46 29
2015/08/06 12:41:34 93E140 r B0 44
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] write to cardreader
2015/08/06 12:41:34 93E140 r (reader) A0 CA 00 00 8F 07 8D 64 6C 21 F6 CD 81 79 9F 88
2015/08/06 12:41:34 93E140 r (reader) C1 47 5A 95 59 C2 6C FF 2B BC 02 5B 5F 53 43 EE
2015/08/06 12:41:34 93E140 r (reader) E5 2C 9E C3 E3 C9 73 C7 57 8D B9 B8 51 FD C3 2F
2015/08/06 12:41:34 93E140 r (reader) A7 BA 29 08 B9 91 9B 30 C8 F3 44 C0 70 11 6D 18
2015/08/06 12:41:34 93E140 r (reader) FB F9 6B 3C 70 DB 36 55 0A 18 B3 6F 7B 47 48 DB
2015/08/06 12:41:34 93E140 r (reader) 8C 4A 24 C5 10 FB B3 83 01 26 74 15 77 08 DF 2A
2015/08/06 12:41:34 93E140 r (reader) 7E CF 4E 86 44 65 FE 0C C7 91 C1 5A 90 26 56 51
2015/08/06 12:41:34 93E140 r (reader) 1D B3 67 F0 0A 66 B1 A1 1C CE 7F C5 DD 2E 17 08
2015/08/06 12:41:34 93E140 r (reader) 34 86 E9 F9 2F 15 A6 B8 CC BE B2 D2 33 46 F9 D9
2015/08/06 12:41:34 93E140 r (reader) 46 29 B0 44 02
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 139 ETU (823 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit size 153 bytes, delay 0 us, timeout=823 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit:
2015/08/06 12:41:34 93E140 r (reader) 00 40 95 A0 CA 00 00 8F 07 8D 64 6C 21 F6 CD 81
2015/08/06 12:41:34 93E140 r (reader) 79 9F 88 C1 47 5A 95 59 C2 6C FF 2B BC 02 5B 5F
2015/08/06 12:41:34 93E140 r (reader) 53 43 EE E5 2C 9E C3 E3 C9 73 C7 57 8D B9 B8 51
2015/08/06 12:41:34 93E140 r (reader) FD C3 2F A7 BA 29 08 B9 91 9B 30 C8 F3 44 C0 70
2015/08/06 12:41:34 93E140 r (reader) 11 6D 18 FB F9 6B 3C 70 DB 36 55 0A 18 B3 6F 7B
2015/08/06 12:41:34 93E140 r (reader) 47 48 DB 8C 4A 24 C5 10 FB B3 83 01 26 74 15 77
2015/08/06 12:41:34 93E140 r (reader) 08 DF 2A 7E CF 4E 86 44 65 FE 0C C7 91 C1 5A 90
2015/08/06 12:41:34 93E140 r (reader) 26 56 51 1D B3 67 F0 0A 66 B1 A1 1C CE 7F C5 DD
2015/08/06 12:41:34 93E140 r (reader) 2E 17 08 34 86 E9 F9 2F 15 A6 B8 CC BE B2 D2 33
2015/08/06 12:41:34 93E140 r (reader) 46 F9 D9 46 29 B0 44 02 6F
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Write timeout 823 us, write delay 0 us, to send 153 char(s), chunksize 153 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Sending:
2015/08/06 12:41:34 93E140 r (reader) 00 40 95 A0 CA 00 00 8F 07 8D 64 6C 21 F6 CD 81
2015/08/06 12:41:34 93E140 r (reader) 79 9F 88 C1 47 5A 95 59 C2 6C FF 2B BC 02 5B 5F
2015/08/06 12:41:34 93E140 r (reader) 53 43 EE E5 2C 9E C3 E3 C9 73 C7 57 8D B9 B8 51
2015/08/06 12:41:34 93E140 r (reader) FD C3 2F A7 BA 29 08 B9 91 9B 30 C8 F3 44 C0 70
2015/08/06 12:41:34 93E140 r (reader) 11 6D 18 FB F9 6B 3C 70 DB 36 55 0A 18 B3 6F 7B
2015/08/06 12:41:34 93E140 r (reader) 47 48 DB 8C 4A 24 C5 10 FB B3 83 01 26 74 15 77
2015/08/06 12:41:34 93E140 r (reader) 08 DF 2A 7E CF 4E 86 44 65 FE 0C C7 91 C1 5A 90
2015/08/06 12:41:34 93E140 r (reader) 26 56 51 1D B3 67 F0 0A 66 B1 A1 1C CE 7F C5 DD
2015/08/06 12:41:34 93E140 r (reader) 2E 17 08 34 86 E9 F9 2F 15 A6 B8 CC BE B2 D2 33
2015/08/06 12:41:34 93E140 r (reader) 46 F9 D9 46 29 B0 44 02 6F
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Sending block I(1,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 178571 ETU (1058198 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 0 (work) start client thread action 31
2015/08/06 12:41:34 9DA9D8 c (work) data from add_job action=31 client c dvbapi
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 40 04 87
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 90 00 53
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Protocol: Received block I(1,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Answer from cardreader:
2015/08/06 12:41:34 93E140 r (reader) 87 00 90 00
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] write to cardreader
2015/08/06 12:41:34 93E140 r (reader) A0 CA 00 00 02 C0 00 06
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 139 ETU (823 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit size 12 bytes, delay 0 us, timeout=823 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 A0 CA 00 00 02 C0 00 06 A6
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Write timeout 823 us, write delay 0 us, to send 12 char(s), chunksize 12 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Sending:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 A0 CA 00 00 02 C0 00 06 A6
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Sending block I(0,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 178571 ETU (1058198 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 B0
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 8 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 04 00 11 04 26 90 00 1F
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Protocol: Received block I(0,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Answer from cardreader:
2015/08/06 12:41:34 93E140 r (reader) B0 04 00 11 04 26 90 00
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Camstate: 11 04 26
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] write to cardreader
2015/08/06 12:41:34 93E140 r (reader) A0 CA 00 00 02 1C 00 36
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 139 ETU (823 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit size 12 bytes, delay 0 us, timeout=823 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit:
2015/08/06 12:41:34 93E140 r (reader) 00 40 08 A0 CA 00 00 02 1C 00 36 0A
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Write timeout 823 us, write delay 0 us, to send 12 char(s), chunksize 12 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Sending:
2015/08/06 12:41:34 93E140 r (reader) 00 40 08 A0 CA 00 00 02 1C 00 36 0A
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Sending block I(1,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 178571 ETU (1058198 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 40 38 9C
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 56 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 56 char(s), chunksize 56 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 34 00 08 E6 57 F5 2D D0 64 B8 88 54 F4 E7 35 62
2015/08/06 12:41:34 93E140 r (reader) B4 13 7D 51 2A D9 E5 06 79 85 6A 00 08 6E 38 87
2015/08/06 12:41:34 93E140 r (reader) 4D 1D 59 70 83 51 27 6A 19 FB 2B F5 40 EE 92 B9
2015/08/06 12:41:34 93E140 r (reader) A6 6F 8F 4A 2D 90 00 1F
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Protocol: Received block I(1,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Answer from cardreader:
2015/08/06 12:41:34 93E140 r (reader) 9C 34 00 08 E6 57 F5 2D D0 64 B8 88 54 F4 E7 35
2015/08/06 12:41:34 93E140 r (reader) 62 B4 13 7D 51 2A D9 E5 06 79 85 6A 00 08 6E 38
2015/08/06 12:41:34 93E140 r (reader) 87 4D 1D 59 70 83 51 27 6A 19 FB 2B F5 40 EE 92
2015/08/06 12:41:34 93E140 r (reader) B9 A6 6F 8F 4A 2D 90 00
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] swap cws
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_ecm: after csystem->do_ecm rc=1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_ecm: ret rc=1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2015/08/06 12:41:34 93E140 r (ecm) {client Enzinger_Dm500, caid 1830, srvid 1581} [write_ecm_answer] cyclecheck passed! Reader: HD+_in_7020hd rc: 0
2015/08/06 12:41:34 93E140 r (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [write_ecm_answer] reader HD+_in_7020hd rc 0, ecm time 321 ms (323 ms)
2015/08/06 12:41:34 93DC08 c (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [send_dcw] rc 0 from reader HD+_in_7020hd
2015/08/06 12:41:34 93DC08 c (ecm) Client Enzinger_Dm500 csphash 3BCA9CCC cw C52D4537BE86478B5516D944CC1611F3 rc 0 1830&000000/0000/1581/92:6D203BB9E1B4B0EAB99312931995E57D
2015/08/06 12:41:34 93DC08 c (ecm) Enzinger_Dm500 (1830&000000/0000/1581/92:6D203BB9E1B4B0EAB99312931995E57D): found (325 ms) by HD+_in_7020hd
2015/08/06 12:41:34 93DC08 c (ecm) cw:
2015/08/06 12:41:34 93DC08 c (ecm) C5 2D 45 37 BE 86 47 8B 55 16 D9 44 CC 16 11 F3
2015/08/06 12:41:34 93E140 r (work) add client job action 32 queue length 0 Enzinger_Dm500
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] TRACE: ecm answer for ecm hash 6D203BB9E1B4B0EAB99312931995E57D rc=0
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] ecm hash: 6D203BB9E1B4B0EAB99312931995E57D real time: 317 ms
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] write to cardreader
2015/08/06 12:41:34 93E140 r (reader) A0 CA 00 00 02 C0 00 06
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 139 ETU (823 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit size 12 bytes, delay 0 us, timeout=823 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 A0 CA 00 00 02 C0 00 06 A6
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Write timeout 823 us, write delay 0 us, to send 12 char(s), chunksize 12 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Sending:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 A0 CA 00 00 02 C0 00 06 A6
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Sending block I(0,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 178571 ETU (1058198 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 B0
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 8 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 04 00 11 04 20 90 00 19
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Protocol: Received block I(0,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Answer from cardreader:
2015/08/06 12:41:34 93E140 r (reader) B0 04 00 11 04 20 90 00
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Camstate: 11 04 20
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 1 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:34 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 1168 ms inactivity
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 3
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Readed:
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:34 9DA9D8 c (dvbapi) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 53 12 E2
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 fetched ECM data (ecmlength = 0B3)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 09C4 PROVID 000000 ECMPID 1B83 FAKECHID 11F9 (unique part in ecm)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 set ecmtable to EVEN (CAID 09C4 PROVID 000000 FD 11)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 11
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 get controlword!
2015/08/06 12:41:34 9DA9D8 c (ecm) get cw for ecm:
2015/08/06 12:41:34 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:34 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:34 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:34 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:34 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:34 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:34 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:34 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:34 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:34 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:34 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:34 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:34 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] NEW REQUEST!
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:34 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:34 9DA9D8 c (ecm) [GET_CW] wait_time 0 caid 09C4 prov 000000 srvid 0086 rc 100 cacheex cl mode 0 ex1rdr 0
2015/08/06 12:41:34 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2015/08/06 12:41:34 9DA9D8 c (ecm) request_cw stage=2 to reader Sky ecm hash=8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:34 9DA9D8 c (work) start reader thread action 5
2015/08/06 12:41:34 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_request] reader Sky --> SENT 1
2015/08/06 12:41:34 9DA9D8 c (ecm) Client dvbapi ECM dump 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:34 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:34 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:34 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:34 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:34 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:34 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:34 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:34 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:34 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:34 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:34 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:34 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 request controlword for ecm 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:34 9A66D8 r (work) data from add_job action=5 client r Sky
2015/08/06 12:41:34 9A66D8 r (reader) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [reader_get_ecm] ecm already sent to reader Sky (NOK)
2015/08/06 12:41:34 9A66D8 r (ecm) {client dvbapi, caid 09C4, srvid 0086} [write_ecm_answer] cyclecheck passed! Reader: Sky rc: 4
2015/08/06 12:41:34 9A66D8 r (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_answer] reader Sky rc 4, ecm time 4 ms (10 ms)
2015/08/06 12:41:34 9A66D8 r (work) start client thread action 32
2015/08/06 12:41:34 9DA9D8 c (work) data from add_job action=32 client c dvbapi
2015/08/06 12:41:34 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [send_dcw] rc 4 from reader Sky
2015/08/06 12:41:34 9DA9D8 c (ecm) Client dvbapi csphash 61DDB176 cw 00000000000000000000000000000000 rc 4 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 no controlword received for PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 CHID 11F9 VPID 00FF
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 not interested in response ecmhash 8BEA5E6367578D0BF8B65957253828A3 (delivered cw is empty!)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 stop Filter 1 (fd: 11 api: 0, caid: 09C4, provid: 000000, ecmpid: 1B83)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:11)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 18 ms inactivity
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 32
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 0 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:34 9DA9D8 c (ecm) dvbapi (09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3): not found (13 ms) by Sky
2015/08/06 12:41:34 9DA9D8 c (ecm) cw:
2015/08/06 12:41:34 9DA9D8 c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83 (found caid/provid in cache - weight: 1)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83:0000 (localrdr: Sky weight: 7)
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader Sky
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:34 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 0 1833:000000:1883:10000 (no matching reader)
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:34 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader Sky
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:34 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 2 098C:000000:1A83:10000 (no matching reader)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 sorting the ecmpids took 10 ms
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 restarting decodingrequests after 11 ms with 1 enabled and 2 disabled ecmpids!
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 ANY CHID PMTPID 0060 VPID 00FF
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 try to start new filter for caid: 09C4, provid: 000000, pid: 1B83
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Open device /dev/dvb/adapter0/demux3 (fd 11)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 09C4 provid 000000 pid 1B83)
2015/08/06 12:41:34 0 (work) start reader thread action 13
2015/08/06 12:41:34 0 (-) -- Skipped 1 duplicated log lines --
2015/08/06 12:41:34 0 (work) add reader job action 13 queue length 1 Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 0 (work) add reader job action 13 queue length 1 Dorcel_&_Hustler
2015/08/06 12:41:34 0 (work) add reader job action 13 queue length 1 austria_sat
2015/08/06 12:41:34 9A66D8 r (work) data from add_job action=13 client r Sky
2015/08/06 12:41:34 93E140 r (work) data from add_job action=13 client r HD+_in_7020hd
>> OSCam << cardserver log switched at Thu Aug 6 12:41:33 2015
-------------------------------------------------------------------------------
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 0 >> OSCam << cardserver log switched, version 1.20-unstable_svn, build r10660 (mipsel-unknown-linux-gnu)
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (ecm) [GET_CW] wait_time 0 caid 09C4 prov 000000 srvid 0086 rc 100 cacheex cl mode 0 ex1rdr 0
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2015/08/06 12:41:33 9DA9D8 c (ecm) request_cw stage=2 to reader Sky ecm hash=8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (work) start reader thread action 5
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_request] reader Sky --> SENT 1
2015/08/06 12:41:33 9A66D8 r (work) data from add_job action=5 client r Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) Client dvbapi ECM dump 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:33 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:33 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:33 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:33 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:33 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:33 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:33 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:33 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:33 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:33 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:33 9A66D8 r (reader) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [reader_get_ecm] ecm already sent to reader Sky (NOK)
2015/08/06 12:41:33 9A66D8 r (ecm) {client dvbapi, caid 09C4, srvid 0086} [write_ecm_answer] cyclecheck passed! Reader: Sky rc: 4
2015/08/06 12:41:33 9A66D8 r (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_answer] reader Sky rc 4, ecm time 4 ms (12 ms)
2015/08/06 12:41:33 9A66D8 r (work) start client thread action 32
2015/08/06 12:41:33 9DA9D8 c (work) data from add_job action=32 client c dvbapi
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [send_dcw] rc 4 from reader Sky
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 request controlword for ecm 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (ecm) Client dvbapi csphash 61DDB176 cw 00000000000000000000000000000000 rc 4 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 no controlword received for PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 CHID 11F9 VPID 00FF
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 not interested in response ecmhash 8BEA5E6367578D0BF8B65957253828A3 (delivered cw is empty!)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 stop Filter 1 (fd: 11 api: 0, caid: 09C4, provid: 000000, ecmpid: 1B83)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 21 ms inactivity
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 32
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 0 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:33 9DA9D8 c (ecm) dvbapi (09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3): not found (15 ms) by Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) cw:
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83 (found caid/provid in cache - weight: 1)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83:0000 (localrdr: Sky weight: 7)
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Sky
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 0 1833:000000:1883:10000 (no matching reader)
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Sky
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 2 098C:000000:1A83:10000 (no matching reader)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 sorting the ecmpids took 9 ms
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 restarting decodingrequests after 10 ms with 1 enabled and 2 disabled ecmpids!
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 ANY CHID PMTPID 0060 VPID 00FF
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 try to start new filter for caid: 09C4, provid: 000000, pid: 1B83
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Open device /dev/dvb/adapter0/demux3 (fd 11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 09C4 provid 000000 pid 1B83)
2015/08/06 12:41:33 0 (work) start client thread action 31
2015/08/06 12:41:33 9DA9D8 c (work) data from add_job action=31 client c dvbapi
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 1 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:33 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 450 ms inactivity
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 3
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Readed:
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:33 9DA9D8 c (dvbapi) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:33 9DA9D8 c (dvbapi) 53 12 E2
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 fetched ECM data (ecmlength = 0B3)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 09C4 PROVID 000000 ECMPID 1B83 FAKECHID 11F9 (unique part in ecm)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 set ecmtable to EVEN (CAID 09C4 PROVID 000000 FD 11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 11
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 get controlword!
2015/08/06 12:41:33 9DA9D8 c (ecm) get cw for ecm:
2015/08/06 12:41:33 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:33 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:33 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:33 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:33 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:33 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:33 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:33 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:33 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:33 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:33 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] NEW REQUEST!
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (ecm) [GET_CW] wait_time 0 caid 09C4 prov 000000 srvid 0086 rc 100 cacheex cl mode 0 ex1rdr 0
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2015/08/06 12:41:33 9DA9D8 c (ecm) request_cw stage=2 to reader Sky ecm hash=8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (work) start reader thread action 5
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_request] reader Sky --> SENT 1
2015/08/06 12:41:33 9A66D8 r (work) data from add_job action=5 client r Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) Client dvbapi ECM dump 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:33 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:33 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:33 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:33 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:33 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:33 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:33 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:33 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:33 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:33 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:33 9A66D8 r (reader) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [reader_get_ecm] ecm already sent to reader Sky (NOK)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 request controlword for ecm 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9A66D8 r (ecm) {client dvbapi, caid 09C4, srvid 0086} [write_ecm_answer] cyclecheck passed! Reader: Sky rc: 4
2015/08/06 12:41:33 9A66D8 r (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_answer] reader Sky rc 4, ecm time 4 ms (10 ms)
2015/08/06 12:41:33 9A66D8 r (work) start client thread action 32
2015/08/06 12:41:33 9DA9D8 c (work) data from add_job action=32 client c dvbapi
2015/08/06 12:41:33 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [send_dcw] rc 4 from reader Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) Client dvbapi csphash 61DDB176 cw 00000000000000000000000000000000 rc 4 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 no controlword received for PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 CHID 11F9 VPID 00FF
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 not interested in response ecmhash 8BEA5E6367578D0BF8B65957253828A3 (delivered cw is empty!)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 stop Filter 1 (fd: 11 api: 0, caid: 09C4, provid: 000000, ecmpid: 1B83)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 17 ms inactivity
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 32
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 0 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83 (found caid/provid in cache - weight: 1)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83:0000 (localrdr: Sky weight: 7)
2015/08/06 12:41:33 9DA9D8 c (ecm) dvbapi (09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3): not found (13 ms) by Sky
2015/08/06 12:41:33 9DA9D8 c (ecm) cw:
2015/08/06 12:41:33 9DA9D8 c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Sky
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 0 1833:000000:1883:10000 (no matching reader)
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Sky
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader ORF_in_600
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:33 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 2 098C:000000:1A83:10000 (no matching reader)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 sorting the ecmpids took 10 ms
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 restarting decodingrequests after 10 ms with 1 enabled and 2 disabled ecmpids!
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 ANY CHID PMTPID 0060 VPID 00FF
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 try to start new filter for caid: 09C4, provid: 000000, pid: 1B83
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Open device /dev/dvb/adapter0/demux3 (fd 11)
2015/08/06 12:41:33 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 09C4 provid 000000 pid 1B83)
2015/08/06 12:41:33 0 (work) start client thread action 31
2015/08/06 12:41:33 9DA9D8 c (work) data from add_job action=31 client c dvbapi
2015/08/06 12:41:33 0 (work) start reader thread action 13
2015/08/06 12:41:33 0 (-) -- Skipped 1 duplicated log lines --
2015/08/06 12:41:33 0 (work) add reader job action 13 queue length 1 Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:33 0 (work) add reader job action 13 queue length 1 Dorcel_&_Hustler
2015/08/06 12:41:33 0 (work) add reader job action 13 queue length 1 austria_sat
2015/08/06 12:41:33 93E140 r (work) data from add_job action=13 client r HD+_in_7020hd
2015/08/06 12:41:33 9A66D8 r (work) data from add_job action=13 client r Sky
2015/08/06 12:41:34 9A66D8 r (reader) Sky [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/08/06 12:41:34 93DC08 c (work) [OSCAM-WORK] new event 1 occurred on fd 17 after 6990 ms inactivity
2015/08/06 12:41:34 93DC08 c (cccam) cccam(s) Enzinger_Dm500: parse_msg=1
2015/08/06 12:41:34 93DC08 c (cccam) cccam(s) Enzinger_Dm500: ECM request from client: caid 1830 srvid 1581(146) prid 000000
2015/08/06 12:41:34 93DC08 c (ecm) get cw for ecm:
2015/08/06 12:41:34 93DC08 c (ecm) 81 30 8F 07 8D 64 6C 21 F6 CD 81 79 9F 88 C1 47
2015/08/06 12:41:34 93DC08 c (ecm) 5A 95 59 C2 6C FF 2B BC 02 5B 5F 53 43 EE E5 2C
2015/08/06 12:41:34 93DC08 c (ecm) 9E C3 E3 C9 73 C7 57 8D B9 B8 51 FD C3 2F A7 BA
2015/08/06 12:41:34 93DC08 c (ecm) 29 08 B9 91 9B 30 C8 F3 44 C0 70 11 6D 18 FB F9
2015/08/06 12:41:34 93DC08 c (ecm) 6B 3C 70 DB 36 55 0A 18 B3 6F 7B 47 48 DB 8C 4A
2015/08/06 12:41:34 93DC08 c (ecm) 24 C5 10 FB B3 83 01 26 74 15 77 08 DF 2A 7E CF
2015/08/06 12:41:34 93DC08 c (ecm) 4E 86 44 65 FE 0C C7 91 C1 5A 90 26 56 51 1D B3
2015/08/06 12:41:34 93DC08 c (ecm) 67 F0 0A 66 B1 A1 1C CE 7F C5 DD 2E 17 08 34 86
2015/08/06 12:41:34 93DC08 c (ecm) E9 F9 2F 15 A6 B8 CC BE B2 D2 33 46 F9 D9 46 29
2015/08/06 12:41:34 93DC08 c (ecm) B0 44
2015/08/06 12:41:34 93DC08 c (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [get_cw] NEW REQUEST!
2015/08/06 12:41:34 93DC08 c (chk) caid 1830 not found in caidlist reader Sky
2015/08/06 12:41:34 93DC08 c (chk) caid 1830 not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 93DC08 c (ecm) [GET_CW] wait_time 0 caid 1830 prov 000000 srvid 1581 rc 100 cacheex cl mode 0 ex1rdr 0
2015/08/06 12:41:34 93DC08 c (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2015/08/06 12:41:34 93DC08 c (ecm) request_cw stage=2 to reader HD+_in_7020hd ecm hash=6D203BB9E1B4B0EAB99312931995E57D
2015/08/06 12:41:34 93DC08 c (work) start reader thread action 5
2015/08/06 12:41:34 93DC08 c (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [write_ecm_request] reader HD+_in_7020hd --> SENT 1
2015/08/06 12:41:34 93DC08 c (ecm) Client Enzinger_Dm500 ECM dump 1830&000000/0000/1581/92:6D203BB9E1B4B0EAB99312931995E57D
2015/08/06 12:41:34 93DC08 c (ecm) 81 30 8F 07 8D 64 6C 21 F6 CD 81 79 9F 88 C1 47
2015/08/06 12:41:34 93DC08 c (ecm) 5A 95 59 C2 6C FF 2B BC 02 5B 5F 53 43 EE E5 2C
2015/08/06 12:41:34 93DC08 c (ecm) 9E C3 E3 C9 73 C7 57 8D B9 B8 51 FD C3 2F A7 BA
2015/08/06 12:41:34 93DC08 c (ecm) 29 08 B9 91 9B 30 C8 F3 44 C0 70 11 6D 18 FB F9
2015/08/06 12:41:34 93DC08 c (ecm) 6B 3C 70 DB 36 55 0A 18 B3 6F 7B 47 48 DB 8C 4A
2015/08/06 12:41:34 93DC08 c (ecm) 24 C5 10 FB B3 83 01 26 74 15 77 08 DF 2A 7E CF
2015/08/06 12:41:34 93DC08 c (ecm) 4E 86 44 65 FE 0C C7 91 C1 5A 90 26 56 51 1D B3
2015/08/06 12:41:34 93DC08 c (ecm) 67 F0 0A 66 B1 A1 1C CE 7F C5 DD 2E 17 08 34 86
2015/08/06 12:41:34 93DC08 c (ecm) E9 F9 2F 15 A6 B8 CC BE B2 D2 33 46 F9 D9 46 29
2015/08/06 12:41:34 93DC08 c (ecm) B0 44
2015/08/06 12:41:34 93E140 r (work) data from add_job action=5 client r HD+_in_7020hd
2015/08/06 12:41:34 93E140 r ecm:
2015/08/06 12:41:34 93E140 r 81 30 8F 07 8D 64 6C 21 F6 CD 81 79 9F 88 C1 47
2015/08/06 12:41:34 93E140 r 5A 95 59 C2 6C FF 2B BC 02 5B 5F 53 43 EE E5 2C
2015/08/06 12:41:34 93E140 r 9E C3 E3 C9 73 C7 57 8D B9 B8 51 FD C3 2F A7 BA
2015/08/06 12:41:34 93E140 r 29 08 B9 91 9B 30 C8 F3 44 C0 70 11 6D 18 FB F9
2015/08/06 12:41:34 93E140 r 6B 3C 70 DB 36 55 0A 18 B3 6F 7B 47 48 DB 8C 4A
2015/08/06 12:41:34 93E140 r 24 C5 10 FB B3 83 01 26 74 15 77 08 DF 2A 7E CF
2015/08/06 12:41:34 93E140 r 4E 86 44 65 FE 0C C7 91 C1 5A 90 26 56 51 1D B3
2015/08/06 12:41:34 93E140 r 67 F0 0A 66 B1 A1 1C CE 7F C5 DD 2E 17 08 34 86
2015/08/06 12:41:34 93E140 r E9 F9 2F 15 A6 B8 CC BE B2 D2 33 46 F9 D9 46 29
2015/08/06 12:41:34 93E140 r B0 44
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] write to cardreader
2015/08/06 12:41:34 93E140 r (reader) A0 CA 00 00 8F 07 8D 64 6C 21 F6 CD 81 79 9F 88
2015/08/06 12:41:34 93E140 r (reader) C1 47 5A 95 59 C2 6C FF 2B BC 02 5B 5F 53 43 EE
2015/08/06 12:41:34 93E140 r (reader) E5 2C 9E C3 E3 C9 73 C7 57 8D B9 B8 51 FD C3 2F
2015/08/06 12:41:34 93E140 r (reader) A7 BA 29 08 B9 91 9B 30 C8 F3 44 C0 70 11 6D 18
2015/08/06 12:41:34 93E140 r (reader) FB F9 6B 3C 70 DB 36 55 0A 18 B3 6F 7B 47 48 DB
2015/08/06 12:41:34 93E140 r (reader) 8C 4A 24 C5 10 FB B3 83 01 26 74 15 77 08 DF 2A
2015/08/06 12:41:34 93E140 r (reader) 7E CF 4E 86 44 65 FE 0C C7 91 C1 5A 90 26 56 51
2015/08/06 12:41:34 93E140 r (reader) 1D B3 67 F0 0A 66 B1 A1 1C CE 7F C5 DD 2E 17 08
2015/08/06 12:41:34 93E140 r (reader) 34 86 E9 F9 2F 15 A6 B8 CC BE B2 D2 33 46 F9 D9
2015/08/06 12:41:34 93E140 r (reader) 46 29 B0 44 02
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 139 ETU (823 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit size 153 bytes, delay 0 us, timeout=823 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit:
2015/08/06 12:41:34 93E140 r (reader) 00 40 95 A0 CA 00 00 8F 07 8D 64 6C 21 F6 CD 81
2015/08/06 12:41:34 93E140 r (reader) 79 9F 88 C1 47 5A 95 59 C2 6C FF 2B BC 02 5B 5F
2015/08/06 12:41:34 93E140 r (reader) 53 43 EE E5 2C 9E C3 E3 C9 73 C7 57 8D B9 B8 51
2015/08/06 12:41:34 93E140 r (reader) FD C3 2F A7 BA 29 08 B9 91 9B 30 C8 F3 44 C0 70
2015/08/06 12:41:34 93E140 r (reader) 11 6D 18 FB F9 6B 3C 70 DB 36 55 0A 18 B3 6F 7B
2015/08/06 12:41:34 93E140 r (reader) 47 48 DB 8C 4A 24 C5 10 FB B3 83 01 26 74 15 77
2015/08/06 12:41:34 93E140 r (reader) 08 DF 2A 7E CF 4E 86 44 65 FE 0C C7 91 C1 5A 90
2015/08/06 12:41:34 93E140 r (reader) 26 56 51 1D B3 67 F0 0A 66 B1 A1 1C CE 7F C5 DD
2015/08/06 12:41:34 93E140 r (reader) 2E 17 08 34 86 E9 F9 2F 15 A6 B8 CC BE B2 D2 33
2015/08/06 12:41:34 93E140 r (reader) 46 F9 D9 46 29 B0 44 02 6F
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Write timeout 823 us, write delay 0 us, to send 153 char(s), chunksize 153 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Sending:
2015/08/06 12:41:34 93E140 r (reader) 00 40 95 A0 CA 00 00 8F 07 8D 64 6C 21 F6 CD 81
2015/08/06 12:41:34 93E140 r (reader) 79 9F 88 C1 47 5A 95 59 C2 6C FF 2B BC 02 5B 5F
2015/08/06 12:41:34 93E140 r (reader) 53 43 EE E5 2C 9E C3 E3 C9 73 C7 57 8D B9 B8 51
2015/08/06 12:41:34 93E140 r (reader) FD C3 2F A7 BA 29 08 B9 91 9B 30 C8 F3 44 C0 70
2015/08/06 12:41:34 93E140 r (reader) 11 6D 18 FB F9 6B 3C 70 DB 36 55 0A 18 B3 6F 7B
2015/08/06 12:41:34 93E140 r (reader) 47 48 DB 8C 4A 24 C5 10 FB B3 83 01 26 74 15 77
2015/08/06 12:41:34 93E140 r (reader) 08 DF 2A 7E CF 4E 86 44 65 FE 0C C7 91 C1 5A 90
2015/08/06 12:41:34 93E140 r (reader) 26 56 51 1D B3 67 F0 0A 66 B1 A1 1C CE 7F C5 DD
2015/08/06 12:41:34 93E140 r (reader) 2E 17 08 34 86 E9 F9 2F 15 A6 B8 CC BE B2 D2 33
2015/08/06 12:41:34 93E140 r (reader) 46 F9 D9 46 29 B0 44 02 6F
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Sending block I(1,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 178571 ETU (1058198 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 0 (work) start client thread action 31
2015/08/06 12:41:34 9DA9D8 c (work) data from add_job action=31 client c dvbapi
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 40 04 87
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 90 00 53
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Protocol: Received block I(1,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Answer from cardreader:
2015/08/06 12:41:34 93E140 r (reader) 87 00 90 00
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] write to cardreader
2015/08/06 12:41:34 93E140 r (reader) A0 CA 00 00 02 C0 00 06
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 139 ETU (823 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit size 12 bytes, delay 0 us, timeout=823 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 A0 CA 00 00 02 C0 00 06 A6
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Write timeout 823 us, write delay 0 us, to send 12 char(s), chunksize 12 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Sending:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 A0 CA 00 00 02 C0 00 06 A6
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Sending block I(0,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 178571 ETU (1058198 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 B0
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 8 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 04 00 11 04 26 90 00 1F
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Protocol: Received block I(0,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Answer from cardreader:
2015/08/06 12:41:34 93E140 r (reader) B0 04 00 11 04 26 90 00
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Camstate: 11 04 26
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] write to cardreader
2015/08/06 12:41:34 93E140 r (reader) A0 CA 00 00 02 1C 00 36
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 139 ETU (823 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit size 12 bytes, delay 0 us, timeout=823 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit:
2015/08/06 12:41:34 93E140 r (reader) 00 40 08 A0 CA 00 00 02 1C 00 36 0A
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Write timeout 823 us, write delay 0 us, to send 12 char(s), chunksize 12 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Sending:
2015/08/06 12:41:34 93E140 r (reader) 00 40 08 A0 CA 00 00 02 1C 00 36 0A
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Sending block I(1,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 178571 ETU (1058198 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 40 38 9C
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 56 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 56 char(s), chunksize 56 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 34 00 08 E6 57 F5 2D D0 64 B8 88 54 F4 E7 35 62
2015/08/06 12:41:34 93E140 r (reader) B4 13 7D 51 2A D9 E5 06 79 85 6A 00 08 6E 38 87
2015/08/06 12:41:34 93E140 r (reader) 4D 1D 59 70 83 51 27 6A 19 FB 2B F5 40 EE 92 B9
2015/08/06 12:41:34 93E140 r (reader) A6 6F 8F 4A 2D 90 00 1F
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Protocol: Received block I(1,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Answer from cardreader:
2015/08/06 12:41:34 93E140 r (reader) 9C 34 00 08 E6 57 F5 2D D0 64 B8 88 54 F4 E7 35
2015/08/06 12:41:34 93E140 r (reader) 62 B4 13 7D 51 2A D9 E5 06 79 85 6A 00 08 6E 38
2015/08/06 12:41:34 93E140 r (reader) 87 4D 1D 59 70 83 51 27 6A 19 FB 2B F5 40 EE 92
2015/08/06 12:41:34 93E140 r (reader) B9 A6 6F 8F 4A 2D 90 00
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] swap cws
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_ecm: after csystem->do_ecm rc=1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_do_ecm: ret rc=1
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2015/08/06 12:41:34 93E140 r (ecm) {client Enzinger_Dm500, caid 1830, srvid 1581} [write_ecm_answer] cyclecheck passed! Reader: HD+_in_7020hd rc: 0
2015/08/06 12:41:34 93E140 r (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [write_ecm_answer] reader HD+_in_7020hd rc 0, ecm time 321 ms (323 ms)
2015/08/06 12:41:34 93DC08 c (ecm) {client Enzinger_Dm500, caid 1830, prid 000000, srvid 1581} [send_dcw] rc 0 from reader HD+_in_7020hd
2015/08/06 12:41:34 93DC08 c (ecm) Client Enzinger_Dm500 csphash 3BCA9CCC cw C52D4537BE86478B5516D944CC1611F3 rc 0 1830&000000/0000/1581/92:6D203BB9E1B4B0EAB99312931995E57D
2015/08/06 12:41:34 93DC08 c (ecm) Enzinger_Dm500 (1830&000000/0000/1581/92:6D203BB9E1B4B0EAB99312931995E57D): found (325 ms) by HD+_in_7020hd
2015/08/06 12:41:34 93DC08 c (ecm) cw:
2015/08/06 12:41:34 93DC08 c (ecm) C5 2D 45 37 BE 86 47 8B 55 16 D9 44 CC 16 11 F3
2015/08/06 12:41:34 93E140 r (work) add client job action 32 queue length 0 Enzinger_Dm500
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] TRACE: ecm answer for ecm hash 6D203BB9E1B4B0EAB99312931995E57D rc=0
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] ecm hash: 6D203BB9E1B4B0EAB99312931995E57D real time: 317 ms
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] write to cardreader
2015/08/06 12:41:34 93E140 r (reader) A0 CA 00 00 02 C0 00 06
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 139 ETU (823 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit size 12 bytes, delay 0 us, timeout=823 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 A0 CA 00 00 02 C0 00 06 A6
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Write timeout 823 us, write delay 0 us, to send 12 char(s), chunksize 12 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Sending:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 A0 CA 00 00 02 C0 00 06 A6
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Transmit succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Sending block I(0,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Setting timeout to 178571 ETU (1058198 us)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 4 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 4 char(s), chunksize 4 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 00 00 08 B0
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive size 8 bytes, delay 0 us, timeout=1058198 us
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Read timeout 1058198 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IO: Receiving:
2015/08/06 12:41:34 93E140 r (reader) 04 00 11 04 20 90 00 19
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Receive succesful
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] IFD: Protocol: Received block I(0,0)
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Answer from cardreader:
2015/08/06 12:41:34 93E140 r (reader) B0 04 00 11 04 20 90 00
2015/08/06 12:41:34 93E140 r (reader) HD+_in_7020hd [nagra] Camstate: 11 04 20
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 1 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:34 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 1168 ms inactivity
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 3
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Readed:
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:34 9DA9D8 c (dvbapi) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:34 9DA9D8 c (dvbapi) 53 12 E2
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 fetched ECM data (ecmlength = 0B3)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 09C4 PROVID 000000 ECMPID 1B83 FAKECHID 11F9 (unique part in ecm)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 set ecmtable to EVEN (CAID 09C4 PROVID 000000 FD 11)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 11
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 get controlword!
2015/08/06 12:41:34 9DA9D8 c (ecm) get cw for ecm:
2015/08/06 12:41:34 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:34 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:34 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:34 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:34 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:34 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:34 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:34 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:34 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:34 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:34 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:34 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:34 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] NEW REQUEST!
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:34 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:34 9DA9D8 c (ecm) [GET_CW] wait_time 0 caid 09C4 prov 000000 srvid 0086 rc 100 cacheex cl mode 0 ex1rdr 0
2015/08/06 12:41:34 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2015/08/06 12:41:34 9DA9D8 c (ecm) request_cw stage=2 to reader Sky ecm hash=8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:34 9DA9D8 c (work) start reader thread action 5
2015/08/06 12:41:34 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_request] reader Sky --> SENT 1
2015/08/06 12:41:34 9DA9D8 c (ecm) Client dvbapi ECM dump 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:34 9DA9D8 c (ecm) 81 70 B0 00 00 01 1D 8B 06 55 2B 11 F9 AA 55 05
2015/08/06 12:41:34 9DA9D8 c (ecm) 20 01 00 00 80 40 0D 69 7A EA B8 F8 5B 55 CC 03
2015/08/06 12:41:34 9DA9D8 c (ecm) 02 05 02 30 8E 7E 0A 8A 7A 48 3D 0D E9 34 C2 00
2015/08/06 12:41:34 9DA9D8 c (ecm) 00 90 80 C1 01 45 34 23 D8 C3 0C 71 49 B7 BD FA
2015/08/06 12:41:34 9DA9D8 c (ecm) BB 75 4B 59 79 62 95 76 70 7E A8 B3 10 64 CA EC
2015/08/06 12:41:34 9DA9D8 c (ecm) 85 00 63 B5 F8 CE F4 63 07 2C 31 58 AA 9F FD 71
2015/08/06 12:41:34 9DA9D8 c (ecm) 9B B2 66 C6 21 31 24 88 3A 38 E9 BE FA DD 1D CC
2015/08/06 12:41:34 9DA9D8 c (ecm) 89 BE 01 32 97 E6 3C B4 05 A3 61 20 B5 7C 73 CD
2015/08/06 12:41:34 9DA9D8 c (ecm) 1A D3 63 EE 06 7B DD 96 06 2B 1C 2D F9 3C 91 4D
2015/08/06 12:41:34 9DA9D8 c (ecm) 9C BC C4 12 87 A2 51 6E 17 6C A6 21 85 9E 51 84
2015/08/06 12:41:34 9DA9D8 c (ecm) 3F A0 26 A7 9B 64 A0 43 03 03 41 41 C2 23 7E 9B
2015/08/06 12:41:34 9DA9D8 c (ecm) 53 12 E2
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 request controlword for ecm 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:34 9A66D8 r (work) data from add_job action=5 client r Sky
2015/08/06 12:41:34 9A66D8 r (reader) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [reader_get_ecm] ecm already sent to reader Sky (NOK)
2015/08/06 12:41:34 9A66D8 r (ecm) {client dvbapi, caid 09C4, srvid 0086} [write_ecm_answer] cyclecheck passed! Reader: Sky rc: 4
2015/08/06 12:41:34 9A66D8 r (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [write_ecm_answer] reader Sky rc 4, ecm time 4 ms (10 ms)
2015/08/06 12:41:34 9A66D8 r (work) start client thread action 32
2015/08/06 12:41:34 9DA9D8 c (work) data from add_job action=32 client c dvbapi
2015/08/06 12:41:34 9DA9D8 c (ecm) {client dvbapi, caid 09C4, prid 000000, srvid 0086} [send_dcw] rc 4 from reader Sky
2015/08/06 12:41:34 9DA9D8 c (ecm) Client dvbapi csphash 61DDB176 cw 00000000000000000000000000000000 rc 4 09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 no controlword received for PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 CHID 11F9 VPID 00FF
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 not interested in response ecmhash 8BEA5E6367578D0BF8B65957253828A3 (delivered cw is empty!)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 stop Filter 1 (fd: 11 api: 0, caid: 09C4, provid: 000000, ecmpid: 1B83)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:11)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) New events occurred on 1 of 7 handlers after 18 ms inactivity
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Now handling fd 11 that reported event 32
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 has 3 ecmpids, 5 streampids, 0 ecmfilters and 4 of max 23 emmfilters
2015/08/06 12:41:34 9DA9D8 c (ecm) dvbapi (09C4&000000/11F9/0086/B3:8BEA5E6367578D0BF8B65957253828A3): not found (13 ms) by Sky
2015/08/06 12:41:34 9DA9D8 c (ecm) cw:
2015/08/06 12:41:34 9DA9D8 c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83 (found caid/provid in cache - weight: 1)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 prio ecmpid 1 09C4:000000:1B83:0000 (localrdr: Sky weight: 7)
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader Sky
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 9DA9D8 c (chk) caid 1833 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:34 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 0 1833:000000:1883:10000 (no matching reader)
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 9DA9D8 c (chk) caid 09C4 not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:34 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader Sky
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader HD+_in_7020hd
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader ORF_in_600
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 9DA9D8 c (chk) caid 098C not found in caidlist reader Dorcel_&_Hustler
2015/08/06 12:41:34 9DA9D8 c (chk) reader unavailable austria_sat
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 ignore ecmpid 2 098C:000000:1A83:10000 (no matching reader)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 sorting the ecmpids took 10 ms
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 restarting decodingrequests after 11 ms with 1 enabled and 2 disabled ecmpids!
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 09C4 PROVID 000000 ECMPID 1B83 ANY CHID PMTPID 0060 VPID 00FF
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 try to start new filter for caid: 09C4, provid: 000000, pid: 1B83
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Open device /dev/dvb/adapter0/demux3 (fd 11)
2015/08/06 12:41:34 9DA9D8 c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 09C4 provid 000000 pid 1B83)
2015/08/06 12:41:34 0 (work) start reader thread action 13
2015/08/06 12:41:34 0 (-) -- Skipped 1 duplicated log lines --
2015/08/06 12:41:34 0 (work) add reader job action 13 queue length 1 Dorcel_&_Hustler_&_MTV
2015/08/06 12:41:34 0 (work) add reader job action 13 queue length 1 Dorcel_&_Hustler
2015/08/06 12:41:34 0 (work) add reader job action 13 queue length 1 austria_sat
2015/08/06 12:41:34 9A66D8 r (work) data from add_job action=13 client r Sky
2015/08/06 12:41:34 93E140 r (work) data from add_job action=13 client r HD+_in_7020hd