2014/10/08 16:24:03 905AF38 95 C2 EF 8F 80 58 AC 17 19 7B 60 DF 7A 67 82 5D
2014/10/08 16:24:03 905AF38 AD 7A 04 22 C1 5F 49 7A E6 03 E5 9F C5 02 53 8A
2014/10/08 16:24:03 905AF38 1D 4C 25 3D 08 5F 91 83 81 8E B7 A1 D6 89 6A 50
2014/10/08 16:24:03 905AF38 96 55 5E 74 13 05 8C BB E0 C2 39 F1 0E 91 9F D1
2014/10/08 16:24:03 905AF38 B2 9B F3 6F 0B B1 4D D5 2C 94 21 82 19 8E 9B C5
2014/10/08 16:24:03 905AF38 0F 32 81 E6 8E D1 72 8A 45 39 DC 4E 63 2E
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IFD: Receive succesful
2014/10/08 16:24:03 905AF38 r ICC_Async_InvertBuffer: size=94 buf[0]=95
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IFD: Setting timeout to 230400 ETU (1950655 us)
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1950655 us
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IO: Read timeout 1950655 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IO: Receiving:
2014/10/08 16:24:03 905AF38 F6
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IFD: Receive succesful
2014/10/08 16:24:03 905AF38 r ICC_Async_InvertBuffer: size=1 buf[0]=f6
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] TRACE: SW1: 90
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IFD: Setting timeout to 230400 ETU (1950655 us)
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=1950655 us
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IO: Read timeout 1950655 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IO: Receiving:
2014/10/08 16:24:03 905AF38 FF
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] IFD: Receive succesful
2014/10/08 16:24:03 905AF38 r ICC_Async_InvertBuffer: size=1 buf[0]=ff
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] TRACE: SW2: 00
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] Answer from cardreader:
2014/10/08 16:24:03 905AF38 56 BC 08 0E FE E5 CA 17 67 21 F9 04 A1 19 BE 45
2014/10/08 16:24:03 905AF38 4A A1 DF BB 7C 05 6D A1 98 3F 58 06 5C BF 35 AE
2014/10/08 16:24:03 905AF38 47 CD 5B 43 EF 05 76 3E 7E 8E 12 7A 94 6E A9 F5
2014/10/08 16:24:03 905AF38 96 55 85 D1 37 5F CE 22 F8 BC 63 70 8F 76 06 74
2014/10/08 16:24:03 905AF38 B2 26 30 09 2F 72 4D 54 CB D6 7B BE 67 8E 26 5C
2014/10/08 16:24:03 905AF38 0F B3 7E 98 8E 74 B1 AE 5D 63 C4 8D 39 8B 90 00
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] Decrypted payload
2014/10/08 16:24:03 905AF38 00 00 00 00 00 00 00 00 00 00 00 00 FF 00 22 02
2014/10/08 16:24:03 905AF38 00 00 0E 02 00 00 0F 06 02 30 20 00 00 11 20 04
2014/10/08 16:24:03 905AF38 00 00 00 00 25 11 00 00 00 00 00 00 00 00 00 00
2014/10/08 16:24:03 905AF38 00 00 00 00 00 00 00 2A 04 00 00 00 00 55 01 20
2014/10/08 16:24:03 905AF38 56 08 00 00 00 00 00 00 00 00 2B 02 00 00
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] classD3 ins54: no cw --> Card isn't active
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] classD3 ins54: no cw --> Card is paired
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] cardreader_do_ecm: after csystem.do_ecm rc=0
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] cardreader_do_ecm: ret rc=0
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=0 (ERROR=0)
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] Error processing ecm for caid 098C, srvid 006C, servicename: Sky Sport News HD
2014/10/08 16:24:03 905AF38 r {client Harald, caid 098C, srvid 006C} [write_ecm_answer] cyclecheck passed! Reader: sky-V14 rc: 4
2014/10/08 16:24:03 905AF38 r {client Harald, caid 098C, prid 000000, srvid 006C} [write_ecm_answer] reader sky-V14 rc 4, ecm time 75 ms (76 ms)
2014/10/08 16:24:03 9071028 c {client Harald, caid 098C, prid 000000, srvid 006C} [send_dcw] rc 4 from reader sky-V14
2014/10/08 16:24:03 9071028 c Client Harald csphash 7D92A853 cw 00000000000000000000000000000000 rc 4 098C&000000/0C20/006C/98:060F08DAC949A37E6B411E48697AAB35
2014/10/08 16:24:03 9071028 c Harald (098C&000000/0C20/006C/98:060F08DAC949A37E6B411E48697AAB35): not found (76 ms) by sky-V14 - Sky Sport News HD
2014/10/08 16:24:03 9071028 c cw:
2014/10/08 16:24:03 9071028 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2014/10/08 16:24:03 905AF38 r add client job action 32 queue length 0 Harald
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] TRACE: ecm answer for ecm hash 060F08DAC949A37E6B411E48697AAB35 rc=4
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] ecm hash: 060F08DAC949A37E6B411E48697AAB35 real time: 70 ms
2014/10/08 16:24:03 905D168 h WebIf: Origin checked. Result: access from 192.168.2.20 => allowed
2014/10/08 16:24:03 905AF38 r sky-V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/10/08 16:24:04 0 --- Skipped 1 duplicated log lines ---
2014/10/08 16:24:04 0 add client job action 31 queue length 1 Harald
2014/10/08 16:24:05 905D168 h WebIf: Origin checked. Result: access from 192.168.2.20 => allowed
2014/10/08 16:24:05 905AF38 r sky-V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/10/08 16:24:06 905D168 h WebIf: Origin checked. Result: access from 192.168.2.20 => allowed
2014/10/08 16:24:06 905AF38 r sky-V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/10/08 16:24:07 905D168 h WebIf: Origin checked. Result: access from 192.168.2.20 => allowed
2014/10/08 16:24:07 905AF38 r sky-V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/10/08 16:24:08 905D168 h WebIf: Origin checked. Result: access from 192.168.2.20 => allowed
2014/10/08 16:24:08 905AF38 r sky-V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/10/08 16:24:09 0 --- Skipped 1 duplicated log lines ---
2014/10/08 16:24:09 905D168 h WebIf: Origin checked. Result: access from 192.168.2.20 => allowed
2014/10/08 16:24:10 9071028 c [OSCAM-WORK] new event 1 occurred on fd 8 after 7247 ms inactivity
2014/10/08 16:24:10 9071028 c cccam(s) Harald: parse_msg=1
2014/10/08 16:24:10 9071028 c cccam(s) Harald: forward card: 098C:006c search share 100
2014/10/08 16:24:10 9071028 c cccam(s) Harald: forward card: share 100 found: 1
2014/10/08 16:24:10 9071028 c cccam(s) Harald: forward card: share 100 origin reader sky-V14 origin id 0
2014/10/08 16:24:10 9071028 c cccam(s) Harald: forward card: share 100 forwarded to sky-V14 origin as id 100
2014/10/08 16:24:10 9071028 c cccam(s) Harald: ECM request from client: caid 098c srvid 006c(152) prid 000000
2014/10/08 16:24:10 9071028 c {client Harald, caid 098C, prid 000000, srvid 006C} [get_cw] NEW REQUEST!
2014/10/08 16:24:10 9071028 c [GET_CW] wait_time 0 caid 098C prov 000000 srvid 006C rc 100 cacheex cl mode 0 ex1rdr 0
2014/10/08 16:24:10 9071028 c {client Harald, caid 098C, prid 000000, srvid 006C} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0
2014/10/08 16:24:10 9071028 c request_cw stage=3 to reader sky-V14 ecm hash=57034BA2453BB153CF4CEFD92EFC7C35
2014/10/08 16:24:10 9071028 c start reader thread action 5
2014/10/08 16:24:10 9071028 c {client Harald, caid 098C, prid 000000, srvid 006C} [write_ecm_request] reader sky-V14 --> SENT 1
2014/10/08 16:24:10 9071028 c Client Harald ECM dump 098C&000000/0C20/006C/98:57034BA2453BB153CF4CEFD92EFC7C35
2014/10/08 16:24:10 9071028 80 70 95 00 00 01 1D 81 08 73 04 0C 20 AA 55 05
2014/10/08 16:24:10 9071028 20 01 00 00 80 40 E4 CD 77 FA 22 BA E8 A4 3C 03
2014/10/08 16:24:10 9071028 02 06 02 02 73 7D 0B 0C 4C E8 B5 13 90 0B CA E1
2014/10/08 16:24:10 9071028 00 00 90 64 C1 01 70 44 29 D6 2B 48 DB E8 38 11
2014/10/08 16:24:10 9071028 96 89 F9 4E 1B 4A AE 70 89 B5 09 BB 60 48 36 91
2014/10/08 16:24:10 9071028 51 40 A5 78 0C 17 10 F4 D0 60 BC 63 37 44 A4 8E
2014/10/08 16:24:10 9071028 04 6C 9F 59 EC ED 34 53 A4 DD 77 DA 52 E8 6E 49
2014/10/08 16:24:10 9071028 E0 FD C3 51 68 EB D4 D1 A9 63 7D D7 EE 98 F0 E5
2014/10/08 16:24:10 9071028 12 25 44 B9 67 06 DC EA D3 AA BA E5 20 E8 71 63
2014/10/08 16:24:10 9071028 99 B4 D1 11 A2 98 4F E7
2014/10/08 16:24:10 905AF38 r data from add_job action=5 client r sky-V14
2014/10/08 16:24:10 905AF38 r ecm:
2014/10/08 16:24:10 905AF38 80 70 95 00 00 01 1D 81 08 73 04 0C 20 AA 55 05
2014/10/08 16:24:10 905AF38 20 01 00 00 80 40 E4 CD 77 FA 22 BA E8 A4 3C 03
2014/10/08 16:24:10 905AF38 02 06 02 02 73 7D 0B 0C 4C E8 B5 13 90 0B CA E1
2014/10/08 16:24:10 905AF38 00 00 90 64 C1 01 70 44 29 D6 2B 48 DB E8 38 11
2014/10/08 16:24:10 905AF38 96 89 F9 4E 1B 4A AE 70 89 B5 09 BB 60 48 36 91
2014/10/08 16:24:10 905AF38 51 40 A5 78 0C 17 10 F4 D0 60 BC 63 37 44 A4 8E
2014/10/08 16:24:10 905AF38 04 6C 9F 59 EC ED 34 53 A4 DD 77 DA 52 E8 6E 49
2014/10/08 16:24:10 905AF38 E0 FD C3 51 68 EB D4 D1 A9 63 7D D7 EE 98 F0 E5
2014/10/08 16:24:10 905AF38 12 25 44 B9 67 06 DC EA D3 AA BA E5 20 E8 71 63
2014/10/08 16:24:10 905AF38 99 B4 D1 11 A2 98 4F E7
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] write to cardreader
2014/10/08 16:24:10 905AF38 D1 40 00 80 74 00 7D 0B 0C 4C E8 B5 13 90 0B CA
2014/10/08 16:24:10 905AF38 E1 00 00 90 64 C1 01 70 44 29 D6 2B 48 DB E8 38
2014/10/08 16:24:10 905AF38 11 96 89 F9 4E 1B 4A AE 70 89 B5 09 BB 60 48 36
2014/10/08 16:24:10 905AF38 91 51 40 A5 78 0C 17 10 F4 D0 60 BC 63 37 44 A4
2014/10/08 16:24:10 905AF38 8E 04 6C 9F 59 EC ED 34 53 A4 DD 77 DA 52 E8 6E
2014/10/08 16:24:10 905AF38 49 E0 FD C3 51 68 EB D4 D1 A9 63 7D D7 EE 98 F0
2014/10/08 16:24:10 905AF38 E5 12 25 44 B9 67 06 DC EA D3 AA BA E5 20 E8 71
2014/10/08 16:24:10 905AF38 63 99 B4 D1 11 A2 98 4F E7
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] IFD: Setting timeout to 14 ETU (118 us)
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] IFD: Transmit size 5 bytes, expected len 1 bytes, delay 0 us, timeout=118 us
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] IFD: Transmit:
2014/10/08 16:24:10 905AF38 D1 40 00 80 74
2014/10/08 16:24:10 905AF38 r ICC_Async_InvertBuffer: size=5 buf[0]=d1
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] IO: Write timeout 118 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] IO: Sending:
2014/10/08 16:24:10 905AF38 74 FD FF FE D1
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] IO: Reading 5 echoed transmitted chars...
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] IO: Read timeout 9990000 us, read delay 0 us, to read 5 char(s), chunksize 5 char(s)
2014/10/08 16:24:10 905AF38 r sky-V14 [videoguard2] IO: Receiving: