Ticket #4859: log.txt

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