Cacheex3

[zalticko]

AW: Cacheex3

Ich habe die 6996 eingepflegt, und das Problem besteht immernoch.

 

[joopastron]

Teste mal bitte folgendes, lokalen Reader deaktivieren beim User und nur die Gruppe vom cacheex zuweisen.


Sent from my iPhone using Tapatalk

 

[zalticko]

AW: Cacheex3

Habe ich gemacht, jedoch bleibt jetzt das Bild dunkel, also wird nicht entschlüsselt.

 

[joopastron]

Dann hat dein cacheex Partner etwas falsch eingestellt. Du bekommst Deinen eigenen Cache zurück.

Cacheex_maxhop stell mal auf 0
Im cacheex User

Sent from my iPhone using Tapatalk

 

[Hotzenwalder]

AW: Cacheex3

Dann hat dein cacheex Partner etwas falsch eingestellt. Du bekommst Deinen eigenen Cache zurück.

Cacheex_maxhop stell mal auf 0
Im cacheex User

Sent from my iPhone using Tapatalk

Sorry to break into this thread in english, but my native language is Dutch. I've been breaking my head over this issue for two days already.
I am using OScam unstable 1.20 #7507 and am exchanging cache with another server with exaxt the same setup.
Although the log says CACHE3 I can see in the log that my local is also checked.

2012/09/26 17:05:56 B70634D8 c Client usrmac hash 06587BE54C68A786227A2D85E9738B1D csp FB5DE01A cw F3156C7473D3B3F97377CCB63D46FF82 rc 3 0604&000000/01FE/0041/44:4A6E
2012/09/26 17:05:56 B70634D8 c usrmac (0604&000000/01FE/0041/44:4A6E): cache3 (155 ms) (P/2/2/2) - Nick Jr. [oost/west]
2012/09/26 17:06:17 B70634D8 c cccam(s) usrmac: ECM request from client: caid 0604 srvid 0041(68) prid 000000
2012/09/26 17:06:17 B70634D8 c Client usrmac ECM dump 0604&000000/01FE/0041/44:5BB8
2012/09/26 17:06:17 B7052100 c replaced pushed ECM 0604&000000/0000/0041/00:D0E2 from usera
2012/09/26 17:06:17 B70634D8 c Client usrmac hash ED6E55A329FE6A80AD8B3A3BE23A12FD csp D0E2C5F1 cw F3156C7473D3B3F90845C916C27BFE3B rc 3 0604&000000/01FE/0041/44:5BB8
2012/09/26 17:06:17 B70634D8 c usrmac (0604&000000/01FE/0041/44:5BB8): cache3 (152 ms) (P/2/2/2) - Nick Jr. [oost/west]
2012/09/26 17:06:38 B70634D8 c cccam(s) usrmac: ECM request from client: caid 0604 srvid 0041(68) prid 000000

I will post my exact configs later today, but this is not an issue that sounds strange to me :emoticon-0138-think
I hope together we can find out what is happening.

 

[joopastron]

Hello,

I Think that is the Same problem like the other User.
Your CS Partner have wrong Settings in your Account on his Server. They send the Cache from your Reader back to you. With the entry cacheex_maxhop 0 i think the Problem Must end.


Sent from my iPad using Tapatalk HD

 

[zalticko]

AW: Cacheex3

Ich kann das eben nicht ausprobieren, weil Clienten online sind! Ich berichte sofort, wenns geht! Danke einstweilen!

 

[Hotzenwalder]

AW: Cacheex3

Hello,

I Think that is the Same problem like the other User.
Your CS Partner have wrong Settings in your Account on his Server. They send the Cache from your Reader back to you. With the entry cacheex_maxhop 0 i think the Problem Must end.


Sent from my iPad using Tapatalk HD

Well... since I am in control of both servers I have control over the settings of both servers so I am curious if this helps. I already tried cacheex_maxhop 0 but we will see. Here are my settings for the readers and users

