2015/02/01 12:38:45 621D60 0D 00 00 90 64 C1 01 EE E0 CE F2 BF 62 1F 13 05
2015/02/01 12:38:45 621D60 3D D3 BE AE E6 8F B9 A1 52 C0 F3 D1 D4 55 CC AF
2015/02/01 12:38:45 621D60 F6 D1 19 B0 84 90 63 10 76 0E E3 83 B2 6B 0A 23
2015/02/01 12:38:45 621D60 88 BE 48 00 B6 60 D9 BB 86 6B A1 F6 A9 73 7C 26
2015/02/01 12:38:45 621D60 12 7D 5A B4 E2 C8 5E FE 89 A3 57 98 38 E0 D3 F1
2015/02/01 12:38:45 621D60 ED 94 CB 68 9E D1 B3 85 AC 4E 40 90 80 8F 8D DD
2015/02/01 12:38:45 621D60 B4 37 42 AA BD C6 F9 98 B1
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Setting timeout to 14 ETU (72 us)
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Transmit size 5 bytes, expected len 1 bytes, delay 0 us, timeout=72 us
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Transmit:
2015/02/01 12:38:45 621D60 D1 40 00 80 74
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IO: Write timeout 72 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IO: Sending:
2015/02/01 12:38:45 621D60 D1 40 00 80 74
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Transmit succesful
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IO: Receiving:
2015/02/01 12:38:45 621D60 40
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Receive succesful
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Setting timeout to 14 ETU (72 us)
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Transmit size 116 bytes, expected len 2 bytes, delay 0 us, timeout=72 us
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Transmit:
2015/02/01 12:38:45 621D60 00 7D 0B 0C AD F4 DE EF 42 92 AE 0D 00 00 90 64
2015/02/01 12:38:45 621D60 C1 01 EE E0 CE F2 BF 62 1F 13 05 3D D3 BE AE E6
2015/02/01 12:38:45 621D60 8F B9 A1 52 C0 F3 D1 D4 55 CC AF F6 D1 19 B0 84
2015/02/01 12:38:45 621D60 90 63 10 76 0E E3 83 B2 6B 0A 23 88 BE 48 00 B6
2015/02/01 12:38:45 621D60 60 D9 BB 86 6B A1 F6 A9 73 7C 26 12 7D 5A B4 E2
2015/02/01 12:38:45 621D60 C8 5E FE 89 A3 57 98 38 E0 D3 F1 ED 94 CB 68 9E
2015/02/01 12:38:45 621D60 D1 B3 85 AC 4E 40 90 80 8F 8D DD B4 37 42 AA BD
2015/02/01 12:38:45 621D60 C6 F9 98 B1
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IO: Write timeout 72 us, write delay 0 us, to send 116 char(s), chunksize 116 char(s)
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IO: Sending:
2015/02/01 12:38:45 621D60 00 7D 0B 0C AD F4 DE EF 42 92 AE 0D 00 00 90 64
2015/02/01 12:38:45 621D60 C1 01 EE E0 CE F2 BF 62 1F 13 05 3D D3 BE AE E6
2015/02/01 12:38:45 621D60 8F B9 A1 52 C0 F3 D1 D4 55 CC AF F6 D1 19 B0 84
2015/02/01 12:38:45 621D60 90 63 10 76 0E E3 83 B2 6B 0A 23 88 BE 48 00 B6
2015/02/01 12:38:45 621D60 60 D9 BB 86 6B A1 F6 A9 73 7C 26 12 7D 5A B4 E2
2015/02/01 12:38:45 621D60 C8 5E FE 89 A3 57 98 38 E0 D3 F1 ED 94 CB 68 9E
2015/02/01 12:38:45 621D60 D1 B3 85 AC 4E 40 90 80 8F 8D DD B4 37 42 AA BD
2015/02/01 12:38:45 621D60 C6 F9 98 B1
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Transmit succesful
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
2015/02/01 12:38:45 621D60 r v14 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2015/02/01 12:38:46 640708 c caid 098C not found in caidlist reader Emulator
2015/02/01 12:38:46 640708 c [GET_CW] wait_time 0 caid 098C prov 000000 srvid 006B rc 100 cacheex cl mode 0 ex1rdr 0
2015/02/01 12:38:46 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2015/02/01 12:38:46 640708 c request_cw stage=2 to reader v14 ecm hash=F23CC296DBFA291815B97E884421D9D1
2015/02/01 12:38:46 640708 c start reader thread action 5
2015/02/01 12:38:46 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_request] reader v14 --> SENT 1
2015/02/01 12:38:46 640708 c Client serverbox ECM dump 098C&000000/0C1E/006B/98:F23CC296DBFA291815B97E884421D9D1
2015/02/01 12:38:46 640708 80 70 95 00 00 01 1D 85 01 5C CF 0C 1E AA 55 05
2015/02/01 12:38:46 640708 20 01 00 00 80 40 35 34 2C F6 1D C3 4C 98 B4 03
2015/02/01 12:38:46 640708 02 05 02 ED 73 7D 0B 0C AD F4 DE EF 42 92 AE 0D
2015/02/01 12:38:46 640708 00 00 90 64 C1 01 EE E0 CE F2 BF 62 1F 13 05 3D
2015/02/01 12:38:46 640708 D3 BE AE E6 8F B9 A1 52 C0 F3 D1 D4 55 CC AF F6
2015/02/01 12:38:46 640708 D1 19 B0 84 90 63 10 76 0E E3 83 B2 6B 0A 23 88
2015/02/01 12:38:46 640708 BE 48 00 B6 60 D9 BB 86 6B A1 F6 A9 73 7C 26 12
2015/02/01 12:38:46 640708 7D 5A B4 E2 C8 5E FE 89 A3 57 98 38 E0 D3 F1 ED
2015/02/01 12:38:46 640708 94 CB 68 9E D1 B3 85 AC 4E 40 90 80 8F 8D DD B4
2015/02/01 12:38:46 640708 37 42 AA BD C6 F9 98 B1
2015/02/01 12:38:46 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:F23CC296DBFA291815B97E884421D9D1
2015/02/01 12:38:46 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:46 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:46 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:46 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 3 ms (4 ms)
2015/02/01 12:38:46 621D60 r start client thread action 32
2015/02/01 12:38:46 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:46 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:46 640708 c Client serverbox csphash D867B4B7 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:F23CC296DBFA291815B97E884421D9D1
2015/02/01 12:38:46 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:46 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash F23CC296DBFA291815B97E884421D9D1 (delivered cw is empty!)
2015/02/01 12:38:46 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:46 640708 c serverbox (098C&000000/0C1E/006B/98:F23CC296DBFA291815B97E884421D9D1): not found (6 ms) by v14 - Sky Hits HD
2015/02/01 12:38:46 640708 c cw:
2015/02/01 12:38:46 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:46 640708 c [DVBAPI] new events occurred on 1 of 6 handlers after 306 ms inactivity
2015/02/01 12:38:46 640708 c [DVBAPI] now handling fd 26 that reported event 32
2015/02/01 12:38:46 640708 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 0 ecmfilters and 3 emmfilters
2015/02/01 12:38:46 640708 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/02/01 12:38:47 621D60 00 80 0E 02 00 00 0F 06 00 10 20 00 00 11 20 04
2015/02/01 12:38:47 621D60 00 00 00 00 25 11 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:47 621D60 00 00 00 00 00 00 00 2A 04 00 00 00 00 55 01 20
2015/02/01 12:38:47 621D60 56 08 00 00 00 00 00 00 00 00 2B 02 00 00
2015/02/01 12:38:47 621D60 r v14 [videoguard2] classD3 ins54: no cw --> Card is paired
2015/02/01 12:38:47 621D60 r v14 [videoguard2] cardreader_do_ecm: after csystem.do_ecm rc=0
2015/02/01 12:38:47 621D60 r v14 [videoguard2] cardreader_do_ecm: ret rc=0
2015/02/01 12:38:47 621D60 r v14 [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=0 (ERROR=0)
2015/02/01 12:38:47 621D60 r v14 [videoguard2] Error processing ecm for caid 098C, srvid 006B, servicename: Sky Hits HD
2015/02/01 12:38:47 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:47 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 68 ms (70 ms)
2015/02/01 12:38:47 621D60 r start client thread action 32
2015/02/01 12:38:47 621D60 r v14 [videoguard2] TRACE: ecm answer for ecm hash 882D5C081A5503AD7CB137CCCB582F51 rc=4
2015/02/01 12:38:47 621D60 r v14 [videoguard2] ecm hash: 882D5C081A5503AD7CB137CCCB582F51 real time: 63 ms
2015/02/01 12:38:47 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:47 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:47 640708 c Client serverbox csphash 17CFD671 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:47 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:47 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 882D5C081A5503AD7CB137CCCB582F51 (delivered cw is empty!)
2015/02/01 12:38:47 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:47 640708 c serverbox (098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51): not found (73 ms) by v14 - Sky Hits HD
2015/02/01 12:38:47 640708 c cw:
2015/02/01 12:38:47 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:47 640708 c [DVBAPI] new events occurred on 1 of 6 handlers after 308 ms inactivity
2015/02/01 12:38:47 640708 c [DVBAPI] now handling fd 26 that reported event 32
2015/02/01 12:38:47 640708 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 0 ecmfilters and 3 emmfilters
2015/02/01 12:38:47 640708 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/02/01 12:38:47 0 start client thread action 31
2015/02/01 12:38:47 640708 c data from add_job action=31 client c serverbox
2015/02/01 12:38:47 640708 c [PRIORITIZE PID 3] 098C:000000:1A0B:0000 (localrdr: v14 weight: 8)
2015/02/01 12:38:47 640708 c [IGNORE PID 0] 1833:000000:180B:0000 (file)
2015/02/01 12:38:47 640708 c [IGNORE PID 1] 09C4:000000:1B0B:0000 (file)
2015/02/01 12:38:47 640708 c [IGNORE PID 2] 09AF:000000:1F0B:0000 (file)
2015/02/01 12:38:47 640708 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A0B ANY CHID PMTPID 0062 VPID 02FF
2015/02/01 12:38:47 640708 c [DVBAPI] Demuxer #0 try to start new filter for caid: 098C, provid: 000000, pid: 1A0B
2015/02/01 12:38:47 640708 c DEVICE open (/dev/dvb/adapter0/demux3) fd 26
2015/02/01 12:38:47 640708 c [DVBAPI] Demuxer #0 Filter #4 started successfully (caid 098C provid 000000 pid 1A0B)
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 Filter #4 set chid to ANY CHID on fd 26
2015/02/01 12:38:49 640708 c [DVBAPI] GET_CW
2015/02/01 12:38:49 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] NEW REQUEST!
2015/02/01 12:38:49 640708 c caid 098C not found in caidlist reader hd01
2015/02/01 12:38:49 640708 c caid 098C not found in caidlist reader Emulator
2015/02/01 12:38:49 640708 c [GET_CW] wait_time 0 caid 098C prov 000000 srvid 006B rc 100 cacheex cl mode 0 ex1rdr 0
2015/02/01 12:38:49 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2015/02/01 12:38:49 640708 c request_cw stage=2 to reader v14 ecm hash=882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:49 640708 c start reader thread action 5
2015/02/01 12:38:49 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_request] reader v14 --> SENT 1
2015/02/01 12:38:49 640708 c Client serverbox ECM dump 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:49 640708 81 70 95 00 00 01 1D 85 01 5C D3 0C 1E AA 55 05
2015/02/01 12:38:49 640708 20 01 00 00 80 40 79 91 FF 51 6D 04 DF AB ED 03
2015/02/01 12:38:49 640708 02 05 02 30 73 7D 0B 0C D9 52 1B 0A A6 2F 8E F3
2015/02/01 12:38:49 640708 00 00 90 64 C1 01 11 7E D4 ED 19 02 1C 14 CB 09
2015/02/01 12:38:49 640708 9D 2A 36 72 C0 82 46 98 B6 B8 23 F8 72 0C 35 80
2015/02/01 12:38:49 640708 EF DF B0 2B 22 03 C3 98 46 6F 6E A8 A3 4E 93 0E
2015/02/01 12:38:49 640708 FC CF F4 69 2D F2 7A 91 5B 63 EA B7 90 BE D4 0B
2015/02/01 12:38:49 640708 3C DD 3C 59 C8 6C B4 1B E3 B1 FA BA 2C B6 CB 2D
2015/02/01 12:38:49 640708 77 11 89 47 8E 67 4E 06 D1 FC EC E8 55 E1 0D C2
2015/02/01 12:38:49 640708 F9 92 18 DF CB 96 76 85
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:49 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:49 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:49 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:49 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 3 ms (4 ms)
2015/02/01 12:38:49 621D60 r start client thread action 32
2015/02/01 12:38:49 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:49 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:49 640708 c Client serverbox csphash 17CFD671 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 882D5C081A5503AD7CB137CCCB582F51 (delivered cw is empty!)
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:49 640708 c serverbox (098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51): not found (6 ms) by v14 - Sky Hits HD
2015/02/01 12:38:49 640708 c cw:
2015/02/01 12:38:49 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:49 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2015/02/01 12:38:49 640708 c request_cw stage=2 to reader v14 ecm hash=882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:49 640708 c start reader thread action 5
2015/02/01 12:38:49 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_request] reader v14 --> SENT 1
2015/02/01 12:38:49 640708 c Client serverbox ECM dump 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:49 640708 81 70 95 00 00 01 1D 85 01 5C D3 0C 1E AA 55 05
2015/02/01 12:38:49 640708 20 01 00 00 80 40 79 91 FF 51 6D 04 DF AB ED 03
2015/02/01 12:38:49 640708 02 05 02 30 73 7D 0B 0C D9 52 1B 0A A6 2F 8E F3
2015/02/01 12:38:49 640708 00 00 90 64 C1 01 11 7E D4 ED 19 02 1C 14 CB 09
2015/02/01 12:38:49 640708 9D 2A 36 72 C0 82 46 98 B6 B8 23 F8 72 0C 35 80
2015/02/01 12:38:49 640708 EF DF B0 2B 22 03 C3 98 46 6F 6E A8 A3 4E 93 0E
2015/02/01 12:38:49 640708 FC CF F4 69 2D F2 7A 91 5B 63 EA B7 90 BE D4 0B
2015/02/01 12:38:49 640708 3C DD 3C 59 C8 6C B4 1B E3 B1 FA BA 2C B6 CB 2D
2015/02/01 12:38:49 640708 77 11 89 47 8E 67 4E 06 D1 FC EC E8 55 E1 0D C2
2015/02/01 12:38:49 640708 F9 92 18 DF CB 96 76 85
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:49 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:49 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:49 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:49 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 3 ms (4 ms)
2015/02/01 12:38:49 621D60 r start client thread action 32
2015/02/01 12:38:49 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:49 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:49 640708 c Client serverbox csphash 17CFD671 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 882D5C081A5503AD7CB137CCCB582F51 (delivered cw is empty!)
2015/02/01 12:38:49 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:49 640708 c serverbox (098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51): not found (6 ms) by v14 - Sky Hits HD
2015/02/01 12:38:49 640708 c cw:
2015/02/01 12:38:49 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:50 621D60 r v14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/02/01 12:38:50 623B90 r hd01 [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/02/01 12:38:50 640708 c [DVBAPI] new events occurred on 1 of 6 handlers after 306 ms inactivity
2015/02/01 12:38:50 640708 c [DVBAPI] now handling fd 26 that reported event 32
2015/02/01 12:38:50 640708 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 0 ecmfilters and 3 emmfilters
2015/02/01 12:38:50 640708 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/02/01 12:38:50 640708 9D 2A 36 72 C0 82 46 98 B6 B8 23 F8 72 0C 35 80
2015/02/01 12:38:50 640708 EF DF B0 2B 22 03 C3 98 46 6F 6E A8 A3 4E 93 0E
2015/02/01 12:38:50 640708 FC CF F4 69 2D F2 7A 91 5B 63 EA B7 90 BE D4 0B
2015/02/01 12:38:50 640708 3C DD 3C 59 C8 6C B4 1B E3 B1 FA BA 2C B6 CB 2D
2015/02/01 12:38:50 640708 77 11 89 47 8E 67 4E 06 D1 FC EC E8 55 E1 0D C2
2015/02/01 12:38:50 640708 F9 92 18 DF CB 96 76 85
2015/02/01 12:38:50 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:50 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:50 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:50 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:50 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 3 ms (4 ms)
2015/02/01 12:38:50 621D60 r start client thread action 32
2015/02/01 12:38:50 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:50 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:50 640708 c Client serverbox csphash 17CFD671 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:50 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:50 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 882D5C081A5503AD7CB137CCCB582F51 (delivered cw is empty!)
2015/02/01 12:38:50 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:50 640708 c serverbox (098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51): not found (6 ms) by v14 - Sky Hits HD
2015/02/01 12:38:50 640708 c cw:
2015/02/01 12:38:50 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:51 640708 c [DVBAPI] new events occurred on 1 of 6 handlers after 306 ms inactivity
2015/02/01 12:38:51 640708 c [DVBAPI] now handling fd 26 that reported event 32
2015/02/01 12:38:51 640708 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 0 ecmfilters and 3 emmfilters
2015/02/01 12:38:51 640708 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/02/01 12:38:51 621D60 r v14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/02/01 12:38:51 623B90 r hd01 [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/02/01 12:38:51 0 start client thread action 31
2015/02/01 12:38:51 640708 c data from add_job action=31 client c serverbox
2015/02/01 12:38:51 640708 c [PRIORITIZE PID 3] 098C:000000:1A0B:0000 (localrdr: v14 weight: 8)
2015/02/01 12:38:51 640708 c [IGNORE PID 0] 1833:000000:180B:0000 (file)
2015/02/01 12:38:51 640708 c [IGNORE PID 1] 09C4:000000:1B0B:0000 (file)
2015/02/01 12:38:51 640708 c [IGNORE PID 2] 09AF:000000:1F0B:0000 (file)
2015/02/01 12:38:51 640708 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A0B ANY CHID PMTPID 0062 VPID 02FF
2015/02/01 12:38:51 640708 c [DVBAPI] Demuxer #0 try to start new filter for caid: 098C, provid: 000000, pid: 1A0B
2015/02/01 12:38:51 640708 c DEVICE open (/dev/dvb/adapter0/demux3) fd 26
2015/02/01 12:38:51 640708 c [DVBAPI] Demuxer #0 Filter #4 started successfully (caid 098C provid 000000 pid 1A0B)
2015/02/01 12:38:52 640708 c [DVBAPI] GET_CW
2015/02/01 12:38:52 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] NEW REQUEST!
2015/02/01 12:38:52 640708 c caid 098C not found in caidlist reader hd01
2015/02/01 12:38:52 640708 c caid 098C not found in caidlist reader Emulator
2015/02/01 12:38:52 640708 c [GET_CW] wait_time 0 caid 098C prov 000000 srvid 006B rc 100 cacheex cl mode 0 ex1rdr 0
2015/02/01 12:38:52 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2015/02/01 12:38:52 640708 c request_cw stage=2 to reader v14 ecm hash=882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:52 640708 c start reader thread action 5
2015/02/01 12:38:52 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_request] reader v14 --> SENT 1
2015/02/01 12:38:52 640708 c Client serverbox ECM dump 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:52 640708 81 70 95 00 00 01 1D 85 01 5C D3 0C 1E AA 55 05
2015/02/01 12:38:52 640708 20 01 00 00 80 40 79 91 FF 51 6D 04 DF AB ED 03
2015/02/01 12:38:52 640708 02 05 02 30 73 7D 0B 0C D9 52 1B 0A A6 2F 8E F3
2015/02/01 12:38:52 640708 00 00 90 64 C1 01 11 7E D4 ED 19 02 1C 14 CB 09
2015/02/01 12:38:52 640708 9D 2A 36 72 C0 82 46 98 B6 B8 23 F8 72 0C 35 80
2015/02/01 12:38:52 640708 EF DF B0 2B 22 03 C3 98 46 6F 6E A8 A3 4E 93 0E
2015/02/01 12:38:52 640708 FC CF F4 69 2D F2 7A 91 5B 63 EA B7 90 BE D4 0B
2015/02/01 12:38:52 640708 3C DD 3C 59 C8 6C B4 1B E3 B1 FA BA 2C B6 CB 2D
2015/02/01 12:38:52 640708 77 11 89 47 8E 67 4E 06 D1 FC EC E8 55 E1 0D C2
2015/02/01 12:38:52 640708 F9 92 18 DF CB 96 76 85
2015/02/01 12:38:52 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:52 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:52 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:52 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:52 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 3 ms (4 ms)
2015/02/01 12:38:52 621D60 r start client thread action 32
2015/02/01 12:38:52 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:52 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:52 640708 c Client serverbox csphash 17CFD671 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51
2015/02/01 12:38:52 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:52 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 882D5C081A5503AD7CB137CCCB582F51 (delivered cw is empty!)
2015/02/01 12:38:52 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:52 640708 c serverbox (098C&000000/0C1E/006B/98:882D5C081A5503AD7CB137CCCB582F51): not found (6 ms) by v14 - Sky Hits HD
2015/02/01 12:38:52 640708 c cw:
2015/02/01 12:38:52 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:53 621D60 r v14 [videoguard2] TRACE: SW2: 00
2015/02/01 12:38:53 621D60 r v14 [videoguard2] Answer from cardreader:
2015/02/01 12:38:53 621D60 6A 0F 8F B5 9F 94 0C 17 62 3D A3 78 8B EC C7 72
2015/02/01 12:38:53 621D60 31 F4 B2 7D 35 62 E5 DB 55 D7 5C 60 D4 74 1E FB
2015/02/01 12:38:53 621D60 82 E3 2E 48 2C 41 BF 55 24 55 BE C1 06 85 D7 94
2015/02/01 12:38:53 621D60 5E CD 04 08 90 4D EC 58 FE 61 4B 0F F6 A2 12 D5
2015/02/01 12:38:53 621D60 86 55 7E 3D 26 67 AF 7A 7B 36 32 18 3E 08 40 4A
2015/02/01 12:38:53 621D60 DB 86 2F D1 A4 17 B6 21 8F 22 76 CB 13 CF 90 00
2015/02/01 12:38:53 621D60 r v14 [videoguard2] Decrypted payload
2015/02/01 12:38:53 621D60 00 00 00 00 00 00 00 00 00 00 00 00 FF 00 22 02
2015/02/01 12:38:53 621D60 00 80 0E 02 00 00 0F 06 00 10 20 00 00 11 20 04
2015/02/01 12:38:53 621D60 00 00 00 00 25 11 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:53 621D60 00 00 00 00 00 00 00 2A 04 00 00 00 00 55 01 20
2015/02/01 12:38:53 621D60 56 08 00 00 00 00 00 00 00 00 2B 02 00 00
2015/02/01 12:38:53 621D60 r v14 [videoguard2] classD3 ins54: no cw --> Card is paired
2015/02/01 12:38:53 621D60 r v14 [videoguard2] cardreader_do_ecm: after csystem.do_ecm rc=0
2015/02/01 12:38:53 621D60 r v14 [videoguard2] cardreader_do_ecm: ret rc=0
2015/02/01 12:38:53 621D60 r v14 [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=0 (ERROR=0)
2015/02/01 12:38:53 621D60 r v14 [videoguard2] Error processing ecm for caid 098C, srvid 006B, servicename: Sky Hits HD
2015/02/01 12:38:53 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:53 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 65 ms (66 ms)
2015/02/01 12:38:53 621D60 r start client thread action 32
2015/02/01 12:38:53 621D60 r v14 [videoguard2] TRACE: ecm answer for ecm hash 06FE8E868E06E1869568D99D8C4FA5EB rc=4
2015/02/01 12:38:53 621D60 r v14 [videoguard2] ecm hash: 06FE8E868E06E1869568D99D8C4FA5EB real time: 60 ms
2015/02/01 12:38:53 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:53 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:53 640708 c Client serverbox csphash 90484B72 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:53 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:53 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 06FE8E868E06E1869568D99D8C4FA5EB (delivered cw is empty!)
2015/02/01 12:38:53 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:53 640708 c serverbox (098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB): not found (68 ms) by v14 - Sky Hits HD
2015/02/01 12:38:53 640708 c cw:
2015/02/01 12:38:53 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:53 640708 c [DVBAPI] new events occurred on 1 of 6 handlers after 306 ms inactivity
2015/02/01 12:38:53 640708 c [DVBAPI] now handling fd 26 that reported event 32
2015/02/01 12:38:53 640708 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 0 ecmfilters and 3 emmfilters
2015/02/01 12:38:53 640708 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/02/01 12:38:54 0 start client thread action 31
2015/02/01 12:38:54 640708 c data from add_job action=31 client c serverbox
2015/02/01 12:38:54 640708 7F F8 5B 66 7E 85 88 E0 9C DC AC E5 B1 48 27 42
2015/02/01 12:38:54 640708 0E E5 AC 96 DF 7F D8 38 B4 14 4D CD EF 50 CA 58
2015/02/01 12:38:54 640708 93 0A C0 BF 3B 8E 8B 53 66 A6 90 06 50 BA 91 DD
2015/02/01 12:38:54 640708 3F 41 C8 3D B3 F3 00 BF
2015/02/01 12:38:54 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:54 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:54 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:54 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:54 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 2 ms (4 ms)
2015/02/01 12:38:54 621D60 r start client thread action 32
2015/02/01 12:38:54 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:54 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:54 640708 c Client serverbox csphash 90484B72 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:54 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:54 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 06FE8E868E06E1869568D99D8C4FA5EB (delivered cw is empty!)
2015/02/01 12:38:54 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:54 640708 c serverbox (098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB): not found (6 ms) by v14 - Sky Hits HD
2015/02/01 12:38:54 640708 c cw:
2015/02/01 12:38:54 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:54 640708 c [DVBAPI] new events occurred on 1 of 6 handlers after 306 ms inactivity
2015/02/01 12:38:54 640708 c [DVBAPI] now handling fd 26 that reported event 32
2015/02/01 12:38:54 640708 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 0 ecmfilters and 3 emmfilters
2015/02/01 12:38:54 640708 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/02/01 12:38:54 0 start client thread action 31
2015/02/01 12:38:54 640708 c data from add_job action=31 client c serverbox
2015/02/01 12:38:55 640708 c [PRIORITIZE PID 3] 098C:000000:1A0B:0000 (localrdr: v14 weight: 8)
2015/02/01 12:38:55 640708 c [IGNORE PID 0] 1833:000000:180B:0000 (file)
2015/02/01 12:38:55 640708 c [IGNORE PID 1] 09C4:000000:1B0B:0000 (file)
2015/02/01 12:38:55 640708 c [IGNORE PID 2] 09AF:000000:1F0B:0000 (file)
2015/02/01 12:38:55 640708 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A0B ANY CHID PMTPID 0062 VPID 02FF
2015/02/01 12:38:55 640708 c [DVBAPI] Demuxer #0 try to start new filter for caid: 098C, provid: 000000, pid: 1A0B
2015/02/01 12:38:55 640708 c DEVICE open (/dev/dvb/adapter0/demux3) fd 26
2015/02/01 12:38:55 640708 c [DVBAPI] Demuxer #0 Filter #4 started successfully (caid 098C provid 000000 pid 1A0B)
2015/02/01 12:38:55 621D60 r v14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/02/01 12:38:55 623B90 r hd01 [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/02/01 12:38:56 640708 c [DVBAPI] Demuxer #0 Filter #4 set chid to ANY CHID on fd 26
2015/02/01 12:38:56 640708 c [DVBAPI] GET_CW
2015/02/01 12:38:56 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] NEW REQUEST!
2015/02/01 12:38:56 640708 c caid 098C not found in caidlist reader hd01
2015/02/01 12:38:56 640708 c caid 098C not found in caidlist reader Emulator
2015/02/01 12:38:56 640708 c [GET_CW] wait_time 0 caid 098C prov 000000 srvid 006B rc 100 cacheex cl mode 0 ex1rdr 0
2015/02/01 12:38:56 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2015/02/01 12:38:56 640708 c request_cw stage=2 to reader v14 ecm hash=06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:56 640708 c start reader thread action 5
2015/02/01 12:38:56 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_request] reader v14 --> SENT 1
2015/02/01 12:38:56 640708 c Client serverbox ECM dump 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:56 640708 80 70 95 00 00 01 1D 85 01 5C D6 0C 1E AA 55 05
2015/02/01 12:38:56 640708 20 01 00 00 80 40 D0 B3 4A 4A C6 2C 28 14 33 03
2015/02/01 12:38:56 640708 02 05 02 69 73 7D 0B 0C 22 E1 16 A7 B6 DF 7E 33
2015/02/01 12:38:56 640708 00 00 90 64 C1 01 1B A6 57 8D 30 01 6F 79 B9 97
2015/02/01 12:38:56 640708 E4 82 BA BC 37 10 EC 59 EE 6A 75 02 01 B9 78 D2
2015/02/01 12:38:56 640708 A7 51 EB 38 24 23 17 30 80 90 F4 D7 5D 62 25 0F
2015/02/01 12:38:56 640708 7F F8 5B 66 7E 85 88 E0 9C DC AC E5 B1 48 27 42
2015/02/01 12:38:56 640708 0E E5 AC 96 DF 7F D8 38 B4 14 4D CD EF 50 CA 58
2015/02/01 12:38:56 640708 93 0A C0 BF 3B 8E 8B 53 66 A6 90 06 50 BA 91 DD
2015/02/01 12:38:56 640708 3F 41 C8 3D B3 F3 00 BF
2015/02/01 12:38:56 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:56 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:56 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:56 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:56 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 3 ms (5 ms)
2015/02/01 12:38:56 621D60 r start client thread action 32
2015/02/01 12:38:56 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:56 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:56 640708 c Client serverbox csphash 90484B72 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:56 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:56 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 06FE8E868E06E1869568D99D8C4FA5EB (delivered cw is empty!)
2015/02/01 12:38:56 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:56 640708 c serverbox (098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB): not found (7 ms) by v14 - Sky Hits HD
2015/02/01 12:38:56 640708 c cw:
2015/02/01 12:38:56 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:57 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2015/02/01 12:38:57 640708 c request_cw stage=2 to reader v14 ecm hash=06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:57 640708 c start reader thread action 5
2015/02/01 12:38:57 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_request] reader v14 --> SENT 1
2015/02/01 12:38:57 640708 c Client serverbox ECM dump 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:57 640708 80 70 95 00 00 01 1D 85 01 5C D6 0C 1E AA 55 05
2015/02/01 12:38:57 640708 20 01 00 00 80 40 D0 B3 4A 4A C6 2C 28 14 33 03
2015/02/01 12:38:57 640708 02 05 02 69 73 7D 0B 0C 22 E1 16 A7 B6 DF 7E 33
2015/02/01 12:38:57 640708 00 00 90 64 C1 01 1B A6 57 8D 30 01 6F 79 B9 97
2015/02/01 12:38:57 640708 E4 82 BA BC 37 10 EC 59 EE 6A 75 02 01 B9 78 D2
2015/02/01 12:38:57 640708 A7 51 EB 38 24 23 17 30 80 90 F4 D7 5D 62 25 0F
2015/02/01 12:38:57 640708 7F F8 5B 66 7E 85 88 E0 9C DC AC E5 B1 48 27 42
2015/02/01 12:38:57 640708 0E E5 AC 96 DF 7F D8 38 B4 14 4D CD EF 50 CA 58
2015/02/01 12:38:57 640708 93 0A C0 BF 3B 8E 8B 53 66 A6 90 06 50 BA 91 DD
2015/02/01 12:38:57 640708 3F 41 C8 3D B3 F3 00 BF
2015/02/01 12:38:57 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:57 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:57 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:57 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:57 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 3 ms (4 ms)
2015/02/01 12:38:57 621D60 r start client thread action 32
2015/02/01 12:38:57 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:57 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:57 640708 c Client serverbox csphash 90484B72 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:57 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:57 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 06FE8E868E06E1869568D99D8C4FA5EB (delivered cw is empty!)
2015/02/01 12:38:57 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:57 640708 c serverbox (098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB): not found (6 ms) by v14 - Sky Hits HD
2015/02/01 12:38:57 640708 c cw:
2015/02/01 12:38:57 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:57 640708 c [DVBAPI] new events occurred on 1 of 6 handlers after 306 ms inactivity
2015/02/01 12:38:57 640708 c [DVBAPI] now handling fd 26 that reported event 32
2015/02/01 12:38:57 640708 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 0 ecmfilters and 3 emmfilters
2015/02/01 12:38:57 640708 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/02/01 12:38:57 0 start client thread action 31
2015/02/01 12:38:57 640708 c data from add_job action=31 client c serverbox
2015/02/01 12:38:58 640708 E4 82 BA BC 37 10 EC 59 EE 6A 75 02 01 B9 78 D2
2015/02/01 12:38:58 640708 A7 51 EB 38 24 23 17 30 80 90 F4 D7 5D 62 25 0F
2015/02/01 12:38:58 640708 7F F8 5B 66 7E 85 88 E0 9C DC AC E5 B1 48 27 42
2015/02/01 12:38:58 640708 0E E5 AC 96 DF 7F D8 38 B4 14 4D CD EF 50 CA 58
2015/02/01 12:38:58 640708 93 0A C0 BF 3B 8E 8B 53 66 A6 90 06 50 BA 91 DD
2015/02/01 12:38:58 640708 3F 41 C8 3D B3 F3 00 BF
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 request cw for ecm 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:58 621D60 r data from add_job action=5 client r v14
2015/02/01 12:38:58 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [reader_get_ecm] ecm already sent to reader v14 (NOK)
2015/02/01 12:38:58 621D60 r {client serverbox, caid 098C, srvid 006B} [write_ecm_answer] cyclecheck passed! Reader: v14 rc: 4
2015/02/01 12:38:58 621D60 r {client serverbox, caid 098C, prid 000000, srvid 006B} [write_ecm_answer] reader v14 rc 4, ecm time 3 ms (4 ms)
2015/02/01 12:38:58 621D60 r start client thread action 32
2015/02/01 12:38:58 640708 c data from add_job action=32 client c serverbox
2015/02/01 12:38:58 640708 c {client serverbox, caid 098C, prid 000000, srvid 006B} [send_dcw] rc 4 from reader v14
2015/02/01 12:38:58 640708 c Client serverbox csphash 90484B72 cw 00000000000000000000000000000000 rc 4 098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 no controlword received for PID #3 CAID 098C PROVID 000000 ECMPID 1A0B CHID 0C1E VPID 02FF
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 not interested in response ecmhash 06FE8E868E06E1869568D99D8C4FA5EB (delivered cw is empty!)
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 stop Filter #4 (fd: 26 api: 0, caid: 098C, provid: 000000, ecmpid: 1A0B)
2015/02/01 12:38:58 640708 c serverbox (098C&000000/0C1E/006B/98:06FE8E868E06E1869568D99D8C4FA5EB): not found (6 ms) by v14 - Sky Hits HD
2015/02/01 12:38:58 640708 c cw:
2015/02/01 12:38:58 640708 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/02/01 12:38:58 621D60 r v14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/02/01 12:38:58 623B90 r hd01 [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/02/01 12:38:58 640708 c [DVBAPI] new events occurred on 1 of 6 handlers after 306 ms inactivity
2015/02/01 12:38:58 640708 c [DVBAPI] now handling fd 26 that reported event 32
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 has 4 ecmpids, 3 streampids, 0 ecmfilters and 3 emmfilters
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 (re)starting decodingrequests on all 4 ecmpids!
2015/02/01 12:38:58 0 start client thread action 31
2015/02/01 12:38:58 640708 c data from add_job action=31 client c serverbox
2015/02/01 12:38:58 640708 c [PRIORITIZE PID 3] 098C:000000:1A0B:0000 (localrdr: v14 weight: 8)
2015/02/01 12:38:58 640708 c [IGNORE PID 0] 1833:000000:180B:0000 (file)
2015/02/01 12:38:58 640708 c [IGNORE PID 1] 09C4:000000:1B0B:0000 (file)
2015/02/01 12:38:58 640708 c [IGNORE PID 2] 09AF:000000:1F0B:0000 (file)
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 trying to descramble PID #3 CAID 098C PROVID 000000 ECMPID 1A0B ANY CHID PMTPID 0062 VPID 02FF
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 try to start new filter for caid: 098C, provid: 000000, pid: 1A0B
2015/02/01 12:38:58 640708 c DEVICE open (/dev/dvb/adapter0/demux3) fd 26
2015/02/01 12:38:58 640708 c [DVBAPI] Demuxer #0 Filter #4 started successfully (caid 098C provid 000000 pid 1A0B)