-------------------------------------------------------------------------------
>> OSCam << cardserver log switched at Sat Dec 20 22:55:43 2014
-------------------------------------------------------------------------------
2014/12/20 22:55:43 8AE4C00 r
hd++ [nagra] eigenserver emmtype=global, len=150, idx=2, cnt=1: written (459 ms)
2014/12/20 22:55:43 0 >> OSCam << cardserver log switched, version 1.20-unstable_svn, build r9997 (i486-linux-gnu)
2014/12/20 22:55:46 8B52E58 c lothar (1702&000000/0000/0082/93:A636ACA7BA0544CED13C69354D3B7160): found (521 ms) by s02 (P/1/2/2) - Discovery HD
2014/12/20 22:55:46 8B69258 c uwe (1702&000000/0000/0083/93:BC17EAAA3702FF0EB8A025EFC02CAB57): found (571 ms) by s02+ (P/1/2/2) - Sky Cinema HD
2014/12/20 22:55:46 8B4F0D8 c stefan1 (1702&000000/0000/0083/93:BC17EAAA3702FF0EB8A025EFC02CAB57): cache2 (52 ms) by s02+ (P/1/2/2) - Sky Cinema HD
2014/12/20 22:55:47 8B19010 c eigenserver (1830&000000/0000/EF10/92:0E33EF76441240922CD9A06DE497AEE8): rejected group (0 ms) - RTL HD (no matching reader)
2014/12/20 22:55:49 0 --- Skipped 1 duplicated log lines ---
2014/12/20 22:55:49 8B171E0 c detlef (1702&000000/0000/0017/93:F38811E76A3623F9AD0461A65A83CED3): found (636 ms) by s02+ (P/1/2/2) - Sky Krimi
2014/12/20 22:55:49 8B50F98 c stefan (1702&000000/0000/00DF/93:540BDF6731802407A483808E029A3F28): found (585 ms) by s02+ (P/1/1/2) - Sky Bundesliga 1
2014/12/20 22:55:51 8AE2CE0 r
hd+ [nagra] eigenserver emmtype=global, len=150, idx=3, cnt=1: written (454 ms)
2014/12/20 22:55:51 8AE4C00 r
hd++ [nagra] eigenserver emmtype=global, len=150, idx=3, cnt=1: written (458 ms)
2014/12/20 22:55:53 8B52E58 c lothar (1702&000000/0000/0082/93:97D934790A265127FCF701DA983BB241): found (520 ms) by s02 (P/1/2/2) - Discovery HD
2014/12/20 22:55:53 8B69258 c uwe (1702&000000/0000/0083/93:1CD0E3454AF2B18C5625115C04414AC4): found (572 ms) by s02+ (P/1/2/2) - Sky Cinema HD
2014/12/20 22:55:53 8B4F0D8 c stefan1 (1702&000000/0000/0083/93:1CD0E3454AF2B18C5625115C04414AC4): cache2 (552 ms) by s02+ (P/1/2/2) - Sky Cinema HD
2014/12/20 22:55:55 8AED790 h all debug_level=65535
2014/12/20 22:55:55 8AED790 h WebIf: Origin checked. Result: access from 192.168.1.158 => allowed
2014/12/20 22:55:55 8ADA148 r s02+ [irdeto] IO: Receiving:
2014/12/20 22:55:55 8ADA148 01 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00
2014/12/20 22:55:55 8ADA148 8F DC B2 0E AD 42 FF 3F E3 DF 67 38 3A 2C D8 A4
2014/12/20 22:55:55 8ADA148 77 7A 2C 3B C5 42 19 F1 A5 AF 67 DD 76 5C CC 97
2014/12/20 22:55:55 8ADA148 FE 6F 5B 49 DD 03 02 9F 6F 01 82 91 46 C5 EC AB
2014/12/20 22:55:55 8ADA148 25 86 74 E2 78 0C 8B EC 1B 9E 9F F3 EB 41 C4 A3
2014/12/20 22:55:55 8ADA148 D9 E2 BD 4F 35 17 59 39 F5 0A E2 16 5E 64 DB 83
2014/12/20 22:55:55 8ADA148 BA 5D B2 1B 25 7B 57 40 E1 D1 EF 71 FD B5 B9 71
2014/12/20 22:55:55 8ADA148 FB FF 42 EA A6 0D D6 3E D7 A8 1E CF EE 15 38 0A
2014/12/20 22:55:55 8ADA148 0B 57 46 2F E7 2B F4 12 7E 66 85 72 CA 83 6B 66
2014/12/20 22:55:55 8ADA148 F5 3A F8 65
2014/12/20 22:55:55 8ADA148 r s02+ [irdeto] IO: Reading of echoed transmitted chars done!
2014/12/20 22:55:55 8ADA148 r s02+ [irdeto] IFD: Transmit succesful
2014/12/20 22:55:55 8ADA148 r s02+ [irdeto] IFD: Setting timeout to 9600 ETU (1071359 us)
2014/12/20 22:55:55 8ADA148 r s02+ [irdeto] IFD: Receive size 8 bytes, delay 0 us, timeout=1071359 us
2014/12/20 22:55:55 8ADA148 r s02+ [irdeto] IO: Read timeout 1071359 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2014/12/20 22:55:56 8B50F98 c [OSCAM-WORK] new event 1 occurred on fd 26 after 6995 ms inactivity
2014/12/20 22:55:56 8B50F98 c cccam(s) stefan: parse_msg=1
2014/12/20 22:55:56 8B50F98 c cccam(s) stefan: ECM request from client: caid 1702 srvid 00df(147) prid 000000
2014/12/20 22:55:56 8B50F98 c {client stefan, caid 1702, prid 000000, srvid 00DF} [get_cw] NEW REQUEST!
2014/12/20 22:55:56 8B50F98 c caid 1702 not found in caidlist reader
hd+
2014/12/20 22:55:56 8B50F98 c caid 1702 not found in caidlist reader
hd++
2014/12/20 22:55:56 8B50F98 c caid 1702 not found in caidlist reader camd3emu
2014/12/20 22:55:56 8B50F98 c loadbalancer: client stefan for 1702&000000/0000/00DF/93:F3BCD9C16DB7234E462268626121355A: n=2 valid readers: s02L s02+L
2014/12/20 22:55:56 8B50F98 c loadbalancer: --------------------------------------------
2014/12/20 22:55:56 8B50F98 c loadbalancer: mode 1, nbest 1, nfb 5, max_reopen ALL, retrylimit 3000 ms
2014/12/20 22:55:56 8B50F98 c loadbalancer: reader s02+ lbvalue = 718 (time-avg 718)
2014/12/20 22:55:56 8B50F98 c loadbalancer: reader s02+ --> ACTIVE
2014/12/20 22:55:56 8B50F98 c loadbalancer: reader s02 blocked for 5000 seconds (fail_factor 5), retrying in 3866 seconds
2014/12/20 22:55:56 8B50F98 c loadbalancer: --------------------------------------------
2014/12/20 22:55:56 8B50F98 c loadbalancer: client stefan for 1702&000000/0000/00DF/93:F3BCD9C16DB7234E462268626121355A: n=1 selected readers: s02+L
2014/12/20 22:55:56 8B50F98 c [GET_CW] wait_time 0 caid 1702 prov 000000 srvid 00DF rc 100 cacheex cl mode 0 ex1rdr 0
2014/12/20 22:55:56 8B50F98 c {client stefan, caid 1702, prid 000000, srvid 00DF} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 2, hitcache 0
2014/12/20 22:55:56 8B50F98 c request_cw stage=3 to reader s02+ ecm hash=F3BCD9C16DB7234E462268626121355A
2014/12/20 22:55:56 8B50F98 c add reader job action 5 queue length 1 s02+
2014/12/20 22:55:56 8B50F98 c {client stefan, caid 1702, prid 000000, srvid 00DF} [write_ecm_request] reader s02+ --> SENT 1
2014/12/20 22:55:56 8B50F98 c Client stefan ECM dump 1702&000000/0000/00DF/93:F3BCD9C16DB7234E462268626121355A
2014/12/20 22:55:56 8B50F98 81 70 90 C7 00 00 00 01 10 10 00 87 13 07 84 00
2014/12/20 22:55:56 8B50F98 20 F5 30 11 63 07 41 8E AA C3 C9 DB FD 64 3E 01
2014/12/20 22:55:56 8B50F98 E7 7F B3 A2 7D 32 8C C5 EC 81 42 EB E4 23 8A BB
2014/12/20 22:55:56 8B50F98 56 73 AF 5F DE ED 86 42 CC 08 31 63 2A 8F 28 17
2014/12/20 22:55:56 8B50F98 CC F1 A1 94 AC F5 A3 D1 19 BC 15 3D 32 26 1A C8
2014/12/20 22:55:56 8B50F98 07 55 28 01 FB FC 3C 3B D7 7A 08 58 01 F7 04 FD
2014/12/20 22:55:56 8B50F98 56 DB 09 22 98 C9 42 26 23 07 99 2D 8A D4 24 3E
2014/12/20 22:55:56 8B50F98 80 AA 93 F5 B7 49 FA 3B DD DD 7A 60 1F 92 C9 BD
2014/12/20 22:55:56 8B50F98 EB A1 D8 FD 49 7B 9E F2 20 43 C4 AB 12 59 8F A5
2014/12/20 22:55:56 8B50F98 CC C2 E3
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Receiving:
2014/12/20 22:55:56 8ADA148 01 05 9D 00 38 00 02 16
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive succesful
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Setting timeout to 9600 ETU (1071359 us)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive size 22 bytes, delay 0 us, timeout=1071359 us
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Read timeout 1071359 us, read delay 0 us, to read 22 char(s), chunksize 22 char(s)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Receiving:
2014/12/20 22:55:56 8ADA148 00 01 00 12 FF FF F5 68 60 C5 3D E7 65 83 09 17
2014/12/20 22:55:56 8ADA148 4A 81 99 8E AF C3
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive succesful
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Setting timeout to 9600 ETU (1071359 us)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive size 1 bytes, delay 0 us, timeout=1071359 us
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Read timeout 1071359 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Receiving:
2014/12/20 22:55:56 8ADA148 33
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive succesful
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] Answer from cardreader:
2014/12/20 22:55:56 8ADA148 00 01 00 12 FF FF F5 68 60 C5 3D E7 65 83 09 17
2014/12/20 22:55:56 8ADA148 4A 81 99 8E AF C3 9D 00
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] cardreader_do_ecm: after csystem.do_ecm rc=1
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] cardreader_do_ecm: ret rc=1
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2014/12/20 22:55:56 8ADA148 r {client detlef, caid 1702, srvid 0017} [write_ecm_answer] cyclecheck passed! Reader: s02+ rc: 0
2014/12/20 22:55:56 8ADA148 r {client detlef, caid 1702, prid 000000, srvid 0017} [write_ecm_answer] reader s02+ rc 0, ecm time 634 ms (634 ms)
2014/12/20 22:55:56 8ADA148 r add client job action 32 queue length 1 detlef
2014/12/20 22:55:56 8ADA148 r loadbalancer: adding stat for reader s02+: rc 0 1702&000000/0000/0017/93:0E97BAF4E9EC407026A58AFC40D37A0C time 634 ms fail 0
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] TRACE: ecm answer for ecm hash 0E97BAF4E9EC407026A58AFC40D37A0C rc=0
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] ecm hash: 0E97BAF4E9EC407026A58AFC40D37A0C real time: 634 ms
2014/12/20 22:55:56 8ADA148 r ecm:
2014/12/20 22:55:56 8ADA148 81 70 90 C7 00 00 00 01 10 10 00 87 13 07 84 00
2014/12/20 22:55:56 8ADA148 20 F5 30 11 63 07 41 8E AA C3 C9 DB FD 64 3E 01
2014/12/20 22:55:56 8ADA148 E7 7F B3 A2 7D 32 8C C5 EC 81 42 EB E4 23 8A BB
2014/12/20 22:55:56 8ADA148 56 73 AF 5F DE ED 86 42 CC 08 31 63 2A 8F 28 17
2014/12/20 22:55:56 8ADA148 CC F1 A1 94 AC F5 A3 D1 19 BC 15 3D 32 26 1A C8
2014/12/20 22:55:56 8ADA148 07 55 28 01 FB FC 3C 3B D7 7A 08 58 01 F7 04 FD
2014/12/20 22:55:56 8ADA148 56 DB 09 22 98 C9 42 26 23 07 99 2D 8A D4 24 3E
2014/12/20 22:55:56 8ADA148 80 AA 93 F5 B7 49 FA 3B DD DD 7A 60 1F 92 C9 BD
2014/12/20 22:55:56 8ADA148 EB A1 D8 FD 49 7B 9E F2 20 43 C4 AB 12 59 8F A5
2014/12/20 22:55:56 8ADA148 CC C2 E3
2014/12/20 22:55:56 8B171E0 c {client detlef, caid 1702, prid 000000, srvid 0017} [send_dcw] rc 0 from reader s02+
2014/12/20 22:55:56 8B171E0 c Client detlef csphash CAA97AF4 cw CC117D5AD3FDAF7FE6034E37BBE76608 rc 0 1702&000000/0000/0017/93:0E97BAF4E9EC407026A58AFC40D37A0C
2014/12/20 22:55:56 8B171E0 c detlef (1702&000000/0000/0017/93:0E97BAF4E9EC407026A58AFC40D37A0C): found (636 ms) by s02+ (P/1/2/2) - Sky Krimi
2014/12/20 22:55:56 8B171E0 c cw:
2014/12/20 22:55:56 8B171E0 CC 11 7D 5A D3 FD AF 7F E6 03 4E 37 BB E7 66 08
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] write to cardreader
2014/12/20 22:55:56 8ADA148 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00 20
2014/12/20 22:55:56 8ADA148 F5 30 11 63 07 41 8E AA C3 C9 DB FD 64 3E 01 E7
2014/12/20 22:55:56 8ADA148 7F B3 A2 7D 32 8C C5 EC 81 42 EB E4 23 8A BB 56
2014/12/20 22:55:56 8ADA148 73 AF 5F DE ED 86 42 CC 08 31 63 2A 8F 28 17 CC
2014/12/20 22:55:56 8ADA148 F1 A1 94 AC F5 A3 D1 19 BC 15 3D 32 26 1A C8 07
2014/12/20 22:55:56 8ADA148 55 28 01 FB FC 3C 3B D7 7A 08 58 01 F7 04 FD 56
2014/12/20 22:55:56 8ADA148 DB 09 22 98 C9 42 26 23 07 99 2D 8A D4 24 3E 80
2014/12/20 22:55:56 8ADA148 AA 93 F5 B7 49 FA 3B DD DD 7A 60 1F 92 C9 BD EB
2014/12/20 22:55:56 8ADA148 A1 D8 FD 49 7B 9E F2 20 43 C4 AB 12 59 8F A5 CC
2014/12/20 22:55:56 8ADA148 C2 E3
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Setting timeout to 12 ETU (1339 us)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Transmit size 148 bytes, delay 0 us, timeout=1339 us
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Transmit:
2014/12/20 22:55:56 8ADA148 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2014/12/20 22:55:56 8ADA148 20 F5 30 11 63 07 41 8E AA C3 C9 DB FD 64 3E 01
2014/12/20 22:55:56 8ADA148 E7 7F B3 A2 7D 32 8C C5 EC 81 42 EB E4 23 8A BB
2014/12/20 22:55:56 8ADA148 56 73 AF 5F DE ED 86 42 CC 08 31 63 2A 8F 28 17
2014/12/20 22:55:56 8ADA148 CC F1 A1 94 AC F5 A3 D1 19 BC 15 3D 32 26 1A C8
2014/12/20 22:55:56 8ADA148 07 55 28 01 FB FC 3C 3B D7 7A 08 58 01 F7 04 FD
2014/12/20 22:55:56 8ADA148 56 DB 09 22 98 C9 42 26 23 07 99 2D 8A D4 24 3E
2014/12/20 22:55:56 8ADA148 80 AA 93 F5 B7 49 FA 3B DD DD 7A 60 1F 92 C9 BD
2014/12/20 22:55:56 8ADA148 EB A1 D8 FD 49 7B 9E F2 20 43 C4 AB 12 59 8F A5
2014/12/20 22:55:56 8ADA148 CC C2 E3 34
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Write timeout 1339 us, write delay 0 us, to send 148 char(s), chunksize 148 char(s)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Sending:
2014/12/20 22:55:56 8ADA148 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2014/12/20 22:55:56 8ADA148 20 F5 30 11 63 07 41 8E AA C3 C9 DB FD 64 3E 01
2014/12/20 22:55:56 8ADA148 E7 7F B3 A2 7D 32 8C C5 EC 81 42 EB E4 23 8A BB
2014/12/20 22:55:56 8ADA148 56 73 AF 5F DE ED 86 42 CC 08 31 63 2A 8F 28 17
2014/12/20 22:55:56 8ADA148 CC F1 A1 94 AC F5 A3 D1 19 BC 15 3D 32 26 1A C8
2014/12/20 22:55:56 8ADA148 07 55 28 01 FB FC 3C 3B D7 7A 08 58 01 F7 04 FD
2014/12/20 22:55:56 8ADA148 56 DB 09 22 98 C9 42 26 23 07 99 2D 8A D4 24 3E
2014/12/20 22:55:56 8ADA148 80 AA 93 F5 B7 49 FA 3B DD DD 7A 60 1F 92 C9 BD
2014/12/20 22:55:56 8ADA148 EB A1 D8 FD 49 7B 9E F2 20 43 C4 AB 12 59 8F A5
2014/12/20 22:55:56 8ADA148 CC C2 E3 34
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Reading 148 echoed transmitted chars...
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Read timeout 9990000 us, read delay 0 us, to read 148 char(s), chunksize 148 char(s)
2014/12/20 22:55:56 8AE4C00 r
hd++ [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:56 8AE2CE0 r
hd+ [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:56 8AD81F8 r s02 [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Receiving:
2014/12/20 22:55:56 8ADA148 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2014/12/20 22:55:56 8ADA148 20 F5 30 11 63 07 41 8E AA C3 C9 DB FD 64 3E 01
2014/12/20 22:55:56 8ADA148 E7 7F B3 A2 7D 32 8C C5 EC 81 42 EB E4 23 8A BB
2014/12/20 22:55:56 8ADA148 56 73 AF 5F DE ED 86 42 CC 08 31 63 2A 8F 28 17
2014/12/20 22:55:56 8ADA148 CC F1 A1 94 AC F5 A3 D1 19 BC 15 3D 32 26 1A C8
2014/12/20 22:55:56 8ADA148 07 55 28 01 FB FC 3C 3B D7 7A 08 58 01 F7 04 FD
2014/12/20 22:55:56 8ADA148 56 DB 09 22 98 C9 42 26 23 07 99 2D 8A D4 24 3E
2014/12/20 22:55:56 8ADA148 80 AA 93 F5 B7 49 FA 3B DD DD 7A 60 1F 92 C9 BD
2014/12/20 22:55:56 8ADA148 EB A1 D8 FD 49 7B 9E F2 20 43 C4 AB 12 59 8F A5
2014/12/20 22:55:56 8ADA148 CC C2 E3 34
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Reading of echoed transmitted chars done!
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Transmit succesful
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Setting timeout to 9600 ETU (1071359 us)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive size 8 bytes, delay 0 us, timeout=1071359 us
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Read timeout 1071359 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Receiving:
2014/12/20 22:55:56 8ADA148 01 05 9D 00 38 00 02 16
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive succesful
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Setting timeout to 9600 ETU (1071359 us)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive size 22 bytes, delay 0 us, timeout=1071359 us
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Read timeout 1071359 us, read delay 0 us, to read 22 char(s), chunksize 22 char(s)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Receiving:
2014/12/20 22:55:56 8ADA148 00 01 00 13 FF FF 73 DD 32 D1 AF 05 54 6D 78 4C
2014/12/20 22:55:56 8ADA148 9C 60 61 1E DB EA
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive succesful
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Setting timeout to 9600 ETU (1071359 us)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive size 1 bytes, delay 0 us, timeout=1071359 us
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Read timeout 1071359 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IO: Receiving:
2014/12/20 22:55:56 8ADA148 C0
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] IFD: Receive succesful
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] Answer from cardreader:
2014/12/20 22:55:56 8ADA148 00 01 00 13 FF FF 73 DD 32 D1 AF 05 54 6D 78 4C
2014/12/20 22:55:56 8ADA148 9C 60 61 1E DB EA 9D 00
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] cardreader_do_ecm: after csystem.do_ecm rc=1
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] cardreader_do_ecm: ret rc=1
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2014/12/20 22:55:56 8ADA148 r {client stefan, caid 1702, srvid 00DF} [write_ecm_answer] cyclecheck passed! Reader: s02+ rc: 0
2014/12/20 22:55:56 8ADA148 r {client stefan, caid 1702, prid 000000, srvid 00DF} [write_ecm_answer] reader s02+ rc 0, ecm time 592 ms (594 ms)
2014/12/20 22:55:56 8ADA148 r add client job action 32 queue length 1 stefan
2014/12/20 22:55:56 8ADA148 r loadbalancer: adding stat for reader s02+: rc 0 1702&000000/0000/00DF/93:F3BCD9C16DB7234E462268626121355A time 592 ms fail 0
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] TRACE: ecm answer for ecm hash F3BCD9C16DB7234E462268626121355A rc=0
2014/12/20 22:55:56 8ADA148 r s02+ [irdeto] ecm hash: F3BCD9C16DB7234E462268626121355A real time: 567 ms
2014/12/20 22:55:56 8B50F98 c {client stefan, caid 1702, prid 000000, srvid 00DF} [send_dcw] rc 0 from reader s02+
2014/12/20 22:55:56 8B50F98 c Client stefan csphash 0DF674E0 cw 67A199A15008146C2EB35132A51C8445 rc 0 1702&000000/0000/00DF/93:F3BCD9C16DB7234E462268626121355A
2014/12/20 22:55:56 8B50F98 c stefan (1702&000000/0000/00DF/93:F3BCD9C16DB7234E462268626121355A): found (596 ms) by s02+ (P/1/1/2) - Sky Bundesliga 1
2014/12/20 22:55:56 8B50F98 c cw:
2014/12/20 22:55:56 8B50F98 67 A1 99 A1 50 08 14 6C 2E B3 51 32 A5 1C 84 45
2014/12/20 22:55:57 8AE4C00 r
hd++ [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:57 8ADA148 r s02+ [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:57 8AD81F8 r s02 [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:57 8AE2CE0 r
hd+ [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:57 8B19010 c [OSCAM-WORK] new event 1 occurred on fd 25 after 6887 ms inactivity
2014/12/20 22:55:57 8B19010 c cccam(s) eigenserver: parse_msg=1
2014/12/20 22:55:57 8B19010 c cccam(s) eigenserver: ECM request from client: caid 1830 srvid ef10(146) prid 000000
2014/12/20 22:55:57 8B19010 c {client eigenserver, caid 1830, prid 000000, srvid EF10} [get_cw] NEW REQUEST!
2014/12/20 22:55:57 8B19010 c caid 1830 not found in caidlist reader s02
2014/12/20 22:55:57 8B19010 c caid 1830 not found in caidlist reader s02+
2014/12/20 22:55:57 8B19010 c caid 1830 not found in caidlist reader camd3emu
2014/12/20 22:55:57 8B19010 c loadbalancer: client eigenserver for 1830&000000/0000/EF10/92:973654EB86A28CC7EE65C4FE11489CC4: n=2 valid readers: hd+L
hd++L
2014/12/20 22:55:57 8B19010 c loadbalancer: --------------------------------------------
2014/12/20 22:55:57 8B19010 c loadbalancer: mode 1, nbest 1, nfb 5, max_reopen ALL, retrylimit 3000 ms
2014/12/20 22:55:57 8B19010 c loadbalancer: NO VALID MATCHING READER FOUND!
2014/12/20 22:55:57 8B19010 c loadbalancer: reader
hd+ blocked for 1000 seconds (fail_factor 1), retrying in 411088 seconds
2014/12/20 22:55:57 8B19010 c loadbalancer: reader
hd++ blocked for 1000 seconds (fail_factor 1), retrying in 411088 seconds
2014/12/20 22:55:57 8B19010 c loadbalancer: --------------------------------------------
2014/12/20 22:55:57 8B19010 c loadbalancer: client eigenserver for 1830&000000/0000/EF10/92:973654EB86A28CC7EE65C4FE11489CC4: n=0 selected readers:
2014/12/20 22:55:57 8B19010 c [GET_CW] wait_time 0 caid 1830 prov 000000 srvid EF10 rc 100 cacheex cl mode 0 ex1rdr 0
2014/12/20 22:55:57 8B19010 c {client eigenserver, caid 1830, prid 000000, srvid EF10} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 2, hitcache 0
2014/12/20 22:55:57 8B19010 c {client eigenserver, caid 1830, prid 000000, srvid EF10} [get_cw] NO Readers and NO wait_time... not_found!
2014/12/20 22:55:57 8B19010 c {client eigenserver, caid 1830, prid 000000, srvid EF10} [send_dcw] rc 4 from reader -
2014/12/20 22:55:57 8B19010 c Client eigenserver csphash DD48F390 cw 00000000000000000000000000000000 rc 4 1830&000000/0000/EF10/92:973654EB86A28CC7EE65C4FE11489CC4
2014/12/20 22:55:57 8B19010 c eigenserver (1830&000000/0000/EF10/92:973654EB86A28CC7EE65C4FE11489CC4): rejected group (2 ms) - RTL HD (no matching reader)
2014/12/20 22:55:57 8B19010 c cw:
2014/12/20 22:55:57 8B19010 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2014/12/20 22:55:57 8B19010 c [OSCAM-WORK] new event 1 occurred on fd 25 after 3 ms inactivity
2014/12/20 22:55:57 8B19010 c cccam(s) eigenserver: parse_msg=1
2014/12/20 22:55:57 8B19010 c cccam(s) eigenserver: ECM request from client: caid 1830 srvid ef10(146) prid 000000
2014/12/20 22:55:57 8B19010 c {client eigenserver, caid 1830, prid 000000, srvid EF10} [get_cw] NEW REQUEST!
2014/12/20 22:55:57 8B19010 c caid 1830 not found in caidlist reader s02
2014/12/20 22:55:57 8B19010 c caid 1830 not found in caidlist reader s02+
2014/12/20 22:55:57 8B19010 c caid 1830 not found in caidlist reader camd3emu
2014/12/20 22:55:57 8B19010 c loadbalancer: client eigenserver for 1830&000000/0000/EF10/92:973654EB86A28CC7EE65C4FE11489CC4: n=2 valid readers: hd+L
hd++L
2014/12/20 22:55:57 8B19010 c loadbalancer: --------------------------------------------
2014/12/20 22:55:57 8B19010 c loadbalancer: mode 1, nbest 1, nfb 5, max_reopen ALL, retrylimit 3000 ms
2014/12/20 22:55:57 8B19010 c loadbalancer: NO VALID MATCHING READER FOUND!
2014/12/20 22:55:57 8B19010 c loadbalancer: reader
hd+ blocked for 1000 seconds (fail_factor 1), retrying in 411088 seconds
2014/12/20 22:55:57 8B19010 c loadbalancer: reader
hd++ blocked for 1000 seconds (fail_factor 1), retrying in 411088 seconds
2014/12/20 22:55:57 8B19010 c loadbalancer: --------------------------------------------
2014/12/20 22:55:57 8B19010 c loadbalancer: client eigenserver for 1830&000000/0000/EF10/92:973654EB86A28CC7EE65C4FE11489CC4: n=0 selected readers:
2014/12/20 22:55:57 8B19010 c [GET_CW] wait_time 0 caid 1830 prov 000000 srvid EF10 rc 100 cacheex cl mode 0 ex1rdr 0
2014/12/20 22:55:57 8B19010 c {client eigenserver, caid 1830, prid 000000, srvid EF10} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 2, hitcache 0
2014/12/20 22:55:57 8B19010 c {client eigenserver, caid 1830, prid 000000, srvid EF10} [get_cw] NO Readers and NO wait_time... not_found!
2014/12/20 22:55:57 8B19010 c {client eigenserver, caid 1830, prid 000000, srvid EF10} [send_dcw] rc 4 from reader -
2014/12/20 22:55:57 8B19010 c Client eigenserver csphash DD48F390 cw 00000000000000000000000000000000 rc 4 1830&000000/0000/EF10/92:973654EB86A28CC7EE65C4FE11489CC4
2014/12/20 22:55:57 8B19010 c eigenserver (1830&000000/0000/EF10/92:973654EB86A28CC7EE65C4FE11489CC4): rejected group (1 ms) - RTL HD (no matching reader)
2014/12/20 22:55:57 8B19010 c cw:
2014/12/20 22:55:57 8B19010 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2014/12/20 22:55:58 8AE4C00 r
hd++ [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:58 8AE2CE0 r
hd+ [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:58 8ADA148 r s02+ [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:58 8AD81F8 r s02 [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:58 0 add client job action 31 queue length 1 lothar
2014/12/20 22:55:58 0 add client job action 31 queue length 1 uwe
2014/12/20 22:55:58 0 add client job action 31 queue length 1 stefan1
2014/12/20 22:55:59 8AE4C00 r
hd++ [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2014/12/20 22:55:59 8AE2CE0 r
hd+ [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1