2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:21 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:21 20853DDC c (dvbapi) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:21 20853DDC c (dvbapi) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:21 20853DDC c (dvbapi) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:21 20853DDC c (dvbapi) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:21 00000000 (work) start client thread action 31
2019/11/03 20:37:21 00000000 (main) starting thread client work
2019/11/03 20:37:21 00000000 (main) client work thread started
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0E00 PROVID 000000 ECMPID 1775 FAKECHID 0000 (unique part in ecm)
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 0E00 PROVID 000000 FD 18)
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 18
2019/11/03 20:37:21 20853DDC c (ecm) get cw for ecm:
2019/11/03 20:37:21 20853DDC c (ecm) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:21 20853DDC c (ecm) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:21 20853DDC c (ecm) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:21 20853DDC c (ecm) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:21 20853DDC c (ecm) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:21 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [get_cw] NEW REQUEST!
2019/11/03 20:37:21 20853DDC c (chk) reader unavailable remote1
2019/11/03 20:37:21 20853DDC c (chk) reader unavailable remote
2019/11/03 20:37:21 20853DDC c (-) -- Skipped 2 duplicated log lines --
2019/11/03 20:37:21 20853DDC c (chk) trying reader 'emulator' filter 0E00@000000
2019/11/03 20:37:21 20853DDC c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@000000
2019/11/03 20:37:21 20853DDC c (work) data from add_job action=31 client c dvbapiau
2019/11/03 20:37:21 20853DDC c (ecm) request_cw stage=2 to reader emulator ecm hash=AAB72E05C0E1F05D802F0CB70C66EDA0
2019/11/03 20:37:21 20853DDC c (work) start reader thread action 5
2019/11/03 20:37:21 20853DDC c (main) starting thread client work
2019/11/03 20:37:21 20853DDC c (main) client work thread started
2019/11/03 20:37:21 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [write_ecm_request] reader emulator --> SENT 1
2019/11/03 20:37:21 68403090 r (work) data from add_job action=5 client r emulator
2019/11/03 20:37:21 20853DDC c (ecm) Client dvbapiau ECM dump P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000000000000000000000 HOP:
2019/11/03 20:37:21 20853DDC c (ecm) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:21 20853DDC c (ecm) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:21 20853DDC c (ecm) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:21 20853DDC c (ecm) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:21 20853DDC c (ecm) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:21 68403090 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 request controlword for ecm P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000000000000000000000 HOP:
2019/11/03 20:37:21 68403090 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2019/11/03 20:37:21 68403090 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 06A9, hashModeCw: 1, modeCW: 8
2019/11/03 20:37:21 68403090 r (emu) channel hash: 011E3D05, group id: 0000
2019/11/03 20:37:21 68403090 r (emu) calculated cw 0: 737C45CB0EF86B01
2019/11/03 20:37:21 68403090 r (emu) calculated cw 1: 97853D37863DA8DF
2019/11/03 20:37:21 68403090 r (emu) calculated cw 2: F8891532E5AB9238
2019/11/03 20:37:21 68403090 r (emu) calculated cw 3: F8891532E5AB9238
2019/11/03 20:37:21 68403090 r (emu) calculated cw 4: F8891532E5AB9238
2019/11/03 20:37:21 68403090 r (emu) calculated cw 5: F8891532E5AB9238
2019/11/03 20:37:21 68403090 r (emu) calculated cw 6: E98586F7D3832A97
2019/11/03 20:37:21 68403090 r (emu) calculated cw 7: FEEA86023D1A4A4C
2019/11/03 20:37:21 68403090 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=1
2019/11/03 20:37:21 68403090 r (reader) emulator [emu] cardreader_do_ecm: ret rc=1
2019/11/03 20:37:21 68403090 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2019/11/03 20:37:21 68403090 r (ecm) notice: CW checksum check disabled for 0E00:000000
2019/11/03 20:37:21 68403090 r (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [write_ecm_answer] reader emulator rc 0, ecm time 1 ms (2 ms)
2019/11/03 20:37:21 68403090 r (work) start client thread action 32
2019/11/03 20:37:21 68403090 r (main) starting thread client work
2019/11/03 20:37:21 68403090 r (main) client work thread started
2019/11/03 20:37:21 68403090 r (reader) emulator [emu] TRACE: ecm answer for ecm hash AAB72E05C0E1F05D802F0CB70C66EDA0 rc=0
2019/11/03 20:37:21 68403090 r (reader) emulator [emu] ecm hash: AAB72E05C0E1F05D802F0CB70C66EDA0 real time: 1 ms
2019/11/03 20:37:21 20853DDC c (work) data from add_job action=32 client c dvbapiau
2019/11/03 20:37:21 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [send_dcw] rc 0 from reader emulator
2019/11/03 20:37:21 20853DDC c (ecm) Client dvbapiau cw 737C45CB0EF86B010000000000000000 rc 0 P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=737C45CB0EF86B010000000000000000 HOP:
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 1775 CHID 0000 VPID 05DC
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 requested controlword for ecm AAB72E05C0E1F05D802F0CB70C66EDA0 on fd 18
2019/11/03 20:37:21 20853DDC c (dvbapi) generic delay: write cw 60 ms after ecmrequest
2019/11/03 20:37:21 20853DDC c (dvbapi) delayer: gone=3 ms, cfg=60 ms -> delay=57 ms
2019/11/03 20:37:21 00000000 (main) starting thread client work
2019/11/03 20:37:21 00000000 (main) client work thread started
2019/11/03 20:37:21 68403090 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 PID: 0 CAID: 0E00 ECMPID: 1775 has no free index
2019/11/03 20:37:21 20853DDC c (-) -- Skipped 4 duplicated log lines --
2019/11/03 20:37:21 20853DDC c (ecm) dvbapiau (P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=737C45CB0EF86B010000000000000000 HOP
: found (3 ms) by emulator (L/1/2/2) - DSC Southeast Asia
2019/11/03 20:37:21 20853DDC c (ecm) cw:
2019/11/03 20:37:21 20853DDC c (ecm) 73 7C 45 CB 0E F8 6B 01 00 00 00 00 00 00 00 00
2019/11/03 20:37:21 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 199 ms inactivity
2019/11/03 20:37:21 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:21 20853DDC c (dvbapi) Received:
2019/11/03 20:37:21 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B7 08 70 62 5E 36 77 4E
2019/11/03 20:37:21 20853DDC c (dvbapi) 79 1D 00 58 B0 00 F8 FC 59 6D 48 E9 39 C6 DF 2F
2019/11/03 20:37:21 20853DDC c (dvbapi) 19 2C B8 67 6D 6A 90 09 00 DE F5 97 E6 10 BF 39
2019/11/03 20:37:21 20853DDC c (dvbapi) E2 6B 12 5E C3 BB F3 09 3A 91 C6 39 51 4F 73 0B
2019/11/03 20:37:21 20853DDC c (dvbapi) D1 59 76 CB 76 DB 3A 1F
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:21 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B7 08 70 62 5E 36 77 4E
2019/11/03 20:37:21 20853DDC c (dvbapi) 79 1D 00 58 B0 00 F8 FC 59 6D 48 E9 39 C6 DF 2F
2019/11/03 20:37:21 20853DDC c (dvbapi) 19 2C B8 67 6D 6A 90 09 00 DE F5 97 E6 10 BF 39
2019/11/03 20:37:21 20853DDC c (dvbapi) E2 6B 12 5E C3 BB F3 09 3A 91 C6 39 51 4F 73 0B
2019/11/03 20:37:21 20853DDC c (dvbapi) D1 59 76 CB 76 DB 3A 1F
2019/11/03 20:37:21 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 199 ms inactivity
2019/11/03 20:37:21 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:21 20853DDC c (dvbapi) Received:
2019/11/03 20:37:21 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B7 08 70 62 5E 36 77 4E
2019/11/03 20:37:21 20853DDC c (dvbapi) 79 1D 00 58 B0 00 F8 FC 59 6D 48 E9 39 C6 DF 2F
2019/11/03 20:37:21 20853DDC c (dvbapi) 19 2C B8 67 6D 6A 90 09 00 DE F5 97 E6 10 BF 39
2019/11/03 20:37:21 20853DDC c (dvbapi) E2 6B 12 5E C3 BB F3 09 3A 91 C6 39 51 4F 73 0B
2019/11/03 20:37:21 20853DDC c (dvbapi) D1 59 76 CB 76 DB 3A 1F
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:21 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B7 08 70 62 5E 36 77 4E
2019/11/03 20:37:21 20853DDC c (dvbapi) 79 1D 00 58 B0 00 F8 FC 59 6D 48 E9 39 C6 DF 2F
2019/11/03 20:37:21 20853DDC c (dvbapi) 19 2C B8 67 6D 6A 90 09 00 DE F5 97 E6 10 BF 39
2019/11/03 20:37:21 20853DDC c (dvbapi) E2 6B 12 5E C3 BB F3 09 3A 91 C6 39 51 4F 73 0B
2019/11/03 20:37:21 20853DDC c (dvbapi) D1 59 76 CB 76 DB 3A 1F
2019/11/03 20:37:21 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 190 ms inactivity
2019/11/03 20:37:21 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:21 20853DDC c (dvbapi) Received:
2019/11/03 20:37:21 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B7 08 70 62 5E 36 77 4E
2019/11/03 20:37:21 20853DDC c (dvbapi) 79 1D 00 58 B0 00 F8 FC 59 6D 48 E9 39 C6 DF 2F
2019/11/03 20:37:21 20853DDC c (dvbapi) 19 2C B8 67 6D 6A 90 09 00 DE F5 97 E6 10 BF 39
2019/11/03 20:37:21 20853DDC c (dvbapi) E2 6B 12 5E C3 BB F3 09 3A 91 C6 39 51 4F 73 0B
2019/11/03 20:37:21 20853DDC c (dvbapi) D1 59 76 CB 76 DB 3A 1F
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2019/11/03 20:37:21 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:21 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B7 08 70 62 5E 36 77 4E
2019/11/03 20:37:21 20853DDC c (dvbapi) 79 1D 00 58 B0 00 F8 FC 59 6D 48 E9 39 C6 DF 2F
2019/11/03 20:37:21 20853DDC c (dvbapi) 19 2C B8 67 6D 6A 90 09 00 DE F5 97 E6 10 BF 39
2019/11/03 20:37:21 20853DDC c (dvbapi) E2 6B 12 5E C3 BB F3 09 3A 91 C6 39 51 4F 73 0B
2019/11/03 20:37:21 20853DDC c (dvbapi) D1 59 76 CB 76 DB 3A 1F
2019/11/03 20:37:21 00000000 (work) add reader job action 13 queue length 1 remote1
2019/11/03 20:37:21 00000000 (work) add reader job action 13 queue length 1 cw
2019/11/03 20:37:21 00000000 (work) start reader thread action 13
2019/11/03 20:37:21 00000000 (main) starting thread client work
2019/11/03 20:37:21 00000000 (main) client work thread started
2019/11/03 20:37:21 68403090 r (work) data from add_job action=13 client r emulator
2019/11/03 20:37:22 6517A6F4 h (webif) WebIf: Origin checked. Result: access from 192.168.1.101 => allowed
2019/11/03 20:37:22 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 410 ms inactivity
2019/11/03 20:37:22 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:22 20853DDC c (dvbapi) Received:
2019/11/03 20:37:22 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:22 20853DDC c (dvbapi) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:22 20853DDC c (dvbapi) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:22 20853DDC c (dvbapi) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:22 20853DDC c (dvbapi) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:22 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:22 20853DDC c (dvbapi) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:22 20853DDC c (dvbapi) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:22 20853DDC c (dvbapi) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:22 20853DDC c (dvbapi) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 0E00 PROVID 000000 ECMPID 1775 FAKECHID 0000 (unique part in ecm)
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 Filter 1 set ecmtable to EVEN (CAID 0E00 PROVID 000000 FD 18)
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 18
2019/11/03 20:37:22 20853DDC c (ecm) get cw for ecm:
2019/11/03 20:37:22 20853DDC c (ecm) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:22 20853DDC c (ecm) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:22 20853DDC c (ecm) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:22 20853DDC c (ecm) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:22 20853DDC c (ecm) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:22 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [get_cw] NEW REQUEST!
2019/11/03 20:37:22 20853DDC c (chk) reader unavailable remote1
2019/11/03 20:37:22 20853DDC c (chk) reader unavailable remote
2019/11/03 20:37:22 20853DDC c (-) -- Skipped 2 duplicated log lines --
2019/11/03 20:37:22 20853DDC c (chk) trying reader 'emulator' filter 0E00@000000
2019/11/03 20:37:22 20853DDC c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@000000
2019/11/03 20:37:22 20853DDC c (ecm) request_cw stage=2 to reader emulator ecm hash=3E792742739B119948F1F8E73F84BD57
2019/11/03 20:37:22 20853DDC c (work) start reader thread action 5
2019/11/03 20:37:22 20853DDC c (main) starting thread client work
2019/11/03 20:37:22 20853DDC c (main) client work thread started
2019/11/03 20:37:22 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [write_ecm_request] reader emulator --> SENT 1
2019/11/03 20:37:22 68403090 r (work) data from add_job action=5 client r emulator
2019/11/03 20:37:22 20853DDC c (ecm) Client dvbapiau ECM dump P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000000000000000000000 HOP:
2019/11/03 20:37:22 20853DDC c (ecm) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:22 20853DDC c (ecm) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:22 20853DDC c (ecm) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:22 20853DDC c (ecm) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:22 20853DDC c (ecm) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 request controlword for ecm P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000000000000000000000 HOP:
2019/11/03 20:37:22 00000000 (work) start client thread action 31
2019/11/03 20:37:22 00000000 (main) starting thread client work
2019/11/03 20:37:22 00000000 (main) client work thread started
2019/11/03 20:37:22 68403090 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/11/03 20:37:22 68403090 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2019/11/03 20:37:22 68403090 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 06A9, hashModeCw: 1, modeCW: 8
2019/11/03 20:37:22 68403090 r (emu) channel hash: 011E3D05, group id: 0000
2019/11/03 20:37:22 20853DDC c (work) data from add_job action=31 client c dvbapiau
2019/11/03 20:37:22 68403090 r (emu) calculated cw 0: 7AA8DC7F43B00B89
2019/11/03 20:37:22 68403090 r (emu) calculated cw 1: CB0BC416C25ECB9E
2019/11/03 20:37:22 68403090 r (emu) calculated cw 2: 01C8852023DF5B9D
2019/11/03 20:37:22 68403090 r (emu) calculated cw 3: 01C8852023DF5B9D
2019/11/03 20:37:22 68403090 r (emu) calculated cw 4: 01C8852023DF5B9D
2019/11/03 20:37:22 68403090 r (emu) calculated cw 5: 01C8852023DF5B9D
2019/11/03 20:37:22 68403090 r (emu) calculated cw 6: 4FC1FB9BD3B9197A
2019/11/03 20:37:22 68403090 r (emu) calculated cw 7: 5175ECAEBCB6E5B9
2019/11/03 20:37:22 68403090 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=1
2019/11/03 20:37:22 68403090 r (reader) emulator [emu] cardreader_do_ecm: ret rc=1
2019/11/03 20:37:22 68403090 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2019/11/03 20:37:22 68403090 r (ecm) notice: CW checksum check disabled for 0E00:000000
2019/11/03 20:37:22 68403090 r (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [write_ecm_answer] reader emulator rc 0, ecm time 3 ms (3 ms)
2019/11/03 20:37:22 68403090 r (work) start client thread action 32
2019/11/03 20:37:22 68403090 r (main) starting thread client work
2019/11/03 20:37:22 68403090 r (main) client work thread started
2019/11/03 20:37:22 68403090 r (reader) emulator [emu] TRACE: ecm answer for ecm hash 3E792742739B119948F1F8E73F84BD57 rc=0
2019/11/03 20:37:22 20853DDC c (work) data from add_job action=32 client c dvbapiau
2019/11/03 20:37:22 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [send_dcw] rc 0 from reader emulator
2019/11/03 20:37:22 68403090 r (reader) emulator [emu] ecm hash: 3E792742739B119948F1F8E73F84BD57 real time: 3 ms
2019/11/03 20:37:22 20853DDC c (ecm) Client dvbapiau cw 00000000000000007AA8DC7F43B00B89 rc 0 P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000007AA8DC7F43B00B89 HOP:
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 1775 CHID 0000 VPID 05DC
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 requested controlword for ecm 3E792742739B119948F1F8E73F84BD57 on fd 18
2019/11/03 20:37:22 20853DDC c (dvbapi) generic delay: write cw 60 ms after ecmrequest
2019/11/03 20:37:22 20853DDC c (dvbapi) delayer: gone=4 ms, cfg=60 ms -> delay=56 ms
2019/11/03 20:37:22 00000000 (main) starting thread client work
2019/11/03 20:37:22 00000000 (main) client work thread started
2019/11/03 20:37:22 68403090 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 PID: 0 CAID: 0E00 ECMPID: 1775 has no free index
2019/11/03 20:37:22 20853DDC c (-) -- Skipped 4 duplicated log lines --
2019/11/03 20:37:22 20853DDC c (ecm) dvbapiau (P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000007AA8DC7F43B00B89 HOP
: found (4 ms) by emulator (L/1/2/2) - DSC Southeast Asia
2019/11/03 20:37:22 20853DDC c (ecm) cw:
2019/11/03 20:37:22 20853DDC c (ecm) 00 00 00 00 00 00 00 00 7A A8 DC 7F 43 B0 0B 89
2019/11/03 20:37:22 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 199 ms inactivity
2019/11/03 20:37:22 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:22 20853DDC c (dvbapi) Received:
2019/11/03 20:37:22 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:22 20853DDC c (dvbapi) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:22 20853DDC c (dvbapi) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:22 20853DDC c (dvbapi) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:22 20853DDC c (dvbapi) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:22 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:22 20853DDC c (dvbapi) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:22 20853DDC c (dvbapi) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:22 20853DDC c (dvbapi) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:22 20853DDC c (dvbapi) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:22 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 200 ms inactivity
2019/11/03 20:37:22 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:22 20853DDC c (dvbapi) Received:
2019/11/03 20:37:22 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:22 20853DDC c (dvbapi) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:22 20853DDC c (dvbapi) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:22 20853DDC c (dvbapi) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:22 20853DDC c (dvbapi) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:22 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:22 20853DDC c (dvbapi) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:22 20853DDC c (dvbapi) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:22 20853DDC c (dvbapi) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:22 20853DDC c (dvbapi) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:22 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 190 ms inactivity
2019/11/03 20:37:22 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:22 20853DDC c (dvbapi) Received:
2019/11/03 20:37:22 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:22 20853DDC c (dvbapi) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:22 20853DDC c (dvbapi) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:22 20853DDC c (dvbapi) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:22 20853DDC c (dvbapi) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2019/11/03 20:37:22 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:22 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 23 08 54 5D 3F CA BF 59
2019/11/03 20:37:22 20853DDC c (dvbapi) BE 25 00 59 B0 00 27 21 8B 67 7E 60 F9 90 62 4C
2019/11/03 20:37:22 20853DDC c (dvbapi) C0 44 8A BE 46 6A 90 09 00 6E 0A 5D B9 0D 5F 9A
2019/11/03 20:37:22 20853DDC c (dvbapi) 98 83 B4 17 B3 7E 81 9A AC BE 2C 46 5A CC DC 18
2019/11/03 20:37:22 20853DDC c (dvbapi) 39 7C A3 9F C4 72 36 C9
2019/11/03 20:37:22 00000000 (work) add reader job action 13 queue length 1 remote1
2019/11/03 20:37:22 00000000 (work) add reader job action 13 queue length 1 cw
2019/11/03 20:37:22 00000000 (work) start reader thread action 13
2019/11/03 20:37:22 00000000 (main) starting thread client work
2019/11/03 20:37:22 00000000 (main) client work thread started
2019/11/03 20:37:22 68403090 r (work) data from add_job action=13 client r emulator
2019/11/03 20:37:23 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 410 ms inactivity
2019/11/03 20:37:23 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:23 20853DDC c (dvbapi) Received:
2019/11/03 20:37:23 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 0E 08 54 C4 A0 3E CC 96
2019/11/03 20:37:23 20853DDC c (dvbapi) 9A C7 00 5B B0 00 5F FD DB 11 77 A2 87 2C 91 CD
2019/11/03 20:37:23 20853DDC c (dvbapi) E8 8E E8 DA 15 6A 90 09 00 A1 40 D3 4D DA 02 02
2019/11/03 20:37:23 20853DDC c (dvbapi) BF 19 EA B5 E4 21 D1 31 3B 44 FB EF 85 E3 42 1C
2019/11/03 20:37:23 20853DDC c (dvbapi) AF F5 30 94 88 22 DF BD
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:23 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 0E 08 54 C4 A0 3E CC 96
2019/11/03 20:37:23 20853DDC c (dvbapi) 9A C7 00 5B B0 00 5F FD DB 11 77 A2 87 2C 91 CD
2019/11/03 20:37:23 20853DDC c (dvbapi) E8 8E E8 DA 15 6A 90 09 00 A1 40 D3 4D DA 02 02
2019/11/03 20:37:23 20853DDC c (dvbapi) BF 19 EA B5 E4 21 D1 31 3B 44 FB EF 85 E3 42 1C
2019/11/03 20:37:23 20853DDC c (dvbapi) AF F5 30 94 88 22 DF BD
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 0E00 PROVID 000000 ECMPID 1775 FAKECHID 0000 (unique part in ecm)
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 0E00 PROVID 000000 FD 18)
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 18
2019/11/03 20:37:23 20853DDC c (ecm) get cw for ecm:
2019/11/03 20:37:23 20853DDC c (ecm) 80 30 45 50 3F 20 0E 00 0E 08 54 C4 A0 3E CC 96
2019/11/03 20:37:23 20853DDC c (ecm) 9A C7 00 5B B0 00 5F FD DB 11 77 A2 87 2C 91 CD
2019/11/03 20:37:23 20853DDC c (ecm) E8 8E E8 DA 15 6A 90 09 00 A1 40 D3 4D DA 02 02
2019/11/03 20:37:23 20853DDC c (ecm) BF 19 EA B5 E4 21 D1 31 3B 44 FB EF 85 E3 42 1C
2019/11/03 20:37:23 20853DDC c (ecm) AF F5 30 94 88 22 DF BD
2019/11/03 20:37:23 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [get_cw] NEW REQUEST!
2019/11/03 20:37:23 00000000 (work) start client thread action 31
2019/11/03 20:37:23 00000000 (main) starting thread client work
2019/11/03 20:37:23 00000000 (main) client work thread started
2019/11/03 20:37:23 20853DDC c (chk) reader unavailable remote1
2019/11/03 20:37:23 20853DDC c (chk) reader unavailable remote
2019/11/03 20:37:23 20853DDC c (-) -- Skipped 2 duplicated log lines --
2019/11/03 20:37:23 20853DDC c (chk) trying reader 'emulator' filter 0E00@000000
2019/11/03 20:37:23 20853DDC c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@000000
2019/11/03 20:37:23 20853DDC c (ecm) request_cw stage=2 to reader emulator ecm hash=CCE26DC351A4B7BBFB0681478E9CB73D
2019/11/03 20:37:23 20853DDC c (work) start reader thread action 5
2019/11/03 20:37:23 20853DDC c (main) starting thread client work
2019/11/03 20:37:23 20853DDC c (work) data from add_job action=31 client c dvbapiau
2019/11/03 20:37:23 20853DDC c (main) client work thread started
2019/11/03 20:37:23 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [write_ecm_request] reader emulator --> SENT 1
2019/11/03 20:37:23 20853DDC c (ecm) Client dvbapiau ECM dump P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000000000000000000000 HOP:
2019/11/03 20:37:23 20853DDC c (ecm) 80 30 45 50 3F 20 0E 00 0E 08 54 C4 A0 3E CC 96
2019/11/03 20:37:23 20853DDC c (ecm) 9A C7 00 5B B0 00 5F FD DB 11 77 A2 87 2C 91 CD
2019/11/03 20:37:23 20853DDC c (ecm) E8 8E E8 DA 15 6A 90 09 00 A1 40 D3 4D DA 02 02
2019/11/03 20:37:23 20853DDC c (ecm) BF 19 EA B5 E4 21 D1 31 3B 44 FB EF 85 E3 42 1C
2019/11/03 20:37:23 20853DDC c (ecm) AF F5 30 94 88 22 DF BD
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 request controlword for ecm P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000000000000000000000 HOP:
2019/11/03 20:37:23 68403090 r (work) data from add_job action=5 client r emulator
2019/11/03 20:37:23 68403090 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/11/03 20:37:23 68403090 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2019/11/03 20:37:23 68403090 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 06A9, hashModeCw: 1, modeCW: 20
2019/11/03 20:37:23 68403090 r (emu) channel hash: 011E3D05, group id: 0000
2019/11/03 20:37:23 68403090 r (emu) calculated cw 0: 1CA4914C4CE97F86
2019/11/03 20:37:23 68403090 r (emu) calculated cw 1: D5498F1C7CCD4AD9
2019/11/03 20:37:23 68403090 r (emu) calculated cw 2: 736E0119B3671F9D
2019/11/03 20:37:23 68403090 r (emu) calculated cw 3: 736E0119B3671F9D
2019/11/03 20:37:23 68403090 r (emu) calculated cw 4: 736E0119B3671F9D
2019/11/03 20:37:23 68403090 r (emu) calculated cw 5: 736E0119B3671F9D
2019/11/03 20:37:23 68403090 r (emu) calculated cw 6: 23D573FBC76276CB
2019/11/03 20:37:23 68403090 r (emu) calculated cw 7: 3432F7FB1F0B3B92
2019/11/03 20:37:23 68403090 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=1
2019/11/03 20:37:23 68403090 r (reader) emulator [emu] cardreader_do_ecm: ret rc=1
2019/11/03 20:37:23 68403090 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2019/11/03 20:37:23 68403090 r (ecm) notice: CW checksum check disabled for 0E00:000000
2019/11/03 20:37:23 68403090 r (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [write_ecm_answer] reader emulator rc 0, ecm time 2 ms (3 ms)
2019/11/03 20:37:23 68403090 r (work) start client thread action 32
2019/11/03 20:37:23 68403090 r (main) starting thread client work
2019/11/03 20:37:23 68403090 r (main) client work thread started
2019/11/03 20:37:23 20853DDC c (work) data from add_job action=32 client c dvbapiau
2019/11/03 20:37:23 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [send_dcw] rc 0 from reader emulator
2019/11/03 20:37:23 20853DDC c (ecm) Client dvbapiau cw 1CA4914C4CE97F860000000000000000 rc 0 P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=1CA4914C4CE97F860000000000000000 HOP:
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 1775 CHID 0000 VPID 05DC
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 requested controlword for ecm CCE26DC351A4B7BBFB0681478E9CB73D on fd 18
2019/11/03 20:37:23 68403090 r (reader) emulator [emu] TRACE: ecm answer for ecm hash CCE26DC351A4B7BBFB0681478E9CB73D rc=0
2019/11/03 20:37:23 20853DDC c (dvbapi) generic delay: write cw 60 ms after ecmrequest
2019/11/03 20:37:23 20853DDC c (dvbapi) delayer: gone=4 ms, cfg=60 ms -> delay=56 ms
2019/11/03 20:37:23 68403090 r (reader) emulator [emu] ecm hash: CCE26DC351A4B7BBFB0681478E9CB73D real time: 1 ms
2019/11/03 20:37:23 00000000 (main) starting thread client work
2019/11/03 20:37:23 00000000 (main) client work thread started
2019/11/03 20:37:23 68403090 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 PID: 0 CAID: 0E00 ECMPID: 1775 has no free index
2019/11/03 20:37:23 20853DDC c (-) -- Skipped 4 duplicated log lines --
2019/11/03 20:37:23 20853DDC c (ecm) dvbapiau (P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=1CA4914C4CE97F860000000000000000 HOP
: found (4 ms) by emulator (L/1/2/2) - DSC Southeast Asia
2019/11/03 20:37:23 20853DDC c (ecm) cw:
2019/11/03 20:37:23 20853DDC c (ecm) 1C A4 91 4C 4C E9 7F 86 00 00 00 00 00 00 00 00
2019/11/03 20:37:23 6517A6F4 h (webif) WebIf: Origin checked. Result: access from 192.168.1.101 => allowed
2019/11/03 20:37:23 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 199 ms inactivity
2019/11/03 20:37:23 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:23 20853DDC c (dvbapi) Received:
2019/11/03 20:37:23 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:23 20853DDC c (dvbapi) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:23 20853DDC c (dvbapi) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:23 20853DDC c (dvbapi) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:23 20853DDC c (dvbapi) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:23 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:23 20853DDC c (dvbapi) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:23 20853DDC c (dvbapi) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:23 20853DDC c (dvbapi) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:23 20853DDC c (dvbapi) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:23 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 200 ms inactivity
2019/11/03 20:37:23 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:23 20853DDC c (dvbapi) Received:
2019/11/03 20:37:23 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:23 20853DDC c (dvbapi) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:23 20853DDC c (dvbapi) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:23 20853DDC c (dvbapi) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:23 20853DDC c (dvbapi) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:23 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:23 20853DDC c (dvbapi) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:23 20853DDC c (dvbapi) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:23 20853DDC c (dvbapi) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:23 20853DDC c (dvbapi) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:23 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 189 ms inactivity
2019/11/03 20:37:23 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:23 20853DDC c (dvbapi) Received:
2019/11/03 20:37:23 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:23 20853DDC c (dvbapi) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:23 20853DDC c (dvbapi) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:23 20853DDC c (dvbapi) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:23 20853DDC c (dvbapi) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2019/11/03 20:37:23 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:23 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 B3 08 51 00 14 1C 79 68
2019/11/03 20:37:23 20853DDC c (dvbapi) 9B 84 00 5A B0 00 4E 4E EE 94 00 84 58 06 52 F6
2019/11/03 20:37:23 20853DDC c (dvbapi) B3 EE 77 21 92 6A 90 09 00 25 EC 2B 9E 54 35 A3
2019/11/03 20:37:23 20853DDC c (dvbapi) F1 09 FC E1 4C EB 25 E2 2D B7 2B F1 E8 B4 49 74
2019/11/03 20:37:23 20853DDC c (dvbapi) 42 03 68 90 CF AA 19 D0
2019/11/03 20:37:23 00000000 (work) add reader job action 13 queue length 1 remote1
2019/11/03 20:37:23 00000000 (work) add reader job action 13 queue length 1 cw
2019/11/03 20:37:23 00000000 (work) start reader thread action 13
2019/11/03 20:37:23 00000000 (main) starting thread client work
2019/11/03 20:37:23 00000000 (main) client work thread started
2019/11/03 20:37:23 68403090 r (work) data from add_job action=13 client r emulator
2019/11/03 20:37:24 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 410 ms inactivity
2019/11/03 20:37:24 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:24 20853DDC c (dvbapi) Received:
2019/11/03 20:37:24 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 D1 08 94 46 B8 CB 3F D5
2019/11/03 20:37:24 20853DDC c (dvbapi) 86 89 00 5C B0 00 FA 5F 03 C2 BB 09 52 7C F2 74
2019/11/03 20:37:24 20853DDC c (dvbapi) D6 E3 25 99 D7 6A 90 09 00 A0 5F 2E 52 E5 01 16
2019/11/03 20:37:24 20853DDC c (dvbapi) 2B C7 FB 73 34 B6 E1 49 58 A8 64 C6 3D 3B BD EB
2019/11/03 20:37:24 20853DDC c (dvbapi) 2C 3B 8D C4 6B A6 8C 74
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:24 20853DDC c (dvbapi) 81 30 45 50 3F 20 0E 00 D1 08 94 46 B8 CB 3F D5
2019/11/03 20:37:24 20853DDC c (dvbapi) 86 89 00 5C B0 00 FA 5F 03 C2 BB 09 52 7C F2 74
2019/11/03 20:37:24 20853DDC c (dvbapi) D6 E3 25 99 D7 6A 90 09 00 A0 5F 2E 52 E5 01 16
2019/11/03 20:37:24 20853DDC c (dvbapi) 2B C7 FB 73 34 B6 E1 49 58 A8 64 C6 3D 3B BD EB
2019/11/03 20:37:24 20853DDC c (dvbapi) 2C 3B 8D C4 6B A6 8C 74
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 ECMTYPE 81 CAID 0E00 PROVID 000000 ECMPID 1775 FAKECHID 0000 (unique part in ecm)
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 Filter 1 set ecmtable to EVEN (CAID 0E00 PROVID 000000 FD 18)
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 18
2019/11/03 20:37:24 20853DDC c (ecm) get cw for ecm:
2019/11/03 20:37:24 20853DDC c (ecm) 81 30 45 50 3F 20 0E 00 D1 08 94 46 B8 CB 3F D5
2019/11/03 20:37:24 20853DDC c (ecm) 86 89 00 5C B0 00 FA 5F 03 C2 BB 09 52 7C F2 74
2019/11/03 20:37:24 20853DDC c (ecm) D6 E3 25 99 D7 6A 90 09 00 A0 5F 2E 52 E5 01 16
2019/11/03 20:37:24 20853DDC c (ecm) 2B C7 FB 73 34 B6 E1 49 58 A8 64 C6 3D 3B BD EB
2019/11/03 20:37:24 20853DDC c (ecm) 2C 3B 8D C4 6B A6 8C 74
2019/11/03 20:37:24 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [get_cw] NEW REQUEST!
2019/11/03 20:37:24 20853DDC c (chk) reader unavailable remote1
2019/11/03 20:37:24 20853DDC c (chk) reader unavailable remote
2019/11/03 20:37:24 20853DDC c (-) -- Skipped 2 duplicated log lines --
2019/11/03 20:37:24 20853DDC c (chk) trying reader 'emulator' filter 0E00@000000
2019/11/03 20:37:24 20853DDC c (chk) 0E00@000000 allowed by reader 'emulator' filter 0E00@000000
2019/11/03 20:37:24 20853DDC c (ecm) request_cw stage=2 to reader emulator ecm hash=A31C6432D442E18A5EB29F46A3CAA3A9
2019/11/03 20:37:24 20853DDC c (work) start reader thread action 5
2019/11/03 20:37:24 20853DDC c (main) starting thread client work
2019/11/03 20:37:24 20853DDC c (main) client work thread started
2019/11/03 20:37:24 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [write_ecm_request] reader emulator --> SENT 1
2019/11/03 20:37:24 20853DDC c (ecm) Client dvbapiau ECM dump P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000000000000000000000 HOP:
2019/11/03 20:37:24 20853DDC c (ecm) 81 30 45 50 3F 20 0E 00 D1 08 94 46 B8 CB 3F D5
2019/11/03 20:37:24 20853DDC c (ecm) 86 89 00 5C B0 00 FA 5F 03 C2 BB 09 52 7C F2 74
2019/11/03 20:37:24 20853DDC c (ecm) D6 E3 25 99 D7 6A 90 09 00 A0 5F 2E 52 E5 01 16
2019/11/03 20:37:24 20853DDC c (ecm) 2B C7 FB 73 34 B6 E1 49 58 A8 64 C6 3D 3B BD EB
2019/11/03 20:37:24 20853DDC c (ecm) 2C 3B 8D C4 6B A6 8C 74
2019/11/03 20:37:24 68403090 r (work) data from add_job action=5 client r emulator
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 request controlword for ecm P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=00000000000000000000000000000000 HOP:
2019/11/03 20:37:24 68403090 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/11/03 20:37:24 68403090 r (reader) emulator [emu] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2019/11/03 20:37:24 68403090 r (emu) csaUsed: 0, xorMode: 0, ecmSrvid: 06A9, hashModeCw: 1, modeCW: 8
2019/11/03 20:37:24 68403090 r (emu) channel hash: 011E3D05, group id: 0000
2019/11/03 20:37:24 00000000 (work) start client thread action 31
2019/11/03 20:37:24 00000000 (main) starting thread client work
2019/11/03 20:37:24 68403090 r (emu) calculated cw 0: 323BC898852FD5FE
2019/11/03 20:37:24 00000000 (main) client work thread started
2019/11/03 20:37:24 20853DDC c (work) data from add_job action=31 client c dvbapiau
2019/11/03 20:37:24 68403090 r (emu) calculated cw 1: 4C9445B51A2F2052
2019/11/03 20:37:24 68403090 r (emu) calculated cw 2: B06DC268102CAD94
2019/11/03 20:37:24 68403090 r (emu) calculated cw 3: B06DC268102CAD94
2019/11/03 20:37:24 68403090 r (emu) calculated cw 4: B06DC268102CAD94
2019/11/03 20:37:24 68403090 r (emu) calculated cw 5: B06DC268102CAD94
2019/11/03 20:37:24 68403090 r (emu) calculated cw 6: 0401376798CB7568
2019/11/03 20:37:24 68403090 r (emu) calculated cw 7: 3729EC982525EF2F
2019/11/03 20:37:24 68403090 r (reader) emulator [emu] cardreader_do_ecm: after csystem->do_ecm rc=1
2019/11/03 20:37:24 68403090 r (reader) emulator [emu] cardreader_do_ecm: ret rc=1
2019/11/03 20:37:24 68403090 r (reader) emulator [emu] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2019/11/03 20:37:24 68403090 r (ecm) notice: CW checksum check disabled for 0E00:000000
2019/11/03 20:37:24 68403090 r (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [write_ecm_answer] reader emulator rc 0, ecm time 2 ms (2 ms)
2019/11/03 20:37:24 68403090 r (work) start client thread action 32
2019/11/03 20:37:24 68403090 r (main) starting thread client work
2019/11/03 20:37:24 68403090 r (main) client work thread started
2019/11/03 20:37:24 20853DDC c (work) data from add_job action=32 client c dvbapiau
2019/11/03 20:37:24 20853DDC c (ecm) {client dvbapiau, caid 0E00, prid 000000, srvid 06A9} [send_dcw] rc 0 from reader emulator
2019/11/03 20:37:24 20853DDC c (ecm) Client dvbapiau cw 0000000000000000323BC898852FD5FE rc 0 P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=0000000000000000323BC898852FD5FE HOP:
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 control word received for PID 0 CAID 0E00 PROVID 000000 ECMPID 1775 CHID 0000 VPID 05DC
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 requested controlword for ecm A31C6432D442E18A5EB29F46A3CAA3A9 on fd 18
2019/11/03 20:37:24 20853DDC c (dvbapi) generic delay: write cw 60 ms after ecmrequest
2019/11/03 20:37:24 20853DDC c (dvbapi) delayer: gone=3 ms, cfg=60 ms -> delay=57 ms
2019/11/03 20:37:24 68403090 r (reader) emulator [emu] TRACE: ecm answer for ecm hash A31C6432D442E18A5EB29F46A3CAA3A9 rc=0
2019/11/03 20:37:24 68403090 r (reader) emulator [emu] ecm hash: A31C6432D442E18A5EB29F46A3CAA3A9 real time: 2 ms
2019/11/03 20:37:24 00000000 (main) starting thread client work
2019/11/03 20:37:24 00000000 (main) client work thread started
2019/11/03 20:37:24 68403090 r (reader) emulator [emu] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 PID: 0 CAID: 0E00 ECMPID: 1775 has no free index
2019/11/03 20:37:24 20853DDC c (-) -- Skipped 4 duplicated log lines --
2019/11/03 20:37:24 20853DDC c (ecm) dvbapiau (P: 0E00:000000:06A9:1775:0000: #ECM_L:48 #CW=0000000000000000323BC898852FD5FE HOP
: found (2 ms) by emulator (L/1/2/2) - DSC Southeast Asia
2019/11/03 20:37:24 20853DDC c (ecm) cw:
2019/11/03 20:37:24 20853DDC c (ecm) 00 00 00 00 00 00 00 00 32 3B C8 98 85 2F D5 FE
2019/11/03 20:37:24 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 199 ms inactivity
2019/11/03 20:37:24 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:24 20853DDC c (dvbapi) Received:
2019/11/03 20:37:24 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 0E 08 54 C4 A0 3E CC 96
2019/11/03 20:37:24 20853DDC c (dvbapi) 9A C7 00 5B B0 00 5F FD DB 11 77 A2 87 2C 91 CD
2019/11/03 20:37:24 20853DDC c (dvbapi) E8 8E E8 DA 15 6A 90 09 00 A1 40 D3 4D DA 02 02
2019/11/03 20:37:24 20853DDC c (dvbapi) BF 19 EA B5 E4 21 D1 31 3B 44 FB EF 85 E3 42 1C
2019/11/03 20:37:24 20853DDC c (dvbapi) AF F5 30 94 88 22 DF BD
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:24 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 0E 08 54 C4 A0 3E CC 96
2019/11/03 20:37:24 20853DDC c (dvbapi) 9A C7 00 5B B0 00 5F FD DB 11 77 A2 87 2C 91 CD
2019/11/03 20:37:24 20853DDC c (dvbapi) E8 8E E8 DA 15 6A 90 09 00 A1 40 D3 4D DA 02 02
2019/11/03 20:37:24 20853DDC c (dvbapi) BF 19 EA B5 E4 21 D1 31 3B 44 FB EF 85 E3 42 1C
2019/11/03 20:37:24 20853DDC c (dvbapi) AF F5 30 94 88 22 DF BD
2019/11/03 20:37:24 20853DDC c (dvbapi) New events occurred on 1 of 6 handlers after 201 ms inactivity
2019/11/03 20:37:24 20853DDC c (dvbapi) Now handling fd 18 that reported event 3
2019/11/03 20:37:24 20853DDC c (dvbapi) Received:
2019/11/03 20:37:24 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 0E 08 54 C4 A0 3E CC 96
2019/11/03 20:37:24 20853DDC c (dvbapi) 9A C7 00 5B B0 00 5F FD DB 11 77 A2 87 2C 91 CD
2019/11/03 20:37:24 20853DDC c (dvbapi) E8 8E E8 DA 15 6A 90 09 00 A1 40 D3 4D DA 02 02
2019/11/03 20:37:24 20853DDC c (dvbapi) BF 19 EA B5 E4 21 D1 31 3B 44 FB EF 85 E3 42 1C
2019/11/03 20:37:24 20853DDC c (dvbapi) AF F5 30 94 88 22 DF BD
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2019/11/03 20:37:24 20853DDC c (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x048):
2019/11/03 20:37:24 20853DDC c (dvbapi) 80 30 45 50 3F 20 0E 00 0E 08 54 C4 A0 3E CC 96
2019/11/03 20:37:24 20853DDC c (dvbapi) 9A C7 00 5B B0 00 5F FD DB 11 77 A2 87 2C 91 CD
2019/11/03 20:37:24 20853DDC c (dvbapi) E8 8E E8 DA 15 6A 90 09 00 A1 40 D3 4D DA 02 02
2019/11/03 20:37:24 20853DDC c (dvbapi) BF 19 EA B5 E4 21 D1 31 3B 44 FB EF 85 E3 42 1C
2019/11/03 20:37:24 20853DDC c (dvbapi) AF F5 30 94 88 22 DF BD