2019/05/22 20:27:05 00000000 (work) start reader thread action 13
2019/05/22 20:27:05 00000000 (main) starting thread client work
2019/05/22 20:27:05 00000000 (main) client work thread started
2019/05/22 20:27:05 00000000 (work) start reader thread action 13
2019/05/22 20:27:05 00000000 (main) starting thread client work
2019/05/22 20:27:05 00000000 (main) client work thread started
2019/05/22 20:27:05 56544F10 r (work) data from add_job action=13 client r
hdplus
2019/05/22 20:27:05 797949AC r (work) data from add_job action=13 client r sky
2019/05/22 20:27:05 596FE783 h (reader) sky [videoguard2] EMM: UNIQUE
2019/05/22 20:27:05 596FE783 h (reader) sky [videoguard2] EMM: CCCam unique EMM detected, no serial available, skipping filter check
2019/05/22 20:27:05 596FE783 h (webif) emm is being sent to reader sky.
2019/05/22 20:27:05 596FE783 h (work) start reader thread action 6
2019/05/22 20:27:05 596FE783 h (main) starting thread client work
2019/05/22 20:27:05 596FE783 h (main) client work thread started
2019/05/22 20:27:05 797949AC r (work) data from add_job action=6 client r sky
2019/05/22 20:27:05 596FE783 h (webif) WebIf: Origin checked. Result: access from 192.168.2.22 => allowed
2019/05/22 20:27:05 797949AC r (emm) Cleaned 0 emm stale stats and cache entries
2019/05/22 20:27:05 797949AC r (emmcache) found emmcache match
2019/05/22 20:27:05 797949AC r (emmcache) B0 76 83 87 A6 55 17 4E FD A9 35 A0 D2 EC 31 55
2019/05/22 20:27:05 797949AC r (emmcache) found emmstat match (reader:sky, count:4)
2019/05/22 20:27:05 797949AC r (emmcache) B0 76 83 87 A6 55 17 4E FD A9 35 A0 D2 EC 31 55
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] local emm reader
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] write to cardreader
2019/05/22 20:27:05 797949AC r (reader) D1 42 00 00 29 90 27 44 01 12 86 01 37 AF EA D7
2019/05/22 20:27:05 797949AC r (reader) CA 65 85 64 C2 32 AB 3B 73 AC 7E 56 18 E3 76 4F
2019/05/22 20:27:05 797949AC r (reader) 27 B6 BA 94 18 6E D0 7D 9A C4 A3 32 05 A1
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 15 ETU (77 us)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Transmit size 5 bytes, expected len 1 bytes, delay 0 us, timeout=77 us
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Transmit:
2019/05/22 20:27:05 797949AC r (reader) D1 42 00 00 29
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Write timeout 77 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Sending:
2019/05/22 20:27:05 797949AC r (reader) D1 42 00 00 29
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Transmit succesful
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:05 797949AC r (reader) 42
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 15 ETU (77 us)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Transmit size 41 bytes, expected len 2 bytes, delay 0 us, timeout=77 us
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Transmit:
2019/05/22 20:27:05 797949AC r (reader) 90 27 44 01 12 86 01 37 AF EA D7 CA 65 85 64 C2
2019/05/22 20:27:05 797949AC r (reader) 32 AB 3B 73 AC 7E 56 18 E3 76 4F 27 B6 BA 94 18
2019/05/22 20:27:05 797949AC r (reader) 6E D0 7D 9A C4 A3 32 05 A1
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Write timeout 77 us, write delay 0 us, to send 41 char(s), chunksize 41 char(s)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Sending:
2019/05/22 20:27:05 797949AC r (reader) 90 27 44 01 12 86 01 37 AF EA D7 CA 65 85 64 C2
2019/05/22 20:27:05 797949AC r (reader) 32 AB 3B 73 AC 7E 56 18 E3 76 4F 27 B6 BA 94 18
2019/05/22 20:27:05 797949AC r (reader) 6E D0 7D 9A C4 A3 32 05 A1
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Transmit succesful
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:05 797949AC r (reader) 90
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] TRACE: SW1: 90
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:05 797949AC r (reader) 80
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] TRACE: SW2: 80
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] Answer from cardreader:
2019/05/22 20:27:05 797949AC r (reader) 90 80
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] EMM: request return code : 9080
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] root emmtype=unique, len=45 (hex: 0x2D), cnt=5: written (35 ms)
2019/05/22 20:27:05 227C746C c (work) [OSCAM-WORK] new event 1 occurred on fd 25 after 13001 ms inactivity
2019/05/22 20:27:05 00000000 (main) starting thread client work
2019/05/22 20:27:05 00000000 (main) client work thread started
2019/05/22 20:27:05 00000000 (main) starting thread client work
2019/05/22 20:27:05 00000000 (main) client work thread started
2019/05/22 20:27:05 797949AC r (reader) sky [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/05/22 20:27:05 56544F10 r (reader)
hdplus [nagra] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
...
2019/05/22 20:27:06 4E64B7AD c (ecm) get cw for ecm:
2019/05/22 20:27:06 4E64B7AD c (ecm) 81 70 9F 00 00 01 1F B8 16 93 59 02 5A AA 55 05
2019/05/22 20:27:06 4E64B7AD c (ecm) 20 01 08 00 80 40 C9 0D B0 BD 10 C5 0F 44 CE 03
2019/05/22 20:27:06 4E64B7AD c (ecm) 04 05 02 FE 16 7E 7B 7E 0A 71 91 62 B7 6C 10 EC
2019/05/22 20:27:06 4E64B7AD c (ecm) F9 00 00 90 6D C1 01 61 24 11 EA 2B 7A 4D 7B 9A
2019/05/22 20:27:06 4E64B7AD c (ecm) 5A 97 B1 14 B1 08 EF 70 09 08 16 56 B9 4C F7 66
2019/05/22 20:27:06 4E64B7AD c (ecm) B8 46 73 AF A1 5D 85 92 3C 21 3C 6A A2 8A 1D E5
2019/05/22 20:27:06 4E64B7AD c (ecm) 42 89 DE 4D 9C 5B EF 2A 6B 74 E6 0F D9 A8 DF 84
2019/05/22 20:27:06 4E64B7AD c (ecm) B3 AB 04 60 CB 17 3E A6 34 21 34 50 9F FF 5D 79
2019/05/22 20:27:06 4E64B7AD c (ecm) C1 55 AB CA BA 58 8D B6 AB F6 FB DD BE 37 CA E8
2019/05/22 20:27:06 4E64B7AD c (ecm) DD 61 FD 2E 22 B8 DB 7F EA C3 59 0A 07 43 E4 37
2019/05/22 20:27:06 4E64B7AD c (ecm) 82 8D
2019/05/22 20:27:06 4E64B7AD c (ecm) {client local, caid 09C4, prid 000000, srvid 0082} [get_cw] NEW REQUEST!
2019/05/22 20:27:06 4E64B7AD c (chk) caid 09C4 not found in caidlist reader
hdplus
2019/05/22 20:27:06 4E64B7AD c (ecm) [GET_CW] wait_time 0 caid 09C4 prov 000000 srvid 0082 rc 100 cacheex cl mode 0 ex1rdr 0
2019/05/22 20:27:06 4E64B7AD c (ecm) {client local, caid 09C4, prid 000000, srvid 0082} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 0
2019/05/22 20:27:06 4E64B7AD c (ecm) request_cw stage=3 to reader sky ecm hash=BC22FF4466A5A7C56265F2447B3043A7
2019/05/22 20:27:06 4E64B7AD c (work) start reader thread action 5
2019/05/22 20:27:06 4E64B7AD c (main) starting thread client work
2019/05/22 20:27:06 4E64B7AD c (main) client work thread started
2019/05/22 20:27:06 4E64B7AD c (ecm) {client local, caid 09C4, prid 000000, srvid 0082} [write_ecm_request] reader sky --> SENT 1
2019/05/22 20:27:06 4E64B7AD c (ecm) Client local ECM dump 09C4@000000/025A/0082/A2:BC22FF4466A5A7C56265F2447B3043A7
2019/05/22 20:27:06 4E64B7AD c (ecm) 81 70 9F 00 00 01 1F B8 16 93 59 02 5A AA 55 05
2019/05/22 20:27:06 4E64B7AD c (ecm) 20 01 08 00 80 40 C9 0D B0 BD 10 C5 0F 44 CE 03
2019/05/22 20:27:06 4E64B7AD c (ecm) 04 05 02 FE 16 7E 7B 7E 0A 71 91 62 B7 6C 10 EC
2019/05/22 20:27:06 4E64B7AD c (ecm) F9 00 00 90 6D C1 01 61 24 11 EA 2B 7A 4D 7B 9A
2019/05/22 20:27:06 4E64B7AD c (ecm) 5A 97 B1 14 B1 08 EF 70 09 08 16 56 B9 4C F7 66
2019/05/22 20:27:06 4E64B7AD c (ecm) B8 46 73 AF A1 5D 85 92 3C 21 3C 6A A2 8A 1D E5
2019/05/22 20:27:06 4E64B7AD c (ecm) 42 89 DE 4D 9C 5B EF 2A 6B 74 E6 0F D9 A8 DF 84
2019/05/22 20:27:06 4E64B7AD c (ecm) B3 AB 04 60 CB 17 3E A6 34 21 34 50 9F FF 5D 79
2019/05/22 20:27:06 4E64B7AD c (ecm) C1 55 AB CA BA 58 8D B6 AB F6 FB DD BE 37 CA E8
2019/05/22 20:27:06 4E64B7AD c (ecm) DD 61 FD 2E 22 B8 DB 7F EA C3 59 0A 07 43 E4 37
2019/05/22 20:27:06 4E64B7AD c (ecm) 82 8D
2019/05/22 20:27:06 4E64B7AD c (dvbapi) Demuxer 0 request controlword for ecm 09C4@000000/025A/0082/A2:BC22FF4466A5A7C56265F2447B3043A7
2019/05/22 20:27:06 797949AC r (work) data from add_job action=5 client r sky
2019/05/22 20:27:06 797949AC r ecm:
2019/05/22 20:27:06 797949AC r 81 70 9F 00 00 01 1F B8 16 93 59 02 5A AA 55 05
2019/05/22 20:27:06 797949AC r 20 01 08 00 80 40 C9 0D B0 BD 10 C5 0F 44 CE 03
2019/05/22 20:27:06 797949AC r 04 05 02 FE 16 7E 7B 7E 0A 71 91 62 B7 6C 10 EC
2019/05/22 20:27:06 797949AC r F9 00 00 90 6D C1 01 61 24 11 EA 2B 7A 4D 7B 9A
2019/05/22 20:27:06 797949AC r 5A 97 B1 14 B1 08 EF 70 09 08 16 56 B9 4C F7 66
2019/05/22 20:27:06 797949AC r B8 46 73 AF A1 5D 85 92 3C 21 3C 6A A2 8A 1D E5
2019/05/22 20:27:06 797949AC r 42 89 DE 4D 9C 5B EF 2A 6B 74 E6 0F D9 A8 DF 84
2019/05/22 20:27:06 797949AC r B3 AB 04 60 CB 17 3E A6 34 21 34 50 9F FF 5D 79
2019/05/22 20:27:06 797949AC r C1 55 AB CA BA 58 8D B6 AB F6 FB DD BE 37 CA E8
2019/05/22 20:27:06 797949AC r DD 61 FD 2E 22 B8 DB 7F EA C3 59 0A 07 43 E4 37
2019/05/22 20:27:06 797949AC r 82 8D
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] write to cardreader
2019/05/22 20:27:06 797949AC r (reader) D1 40 00 80 7C 00 7E 0A 71 91 62 B7 6C 10 EC F9
2019/05/22 20:27:06 797949AC r (reader) 00 00 90 6D C1 01 61 24 11 EA 2B 7A 4D 7B 9A 5A
2019/05/22 20:27:06 797949AC r (reader) 97 B1 14 B1 08 EF 70 09 08 16 56 B9 4C F7 66 B8
2019/05/22 20:27:06 797949AC r (reader) 46 73 AF A1 5D 85 92 3C 21 3C 6A A2 8A 1D E5 42
2019/05/22 20:27:06 797949AC r (reader) 89 DE 4D 9C 5B EF 2A 6B 74 E6 0F D9 A8 DF 84 B3
2019/05/22 20:27:06 797949AC r (reader) AB 04 60 CB 17 3E A6 34 21 34 50 9F FF 5D 79 C1
2019/05/22 20:27:06 797949AC r (reader) 55 AB CA BA 58 8D B6 AB F6 FB DD BE 37 CA E8 DD
2019/05/22 20:27:06 797949AC r (reader) 61 FD 2E 22 B8 DB 7F EA C3 59 0A 07 43 E4 37 82
2019/05/22 20:27:06 797949AC r (reader) 8D
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 15 ETU (77 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit size 5 bytes, expected len 1 bytes, delay 0 us, timeout=77 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit:
2019/05/22 20:27:06 797949AC r (reader) D1 40 00 80 7C
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Write timeout 77 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Sending:
2019/05/22 20:27:06 797949AC r (reader) D1 40 00 80 7C
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:06 797949AC r (reader) 40
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 15 ETU (77 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit size 124 bytes, expected len 2 bytes, delay 0 us, timeout=77 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit:
2019/05/22 20:27:06 797949AC r (reader) 00 7E 0A 71 91 62 B7 6C 10 EC F9 00 00 90 6D C1
2019/05/22 20:27:06 797949AC r (reader) 01 61 24 11 EA 2B 7A 4D 7B 9A 5A 97 B1 14 B1 08
2019/05/22 20:27:06 797949AC r (reader) EF 70 09 08 16 56 B9 4C F7 66 B8 46 73 AF A1 5D
2019/05/22 20:27:06 797949AC r (reader) 85 92 3C 21 3C 6A A2 8A 1D E5 42 89 DE 4D 9C 5B
2019/05/22 20:27:06 797949AC r (reader) EF 2A 6B 74 E6 0F D9 A8 DF 84 B3 AB 04 60 CB 17
2019/05/22 20:27:06 797949AC r (reader) 3E A6 34 21 34 50 9F FF 5D 79 C1 55 AB CA BA 58
2019/05/22 20:27:06 797949AC r (reader) 8D B6 AB F6 FB DD BE 37 CA E8 DD 61 FD 2E 22 B8
2019/05/22 20:27:06 797949AC r (reader) DB 7F EA C3 59 0A 07 43 E4 37 82 8D
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Write timeout 77 us, write delay 0 us, to send 124 char(s), chunksize 124 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Sending:
2019/05/22 20:27:06 797949AC r (reader) 00 7E 0A 71 91 62 B7 6C 10 EC F9 00 00 90 6D C1
2019/05/22 20:27:06 797949AC r (reader) 01 61 24 11 EA 2B 7A 4D 7B 9A 5A 97 B1 14 B1 08
2019/05/22 20:27:06 797949AC r (reader) EF 70 09 08 16 56 B9 4C F7 66 B8 46 73 AF A1 5D
2019/05/22 20:27:06 797949AC r (reader) 85 92 3C 21 3C 6A A2 8A 1D E5 42 89 DE 4D 9C 5B
2019/05/22 20:27:06 797949AC r (reader) EF 2A 6B 74 E6 0F D9 A8 DF 84 B3 AB 04 60 CB 17
2019/05/22 20:27:06 797949AC r (reader) 3E A6 34 21 34 50 9F FF 5D 79 C1 55 AB CA BA 58
2019/05/22 20:27:06 797949AC r (reader) 8D B6 AB F6 FB DD BE 37 CA E8 DD 61 FD 2E 22 B8
2019/05/22 20:27:06 797949AC r (reader) DB 7F EA C3 59 0A 07 43 E4 37 82 8D
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:06 4E64B7AD c (ecm) get cw for ecm:
2019/05/22 20:27:06 4E64B7AD c (ecm) 80 70 9F 00 00 01 1F B8 16 93 5D 02 5A AA 55 05
2019/05/22 20:27:06 4E64B7AD c (ecm) 20 01 08 00 80 40 27 F7 DE B8 0D 71 8A 6D 71 03
2019/05/22 20:27:06 4E64B7AD c (ecm) 04 05 02 FE 16 E3 7B 7E 0A D0 9E C2 B6 75 D7 D0
2019/05/22 20:27:06 4E64B7AD c (ecm) 9F 00 00 90 6D C1 01 91 92 0D B2 0C E5 67 70 C7
2019/05/22 20:27:06 4E64B7AD c (ecm) 8F 09 56 BF 41 CB B2 09 48 31 DD 4A 02 A4 3F 24
2019/05/22 20:27:06 4E64B7AD c (ecm) A5 98 E0 0B 15 D8 01 FA 78 8B 1A B5 D7 32 26 15
2019/05/22 20:27:06 4E64B7AD c (ecm) 57 6C D5 30 B6 F5 DF 5B 7D AB 92 2D 53 B5 68 5D
2019/05/22 20:27:06 4E64B7AD c (ecm) A4 7B 15 40 CE 1B AD 9C 79 FD FE 4A 83 A2 8A 08
2019/05/22 20:27:06 4E64B7AD c (ecm) 9C 7C B0 5A B4 8F 4C A7 46 7B 56 E6 18 FC 6D 12
2019/05/22 20:27:06 4E64B7AD c (ecm) C8 82 1D EC 86 F8 0C 9B 9F 03 30 3E 3F 79 4E 14
2019/05/22 20:27:06 4E64B7AD c (ecm) B9 4A
2019/05/22 20:27:06 4E64B7AD c (ecm) {client local, caid 09C4, prid 000000, srvid 0082} [get_cw] NEW REQUEST!
2019/05/22 20:27:06 4E64B7AD c (chk) caid 09C4 not found in caidlist reader
hdplus
2019/05/22 20:27:06 4E64B7AD c (ecm) [GET_CW] wait_time 0 caid 09C4 prov 000000 srvid 0082 rc 100 cacheex cl mode 0 ex1rdr 0
2019/05/22 20:27:06 4E64B7AD c (ecm) {client local, caid 09C4, prid 000000, srvid 0082} [get_cw] wait_time 0 - client cacheex mode 0, reader avail for ecm 1, hitcache 0, preferlocalcards 0
2019/05/22 20:27:06 4E64B7AD c (ecm) request_cw stage=3 to reader sky ecm hash=CA04E516A34D8D1004E21AFDC59862C3
2019/05/22 20:27:06 4E64B7AD c (work) add reader job action 5 queue length 1 sky
2019/05/22 20:27:06 4E64B7AD c (ecm) {client local, caid 09C4, prid 000000, srvid 0082} [write_ecm_request] reader sky --> SENT 1
2019/05/22 20:27:06 4E64B7AD c (ecm) Client local ECM dump 09C4@000000/025A/0082/A2:CA04E516A34D8D1004E21AFDC59862C3
2019/05/22 20:27:06 4E64B7AD c (ecm) 80 70 9F 00 00 01 1F B8 16 93 5D 02 5A AA 55 05
2019/05/22 20:27:06 4E64B7AD c (ecm) 20 01 08 00 80 40 27 F7 DE B8 0D 71 8A 6D 71 03
2019/05/22 20:27:06 4E64B7AD c (ecm) 04 05 02 FE 16 E3 7B 7E 0A D0 9E C2 B6 75 D7 D0
2019/05/22 20:27:06 4E64B7AD c (ecm) 9F 00 00 90 6D C1 01 91 92 0D B2 0C E5 67 70 C7
2019/05/22 20:27:06 4E64B7AD c (ecm) 8F 09 56 BF 41 CB B2 09 48 31 DD 4A 02 A4 3F 24
2019/05/22 20:27:06 4E64B7AD c (ecm) A5 98 E0 0B 15 D8 01 FA 78 8B 1A B5 D7 32 26 15
2019/05/22 20:27:06 4E64B7AD c (ecm) 57 6C D5 30 B6 F5 DF 5B 7D AB 92 2D 53 B5 68 5D
2019/05/22 20:27:06 4E64B7AD c (ecm) A4 7B 15 40 CE 1B AD 9C 79 FD FE 4A 83 A2 8A 08
2019/05/22 20:27:06 4E64B7AD c (ecm) 9C 7C B0 5A B4 8F 4C A7 46 7B 56 E6 18 FC 6D 12
2019/05/22 20:27:06 4E64B7AD c (ecm) C8 82 1D EC 86 F8 0C 9B 9F 03 30 3E 3F 79 4E 14
2019/05/22 20:27:06 4E64B7AD c (ecm) B9 4A
2019/05/22 20:27:06 4E64B7AD c (dvbapi) Demuxer 0 request controlword for ecm 09C4@000000/025A/0082/A2:CA04E516A34D8D1004E21AFDC59862C3
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:06 797949AC r (reader) 90
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] TRACE: SW1: 90
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:06 797949AC r (reader) 80
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] TRACE: SW2: 80
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] Answer from cardreader:
2019/05/22 20:27:06 797949AC r (reader) 90 80
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] write to cardreader
2019/05/22 20:27:06 797949AC r (reader) D3 54 00 00 58
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 15 ETU (77 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit size 5 bytes, expected len 91 bytes, delay 0 us, timeout=77 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit:
2019/05/22 20:27:06 797949AC r (reader) D3 54 00 00 58
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Write timeout 77 us, write delay 0 us, to send 5 char(s), chunksize 5 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Sending:
2019/05/22 20:27:06 797949AC r (reader) D3 54 00 00 58
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Transmit succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:06 797949AC r (reader) 54
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive size 88 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 88 char(s), chunksize 88 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:06 797949AC r (reader) 59 4C 82 37 9C 0D 74 CB F3 A9 4F 95 AF 30 F4 74
2019/05/22 20:27:06 797949AC r (reader) D8 EB D6 4A C7 63 61 31 A8 F6 CD D7 10 15 F1 7E
2019/05/22 20:27:06 797949AC r (reader) 60 EB 64 6E 4D 53 FA FB 2B 17 EA 88 16 E6 9E B9
2019/05/22 20:27:06 797949AC r (reader) C7 CA 21 2D C5 82 05 EA 8F 6E 50 A7 94 16 36 C8
2019/05/22 20:27:06 797949AC r (reader) B5 E9 FD C9 56 5F 87 6B 40 2A 41 72 C3 FC C8 18
2019/05/22 20:27:06 797949AC r (reader) A5 D6 05 D6 A7 9C 99 2D
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:06 797949AC r (reader) 90
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] TRACE: SW1: 90
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Setting timeout to 153600 ETU (793600 us)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive size 1 bytes, delay 0 us, timeout=793600 us
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Read timeout 793600 us, read delay 0 us, to read 1 char(s), chunksize 1 char(s)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IO: Receiving:
2019/05/22 20:27:06 797949AC r (reader) 80
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] IFD: Receive succesful
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] TRACE: SW2: 80
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] Answer from cardreader:
2019/05/22 20:27:06 797949AC r (reader) 59 4C 82 37 9C 0D 74 CB F3 A9 4F 95 AF 30 F4 74
2019/05/22 20:27:06 797949AC r (reader) D8 EB D6 4A C7 63 61 31 A8 F6 CD D7 10 15 F1 7E
2019/05/22 20:27:06 797949AC r (reader) 60 EB 64 6E 4D 53 FA FB 2B 17 EA 88 16 E6 9E B9
2019/05/22 20:27:06 797949AC r (reader) C7 CA 21 2D C5 82 05 EA 8F 6E 50 A7 94 16 36 C8
2019/05/22 20:27:06 797949AC r (reader) B5 E9 FD C9 56 5F 87 6B 40 2A 41 72 C3 FC C8 18
2019/05/22 20:27:06 797949AC r (reader) A5 D6 05 D6 A7 9C 99 2D 90 80
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] Decrypted payload
2019/05/22 20:27:06 797949AC r (reader) 00 00 00 00 00 00 00 00 00 00 00 00 FF 00 22 02
2019/05/22 20:27:06 797949AC r (reader) 00 80 0E 02 00 00 0F 04 00 00 20 00 20 04 00 00
2019/05/22 20:27:06 797949AC r (reader) 00 00 25 11 00 00 00 00 00 00 00 00 00 00 00 00
2019/05/22 20:27:06 797949AC r (reader) 00 00 00 00 00 2A 04 00 00 00 00 55 01 00 56 08
2019/05/22 20:27:06 797949AC r (reader) 00 00 00 00 00 00 00 00
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] classD3 ins54: no cw --> Tier expired
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] cardreader_do_ecm: after csystem->do_ecm rc=0
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] cardreader_do_ecm: ret rc=0
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=0 (ERROR=0)
2019/05/22 20:27:06 797949AC r (reader) sky [videoguard2] Error processing ecm for caid 09C4, provid 000000, srvid 0082, servicename: Discovery HD
2019/05/22 20:27:06 797949AC r (ecm) {client local, caid 09C4, srvid 0082} [write_ecm_answer] cyclecheck passed! Reader: sky rc: 4
2019/05/22 20:27:06 797949AC r (ecm) {client local, caid 09C4, prid 000000, srvid 0082} [write_ecm_answer] reader sky rc 4, ecm time 118 ms (119 ms)
2019/05/22 20:27:06 797949AC r (work) start client thread action 32