Latency bei Remote Reader steigt stetig

[Kangaroo]

Moin!

ich habe folgendes Setup 2 Gigablue Boxen, in einer eine G02 in der anderen eine G09, alles Kabel (KDG und SKY). Das Setup betreibe ich schon ewig, vorher mit Dreamboxen. Beim Umstieg auf die Gigablues muss sich aber irgendwas geändert haben, dass ich jetzt folgendes Problem hab:

Box B mit Sky G02 keine Probleme

Box A mit KDG G09 2 Reader eingerichtet: lokal und remote. Sind beide Reader aktiv, so beginnt die Latenz beim Remotereader bei ca 100ms (Normalwert) und wird von Minute zu Minute immer höher. Sie steigt dann bis 5000ms, unterbrechungsfreies Bild ist da schon lange nicht mehr möglich.

Der lokale Reader ist ok, keine Probleme.

Ich habe nun schon recht viel probiert und nichts davon hat geholfen, sodass meine Config wieder aussieht wie am Anfang. Und jetzt wird es spannend, denn etwas habe ich doch rausbekommen. Sobald ich den lokalen Reader deaktiviere, sind die Zeiten des Remote Readers in Ordnung und bleiben es auch über Tage hingweg. Aktiviere ich den lokalen Reader wieder, beginnen die Zeiten des Remote Readers zu steigen.

An Box B sieht man diese Zeiten übrigens nicht, dort sind die Zeiten von den Antworten, die an A geschickt werden, minimal. Daher schließe ich Box B mittlerweile gänzlich als Ursache aus und poste nur die Config von A:

Spoiler: oscam.conf

[global]
logfile = stdout
clienttimeout = 4000
fallbacktimeout = 2600
nice = -1
preferlocalcards = 1
lb_save = 100
lb_savepath = /usr/local/etc/.oscam/stat

[cache]

[newcamd]
port = 33911@09C7:000000
key = xx
keepalive = 1

[cccam]
port = 44911
nodeid = xx
version = 2.2.1
reshare = 2

[dvbapi]
enabled = 1
au = 1
pmt_mode = 0
user = xx
read_sdt = 2
write_sdt_prov = 1
boxtype = dreambox

[webif]
httpport = 33910
httpuser = xx
httppwd = xx
httprefresh = 10
httpallowed = 127.0.0.1,0.0.0.0-255.255.255.255
hideclient_to = 15

Spoiler: oscam.server

[reader]
label = kdde_g09
protocol = internal
device = /dev/sci0
caid = 09C7
boxid = 12345678
ins7e11 = 15
detect = cd
mhz = 450
cardmhz = 2700
ident = 09C7:000000
group = 1
emmcache = 1,1,2,0
blockemm-s = 1
blockemm-g = 1
saveemm-u = 1
ndsversion = 2

[reader]
label = remote
protocol = cccam_ext
device = xx
user = xx
password = xx
inactivitytimeout = 30
caid = 09C7
group = 2
cccversion = 2.2.1
cccmaxhops = 2

Spoiler: Logs während einschalten lokaler Reader

2017/09/14 08:28:58 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:9EEAAB687E89C8EBE9E3102DD7A3DAF9): found (106 ms) by remote - Sky Sport 4 HD
2017/09/14 08:29:08 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:6B6405ED78921704E08018AB04B05168): found (107 ms) by remote - Sky Sport 4 HD
2017/09/14 08:29:16 3844DADD h   (reader) kdde_g09 [videoguard2] Restarting reader
2017/09/14 08:29:16 3844DADD h   (reader) kdde_g09 [videoguard2] creating thread for device /dev/sci0
2017/09/14 08:29:16 2F3790E7 r   (reader) kdde_g09 [videoguard2] Reader initialized (device=/dev/sci0, detect=cd, pll max=27.00 MHz, wanted mhz=4.50 MHz)
2017/09/14 08:29:16 2F3790E7 r   (reader) kdde_g09 [videoguard2] Reader sci internal, detected box type: gigablue
2017/09/14 08:29:17 2F3790E7 r   (reader) kdde_g09 [videoguard2] card detected
2017/09/14 08:29:17 2F3790E7 r   (reader) kdde_g09 [internal] Init card protocol T0, FI=1, F=372, D=16, N=2
2017/09/14 08:29:17 2F3790E7 r   (reader) kdde_g09 [internal] Calculated work ETU is 5.17 us reader mhz = 450
2017/09/14 08:29:18 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:B94368C4AB34C2FD78AE511AE1B3204D): found (105 ms) by remote - Sky Sport 4 HD
2017/09/14 08:29:22 2F3790E7 r   (reader) kdde_g09 [internal] Buffers readed  0 bytes total time_us 5424737
2017/09/14 08:29:23 2F3790E7 r   (reader) kdde_g09 [internal] PLL Reader: ATR Fsmax is 5 MHz, clocking card to 4.50 Mhz (nearest possible mhz specified reader->mhz)
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] Card type:   KLPV
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] Rom version: 10LM
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] Credit available on card: 0 euro
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] FuseByte: 05
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] Region Code: DEU00001
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] Country Code: DEU
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] Pincode read: 5632
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] PCB settings: FF FF FF FF
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] type: VideoGuard Kabel Deutschland G02/G09 (09C7), caid: 09C7
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] serial: ########, BoxID: ########, baseyear: 2004
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] ready for requests
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [internal] found card system videoguard2
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] THIS WAS A SUCCESSFUL START ATTEMPT No  1 out of max alloted of 1
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] card detected
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] type: VideoGuard Kabel Deutschland G02/G09 (09C7)
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] ------------------------------------------------------------------
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] |- class -|-- tier --|----- valid to ------|--- package name ----|
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] +---------+----------+---------------------+---------------------+
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] |-- 00 ---|-- 00fa --| 2017/11/21-00:00:00 |
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] |-- 00 ---|-- 00f3 --| 2017/11/21-00:00:00 |
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] |-- 00 ---|-- 00f4 --| 2017/11/21-00:00:00 |
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] |-- 00 ---|-- 00fe --| 2017/11/21-00:00:00 |
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] |-- 01 ---|-- 0106 --| 2017/11/21-00:00:02 |
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] |-- 01 ---|-- 0133 --| 2017/11/21-00:00:02 |
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] |-- 01 ---|-- 0137 --| 2017/11/21-00:00:02 |
2017/09/14 08:29:24 2F3790E7 r   (reader) kdde_g09 [videoguard2] ------------------------------------------------------from-ins70--
2017/09/14 08:29:28 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:29:28 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:F316B107C1E0D98410B81BD8C8856DF8): found (179 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:29:38 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:29:38 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:AECB76A6EEAC19334095364688D9FA43): found (155 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:29:48 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:29:48 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:3480D9680E9BE879DF821F621831FE01): found (154 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:29:58 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:29:58 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:821863DD4E465D9ED17C0A46BEBD74C9): found (151 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:30:08 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:30:08 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:4FA68DF47ED5824BA1C1E2C05ACF54E1): found (151 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:30:18 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:30:18 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:27BBA92563E040E57A2549D00163DFFF): found (153 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:30:28 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:30:28 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:1D4A9274851C82826BFDBC0AC41CD0A3): found (153 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:30:38 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:30:38 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:DAFE3A1325F0CD6CD35F31C34B561ADC): found (157 ms) by remote (P/2/2/2) - Sky Sport 4 HD
... gekürzt ...
2017/09/14 08:38:28 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:38:28 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:E6283B430A5326065F3BB3373C3193C9): found (243 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:38:38 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:38:38 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:A620F045C1C9D9DBF0A8D80E2207BF87): found (234 ms) by remote (P/2/2/2) - Sky Sport 4 HD
2017/09/14 08:38:48 2F3790E7 r   (reader) kdde_g09 [videoguard2] classD3 ins54: no cw --> Tier expired
2017/09/14 08:38:48 6D61D921 c      (ecm) xx (09C7@000000/000D/0116/96:C8386381D3AA5E023B747A1CE3B4C460): found (280 ms) by remote (P/2/2/2) - Sky Sport 4 HD

der LB ist aus. Inwieweit das mit den Tiers zu tun hat, weiß ich nicht. Die Meldung besagt doch meiner Meinung nach nur, dass er auf der lokalen Karte nix findet, wohl aber remote.

Ich vermute, dass die Lösung gar nicht so kompliziert ist. Ich danke euch vorab!

 

[axel]

Hi,

da ist doch alles im grünen Bereich, freezen sollte da mal nix...

Ich vermute, dass die Lösung gar nicht so kompliziert ist. Ich danke euch vorab!

Wie ist die Geschichte angebunden?

cccversion = 2.2.1

Das würde ich pauschal mal auf = 2.3.0 setzen.

Gruß

 

[waves]

Laut Log wird über den Remote Reader aber eine G02/G09 Karte abgefragt......keine D09......
D09 hat die CAID 1834 und nicht 09C7.
09C7 ist G02/G09......

Gehe also mal davon aus, dass in beiden Boxen eine G02 oder G09 drinne ist.
In diesem Fall solltest Du mal über Services nachdenken.

 

[Kangaroo]

Spoiler

Laut Log wird über den Remote Reader aber eine G02/G09 Karte abgefragt......keine D09......
D09 hat die CAID 1834 und nicht 09C7.
09C7 ist G02/G09......

Gehe also mal davon aus, dass in beiden Boxen eine G02 oder G09 drinne ist.
In diesem Fall solltest Du mal über Services nachdenken.

du hast natürlich recht. remote ist eine g02. ich passe das oben an.

jetzt nach 2h sieht es schon so aus:
2017/09/14 10:45:16 6D61D921 c (ecm) xx(09C7@000000/000D/0116/96:59E966A6005DB0EAE0C3FAFC9D05C492): found (1713 ms) by remote (P/2/2/2) - Sky Sport 4 HD

Services wären eine Idee, aber andersum auf dem Remote Gerät ist die Config quasi identisch und läuft ohne Probleme :/

Spoiler

Hi,

da ist doch alles im grünen Bereich, freezen sollte da mal nix...



Wie ist die Geschichte angebunden?



Das würde ich pauschal mal auf = 2.3.0 setzen.

Gruß

ich setze mal auf 2.3.0.
Angebunden alles über Kabel und WAN mit min. VDSL50

Im Anhang mal noch ein Graph, da sieht man es auch schön..

Spoiler

Du musst Regestriert sein, um das angehängte Bild zusehen.

 

[axel]

Hi,

found (1713 ms) by remote

ich denke das liegt eher an dem Proxy bzw. dessen Internetanbindung...

Gruß

 

[waves]

@axfa77

Unwahrscheinlich, denn:

Sobald ich den lokalen

Sie müssen registriert sein, um Links zu sehen.

deaktiviere, sind die Zeiten des Remote Readers in Ordnung und bleiben es auch über Tage hingweg. Aktiviere ich den lokalen

Sie müssen registriert sein, um Links zu sehen.

wieder, beginnen die Zeiten des Remote Readers zu steigen.

Denke das Problem liegt an den internen Prozessen, die, warum auch immer, immer länger brauchen den passenden Reader zu finden.
Deshalb würde ich es mal, wie schon erwähnt, mit Services probieren.

 

[janni1]

Hi,
schon mal mit aktiviertem Loadbalancer versucht?
Dieser sollte lernen, was auf welchem Reader verfügbar ist und die doppelte Anfrage verhindern.

 

[Kangaroo]

ja, das brachte leider überhaupt nichts.

 

[Pilot]

Hast du nach der Änderung oscam neu gestartet?

 

[dodo83]

Hi,
wie sieht es am Server aus von wo die "remote" Karte kommt? poste mal auch das log von da, und mal die OScam User, kann es sein das du die Karten hin und wieder zurück schickst?

 

[janni1]

Hi,
mich würde auch noch ein Log mit aktiviertem LB interessieren.

 

[Kangaroo]

Hi,
wie sieht es am Server aus von wo die "remote" Karte kommt? poste mal auch das log von da, und mal die OScam User, kann es sein das du die Karten hin und wieder zurück schickst?

das kann prinzipiell tatsächlich sein. Ich habe ja nur diese beiden Seiten und die geben sich gegenseitig alles frei inkl. reshare. Log poste ich heute Abend, an das komme ich nicht immer ran.

Ich habe mir jetzt die Services eingerichtet und lasse es mal eine Weile laufen.

 

[Kangaroo]

also mit den services brachte eigentlich nur soviel, dass die tier-Meldung weg ist. Ansonsten selbes Phänomen. Ein bisschen näher komme ich aber, ich denke es hat wirklich damit zu tun, dass ich hin wie her schicke. Ich benutze auch für alles ein und denselben User auf beiden Seiten. In der Richtung probiere ich heute Abend weiter..

 

[janni1]

Hi,
das sollte eigentlich beim cccam-Protokoll nicht passieren, da hier dafür die unterschiedlichen NodeIds ausgewertet werden.
Außerdem bietet hier auch oscam mit "block_same_ip" einen ersten Schutz vor solchen Loops.

Die Configs der anderen Box wären aber trortzdem auch interessant.
Auch zeitgleiche Logs könnten hilfreich sein.

Was passiert eigentlich, wenn du bei so einem Verhalten (angestiegene ECM-Zeit) auf einen anderen Sender schaltest, der aber ebenfalls nicht von der eigenen Karte beantwortet werden kann?
Bleiben die Zeiten so hoch?
Bzw, was passiert, wenn du den Proxy kurz deaktivierst?

 

[Kangaroo]

umschalten auf einen anderen Sender hat keinerlei Auswirkungen auf die Zeiten, die Werte bleiben also so wie auf dem alten Sender.

Interessant ist der Punkt mit dem Proxy: Wenn ich nur den Proxy neustarte, werden die Zeiten zurückgesetzt, d.h. ich bin wieder bei ca 100ms. steigt dann wieder.

momentan habe ich auch den LB an und testweise mal auf cs378x umgestellt. Außerdem habe ich die User etwas getrennt. Alles ohne jegliche Auswirkungen.

Hier mal alle relevanten Configs:

Spoiler: Box A oscam.conf

[global]
logfile = stdout
clienttimeout = 4000
fallbacktimeout = 2600
nice = -1
lb_mode = 1
lb_save = 100
lb_savepath = /usr/local/etc/.oscam/stat

[cache]

[newcamd]
port = 33911@09C7:000000
key = 0102030405060708091011121314
keepalive = 1

[cs378x]
port = 44911

[dvbapi]
enabled = 1
au = 1
pmt_mode = 0
user = UserA
read_sdt = 2
write_sdt_prov = 1
boxtype = dreambox

Spoiler: Box B oscam.conf

[global]
logfile = stdout
nice = -1
preferlocalcards = 1

[cache]

[newcamd]
port = 12013@09C7:000000
key = 0102030405060708091011121314
keepalive = 1

[cs378x]
port = 12012

[dvbapi]
enabled = 1
au = 1
pmt_mode = 0
user = UserB
read_sdt = 2
write_sdt_prov = 1
boxtype = dreambox

Spoiler: Box A oscam.server

[reader]
label = kdde_g09
protocol = internal
device = /dev/sci0
services = kd_hdtv_komplett,kd_sdtv_komplett_ohne_internationale_senderpakete,kd_tv_sonstiges,kd_radio_vielfalt_hd,kd_radio_basis_hd
caid = 09C7
boxid = 12345678
ins7e11 = 15
detect = cd
mhz = 450
cardmhz = 2700
ident = 09C7:000000
group = 1
emmcache = 1,1,2,0
blockemm-s = 1
blockemm-g = 1
saveemm-u = 1
ndsversion = 2

[reader]
label = RemoteB
protocol = cs378x
device = BoxB,12012
user = UserB
password =
services = sky_hdtv_komplett,sky_sdtv_komplett
inactivitytimeout = 30
caid = 09C7
group = 2

Spoiler: Box B oscam.server

[reader]
label = kdde_g02
protocol = internal
device = /dev/sci0
services = sky_hdtv_komplett,sky_sdtv_komplett
caid = 09C7
boxid = 12345678
ins7e11 = 15
detect = cd
mhz = 450
cardmhz = 2700
ident = 09C7:000000
group = 2
emmcache = 1,1,2,0
blockemm-s = 1
blockemm-g = 1
ndsversion = 2

[reader]
label = RemoteA
protocol = cs378x
device = BoxA,44911
user = UserA
password =
services = kd_hdtv_komplett,kd_sdtv_komplett_ohne_internationale_senderpakete,kd_tv_sonstiges,kd_radio_vielfalt_hd,kd_radio_basis_hd
inactivitytimeout = 30
caid = 09C7
group = 1

Spoiler: Box A oscam.user

[account]
user = UserA
pwd =
caid = 09C7
monlevel = 2
au = 1
group = 1,2
cccmaxhops = 2

Spoiler: Box B oscam.user

[account]
user = UserB
pwd =
caid = 09C7
monlevel = 2
au = 1
group = 1,2
cccmaxhops = 2

Spoiler: Einzelnes Logbeispiel

2017/09/14 23:10:07 52BF570E c      (ecm) UserA (09C7@000000/000D/0116/96:055B83249178EBAC4295DA67F8F4FC90): found (154 ms) by RemoteB - Sky Sport 4 HD
2017/09/14 23:10:06 55B97B0D c      (ecm) UserB (09C7@000000/000D/0116/96:055B83249178EBAC4295DA67F8F4FC90:0F06000000000000:): found (80 ms) by kdde_g02 - Sky Sport 4 HD

was braucht ihr vom LB?