2014/10/23 19:17:01 846A5E8 c [OSCAM-WORK] new event 1 occurred on fd 24 after 6998 ms inactivity2014/10/23 19:17:01 846A5E8 c nmr(): len=2, errno=0
2014/10/23 19:17:01 846A5E8 c received 154 bytes from client
2014/10/23 19:17:01 846A5E8 00 83 81 70 95 00 00 01 1D 81 17 8A 1B 02 5B AA
2014/10/23 19:17:01 846A5E8 55 05 20 01 00 00 80 40 75 AE E8 BE 0D B2 23 90
2014/10/23 19:17:01 846A5E8 B9 03 02 05 02 9D 73 7D 0B 0C 25 62 F3 33 2A 17
2014/10/23 19:17:01 846A5E8 74 38 00 00 90 64 C1 01 CD CE 1F 48 29 D2 31 82
2014/10/23 19:17:01 846A5E8 CF 2A 51 42 C3 0A EA E3 36 C6 F3 C8 A1 6D 9C FA
2014/10/23 19:17:01 846A5E8 8B 09 14 D9 16 93 BF 47 DD 38 AE 11 93 D6 D2 C8
2014/10/23 19:17:01 846A5E8 90 A6 D0 C4 8B AA 74 50 72 7E 37 A5 7B 50 85 1B
2014/10/23 19:17:01 846A5E8 E1 14 15 D5 3E 05 E3 B8 E6 03 E7 21 A7 FF DD AA
2014/10/23 19:17:01 846A5E8 94 25 A7 DE 57 22 95 7C C9 4A B7 D2 E2 2F 70 08
2014/10/23 19:17:01 846A5E8 6B 66 AD 64 4F 1A 4F 9C 25 F4
2014/10/23 19:17:01 846A5E8 c newcamd: got cmd 129
2014/10/23 19:17:01 846A5E8 c ncd_process_ecm: er->msgid=7 len=154 ecmlen=152
2014/10/23 19:17:01 846A5E8 c {client test01, caid 098C, prid 000000, srvid 0083} [get_cw] NEW REQUEST!
2014/10/23 19:17:01 846A5E8 c trying user 'test01' filter 098C:000000
2014/10/23 19:17:01 846A5E8 c 098C:000000 allowed by user 'test01' filter 098C:000000
2014/10/23 19:17:01 846A5E8 c trying server filter 098C:000000
2014/10/23 19:17:01 846A5E8 c 098C:000000 allowed by server filter 098C:000000
2014/10/23 19:17:01 846A5E8 c caid 098C not found in caidlist reader hd+
2014/10/23 19:17:01 846A5E8 c caid 098C not found in caidlist reader orf
2014/10/23 19:17:01 846A5E8 c caid 098C not found in caidlist reader sky
2014/10/23 19:17:01 846A5E8 c caid 098C not found in caidlist reader red
2014/10/23 19:17:01 846A5E8 c request_cw stage=2 to reader em2 ecm hash=570424422F23044DCFDE94C78D2B7556
2014/10/23 19:17:01 846A5E8 c start reader thread action 5
2014/10/23 19:17:01 846A5E8 c {client test01, caid 098C, prid 000000, srvid 0083} [write_ecm_request] reader em2 --> SENT 1
2014/10/23 19:17:01 846A5E8 c Client test01 ECM dump 098C:000000:0083, CW:00000000000000000000000000000000
2014/10/23 19:17:01 846A5E8 81 70 95 00 00 01 1D 81 17 8A 1B 02 5B AA 55 05
2014/10/23 19:17:01 846A5E8 20 01 00 00 80 40 75 AE E8 BE 0D B2 23 90 B9 03
2014/10/23 19:17:01 846A5E8 02 05 02 9D 73 7D 0B 0C 25 62 F3 33 2A 17 74 38
2014/10/23 19:17:01 846A5E8 00 00 90 64 C1 01 CD CE 1F 48 29 D2 31 82 CF 2A
2014/10/23 19:17:01 846A5E8 51 42 C3 0A EA E3 36 C6 F3 C8 A1 6D 9C FA 8B 09
2014/10/23 19:17:01 846A5E8 14 D9 16 93 BF 47 DD 38 AE 11 93 D6 D2 C8 90 A6
2014/10/23 19:17:01 846A5E8 D0 C4 8B AA 74 50 72 7E 37 A5 7B 50 85 1B E1 14
2014/10/23 19:17:01 846A5E8 15 D5 3E 05 E3 B8 E6 03 E7 21 A7 FF DD AA 94 25
2014/10/23 19:17:01 846A5E8 A7 DE 57 22 95 7C C9 4A B7 D2 E2 2F 70 08 6B 66
2014/10/23 19:17:01 846A5E8 AD 64 4F 1A 4F 9C 25 F4
2014/10/23 19:17:01 8462958 r data from add_job action=5 client r em2
2014/10/23 19:17:01 8462958 r ecm:
2014/10/23 19:17:01 8462958 81 70 95 00 00 01 1D 81 17 8A 1B 02 5B AA 55 05
2014/10/23 19:17:01 8462958 20 01 00 00 80 40 75 AE E8 BE 0D B2 23 90 B9 03
2014/10/23 19:17:01 8462958 02 05 02 9D 73 7D 0B 0C 25 62 F3 33 2A 17 74 38
2014/10/23 19:17:01 8462958 00 00 90 64 C1 01 CD CE 1F 48 29 D2 31 82 CF 2A
2014/10/23 19:17:01 8462958 51 42 C3 0A EA E3 36 C6 F3 C8 A1 6D 9C FA 8B 09
2014/10/23 19:17:01 8462958 14 D9 16 93 BF 47 DD 38 AE 11 93 D6 D2 C8 90 A6
2014/10/23 19:17:01 8462958 D0 C4 8B AA 74 50 72 7E 37 A5 7B 50 85 1B E1 14
2014/10/23 19:17:01 8462958 15 D5 3E 05 E3 B8 E6 03 E7 21 A7 FF DD AA 94 25
2014/10/23 19:17:01 8462958 A7 DE 57 22 95 7C C9 4A B7 D2 E2 2F 70 08 6B 66
2014/10/23 19:17:01 8462958 AD 64 4F 1A 4F 9C 25 F4
2014/10/23 19:17:01 8462958 r em2 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/10/23 19:17:01 8462958 r em2 [videoguard2] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2014/10/23 19:17:01 8462958 r em2 [videoguard2] write to cardreader
2014/10/23 19:17:01 8462958 D1 40 00 80 74 00 7D 0B 0C 25 62 F3 33 2A 17 74
2014/10/23 19:17:01 8462958 38 00 00 90 64 C1 01 CD CE 1F 48 29 D2 31 82 CF
2014/10/23 19:17:01 8462958 2A 51 42 C3 0A EA E3 36 C6 F3 C8 A1 6D 9C FA 8B
2014/10/23 19:17:01 8462958 09 14 D9 16 93 BF 47 DD 38 AE 11 93 D6 D2 C8 90
2014/10/23 19:17:01 8462958 A6 D0 C4 8B AA 74 50 72 7E 37 A5 7B 50 85 1B E1
2014/10/23 19:17:01 8462958 14 15 D5 3E 05 E3 B8 E6 03 E7 21 A7 FF DD AA 94
2014/10/23 19:17:01 8462958 25 A7 DE 57 22 95 7C C9 4A B7 D2 E2 2F 70 08 6B
2014/10/23 19:17:01 8462958 66 AD 64 4F 1A 4F 9C 25 F4
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 14 ETU (109 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit size 5 bytes, expected len 1 bytes, delay 0 us, timeout=109 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit:
2014/10/23 19:17:01 8462958 D1 40 00 80 74
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=5 buf[0]=d1
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Write timeout 109 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Sending:
2014/10/23 19:17:01 8462958 74 FD FF FE D1
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Reading 5 echoed transmitted chars...
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 9990000 us, read delay 0 us, to read 5 char(s), chunksize 5 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 74 FD FF FE D1
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Reading of echoed transmitted chars done!
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=5 buf[0]=74
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 230400 ETU (1800605 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1800605 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 1800605 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 FD
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=1 buf[0]=fd
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 14 ETU (109 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit size 116 bytes, expected len 2 bytes, delay 0 us, timeout=109 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit:
2014/10/23 19:17:01 8462958 00 7D 0B 0C 25 62 F3 33 2A 17 74 38 00 00 90 64
2014/10/23 19:17:01 8462958 C1 01 CD CE 1F 48 29 D2 31 82 CF 2A 51 42 C3 0A
2014/10/23 19:17:01 8462958 EA E3 36 C6 F3 C8 A1 6D 9C FA 8B 09 14 D9 16 93
2014/10/23 19:17:01 8462958 BF 47 DD 38 AE 11 93 D6 D2 C8 90 A6 D0 C4 8B AA
2014/10/23 19:17:01 8462958 74 50 72 7E 37 A5 7B 50 85 1B E1 14 15 D5 3E 05
2014/10/23 19:17:01 8462958 E3 B8 E6 03 E7 21 A7 FF DD AA 94 25 A7 DE 57 22
2014/10/23 19:17:01 8462958 95 7C C9 4A B7 D2 E2 2F 70 08 6B 66 AD 64 4F 1A
2014/10/23 19:17:01 8462958 4F 9C 25 F4
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=116 buf[0]=00
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Write timeout 109 us, write delay 0 us, to send 116 char(s), chunksize 116 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Sending:
2014/10/23 19:17:01 8462958 FF 41 2F CF 5B B9 30 33 AB 17 D1 E3 FF FF F6 D9
2014/10/23 19:17:01 8462958 7C 7F 4C 8C 07 ED 6B B4 73 BE 0C AB 75 BD 3C AF
2014/10/23 19:17:01 8462958 A8 38 93 9C 30 EC 7A 49 C6 A0 2E 6F D7 64 97 36
2014/10/23 19:17:01 8462958 02 1D 44 E3 8A 77 36 94 B4 EC F6 9A F4 DC 2E AA
2014/10/23 19:17:01 8462958 D1 F5 B1 81 13 5A 21 F5 5E 27 78 D7 57 54 83 5F
2014/10/23 19:17:01 8462958 38 E2 98 3F 18 7B 1A 00 44 AA D6 5B 1A 84 15 BB
2014/10/23 19:17:01 8462958 56 C1 6C AD 12 B4 B8 0B F1 EF 29 99 4A D9 0D A7
2014/10/23 19:17:01 8462958 0D C6 5B D0
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Reading 116 echoed transmitted chars...
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 9990000 us, read delay 0 us, to read 116 char(s), chunksize 116 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 FF 41 2F CF 5B B9 30 33 AB 17 D1 E3 FF FF F6 D9
2014/10/23 19:17:01 8462958 7C 7F 4C 8C 07 ED 6B B4 73 BE 0C AB 75 BD 3C AF
2014/10/23 19:17:01 8462958 A8 38 93 9C 30 EC 7A 49 C6 A0 2E 6F D7 64 97 36
2014/10/23 19:17:01 8462958 02 1D 44 E3 8A 77 36 94 B4 EC F6 9A F4 DC 2E AA
2014/10/23 19:17:01 8462958 D1 F5 B1 81 13 5A 21 F5 5E 27 78 D7 57 54 83 5F
2014/10/23 19:17:01 8462958 38 E2 98 3F 18 7B 1A 00 44 AA D6 5B 1A 84 15 BB
2014/10/23 19:17:01 8462958 56 C1 6C AD 12 B4 B8 0B F1 EF 29 99 4A D9 0D A7
2014/10/23 19:17:01 8462958 0D C6 5B D0
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Reading of echoed transmitted chars done!
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=116 buf[0]=ff
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 230400 ETU (1800605 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1800605 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 1800605 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 F6
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=1 buf[0]=f6
2014/10/23 19:17:01 8462958 r em2 [videoguard2] TRACE: SW1: 90
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 230400 ETU (1800605 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1800605 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 1800605 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 FF
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=1 buf[0]=ff
2014/10/23 19:17:01 8462958 r em2 [videoguard2] TRACE: SW2: 00
2014/10/23 19:17:01 8462958 r em2 [videoguard2] Answer from cardreader:
2014/10/23 19:17:01 8462958 90 00
2014/10/23 19:17:01 8462958 r em2 [videoguard2] write to cardreader
2014/10/23 19:17:01 8462958 D3 54 00 00 5E
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 14 ETU (109 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit size 5 bytes, expected len 97 bytes, delay 0 us, timeout=109 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit:
2014/10/23 19:17:01 8462958 D3 54 00 00 5E
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=5 buf[0]=d3
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Write timeout 109 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Sending:
2014/10/23 19:17:01 8462958 34 D5 FF FF 85
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Reading 5 echoed transmitted chars...
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 9990000 us, read delay 0 us, to read 5 char(s), chunksize 5 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 34 D5 FF FF 85
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Reading of echoed transmitted chars done!
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Transmit succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=5 buf[0]=34
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 230400 ETU (1800605 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1800605 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 1800605 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 D5
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=1 buf[0]=d5
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 230400 ETU (1800605 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive size 94 bytes, delay 0 us, timeout=1800605 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 1800605 us, read delay 0 us, to read 94 char(s), chunksize 94 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 D5 CE 19 6D E4 A5 9E EA 9F E1 53 65 48 02 EA ED
2014/10/23 19:17:01 8462958 A4 3E B9 5B 5A 20 2A 3C 4D C7 88 4A BC BA 37 9B
2014/10/23 19:17:01 8462958 9A BE 98 C6 0B 3F 26 31 3E D4 38 62 B9 C2 F3 81
2014/10/23 19:17:01 8462958 81 6B BC 2C 8D A7 DE 69 AE E6 C7 62 9A A8 F1 8C
2014/10/23 19:17:01 8462958 25 72 18 14 07 B0 02 9E A3 E5 1E 02 20 C4 D1 FA
2014/10/23 19:17:01 8462958 B8 3C CD 26 9E 51 C4 0F EA B4 EC 7C C8 B4
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=94 buf[0]=d5
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 230400 ETU (1800605 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1800605 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 1800605 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 F6
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=1 buf[0]=f6
2014/10/23 19:17:01 8462958 r em2 [videoguard2] TRACE: SW1: 90
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Setting timeout to 230400 ETU (1800605 us)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1800605 us
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Read timeout 1800605 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IO: Receiving:
2014/10/23 19:17:01 8462958 FF
2014/10/23 19:17:01 8462958 r em2 [videoguard2] IFD: Receive succesful
2014/10/23 19:17:01 8462958 r ICC_Async_InvertBuffer: size=1 buf[0]=ff
2014/10/23 19:17:01 8462958 r em2 [videoguard2] TRACE: SW2: 00
2014/10/23 19:17:01 8462958 r em2 [videoguard2] Answer from cardreader:
2014/10/23 19:17:01 8462958 54 8C 67 49 D8 5A 86 A8 06 78 35 59 ED BF A8 48
2014/10/23 19:17:01 8462958 DA 83 62 25 A5 FB AB C3 4D 1C EE AD C2 A2 13 26
2014/10/23 19:17:01 8462958 A6 82 E6 9C 2F 03 9B 73 83 D4 E3 B9 62 BC 30 7E
2014/10/23 19:17:01 8462958 7E 29 C2 CB 4E 1A 84 69 8A 98 1C B9 A6 EA 70 CE
2014/10/23 19:17:01 8462958 5B B1 E7 D7 1F F2 BF 86 3A 58 87 BF FB DC 74 A0
2014/10/23 19:17:01 8462958 E2 C3 4C 9B 86 75 DC 0F A8 D2 C8 C1 EC D2 90 00
2014/10/23 19:17:01 8462958 r em2 [videoguard2] Decrypted payload
2014/10/23 19:17:01 8462958 00 00 00 00 00 00 00 00 00 00 00 CA 00 00 22 02
2014/10/23 19:17:01 8462958 00 80 0E 02 00 00 0F 06 00 10 00 00 00 0A 20 04
2014/10/23 19:17:01 8462958 00 00 00 00 25 11 00 00 00 00 00 00 00 00 00 00
2014/10/23 19:17:01 8462958 00 00 00 00 00 00 00 2A 04 00 CA 00 00 55 01 20
2014/10/23 19:17:01 8462958 56 08 00 00 00 00 00 00 00 00 2B 02 00 00
2014/10/23 19:17:01 8462958 r em2 [videoguard2] classD3 ins54: no cw --> Card needs pairing/extra data
2014/10/23 19:17:01 8462958 r em2 [videoguard2] cardreader_do_ecm: after csystem.do_ecm rc=0
2014/10/23 19:17:01 8462958 r em2 [videoguard2] cardreader_do_ecm: ret rc=0
2014/10/23 19:17:01 8462958 r em2 [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=0 (ERROR=0)
2014/10/23 19:17:01 8462958 r em2 [videoguard2] Error processing ecm for caid 098C, srvid 0083, servicename: 098C:0083 unknown
2014/10/23 19:17:01 8462958 r {client test01, caid 098C, prid 000000, srvid 0083} [write_ecm_answer] reader em2 rc 4, ecm time 64 ms (64 ms)
2014/10/23 19:17:01 846A5E8 c {client test01, caid 098C, prid 000000, srvid 0083} [send_dcw] rc 4 from reader em2
2014/10/23 19:17:01 846A5E8 c Client test01 cw 00000000000000000000000000000000 rc 4 098C:000000:0083, CW:00000000000000000000000000000000
2014/10/23 19:17:01 846A5E8 c ncd_send_dcw: er->msgid=7, cl_msgid=7, 81
2014/10/23 19:17:01 846A5E8 c send 15 bytes to client
2014/10/23 19:17:01 846A5E8 00 0D 00 07 00 00 00 00 00 00 00 00 81 00 00
2014/10/23 19:17:01 846A5E8 c test01 (098C:000000:0083, CW:00000000000000000000000000000000): not found (64 ms) by em2
2014/10/23 19:17:01 846A5E8 c cw:
2014/10/23 19:17:01 846A5E8 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2014/10/23 19:17:01 8462958 r add client job action 32 queue length 0 test01
2014/10/23 19:17:01 8462958 r em2 [videoguard2] TRACE: ecm answer for ecm hash 570424422F23044DCFDE94C78D2B7556 rc=4
2014/10/23 19:17:01 8462958 r em2 [videoguard2] ecm hash: 570424422F23044DCFDE94C78D2B7556 real time: 62 ms