Показать сообщение отдельно
Старый 20.05.2021, 22:05 #122
edv7 edv7 вне форума
Junior Member
 
Регистрация: 10.05.2020
Сообщений: 29
Спасибо: 14
Поблагодарили 2 в 1 сообщении
3
По умолчанию

-------------------------------------------------------------------------------
>> OSCam << cardserver log switched at Thu May 20 21:03:45 2021
-------------------------------------------------------------------------------
2021/05/20 21:03:45 2EF330A5 h >> OSCam << cardserver log switched, version 1.20_svn, build r11668-798 (arm-gcc49-linux-gnueabihf-ssl)
2021/05/20 21:03:45 2EF330A5 h (webif) WebIf: Origin checked. Result: access from 192.168.178.133 => allowed
2021/05/20 21:03:45 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity
2021/05/20 21:03:45 35F38746 c (dvbapi) Now handling fd 50 that reported event 3
2021/05/20 21:03:45 35F38746 c (dvbapi) Received:
2021/05/20 21:03:45 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C4 A0 00 E4 7C
2021/05/20 21:03:45 35F38746 c (dvbapi) 00 5B 56 50 9D 9C 3B C7 04 F4 4C 0C 00 19 00 00
2021/05/20 21:03:45 35F38746 c (dvbapi) 00 FA 77 4A 04 D7 CD 36 D7 97 C4 A3 62 33 BC DB
2021/05/20 21:03:45 35F38746 c (dvbapi) E0 7F 4D 46 B9 90 92 74 53 C2 46 51 5B 6B 5E 2C
2021/05/20 21:03:45 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2021/05/20 21:03:45 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):
2021/05/20 21:03:45 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C4 A0 00 E4 7C
2021/05/20 21:03:45 35F38746 c (dvbapi) 00 5B 56 50 9D 9C 3B C7 04 F4 4C 0C 00 19 00 00
2021/05/20 21:03:45 35F38746 c (dvbapi) 00 FA 77 4A 04 D7 CD 36 D7 97 C4 A3 62 33 BC DB
2021/05/20 21:03:45 35F38746 c (dvbapi) E0 7F 4D 46 B9 90 92 74 53 C2 46 51 5B 6B 5E 2C
2021/05/20 21:03:45 00000000 (work) start reader thread action 13
2021/05/20 21:03:45 00000000 (main) starting thread client work
2021/05/20 21:03:45 00000000 (main) client work thread started
2021/05/20 21:03:45 669C4893 r (work) data from add_job action=13 client r emulator
2021/05/20 21:03:46 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity
2021/05/20 21:03:46 35F38746 c (dvbapi) Now handling fd 50 that reported event 3
2021/05/20 21:03:46 35F38746 c (dvbapi) Received:
2021/05/20 21:03:46 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2
2021/05/20 21:03:46 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00
2021/05/20 21:03:46 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0
2021/05/20 21:03:46 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF
2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):
2021/05/20 21:03:46 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2
2021/05/20 21:03:46 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00
2021/05/20 21:03:46 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0
2021/05/20 21:03:46 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF
2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)
2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to EVEN (CAID 0E00 PROVID 000000 FD 50)
2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 50
2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 get controlword!
2021/05/20 21:03:46 35F38746 c (ecm) get cw for ecm:
2021/05/20 21:03:46 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2
2021/05/20 21:03:46 35F38746 c (ecm) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00
2021/05/20 21:03:46 35F38746 c (ecm) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0
2021/05/20 21:03:46 35F38746 c (ecm) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF
2021/05/20 21:03:46 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!
2021/05/20 21:03:46 35F38746 c (chk) trying reader 'emulator' filter 0E00@000000
2021/05/20 21:03:46 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@000000
2021/05/20 21:03:46 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters
2021/05/20 21:03:46 35F38746 c (chk) r-filter reader shara
2021/05/20 21:03:46 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 0
2021/05/20 21:03:46 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 1
2021/05/20 21:03:46 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=5976E3C88020077B94CF7AE7A82E40C1
2021/05/20 21:03:46 35F38746 c (work) start reader thread action 5
2021/05/20 21:03:46 35F38746 c (main) starting thread client work
2021/05/20 21:03:46 35F38746 c (main) client work thread started
2021/05/20 21:03:46 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 1
2021/05/20 21:03:46 669C4893 r (work) data from add_job action=5 client r emulator
2021/05/20 21:03:46 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2021/05/20 21:03:46 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2021/05/20 21:03:46 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:5976E3C88020077B94CF7AE7A82E40C1
2021/05/20 21:03:46 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2
2021/05/20 21:03:46 35F38746 c (ecm) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00
2021/05/20 21:03:46 35F38746 c (ecm) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0
2021/05/20 21:03:46 35F38746 c (ecm) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF
2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:5976E3C88020077B94CF7AE7A82E40C1
2021/05/20 21:03:46 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 0
2021/05/20 21:03:46 669C4893 r (emu) channel hash: 00000000, group id: 0000

Скрытый текст