SERVER A

Spoiler

[reader]
label                         = userb
protocol                      = camd35
device                        = ******,******
user                          = usera
password                      = usera
reconnecttimeout              = 480
cacheex                       = 3
cacheex_maxhop                = 2
group                         = 1
dropbadcws                    = 1

[account]
user                          = userb
pwd                           = userb
uniq                          = 3
group                         = 50
cacheex                       = 3
cacheex_maxhop                = 0
cccmaxhops                    = -1
cccreshare                    = 3

SERVER B

Spoiler

[reader]
label                         = usera
protocol                      = camd35
device                        = *******,******
user                          = userb
password                      = userb
reconnecttimeout              = 480
cacheex                       = 3
cacheex_maxhop                = 2
group                         = 1
dropbadcws                    = 1
audisabled                    = 1

[account]
user                          = usera
pwd                           = usera
group                         = 50
cacheex                       = 3
cacheex_maxhop                = 0
cccmaxhops                    = -1
cccreshare                    = 3

The locals are identical on both servers and are both in group 1
Group 50 is reserved for the cacheex users

A client account on SERVER B

Spoiler

[account]
user                          = usr001
pwd                           = pw001
group                         = 1,50
cccreshare                    = 0

The result...

Spoiler

2012/09/26 19:37:04 285AEF60 c l_5555_frontend01  (0604&000000/000B/00CD/44:F068): found (156 ms) by localma (P/1/1/6)  - RTL 5 HD
2012/09/26 19:37:25 285AEF60 c l_5555_frontend01 (0604&000000/000B/00CD/44:48A5): cache3 (137 ms) (P/1/1/6) - RTL 5 HD
2012/09/26 19:37:46 285AEF60 c l_5555_frontend01 (0604&000000/000B/00CD/44:3AB4): cache3 (129 ms) (P/1/1/6) - RTL 5 HD
2012/09/26 19:38:07 285AEF60 c l_5555_frontend01 (0604&000000/000B/00CD/44:3CFA): cache3 (127 ms) (P/1/1/6) - RTL 5 HD
2012/09/26 19:38:28 285AEF60 c l_5555_frontend01 (0604&000000/000B/00CD/44:D120): cache3 (145 ms) (P/1/1/6) - RTL 5 HD
2012/09/26 19:38:49 285AEF60 c l_5555_frontend01 (0604&000000/000B/00CD/44:57F7): cache3 (141 ms) (P/1/1/6) - RTL 5 HD

But...

As you can see from the screendump. The ecm is from the cache (cache3), but the local on the same server is also triggered (smartreader).
Another channel... i tried to pick the part in the debug log where the request starts and in my opinion ends

Spoiler

