Ticket #4859: log (3).txt

File log (3).txt, 47.0 KB (added by haydos, 5 months ago)
Line 
12021/07/16 13:22:26 41E0716C c (dvbapi) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
22021/07/16 13:22:26 41E0716C c (dvbapi) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98
32021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0958 PROVID 000000 ECMPID 05D8 FAKECHID 0394 (unique part in ecm)
42021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 0958 PROVID 000000 FD 14)
52021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 14
62021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 requesting same ecm again (previous result was not found!)
72021/07/16 13:22:26 41E0716C c (ecm) get cw for ecm:
82021/07/16 13:22:26 41E0716C c (ecm) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
92021/07/16 13:22:26 41E0716C c (ecm) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
102021/07/16 13:22:26 41E0716C c (ecm) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
112021/07/16 13:22:26 41E0716C c (ecm) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
122021/07/16 13:22:26 41E0716C c (ecm) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
132021/07/16 13:22:26 41E0716C c (ecm) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
142021/07/16 13:22:26 41E0716C c (ecm) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98
152021/07/16 13:22:26 41E0716C c (ecm) request_cw stage=3 to reader SkyNZ2 ecm hash=254DFE9B52D1A32D9294197C952C01EB
162021/07/16 13:22:26 41E0716C c (work) start reader thread action 5
172021/07/16 13:22:26 41E0716C c (main) starting thread client work
182021/07/16 13:22:26 41E0716C c (main) client work thread started
192021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 request controlword for ecm 0958@000000/0394/04E3/6E:254DFE9B52D1A32D9294197C952C01EB
202021/07/16 13:22:26 4F228365 r (work) data from add_job action=5 client r SkyNZ2
212021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
222021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
232021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] write to cardreader
242021/07/16 13:22:26 4F228365 r (reader) D1 40 00 80 56 00 7D 13 1C 15 E8 1B 3C A4 90 32
252021/07/16 13:22:26 4F228365 r (reader) 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E C0 02 25
262021/07/16 13:22:26 4F228365 r (reader) E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E 33
272021/07/16 13:22:26 4F228365 r (reader) 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04 8F
282021/07/16 13:22:26 4F228365 r (reader) 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90 B0 D5 7A
292021/07/16 13:22:26 4F228365 r (reader) 65 1A AA 2E DE E1 3E E0 25 00 98
302021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 14 ETU (72 us)
312021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit size 5 bytes, expected len 1 bytes, delay 0 us, timeout=72 us
322021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit:
332021/07/16 13:22:26 4F228365 r (reader) D1 40 00 80 56
342021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Write timeout 72 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
352021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Sending:
362021/07/16 13:22:26 4F228365 r (reader) D1 40 00 80 56
372021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit succesful
382021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
392021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
402021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
412021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Receiving:
422021/07/16 13:22:26 4F228365 r (reader) 40
432021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive succesful
442021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 14 ETU (72 us)
452021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit size 86 bytes, expected len 2 bytes, delay 0 us, timeout=72 us
462021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit:
472021/07/16 13:22:26 4F228365 r (reader) 00 7D 13 1C 15 E8 1B 3C A4 90 32 8F 00 00 CC AB
482021/07/16 13:22:26 4F228365 r (reader) 62 7D FF 5C 07 AC 90 3E C0 02 25 E4 37 88 DE F4
492021/07/16 13:22:26 4F228365 r (reader) 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E 79 2E 09 8F
502021/07/16 13:22:26 4F228365 r (reader) 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48 9D 6C B2 36
512021/07/16 13:22:26 4F228365 r (reader) 30 6B 40 D6 26 04 86 90 B0 D5 7A 65 1A AA 2E DE
522021/07/16 13:22:26 4F228365 r (reader) E1 3E E0 25 00 98
532021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Write timeout 72 us, write delay 0 us, to send 86 char(s), chunksize 86 char(s)
542021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Sending:
552021/07/16 13:22:26 4F228365 r (reader) 00 7D 13 1C 15 E8 1B 3C A4 90 32 8F 00 00 CC AB
562021/07/16 13:22:26 4F228365 r (reader) 62 7D FF 5C 07 AC 90 3E C0 02 25 E4 37 88 DE F4
572021/07/16 13:22:26 4F228365 r (reader) 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E 79 2E 09 8F
582021/07/16 13:22:26 4F228365 r (reader) 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48 9D 6C B2 36
592021/07/16 13:22:26 4F228365 r (reader) 30 6B 40 D6 26 04 86 90 B0 D5 7A 65 1A AA 2E DE
602021/07/16 13:22:26 4F228365 r (reader) E1 3E E0 25 00 98
612021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit succesful
622021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
632021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
642021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
652021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Receiving:
662021/07/16 13:22:26 4F228365 r (reader) 90
672021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive succesful
682021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] TRACE: SW1: 90
692021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
702021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
712021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
722021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Receiving:
732021/07/16 13:22:26 4F228365 r (reader) 80
742021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive succesful
752021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] TRACE: SW2: 80
762021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] Answer from cardreader:
772021/07/16 13:22:26 4F228365 r (reader) 90 80
782021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] write to cardreader
792021/07/16 13:22:26 4F228365 r (reader) D3 54 00 00 5E
802021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 14 ETU (72 us)
812021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit size 5 bytes, expected len 97 bytes, delay 0 us, timeout=72 us
822021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit:
832021/07/16 13:22:26 4F228365 r (reader) D3 54 00 00 5E
842021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Write timeout 72 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
852021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Sending:
862021/07/16 13:22:26 4F228365 r (reader) D3 54 00 00 5E
872021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Transmit succesful
882021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
892021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
902021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
912021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Receiving:
922021/07/16 13:22:26 4F228365 r (reader) 54
932021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive succesful
942021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
952021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive size 94 bytes, delay 0 us, timeout=1190400 us
962021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 94 char(s), chunksize 94 char(s)
972021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Receiving:
982021/07/16 13:22:26 4F228365 r (reader) 0D C2 33 AA 9D 06 29 CC EE FD AD 25 F3 CA 40 70
992021/07/16 13:22:26 4F228365 r (reader) BF F7 4A CD 15 72 59 F0 FB C8 07 9D F4 4F C7 25
1002021/07/16 13:22:26 4F228365 r (reader) E2 C3 63 12 DA 9A D9 F6 C1 64 C2 00 70 3E 41 39
1012021/07/16 13:22:26 4F228365 r (reader) 66 92 74 AC 7C 0B D8 4B C3 12 BD CE 6F 49 4F E6
1022021/07/16 13:22:26 4F228365 r (reader) 2F BB E1 CB 86 C8 28 57 9A AA 62 93 DF D5 97 27
1032021/07/16 13:22:26 4F228365 r (reader) 80 01 69 A9 3F E8 4F 65 AB 26 A8 52 1D BE
1042021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive succesful
1052021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
1062021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
1072021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
1082021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Receiving:
1092021/07/16 13:22:26 4F228365 r (reader) 90
1102021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive succesful
1112021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] TRACE: SW1: 90
1122021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Setting timeout to 230400 ETU (1190400 us)
1132021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1190400 us
1142021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Read timeout 1190400 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
1152021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IO: Receiving:
1162021/07/16 13:22:26 4F228365 r (reader) 80
1172021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] IFD: Receive succesful
1182021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] TRACE: SW2: 80
1192021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] Answer from cardreader:
1202021/07/16 13:22:26 4F228365 r (reader) 0D C2 33 AA 9D 06 29 CC EE FD AD 25 F3 CA 40 70
1212021/07/16 13:22:26 4F228365 r (reader) BF F7 4A CD 15 72 59 F0 FB C8 07 9D F4 4F C7 25
1222021/07/16 13:22:26 4F228365 r (reader) E2 C3 63 12 DA 9A D9 F6 C1 64 C2 00 70 3E 41 39
1232021/07/16 13:22:26 4F228365 r (reader) 66 92 74 AC 7C 0B D8 4B C3 12 BD CE 6F 49 4F E6
1242021/07/16 13:22:26 4F228365 r (reader) 2F BB E1 CB 86 C8 28 57 9A AA 62 93 DF D5 97 27
1252021/07/16 13:22:26 4F228365 r (reader) 80 01 69 A9 3F E8 4F 65 AB 26 A8 52 1D BE 90 80
1262021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] Decrypted payload
1272021/07/16 13:22:26 4F228365 r (reader) 00 00 00 00 00 00 00 00 00 00 0F A6 0F 00 22 02
1282021/07/16 13:22:26 4F228365 r (reader) 00 00 0E 02 00 00 0F 06 00 40 00 00 00 03 20 04
1292021/07/16 13:22:26 4F228365 r (reader) 00 00 00 00 25 11 00 00 00 00 00 00 00 00 00 00
1302021/07/16 13:22:26 4F228365 r (reader) 00 00 00 00 00 00 00 2A 04 0F A6 00 00 55 01 28
1312021/07/16 13:22:26 4F228365 r (reader) 56 08 00 00 00 00 00 00 00 00 2B 02 00 00
1322021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] classD3 ins54: no cw --> Card needs pin
1332021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_do_ecm: after csystem->do_ecm rc=0
1342021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_do_ecm: ret rc=0
1352021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=0 (ERROR=0)
1362021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] Error processing ecm for caid 0958, provid 000000, srvid 04E3, servicename: SKY BOX SETS HD
1372021/07/16 13:22:26 4F228365 r (work) start client thread action 32
1382021/07/16 13:22:26 4F228365 r (main) starting thread client work
1392021/07/16 13:22:26 4F228365 r (main) client work thread started
1402021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] TRACE: ecm answer for ecm hash 254DFE9B52D1A32D9294197C952C01EB rc=4
1412021/07/16 13:22:26 41E0716C c (work) data from add_job action=32 client c anonymous
1422021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 no control word received for PID 1 CAID 0958 PROVID 000000 ECMPID 05D8 CHID 0394 VPID 03F8
1432021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] ecm hash: 254DFE9B52D1A32D9294197C952C01EB real time: 41 ms
1442021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 requested controlword for ecm 254DFE9B52D1A32D9294197C952C01EB on fd 14
1452021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 not interested in response ecmhash 254DFE9B52D1A32D9294197C952C01EB (delivered cw is empty!)
1462021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 14 api: 0, caid: 0958, provid: 000000, ecmpid: 05D8)
1472021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:14)
1482021/07/16 13:22:26 41E0716C c (ecm) anonymous (0958@000000/0394/04E3/6E:254DFE9B52D1A32D9294197C952C01EB:0F06004000000003:): not found (42 ms) by SkyNZ2 - SKY BOX SETS HD
1492021/07/16 13:22:26 41E0716C c (ecm) cw:
1502021/07/16 13:22:26 41E0716C c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1512021/07/16 13:22:26 00000000 (work) start reader thread action 13
1522021/07/16 13:22:26 00000000 (main) starting thread client work
1532021/07/16 13:22:26 00000000 (main) client work thread started
1542021/07/16 13:22:26 4F228365 r (work) data from add_job action=13 client r SkyNZ2
1552021/07/16 13:22:26 00000000 (main) starting thread client work
1562021/07/16 13:22:26 00000000 (main) client work thread started
1572021/07/16 13:22:26 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
1582021/07/16 13:22:26 0209D91A c (work) add client job action 26 queue length 1 008
1592021/07/16 13:22:26 0209D91A c (cccam) ccc idle 008
1602021/07/16 13:22:26 0209D91A c (cccam) cccam: send:
1612021/07/16 13:22:26 0209D91A c (cccam) 56 06 00 00
1622021/07/16 13:22:26 41E0716C c (dvbapi) New events occurred on 1 of 3 handlers after 502 ms inactivity
1632021/07/16 13:22:26 41E0716C c (dvbapi) Now handling fd 14 that reported event 32
1642021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 of max 0 emmfilters
1652021/07/16 13:22:26 41E0716C c (chk) caid 096A not found in caidlist reader SkyNZ2
1662021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 ignore ecmpid 0 096A@000000:0574:10000 (no matching reader)
1672021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 prio ecmpid 1 0958@000000:05D8:10000 weight: 1 (1 readers)
1682021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 prio ecmpid 1 0958@000000:05D8 weight: 2 (found caid/provid in cache)
1692021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 sorting the ecmpids took 1 ms
1702021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 1 disabled ecmpids!
1712021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0958 PROVID 000000 ECMPID 05D8 ANY CHID PMTPID 013C VPID 03F8
1722021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 try to start new filter for caid: 0958, provid: 000000, pid: 05D8
1732021/07/16 13:22:26 41E0716C c (dvbapi) Open device /dev/dvb/adapter0/demux0 (fd 14)
1742021/07/16 13:22:26 41E0716C c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 0958 provid 000000 pid 05D8)
1752021/07/16 13:22:26 438F132B c (work) add client job action 26 queue length 1 007
1762021/07/16 13:22:26 438F132B c (cccam) ccc idle 007
1772021/07/16 13:22:26 438F132B c (cccam) cccam: send:
1782021/07/16 13:22:26 438F132B c (cccam) AC 06 00 00
1792021/07/16 13:22:27 00000000 (work) start client thread action 31
1802021/07/16 13:22:27 00000000 (main) starting thread client work
1812021/07/16 13:22:27 00000000 (main) client work thread started
1822021/07/16 13:22:27 41E0716C c (work) data from add_job action=31 client c anonymous
1832021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 of max 0 emmfilters
1842021/07/16 13:22:27 41E0716C c (dvbapi) New events occurred on 1 of 3 handlers after 501 ms inactivity
1852021/07/16 13:22:27 41E0716C c (dvbapi) Now handling fd 14 that reported event 3
1862021/07/16 13:22:27 41E0716C c (dvbapi) Received:
1872021/07/16 13:22:27 41E0716C c (dvbapi) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
1882021/07/16 13:22:27 41E0716C c (dvbapi) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
1892021/07/16 13:22:27 41E0716C c (dvbapi) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
1902021/07/16 13:22:27 41E0716C c (dvbapi) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
1912021/07/16 13:22:27 41E0716C c (dvbapi) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
1922021/07/16 13:22:27 41E0716C c (dvbapi) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
1932021/07/16 13:22:27 41E0716C c (dvbapi) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98 80 70
1942021/07/16 13:22:27 41E0716C c (dvbapi) 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85 21 80
1952021/07/16 13:22:27 41E0716C c (dvbapi) 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90
1962021/07/16 13:22:27 41E0716C c (dvbapi) 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E C0 02
1972021/07/16 13:22:27 41E0716C c (dvbapi) 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E
1982021/07/16 13:22:27 41E0716C c (dvbapi) 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04
1992021/07/16 13:22:27 41E0716C c (dvbapi) 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90 B0 D5
2002021/07/16 13:22:27 41E0716C c (dvbapi) 7A 65 1A AA 2E DE E1 3E E0 25 00 98 80 70 6B 00
2012021/07/16 13:22:27 41E0716C c (dvbapi) 00 01 11 62 10 0A C9 03 94 AA 55 85 21 80 01 00
2022021/07/16 13:22:27 41E0716C c (dvbapi) 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F
2032021/07/16 13:22:27 41E0716C c (dvbapi) 00 00 CC AB 62 7D FF 5C 07 AC 90 3E C0 02 25 E4
2042021/07/16 13:22:27 41E0716C c (dvbapi) 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E
2052021/07/16 13:22:27 41E0716C c (dvbapi) 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48
2062021/07/16 13:22:27 41E0716C c (dvbapi) 9D 6C B2 36 30 6B 40 D6 26 04 86 90 B0 D5 7A 65
2072021/07/16 13:22:27 41E0716C c (dvbapi) 1A AA 2E DE E1 3E E0 25 00 98 80 70 6B 00 00 01
2082021/07/16 13:22:27 41E0716C c (dvbapi) 11 62 10 0A C9 03 94 AA 55 85 21 80 01 00 01 25
2092021/07/16 13:22:27 41E0716C c (dvbapi) 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F 00 00
2102021/07/16 13:22:27 41E0716C c (dvbapi) CC AB 62 7D FF 5C 07 AC 90 3E C0 02 25 E4 37 88
2112021/07/16 13:22:27 41E0716C c (dvbapi) DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E 79 2E
2122021/07/16 13:22:27 41E0716C c (dvbapi) 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48 9D 6C
2132021/07/16 13:22:27 41E0716C c (dvbapi) B2 36 30 6B 40 D6 26 04 86 90 B0 D5 7A 65 1A AA
2142021/07/16 13:22:27 41E0716C c (dvbapi) 2E DE E1 3E E0 25 00 98 80 70 6B 00 00 01 11 62
2152021/07/16 13:22:27 41E0716C c (dvbapi) 10 0A C9 03 94 AA 55 85 21 80 01 00 01 25 00 3A
2162021/07/16 13:22:27 41E0716C c (dvbapi) 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F 00 00 CC AB
2172021/07/16 13:22:27 41E0716C c (dvbapi) 62 7D FF 5C 07 AC 90 3E C0 02 25 E4 37 88 DE F4
2182021/07/16 13:22:27 41E0716C c (dvbapi) 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E 79 2E 09 8F
2192021/07/16 13:22:27 41E0716C c (dvbapi) 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48 9D 6C B2 36
2202021/07/16 13:22:27 41E0716C c (dvbapi) 30 6B 40 D6 26 04 86 90 B0 D5 7A 65 1A AA 2E DE
2212021/07/16 13:22:27 41E0716C c (dvbapi) E1 3E E0 25 00 98
2222021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = F0, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2232021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x06E):
2242021/07/16 13:22:27 41E0716C c (dvbapi) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
2252021/07/16 13:22:27 41E0716C c (dvbapi) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
2262021/07/16 13:22:27 41E0716C c (dvbapi) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
2272021/07/16 13:22:27 41E0716C c (dvbapi) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
2282021/07/16 13:22:27 41E0716C c (dvbapi) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
2292021/07/16 13:22:27 41E0716C c (dvbapi) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
2302021/07/16 13:22:27 41E0716C c (dvbapi) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98
2312021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0958 PROVID 000000 ECMPID 05D8 FAKECHID 0394 (unique part in ecm)
2322021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 0958 PROVID 000000 FD 14)
2332021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 14
2342021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 requesting same ecm again (previous result was not found!)
2352021/07/16 13:22:27 41E0716C c (ecm) get cw for ecm:
2362021/07/16 13:22:27 41E0716C c (ecm) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
2372021/07/16 13:22:27 41E0716C c (ecm) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
2382021/07/16 13:22:27 41E0716C c (ecm) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
2392021/07/16 13:22:27 41E0716C c (ecm) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
2402021/07/16 13:22:27 41E0716C c (ecm) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
2412021/07/16 13:22:27 41E0716C c (ecm) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
2422021/07/16 13:22:27 41E0716C c (ecm) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98
2432021/07/16 13:22:27 41E0716C c (ecm) request_cw stage=3 to reader SkyNZ2 ecm hash=254DFE9B52D1A32D9294197C952C01EB
2442021/07/16 13:22:27 41E0716C c (work) start reader thread action 5
2452021/07/16 13:22:27 41E0716C c (main) starting thread client work
2462021/07/16 13:22:27 41E0716C c (main) client work thread started
2472021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 request controlword for ecm 0958@000000/0394/04E3/6E:254DFE9B52D1A32D9294197C952C01EB
2482021/07/16 13:22:27 4F228365 r (work) data from add_job action=5 client r SkyNZ2
2492021/07/16 13:22:27 4F228365 r (work) start client thread action 32
2502021/07/16 13:22:27 4F228365 r (main) starting thread client work
2512021/07/16 13:22:27 4F228365 r (main) client work thread started
2522021/07/16 13:22:27 41E0716C c (work) data from add_job action=32 client c anonymous
2532021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 no control word received for PID 1 CAID 0958 PROVID 000000 ECMPID 05D8 CHID 0394 VPID 03F8
2542021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 requested controlword for ecm 254DFE9B52D1A32D9294197C952C01EB on fd 14
2552021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 not interested in response ecmhash 254DFE9B52D1A32D9294197C952C01EB (delivered cw is empty!)
2562021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 14 api: 0, caid: 0958, provid: 000000, ecmpid: 05D8)
2572021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:14)
2582021/07/16 13:22:27 41E0716C c (ecm) anonymous (0958@000000/0394/04E3/6E:254DFE9B52D1A32D9294197C952C01EB:0F06004000000003:): not found (1 ms) by SkyNZ2 - SKY BOX SETS HD
2592021/07/16 13:22:27 41E0716C c (ecm) cw:
2602021/07/16 13:22:27 41E0716C c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2612021/07/16 13:22:27 5D56CD77 c (work) add client job action 26 queue length 1 020
2622021/07/16 13:22:27 5D56CD77 c (cccam) ccc idle 020
2632021/07/16 13:22:27 5D56CD77 c (cccam) cccam: send:
2642021/07/16 13:22:27 5D56CD77 c (cccam) D2 06 00 00
2652021/07/16 13:22:27 23677ACB h (webif) WebIf: Origin checked. Result: access from 192.168.20.142 => allowed
2662021/07/16 13:22:27 00000000 (work) start reader thread action 13
2672021/07/16 13:22:27 00000000 (main) starting thread client work
2682021/07/16 13:22:27 00000000 (main) client work thread started
2692021/07/16 13:22:27 4F228365 r (work) data from add_job action=13 client r SkyNZ2
2702021/07/16 13:22:27 4E67FDFF c (work) add client job action 26 queue length 1 009
2712021/07/16 13:22:27 4E67FDFF c (cccam) ccc idle 009
2722021/07/16 13:22:27 4E67FDFF c (cccam) cccam: send:
2732021/07/16 13:22:27 4E67FDFF c (cccam) C1 06 00 00
2742021/07/16 13:22:27 00000000 (main) starting thread client work
2752021/07/16 13:22:27 00000000 (main) client work thread started
2762021/07/16 13:22:27 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2772021/07/16 13:22:27 41E0716C c (dvbapi) New events occurred on 1 of 3 handlers after 503 ms inactivity
2782021/07/16 13:22:27 41E0716C c (dvbapi) Now handling fd 14 that reported event 32
2792021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 of max 0 emmfilters
2802021/07/16 13:22:27 41E0716C c (chk) caid 096A not found in caidlist reader SkyNZ2
2812021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 ignore ecmpid 0 096A@000000:0574:10000 (no matching reader)
2822021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 prio ecmpid 1 0958@000000:05D8:10000 weight: 1 (1 readers)
2832021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 prio ecmpid 1 0958@000000:05D8 weight: 2 (found caid/provid in cache)
2842021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 sorting the ecmpids took 0 ms
2852021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 1 disabled ecmpids!
2862021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0958 PROVID 000000 ECMPID 05D8 ANY CHID PMTPID 013C VPID 03F8
2872021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 try to start new filter for caid: 0958, provid: 000000, pid: 05D8
2882021/07/16 13:22:27 41E0716C c (dvbapi) Open device /dev/dvb/adapter0/demux0 (fd 14)
2892021/07/16 13:22:27 41E0716C c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 0958 provid 000000 pid 05D8)
2902021/07/16 13:22:28 28257801 c (work) add client job action 26 queue length 1 003
2912021/07/16 13:22:28 28257801 c (cccam) ccc idle 003
2922021/07/16 13:22:28 28257801 c (cccam) cccam: send:
2932021/07/16 13:22:28 28257801 c (cccam) B8 06 00 00
2942021/07/16 13:22:28 00000000 (work) start client thread action 31
2952021/07/16 13:22:28 00000000 (main) starting thread client work
2962021/07/16 13:22:28 00000000 (main) client work thread started
2972021/07/16 13:22:28 41E0716C c (work) data from add_job action=31 client c anonymous
2982021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 of max 0 emmfilters
2992021/07/16 13:22:28 41E0716C c (dvbapi) New events occurred on 1 of 3 handlers after 501 ms inactivity
3002021/07/16 13:22:28 41E0716C c (dvbapi) Now handling fd 14 that reported event 3
3012021/07/16 13:22:28 41E0716C c (dvbapi) Received:
3022021/07/16 13:22:28 41E0716C c (dvbapi) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
3032021/07/16 13:22:28 41E0716C c (dvbapi) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
3042021/07/16 13:22:28 41E0716C c (dvbapi) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
3052021/07/16 13:22:28 41E0716C c (dvbapi) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
3062021/07/16 13:22:28 41E0716C c (dvbapi) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
3072021/07/16 13:22:28 41E0716C c (dvbapi) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
3082021/07/16 13:22:28 41E0716C c (dvbapi) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98 80 70
3092021/07/16 13:22:28 41E0716C c (dvbapi) 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85 21 80
3102021/07/16 13:22:28 41E0716C c (dvbapi) 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90
3112021/07/16 13:22:28 41E0716C c (dvbapi) 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E C0 02
3122021/07/16 13:22:28 41E0716C c (dvbapi) 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E
3132021/07/16 13:22:28 41E0716C c (dvbapi) 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04
3142021/07/16 13:22:28 41E0716C c (dvbapi) 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90 B0 D5
3152021/07/16 13:22:28 41E0716C c (dvbapi) 7A 65 1A AA 2E DE E1 3E E0 25 00 98 80 70 6B 00
3162021/07/16 13:22:28 41E0716C c (dvbapi) 00 01 11 62 10 0A C9 03 94 AA 55 85 21 80 01 00
3172021/07/16 13:22:28 41E0716C c (dvbapi) 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F
3182021/07/16 13:22:28 41E0716C c (dvbapi) 00 00 CC AB 62 7D FF 5C 07 AC 90 3E C0 02 25 E4
3192021/07/16 13:22:28 41E0716C c (dvbapi) 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E
3202021/07/16 13:22:28 41E0716C c (dvbapi) 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48
3212021/07/16 13:22:28 41E0716C c (dvbapi) 9D 6C B2 36 30 6B 40 D6 26 04 86 90 B0 D5 7A 65
3222021/07/16 13:22:28 41E0716C c (dvbapi) 1A AA 2E DE E1 3E E0 25 00 98 80 70 6B 00 00 01
3232021/07/16 13:22:28 41E0716C c (dvbapi) 11 62 10 0A C9 03 94 AA 55 85 21 80 01 00 01 25
3242021/07/16 13:22:28 41E0716C c (dvbapi) 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F 00 00
3252021/07/16 13:22:28 41E0716C c (dvbapi) CC AB 62 7D FF 5C 07 AC 90 3E C0 02 25 E4 37 88
3262021/07/16 13:22:28 41E0716C c (dvbapi) DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E 79 2E
3272021/07/16 13:22:28 41E0716C c (dvbapi) 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48 9D 6C
3282021/07/16 13:22:28 41E0716C c (dvbapi) B2 36 30 6B 40 D6 26 04 86 90 B0 D5 7A 65 1A AA
3292021/07/16 13:22:28 41E0716C c (dvbapi) 2E DE E1 3E E0 25 00 98 80 70 6B 00 00 01 11 62
3302021/07/16 13:22:28 41E0716C c (dvbapi) 10 0A C9 03 94 AA 55 85 21 80 01 00 01 25 00 3A
3312021/07/16 13:22:28 41E0716C c (dvbapi) 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F 00 00 CC AB
3322021/07/16 13:22:28 41E0716C c (dvbapi) 62 7D FF 5C 07 AC 90 3E C0 02 25 E4 37 88 DE F4
3332021/07/16 13:22:28 41E0716C c (dvbapi) 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E 79 2E 09 8F
3342021/07/16 13:22:28 41E0716C c (dvbapi) 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48 9D 6C B2 36
3352021/07/16 13:22:28 41E0716C c (dvbapi) 30 6B 40 D6 26 04 86 90 B0 D5 7A 65 1A AA 2E DE
3362021/07/16 13:22:28 41E0716C c (dvbapi) E1 3E E0 25 00 98
3372021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = F0, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
3382021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x06E):
3392021/07/16 13:22:28 41E0716C c (dvbapi) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
3402021/07/16 13:22:28 41E0716C c (dvbapi) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
3412021/07/16 13:22:28 41E0716C c (dvbapi) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
3422021/07/16 13:22:28 41E0716C c (dvbapi) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
3432021/07/16 13:22:28 41E0716C c (dvbapi) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
3442021/07/16 13:22:28 41E0716C c (dvbapi) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
3452021/07/16 13:22:28 41E0716C c (dvbapi) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98
3462021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0958 PROVID 000000 ECMPID 05D8 FAKECHID 0394 (unique part in ecm)
3472021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 0958 PROVID 000000 FD 14)
3482021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 14
3492021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 requesting same ecm again (previous result was not found!)
3502021/07/16 13:22:28 41E0716C c (ecm) get cw for ecm:
3512021/07/16 13:22:28 41E0716C c (ecm) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
3522021/07/16 13:22:28 41E0716C c (ecm) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
3532021/07/16 13:22:28 41E0716C c (ecm) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
3542021/07/16 13:22:28 41E0716C c (ecm) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
3552021/07/16 13:22:28 41E0716C c (ecm) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
3562021/07/16 13:22:28 41E0716C c (ecm) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
3572021/07/16 13:22:28 41E0716C c (ecm) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98
3582021/07/16 13:22:28 41E0716C c (ecm) request_cw stage=3 to reader SkyNZ2 ecm hash=254DFE9B52D1A32D9294197C952C01EB
3592021/07/16 13:22:28 41E0716C c (work) start reader thread action 5
3602021/07/16 13:22:28 41E0716C c (main) starting thread client work
3612021/07/16 13:22:28 41E0716C c (main) client work thread started
3622021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 request controlword for ecm 0958@000000/0394/04E3/6E:254DFE9B52D1A32D9294197C952C01EB
3632021/07/16 13:22:28 4F228365 r (work) data from add_job action=5 client r SkyNZ2
3642021/07/16 13:22:28 4F228365 r (work) start client thread action 32
3652021/07/16 13:22:28 4F228365 r (main) starting thread client work
3662021/07/16 13:22:28 4F228365 r (main) client work thread started
3672021/07/16 13:22:28 41E0716C c (work) data from add_job action=32 client c anonymous
3682021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 no control word received for PID 1 CAID 0958 PROVID 000000 ECMPID 05D8 CHID 0394 VPID 03F8
3692021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 requested controlword for ecm 254DFE9B52D1A32D9294197C952C01EB on fd 14
3702021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 not interested in response ecmhash 254DFE9B52D1A32D9294197C952C01EB (delivered cw is empty!)
3712021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 14 api: 0, caid: 0958, provid: 000000, ecmpid: 05D8)
3722021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:14)
3732021/07/16 13:22:28 41E0716C c (ecm) anonymous (0958@000000/0394/04E3/6E:254DFE9B52D1A32D9294197C952C01EB:0F06004000000003:): not found (1 ms) by SkyNZ2 - SKY BOX SETS HD
3742021/07/16 13:22:28 41E0716C c (ecm) cw:
3752021/07/16 13:22:28 41E0716C c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
3762021/07/16 13:22:28 00000000 (work) start reader thread action 13
3772021/07/16 13:22:28 00000000 (main) starting thread client work
3782021/07/16 13:22:28 00000000 (main) client work thread started
3792021/07/16 13:22:28 4F228365 r (work) data from add_job action=13 client r SkyNZ2
3802021/07/16 13:22:28 00000000 (main) starting thread client work
3812021/07/16 13:22:28 00000000 (main) client work thread started
3822021/07/16 13:22:28 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
3832021/07/16 13:22:28 41E0716C c (dvbapi) New events occurred on 1 of 3 handlers after 502 ms inactivity
3842021/07/16 13:22:28 41E0716C c (dvbapi) Now handling fd 14 that reported event 32
3852021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 of max 0 emmfilters
3862021/07/16 13:22:28 41E0716C c (chk) caid 096A not found in caidlist reader SkyNZ2
3872021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 ignore ecmpid 0 096A@000000:0574:10000 (no matching reader)
3882021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 prio ecmpid 1 0958@000000:05D8:10000 weight: 1 (1 readers)
3892021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 prio ecmpid 1 0958@000000:05D8 weight: 2 (found caid/provid in cache)
3902021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 sorting the ecmpids took 1 ms
3912021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 1 disabled ecmpids!
3922021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0958 PROVID 000000 ECMPID 05D8 ANY CHID PMTPID 013C VPID 03F8
3932021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 try to start new filter for caid: 0958, provid: 000000, pid: 05D8
3942021/07/16 13:22:28 41E0716C c (dvbapi) Open device /dev/dvb/adapter0/demux0 (fd 14)
3952021/07/16 13:22:28 41E0716C c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 0958 provid 000000 pid 05D8)
3962021/07/16 13:22:28 23677ACB h (webif) WebIf: Origin checked. Result: access from 192.168.20.142 => allowed
3972021/07/16 13:22:28 725FD3BD c (work) add client job action 26 queue length 1 019
3982021/07/16 13:22:28 725FD3BD c (cccam) ccc idle 019
3992021/07/16 13:22:28 725FD3BD c (cccam) cccam: send:
4002021/07/16 13:22:28 725FD3BD c (cccam) E3 06 00 00
4012021/07/16 13:22:28 17BA66EE c (work) add client job action 26 queue length 1 016
4022021/07/16 13:22:28 17BA66EE c (cccam) ccc idle 016
4032021/07/16 13:22:28 17BA66EE c (cccam) cccam: send:
4042021/07/16 13:22:28 17BA66EE c (cccam) 41 06 00 00
4052021/07/16 13:22:29 316B3A7B c (work) add client job action 26 queue length 1 018
4062021/07/16 13:22:29 316B3A7B c (cccam) ccc idle 018
4072021/07/16 13:22:29 316B3A7B c (cccam) cccam: send:
4082021/07/16 13:22:29 316B3A7B c (cccam) 82 06 00 00
4092021/07/16 13:22:29 00000000 (work) start client thread action 31
4102021/07/16 13:22:29 00000000 (main) starting thread client work
4112021/07/16 13:22:29 00000000 (main) client work thread started
4122021/07/16 13:22:29 41E0716C c (work) data from add_job action=31 client c anonymous
4132021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 has 2 ecmpids, 3 streampids, 1 ecmfilters and 0 of max 0 emmfilters
4142021/07/16 13:22:29 41E0716C c (dvbapi) New events occurred on 1 of 3 handlers after 502 ms inactivity
4152021/07/16 13:22:29 41E0716C c (dvbapi) Now handling fd 14 that reported event 3
4162021/07/16 13:22:29 41E0716C c (dvbapi) Received:
4172021/07/16 13:22:29 41E0716C c (dvbapi) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
4182021/07/16 13:22:29 41E0716C c (dvbapi) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
4192021/07/16 13:22:29 41E0716C c (dvbapi) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
4202021/07/16 13:22:29 41E0716C c (dvbapi) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
4212021/07/16 13:22:29 41E0716C c (dvbapi) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
4222021/07/16 13:22:29 41E0716C c (dvbapi) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
4232021/07/16 13:22:29 41E0716C c (dvbapi) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98 80 70
4242021/07/16 13:22:29 41E0716C c (dvbapi) 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85 21 80
4252021/07/16 13:22:29 41E0716C c (dvbapi) 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90
4262021/07/16 13:22:29 41E0716C c (dvbapi) 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E C0 02
4272021/07/16 13:22:29 41E0716C c (dvbapi) 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E
4282021/07/16 13:22:29 41E0716C c (dvbapi) 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04
4292021/07/16 13:22:29 41E0716C c (dvbapi) 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90 B0 D5
4302021/07/16 13:22:29 41E0716C c (dvbapi) 7A 65 1A AA 2E DE E1 3E E0 25 00 98 80 70 6B 00
4312021/07/16 13:22:29 41E0716C c (dvbapi) 00 01 11 62 10 0A C9 03 94 AA 55 85 21 80 01 00
4322021/07/16 13:22:29 41E0716C c (dvbapi) 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F
4332021/07/16 13:22:29 41E0716C c (dvbapi) 00 00 CC AB 62 7D FF 5C 07 AC 90 3E C0 02 25 E4
4342021/07/16 13:22:29 41E0716C c (dvbapi) 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E
4352021/07/16 13:22:29 41E0716C c (dvbapi) 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48
4362021/07/16 13:22:29 41E0716C c (dvbapi) 9D 6C B2 36 30 6B 40 D6 26 04 86 90 B0 D5 7A 65
4372021/07/16 13:22:29 41E0716C c (dvbapi) 1A AA 2E DE E1 3E E0 25 00 98 80 70 6B 00 00 01
4382021/07/16 13:22:29 41E0716C c (dvbapi) 11 62 10 0A C9 03 94 AA 55 85 21 80 01 00 01 25
4392021/07/16 13:22:29 41E0716C c (dvbapi) 00 3A 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F 00 00
4402021/07/16 13:22:29 41E0716C c (dvbapi) CC AB 62 7D FF 5C 07 AC 90 3E C0 02 25 E4 37 88
4412021/07/16 13:22:29 41E0716C c (dvbapi) DE F4 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E 79 2E
4422021/07/16 13:22:29 41E0716C c (dvbapi) 09 8F 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48 9D 6C
4432021/07/16 13:22:29 41E0716C c (dvbapi) B2 36 30 6B 40 D6 26 04 86 90 B0 D5 7A 65 1A AA
4442021/07/16 13:22:29 41E0716C c (dvbapi) 2E DE E1 3E E0 25 00 98 80 70 6B 00 00 01 11 62
4452021/07/16 13:22:29 41E0716C c (dvbapi) 10 0A C9 03 94 AA 55 85 21 80 01 00 01 25 00 3A
4462021/07/16 13:22:29 41E0716C c (dvbapi) 55 7D 13 1C 15 E8 1B 3C A4 90 32 8F 00 00 CC AB
4472021/07/16 13:22:29 41E0716C c (dvbapi) 62 7D FF 5C 07 AC 90 3E C0 02 25 E4 37 88 DE F4
4482021/07/16 13:22:29 41E0716C c (dvbapi) 09 12 F1 C5 9F B2 5A 1F 9D 4E 33 4E 79 2E 09 8F
4492021/07/16 13:22:29 41E0716C c (dvbapi) 10 32 EB 56 AB 57 D9 A8 F8 04 8F 48 9D 6C B2 36
4502021/07/16 13:22:29 41E0716C c (dvbapi) 30 6B 40 D6 26 04 86 90 B0 D5 7A 65 1A AA 2E DE
4512021/07/16 13:22:29 41E0716C c (dvbapi) E1 3E E0 25 00 98
4522021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = F0, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
4532021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x06E):
4542021/07/16 13:22:29 41E0716C c (dvbapi) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
4552021/07/16 13:22:29 41E0716C c (dvbapi) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
4562021/07/16 13:22:29 41E0716C c (dvbapi) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
4572021/07/16 13:22:29 41E0716C c (dvbapi) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
4582021/07/16 13:22:29 41E0716C c (dvbapi) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
4592021/07/16 13:22:29 41E0716C c (dvbapi) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
4602021/07/16 13:22:29 41E0716C c (dvbapi) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98
4612021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0958 PROVID 000000 ECMPID 05D8 FAKECHID 0394 (unique part in ecm)
4622021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 0958 PROVID 000000 FD 14)
4632021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 14
4642021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 requesting same ecm again (previous result was not found!)
4652021/07/16 13:22:29 41E0716C c (ecm) get cw for ecm:
4662021/07/16 13:22:29 41E0716C c (ecm) 80 70 6B 00 00 01 11 62 10 0A C9 03 94 AA 55 85
4672021/07/16 13:22:29 41E0716C c (ecm) 21 80 01 00 01 25 00 3A 55 7D 13 1C 15 E8 1B 3C
4682021/07/16 13:22:29 41E0716C c (ecm) A4 90 32 8F 00 00 CC AB 62 7D FF 5C 07 AC 90 3E
4692021/07/16 13:22:29 41E0716C c (ecm) C0 02 25 E4 37 88 DE F4 09 12 F1 C5 9F B2 5A 1F
4702021/07/16 13:22:29 41E0716C c (ecm) 9D 4E 33 4E 79 2E 09 8F 10 32 EB 56 AB 57 D9 A8
4712021/07/16 13:22:29 41E0716C c (ecm) F8 04 8F 48 9D 6C B2 36 30 6B 40 D6 26 04 86 90
4722021/07/16 13:22:29 41E0716C c (ecm) B0 D5 7A 65 1A AA 2E DE E1 3E E0 25 00 98
4732021/07/16 13:22:29 41E0716C c (ecm) request_cw stage=3 to reader SkyNZ2 ecm hash=254DFE9B52D1A32D9294197C952C01EB
4742021/07/16 13:22:29 41E0716C c (work) start reader thread action 5
4752021/07/16 13:22:29 41E0716C c (main) starting thread client work
4762021/07/16 13:22:29 41E0716C c (main) client work thread started
4772021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 request controlword for ecm 0958@000000/0394/04E3/6E:254DFE9B52D1A32D9294197C952C01EB
4782021/07/16 13:22:29 4F228365 r (work) data from add_job action=5 client r SkyNZ2
4792021/07/16 13:22:29 4F228365 r (work) start client thread action 32
4802021/07/16 13:22:29 4F228365 r (main) starting thread client work
4812021/07/16 13:22:29 4F228365 r (main) client work thread started
4822021/07/16 13:22:29 41E0716C c (work) data from add_job action=32 client c anonymous
4832021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 no control word received for PID 1 CAID 0958 PROVID 000000 ECMPID 05D8 CHID 0394 VPID 03F8
4842021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 requested controlword for ecm 254DFE9B52D1A32D9294197C952C01EB on fd 14
4852021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 not interested in response ecmhash 254DFE9B52D1A32D9294197C952C01EB (delivered cw is empty!)
4862021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 14 api: 0, caid: 0958, provid: 000000, ecmpid: 05D8)
4872021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 flushing stale input data of filter 1 (fd:14)
4882021/07/16 13:22:29 41E0716C c (ecm) anonymous (0958@000000/0394/04E3/6E:254DFE9B52D1A32D9294197C952C01EB:0F06004000000003:): not found (2 ms) by SkyNZ2 - SKY BOX SETS HD
4892021/07/16 13:22:29 41E0716C c (ecm) cw:
4902021/07/16 13:22:29 41E0716C c (ecm) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
4912021/07/16 13:22:29 00000000 (work) start reader thread action 13
4922021/07/16 13:22:29 00000000 (main) starting thread client work
4932021/07/16 13:22:29 00000000 (main) client work thread started
4942021/07/16 13:22:29 4F228365 r (work) data from add_job action=13 client r SkyNZ2
4952021/07/16 13:22:29 00000000 (main) starting thread client work
4962021/07/16 13:22:29 00000000 (main) client work thread started
4972021/07/16 13:22:29 4F228365 r (reader) SkyNZ2 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
4982021/07/16 13:22:29 41E0716C c (dvbapi) New events occurred on 1 of 3 handlers after 502 ms inactivity
4992021/07/16 13:22:29 41E0716C c (dvbapi) Now handling fd 14 that reported event 32
5002021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 has 2 ecmpids, 3 streampids, 0 ecmfilters and 0 of max 0 emmfilters
5012021/07/16 13:22:29 41E0716C c (chk) caid 096A not found in caidlist reader SkyNZ2
5022021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 ignore ecmpid 0 096A@000000:0574:10000 (no matching reader)
5032021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 prio ecmpid 1 0958@000000:05D8:10000 weight: 1 (1 readers)
5042021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 prio ecmpid 1 0958@000000:05D8 weight: 2 (found caid/provid in cache)
5052021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 sorting the ecmpids took 0 ms
5062021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 restarting decoding requests after 0 ms with 1 enabled and 1 disabled ecmpids!
5072021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 0958 PROVID 000000 ECMPID 05D8 ANY CHID PMTPID 013C VPID 03F8
5082021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 try to start new filter for caid: 0958, provid: 000000, pid: 05D8
5092021/07/16 13:22:29 41E0716C c (dvbapi) Open device /dev/dvb/adapter0/demux0 (fd 14)
5102021/07/16 13:22:29 41E0716C c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 0958 provid 000000 pid 05D8)
5112021/07/16 13:22:30 23677ACB h (webif) WebIf: Received stale header from 192.168.20.142 (nonce=3fb965c6076b7014b6bd250aa0cd40cd, expectednonce=32f32ea55bcad10e1103c729a02e4b7e, opaque=9e62b01314a7073f484ef244724c3258).
5122021/07/16 13:22:30 23677ACB h (webif) WebIf: Origin checked. Result: access from 192.168.20.142 => allowed