-------------------------------------------------------------------------------
>> OSCam << cardserver started at Tue Apr 23 08:07:11 2013
-------------------------------------------------------------------------------
2013/04/23 08:07:11 0 s >> OSCam << cardserver started, version 1.20-unstable_svn, build r8621 (arm-linux-gnueabihf)
2013/04/23 08:07:11 0 s creating pidfile /tmp/.oscam/oscam.pid with pid 6665
2013/04/23 08:07:11 0 s services reloaded: 0 services freed, 18 services loaded, rejected 0
2013/04/23 08:07:11 0 s userdb reloaded: 3 accounts loaded, 0 expired, 0 disabled
2013/04/23 08:07:11 0 s signal handling initialized
2013/04/23 08:07:11 0 s 249 service-id's loaded in 5ms
2013/04/23 08:07:11 0 s 116 tier-id's loaded
2013/04/23 08:07:11 0 s monitor: initialized (fd=5, port=988)
2013/04/23 08:07:11 0 s cccam: initialized (fd=6, port=21456)
2013/04/23 08:07:11 0 s skyreader [mouse] creating thread for device /dev/ttyUSB0
2013/04/23 08:07:11 0 s loadbalancer: can't read from file /tmp/.oscam/stat
2013/04/23 08:07:11 0 s waiting for local card init
2013/04/23 08:07:11 15246B0 h webif: decompressed 50905 bytes back into 150836 bytes
2013/04/23 08:07:11 15246B0 h HTTP Server listening on port 11111
2013/04/23 08:07:11 15226F8 r skyreader [mouse] Reader initialized (device=/dev/ttyUSB0, detect=cd, mhz=600, cardmhz=600)
2013/04/23 08:07:12 0 s init for all local cards done
2013/04/23 08:07:12 0 s anti cascading disabled
2013/04/23 08:07:17 153C4A0 c encrypted cccam-client 192.168.178.22 granted (sector, au=on (1 reader))
2013/04/25 06:11:15 15226F8 r skyreader [mouse] card detected
2013/04/25 06:11:16 15226F8 r skyreader [mouse] ATR: 3B 9F 21 0E 49 52 44 45 54 4F 20 41 43 53 03 84 55 FF 80 6D
2013/04/25 06:11:16 15226F8 r skyreader [mouse] Init card protocol T14, FI=2, F=558, D=1, N=0
2013/04/25 06:11:16 15226F8 r skyreader [mouse] Calculated work ETU is 93.00 us
2013/04/25 06:11:16 15226F8 r skyreader [mouse] ATR Fsmax is: 6 Mhz, clocking card to wanted user cardspeed of 6.00 Mhz (specified in reader->mhz)
2013/04/25 06:11:17 15226F8 r skyreader [mouse] detect irdeto tunneled nagra card
2013/04/25 06:11:17 15226F8 r skyreader [mouse] no rsa key configured -> using irdeto mode
2013/04/25 06:11:17 15226F8 r skyreader [mouse] detect irdeto card
2013/04/25 06:11:17 15226F8 r skyreader [mouse] caid: 1722, acs: 3.84, country code: TEL
2013/04/25 06:11:18 15226F8 r skyreader [mouse] providers: 4, ascii serial: ##########, hex serial: ######, hex base: ##
2013/04/25 06:11:21 15226F8 r skyreader [mouse] active providers: 2 (000000,000000)
2013/04/25 06:11:21 15226F8 r skyreader [mouse] found card system irdeto
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] entitlements for provider: 2, id: 000000
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 0107, date: 2008/10/23 - 2008/11/08
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 0108, date: 2008/10/23 - 2008/11/08
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05FC, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 0609, date: 2012/12/07 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 7D23, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05F2, date: 2012/10/09 - 2013/06/14
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 04CE, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05E8, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05F5, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05F4, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05FB, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05E7, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 0605, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 000F, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 7D27, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 00F0, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 0109, date: 2008/10/23 - 2008/11/08
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 0064, date: 2008/01/19 - 2008/09/29
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05DD, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05F1, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05DF, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] chid: 05DE, date: 2012/10/09 - 2013/06/04
2013/04/25 06:11:21 15226F8 r skyreader [irdeto] ready for requests
2013/04/25 06:14:34 153C4A0 c sector disconnected from 192.168.178.22
2013/04/25 06:14:38 1540BE0 c encrypted cccam-client 192.168.178.22 granted (sector, au=on (1 reader))
2013/04/25 06:14:43 1540BE0 c sector (1722&000000/0000/00DF/93
EE0118CF7E9DFCCD9058E2F8B5F19F9): found (578 ms) by skyreader - Sky Bundesliga
2013/04/25 06:14:46 1540BE0 c sector (1722&000000/0000/00DF/93:F1E5C9765F9F227F036137D7684AE1C8): found (539 ms) by skyreader - Sky Bundesliga
2013/04/25 06:15:10 1540BE0 c sector (1722&000000/0000/00DF/93:53D1832D04E96C21D77C54ECBC21C67F): found (537 ms) by skyreader - Sky Bundesliga
2013/04/25 06:15:52 15246B0 h all debug_level=65535
2013/04/25 06:15:52 15246B0 h WebIf: Origin checked. Result: access from 192.168.178.25 => allowed
2013/04/25 06:15:53 0 --- Skipped 1 duplicated log lines ---
2013/04/25 06:15:53 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:15:57 0 --- Skipped 4 duplicated log lines ---
2013/04/25 06:15:57 15246B0 h WebIf: No data received from client 192.168.178.25. Closing connection.
2013/04/25 06:15:58 0 --- Skipped 1 duplicated log lines ---
2013/04/25 06:15:58 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:02 0 --- Skipped 3 duplicated log lines ---
2013/04/25 06:16:02 1540BE0 c cccam(s) sector: parse_msg=1
2013/04/25 06:16:02 1540BE0 c cccam(s) sector: ECM request from client: caid 1722 srvid 00df(147) prid 000000
2013/04/25 06:16:02 1540BE0 c matching_reader became slot attribute of 1
2013/04/25 06:16:02 1540BE0 c [GET_CW] wait_time 0 caid 1722 prov 000000 srvid 00DF rc 100 cacheex cl mode 0 ex1rdr 0
2013/04/25 06:16:02 1540BE0 c request_cw stage=2 to reader skyreader ecm hash=B6F2E2052C94A6BD9C33F5D05F6830E7
2013/04/25 06:16:02 1540BE0 c start reader thread action 5
2013/04/25 06:16:02 1540BE0 c Client sector ECM dump 1722&000000/0000/00DF/93:B6F2E2052C94A6BD9C33F5D05F6830E7
2013/04/25 06:16:02 1540BE0 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:02 1540BE0 CB 10 34 30 69 62 67 1E A8 7B 0A 68 D7 BB C4 AD
2013/04/25 06:16:02 1540BE0 14 71 09 5D F0 97 4F E8 6F D8 71 D9 42 28 96 9A
2013/04/25 06:16:02 1540BE0 DA 08 1A E9 25 34 CB EB 03 71 4F BD 06 70 69 C6
2013/04/25 06:16:02 1540BE0 0C DA 62 01 46 9B 9C 22 56 E4 70 ED 23 2F 60 06
2013/04/25 06:16:02 1540BE0 29 E2 5E 61 20 A6 9F 66 C2 88 8F B3 D4 C6 E2 BE
2013/04/25 06:16:02 1540BE0 A2 7E FF D7 3F 4E 6F AA 77 D5 A3 E6 1F EB BE E0
2013/04/25 06:16:02 1540BE0 AE 5A 51 12 32 44 E2 1E 03 41 E3 C4 13 8E B8 58
2013/04/25 06:16:02 1540BE0 07 79 20 42 62 C2 17 62 82 C2 17 B0 9A 5D F5 73
2013/04/25 06:16:02 1540BE0 B9 81 DE
2013/04/25 06:16:02 15226F8 r data from add_job action=5 client r skyreader
2013/04/25 06:16:02 15226F8 r ecm:
2013/04/25 06:16:02 15226F8 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:02 15226F8 CB 10 34 30 69 62 67 1E A8 7B 0A 68 D7 BB C4 AD
2013/04/25 06:16:02 15226F8 14 71 09 5D F0 97 4F E8 6F D8 71 D9 42 28 96 9A
2013/04/25 06:16:02 15226F8 DA 08 1A E9 25 34 CB EB 03 71 4F BD 06 70 69 C6
2013/04/25 06:16:02 15226F8 0C DA 62 01 46 9B 9C 22 56 E4 70 ED 23 2F 60 06
2013/04/25 06:16:02 15226F8 29 E2 5E 61 20 A6 9F 66 C2 88 8F B3 D4 C6 E2 BE
2013/04/25 06:16:02 15226F8 A2 7E FF D7 3F 4E 6F AA 77 D5 A3 E6 1F EB BE E0
2013/04/25 06:16:02 15226F8 AE 5A 51 12 32 44 E2 1E 03 41 E3 C4 13 8E B8 58
2013/04/25 06:16:02 15226F8 07 79 20 42 62 C2 17 62 82 C2 17 B0 9A 5D F5 73
2013/04/25 06:16:02 15226F8 B9 81 DE
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] write to cardreader
2013/04/25 06:16:02 15226F8 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00 CB
2013/04/25 06:16:02 15226F8 10 34 30 69 62 67 1E A8 7B 0A 68 D7 BB C4 AD 14
2013/04/25 06:16:02 15226F8 71 09 5D F0 97 4F E8 6F D8 71 D9 42 28 96 9A DA
2013/04/25 06:16:02 15226F8 08 1A E9 25 34 CB EB 03 71 4F BD 06 70 69 C6 0C
2013/04/25 06:16:02 15226F8 DA 62 01 46 9B 9C 22 56 E4 70 ED 23 2F 60 06 29
2013/04/25 06:16:02 15226F8 E2 5E 61 20 A6 9F 66 C2 88 8F B3 D4 C6 E2 BE A2
2013/04/25 06:16:02 15226F8 7E FF D7 3F 4E 6F AA 77 D5 A3 E6 1F EB BE E0 AE
2013/04/25 06:16:02 15226F8 5A 51 12 32 44 E2 1E 03 41 E3 C4 13 8E B8 58 07
2013/04/25 06:16:02 15226F8 79 20 42 62 C2 17 62 82 C2 17 B0 9A 5D F5 73 B9
2013/04/25 06:16:02 15226F8 81 DE
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IFD: Setting timeout to 12 ETU (2232 us)
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IFD: Transmit size 148 bytes, delay 0 us, timeout=2232 us
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IFD: Transmit:
2013/04/25 06:16:02 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:02 15226F8 CB 10 34 30 69 62 67 1E A8 7B 0A 68 D7 BB C4 AD
2013/04/25 06:16:02 15226F8 14 71 09 5D F0 97 4F E8 6F D8 71 D9 42 28 96 9A
2013/04/25 06:16:02 15226F8 DA 08 1A E9 25 34 CB EB 03 71 4F BD 06 70 69 C6
2013/04/25 06:16:02 15226F8 0C DA 62 01 46 9B 9C 22 56 E4 70 ED 23 2F 60 06
2013/04/25 06:16:02 15226F8 29 E2 5E 61 20 A6 9F 66 C2 88 8F B3 D4 C6 E2 BE
2013/04/25 06:16:02 15226F8 A2 7E FF D7 3F 4E 6F AA 77 D5 A3 E6 1F EB BE E0
2013/04/25 06:16:02 15226F8 AE 5A 51 12 32 44 E2 1E 03 41 E3 C4 13 8E B8 58
2013/04/25 06:16:02 15226F8 07 79 20 42 62 C2 17 62 82 C2 17 B0 9A 5D F5 73
2013/04/25 06:16:02 15226F8 B9 81 DE FA
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IO: Write timeout 2232 us, write delay 0 us, to send 148 char(s), chunksize 148 char(s)
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IO: Sending:
2013/04/25 06:16:02 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:02 15226F8 CB 10 34 30 69 62 67 1E A8 7B 0A 68 D7 BB C4 AD
2013/04/25 06:16:02 15226F8 14 71 09 5D F0 97 4F E8 6F D8 71 D9 42 28 96 9A
2013/04/25 06:16:02 15226F8 DA 08 1A E9 25 34 CB EB 03 71 4F BD 06 70 69 C6
2013/04/25 06:16:02 15226F8 0C DA 62 01 46 9B 9C 22 56 E4 70 ED 23 2F 60 06
2013/04/25 06:16:02 15226F8 29 E2 5E 61 20 A6 9F 66 C2 88 8F B3 D4 C6 E2 BE
2013/04/25 06:16:02 15226F8 A2 7E FF D7 3F 4E 6F AA 77 D5 A3 E6 1F EB BE E0
2013/04/25 06:16:02 15226F8 AE 5A 51 12 32 44 E2 1E 03 41 E3 C4 13 8E B8 58
2013/04/25 06:16:02 15226F8 07 79 20 42 62 C2 17 62 82 C2 17 B0 9A 5D F5 73
2013/04/25 06:16:02 15226F8 B9 81 DE FA
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IO: Reading 148 echoed transmitted chars...
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IO: Read timeout 9990000 us, read delay 0 us, to read 148 char(s), chunksize 148 char(s)
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:02 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:02 15226F8 CB 10 34 30 69 62 67 1E A8 7B 0A 68 D7 BB C4 AD
2013/04/25 06:16:02 15226F8 14 71 09 5D F0 97 4F E8 6F D8 71 D9 42 28 96 9A
2013/04/25 06:16:02 15226F8 DA 08 1A E9 25 34 CB EB 03 71 4F BD 06 70 69 C6
2013/04/25 06:16:02 15226F8 0C DA 62 01 46 9B 9C 22 56 E4 70 ED 23 2F 60 06
2013/04/25 06:16:02 15226F8 29 E2 5E 61 20 A6 9F 66 C2 88 8F B3 D4 C6 E2 BE
2013/04/25 06:16:02 15226F8 A2 7E FF D7 3F 4E 6F AA 77 D5 A3 E6 1F EB BE E0
2013/04/25 06:16:02 15226F8 AE 5A 51 12 32 44 E2 1E 03 41 E3 C4 13 8E B8 58
2013/04/25 06:16:02 15226F8 07 79 20 42 62 C2 17 62 82 C2 17 B0 9A 5D F5 73
2013/04/25 06:16:02 15226F8 B9 81 DE FA
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IO: Reading of echoed transmitted chars done!
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IFD: Transmit succesful
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IFD: Receive size 8 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:02 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:03 15226F8 01 05 9D 00 38 00 02 16
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive size 22 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 22 char(s), chunksize 22 char(s)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:03 15226F8 00 01 00 12 FF FF C1 92 DA D2 22 88 AB 58 1E F0
2013/04/25 06:16:03 15226F8 53 05 82 9D 5F 10
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive size 1 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:03 15226F8 73
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] Answer from cardreader:
2013/04/25 06:16:03 15226F8 00 01 00 12 FF FF C1 92 DA D2 22 88 AB 58 1E F0
2013/04/25 06:16:03 15226F8 53 05 82 9D 5F 10 9D 00
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] cardreader_do_ecm: after csystem.do_ecm rc=1
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] cardreader_do_ecm: ret rc=1
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] ecm hash: B6F2E2052C94A6BD9C33F5D05F6830E7 real time: 627 ms
2013/04/25 06:16:03 1540BE0 c poll() timeout
2013/04/25 06:16:03 1540BE0 c skyreader [irdeto] TRACE: ecm answer for ecm hash B6F2E2052C94A6BD9C33F5D05F6830E7 rc=0
2013/04/25 06:16:03 1540BE0 c Client sector csphash 2BEA899B cw FF25D1F52E59921940BFD0CF84F284FA rc 0 1722&000000/0000/00DF/93:B6F2E2052C94A6BD9C33F5D05F6830E7
2013/04/25 06:16:03 1540BE0 c sector (1722&000000/0000/00DF/93:B6F2E2052C94A6BD9C33F5D05F6830E7): found (652 ms) by skyreader - Sky Bundesliga
2013/04/25 06:16:03 1540BE0 c cw:
2013/04/25 06:16:03 1540BE0 FF 25 D1 F5 2E 59 92 19 40 BF D0 CF 84 F2 84 FA
2013/04/25 06:16:03 1540BE0 c cccam(s) sector: parse_msg=1
2013/04/25 06:16:03 1540BE0 c cccam(s) sector: ECM request from client: caid 1722 srvid 00df(147) prid 000000
2013/04/25 06:16:03 1540BE0 c matching_reader became slot attribute of 1
2013/04/25 06:16:03 1540BE0 c [GET_CW] wait_time 0 caid 1722 prov 000000 srvid 00DF rc 100 cacheex cl mode 0 ex1rdr 0
2013/04/25 06:16:03 1540BE0 c request_cw stage=2 to reader skyreader ecm hash=1F6A03BA7BA0A1210EB1AB46B1CEA41F
2013/04/25 06:16:03 1540BE0 c add reader job action 5 queue length 1 skyreader
2013/04/25 06:16:03 1540BE0 c Client sector ECM dump 1722&000000/0000/00DF/93:1F6A03BA7BA0A1210EB1AB46B1CEA41F
2013/04/25 06:16:03 1540BE0 81 70 90 C7 00 00 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:03 1540BE0 3A 58 1B F8 1A 87 0A 7C BC 62 94 92 5A 08 3E CB
2013/04/25 06:16:03 1540BE0 54 3C 75 27 5F BD D5 7C 33 9F 31 B4 E0 0C 8C 91
2013/04/25 06:16:03 1540BE0 C5 55 7C 56 69 72 9D 3C C0 5C 13 71 20 C9 2F FD
2013/04/25 06:16:03 1540BE0 B4 CE 34 49 C8 5A B3 1B 2F F2 B6 4B BC 06 9D 3D
2013/04/25 06:16:03 1540BE0 42 20 50 2F C6 82 C0 C1 88 0A 10 BF D5 58 9A 36
2013/04/25 06:16:03 1540BE0 34 D7 AE 41 2E 70 FB E9 32 01 84 C3 31 88 26 C0
2013/04/25 06:16:03 1540BE0 96 56 05 7A 31 A0 5A 2B C2 29 47 C0 AD 86 69 54
2013/04/25 06:16:03 1540BE0 49 64 1A 1D 32 C1 83 76 2E EE CE 98 6F D0 41 C8
2013/04/25 06:16:03 1540BE0 6F 05 8E
2013/04/25 06:16:03 15226F8 r add client job action 24 queue length 0 sector
2013/04/25 06:16:03 15226F8 r ecm:
2013/04/25 06:16:03 15226F8 81 70 90 C7 00 00 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:03 15226F8 3A 58 1B F8 1A 87 0A 7C BC 62 94 92 5A 08 3E CB
2013/04/25 06:16:03 15226F8 54 3C 75 27 5F BD D5 7C 33 9F 31 B4 E0 0C 8C 91
2013/04/25 06:16:03 15226F8 C5 55 7C 56 69 72 9D 3C C0 5C 13 71 20 C9 2F FD
2013/04/25 06:16:03 15226F8 B4 CE 34 49 C8 5A B3 1B 2F F2 B6 4B BC 06 9D 3D
2013/04/25 06:16:03 15226F8 42 20 50 2F C6 82 C0 C1 88 0A 10 BF D5 58 9A 36
2013/04/25 06:16:03 15226F8 34 D7 AE 41 2E 70 FB E9 32 01 84 C3 31 88 26 C0
2013/04/25 06:16:03 15226F8 96 56 05 7A 31 A0 5A 2B C2 29 47 C0 AD 86 69 54
2013/04/25 06:16:03 15226F8 49 64 1A 1D 32 C1 83 76 2E EE CE 98 6F D0 41 C8
2013/04/25 06:16:03 15226F8 6F 05 8E
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] write to cardreader
2013/04/25 06:16:03 15226F8 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00 3A
2013/04/25 06:16:03 15226F8 58 1B F8 1A 87 0A 7C BC 62 94 92 5A 08 3E CB 54
2013/04/25 06:16:03 15226F8 3C 75 27 5F BD D5 7C 33 9F 31 B4 E0 0C 8C 91 C5
2013/04/25 06:16:03 15226F8 55 7C 56 69 72 9D 3C C0 5C 13 71 20 C9 2F FD B4
2013/04/25 06:16:03 15226F8 CE 34 49 C8 5A B3 1B 2F F2 B6 4B BC 06 9D 3D 42
2013/04/25 06:16:03 15226F8 20 50 2F C6 82 C0 C1 88 0A 10 BF D5 58 9A 36 34
2013/04/25 06:16:03 15226F8 D7 AE 41 2E 70 FB E9 32 01 84 C3 31 88 26 C0 96
2013/04/25 06:16:03 15226F8 56 05 7A 31 A0 5A 2B C2 29 47 C0 AD 86 69 54 49
2013/04/25 06:16:03 15226F8 64 1A 1D 32 C1 83 76 2E EE CE 98 6F D0 41 C8 6F
2013/04/25 06:16:03 15226F8 05 8E
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Setting timeout to 12 ETU (2232 us)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Transmit size 148 bytes, delay 0 us, timeout=2232 us
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Transmit:
2013/04/25 06:16:03 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:03 15226F8 3A 58 1B F8 1A 87 0A 7C BC 62 94 92 5A 08 3E CB
2013/04/25 06:16:03 15226F8 54 3C 75 27 5F BD D5 7C 33 9F 31 B4 E0 0C 8C 91
2013/04/25 06:16:03 15226F8 C5 55 7C 56 69 72 9D 3C C0 5C 13 71 20 C9 2F FD
2013/04/25 06:16:03 15226F8 B4 CE 34 49 C8 5A B3 1B 2F F2 B6 4B BC 06 9D 3D
2013/04/25 06:16:03 15226F8 42 20 50 2F C6 82 C0 C1 88 0A 10 BF D5 58 9A 36
2013/04/25 06:16:03 15226F8 34 D7 AE 41 2E 70 FB E9 32 01 84 C3 31 88 26 C0
2013/04/25 06:16:03 15226F8 96 56 05 7A 31 A0 5A 2B C2 29 47 C0 AD 86 69 54
2013/04/25 06:16:03 15226F8 49 64 1A 1D 32 C1 83 76 2E EE CE 98 6F D0 41 C8
2013/04/25 06:16:03 15226F8 6F 05 8E AE
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Write timeout 2232 us, write delay 0 us, to send 148 char(s), chunksize 148 char(s)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Sending:
2013/04/25 06:16:03 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:03 15226F8 3A 58 1B F8 1A 87 0A 7C BC 62 94 92 5A 08 3E CB
2013/04/25 06:16:03 15226F8 54 3C 75 27 5F BD D5 7C 33 9F 31 B4 E0 0C 8C 91
2013/04/25 06:16:03 15226F8 C5 55 7C 56 69 72 9D 3C C0 5C 13 71 20 C9 2F FD
2013/04/25 06:16:03 15226F8 B4 CE 34 49 C8 5A B3 1B 2F F2 B6 4B BC 06 9D 3D
2013/04/25 06:16:03 15226F8 42 20 50 2F C6 82 C0 C1 88 0A 10 BF D5 58 9A 36
2013/04/25 06:16:03 15226F8 34 D7 AE 41 2E 70 FB E9 32 01 84 C3 31 88 26 C0
2013/04/25 06:16:03 15226F8 96 56 05 7A 31 A0 5A 2B C2 29 47 C0 AD 86 69 54
2013/04/25 06:16:03 15226F8 49 64 1A 1D 32 C1 83 76 2E EE CE 98 6F D0 41 C8
2013/04/25 06:16:03 15226F8 6F 05 8E AE
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Reading 148 echoed transmitted chars...
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Read timeout 9990000 us, read delay 0 us, to read 148 char(s), chunksize 148 char(s)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:03 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:03 15226F8 3A 58 1B F8 1A 87 0A 7C BC 62 94 92 5A 08 3E CB
2013/04/25 06:16:03 15226F8 54 3C 75 27 5F BD D5 7C 33 9F 31 B4 E0 0C 8C 91
2013/04/25 06:16:03 15226F8 C5 55 7C 56 69 72 9D 3C C0 5C 13 71 20 C9 2F FD
2013/04/25 06:16:03 15226F8 B4 CE 34 49 C8 5A B3 1B 2F F2 B6 4B BC 06 9D 3D
2013/04/25 06:16:03 15226F8 42 20 50 2F C6 82 C0 C1 88 0A 10 BF D5 58 9A 36
2013/04/25 06:16:03 15226F8 34 D7 AE 41 2E 70 FB E9 32 01 84 C3 31 88 26 C0
2013/04/25 06:16:03 15226F8 96 56 05 7A 31 A0 5A 2B C2 29 47 C0 AD 86 69 54
2013/04/25 06:16:03 15226F8 49 64 1A 1D 32 C1 83 76 2E EE CE 98 6F D0 41 C8
2013/04/25 06:16:03 15226F8 6F 05 8E AE
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Reading of echoed transmitted chars done!
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Transmit succesful
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive size 8 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:03 15226F8 01 05 9D 00 38 00 02 16
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive size 22 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 22 char(s), chunksize 22 char(s)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:03 15226F8 00 01 00 13 FF FF C1 92 DA D2 22 88 AB 58 61 06
2013/04/25 06:16:03 15226F8 EE AE A6 43 3F F9
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive size 1 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:03 15226F8 9E
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] Answer from cardreader:
2013/04/25 06:16:03 15226F8 00 01 00 13 FF FF C1 92 DA D2 22 88 AB 58 61 06
2013/04/25 06:16:03 15226F8 EE AE A6 43 3F F9 9D 00
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] cardreader_do_ecm: after csystem.do_ecm rc=1
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] cardreader_do_ecm: ret rc=1
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2013/04/25 06:16:03 15226F8 r skyreader [irdeto] ecm hash: 1F6A03BA7BA0A1210EB1AB46B1CEA41F real time: 571 ms
2013/04/25 06:16:03 1540BE0 c poll() timeout
2013/04/25 06:16:03 1540BE0 c skyreader [irdeto] TRACE: ecm answer for ecm hash 1F6A03BA7BA0A1210EB1AB46B1CEA41F rc=0
2013/04/25 06:16:03 1540BE0 c Client sector csphash 194840B9 cw FF25D1F52E599219D4807FD37BBCC6FD rc 0 1722&000000/0000/00DF/93:1F6A03BA7BA0A1210EB1AB46B1CEA41F
2013/04/25 06:16:03 1540BE0 c sector (1722&000000/0000/00DF/93:1F6A03BA7BA0A1210EB1AB46B1CEA41F): found (600 ms) by skyreader - Sky Bundesliga
2013/04/25 06:16:03 1540BE0 c cw:
2013/04/25 06:16:03 1540BE0 FF 25 D1 F5 2E 59 92 19 D4 80 7F D3 7B BC C6 FD
2013/04/25 06:16:03 15226F8 r add client job action 24 queue length 0 sector
2013/04/25 06:16:03 1540BE0 c cccam(s) sector: parse_msg=1
2013/04/25 06:16:03 1540BE0 c cccam(s) sector: ECM request from client: caid 1722 srvid 00df(147) prid 000000
2013/04/25 06:16:03 1540BE0 c matching_reader became slot attribute of 1
2013/04/25 06:16:03 1540BE0 c [GET_CW] wait_time 0 caid 1722 prov 000000 srvid 00DF rc 1 cacheex cl mode 0 ex1rdr 0
2013/04/25 06:16:03 1540BE0 c Client sector csphash 194840B9 cw FF25D1F52E599219D4807FD37BBCC6FD rc 1 1722&000000/0000/00DF/93:1F6A03BA7BA0A1210EB1AB46B1CEA41F
2013/04/25 06:16:03 1540BE0 c sector (1722&000000/0000/00DF/93:1F6A03BA7BA0A1210EB1AB46B1CEA41F): cache1 (0 ms) by skyreader - Sky Bundesliga
2013/04/25 06:16:03 1540BE0 c cw:
2013/04/25 06:16:03 1540BE0 FF 25 D1 F5 2E 59 92 19 D4 80 7F D3 7B BC C6 FD
2013/04/25 06:16:04 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:13 0 --- Skipped 8 duplicated log lines ---
2013/04/25 06:16:13 0 share-updater mode=interval t=240s
2013/04/25 06:16:13 1540BE0 c cccam(s) sector: parse_msg=1
2013/04/25 06:16:13 1540BE0 c cccam(s) sector: ECM request from client: caid 1722 srvid 00df(147) prid 000000
2013/04/25 06:16:13 1540BE0 c matching_reader became slot attribute of 1
2013/04/25 06:16:13 1540BE0 c [GET_CW] wait_time 0 caid 1722 prov 000000 srvid 00DF rc 100 cacheex cl mode 0 ex1rdr 0
2013/04/25 06:16:13 1540BE0 c request_cw stage=2 to reader skyreader ecm hash=8299F54A2DECF41EEBE39F2195BA3790
2013/04/25 06:16:13 1540BE0 c start reader thread action 5
2013/04/25 06:16:13 1540BE0 c Client sector ECM dump 1722&000000/0000/00DF/93:8299F54A2DECF41EEBE39F2195BA3790
2013/04/25 06:16:13 1540BE0 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:13 1540BE0 B3 EB 2B 70 C9 BA A0 58 01 70 4C 63 0A 2E 4F D2
2013/04/25 06:16:13 1540BE0 C7 FD E8 C4 F7 D7 22 34 F6 66 9B E1 FE A1 95 F1
2013/04/25 06:16:13 1540BE0 76 D2 38 FD 67 D5 E7 88 D5 82 3D 4C EE 86 44 F1
2013/04/25 06:16:13 1540BE0 A9 E4 B6 10 E0 AB 54 C3 68 60 96 6D 5C 49 C7 E2
2013/04/25 06:16:13 1540BE0 64 96 31 FA F0 56 81 3B AA 52 A5 BD 9D A4 D4 33
2013/04/25 06:16:13 1540BE0 AB 4C 19 FF BB 9E 3E A5 CC BF 00 3B 4F C9 B0 39
2013/04/25 06:16:13 1540BE0 75 2B DC 93 C5 DC BE DB C9 13 DA 4B 4B 69 35 91
2013/04/25 06:16:13 1540BE0 38 BE BD 1A CC 83 DB D0 41 E7 09 7A 78 BF 15 15
2013/04/25 06:16:13 1540BE0 82 FB 1B
2013/04/25 06:16:13 15226F8 r data from add_job action=5 client r skyreader
2013/04/25 06:16:13 15226F8 r ecm:
2013/04/25 06:16:13 15226F8 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:13 15226F8 B3 EB 2B 70 C9 BA A0 58 01 70 4C 63 0A 2E 4F D2
2013/04/25 06:16:13 15226F8 C7 FD E8 C4 F7 D7 22 34 F6 66 9B E1 FE A1 95 F1
2013/04/25 06:16:13 15226F8 76 D2 38 FD 67 D5 E7 88 D5 82 3D 4C EE 86 44 F1
2013/04/25 06:16:13 15226F8 A9 E4 B6 10 E0 AB 54 C3 68 60 96 6D 5C 49 C7 E2
2013/04/25 06:16:13 15226F8 64 96 31 FA F0 56 81 3B AA 52 A5 BD 9D A4 D4 33
2013/04/25 06:16:13 15226F8 AB 4C 19 FF BB 9E 3E A5 CC BF 00 3B 4F C9 B0 39
2013/04/25 06:16:13 15226F8 75 2B DC 93 C5 DC BE DB C9 13 DA 4B 4B 69 35 91
2013/04/25 06:16:13 15226F8 38 BE BD 1A CC 83 DB D0 41 E7 09 7A 78 BF 15 15
2013/04/25 06:16:13 15226F8 82 FB 1B
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] write to cardreader
2013/04/25 06:16:13 15226F8 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00 B3
2013/04/25 06:16:13 15226F8 EB 2B 70 C9 BA A0 58 01 70 4C 63 0A 2E 4F D2 C7
2013/04/25 06:16:13 15226F8 FD E8 C4 F7 D7 22 34 F6 66 9B E1 FE A1 95 F1 76
2013/04/25 06:16:13 15226F8 D2 38 FD 67 D5 E7 88 D5 82 3D 4C EE 86 44 F1 A9
2013/04/25 06:16:13 15226F8 E4 B6 10 E0 AB 54 C3 68 60 96 6D 5C 49 C7 E2 64
2013/04/25 06:16:13 15226F8 96 31 FA F0 56 81 3B AA 52 A5 BD 9D A4 D4 33 AB
2013/04/25 06:16:13 15226F8 4C 19 FF BB 9E 3E A5 CC BF 00 3B 4F C9 B0 39 75
2013/04/25 06:16:13 15226F8 2B DC 93 C5 DC BE DB C9 13 DA 4B 4B 69 35 91 38
2013/04/25 06:16:13 15226F8 BE BD 1A CC 83 DB D0 41 E7 09 7A 78 BF 15 15 82
2013/04/25 06:16:13 15226F8 FB 1B
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Setting timeout to 12 ETU (2232 us)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Transmit size 148 bytes, delay 0 us, timeout=2232 us
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Transmit:
2013/04/25 06:16:13 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:13 15226F8 B3 EB 2B 70 C9 BA A0 58 01 70 4C 63 0A 2E 4F D2
2013/04/25 06:16:13 15226F8 C7 FD E8 C4 F7 D7 22 34 F6 66 9B E1 FE A1 95 F1
2013/04/25 06:16:13 15226F8 76 D2 38 FD 67 D5 E7 88 D5 82 3D 4C EE 86 44 F1
2013/04/25 06:16:13 15226F8 A9 E4 B6 10 E0 AB 54 C3 68 60 96 6D 5C 49 C7 E2
2013/04/25 06:16:13 15226F8 64 96 31 FA F0 56 81 3B AA 52 A5 BD 9D A4 D4 33
2013/04/25 06:16:13 15226F8 AB 4C 19 FF BB 9E 3E A5 CC BF 00 3B 4F C9 B0 39
2013/04/25 06:16:13 15226F8 75 2B DC 93 C5 DC BE DB C9 13 DA 4B 4B 69 35 91
2013/04/25 06:16:13 15226F8 38 BE BD 1A CC 83 DB D0 41 E7 09 7A 78 BF 15 15
2013/04/25 06:16:13 15226F8 82 FB 1B D8
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Write timeout 2232 us, write delay 0 us, to send 148 char(s), chunksize 148 char(s)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Sending:
2013/04/25 06:16:13 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:13 15226F8 B3 EB 2B 70 C9 BA A0 58 01 70 4C 63 0A 2E 4F D2
2013/04/25 06:16:13 15226F8 C7 FD E8 C4 F7 D7 22 34 F6 66 9B E1 FE A1 95 F1
2013/04/25 06:16:13 15226F8 76 D2 38 FD 67 D5 E7 88 D5 82 3D 4C EE 86 44 F1
2013/04/25 06:16:13 15226F8 A9 E4 B6 10 E0 AB 54 C3 68 60 96 6D 5C 49 C7 E2
2013/04/25 06:16:13 15226F8 64 96 31 FA F0 56 81 3B AA 52 A5 BD 9D A4 D4 33
2013/04/25 06:16:13 15226F8 AB 4C 19 FF BB 9E 3E A5 CC BF 00 3B 4F C9 B0 39
2013/04/25 06:16:13 15226F8 75 2B DC 93 C5 DC BE DB C9 13 DA 4B 4B 69 35 91
2013/04/25 06:16:13 15226F8 38 BE BD 1A CC 83 DB D0 41 E7 09 7A 78 BF 15 15
2013/04/25 06:16:13 15226F8 82 FB 1B D8
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Reading 148 echoed transmitted chars...
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Read timeout 9990000 us, read delay 0 us, to read 148 char(s), chunksize 148 char(s)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:13 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 12 07 84 00
2013/04/25 06:16:13 15226F8 B3 EB 2B 70 C9 BA A0 58 01 70 4C 63 0A 2E 4F D2
2013/04/25 06:16:13 15226F8 C7 FD E8 C4 F7 D7 22 34 F6 66 9B E1 FE A1 95 F1
2013/04/25 06:16:13 15226F8 76 D2 38 FD 67 D5 E7 88 D5 82 3D 4C EE 86 44 F1
2013/04/25 06:16:13 15226F8 A9 E4 B6 10 E0 AB 54 C3 68 60 96 6D 5C 49 C7 E2
2013/04/25 06:16:13 15226F8 64 96 31 FA F0 56 81 3B AA 52 A5 BD 9D A4 D4 33
2013/04/25 06:16:13 15226F8 AB 4C 19 FF BB 9E 3E A5 CC BF 00 3B 4F C9 B0 39
2013/04/25 06:16:13 15226F8 75 2B DC 93 C5 DC BE DB C9 13 DA 4B 4B 69 35 91
2013/04/25 06:16:13 15226F8 38 BE BD 1A CC 83 DB D0 41 E7 09 7A 78 BF 15 15
2013/04/25 06:16:13 15226F8 82 FB 1B D8
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Reading of echoed transmitted chars done!
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Transmit succesful
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Receive size 8 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:13 15226F8 01 05 9D 00 38 00 02 16
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Receive size 22 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 22 char(s), chunksize 22 char(s)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:13 15226F8 00 01 00 12 FF FF 63 56 A3 07 2F 36 B7 C9 61 06
2013/04/25 06:16:13 15226F8 EE AE A6 43 3F F9
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Receive size 1 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:13 15226F8 6B
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] Answer from cardreader:
2013/04/25 06:16:13 15226F8 00 01 00 12 FF FF 63 56 A3 07 2F 36 B7 C9 61 06
2013/04/25 06:16:13 15226F8 EE AE A6 43 3F F9 9D 00
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] cardreader_do_ecm: after csystem.do_ecm rc=1
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] cardreader_do_ecm: ret rc=1
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2013/04/25 06:16:13 15226F8 r skyreader [irdeto] ecm hash: 8299F54A2DECF41EEBE39F2195BA3790 real time: 551 ms
2013/04/25 06:16:13 1540BE0 c poll() timeout
2013/04/25 06:16:13 1540BE0 c skyreader [irdeto] TRACE: ecm answer for ecm hash 8299F54A2DECF41EEBE39F2195BA3790 rc=0
2013/04/25 06:16:13 1540BE0 c Client sector csphash 99413E09 cw 270DF82CFB8C7C03D4807FD37BBCC6FD rc 0 1722&000000/0000/00DF/93:8299F54A2DECF41EEBE39F2195BA3790
2013/04/25 06:16:13 1540BE0 c sector (1722&000000/0000/00DF/93:8299F54A2DECF41EEBE39F2195BA3790): found (561 ms) by skyreader - Sky Bundesliga
2013/04/25 06:16:13 1540BE0 c cw:
2013/04/25 06:16:13 1540BE0 27 0D F8 2C FB 8C 7C 03 D4 80 7F D3 7B BC C6 FD
2013/04/25 06:16:13 15226F8 r add client job action 24 queue length 0 sector
2013/04/25 06:16:14 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:16 0 --- Skipped 2 duplicated log lines ---
2013/04/25 06:16:16 1540BE0 c cccam(s) sector: parse_msg=1
2013/04/25 06:16:16 1540BE0 c cccam(s) sector: ECM request from client: caid 1722 srvid 00df(147) prid 000000
2013/04/25 06:16:16 1540BE0 c matching_reader became slot attribute of 1
2013/04/25 06:16:16 1540BE0 c [GET_CW] wait_time 0 caid 1722 prov 000000 srvid 00DF rc 100 cacheex cl mode 0 ex1rdr 0
2013/04/25 06:16:16 1540BE0 c request_cw stage=2 to reader skyreader ecm hash=B35BB5A10043285776048B100500994E
2013/04/25 06:16:16 1540BE0 c start reader thread action 5
2013/04/25 06:16:16 1540BE0 c Client sector ECM dump 1722&000000/0000/00DF/93:B35BB5A10043285776048B100500994E
2013/04/25 06:16:16 1540BE0 81 70 90 C7 00 00 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:16 1540BE0 78 0A E8 43 1E 2F 3A 17 73 FF 2F A9 7B F8 04 2A
2013/04/25 06:16:16 1540BE0 4F 02 73 F1 43 B0 22 8A D7 92 7D AF 49 04 82 1A
2013/04/25 06:16:16 1540BE0 57 4E 30 BD 80 76 B0 D6 55 8D EE D3 70 23 E5 AC
2013/04/25 06:16:16 1540BE0 70 0F 05 E5 6C 43 8B 93 37 A1 59 FF 22 62 4A 7E
2013/04/25 06:16:16 1540BE0 57 8B BA 63 B4 F9 E0 68 25 4B 8B 58 30 88 48 AD
2013/04/25 06:16:16 1540BE0 D2 0A 28 9F 3C 8C 29 B3 8A 68 C6 AE 7E 8B 90 87
2013/04/25 06:16:16 1540BE0 F5 ED 50 77 DB 93 40 77 E5 24 E3 78 4E 50 C3 95
2013/04/25 06:16:16 1540BE0 2E C3 F9 2C FB 34 C5 74 D4 4C 53 53 E1 E4 43 0C
2013/04/25 06:16:16 1540BE0 33 EF 50
2013/04/25 06:16:16 15226F8 r data from add_job action=5 client r skyreader
2013/04/25 06:16:16 15226F8 r ecm:
2013/04/25 06:16:16 15226F8 81 70 90 C7 00 00 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:16 15226F8 78 0A E8 43 1E 2F 3A 17 73 FF 2F A9 7B F8 04 2A
2013/04/25 06:16:16 15226F8 4F 02 73 F1 43 B0 22 8A D7 92 7D AF 49 04 82 1A
2013/04/25 06:16:16 15226F8 57 4E 30 BD 80 76 B0 D6 55 8D EE D3 70 23 E5 AC
2013/04/25 06:16:16 15226F8 70 0F 05 E5 6C 43 8B 93 37 A1 59 FF 22 62 4A 7E
2013/04/25 06:16:16 15226F8 57 8B BA 63 B4 F9 E0 68 25 4B 8B 58 30 88 48 AD
2013/04/25 06:16:16 15226F8 D2 0A 28 9F 3C 8C 29 B3 8A 68 C6 AE 7E 8B 90 87
2013/04/25 06:16:16 15226F8 F5 ED 50 77 DB 93 40 77 E5 24 E3 78 4E 50 C3 95
2013/04/25 06:16:16 15226F8 2E C3 F9 2C FB 34 C5 74 D4 4C 53 53 E1 E4 43 0C
2013/04/25 06:16:16 15226F8 33 EF 50
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] write to cardreader
2013/04/25 06:16:16 15226F8 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00 78
2013/04/25 06:16:16 15226F8 0A E8 43 1E 2F 3A 17 73 FF 2F A9 7B F8 04 2A 4F
2013/04/25 06:16:16 15226F8 02 73 F1 43 B0 22 8A D7 92 7D AF 49 04 82 1A 57
2013/04/25 06:16:16 15226F8 4E 30 BD 80 76 B0 D6 55 8D EE D3 70 23 E5 AC 70
2013/04/25 06:16:16 15226F8 0F 05 E5 6C 43 8B 93 37 A1 59 FF 22 62 4A 7E 57
2013/04/25 06:16:16 15226F8 8B BA 63 B4 F9 E0 68 25 4B 8B 58 30 88 48 AD D2
2013/04/25 06:16:16 15226F8 0A 28 9F 3C 8C 29 B3 8A 68 C6 AE 7E 8B 90 87 F5
2013/04/25 06:16:16 15226F8 ED 50 77 DB 93 40 77 E5 24 E3 78 4E 50 C3 95 2E
2013/04/25 06:16:16 15226F8 C3 F9 2C FB 34 C5 74 D4 4C 53 53 E1 E4 43 0C 33
2013/04/25 06:16:16 15226F8 EF 50
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] IFD: Setting timeout to 12 ETU (2232 us)
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] IFD: Transmit size 148 bytes, delay 0 us, timeout=2232 us
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] IFD: Transmit:
2013/04/25 06:16:16 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:16 15226F8 78 0A E8 43 1E 2F 3A 17 73 FF 2F A9 7B F8 04 2A
2013/04/25 06:16:16 15226F8 4F 02 73 F1 43 B0 22 8A D7 92 7D AF 49 04 82 1A
2013/04/25 06:16:16 15226F8 57 4E 30 BD 80 76 B0 D6 55 8D EE D3 70 23 E5 AC
2013/04/25 06:16:16 15226F8 70 0F 05 E5 6C 43 8B 93 37 A1 59 FF 22 62 4A 7E
2013/04/25 06:16:16 15226F8 57 8B BA 63 B4 F9 E0 68 25 4B 8B 58 30 88 48 AD
2013/04/25 06:16:16 15226F8 D2 0A 28 9F 3C 8C 29 B3 8A 68 C6 AE 7E 8B 90 87
2013/04/25 06:16:16 15226F8 F5 ED 50 77 DB 93 40 77 E5 24 E3 78 4E 50 C3 95
2013/04/25 06:16:16 15226F8 2E C3 F9 2C FB 34 C5 74 D4 4C 53 53 E1 E4 43 0C
2013/04/25 06:16:16 15226F8 33 EF 50 46
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] IO: Write timeout 2232 us, write delay 0 us, to send 148 char(s), chunksize 148 char(s)
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] IO: Sending:
2013/04/25 06:16:16 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:16 15226F8 78 0A E8 43 1E 2F 3A 17 73 FF 2F A9 7B F8 04 2A
2013/04/25 06:16:16 15226F8 4F 02 73 F1 43 B0 22 8A D7 92 7D AF 49 04 82 1A
2013/04/25 06:16:16 15226F8 57 4E 30 BD 80 76 B0 D6 55 8D EE D3 70 23 E5 AC
2013/04/25 06:16:16 15226F8 70 0F 05 E5 6C 43 8B 93 37 A1 59 FF 22 62 4A 7E
2013/04/25 06:16:16 15226F8 57 8B BA 63 B4 F9 E0 68 25 4B 8B 58 30 88 48 AD
2013/04/25 06:16:16 15226F8 D2 0A 28 9F 3C 8C 29 B3 8A 68 C6 AE 7E 8B 90 87
2013/04/25 06:16:16 15226F8 F5 ED 50 77 DB 93 40 77 E5 24 E3 78 4E 50 C3 95
2013/04/25 06:16:16 15226F8 2E C3 F9 2C FB 34 C5 74 D4 4C 53 53 E1 E4 43 0C
2013/04/25 06:16:16 15226F8 33 EF 50 46
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] IO: Reading 148 echoed transmitted chars...
2013/04/25 06:16:16 15226F8 r skyreader [irdeto] IO: Read timeout 9990000 us, read delay 0 us, to read 148 char(s), chunksize 148 char(s)
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:17 15226F8 01 05 00 00 02 8D 00 01 10 10 00 87 13 07 84 00
2013/04/25 06:16:17 15226F8 78 0A E8 43 1E 2F 3A 17 73 FF 2F A9 7B F8 04 2A
2013/04/25 06:16:17 15226F8 4F 02 73 F1 43 B0 22 8A D7 92 7D AF 49 04 82 1A
2013/04/25 06:16:17 15226F8 57 4E 30 BD 80 76 B0 D6 55 8D EE D3 70 23 E5 AC
2013/04/25 06:16:17 15226F8 70 0F 05 E5 6C 43 8B 93 37 A1 59 FF 22 62 4A 7E
2013/04/25 06:16:17 15226F8 57 8B BA 63 B4 F9 E0 68 25 4B 8B 58 30 88 48 AD
2013/04/25 06:16:17 15226F8 D2 0A 28 9F 3C 8C 29 B3 8A 68 C6 AE 7E 8B 90 87
2013/04/25 06:16:17 15226F8 F5 ED 50 77 DB 93 40 77 E5 24 E3 78 4E 50 C3 95
2013/04/25 06:16:17 15226F8 2E C3 F9 2C FB 34 C5 74 D4 4C 53 53 E1 E4 43 0C
2013/04/25 06:16:17 15226F8 33 EF 50 46
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IO: Reading of echoed transmitted chars done!
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Transmit succesful
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Receive size 8 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 8 char(s), chunksize 8 char(s)
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:17 15226F8 01 05 9D 00 38 00 02 16
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Receive size 22 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 22 char(s), chunksize 22 char(s)
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:17 15226F8 00 01 00 13 FF FF 63 56 A3 07 2F 36 B7 C9 54 3F
2013/04/25 06:16:17 15226F8 B4 15 2F 96 05 74
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Setting timeout to 9600 ETU (1785600 us)
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Receive size 1 bytes, delay 0 us, timeout=1785600 us
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IO: Read timeout 1785600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IO: Receiving:
2013/04/25 06:16:17 15226F8 6C
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] IFD: Receive succesful
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] Answer from cardreader:
2013/04/25 06:16:17 15226F8 00 01 00 13 FF FF 63 56 A3 07 2F 36 B7 C9 54 3F
2013/04/25 06:16:17 15226F8 B4 15 2F 96 05 74 9D 00
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] cardreader_do_ecm: after csystem.do_ecm rc=1
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] cardreader_do_ecm: ret rc=1
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] ecm hash: B35BB5A10043285776048B100500994E real time: 547 ms
2013/04/25 06:16:17 1540BE0 c poll() timeout
2013/04/25 06:16:17 1540BE0 c skyreader [irdeto] TRACE: ecm answer for ecm hash B35BB5A10043285776048B100500994E rc=0
2013/04/25 06:16:17 1540BE0 c Client sector csphash DB682D8E cw 270DF82CFB8C7C037FA85279732B9432 rc 0 1722&000000/0000/00DF/93:B35BB5A10043285776048B100500994E
2013/04/25 06:16:17 1540BE0 c sector (1722&000000/0000/00DF/93:B35BB5A10043285776048B100500994E): found (558 ms) by skyreader - Sky Bundesliga
2013/04/25 06:16:17 1540BE0 c cw:
2013/04/25 06:16:17 1540BE0 27 0D F8 2C FB 8C 7C 03 7F A8 52 79 73 2B 94 32
2013/04/25 06:16:17 15226F8 r add client job action 24 queue length 0 sector
2013/04/25 06:16:17 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:22 0 --- Skipped 5 duplicated log lines ---
2013/04/25 06:16:22 15246B0 h WebIf: Origin checked. Result: access from 192.168.178.25 => allowed
2013/04/25 06:16:22 15246B0 h WebIf: Received stale header from 192.168.178.25 (nonce=1de5a125b76cf5aeeffedf6fe6c0a877, expectednonce=518e8e11234b224454f6ad3428fc7d39, opaque=4a9ead2545866c4953a2266cb411aeb3).
2013/04/25 06:16:22 15246B0 h WebIf: Origin checked. Result: access from 192.168.178.25 => allowed
2013/04/25 06:16:23 0 --- Skipped 2 duplicated log lines ---
2013/04/25 06:16:23 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:27 0 --- Skipped 3 duplicated log lines ---
2013/04/25 06:16:27 15246B0 h WebIf: Origin checked. Result: access from 192.168.178.25 => allowed
2013/04/25 06:16:27 0 --- Skipped 1 duplicated log lines ---
2013/04/25 06:16:27 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:33 0 --- Skipped 5 duplicated log lines ---
2013/04/25 06:16:33 15246B0 h WebIf: No data received from client 192.168.178.25. Closing connection.
2013/04/25 06:16:33 0 --- Skipped 1 duplicated log lines ---
2013/04/25 06:16:33 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2013/04/25 06:16:44 0 --- Skipped 10 duplicated log lines ---
2013/04/25 06:16:44 1540BE0 c cccam(s) sector: parse_msg=6
2013/04/25 06:16:44 1540BE0 c cccam: keepalive
2013/04/25 06:16:44 15226F8 r skyreader [irdeto] cardreader_do_checkhealth: reader->card_status = 2, ret = 1