2012/09/26 20:07:25 B7095DB8 c trying user 'usr000' filter 0604:000000
2012/09/26 20:07:25 B7095DB8 c 0604:000000 allowed by user 'usr000' filter 0604:000000
2012/09/26 20:07:25 B7095DB8 c trying server filter 0604:000000
2012/09/26 20:07:25 B7095DB8 c 0604:000000 allowed by server filter 0604:000000
2012/09/26 20:07:25 B7095DB8 c trying reader 'localmd' filter 0604:000000
2012/09/26 20:07:25 B7095DB8 c 0604:000000 allowed by reader 'localmd' filter 0604:000000
2012/09/26 20:07:25 B7095DB8 c trying reader 'localmd' CHID filter 0604:000B
2012/09/26 20:07:25 B7095DB8 c trying reader 'localmd' CHID filter 0604:01F4
2012/09/26 20:07:25 B7095DB8 c trying reader 'localmd' CHID filter 0604:01FE
2012/09/26 20:07:25 B7095DB8 c 0604:01FE allowed by reader 'localmd' CHID filter 0604:01FE
2012/09/26 20:07:25 B7095DB8 c trying reader 'localbd' filter 0604:000000
2012/09/26 20:07:25 B7095DB8 c 0604:000000 allowed by reader 'localbd' filter 0604:000000
2012/09/26 20:07:25 B7095DB8 c trying reader 'localbd' CHID filter 0604:000B
2012/09/26 20:07:25 B7095DB8 c trying reader 'localbd' CHID filter 0604:01F4
2012/09/26 20:07:25 B7095DB8 c trying reader 'localbd' CHID filter 0604:01FE
2012/09/26 20:07:25 B7095DB8 c 0604:01FE allowed by reader 'localbd' CHID filter 0604:01FE
2012/09/26 20:07:25 B7095DB8 c service 2716 not matching reader ******
2012/09/26 20:07:25 B7095DB8 c service 2716 not matching reader ******
2012/09/26 20:07:25 B7095DB8 c service 2716 not matching reader ******
2012/09/26 20:07:25 B7095DB8 c request_cw stage=3 to reader localmd ecm=C480
2012/09/26 20:07:25 B7095DB8 c start reader thread action 5
2012/09/26 20:07:25 B7095DB8 c request_cw stage=3 to reader localbd ecm=C480
2012/09/26 20:07:25 B7095DB8 c start reader thread action 5
2012/09/26 20:07:25 B7095DB8 c request_cw stage=3 to reader ******** ecm=C480
2012/09/26 20:07:25 B7095DB8 c add reader job action 5 queue length 1 ******
2012/09/26 20:07:25 B7095DB8 c Client usr000 ECM dump 0604&000000/01FE/2716/44:C480
2012/09/26 20:07:25 B7095DB8    80 70 41 4D 00 00 01 FE 00 08 40 38 FB FF BF 12 
2012/09/26 20:07:25 B7095DB8    18 AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 
2012/09/26 20:07:25 B7095DB8    F5 82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 
2012/09/26 20:07:25 B7095DB8    03 27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 
2012/09/26 20:07:25 B7095DB8    FD C2 96 31 
2012/09/26 20:07:25  9AAE1B0 p poll wakeup
2012/09/26 20:07:25  9AAE1B0 p ---- ecm_task 0, idx 338, sflag=1, level=0
2012/09/26 20:07:25  9AAE1B0 p casc ecm:
2012/09/26 20:07:25  9AAE1B0    80 70 41 4D 00 00 01 FE 00 08 40 38 FB FF BF 12 
2012/09/26 20:07:25  9AAE1B0    18 AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 
2012/09/26 20:07:25  9AAE1B0    F5 82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 
2012/09/26 20:07:25  9AAE1B0    03 27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 
2012/09/26 20:07:25  9AAE1B0    FD C2 96 31 
2012/09/26 20:07:25  9AAE1B0 p cccam: ecm trylock: got lock
2012/09/26 20:07:25  9AAE1B0 p cccam: ecm-task 338
2012/09/26 20:07:25  9AAE1B0 p cccam(r) *******: sending ecm for sid 2716(68) to card 0000010e, hop 1, ecmtask 338
2012/09/26 20:07:25  9A1D830 r data from add_job action=5 client r localmd
2012/09/26 20:07:25  9A1D830 r ecm:
2012/09/26 20:07:25  9A1D830    80 70 41 4D 00 00 01 FE 00 08 40 38 FB FF BF 12 
2012/09/26 20:07:25  9A1D830    18 AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 
2012/09/26 20:07:25  9A1D830    F5 82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 
2012/09/26 20:07:25  9A1D830    03 27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 
2012/09/26 20:07:25  9A1D830    FD C2 96 31 
2012/09/26 20:07:25  9A1D830 r localmd [irdeto] write to cardreader
2012/09/26 20:07:25  9A1D830    05 00 00 02 3E 01 FE 00 08 40 38 FB FF BF 12 18 
2012/09/26 20:07:25  9A1D830    AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 F5 
2012/09/26 20:07:25  9A1D830    82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 03 
2012/09/26 20:07:25  9A1D830    27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 FD 
2012/09/26 20:07:25  9A1D830    C2 96 31 
2012/09/26 20:07:25  9A1D830 r localmd [irdeto] IFD: Transmit:
2012/09/26 20:07:25  9A1D830    01 05 00 00 02 3E 01 FE 00 08 40 38 FB FF BF 12 
2012/09/26 20:07:25  9A1D830    18 AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 
2012/09/26 20:07:25  9A1D830    F5 82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 
2012/09/26 20:07:25  9A1D830    03 27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 
2012/09/26 20:07:25  9A1D830    FD C2 96 31 A0 
2012/09/26 20:07:25  9A1F1B8 r data from add_job action=5 client r localbd
2012/09/26 20:07:25  9A1F1B8 r ecm:
2012/09/26 20:07:25  9A1F1B8    80 70 41 4D 00 00 01 FE 00 08 40 38 FB FF BF 12 
2012/09/26 20:07:25  9A1F1B8    18 AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 
2012/09/26 20:07:25  9A1F1B8    F5 82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 
2012/09/26 20:07:25  9A1F1B8    03 27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 
2012/09/26 20:07:25  9A1F1B8    FD C2 96 31 
2012/09/26 20:07:25  9A1F1B8 r localbd [irdeto] write to cardreader
2012/09/26 20:07:25  9A1F1B8    05 00 00 02 3E 01 FE 00 08 40 38 FB FF BF 12 18 
2012/09/26 20:07:25  9A1F1B8    AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 F5 
2012/09/26 20:07:25  9A1F1B8    82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 03 
2012/09/26 20:07:25  9A1F1B8    27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 FD 
2012/09/26 20:07:25  9A1F1B8    C2 96 31 
2012/09/26 20:07:25  9A1F1B8 r localbd [irdeto] IFD: Transmit:
2012/09/26 20:07:25  9A1F1B8    01 05 00 00 02 3E 01 FE 00 08 40 38 FB FF BF 12 
2012/09/26 20:07:25  9A1F1B8    18 AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 
2012/09/26 20:07:25  9A1F1B8    F5 82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 
2012/09/26 20:07:25  9A1F1B8    03 27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 
2012/09/26 20:07:25  9A1F1B8    FD C2 96 31 A0 
2012/09/26 20:07:25  9A1D830 r localmd [irdeto] IO: SR: Transmit:
2012/09/26 20:07:25  9A1D830    01 05 00 00 02 3E 01 FE 00 08 40 38 FB FF BF 12 
2012/09/26 20:07:25  9A1D830    18 AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 
2012/09/26 20:07:25  9A1D830    F5 82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 
2012/09/26 20:07:25  9A1D830    03 27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 
2012/09/26 20:07:25  9A1D830    FD C2 96 31 A0 
2012/09/26 20:07:25        0   --- Skipped 1 duplicated log lines ---
2012/09/26 20:07:25  9A1D830 r localmd [irdeto] IFD: Transmit succesful
2012/09/26 20:07:25  9A1F1B8 r localbd [irdeto] IO: SR: Transmit:
2012/09/26 20:07:25  9A1F1B8    01 05 00 00 02 3E 01 FE 00 08 40 38 FB FF BF 12 
2012/09/26 20:07:25  9A1F1B8    18 AB 05 3B 49 64 1C 19 93 C0 E3 8E E6 52 AF 36 
2012/09/26 20:07:25  9A1F1B8    F5 82 84 DB 41 62 D3 5E 86 7B B2 9C AE 79 07 01 
2012/09/26 20:07:25  9A1F1B8    03 27 2E E6 F1 C7 1A 76 F7 C1 46 26 7C 58 08 E7 
2012/09/26 20:07:25  9A1F1B8    FD C2 96 31 A0 
2012/09/26 20:07:25        0   --- Skipped 1 duplicated log lines ---
2012/09/26 20:07:25  9A1F1B8 r localbd [irdeto] IFD: Transmit succesful
2012/09/26 20:07:26  9AAE1B0 p data on socket
2012/09/26 20:07:26  9AAE1B0 p cccam(r) ******: parse_msg=255
2012/09/26 20:07:26  9AAE1B0 p cccam(r) ******: Moving card 0000010E to the end...
2012/09/26 20:07:26  9AAE1B0 p cccam(r) ******: added sid block 2716(CHID 01FE, length 68) for card 0000010e
2012/09/26 20:07:26  9AAE1B0 p cccam: ecm trylock: got lock
2012/09/26 20:07:26  9AAE1B0 p cccam: ecm-task 338
2012/09/26 20:07:26  9AAE1B0 p cccam(r) ******: no suitable card on server
2012/09/26 20:07:26  9AAE1B0 p add client job action 24 queue length 1 usr000
2012/09/26 20:07:26  9AAE1B0 p cccam(r) ******: no ecm pending!
2012/09/26 20:07:26 B7095DB8 c poll wakeup
2012/09/26 20:07:26 B7095DB8 c ****** [cccam] TRACE: ecm answer for ecm C480 rc=4
2012/09/26 20:07:26        0 s got 84 bytes on port ****** from ip ******:31452 client usera
2012/09/26 20:07:26        0 s start client thread action 22
2012/09/26 20:07:26 B70A2020 c data from add_job action=22 client c usera
2012/09/26 20:07:26 B70A2020 c received 80 bytes from client
2012/09/26 20:07:26 B70A2020    3F 2D 00 00 80 1B 23 D6 27 16 06 04 00 00 00 00 
2012/09/26 20:07:26 B70A2020    00 00 00 00 B4 FB F1 2A 55 75 58 C7 B5 C1 4C 56 
2012/09/26 20:07:26 B70A2020    A8 A2 16 E5 94 74 92 B9 95 4D 79 5B 0A 7F 2F B8 
2012/09/26 20:07:26 B70A2020    5A DD 45 7C 32 02 3F 73 01 55 B1 42 3B 10 27 13 
2012/09/26 20:07:26 B70A2020    EE FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF 
2012/09/26 20:07:26 B70A2020 c cacheex: received 1 nodes usera
2012/09/26 20:07:26 B70A2020 c cacheex: received node 17155098356251537749X usera
2012/09/26 20:07:26 B70A2020 c cacheex: received cacheex from remote node id 17155098356251537749X
2012/09/26 20:07:26 B70A2020 c add client job action 24 queue length 1 usr000
2012/09/26 20:07:26 B7095DB8 c poll wakeup
2012/09/26 20:07:26        0   --- Skipped 1 duplicated log lines ---
2012/09/26 20:07:26  9ABF738 p cacheex: check node 17155098356251537749X == 17155098356251537749X ?
2012/09/26 20:07:26 B7095DB8 c add client job action 28 queue length 1 usera
2012/09/26 20:07:26 B7095DB8 c Client usr000 hash  B4FBF12A557558C7B5C14C56A8A216E5 csp 947492B9 cw  954D795B0A7F2FB85ADD457C32023F73 rc 3 0604&000000/01FE/2716/44:C480
2012/09/26 20:07:26 B7095DB8 c ncd_send_dcw: er->msgid=44, cl_msgid=44, 80
2012/09/26 20:07:26 B7095DB8 c send 31 bytes to client
2012/09/26 20:07:26 B7095DB8    00 1D 00 2C 00 00 00 00 00 00 00 00 80 10 10 95 
2012/09/26 20:07:26 B7095DB8    4D 79 5B 0A 7F 2F B8 5A DD 45 7C 32 02 3F 73 
2012/09/26 20:07:26  9ABF738 p cacheex: node 17155098356251537749X found in list => skip push!
2012/09/26 20:07:26  9ABF738 p cacheex: check node 17155098356251537749X == 17155098356251537749X ?
2012/09/26 20:07:26  9ABF738 p cacheex: node 17155098356251537749X found in list => skip push!
2012/09/26 20:07:26 B70A2020 c add client job action 28 queue length 1 usera
[B]2012/09/26 20:07:26 B70A2020 c replaced pushed ECM 0604&000000/0000/2716/00:9474 from usera[/B]
[B]2012/09/26 20:07:26 B7095DB8 c usr000 (0604&000000/01FE/2716/44:C480): cache3 (113 ms) (P/3/3/3) - CNBC Europe[/B]
2012/09/26 20:07:26 B7095DB8 c cw:
2012/09/26 20:07:26 B7095DB8    95 4D 79 5B 0A 7F 2F B8 5A DD 45 7C 32 02 3F 73 
2012/09/26 20:07:26  9A1F1B8 r localbd [irdeto] IO: SR: Receive:
2012/09/26 20:07:26  9A1F1B8    01 05 9D 00 38 01 02 16 
2012/09/26 20:07:26  9A1F1B8 r localbd [irdeto] IFD: Received:
2012/09/26 20:07:26  9A1F1B8    01 05 9D 00 38 01 02 16 
2012/09/26 20:07:26  9A1D830 r localmd [irdeto] IO: SR: Receive:
2012/09/26 20:07:26  9A1D830    01 05 9D 00 38 01 02 16 
2012/09/26 20:07:26  9A1D830 r localmd [irdeto] IFD: Received:
2012/09/26 20:07:26  9A1D830    01 05 9D 00 38 01 02 16 
2012/09/26 20:07:26  9AAAEF8 p data on socket

