2012/12/16 14:24:44 5B5160 h all debug_level=65535
2012/12/16 14:24:45 5B5160 h WebIf: Origin checked. Result: access from 192.168.100.186 => allowed
2012/12/16 14:24:45 0 --- Skipped 2 duplicated log lines ---
2012/12/16 14:24:45 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:24:46 0 --- Skipped 1 duplicated log lines ---
2012/12/16 14:24:46 5DC7D0 p add reader job action 1 queue length 1 sky-01
2012/12/16 14:24:46 5E1B60 p add reader job action 1 queue length 1 sky-03
2012/12/16 14:24:46 5DF198 p add reader job action 1 queue length 1 sky-02
2012/12/16 14:24:46 5E4C88 p add reader job action 1 queue length 1 sky-04
2012/12/16 14:24:47 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:24:50 0 --- Skipped 2 duplicated log lines ---
2012/12/16 14:24:50 0 share-updater mode=interval t=20s
2012/12/16 14:24:50 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:24:50 5B5160 h WebIf: read error ret=0 (errno=9 Bad file descriptor)
2012/12/16 14:24:50 5B5160 h WebIf: No data received from client 192.168.100.186. Closing connection.
2012/12/16 14:24:50 5B5160 h WebIf: read error ret=0 (errno=9 Bad file descriptor)
2012/12/16 14:24:50 5B5160 h WebIf: No data received from client 192.168.100.186. Closing connection.
2012/12/16 14:24:50 5B5160 h WebIf: read error ret=0 (errno=9 Bad file descriptor)
2012/12/16 14:24:50 5B5160 h WebIf: No data received from client 192.168.100.186. Closing connection.
2012/12/16 14:24:51 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:00 0 --- Skipped 9 duplicated log lines ---
2012/12/16 14:25:00 62F720 c cccam(s) xxxxxxx: parse_msg=1
2012/12/16 14:25:00 62F720 c cccam(s) xxxxxxx: ECM request from client: caid 1702 srvid 0078(147) prid 000000
2012/12/16 14:25:00 62F720 c caid 1702 not found in caidlist reader
smartmouse
2012/12/16 14:25:00 62F720 c matching_reader became slot attribute of 1
2012/12/16 14:25:00 0 --- Skipped 3 duplicated log lines ---
2012/12/16 14:25:00 62F720 c request_cw stage=4 to reader sky-01 ecm hash=6C6F03B737E89CA07B75C025532500B6
2012/12/16 14:25:00 62F720 c add reader job action 5 queue length 1 sky-01
2012/12/16 14:25:00 62F720 c request_cw stage=4 to reader sky-02 ecm hash=6C6F03B737E89CA07B75C025532500B6
2012/12/16 14:25:00 62F720 c add reader job action 5 queue length 1 sky-02
2012/12/16 14:25:00 62F720 c request_cw stage=4 to reader sky-03 ecm hash=6C6F03B737E89CA07B75C025532500B6
2012/12/16 14:25:00 62F720 c add reader job action 5 queue length 1 sky-03
2012/12/16 14:25:00 62F720 c request_cw stage=4 to reader sky-04 ecm hash=6C6F03B737E89CA07B75C025532500B6
2012/12/16 14:25:00 62F720 c add reader job action 5 queue length 1 sky-04
2012/12/16 14:25:00 62F720 c Client xxxxxxx ECM dump 1702&000000/0000/0078/93:6C6F03B737E89CA07B75C025532500B6
2012/12/16 14:25:00 62F720 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2012/12/16 14:25:00 62F720 D8 3D 6D F7 34 FB EE 31 FF 88 0A 03 37 73 71 D9
2012/12/16 14:25:00 62F720 09 71 22 65 BC 86 D5 8F A3 21 64 65 FA E2 E9 B0
2012/12/16 14:25:00 62F720 8F A2 30 80 B8 92 42 42 76 10 2B 68 E4 26 78 FA
2012/12/16 14:25:00 62F720 84 6B 15 18 2F 33 F6 22 E8 3F C8 E2 F5 90 BF 48
2012/12/16 14:25:00 62F720 81 05 F2 AB 23 0F 3B 27 4D F8 A6 4F 57 32 8A B0
2012/12/16 14:25:00 62F720 0C 7E 7B 4D 71 58 37 59 C1 B5 DF B6 53 6E 74 93
2012/12/16 14:25:00 62F720 8A ED E1 33 51 2C 37 3C B3 88 8F 26 F2 70 0D 45
2012/12/16 14:25:00 62F720 0A 66 FB 0E 40 CE D1 17 31 95 66 CC 54 93 F1 24
2012/12/16 14:25:00 62F720 EB 64 83
2012/12/16 14:25:00 5E4C88 p poll() timeout
2012/12/16 14:25:00 5E4C88 p ---- ecm_task 0, idx 1, sflag=1, level=0
2012/12/16 14:25:00 5E4C88 p casc ecm:
2012/12/16 14:25:00 5E4C88 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2012/12/16 14:25:00 5E4C88 D8 3D 6D F7 34 FB EE 31 FF 88 0A 03 37 73 71 D9
2012/12/16 14:25:00 5E4C88 09 71 22 65 BC 86 D5 8F A3 21 64 65 FA E2 E9 B0
2012/12/16 14:25:00 5E4C88 8F A2 30 80 B8 92 42 42 76 10 2B 68 E4 26 78 FA
2012/12/16 14:25:00 5E4C88 84 6B 15 18 2F 33 F6 22 E8 3F C8 E2 F5 90 BF 48
2012/12/16 14:25:00 5E4C88 81 05 F2 AB 23 0F 3B 27 4D F8 A6 4F 57 32 8A B0
2012/12/16 14:25:00 5E4C88 0C 7E 7B 4D 71 58 37 59 C1 B5 DF B6 53 6E 74 93
2012/12/16 14:25:00 5E4C88 8A ED E1 33 51 2C 37 3C B3 88 8F 26 F2 70 0D 45
2012/12/16 14:25:00 5E4C88 0A 66 FB 0E 40 CE D1 17 31 95 66 CC 54 93 F1 24
2012/12/16 14:25:00 5E4C88 EB 64 83
2012/12/16 14:25:00 5E4C88 p cccam: ecm trylock: got lock
2012/12/16 14:25:00 5E4C88 p cccam: ecm-task 1
2012/12/16 14:25:00 5E4C88 p cccam(r) sky-04: sending ecm for sid 0078(147) to card 0000006c, hop 1, ecmtask 1
2012/12/16 14:25:00 5E1B60 p poll() timeout
2012/12/16 14:25:00 5E1B60 p ---- ecm_task 0, idx 1, sflag=1, level=0
2012/12/16 14:25:00 5E1B60 p casc ecm:
2012/12/16 14:25:00 5E1B60 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2012/12/16 14:25:00 5E1B60 D8 3D 6D F7 34 FB EE 31 FF 88 0A 03 37 73 71 D9
2012/12/16 14:25:00 5E1B60 09 71 22 65 BC 86 D5 8F A3 21 64 65 FA E2 E9 B0
2012/12/16 14:25:00 5E1B60 8F A2 30 80 B8 92 42 42 76 10 2B 68 E4 26 78 FA
2012/12/16 14:25:00 5E1B60 84 6B 15 18 2F 33 F6 22 E8 3F C8 E2 F5 90 BF 48
2012/12/16 14:25:00 5E1B60 81 05 F2 AB 23 0F 3B 27 4D F8 A6 4F 57 32 8A B0
2012/12/16 14:25:00 5E1B60 0C 7E 7B 4D 71 58 37 59 C1 B5 DF B6 53 6E 74 93
2012/12/16 14:25:00 5E1B60 8A ED E1 33 51 2C 37 3C B3 88 8F 26 F2 70 0D 45
2012/12/16 14:25:00 5E1B60 0A 66 FB 0E 40 CE D1 17 31 95 66 CC 54 93 F1 24
2012/12/16 14:25:00 5E1B60 EB 64 83
2012/12/16 14:25:00 5E1B60 p cccam: ecm trylock: got lock
2012/12/16 14:25:00 5E1B60 p cccam: ecm-task 1
2012/12/16 14:25:00 5DC7D0 p poll() timeout
2012/12/16 14:25:00 5DC7D0 p ---- ecm_task 0, idx 1, sflag=1, level=0
2012/12/16 14:25:00 5DC7D0 p casc ecm:
2012/12/16 14:25:00 5DC7D0 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2012/12/16 14:25:00 5DC7D0 D8 3D 6D F7 34 FB EE 31 FF 88 0A 03 37 73 71 D9
2012/12/16 14:25:00 5DC7D0 09 71 22 65 BC 86 D5 8F A3 21 64 65 FA E2 E9 B0
2012/12/16 14:25:00 5DC7D0 8F A2 30 80 B8 92 42 42 76 10 2B 68 E4 26 78 FA
2012/12/16 14:25:00 5DC7D0 84 6B 15 18 2F 33 F6 22 E8 3F C8 E2 F5 90 BF 48
2012/12/16 14:25:00 5DC7D0 81 05 F2 AB 23 0F 3B 27 4D F8 A6 4F 57 32 8A B0
2012/12/16 14:25:00 5DC7D0 0C 7E 7B 4D 71 58 37 59 C1 B5 DF B6 53 6E 74 93
2012/12/16 14:25:00 5DC7D0 8A ED E1 33 51 2C 37 3C B3 88 8F 26 F2 70 0D 45
2012/12/16 14:25:00 5DC7D0 0A 66 FB 0E 40 CE D1 17 31 95 66 CC 54 93 F1 24
2012/12/16 14:25:00 5DC7D0 EB 64 83
2012/12/16 14:25:00 5DC7D0 p cccam: ecm trylock: got lock
2012/12/16 14:25:00 5DC7D0 p cccam: ecm-task 1
2012/12/16 14:25:00 5DC7D0 p cccam(r) sky-01: sending ecm for sid 0078(147) to card 0000006c, hop 1, ecmtask 1
2012/12/16 14:25:00 5DF198 p poll() timeout
2012/12/16 14:25:00 5DF198 p ---- ecm_task 0, idx 1, sflag=1, level=0
2012/12/16 14:25:00 5DF198 p casc ecm:
2012/12/16 14:25:00 5DF198 80 70 90 C7 00 00 00 01 10 10 00 87 12 07 84 00
2012/12/16 14:25:00 5DF198 D8 3D 6D F7 34 FB EE 31 FF 88 0A 03 37 73 71 D9
2012/12/16 14:25:00 5DF198 09 71 22 65 BC 86 D5 8F A3 21 64 65 FA E2 E9 B0
2012/12/16 14:25:00 5DF198 8F A2 30 80 B8 92 42 42 76 10 2B 68 E4 26 78 FA
2012/12/16 14:25:00 5DF198 84 6B 15 18 2F 33 F6 22 E8 3F C8 E2 F5 90 BF 48
2012/12/16 14:25:00 5DF198 81 05 F2 AB 23 0F 3B 27 4D F8 A6 4F 57 32 8A B0
2012/12/16 14:25:00 5DF198 0C 7E 7B 4D 71 58 37 59 C1 B5 DF B6 53 6E 74 93
2012/12/16 14:25:00 5DF198 8A ED E1 33 51 2C 37 3C B3 88 8F 26 F2 70 0D 45
2012/12/16 14:25:00 5DF198 0A 66 FB 0E 40 CE D1 17 31 95 66 CC 54 93 F1 24
2012/12/16 14:25:00 5DF198 EB 64 83
2012/12/16 14:25:00 5DF198 p cccam: ecm trylock: got lock
2012/12/16 14:25:00 5DF198 p cccam: ecm-task 1
2012/12/16 14:25:00 5DF198 p cccam(r) sky-02: sending ecm for sid 0078(147) to card 0000006c, hop 1, ecmtask 1
2012/12/16 14:25:00 5E1B60 p cccam(r) sky-03: sending ecm for sid 0078(147) to card 0000006c, hop 1, ecmtask 1
2012/12/16 14:25:01 5DC7D0 p cccam(r) sky-01: parse_msg=254
2012/12/16 14:25:01 5DC7D0 p cccam(r) sky-01: card removed: id 6C remoteid 6C caid 1702 hop 1 reshare 0 originid 6C cardtype 10
2012/12/16 14:25:01 5DC7D0 p cccam: ecm trylock: got lock
2012/12/16 14:25:01 5DC7D0 p cccam: ecm-task 1
2012/12/16 14:25:01 5DC7D0 p cccam(r) sky-01: no suitable card on server
2012/12/16 14:25:01 5DC7D0 p add client job action 24 queue length 1 xxxxxxx
2012/12/16 14:25:01 5DC7D0 p cccam(r) sky-01: no ecm pending!
2012/12/16 14:25:01 62F720 c poll() timeout
2012/12/16 14:25:01 62F720 c sky-01 [cccam] TRACE: ecm answer for ecm hash 6C6F03B737E89CA07B75C025532500B6 rc=4
2012/12/16 14:25:01 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:02 0 --- Skipped 1 duplicated log lines ---
2012/12/16 14:25:02 5DF198 p cccam(r) sky-02: parse_msg=254
2012/12/16 14:25:02 5DF198 p cccam(r) sky-02: card removed: id 6C remoteid 6C caid 1702 hop 1 reshare 0 originid 6C cardtype 10
2012/12/16 14:25:02 5DF198 p cccam: ecm trylock: got lock
2012/12/16 14:25:02 5DF198 p cccam: ecm-task 1
2012/12/16 14:25:02 5DF198 p cccam(r) sky-02: no suitable card on server
2012/12/16 14:25:02 5DF198 p add client job action 24 queue length 1 xxxxxxx
2012/12/16 14:25:02 5DF198 p cccam(r) sky-02: no ecm pending!
2012/12/16 14:25:02 62F720 c poll() timeout
2012/12/16 14:25:02 62F720 c sky-02 [cccam] TRACE: ecm answer for ecm hash 6C6F03B737E89CA07B75C025532500B6 rc=4
2012/12/16 14:25:03 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: parse_msg=254
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: card removed: id 6C remoteid 6C caid 1702 hop 1 reshare 0 originid 6C cardtype 10
2012/12/16 14:25:03 5E4C88 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5E4C88 p cccam: ecm-task 1
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: no suitable card on server
2012/12/16 14:25:03 5E4C88 p add client job action 24 queue length 1 xxxxxxx
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: no ecm pending!
2012/12/16 14:25:03 62F720 c poll() timeout
2012/12/16 14:25:03 62F720 c sky-04 [cccam] TRACE: ecm answer for ecm hash 6C6F03B737E89CA07B75C025532500B6 rc=4
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: parse_msg=254
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: card removed: id 6C remoteid 6C caid 1702 hop 1 reshare 0 originid 6C cardtype 10
2012/12/16 14:25:03 5E1B60 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5E1B60 p cccam: ecm-task 1
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: no suitable card on server
2012/12/16 14:25:03 5E1B60 p add client job action 24 queue length 1 xxxxxxx
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: no ecm pending!
2012/12/16 14:25:03 62F720 c poll() timeout
2012/12/16 14:25:03 62F720 c sky-03 [cccam] TRACE: ecm answer for ecm hash 6C6F03B737E89CA07B75C025532500B6 rc=4
2012/12/16 14:25:03 62F720 c Client xxxxxxx csphash 3486D872 cw 00000000000000000000000000000000 rc 4 1702&000000/0000/0078/93:6C6F03B737E89CA07B75C025532500B6
2012/12/16 14:25:03 62F720 c xxxxxxx (1702&000000/0000/0078/93:6C6F03B737E89CA07B75C025532500B6): rejected (2614 ms) by sky-03 (F/4/0/0) - Sky Select HD
2012/12/16 14:25:03 62F720 c cw:
2012/12/16 14:25:03 62F720 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2012/12/16 14:25:03 62F720 c cccam(s) xxxxxxx: parse_msg=1
2012/12/16 14:25:03 62F720 c cccam(s) xxxxxxx: ECM request from client: caid 09c4 srvid 0078(179) prid 000000
2012/12/16 14:25:03 62F720 c caid 09C4 not found in caidlist reader
smartmouse
2012/12/16 14:25:03 62F720 c matching_reader became slot attribute of 1
2012/12/16 14:25:03 0 --- Skipped 3 duplicated log lines ---
2012/12/16 14:25:03 62F720 c request_cw stage=4 to reader sky-01 ecm hash=6FDB1BCB813CB22FF4A65B2F02653A05
2012/12/16 14:25:03 5DC7D0 p poll() timeout
2012/12/16 14:25:03 5DC7D0 p ---- ecm_task 0, idx 2, sflag=1, level=0
2012/12/16 14:25:03 5DC7D0 p casc ecm:
2012/12/16 14:25:03 5DC7D0 81 70 B0 00 00 01 1D 6B 10 6B 19 0D 4B AA 55 81
2012/12/16 14:25:03 5DC7D0 20 01 00 00 80 40 9C A7 B3 92 BA 1F 7F 35 BD 03
2012/12/16 14:25:03 5DC7D0 02 02 02 B1 8E 7E 0A 91 D6 C9 BB D1 AF AD 75 00
2012/12/16 14:25:03 5DC7D0 00 90 80 C1 01 53 84 2C 21 90 6B 56 BE FA 3C 0B
2012/12/16 14:25:03 5DC7D0 14 40 C2 23 78 27 32 5B B8 48 80 48 AA B0 F1 EF
2012/12/16 14:25:03 5DC7D0 AB B2 20 4C C8 40 AF 9D B5 0C D1 9F 8A ED 7F 98
2012/12/16 14:25:03 5DC7D0 BD 07 82 BB E1 32 53 93 BB 8A 17 7F FA 5D 86 AC
2012/12/16 14:25:03 5DC7D0 3C EC 39 2B 7F 4F 48 51 81 E6 4C 38 B4 DF E9 A2
2012/12/16 14:25:03 5DC7D0 AC BB AB F5 3F D4 07 8C 5C 14 F4 C2 DD 2B DD E5
2012/12/16 14:25:03 5DC7D0 F1 61 5A E9 97 40 64 28 19 85 94 AA 14 4D 64 FB
2012/12/16 14:25:03 5DC7D0 44 EF A5 6B 28 69 89 74 80 7F DB 84 A3 C5 7A D1
2012/12/16 14:25:03 5DC7D0 E6 32 7A
2012/12/16 14:25:03 5DC7D0 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5DC7D0 p cccam: ecm-task 2
2012/12/16 14:25:03 5DC7D0 p cccam(r) sky-01: sending ecm for sid 0078(179) to card 00000078, hop 1, ecmtask 2
2012/12/16 14:25:03 62F720 c add reader job action 5 queue length 0 sky-01
2012/12/16 14:25:03 62F720 c request_cw stage=4 to reader sky-02 ecm hash=6FDB1BCB813CB22FF4A65B2F02653A05
2012/12/16 14:25:03 5DF198 p poll() timeout
2012/12/16 14:25:03 5DF198 p ---- ecm_task 0, idx 2, sflag=1, level=0
2012/12/16 14:25:03 5DF198 p casc ecm:
2012/12/16 14:25:03 5DF198 81 70 B0 00 00 01 1D 6B 10 6B 19 0D 4B AA 55 81
2012/12/16 14:25:03 5DF198 20 01 00 00 80 40 9C A7 B3 92 BA 1F 7F 35 BD 03
2012/12/16 14:25:03 5DF198 02 02 02 B1 8E 7E 0A 91 D6 C9 BB D1 AF AD 75 00
2012/12/16 14:25:03 5DF198 00 90 80 C1 01 53 84 2C 21 90 6B 56 BE FA 3C 0B
2012/12/16 14:25:03 5DF198 14 40 C2 23 78 27 32 5B B8 48 80 48 AA B0 F1 EF
2012/12/16 14:25:03 5DF198 AB B2 20 4C C8 40 AF 9D B5 0C D1 9F 8A ED 7F 98
2012/12/16 14:25:03 5DF198 BD 07 82 BB E1 32 53 93 BB 8A 17 7F FA 5D 86 AC
2012/12/16 14:25:03 5DF198 3C EC 39 2B 7F 4F 48 51 81 E6 4C 38 B4 DF E9 A2
2012/12/16 14:25:03 5DF198 AC BB AB F5 3F D4 07 8C 5C 14 F4 C2 DD 2B DD E5
2012/12/16 14:25:03 5DF198 F1 61 5A E9 97 40 64 28 19 85 94 AA 14 4D 64 FB
2012/12/16 14:25:03 5DF198 44 EF A5 6B 28 69 89 74 80 7F DB 84 A3 C5 7A D1
2012/12/16 14:25:03 5DF198 E6 32 7A
2012/12/16 14:25:03 5DF198 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5DF198 p cccam: ecm-task 2
2012/12/16 14:25:03 5DF198 p cccam(r) sky-02: sending ecm for sid 0078(179) to card 00000078, hop 1, ecmtask 2
2012/12/16 14:25:03 62F720 c add reader job action 5 queue length 0 sky-02
2012/12/16 14:25:03 62F720 c request_cw stage=4 to reader sky-03 ecm hash=6FDB1BCB813CB22FF4A65B2F02653A05
2012/12/16 14:25:03 5E1B60 p poll() timeout
2012/12/16 14:25:03 5E1B60 p ---- ecm_task 0, idx 2, sflag=1, level=0
2012/12/16 14:25:03 5E1B60 p casc ecm:
2012/12/16 14:25:03 5E1B60 81 70 B0 00 00 01 1D 6B 10 6B 19 0D 4B AA 55 81
2012/12/16 14:25:03 5E1B60 20 01 00 00 80 40 9C A7 B3 92 BA 1F 7F 35 BD 03
2012/12/16 14:25:03 5E1B60 02 02 02 B1 8E 7E 0A 91 D6 C9 BB D1 AF AD 75 00
2012/12/16 14:25:03 5E1B60 00 90 80 C1 01 53 84 2C 21 90 6B 56 BE FA 3C 0B
2012/12/16 14:25:03 5E1B60 14 40 C2 23 78 27 32 5B B8 48 80 48 AA B0 F1 EF
2012/12/16 14:25:03 5E1B60 AB B2 20 4C C8 40 AF 9D B5 0C D1 9F 8A ED 7F 98
2012/12/16 14:25:03 5E1B60 BD 07 82 BB E1 32 53 93 BB 8A 17 7F FA 5D 86 AC
2012/12/16 14:25:03 5E1B60 3C EC 39 2B 7F 4F 48 51 81 E6 4C 38 B4 DF E9 A2
2012/12/16 14:25:03 5E1B60 AC BB AB F5 3F D4 07 8C 5C 14 F4 C2 DD 2B DD E5
2012/12/16 14:25:03 5E1B60 F1 61 5A E9 97 40 64 28 19 85 94 AA 14 4D 64 FB
2012/12/16 14:25:03 5E1B60 44 EF A5 6B 28 69 89 74 80 7F DB 84 A3 C5 7A D1
2012/12/16 14:25:03 5E1B60 E6 32 7A
2012/12/16 14:25:03 5E1B60 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5E1B60 p cccam: ecm-task 2
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: sending ecm for sid 0078(179) to card 00000078, hop 1, ecmtask 2
2012/12/16 14:25:03 62F720 c add reader job action 5 queue length 0 sky-03
2012/12/16 14:25:03 62F720 c request_cw stage=4 to reader sky-04 ecm hash=6FDB1BCB813CB22FF4A65B2F02653A05
2012/12/16 14:25:03 5E4C88 p poll() timeout
2012/12/16 14:25:03 5E4C88 p ---- ecm_task 0, idx 2, sflag=1, level=0
2012/12/16 14:25:03 5E4C88 p casc ecm:
2012/12/16 14:25:03 5E4C88 81 70 B0 00 00 01 1D 6B 10 6B 19 0D 4B AA 55 81
2012/12/16 14:25:03 5E4C88 20 01 00 00 80 40 9C A7 B3 92 BA 1F 7F 35 BD 03
2012/12/16 14:25:03 5E4C88 02 02 02 B1 8E 7E 0A 91 D6 C9 BB D1 AF AD 75 00
2012/12/16 14:25:03 5E4C88 00 90 80 C1 01 53 84 2C 21 90 6B 56 BE FA 3C 0B
2012/12/16 14:25:03 5E4C88 14 40 C2 23 78 27 32 5B B8 48 80 48 AA B0 F1 EF
2012/12/16 14:25:03 5E4C88 AB B2 20 4C C8 40 AF 9D B5 0C D1 9F 8A ED 7F 98
2012/12/16 14:25:03 5E4C88 BD 07 82 BB E1 32 53 93 BB 8A 17 7F FA 5D 86 AC
2012/12/16 14:25:03 5E4C88 3C EC 39 2B 7F 4F 48 51 81 E6 4C 38 B4 DF E9 A2
2012/12/16 14:25:03 5E4C88 AC BB AB F5 3F D4 07 8C 5C 14 F4 C2 DD 2B DD E5
2012/12/16 14:25:03 5E4C88 F1 61 5A E9 97 40 64 28 19 85 94 AA 14 4D 64 FB
2012/12/16 14:25:03 5E4C88 44 EF A5 6B 28 69 89 74 80 7F DB 84 A3 C5 7A D1
2012/12/16 14:25:03 5E4C88 E6 32 7A
2012/12/16 14:25:03 5E4C88 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5E4C88 p cccam: ecm-task 2
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: sending ecm for sid 0078(179) to card 00000078, hop 1, ecmtask 2
2012/12/16 14:25:03 62F720 c add reader job action 5 queue length 0 sky-04
2012/12/16 14:25:03 62F720 c Client xxxxxxx ECM dump 09C4&000000/0000/0078/B3:6FDB1BCB813CB22FF4A65B2F02653A05
2012/12/16 14:25:03 62F720 81 70 B0 00 00 01 1D 6B 10 6B 19 0D 4B AA 55 81
2012/12/16 14:25:03 62F720 20 01 00 00 80 40 9C A7 B3 92 BA 1F 7F 35 BD 03
2012/12/16 14:25:03 62F720 02 02 02 B1 8E 7E 0A 91 D6 C9 BB D1 AF AD 75 00
2012/12/16 14:25:03 62F720 00 90 80 C1 01 53 84 2C 21 90 6B 56 BE FA 3C 0B
2012/12/16 14:25:03 62F720 14 40 C2 23 78 27 32 5B B8 48 80 48 AA B0 F1 EF
2012/12/16 14:25:03 62F720 AB B2 20 4C C8 40 AF 9D B5 0C D1 9F 8A ED 7F 98
2012/12/16 14:25:03 62F720 BD 07 82 BB E1 32 53 93 BB 8A 17 7F FA 5D 86 AC
2012/12/16 14:25:03 62F720 3C EC 39 2B 7F 4F 48 51 81 E6 4C 38 B4 DF E9 A2
2012/12/16 14:25:03 62F720 AC BB AB F5 3F D4 07 8C 5C 14 F4 C2 DD 2B DD E5
2012/12/16 14:25:03 62F720 F1 61 5A E9 97 40 64 28 19 85 94 AA 14 4D 64 FB
2012/12/16 14:25:03 62F720 44 EF A5 6B 28 69 89 74 80 7F DB 84 A3 C5 7A D1
2012/12/16 14:25:03 62F720 E6 32 7A
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: parse_msg=254
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: card removed: id 78 remoteid 78 caid 9C4 hop 1 reshare 0 originid 78 cardtype 10
2012/12/16 14:25:03 5E4C88 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5E4C88 p cccam: ecm-task 2
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: no suitable card on server
2012/12/16 14:25:03 5E4C88 p add client job action 24 queue length 1 xxxxxxx
2012/12/16 14:25:03 5E4C88 p cccam(r) sky-04: no ecm pending!
2012/12/16 14:25:03 62F720 c poll() timeout
2012/12/16 14:25:03 62F720 c sky-04 [cccam] TRACE: ecm answer for ecm hash 6FDB1BCB813CB22FF4A65B2F02653A05 rc=4
2012/12/16 14:25:03 5DC7D0 p cccam(r) sky-01: parse_msg=254
2012/12/16 14:25:03 5DC7D0 p cccam(r) sky-01: card removed: id 78 remoteid 78 caid 9C4 hop 1 reshare 0 originid 78 cardtype 10
2012/12/16 14:25:03 5DC7D0 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5DC7D0 p cccam: ecm-task 2
2012/12/16 14:25:03 5DC7D0 p cccam(r) sky-01: no suitable card on server
2012/12/16 14:25:03 5DC7D0 p add client job action 24 queue length 1 xxxxxxx
2012/12/16 14:25:03 5DC7D0 p cccam(r) sky-01: no ecm pending!
2012/12/16 14:25:03 62F720 c poll() timeout
2012/12/16 14:25:03 62F720 c sky-01 [cccam] TRACE: ecm answer for ecm hash 6FDB1BCB813CB22FF4A65B2F02653A05 rc=4
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: parse_msg=254
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: card removed: id 78 remoteid 78 caid 9C4 hop 1 reshare 0 originid 78 cardtype 10
2012/12/16 14:25:03 5E1B60 p cccam: ecm trylock: got lock
2012/12/16 14:25:03 5E1B60 p cccam: ecm-task 2
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: no suitable card on server
2012/12/16 14:25:03 5E1B60 p add client job action 24 queue length 1 xxxxxxx
2012/12/16 14:25:03 5E1B60 p cccam(r) sky-03: no ecm pending!
2012/12/16 14:25:03 62F720 c poll() timeout
2012/12/16 14:25:03 62F720 c sky-03 [cccam] TRACE: ecm answer for ecm hash 6FDB1BCB813CB22FF4A65B2F02653A05 rc=4
2012/12/16 14:25:04 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:05 5DF198 p cccam(r) sky-02: parse_msg=254
2012/12/16 14:25:05 5DF198 p cccam(r) sky-02: card removed: id 78 remoteid 78 caid 9C4 hop 1 reshare 0 originid 78 cardtype 10
2012/12/16 14:25:05 5DF198 p cccam: ecm trylock: got lock
2012/12/16 14:25:05 5DF198 p cccam: ecm-task 2
2012/12/16 14:25:05 5DF198 p cccam(r) sky-02: no suitable card on server
2012/12/16 14:25:05 5DF198 p add client job action 24 queue length 1 xxxxxxx
2012/12/16 14:25:05 5DF198 p cccam(r) sky-02: no ecm pending!
2012/12/16 14:25:05 62F720 c poll() timeout
2012/12/16 14:25:05 62F720 c sky-02 [cccam] TRACE: ecm answer for ecm hash 6FDB1BCB813CB22FF4A65B2F02653A05 rc=4
2012/12/16 14:25:05 62F720 c Client xxxxxxx csphash 714909C7 cw 00000000000000000000000000000000 rc 4 09C4&000000/0000/0078/B3:6FDB1BCB813CB22FF4A65B2F02653A05
2012/12/16 14:25:05 6328F0 c cccam(s) xxxxxxx: parse_msg=1
2012/12/16 14:25:05 6328F0 c cccam(s) xxxxxxx: ECM request from client: caid 09c4 srvid 0078(179) prid 000000
2012/12/16 14:25:05 6328F0 c caid 09C4 not found in caidlist reader
smartmouse
2012/12/16 14:25:05 6328F0 c reader unavailable sky-01
2012/12/16 14:25:05 6328F0 c reader unavailable sky-02
2012/12/16 14:25:05 6328F0 c reader unavailable sky-03
2012/12/16 14:25:05 6328F0 c reader unavailable sky-04
2012/12/16 14:25:05 62F720 c xxxxxxx (09C4&000000/0000/0078/B3:6FDB1BCB813CB22FF4A65B2F02653A05): rejected (1597 ms) by sky-02 (F/4/0/0) - Sky Select HD
2012/12/16 14:25:05 62F720 c cw:
2012/12/16 14:25:05 62F720 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2012/12/16 14:25:05 6328F0 c Client xxxxxxx csphash 714909C7 cw 00000000000000000000000000000000 rc 4 09C4&000000/0000/0078/B3:6FDB1BCB813CB22FF4A65B2F02653A05
2012/12/16 14:25:05 6328F0 c xxxxxxx (09C4&000000/0000/0078/B3:6FDB1BCB813CB22FF4A65B2F02653A05): rejected group (53 ms) (0/0/0/0) - Sky Select HD (no matching reader)
2012/12/16 14:25:05 6328F0 c cw:
2012/12/16 14:25:05 6328F0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2012/12/16 14:25:05 5B5160 h WebIf: Origin checked. Result: access from 192.168.100.186 => allowed
2012/12/16 14:25:05 5B5160 h WebIf: Received stale header from 192.168.100.186.
2012/12/16 14:25:05 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:05 5B5160 h WebIf: Origin checked. Result: access from 192.168.100.186 => allowed
2012/12/16 14:25:05 0 --- Skipped 3 duplicated log lines ---
2012/12/16 14:25:05 62F720 c cccam(s) xxxxxxx: parse_msg=1
2012/12/16 14:25:05 62F720 c cccam(s) xxxxxxx: ECM request from client: caid 09c4 srvid 0078(179) prid 000000
2012/12/16 14:25:05 62F720 c caid 09C4 not found in caidlist reader
smartmouse
2012/12/16 14:25:05 62F720 c reader unavailable sky-01
2012/12/16 14:25:05 62F720 c reader unavailable sky-02
2012/12/16 14:25:05 62F720 c reader unavailable sky-03
2012/12/16 14:25:05 62F720 c reader unavailable sky-04
2012/12/16 14:25:05 62F720 c Client xxxxxxx csphash 2F3F7562 cw 00000000000000000000000000000000 rc 4 09C4&000000/0000/0078/B3:0C96FE330B4974094F11EC0878EB8CC1
2012/12/16 14:25:05 6328F0 c cccam(s) xxxxxxx: parse_msg=1
2012/12/16 14:25:05 6328F0 c cccam(s) xxxxxxx: ECM request from client: caid 09c4 srvid 0078(179) prid 000000
2012/12/16 14:25:05 6328F0 c caid 09C4 not found in caidlist reader
smartmouse
2012/12/16 14:25:05 6328F0 c reader unavailable sky-01
2012/12/16 14:25:05 6328F0 c reader unavailable sky-02
2012/12/16 14:25:05 6328F0 c reader unavailable sky-03
2012/12/16 14:25:05 6328F0 c reader unavailable sky-04
2012/12/16 14:25:05 62F720 c xxxxxxx (09C4&000000/0000/0078/B3:0C96FE330B4974094F11EC0878EB8CC1): rejected group (54 ms) (0/0/0/0) - Sky Select HD (no matching reader)
2012/12/16 14:25:05 62F720 c cw:
2012/12/16 14:25:05 62F720 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2012/12/16 14:25:06 6328F0 c Client xxxxxxx csphash 2F3F7562 cw 00000000000000000000000000000000 rc 4 09C4&000000/0000/0078/B3:0C96FE330B4974094F11EC0878EB8CC1
2012/12/16 14:25:06 6328F0 c xxxxxxx (09C4&000000/0000/0078/B3:0C96FE330B4974094F11EC0878EB8CC1): rejected group (53 ms) (0/0/0/0) - Sky Select HD (no matching reader)
2012/12/16 14:25:06 6328F0 c cw:
2012/12/16 14:25:06 6328F0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2012/12/16 14:25:06 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:10 0 --- Skipped 3 duplicated log lines ---
2012/12/16 14:25:10 0 share-update [2] 261 253
2012/12/16 14:25:10 0 asking reader sky-01 for cards...
2012/12/16 14:25:10 0 got 61 cards from sky-01
2012/12/16 14:25:10 0 asking reader sky-02 for cards...
2012/12/16 14:25:10 0 got 62 cards from sky-02
2012/12/16 14:25:10 0 asking reader sky-03 for cards...
2012/12/16 14:25:10 0 got 61 cards from sky-03
2012/12/16 14:25:10 0 asking reader sky-04 for cards...
2012/12/16 14:25:10 0 got 61 cards from sky-04
2012/12/16 14:25:10 0 s-card removed: id 83 remoteid 78 caid 9C4 hop 1 reshare 0 originid 78 cardtype 10
2012/12/16 14:25:10 0 s-card removed: id 97 remoteid 6C caid 1702 hop 1 reshare 0 originid 6C cardtype 10
2012/12/16 14:25:10 0 reported/updated +0/-2/dup 186 of 61 cards to sharelist
2012/12/16 14:25:10 0 share-updater mode=interval t=20s
2012/12/16 14:25:10 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:11 5B5160 h WebIf: read error ret=0 (errno=9 Bad file descriptor)
2012/12/16 14:25:11 5B5160 h WebIf: No data received from client 192.168.100.186. Closing connection.
2012/12/16 14:25:11 0 --- Skipped 2 duplicated log lines ---
2012/12/16 14:25:11 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:17 0 --- Skipped 5 duplicated log lines ---
2012/12/16 14:25:17 5DF198 p add reader job action 1 queue length 1 sky-02
2012/12/16 14:25:17 5DF198 p cccam: keepalive
2012/12/16 14:25:17 5DF198 p cccam(r) sky-02: parse_msg=6
2012/12/16 14:25:17 5DF198 p cccam: keepalive ack
2012/12/16 14:25:17 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:18 0 --- Skipped 1 duplicated log lines ---
2012/12/16 14:25:18 5E4C88 p add reader job action 1 queue length 1 sky-04
2012/12/16 14:25:18 5E4C88 p cccam: keepalive
2012/12/16 14:25:18 5DC7D0 p add reader job action 1 queue length 1 sky-01
2012/12/16 14:25:18 5DC7D0 p cccam: keepalive
2012/12/16 14:25:18 5E4C88 p cccam(r) sky-04: parse_msg=6
2012/12/16 14:25:18 5E4C88 p cccam: keepalive ack
2012/12/16 14:25:18 5DC7D0 p cccam(r) sky-01: parse_msg=6
2012/12/16 14:25:18 5DC7D0 p cccam: keepalive ack
2012/12/16 14:25:18 5E1B60 p add reader job action 1 queue length 1 sky-03
2012/12/16 14:25:18 5E1B60 p cccam: keepalive
2012/12/16 14:25:18 5E1B60 p cccam(r) sky-03: parse_msg=6
2012/12/16 14:25:18 5E1B60 p cccam: keepalive ack
2012/12/16 14:25:19 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:25 0 --- Skipped 6 duplicated log lines ---
2012/12/16 14:25:25 5B5160 h WebIf: Origin checked. Result: access from 192.168.100.186 => allowed
2012/12/16 14:25:25 5B5160 h WebIf: Received stale header from 192.168.100.186.
2012/12/16 14:25:25 5B5160 h WebIf: Origin checked. Result: access from 192.168.100.186 => allowed
2012/12/16 14:25:26 0 --- Skipped 2 duplicated log lines ---
2012/12/16 14:25:26 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:30 0 --- Skipped 3 duplicated log lines ---
2012/12/16 14:25:30 0 share-updater mode=interval t=20s
2012/12/16 14:25:30 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2012/12/16 14:25:31 0 --- Skipped 1 duplicated log lines ---
2012/12/16 14:25:31 5B5160 h WebIf: read error ret=0 (errno=9 Bad file descriptor)
2012/12/16 14:25:31 5B5160 h WebIf: No data received from client 192.168.100.186. Closing connection.
2012/12/16 14:25:32 5DAFC0 r
smartmouse [nagra] TRACE: cardreader_do_checkhealth: reader->card_status = 2, ret =