2021/05/20 21:03:46 669C4893 r (emu) calculated cw 0: 89C245026E8CF89B2021/05/20 21:03:46 669C4893 r (emu) calculated cw 1: B9B01945EC0B6D7F2021/05/20 21:03:46 669C4893 r (emu) calculated cw 2: B97F08AD3DF88F072021/05/20 21:03:46 669C4893 r (emu) calculated cw 3: B97F08AD3DF88F072021/05/20 21:03:46 669C4893 r (emu) calculated cw 4: B97F08AD3DF88F072021/05/20 21:03:46 669C4893 r (emu) calculated cw 5: B97F08AD3DF88F072021/05/20 21:03:46 669C4893 r (emu) calculated cw 6: 45DF8967E3EC15FD2021/05/20 21:03:46 669C4893 r (emu) calculated cw 7: 8020B9D92FC2E6762021/05/20 21:03:46 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:46 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:46 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:46 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:46 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:46 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (2 ms)2021/05/20 21:03:46 669C4893 r (work) start client thread action 322021/05/20 21:03:46 669C4893 r (main) starting thread client work2021/05/20 21:03:46 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:46 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:46 669C4893 r (main) client work thread started2021/05/20 21:03:46 35F38746 c (ecm) Client test csphash 7F797F20 cw 000000000000000089C245026E8CF89B rc 0 0E00@000000/0000/0190/40:5976E3C88020077B94CF7AE7A82E40C12021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:46 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 5976E3C88020077B94CF7AE7A82E40C1 rc=02021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm 5976E3C88020077B94CF7AE7A82E40C1 on fd 502021/05/20 21:03:46 669C4893 r (reader) emulator [emu] ecm hash: 5976E3C88020077B94CF7AE7A82E40C1 real time: 1 ms2021/05/20 21:03:46 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:46 35F38746 c (dvbapi) delayer: gone=3 ms, cfg=60 ms -> delay=57 ms2021/05/20 21:03:46 00000000 (work) add client job action 31 queue length 1 test2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write even part of cw!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 writing even part (89C245026E8CF89B) of controlword, replacing expired (B04C46647AA464B9)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 0 (ca0)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write even part of cw!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 writing even part (B97F08AD3DF88F07) of controlword, replacing expired (D5CE296B1A893D2F)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 1 (ca0)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write even part of cw!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 writing even part (B97F08AD3DF88F07) of controlword, replacing expired (D5CE296B1A893D2F)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 2 (ca0)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write even part of cw!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 writing even part (B97F08AD3DF88F07) of controlword, replacing expired (D5CE296B1A893D2F)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 3 (ca0)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write even part of cw!2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 writing even part (B97F08AD3DF88F07) of controlword, replacing expired (D5CE296B1A893D2F)2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 4 (ca0)2021/05/20 21:03:46 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:46 35F38746 c (ecm) test (0E00@000000/0000/0190/40:5976E3C88020077B94CF7AE7A82E40C1): found (2 ms) by emulator2021/05/20 21:03:46 35F38746 c (ecm) cw:2021/05/20 21:03:46 35F38746 c (ecm) 00 00 00 00 00 00 00 00 89 C2 45 02 6E 8C F8 9B 2021/05/20 21:03:46 00000000 (work) start client thread action 312021/05/20 21:03:46 00000000 (main) starting thread client work2021/05/20 21:03:46 00000000 (main) client work thread started2021/05/20 21:03:46 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:46 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:46 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:46 35F38746 c (dvbapi) Received:2021/05/20 21:03:46 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C5 A0 00 2F 47 2021/05/20 21:03:46 35F38746 c (dvbapi) DB 0A 04 65 33 DE 9F B8 FF 50 6B C0 00 19 00 00 2021/05/20 21:03:46 35F38746 c (dvbapi) 00 9A B0 F0 C3 12 B6 F8 EB 3A F5 A2 93 9C 9E A7 2021/05/20 21:03:46 35F38746 c (dvbapi) 3A 1C 86 66 A2 43 33 22 FC 1D 09 B6 FE 94 E9 AE 2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:46 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C5 A0 00 2F 47 2021/05/20 21:03:46 35F38746 c (dvbapi) DB 0A 04 65 33 DE 9F B8 FF 50 6B C0 00 19 00 00 2021/05/20 21:03:46 35F38746 c (dvbapi) 00 9A B0 F0 C3 12 B6 F8 EB 3A F5 A2 93 9C 9E A7 2021/05/20 21:03:46 35F38746 c (dvbapi) 3A 1C 86 66 A2 43 33 22 FC 1D 09 B6 FE 94 E9 AE 2021/05/20 21:03:46 00000000 (main) starting thread client work2021/05/20 21:03:46 00000000 (main) client work thread started2021/05/20 21:03:46 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:46 00000000 (cccam) share-updater check2021/05/20 21:03:46 00000000 (cccam) share-updater mode=initfast t=1s i=242021/05/20 21:03:46 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:46 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:46 35F38746 c (dvbapi) Received:2021/05/20 21:03:46 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C5 A0 00 2F 47 2021/05/20 21:03:46 35F38746 c (dvbapi) DB 0A 04 65 33 DE 9F B8 FF 50 6B C0 00 19 00 00 2021/05/20 21:03:46 35F38746 c (dvbapi) 00 9A B0 F0 C3 12 B6 F8 EB 3A F5 A2 93 9C 9E A7 2021/05/20 21:03:46 35F38746 c (dvbapi) 3A 1C 86 66 A2 43 33 22 FC 1D 09 B6 FE 94 E9 AE 2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:46 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C5 A0 00 2F 47 2021/05/20 21:03:46 35F38746 c (dvbapi) DB 0A 04 65 33 DE 9F B8 FF 50 6B C0 00 19 00 00 2021/05/20 21:03:46 35F38746 c (dvbapi) 00 9A B0 F0 C3 12 B6 F8 EB 3A F5 A2 93 9C 9E A7 2021/05/20 21:03:46 35F38746 c (dvbapi) 3A 1C 86 66 A2 43 33 22 FC 1D 09 B6 FE 94 E9 AE 2021/05/20 21:03:46 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:46 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:46 35F38746 c (dvbapi) Received:2021/05/20 21:03:46 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C5 A0 00 2F 47 2021/05/20 21:03:46 35F38746 c (dvbapi) DB 0A 04 65 33 DE 9F B8 FF 50 6B C0 00 19 00 00 2021/05/20 21:03:46 35F38746 c (dvbapi) 00 9A B0 F0 C3 12 B6 F8 EB 3A F5 A2 93 9C 9E A7 2021/05/20 21:03:46 35F38746 c (dvbapi) 3A 1C 86 66 A2 43 33 22 FC 1D 09 B6 FE 94 E9 AE 2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:46 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C5 A0 00 2F 47 2021/05/20 21:03:46 35F38746 c (dvbapi) DB 0A 04 65 33 DE 9F B8 FF 50 6B C0 00 19 00 00 2021/05/20 21:03:46 35F38746 c (dvbapi) 00 9A B0 F0 C3 12 B6 F8 EB 3A F5 A2 93 9C 9E A7 2021/05/20 21:03:46 35F38746 c (dvbapi) 3A 1C 86 66 A2 43 33 22 FC 1D 09 B6 FE 94 E9 AE 2021/05/20 21:03:46 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:46 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:46 35F38746 c (dvbapi) Received:2021/05/20 21:03:46 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C5 A0 00 2F 47 2021/05/20 21:03:46 35F38746 c (dvbapi) DB 0A 04 65 33 DE 9F B8 FF 50 6B C0 00 19 00 00 2021/05/20 21:03:46 35F38746 c (dvbapi) 00 9A B0 F0 C3 12 B6 F8 EB 3A F5 A2 93 9C 9E A7 2021/05/20 21:03:46 35F38746 c (dvbapi) 3A 1C 86 66 A2 43 33 22 FC 1D 09 B6 FE 94 E9 AE 2021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:46 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:46 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C5 A0 00 2F 47 2021/05/20 21:03:46 35F38746 c (dvbapi) DB 0A 04 65 33 DE 9F B8 FF 50 6B C0 00 19 00 00 2021/05/20 21:03:46 35F38746 c (dvbapi) 00 9A B0 F0 C3 12 B6 F8 EB 3A F5 A2 93 9C 9E A7 2021/05/20 21:03:46 35F38746 c (dvbapi) 3A 1C 86 66 A2 43 33 22 FC 1D 09 B6 FE 94 E9 AE 2021/05/20 21:03:46 00000000 (work) start reader thread action 132021/05/20 21:03:46 00000000 (main) starting thread client work2021/05/20 21:03:46 00000000 (main) client work thread started2021/05/20 21:03:46 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:47 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:47 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:47 00000000 (work) start client thread action 312021/05/20 21:03:47 00000000 (main) starting thread client work2021/05/20 21:03:47 00000000 (main) client work thread started2021/05/20 21:03:47 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:47 35F38746 c (dvbapi) Received:2021/05/20 21:03:47 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:47 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:47 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:47 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:47 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:47 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to ODD (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:47 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:47 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:47 35F38746 c (ecm) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:47 35F38746 c (ecm) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:47 35F38746 c (ecm) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:47 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:47 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:47 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:47 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:47 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:47 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:47 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:47 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=DDF6C68249FE7F969C1A5CBE779E0AC12021/05/20 21:03:47 35F38746 c (work) start reader thread action 52021/05/20 21:03:47 35F38746 c (main) starting thread client work2021/05/20 21:03:47 35F38746 c (main) client work thread started2021/05/20 21:03:47 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:47 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:47 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:DDF6C68249FE7F969C1A5CBE779E0AC12021/05/20 21:03:47 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:47 35F38746 c (ecm) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:47 35F38746 c (ecm) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:47 35F38746 c (ecm) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:DDF6C68249FE7F969C1A5CBE779E0AC12021/05/20 21:03:47 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:47 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:47 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:47 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:47 669C4893 r (emu) calculated cw 0: E907D619762013152021/05/20 21:03:47 669C4893 r (emu) calculated cw 1: 077F7F6EB957D9522021/05/20 21:03:47 669C4893 r (emu) calculated cw 2: 37BF4061524A15E02021/05/20 21:03:47 669C4893 r (emu) calculated cw 3: 37BF4061524A15E02021/05/20 21:03:47 669C4893 r (emu) calculated cw 4: 37BF4061524A15E02021/05/20 21:03:47 669C4893 r (emu) calculated cw 5: 37BF4061524A15E02021/05/20 21:03:47 669C4893 r (emu) calculated cw 6: E90BDFBFD620735E2021/05/20 21:03:47 669C4893 r (emu) calculated cw 7: CE239775C41337132021/05/20 21:03:47 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:47 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:47 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:47 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:47 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:47 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (1 ms)2021/05/20 21:03:47 669C4893 r (work) start client thread action 322021/05/20 21:03:47 669C4893 r (main) starting thread client work2021/05/20 21:03:47 669C4893 r (main) client work thread started2021/05/20 21:03:47 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:47 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:47 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash DDF6C68249FE7F969C1A5CBE779E0AC1 rc=02021/05/20 21:03:47 669C4893 r (reader) emulator [emu] ecm hash: DDF6C68249FE7F969C1A5CBE779E0AC1 real time: 0 ms2021/05/20 21:03:47 35F38746 c (ecm) Client test csphash 4FD93B3A cw E907D619762013150000000000000000 rc 0 0E00@000000/0000/0190/40:DDF6C68249FE7F969C1A5CBE779E0AC12021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm DDF6C68249FE7F969C1A5CBE779E0AC1 on fd 502021/05/20 21:03:47 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:47 35F38746 c (dvbapi) delayer: gone=2 ms, cfg=60 ms -> delay=58 ms2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write odd part of cw!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 writing odd part (E907D61976201315) of controlword, replacing expired (E6E0DF4980E9986D)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 0 (ca0)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write odd part of cw!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 writing odd part (37BF4061524A15E0) of controlword, replacing expired (19233BCB0BDA7908)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 1 (ca0)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write odd part of cw!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 writing odd part (37BF4061524A15E0) of controlword, replacing expired (19233BCB0BDA7908)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 2 (ca0)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write odd part of cw!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 writing odd part (37BF4061524A15E0) of controlword, replacing expired (19233BCB0BDA7908)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 3 (ca0)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write odd part of cw!2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 writing odd part (37BF4061524A15E0) of controlword, replacing expired (19233BCB0BDA7908)2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 4 (ca0)2021/05/20 21:03:47 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:47 35F38746 c (ecm) test (0E00@000000/0000/0190/40:DDF6C68249FE7F969C1A5CBE779E0AC1): found (1 ms) by emulator2021/05/20 21:03:47 35F38746 c (ecm) cw:2021/05/20 21:03:47 35F38746 c (ecm) E9 07 D6 19 76 20 13 15 00 00 00 00 00 00 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:47 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:47 35F38746 c (dvbapi) Received:2021/05/20 21:03:47 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2 2021/05/20 21:03:47 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0 2021/05/20 21:03:47 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF 2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:47 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2 2021/05/20 21:03:47 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0 2021/05/20 21:03:47 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF 2021/05/20 21:03:47 00000000 (main) starting thread client work2021/05/20 21:03:47 00000000 (main) client work thread started2021/05/20 21:03:47 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:47 00000000 (cccam) share-updater check2021/05/20 21:03:47 00000000 (cccam) share-updater mode=initfast t=1s i=232021/05/20 21:03:47 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:47 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:47 35F38746 c (dvbapi) Received:2021/05/20 21:03:47 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2 2021/05/20 21:03:47 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0 2021/05/20 21:03:47 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF 2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:47 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2 2021/05/20 21:03:47 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0 2021/05/20 21:03:47 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF 2021/05/20 21:03:47 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:47 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:47 35F38746 c (dvbapi) Received:2021/05/20 21:03:47 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2 2021/05/20 21:03:47 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0 2021/05/20 21:03:47 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF 2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:47 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2 2021/05/20 21:03:47 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0 2021/05/20 21:03:47 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF 2021/05/20 21:03:47 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:47 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:47 35F38746 c (dvbapi) Received:2021/05/20 21:03:47 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2 2021/05/20 21:03:47 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0 2021/05/20 21:03:47 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF 2021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:47 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:47 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C6 A0 00 FF F2 2021/05/20 21:03:47 35F38746 c (dvbapi) 0C 04 D0 E8 44 CD E2 06 CD 7B 68 6F 00 19 00 00 2021/05/20 21:03:47 35F38746 c (dvbapi) 00 60 D8 1B 8F 6E F0 83 E1 A8 F1 62 2F 5E 7A A0 2021/05/20 21:03:47 35F38746 c (dvbapi) 71 A0 8C 8A 45 33 0E 9E 85 39 61 76 F1 C4 C8 AF 2021/05/20 21:03:47 00000000 (work) start reader thread action 132021/05/20 21:03:47 00000000 (main) starting thread client work2021/05/20 21:03:47 00000000 (main) client work thread started2021/05/20 21:03:47 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:48 00000000 (work) start client thread action 312021/05/20 21:03:48 00000000 (main) starting thread client work2021/05/20 21:03:48 00000000 (main) client work thread started2021/05/20 21:03:48 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:48 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:48 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:48 35F38746 c (dvbapi) Received:2021/05/20 21:03:48 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:48 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:48 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:48 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:48 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:48 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to EVEN (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:48 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:48 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:48 35F38746 c (ecm) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:48 35F38746 c (ecm) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:48 35F38746 c (ecm) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:48 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:48 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:48 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:48 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:48 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:48 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:48 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:48 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=905BCC34E37F3FC21714090C0A71EB912021/05/20 21:03:48 35F38746 c (work) start reader thread action 52021/05/20 21:03:48 35F38746 c (main) starting thread client work2021/05/20 21:03:48 35F38746 c (main) client work thread started2021/05/20 21:03:48 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:48 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:48 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:905BCC34E37F3FC21714090C0A71EB912021/05/20 21:03:48 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:48 35F38746 c (ecm) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:48 35F38746 c (ecm) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:48 35F38746 c (ecm) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:905BCC34E37F3FC21714090C0A71EB912021/05/20 21:03:48 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:48 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:48 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:48 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:48 669C4893 r (emu) calculated cw 0: 370EAB19D5E0B3152021/05/20 21:03:48 669C4893 r (emu) calculated cw 1: C79852E6CB680DDA2021/05/20 21:03:48 669C4893 r (emu) calculated cw 2: A1027C9BC7EA75162021/05/20 21:03:48 669C4893 r (emu) calculated cw 3: A1027C9BC7EA75162021/05/20 21:03:48 669C4893 r (emu) calculated cw 4: A1027C9BC7EA75162021/05/20 21:03:48 669C4893 r (emu) calculated cw 5: A1027C9BC7EA75162021/05/20 21:03:48 669C4893 r (emu) calculated cw 6: 58DA341AA29D1AE52021/05/20 21:03:48 669C4893 r (emu) calculated cw 7: D3735770BAC75DF22021/05/20 21:03:48 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:48 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:48 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:48 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:48 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:48 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (2 ms)2021/05/20 21:03:48 669C4893 r (work) start client thread action 322021/05/20 21:03:48 669C4893 r (main) starting thread client work2021/05/20 21:03:48 669C4893 r (main) client work thread started2021/05/20 21:03:48 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:48 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:48 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 905BCC34E37F3FC21714090C0A71EB91 rc=02021/05/20 21:03:48 669C4893 r (reader) emulator [emu] ecm hash: 905BCC34E37F3FC21714090C0A71EB91 real time: 0 ms2021/05/20 21:03:48 35F38746 c (ecm) Client test csphash 04C1848F cw 0000000000000000370EAB19D5E0B315 rc 0 0E00@000000/0000/0190/40:905BCC34E37F3FC21714090C0A71EB912021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm 905BCC34E37F3FC21714090C0A71EB91 on fd 502021/05/20 21:03:48 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:48 35F38746 c (dvbapi) delayer: gone=2 ms, cfg=60 ms -> delay=58 ms2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write even part of cw!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 writing even part (370EAB19D5E0B315) of controlword, replacing expired (89C245026E8CF89B)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 0 (ca0)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write even part of cw!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 writing even part (A1027C9BC7EA7516) of controlword, replacing expired (B97F08AD3DF88F07)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 1 (ca0)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write even part of cw!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 writing even part (A1027C9BC7EA7516) of controlword, replacing expired (B97F08AD3DF88F07)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 2 (ca0)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write even part of cw!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 writing even part (A1027C9BC7EA7516) of controlword, replacing expired (B97F08AD3DF88F07)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 3 (ca0)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write even part of cw!2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 writing even part (A1027C9BC7EA7516) of controlword, replacing expired (B97F08AD3DF88F07)2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 4 (ca0)2021/05/20 21:03:48 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:48 35F38746 c (ecm) test (0E00@000000/0000/0190/40:905BCC34E37F3FC21714090C0A71EB91): found (2 ms) by emulator2021/05/20 21:03:48 35F38746 c (ecm) cw:2021/05/20 21:03:48 35F38746 c (ecm) 00 00 00 00 00 00 00 00 37 0E AB 19 D5 E0 B3 15 2021/05/20 21:03:48 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:48 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:48 35F38746 c (dvbapi) Received:2021/05/20 21:03:48 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:48 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:48 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:48 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:48 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:48 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:48 00000000 (main) starting thread client work2021/05/20 21:03:48 00000000 (main) client work thread started2021/05/20 21:03:48 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:48 00000000 (cccam) share-updater check2021/05/20 21:03:48 00000000 (cccam) share-updater mode=initfast t=1s i=222021/05/20 21:03:48 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:48 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:48 35F38746 c (dvbapi) Received:2021/05/20 21:03:48 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:48 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:48 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:48 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:48 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:48 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:48 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:48 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:48 35F38746 c (dvbapi) Received:2021/05/20 21:03:48 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:48 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:48 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:48 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:48 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:48 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:48 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:48 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:48 35F38746 c (dvbapi) Received:2021/05/20 21:03:48 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:48 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:48 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:48 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:48 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C7 A0 00 0F 72 2021/05/20 21:03:48 35F38746 c (dvbapi) F4 E7 18 CA A7 1C AD EE 8E 1B 08 23 00 19 00 00 2021/05/20 21:03:48 35F38746 c (dvbapi) 00 E5 05 4F 67 0C 73 50 B0 64 40 97 E4 78 C0 AB 2021/05/20 21:03:48 35F38746 c (dvbapi) 4D 56 C9 FC 59 65 62 BF 2C 99 34 45 DC FE EC 8A 2021/05/20 21:03:48 00000000 (work) start reader thread action 132021/05/20 21:03:48 00000000 (main) starting thread client work2021/05/20 21:03:48 00000000 (main) client work thread started2021/05/20 21:03:48 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:49 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 199 ms inactivity2021/05/20 21:03:49 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:49 35F38746 c (dvbapi) Received:2021/05/20 21:03:49 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:49 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:49 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:49 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:49 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:49 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to ODD (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:49 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:49 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:49 35F38746 c (ecm) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:49 35F38746 c (ecm) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:49 35F38746 c (ecm) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:49 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:49 00000000 (work) start client thread action 312021/05/20 21:03:49 00000000 (main) starting thread client work2021/05/20 21:03:49 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:49 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:49 00000000 (main) client work thread started2021/05/20 21:03:49 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:49 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:49 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:49 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:49 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:49 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=20DB91E1DEDC088D22C90C4A9DDA5D742021/05/20 21:03:49 35F38746 c (work) start reader thread action 52021/05/20 21:03:49 35F38746 c (main) starting thread client work2021/05/20 21:03:49 35F38746 c (main) client work thread started2021/05/20 21:03:49 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:49 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:49 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:49 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:49 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:20DB91E1DEDC088D22C90C4A9DDA5D742021/05/20 21:03:49 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:49 35F38746 c (ecm) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:49 35F38746 c (ecm) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:49 35F38746 c (ecm) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:49 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:49 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:20DB91E1DEDC088D22C90C4A9DDA5D742021/05/20 21:03:49 669C4893 r (emu) calculated cw 0: 4CA88C07AB582FA72021/05/20 21:03:49 669C4893 r (emu) calculated cw 1: 4686C1023B0B5BC72021/05/20 21:03:49 669C4893 r (emu) calculated cw 2: 700BC86D19A2C2F82021/05/20 21:03:49 669C4893 r (emu) calculated cw 3: 700BC86D19A2C2F82021/05/20 21:03:49 669C4893 r (emu) calculated cw 4: 700BC86D19A2C2F82021/05/20 21:03:49 669C4893 r (emu) calculated cw 5: 700BC86D19A2C2F82021/05/20 21:03:49 669C4893 r (emu) calculated cw 6: 6740433E580762D52021/05/20 21:03:49 669C4893 r (emu) calculated cw 7: 345D1679CD9786702021/05/20 21:03:49 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:49 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:49 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:49 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:49 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:49 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (2 ms)2021/05/20 21:03:49 669C4893 r (work) start client thread action 322021/05/20 21:03:49 669C4893 r (main) starting thread client work2021/05/20 21:03:49 669C4893 r (main) client work thread started2021/05/20 21:03:49 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:49 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:49 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 20DB91E1DEDC088D22C90C4A9DDA5D74 rc=02021/05/20 21:03:49 669C4893 r (reader) emulator [emu] ecm hash: 20DB91E1DEDC088D22C90C4A9DDA5D74 real time: 0 ms2021/05/20 21:03:49 35F38746 c (ecm) Client test csphash F07D9AB9 cw 4CA88C07AB582FA70000000000000000 rc 0 0E00@000000/0000/0190/40:20DB91E1DEDC088D22C90C4A9DDA5D742021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm 20DB91E1DEDC088D22C90C4A9DDA5D74 on fd 502021/05/20 21:03:49 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:49 35F38746 c (dvbapi) delayer: gone=2 ms, cfg=60 ms -> delay=58 ms2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write odd part of cw!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 writing odd part (4CA88C07AB582FA7) of controlword, replacing expired (E907D61976201315)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 0 (ca0)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write odd part of cw!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 writing odd part (700BC86D19A2C2F8) of controlword, replacing expired (37BF4061524A15E0)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 1 (ca0)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write odd part of cw!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 writing odd part (700BC86D19A2C2F8) of controlword, replacing expired (37BF4061524A15E0)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 2 (ca0)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write odd part of cw!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 writing odd part (700BC86D19A2C2F8) of controlword, replacing expired (37BF4061524A15E0)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 3 (ca0)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write odd part of cw!2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 writing odd part (700BC86D19A2C2F8) of controlword, replacing expired (37BF4061524A15E0)2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 4 (ca0)2021/05/20 21:03:49 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:49 35F38746 c (ecm) test (0E00@000000/0000/0190/40:20DB91E1DEDC088D22C90C4A9DDA5D74): found (2 ms) by emulator2021/05/20 21:03:49 35F38746 c (ecm) cw:2021/05/20 21:03:49 35F38746 c (ecm) 4C A8 8C 07 AB 58 2F A7 00 00 00 00 00 00 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 201 ms inactivity2021/05/20 21:03:49 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:49 35F38746 c (dvbapi) Received:2021/05/20 21:03:49 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:49 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:49 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:49 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:49 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:49 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:49 00000000 (main) starting thread client work2021/05/20 21:03:49 00000000 (main) client work thread started2021/05/20 21:03:49 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:49 00000000 (cccam) share-updater check2021/05/20 21:03:49 00000000 (cccam) share-updater mode=initfast t=1s i=212021/05/20 21:03:49 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:49 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:49 35F38746 c (dvbapi) Received:2021/05/20 21:03:49 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:49 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:49 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:49 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:49 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:49 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:49 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:49 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:49 35F38746 c (dvbapi) Received:2021/05/20 21:03:49 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:49 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:49 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:49 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:49 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:49 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:49 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:49 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:49 35F38746 c (dvbapi) Received:2021/05/20 21:03:49 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:49 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:49 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:49 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:49 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 C8 A0 00 C9 C9 2021/05/20 21:03:49 35F38746 c (dvbapi) 2F 8C 7B CB 31 60 60 83 2E EF D1 51 00 19 00 00 2021/05/20 21:03:49 35F38746 c (dvbapi) 00 1F CF 97 86 99 3F 31 B0 23 5F 4D 14 5F 96 D4 2021/05/20 21:03:49 35F38746 c (dvbapi) 95 7A F9 F6 F1 8A 1B 9E 86 43 E2 13 E6 FD 91 B0 2021/05/20 21:03:49 00000000 (work) start reader thread action 132021/05/20 21:03:49 00000000 (main) starting thread client work2021/05/20 21:03:49 00000000 (main) client work thread started2021/05/20 21:03:49 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:50 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 199 ms inactivity2021/05/20 21:03:50 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:50 35F38746 c (dvbapi) Received:2021/05/20 21:03:50 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:50 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:50 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:50 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:50 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:50 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to EVEN (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:50 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:50 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:50 35F38746 c (ecm) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:50 35F38746 c (ecm) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:50 35F38746 c (ecm) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:50 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:50 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:50 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:50 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:50 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:50 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:50 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:50 00000000 (work) start client thread action 312021/05/20 21:03:50 00000000 (main) starting thread client work2021/05/20 21:03:50 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=58E7563607B694F8858DF9804E4E0CEC2021/05/20 21:03:50 35F38746 c (work) start reader thread action 52021/05/20 21:03:50 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:50 00000000 (main) client work thread started2021/05/20 21:03:50 35F38746 c (main) starting thread client work2021/05/20 21:03:50 35F38746 c (main) client work thread started2021/05/20 21:03:50 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:50 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:50 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:50 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:50 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:50 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:50 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:58E7563607B694F8858DF9804E4E0CEC2021/05/20 21:03:50 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:50 35F38746 c (ecm) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:50 35F38746 c (ecm) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:50 35F38746 c (ecm) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:58E7563607B694F8858DF9804E4E0CEC2021/05/20 21:03:50 669C4893 r (emu) calculated cw 0: 513BBF29EAB331982021/05/20 21:03:50 669C4893 r (emu) calculated cw 1: E95B8332017532792021/05/20 21:03:50 669C4893 r (emu) calculated cw 2: C88A5E3837E6EC972021/05/20 21:03:50 669C4893 r (emu) calculated cw 3: C88A5E3837E6EC972021/05/20 21:03:50 669C4893 r (emu) calculated cw 4: C88A5E3837E6EC972021/05/20 21:03:50 669C4893 r (emu) calculated cw 5: C88A5E3837E6EC972021/05/20 21:03:50 669C4893 r (emu) calculated cw 6: F1A4F1D9E0BAC4E62021/05/20 21:03:50 669C4893 r (emu) calculated cw 7: 70A291D938BAF2E62021/05/20 21:03:50 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:50 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:50 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:50 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:50 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:50 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (2 ms)2021/05/20 21:03:50 669C4893 r (work) start client thread action 322021/05/20 21:03:50 669C4893 r (main) starting thread client work2021/05/20 21:03:50 669C4893 r (main) client work thread started2021/05/20 21:03:50 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:50 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:50 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 58E7563607B694F8858DF9804E4E0CEC rc=02021/05/20 21:03:50 669C4893 r (reader) emulator [emu] ecm hash: 58E7563607B694F8858DF9804E4E0CEC real time: 0 ms2021/05/20 21:03:50 35F38746 c (ecm) Client test csphash D63D9D9B cw 0000000000000000513BBF29EAB33198 rc 0 0E00@000000/0000/0190/40:58E7563607B694F8858DF9804E4E0CEC2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm 58E7563607B694F8858DF9804E4E0CEC on fd 502021/05/20 21:03:50 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:50 35F38746 c (dvbapi) delayer: gone=2 ms, cfg=60 ms -> delay=58 ms2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write even part of cw!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 writing even part (513BBF29EAB33198) of controlword, replacing expired (370EAB19D5E0B315)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 0 (ca0)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write even part of cw!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 writing even part (C88A5E3837E6EC97) of controlword, replacing expired (A1027C9BC7EA7516)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 1 (ca0)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write even part of cw!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 writing even part (C88A5E3837E6EC97) of controlword, replacing expired (A1027C9BC7EA7516)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 2 (ca0)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write even part of cw!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 writing even part (C88A5E3837E6EC97) of controlword, replacing expired (A1027C9BC7EA7516)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 3 (ca0)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write even part of cw!2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 writing even part (C88A5E3837E6EC97) of controlword, replacing expired (A1027C9BC7EA7516)2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 4 (ca0)2021/05/20 21:03:50 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:50 35F38746 c (ecm) test (0E00@000000/0000/0190/40:58E7563607B694F8858DF9804E4E0CEC): found (2 ms) by emulator2021/05/20 21:03:50 35F38746 c (ecm) cw:2021/05/20 21:03:50 35F38746 c (ecm) 00 00 00 00 00 00 00 00 51 3B BF 29 EA B3 31 98 2021/05/20 21:03:50 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 201 ms inactivity2021/05/20 21:03:50 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:50 35F38746 c (dvbapi) Received:2021/05/20 21:03:50 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:50 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:50 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:50 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:50 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:50 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:50 00000000 (main) starting thread client work2021/05/20 21:03:50 00000000 (main) client work thread started2021/05/20 21:03:50 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:50 00000000 (cccam) share-updater check2021/05/20 21:03:50 00000000 (cccam) share-updater mode=initfast t=1s i=202021/05/20 21:03:50 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 199 ms inactivity2021/05/20 21:03:50 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:50 35F38746 c (dvbapi) Received:2021/05/20 21:03:50 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:50 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:50 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:50 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:50 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:50 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:50 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:50 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:50 35F38746 c (dvbapi) Received:2021/05/20 21:03:50 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:50 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:50 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:50 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:50 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:50 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:50 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:50 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:50 35F38746 c (dvbapi) Received:2021/05/20 21:03:50 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:50 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:50 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:50 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:50 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 C9 A0 00 DB 70 2021/05/20 21:03:50 35F38746 c (dvbapi) 7C 7A A1 5A 67 D2 B4 EC 8A 91 D3 D4 00 19 00 00 2021/05/20 21:03:50 35F38746 c (dvbapi) 00 F8 4E 50 5D EE 5E BF F1 88 E1 46 CE B4 4C 4D 2021/05/20 21:03:50 35F38746 c (dvbapi) AC A9 D0 26 47 C7 6B 6B 24 4C 44 AF 9E 5B 4D 27 2021/05/20 21:03:50 00000000 (work) start reader thread action 132021/05/20 21:03:50 00000000 (main) starting thread client work2021/05/20 21:03:50 00000000 (main) client work thread started2021/05/20 21:03:50 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:51 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:51 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:51 35F38746 c (dvbapi) Received:2021/05/20 21:03:51 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:51 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:51 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:51 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:51 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:51 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to ODD (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:51 00000000 (work) start client thread action 312021/05/20 21:03:51 00000000 (main) starting thread client work2021/05/20 21:03:51 00000000 (main) client work thread started2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:51 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:51 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:51 35F38746 c (ecm) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:51 35F38746 c (ecm) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:51 35F38746 c (ecm) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:51 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:51 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:51 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:51 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:51 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:51 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:51 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:51 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:51 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=2215A186C6FF3F7F74BE721D2211EB872021/05/20 21:03:51 35F38746 c (work) start reader thread action 52021/05/20 21:03:51 35F38746 c (main) starting thread client work2021/05/20 21:03:51 35F38746 c (main) client work thread started2021/05/20 21:03:51 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:51 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:51 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:2215A186C6FF3F7F74BE721D2211EB872021/05/20 21:03:51 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:51 35F38746 c (ecm) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:51 35F38746 c (ecm) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:51 35F38746 c (ecm) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:51 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:51 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:2215A186C6FF3F7F74BE721D2211EB872021/05/20 21:03:51 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:51 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:51 669C4893 r (emu) calculated cw 0: F4BF49DCC4F28F5D2021/05/20 21:03:51 669C4893 r (emu) calculated cw 1: 9DF1FE1562B6F4E02021/05/20 21:03:51 669C4893 r (emu) calculated cw 2: FBE5A2FEAD8F26372021/05/20 21:03:51 669C4893 r (emu) calculated cw 3: FBE5A2FEAD8F26372021/05/20 21:03:51 669C4893 r (emu) calculated cw 4: FBE5A2FEAD8F26372021/05/20 21:03:51 669C4893 r (emu) calculated cw 5: FBE5A2FEAD8F26372021/05/20 21:03:51 669C4893 r (emu) calculated cw 6: EC97C1B3E531C71A2021/05/20 21:03:51 669C4893 r (emu) calculated cw 7: 851C5B9183799EC82021/05/20 21:03:51 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:51 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:51 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:51 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:51 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:51 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (1 ms)2021/05/20 21:03:51 669C4893 r (work) start client thread action 322021/05/20 21:03:51 669C4893 r (main) starting thread client work2021/05/20 21:03:51 669C4893 r (main) client work thread started2021/05/20 21:03:51 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:51 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:51 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 2215A186C6FF3F7F74BE721D2211EB87 rc=02021/05/20 21:03:51 669C4893 r (reader) emulator [emu] ecm hash: 2215A186C6FF3F7F74BE721D2211EB87 real time: 0 ms2021/05/20 21:03:51 35F38746 c (ecm) Client test csphash 0ED95020 cw F4BF49DCC4F28F5D0000000000000000 rc 0 0E00@000000/0000/0190/40:2215A186C6FF3F7F74BE721D2211EB872021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm 2215A186C6FF3F7F74BE721D2211EB87 on fd 502021/05/20 21:03:51 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:51 35F38746 c (dvbapi) delayer: gone=2 ms, cfg=60 ms -> delay=58 ms2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write odd part of cw!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 writing odd part (F4BF49DCC4F28F5D) of controlword, replacing expired (4CA88C07AB582FA7)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 0 (ca0)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write odd part of cw!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 writing odd part (FBE5A2FEAD8F2637) of controlword, replacing expired (700BC86D19A2C2F8)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 1 (ca0)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write odd part of cw!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 writing odd part (FBE5A2FEAD8F2637) of controlword, replacing expired (700BC86D19A2C2F8)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 2 (ca0)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write odd part of cw!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 writing odd part (FBE5A2FEAD8F2637) of controlword, replacing expired (700BC86D19A2C2F8)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 3 (ca0)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write odd part of cw!2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 writing odd part (FBE5A2FEAD8F2637) of controlword, replacing expired (700BC86D19A2C2F8)2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 4 (ca0)2021/05/20 21:03:51 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:51 35F38746 c (ecm) test (0E00@000000/0000/0190/40:2215A186C6FF3F7F74BE721D2211EB87): found (1 ms) by emulator2021/05/20 21:03:51 35F38746 c (ecm) cw:2021/05/20 21:03:51 35F38746 c (ecm) F4 BF 49 DC C4 F2 8F 5D 00 00 00 00 00 00 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:51 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:51 35F38746 c (dvbapi) Received:2021/05/20 21:03:51 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:51 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:51 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:51 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:51 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:51 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:51 00000000 (main) starting thread client work2021/05/20 21:03:51 00000000 (main) client work thread started2021/05/20 21:03:51 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:51 00000000 (cccam) share-updater check2021/05/20 21:03:51 00000000 (cccam) share-updater mode=initfast t=1s i=192021/05/20 21:03:51 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:51 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:51 35F38746 c (dvbapi) Received:2021/05/20 21:03:51 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:51 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:51 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:51 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:51 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:51 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:51 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:51 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:51 35F38746 c (dvbapi) Received:2021/05/20 21:03:51 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:51 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:51 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:51 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:51 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:51 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:51 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:51 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:51 35F38746 c (dvbapi) Received:2021/05/20 21:03:51 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:51 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:51 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:51 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:51 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CA A0 00 C9 0A 2021/05/20 21:03:51 35F38746 c (dvbapi) A7 86 28 E4 0D 98 75 8A 91 F5 E3 B8 00 19 00 00 2021/05/20 21:03:51 35F38746 c (dvbapi) 00 CF 3B C7 88 89 21 87 AB DE 21 15 3D 7C 26 C3 2021/05/20 21:03:51 35F38746 c (dvbapi) F7 63 A1 FD 7C 9C C9 28 82 B4 6D 0F 08 D4 64 CB 2021/05/20 21:03:51 00000000 (work) start reader thread action 132021/05/20 21:03:51 00000000 (main) starting thread client work2021/05/20 21:03:51 00000000 (main) client work thread started2021/05/20 21:03:51 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:52 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:52 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:52 35F38746 c (dvbapi) Received:2021/05/20 21:03:52 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:52 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:52 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:52 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:52 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:52 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to EVEN (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:52 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:52 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:52 35F38746 c (ecm) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:52 35F38746 c (ecm) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:52 35F38746 c (ecm) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:52 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:52 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:52 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:52 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:52 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:52 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:52 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:52 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=E5EA50DF13BF7030227C7BAA8A7ABC5F2021/05/20 21:03:52 35F38746 c (work) start reader thread action 52021/05/20 21:03:52 35F38746 c (main) starting thread client work2021/05/20 21:03:52 35F38746 c (main) client work thread started2021/05/20 21:03:52 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:52 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:52 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:52 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:52 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:E5EA50DF13BF7030227C7BAA8A7ABC5F2021/05/20 21:03:52 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:52 35F38746 c (ecm) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:52 35F38746 c (ecm) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:52 35F38746 c (ecm) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:E5EA50DF13BF7030227C7BAA8A7ABC5F2021/05/20 21:03:52 00000000 (work) start client thread action 312021/05/20 21:03:52 00000000 (main) starting thread client work2021/05/20 21:03:52 00000000 (main) client work thread started2021/05/20 21:03:52 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:52 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:52 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:52 669C4893 r (emu) calculated cw 0: 9EF238762351DC0E2021/05/20 21:03:52 669C4893 r (emu) calculated cw 1: F4F2F2B63401D0522021/05/20 21:03:52 669C4893 r (emu) calculated cw 2: DC1F68F7FD548F5B2021/05/20 21:03:52 669C4893 r (emu) calculated cw 3: DC1F68F7FD548F5B2021/05/20 21:03:52 669C4893 r (emu) calculated cw 4: DC1F68F7FD548F5B2021/05/20 21:03:52 669C4893 r (emu) calculated cw 5: DC1F68F7FD548F5B2021/05/20 21:03:52 669C4893 r (emu) calculated cw 6: 62A7FDCDECB570252021/05/20 21:03:52 669C4893 r (emu) calculated cw 7: 13A820CE1A340DC42021/05/20 21:03:52 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:52 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:52 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:52 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:52 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:52 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (1 ms)2021/05/20 21:03:52 669C4893 r (work) start client thread action 322021/05/20 21:03:52 669C4893 r (main) starting thread client work2021/05/20 21:03:52 669C4893 r (main) client work thread started2021/05/20 21:03:52 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:52 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:52 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash E5EA50DF13BF7030227C7BAA8A7ABC5F rc=02021/05/20 21:03:52 669C4893 r (reader) emulator [emu] ecm hash: E5EA50DF13BF7030227C7BAA8A7ABC5F real time: 0 ms2021/05/20 21:03:52 35F38746 c (ecm) Client test csphash E5DA0960 cw 00000000000000009EF238762351DC0E rc 0 0E00@000000/0000/0190/40:E5EA50DF13BF7030227C7BAA8A7ABC5F2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm E5EA50DF13BF7030227C7BAA8A7ABC5F on fd 502021/05/20 21:03:52 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:52 35F38746 c (dvbapi) delayer: gone=2 ms, cfg=60 ms -> delay=58 ms2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write even part of cw!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 writing even part (9EF238762351DC0E) of controlword, replacing expired (513BBF29EAB33198)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 0 (ca0)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write even part of cw!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 writing even part (DC1F68F7FD548F5B) of controlword, replacing expired (C88A5E3837E6EC97)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 1 (ca0)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write even part of cw!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 writing even part (DC1F68F7FD548F5B) of controlword, replacing expired (C88A5E3837E6EC97)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 2 (ca0)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write even part of cw!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 writing even part (DC1F68F7FD548F5B) of controlword, replacing expired (C88A5E3837E6EC97)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 3 (ca0)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write even part of cw!2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 writing even part (DC1F68F7FD548F5B) of controlword, replacing expired (C88A5E3837E6EC97)2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 4 (ca0)2021/05/20 21:03:52 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:52 35F38746 c (ecm) test (0E00@000000/0000/0190/40:E5EA50DF13BF7030227C7BAA8A7ABC5F): found (2 ms) by emulator2021/05/20 21:03:52 35F38746 c (ecm) cw:2021/05/20 21:03:52 35F38746 c (ecm) 00 00 00 00 00 00 00 00 9E F2 38 76 23 51 DC 0E 2021/05/20 21:03:52 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:52 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:52 35F38746 c (dvbapi) Received:2021/05/20 21:03:52 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:52 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:52 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:52 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:52 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:52 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:52 00000000 (main) starting thread client work2021/05/20 21:03:52 00000000 (main) client work thread started2021/05/20 21:03:52 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:52 00000000 (cccam) share-updater check2021/05/20 21:03:52 00000000 (cccam) share-updater mode=initfast t=1s i=182021/05/20 21:03:52 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:52 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:52 35F38746 c (dvbapi) Received:2021/05/20 21:03:52 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:52 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:52 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:52 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:52 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:52 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:52 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:52 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:52 35F38746 c (dvbapi) Received:2021/05/20 21:03:52 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:52 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:52 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:52 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:52 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:52 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:52 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:52 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:52 35F38746 c (dvbapi) Received:2021/05/20 21:03:52 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:52 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:52 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:52 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:52 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CB A0 00 85 6F 2021/05/20 21:03:52 35F38746 c (dvbapi) BC 78 5D 8B A2 BF CF 51 C1 76 3F C5 00 19 00 00 2021/05/20 21:03:52 35F38746 c (dvbapi) 00 74 3C 67 EF BB 91 68 FE 4A 34 BD A8 FE A5 CD 2021/05/20 21:03:52 35F38746 c (dvbapi) DD 02 56 5A F1 39 4C 72 AC B6 25 17 60 64 BA 1F 2021/05/20 21:03:52 00000000 (work) start reader thread action 132021/05/20 21:03:52 00000000 (main) starting thread client work2021/05/20 21:03:52 00000000 (main) client work thread started2021/05/20 21:03:52 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:53 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:53 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:53 35F38746 c (dvbapi) Received:2021/05/20 21:03:53 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:53 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:53 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:53 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:53 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:53 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to ODD (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:53 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:53 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:53 35F38746 c (ecm) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:53 35F38746 c (ecm) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:53 35F38746 c (ecm) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:53 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:53 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:53 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:53 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:53 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:53 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:53 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:53 00000000 (work) start client thread action 312021/05/20 21:03:53 00000000 (main) starting thread client work2021/05/20 21:03:53 00000000 (main) client work thread started2021/05/20 21:03:53 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:53 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=5F2F4CBDF4EBA0CAEDB726A22EDE5B242021/05/20 21:03:53 35F38746 c (work) start reader thread action 52021/05/20 21:03:53 35F38746 c (main) starting thread client work2021/05/20 21:03:53 35F38746 c (main) client work thread started2021/05/20 21:03:53 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:53 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:53 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:5F2F4CBDF4EBA0CAEDB726A22EDE5B242021/05/20 21:03:53 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:53 35F38746 c (ecm) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:53 35F38746 c (ecm) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:53 35F38746 c (ecm) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:53 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:53 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:53 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:53 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:53 669C4893 r (emu) calculated cw 0: 38BFB502577915A12021/05/20 21:03:53 669C4893 r (emu) calculated cw 1: 38B9434637792A152021/05/20 21:03:53 669C4893 r (emu) calculated cw 2: D68CF7684015545B2021/05/20 21:03:53 669C4893 r (emu) calculated cw 3: D68CF7684015545B2021/05/20 21:03:53 669C4893 r (emu) calculated cw 4: D68CF7684015545B2021/05/20 21:03:53 669C4893 r (emu) calculated cw 5: D68CF7684015545B2021/05/20 21:03:53 669C4893 r (emu) calculated cw 6: 327A9204A72537912021/05/20 21:03:53 669C4893 r (emu) calculated cw 7: 89BA7C759DF838E62021/05/20 21:03:53 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:53 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:53 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:53 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:53 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:53 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (2 ms)2021/05/20 21:03:53 669C4893 r (work) start client thread action 322021/05/20 21:03:53 669C4893 r (main) starting thread client work2021/05/20 21:03:53 669C4893 r (main) client work thread started2021/05/20 21:03:53 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:53 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:53 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 5F2F4CBDF4EBA0CAEDB726A22EDE5B24 rc=02021/05/20 21:03:53 669C4893 r (reader) emulator [emu] ecm hash: 5F2F4CBDF4EBA0CAEDB726A22EDE5B24 real time: 1 ms2021/05/20 21:03:53 35F38746 c (ecm) Client test csphash 7B4789EF cw 38BFB502577915A10000000000000000 rc 0 0E00@000000/0000/0190/40:5F2F4CBDF4EBA0CAEDB726A22EDE5B242021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm 5F2F4CBDF4EBA0CAEDB726A22EDE5B24 on fd 502021/05/20 21:03:53 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:53 35F38746 c (dvbapi) delayer: gone=2 ms, cfg=60 ms -> delay=58 ms2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:5F2F4CBDF4EBA0CAEDB726A22EDE5B242021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write odd part of cw!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 writing odd part (38BFB502577915A1) of controlword, replacing expired (F4BF49DCC4F28F5D)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 0 (ca0)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write odd part of cw!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 writing odd part (D68CF7684015545B) of controlword, replacing expired (FBE5A2FEAD8F2637)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 1 (ca0)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write odd part of cw!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 writing odd part (D68CF7684015545B) of controlword, replacing expired (FBE5A2FEAD8F2637)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 2 (ca0)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write odd part of cw!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 writing odd part (D68CF7684015545B) of controlword, replacing expired (FBE5A2FEAD8F2637)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 3 (ca0)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write odd part of cw!2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 writing odd part (D68CF7684015545B) of controlword, replacing expired (FBE5A2FEAD8F2637)2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 4 (ca0)2021/05/20 21:03:53 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:53 35F38746 c (ecm) test (0E00@000000/0000/0190/40:5F2F4CBDF4EBA0CAEDB726A22EDE5B24): found (2 ms) by emulator2021/05/20 21:03:53 35F38746 c (ecm) cw:2021/05/20 21:03:53 35F38746 c (ecm) 38 BF B5 02 57 79 15 A1 00 00 00 00 00 00 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:53 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:53 35F38746 c (dvbapi) Received:2021/05/20 21:03:53 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:53 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:53 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:53 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:53 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:53 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:53 00000000 (main) starting thread client work2021/05/20 21:03:53 00000000 (main) client work thread started2021/05/20 21:03:53 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:53 00000000 (cccam) share-updater check2021/05/20 21:03:53 00000000 (cccam) share-updater mode=initfast t=1s i=172021/05/20 21:03:53 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:53 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:53 35F38746 c (dvbapi) Received:2021/05/20 21:03:53 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:53 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:53 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:53 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:53 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:53 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:53 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:53 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:53 35F38746 c (dvbapi) Received:2021/05/20 21:03:53 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:53 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:53 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:53 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:53 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:53 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:53 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:53 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:53 35F38746 c (dvbapi) Received:2021/05/20 21:03:53 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:53 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:53 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:53 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:53 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CC A0 00 42 CA 2021/05/20 21:03:53 35F38746 c (dvbapi) EB 06 B6 EB 07 DD 37 E5 05 7F 67 35 00 19 00 00 2021/05/20 21:03:53 35F38746 c (dvbapi) 00 83 55 B5 37 02 B6 68 64 90 B5 A3 EA F0 30 E3 2021/05/20 21:03:53 35F38746 c (dvbapi) 0F 06 C1 3E E5 47 7B B7 61 C5 FD EE 6A F6 E0 4B 2021/05/20 21:03:53 00000000 (work) start reader thread action 132021/05/20 21:03:53 00000000 (main) starting thread client work2021/05/20 21:03:53 00000000 (main) client work thread started2021/05/20 21:03:53 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:54 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:54 00000000 (work) start client thread action 312021/05/20 21:03:54 00000000 (main) starting thread client work2021/05/20 21:03:54 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:54 35F38746 c (dvbapi) Received:2021/05/20 21:03:54 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CE A0 00 74 C5 2021/05/20 21:03:54 35F38746 c (dvbapi) EE 8A 9D 71 36 FE 35 A8 1A 54 31 C0 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 21 F0 43 9A 86 CB E6 67 AD D3 5B 7F 22 4D 9F 2021/05/20 21:03:54 35F38746 c (dvbapi) 15 F1 3A F2 E0 10 4E 64 52 67 3A 4A C6 85 C8 4B 2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:54 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CE A0 00 74 C5 2021/05/20 21:03:54 35F38746 c (dvbapi) EE 8A 9D 71 36 FE 35 A8 1A 54 31 C0 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 21 F0 43 9A 86 CB E6 67 AD D3 5B 7F 22 4D 9F 2021/05/20 21:03:54 35F38746 c (dvbapi) 15 F1 3A F2 E0 10 4E 64 52 67 3A 4A C6 85 C8 4B 2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to EVEN (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:54 00000000 (main) client work thread started2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:54 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:54 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 CE A0 00 74 C5 2021/05/20 21:03:54 35F38746 c (ecm) EE 8A 9D 71 36 FE 35 A8 1A 54 31 C0 00 19 00 00 2021/05/20 21:03:54 35F38746 c (ecm) 00 21 F0 43 9A 86 CB E6 67 AD D3 5B 7F 22 4D 9F 2021/05/20 21:03:54 35F38746 c (ecm) 15 F1 3A F2 E0 10 4E 64 52 67 3A 4A C6 85 C8 4B 2021/05/20 21:03:54 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:54 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:54 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:54 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:54 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:54 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:54 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:54 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=9674EF9A23AE860E819BE578F8AA89D12021/05/20 21:03:54 35F38746 c (work) start reader thread action 52021/05/20 21:03:54 35F38746 c (main) starting thread client work2021/05/20 21:03:54 35F38746 c (main) client work thread started2021/05/20 21:03:54 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:54 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:54 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:9674EF9A23AE860E819BE578F8AA89D12021/05/20 21:03:54 35F38746 c (ecm) 81 30 3D 30 37 20 0E 00 00 00 00 CE A0 00 74 C5 2021/05/20 21:03:54 35F38746 c (ecm) EE 8A 9D 71 36 FE 35 A8 1A 54 31 C0 00 19 00 00 2021/05/20 21:03:54 35F38746 c (ecm) 00 21 F0 43 9A 86 CB E6 67 AD D3 5B 7F 22 4D 9F 2021/05/20 21:03:54 35F38746 c (ecm) 15 F1 3A F2 E0 10 4E 64 52 67 3A 4A C6 85 C8 4B 2021/05/20 21:03:54 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:54 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:54 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:9674EF9A23AE860E819BE578F8AA89D12021/05/20 21:03:54 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:54 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:54 669C4893 r (emu) calculated cw 0: 7F1AA7A41C29FBD52021/05/20 21:03:54 669C4893 r (emu) calculated cw 1: 70E0FEA1DC572F152021/05/20 21:03:54 669C4893 r (emu) calculated cw 2: 5819ABD9A1FB10B62021/05/20 21:03:54 669C4893 r (emu) calculated cw 3: 5819ABD9A1FB10B62021/05/20 21:03:54 669C4893 r (emu) calculated cw 4: 5819ABD9A1FB10B62021/05/20 21:03:54 669C4893 r (emu) calculated cw 5: 5819ABD9A1FB10B62021/05/20 21:03:54 669C4893 r (emu) calculated cw 6: 527049DC5E4FA4A22021/05/20 21:03:54 669C4893 r (emu) calculated cw 7: ABB9F7EF7083EF512021/05/20 21:03:54 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:54 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:54 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:54 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:54 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:54 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (2 ms)2021/05/20 21:03:54 669C4893 r (work) start client thread action 322021/05/20 21:03:54 669C4893 r (main) starting thread client work2021/05/20 21:03:54 669C4893 r (main) client work thread started2021/05/20 21:03:54 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:54 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:54 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 9674EF9A23AE860E819BE578F8AA89D1 rc=02021/05/20 21:03:54 669C4893 r (reader) emulator [emu] ecm hash: 9674EF9A23AE860E819BE578F8AA89D1 real time: 1 ms2021/05/20 21:03:54 35F38746 c (ecm) Client test csphash 96E5DFD1 cw 00000000000000007F1AA7A41C29FBD5 rc 0 0E00@000000/0000/0190/40:9674EF9A23AE860E819BE578F8AA89D12021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm 9674EF9A23AE860E819BE578F8AA89D1 on fd 502021/05/20 21:03:54 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:54 35F38746 c (dvbapi) delayer: gone=3 ms, cfg=60 ms -> delay=57 ms2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write even part of cw!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 writing even part (7F1AA7A41C29FBD5) of controlword, replacing expired (9EF238762351DC0E)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 0 (ca0)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write even part of cw!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 writing even part (5819ABD9A1FB10B6) of controlword, replacing expired (DC1F68F7FD548F5B)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 1 (ca0)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write even part of cw!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 writing even part (5819ABD9A1FB10B6) of controlword, replacing expired (DC1F68F7FD548F5B)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 2 (ca0)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write even part of cw!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 writing even part (5819ABD9A1FB10B6) of controlword, replacing expired (DC1F68F7FD548F5B)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 3 (ca0)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write even part of cw!2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 writing even part (5819ABD9A1FB10B6) of controlword, replacing expired (DC1F68F7FD548F5B)2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 write cw1 index: 4 (ca0)2021/05/20 21:03:54 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:54 35F38746 c (ecm) test (0E00@000000/0000/0190/40:9674EF9A23AE860E819BE578F8AA89D1): found (3 ms) by emulator2021/05/20 21:03:54 35F38746 c (ecm) cw:2021/05/20 21:03:54 35F38746 c (ecm) 00 00 00 00 00 00 00 00 7F 1A A7 A4 1C 29 FB D5 2021/05/20 21:03:54 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:54 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:54 35F38746 c (dvbapi) Received:2021/05/20 21:03:54 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:54 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:54 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:54 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:54 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:54 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:54 00000000 (main) starting thread client work2021/05/20 21:03:54 00000000 (main) client work thread started2021/05/20 21:03:54 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:54 00000000 (cccam) share-updater check2021/05/20 21:03:54 00000000 (cccam) share-updater mode=initfast t=1s i=162021/05/20 21:03:54 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:54 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:54 35F38746 c (dvbapi) Received:2021/05/20 21:03:54 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:54 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:54 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:54 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:54 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:54 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:54 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:54 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:54 35F38746 c (dvbapi) Received:2021/05/20 21:03:54 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:54 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:54 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:54 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:54 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:54 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:54 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:54 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:54 35F38746 c (dvbapi) Received:2021/05/20 21:03:54 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:54 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:54 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:54 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:54 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CD A0 00 82 F2 2021/05/20 21:03:54 35F38746 c (dvbapi) 46 23 AB 29 A2 A8 3F EB 5D BE CD D9 00 19 00 00 2021/05/20 21:03:54 35F38746 c (dvbapi) 00 97 FD 51 F7 39 94 54 56 79 44 72 26 CD 6A 8C 2021/05/20 21:03:54 35F38746 c (dvbapi) 7B F4 99 09 B2 B7 E2 CA 13 9F D7 EC DA D0 F7 83 2021/05/20 21:03:54 00000000 (work) start reader thread action 132021/05/20 21:03:54 00000000 (main) starting thread client work2021/05/20 21:03:54 00000000 (main) client work thread started2021/05/20 21:03:54 669C4893 r (work) data from add_job action=13 client r emulator2021/05/20 21:03:55 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:55 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:55 35F38746 c (dvbapi) Received:2021/05/20 21:03:55 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CF A0 00 C5 7C 2021/05/20 21:03:55 35F38746 c (dvbapi) 97 B9 61 80 77 D0 E7 F4 72 93 A0 45 00 19 00 00 2021/05/20 21:03:55 35F38746 c (dvbapi) 00 5C 37 0F C1 C9 41 6B 8C 66 FB F1 BD 31 8C D9 2021/05/20 21:03:55 35F38746 c (dvbapi) A7 71 2F 37 CE 67 29 09 01 FB AF E0 D0 47 E0 C5 2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 802021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:55 35F38746 c (dvbapi) 80 30 3D 30 37 20 0E 00 00 00 00 CF A0 00 C5 7C 2021/05/20 21:03:55 35F38746 c (dvbapi) 97 B9 61 80 77 D0 E7 F4 72 93 A0 45 00 19 00 00 2021/05/20 21:03:55 35F38746 c (dvbapi) 00 5C 37 0F C1 C9 41 6B 8C 66 FB F1 BD 31 8C D9 2021/05/20 21:03:55 35F38746 c (dvbapi) A7 71 2F 37 CE 67 29 09 01 FB AF E0 D0 47 E0 C5 2021/05/20 21:03:55 00000000 (work) start client thread action 312021/05/20 21:03:55 00000000 (main) starting thread client work2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0E00 PROVID 000000 ECMPID 177A FAKECHID 0000 (unique part in ecm)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 Filter 2 set ecmtable to ODD (CAID 0E00 PROVID 000000 FD 50)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 Filter 2 set chid to ANY CHID on fd 502021/05/20 21:03:55 00000000 (main) client work thread started2021/05/20 21:03:55 35F38746 c (work) data from add_job action=31 client c test2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 get controlword!2021/05/20 21:03:55 35F38746 c (ecm) get cw for ecm:2021/05/20 21:03:55 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 CF A0 00 C5 7C 2021/05/20 21:03:55 35F38746 c (ecm) 97 B9 61 80 77 D0 E7 F4 72 93 A0 45 00 19 00 00 2021/05/20 21:03:55 35F38746 c (ecm) 00 5C 37 0F C1 C9 41 6B 8C 66 FB F1 BD 31 8C D9 2021/05/20 21:03:55 35F38746 c (ecm) A7 71 2F 37 CE 67 29 09 01 FB AF E0 D0 47 E0 C5 2021/05/20 21:03:55 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] NEW REQUEST!2021/05/20 21:03:55 35F38746 c (chk) trying reader 'emulator' filter 0E00@0000002021/05/20 21:03:55 35F38746 c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@0000002021/05/20 21:03:55 35F38746 c (chk) no match, 0E00@000000 rejected by reader 'shara' filters2021/05/20 21:03:55 35F38746 c (chk) r-filter reader shara2021/05/20 21:03:55 35F38746 c (ecm) [GET_CW] wait_time 0 caid 0E00 prov 000000 srvid 0190 rc 100 cacheex cl mode 0 ex1rdr 02021/05/20 21:03:55 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 12021/05/20 21:03:55 35F38746 c (ecm) request_cw stage=2 to reader emulator ecm hash=715785A2DA3025D08E2DC007EE97D5692021/05/20 21:03:55 35F38746 c (work) start reader thread action 52021/05/20 21:03:55 35F38746 c (main) starting thread client work2021/05/20 21:03:55 35F38746 c (main) client work thread started2021/05/20 21:03:55 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_request] reader emulator --> SENT 12021/05/20 21:03:55 669C4893 r (work) data from add_job action=5 client r emulator2021/05/20 21:03:55 35F38746 c (ecm) Client test ECM dump 0E00@000000/0000/0190/40:715785A2DA3025D08E2DC007EE97D5692021/05/20 21:03:55 35F38746 c (ecm) 80 30 3D 30 37 20 0E 00 00 00 00 CF A0 00 C5 7C 2021/05/20 21:03:55 35F38746 c (ecm) 97 B9 61 80 77 D0 E7 F4 72 93 A0 45 00 19 00 00 2021/05/20 21:03:55 35F38746 c (ecm) 00 5C 37 0F C1 C9 41 6B 8C 66 FB F1 BD 31 8C D9 2021/05/20 21:03:55 35F38746 c (ecm) A7 71 2F 37 CE 67 29 09 01 FB AF E0 D0 47 E0 C5 2021/05/20 21:03:55 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:55 669C4893 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=12021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 request controlword for ecm 0E00@000000/0000/0190/40:715785A2DA3025D08E2DC007EE97D5692021/05/20 21:03:55 669C4893 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 0190, hashModeCw: 0, modeCW: 02021/05/20 21:03:55 669C4893 r (emu) channel hash: 00000000, group id: 00002021/05/20 21:03:55 669C4893 r (emu) calculated cw 0: 517094A886945BFD2021/05/20 21:03:55 669C4893 r (emu) calculated cw 1: AB684FCD9845972A2021/05/20 21:03:55 669C4893 r (emu) calculated cw 2: BF1AD6B337A29D752021/05/20 21:03:55 669C4893 r (emu) calculated cw 3: BF1AD6B337A29D752021/05/20 21:03:55 669C4893 r (emu) calculated cw 4: BF1AD6B337A29D752021/05/20 21:03:55 669C4893 r (emu) calculated cw 5: BF1AD6B337A29D752021/05/20 21:03:55 669C4893 r (emu) calculated cw 6: 1C890D4AC2FB92322021/05/20 21:03:55 669C4893 r (emu) calculated cw 7: 7C98D54FF4FB1FF22021/05/20 21:03:55 669C4893 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=12021/05/20 21:03:55 669C4893 r (reader) emulator [emu] cardreader_do_ecm: ret rc=12021/05/20 21:03:55 669C4893 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)2021/05/20 21:03:55 669C4893 r (ecm) notice: CW checksum check disabled2021/05/20 21:03:55 669C4893 r (ecm) {client test, caid 0E00, srvid 0190} [write_ecm_answer] cyclecheck passed! Reader: emulator rc: 02021/05/20 21:03:55 669C4893 r (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (2 ms)2021/05/20 21:03:55 669C4893 r (work) start client thread action 322021/05/20 21:03:55 669C4893 r (main) starting thread client work2021/05/20 21:03:55 669C4893 r (main) client work thread started2021/05/20 21:03:55 35F38746 c (work) data from add_job action=32 client c test2021/05/20 21:03:55 35F38746 c (ecm) {client test, caid 0E00, prid 000000, srvid 0190} [send_dcw] rc 0 from reader emulator2021/05/20 21:03:55 669C4893 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 715785A2DA3025D08E2DC007EE97D569 rc=02021/05/20 21:03:55 35F38746 c (ecm) Client test csphash C0E59D5D cw 517094A886945BFD0000000000000000 rc 0 0E00@000000/0000/0190/40:715785A2DA3025D08E2DC007EE97D5692021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 177A CHID 0000 VPID 01902021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 requested controlword for ecm 715785A2DA3025D08E2DC007EE97D569 on fd 502021/05/20 21:03:55 669C4893 r (reader) emulator [emu] ecm hash: 715785A2DA3025D08E2DC007EE97D569 real time: 1 ms2021/05/20 21:03:55 35F38746 c (dvbapi) generic delay: write cw 60 ms after ecmrequest2021/05/20 21:03:55 35F38746 c (dvbapi) delayer: gone=3 ms, cfg=60 ms -> delay=57 ms2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> write odd part of cw!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 writing odd part (517094A886945BFD) of controlword, replacing expired (38BFB502577915A1)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 0 (ca0)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> write odd part of cw!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 writing odd part (BF1AD6B337A29D75) of controlword, replacing expired (D68CF7684015545B)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 1 (ca0)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> write odd part of cw!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 writing odd part (BF1AD6B337A29D75) of controlword, replacing expired (D68CF7684015545B)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 2 (ca0)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> write odd part of cw!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 writing odd part (BF1AD6B337A29D75) of controlword, replacing expired (D68CF7684015545B)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 3 (ca0)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0190 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 1 for streampid 0191 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 2 for streampid 0192 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 3 for streampid 0193 -> skip!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 ca0 is using index 4 for streampid 0195 -> write odd part of cw!2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 writing odd part (BF1AD6B337A29D75) of controlword, replacing expired (D68CF7684015545B)2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 write cw0 index: 4 (ca0)2021/05/20 21:03:55 35F38746 c (dvbapi) Using 5 of 16 total descramblers2021/05/20 21:03:55 35F38746 c (ecm) test (0E00@000000/0000/0190/40:715785A2DA3025D08E2DC007EE97D569): found (3 ms) by emulator2021/05/20 21:03:55 35F38746 c (ecm) cw:2021/05/20 21:03:55 35F38746 c (ecm) 51 70 94 A8 86 94 5B FD 00 00 00 00 00 00 00 00 2021/05/20 21:03:55 35F38746 c (dvbapi) New events occurred on 1 of 3 handlers after 200 ms inactivity2021/05/20 21:03:55 35F38746 c (dvbapi) Now handling fd 50 that reported event 32021/05/20 21:03:55 35F38746 c (dvbapi) Received:2021/05/20 21:03:55 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CE A0 00 74 C5 2021/05/20 21:03:55 35F38746 c (dvbapi) EE 8A 9D 71 36 FE 35 A8 1A 54 31 C0 00 19 00 00 2021/05/20 21:03:55 35F38746 c (dvbapi) 00 21 F0 43 9A 86 CB E6 67 AD D3 5B 7F 22 4D 9F 2021/05/20 21:03:55 35F38746 c (dvbapi) 15 F1 3A F2 E0 10 4E 64 52 67 3A 4A C6 85 C8 4B 2021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 filter2[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 812021/05/20 21:03:55 35F38746 c (dvbapi) Demuxer 0 filter 2 fetched ECM data (length = 0x040):2021/05/20 21:03:55 35F38746 c (dvbapi) 81 30 3D 30 37 20 0E 00 00 00 00 CE A0 00 74 C5 2021/05/20 21:03:55 35F38746 c (dvbapi) EE 8A 9D 71 36 FE 35 A8 1A 54 31 C0 00 19 00 00 2021/05/20 21:03:55 35F38746 c (dvbapi) 00 21 F0 43 9A 86 CB E6 67 AD D3 5B 7F 22 4D 9F 2021/05/20 21:03:55 35F38746 c (dvbapi) 15 F1 3A F2 E0 10 4E 64 52 67 3A 4A C6 85 C8 4B 2021/05/20 21:03:55 00000000 (main) starting thread client work2021/05/20 21:03:55 00000000 (main) client work thread started2021/05/20 21:03:55 669C4893 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 12021/05/20 21:03:55 00000000 (cccam) share-updater check2021/05/20 21:03:55 00000000 (cccam) share-updater mode=initfast t=1s i=152021/05/20 21:03:55 2EF330A5 h Stopping log...
[свернуть]

Последний раз редактировалось credo; 20.05.2021 в 22:59. Причина: спойлер
edv7 вне форума   Ответить с цитированием