In this case the channel was watched on server A and pushed to server B where my testclient resides. The box received the ecm from cache3, but in the status screen on server B I could see that my local was also triggered for the same channel and from what I can see in this debug log both my locals were tried. I would assume that once a channel is in the cache the local would not be triggered at all.

If I remove the testclient from the group with locals the screen stays blank. (client gets 'rejected group' - no matching reader')

My head is spinning. I can't get a grasp of it and it seems I am not alone in this. :emoticon-0124-worri

 

[zalticko]

AW: Cacheex3

gleiches Problem weiterhin. Ich erhalte zwar cache von meinem Sharepartner, doch er wird nicht zum entschlüsseln genommen. Keine Ahnung was mit dem cache passiert.
Hatte eben meinem lokalen Reader deaktiviert und mir nur die Gruppe 10 zugeteilt.

 

[joopastron]

Hi,

Dann sendet Dir da jemand Fake CW's.
Es wird Dir quasi vorgegaukelt das was da ist.


Sent from my iPhone using Tapatalk

 

[zalticko]

AW: Cacheex3

und was bringt das denjenigen?

 

[Hotzenwalder]

AW: Cacheex3

Since I have control over both servers I can assure you that no fake CW's are sent out from either of the servers. It's clear to see that everytime a channel hits cache3 the local reader is also triggered (is visibible on the status page) and from what I can see also in the debug log (see my previous post).

 

[joopastron]

Da bin ich mit meinem Latein auch am Ende.



Sent from my iPhone using Tapatalk

 

[Hotzenwalder]

AW: Cacheex3

After some more testing this is what I see...

A client is connected to SERVER B and requests a channel which was pushed to the server by the cacheex mode 3 reader on SERVER A. The channel is in the cache constantly. The first ecm is a cache3 hit (10ms)

Spoiler

2012/09/27 10:40:56 B7053980 c usrmac (0604&000000/01FE/4653/44:AE89): cache3 (10 ms) (0/0/1/3) - Baby TV

The second ecm is grabbed from the local on SERVER B

Spoiler

2012/09/27 10:41:27 B7053980 c usrmac (0604&000000/01FE/4653/44:FC00): found (162 ms) by localmd (P/1/1/3) - Baby TV

The rest of the ecm's is also grabbed from the local on SERVER B. No more cache3 hits.
When I look at the cacheex3 page I can see that SERVER A is still pushing the ecm to SERVER B (the push count rises), but on SERVER B the 'got count' for this channel stays the same. (In a few minutes push went from 583 to 591 on SERVER A, but got on SERVER B stayed at 24 for this channel).

If I look in the debug log I can see that from the moment my client started watching the channel (OOD3) the pushed ecm is ignored and my local reader is preferred.

Spoiler

2012/09/27 10:49:35 B7053980 c usrmac (0604&000000/001C/00D3/44:9EF2): found (162 ms) by localmd - Eredivisie Live 1 HD [oost/
2012/09/27 10:49:35 B705E3E8 c ignored duplicate pushed ECM 0604&000000/0000/00D3/00:CE88 from usera

So... I reset the reader stats, the user stats etc. Everything was 0.
User A watches a channel on SERVER A. The channel gets pushed to SERVER B.
User B watches the same channel as user A but he is watching it from SERVER B.
The first ECM is a cache3 hit (10ms). The stats for user B tell me that he is getting cache after cache hit. ECM's delivered = 2, Cache hits = 10

This is a filtered log from SERVER B

Spoiler

2012/09/27 11:32:08 B529E2A0 c usrmac (0604&000000/01FE/2716/44:94CE): cache3 (156 ms) (P/1/1/3) - CNBC Europe
2012/09/27 11:32:29 B529E2A0 c usrmac (0604&000000/01FE/2716/44791): cache3 (156 ms) (P/1/1/3) - CNBC Europe
2012/09/27 11:32:50 B529E2A0 c usrmac (0604&000000/01FE/2716/44:55B0): cache3 (156 ms) (P/1/1/3) - CNBC Europe
2012/09/27 11:33:11 B529E2A0 c usrmac (0604&000000/01FE/2716/44:31E9): cache3 (156 ms) (P/1/1/3) - CNBC Europe
2012/09/27 11:33:32 B529E2A0 c usrmac (0604&000000/01FE/2716/44:BF79): found (161 ms) by localmd (P/1/1/3) - CNBC Europe
2012/09/27 11:33:53 B529E2A0 c usrmac (0604&000000/01FE/2716/44:9329): cache3 (154 ms) (P/1/1/3) - CNBC Europe
2012/09/27 11:34:14 B529E2A0 c usrmac (0604&000000/01FE/2716/4422E): cache3 (158 ms) (P/1/1/3) - CNBC Europe
2012/09/27 11:34:35 B529E2A0 c usrmac (0604&000000/01FE/2716/44593): cache3 (157 ms) (P/1/1/3) - CNBC Europe

But....

The stats for my local show me that the cache3 hits are not for free. Every cache3 hit is also a hit on my local. So 10 cache3 hits in the log means 10 ecm's found by my local and so on. After a while the user stats show that 3 ECM's were delivered (those were visible in the log as ' found (161 ms) by localmd (P/1/1/3) '. The cache3 hits are counted as cache. After about 5 to 10 minutes... cache hits on user B = 30... ECM hits on my local are also 30.

Is it the loadbalancer? (10. Log statistics only)? Prefer local cards is set to 0 (local cards like proxied). Cache delay = 10ms, Cache time = 15 seconds (ZIGGO ecm's have an interval of 20 seconds). Maximum cache count = 7500. Cacheex wait time = 50ms.

So concluding... actually two things are happening

1. Pushed ECM's are replaced by ECM's from my own local when using cacheex mode 3
2. Every cache3 hit in the log is also a ECM found by my local so cache3 is not really cache3.

Why is every cache3 hit also a hit on my own local? What is wrong with my setup or with cache3?

 

[zalticko]

AW: Cacheex3

Niemand mehr eine Idee?