gelöst RAI HD dunkel - private sind hell!

[Willie52]

Seit gestern ist bei mir am Server (wo die Tivusat Goldene Karte ist) RAI HD dunkel, aber andere Tivusat Sender sind hell.
Am Client ist RAI HD hell.
Siehe Server Log Auszug im Spoiler mit RAI HD 1 (dunkel) und Canale 5 (hell).

Hat jemand eine Idee woran es liegen könnte? Jede Hilfe ist sehr willkommen!

Log Auszug:

Spoiler: Log Ausschnitt

2021/05/27 09:41:50 4E716B01 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 183D ECM_PID: 14B6 PROVID: 000000
2021/05/27 09:41:50 4E716B01 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 183E ECM_PID: 14E8 PROVID: 000000
2021/05/27 09:41:50 4E716B01 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 1856 ECM_PID: 1646 PROVID: 000000
2021/05/27 09:41:50 4E716B01 c (dvbapi) Demuxer 0 found 3 ECM pids and 3 STREAM pids in CA PMT
2021/05/27 09:41:50 4E716B01 c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 183E PROVID 000000 ECMPID 14E8 ANY CHID PMTPID 0101 VPID 064A
2021/05/27 09:41:50 4E716B01 c (ecm) local (183E@000000/0000/0002/91:4C7B2F132F68383612996B9B62217FEA): found (219 ms) by raieasy - Canale 5
2021/05/27 09:41:58 4E716B01 c (ecm) local (183E@000000/0000/0002/91:5437F940E168F847B9A123D7315BEE54): found (220 ms) by raieasy - Canale 5
2021/05/27 09:42:00 4E716B01 c (dvbapi) Demuxer 0 stopped descrambling for program 0002 (Canale 5)
2021/05/27 09:42:00 4E716B01 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1856 ECM_PID: 0FA1 PROVID: 000000
2021/05/27 09:42:00 4E716B01 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 0100 ECM_PID: 1069 PROVID: 000030 PBM: 800000000000000 DATE: 27-5-2021
2021/05/27 09:42:00 4E716B01 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 181B ECM_PID: 1069 PROVID: 000030
2021/05/27 09:42:00 4E716B01 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 183D ECM_PID: 10CD PROVID: 000000
2021/05/27 09:42:00 4E716B01 c (dvbapi) Demuxer 0 ecmpid 4 CAID: 183E ECM_PID: 1131 PROVID: 000000
2021/05/27 09:42:00 4E716B01 c (dvbapi) Demuxer 0 found 5 ECM pids and 10 STREAM pids in CA PMT
2021/05/27 09:42:00 4E716B01 c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 183E PROVID 000000 ECMPID 1131 ANY CHID PMTPID 044D VPID 01F5
2021/05/27 09:42:03 4E716B01 c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 4 disabled ecmpids!
...
2021/05/27 09:50:43 4E716B01 c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 183E PROVID 000000 ECMPID 1131 ANY CHID PMTPID 044D VPID 01F5
2021/05/27 09:50:45 4E716B01 c (dvbapi) Demuxer 0 stopped descrambling for program 0D49 (1856@000000:0D49 unknown)

Spoiler: oscam.config (Auszug)

[global]
logfile = /var/log/oscam.log
disablelog = 0
usrfile = /var/log/oscamuser.log
disableuserfile = 0
maxlogsize = 1024
nice = -1
clienttimeout = 15000
clientmaxidle = 0

[streamrelay]
stream_relay_enabled = 0

[dvbapi]
enabled = 1
user = local
pmt_mode = 0
request_mode = 1
delayer = 60
read_sdt = 1
au = 1
boxtype = dreambox

[cs378x]
port = xxxx

Spoiler: oscam server (Aufzug)

[reader]
# Goldene Tivusat Karte
# Einstellung: 5 V, phoenix, 6.00 MHz
label = raieasy
enable = 1
protocol = mouse
device = /dev/PRDR4
services = tivusat
autospeed = 0
rsakey = A9..B5
detect = cd
mhz = 600
cardmhz = 357
group = 1
blockemm-unknown = 1

Spoiler: oscam.services (Auszug)

[tivusat]
caid = 183E
provid = 005411,000000
srvid = 213F,2140,2141,0003,0002,0001,0E1E,0D49,2136,0004,0CEA,2142,0D52,0007,04E4,04E3,0D66,0006,0D4B,0009,000A,0CE9,0E2A,000C,4256,4257,1072,1073,1074,3BCA,0E24,2B66,2C34,000D,2992,2991,0E47,0E4B,4259,0E3F,0E50,0E12,4531,4530,4533,1C6E,001E,2BAC,4534,4535,4536,3070,452F,0047,0048,0385,0D4A,0D4B,2FC3,0CEE,0CED,0C83,0D4C,0D4E,0D4D,2135,0E2B,2C9D,29E5,0E2D,0E28,0E29,0C81,1075,007B,007A,0079,0E26,0078,000E,0081,000F,07D1,2CCA,37AD,05BD,2CF3,2C9A,0835,2DCA,10E1,10E3,10E2,0D4F,11F9,4532,11FC,0450,006F,0077,1C5C,1018,052C,0E25,4552,4544,4545,4554,454D,4555,4556,4559,4547,454C,454A,4553,454B,4543,4551,4546,454F,4548,4558,4557,4549,4550,454E,0085,0049,004A,007E,0086,007C,007F,0082,0084,004F,214D,10EE,052E,052D,052F

Spoiler: oscam.dvbapi (Auszug)

P: xxxx
P: 183E # TIVUSAT
P: yyyy
I: 0

Spoiler: dvb.user (Auszug)

[account]
user = local
pwd = pass
au = 1
group = 1

[account]
user = oben
pwd = pass
au = 0
group = 1

Als Nachtrag auch noch der Log Auszug vom Client.

Da die Konfiguration beim Client ähnlich ist, ist es ein Rätsel für mich:
1.) Warum ist RAI HD beim Client hell und beim Server dunkel?
2.) Warum ist beim Server RAI HD dunkel, aber andere Tivusat Programme (außer RAI HD) hell?

Spoiler: Log des Clients (Auszug)

2021/05/27 10:33:30 1C9F0716 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1856 ECM_PID: 0FA1 PROVID: 000000
2021/05/27 10:33:30 1C9F0716 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 0100 ECM_PID: 1069 PROVID: 000030 PBM: 800000000000000 DATE: 27-5-2021
2021/05/27 10:33:30 1C9F0716 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 181B ECM_PID: 1069 PROVID: 000030
2021/05/27 10:33:30 1C9F0716 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 183D ECM_PID: 10CD PROVID: 000000
2021/05/27 10:33:30 1C9F0716 c (dvbapi) Demuxer 0 ecmpid 4 CAID: 183E ECM_PID: 1131 PROVID: 000000
2021/05/27 10:33:30 1C9F0716 c (dvbapi) Demuxer 0 found 5 ECM pids and 10 STREAM pids in CA PMT
2021/05/27 10:33:30 1C9F0716 c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 183E PROVID 000000 ECMPID 1131 ANY CHID PMTPID 044D VPID 01F5
2021/05/27 10:33:32 1C9F0716 c (ecm) local (183E@000000/0000/0D49/91:01711602B8127EBE3D03848FA1D452EF): found (221 ms) by rairemote - Rai 1 HD
2021/05/27 10:33:37 1C9F0716 c (ecm) local (183E@000000/0000/0D49/91:57FF6C178F88341367AC6A5E69D01730): found (420 ms) by rairemote - Rai 1 HD
2021/05/27 10:33:47 1C9F0716 c (ecm) local (183E@000000/0000/0D49/91:9C9522C8A855C5490C3E464142AD165E): found (221 ms) by rairemote - Rai 1 HD
2021/05/27 10:33:57 1C9F0716 c (ecm) local (183E@000000/0000/0D49/91:C52E40C2D426375677A857A03608FEED): found (220 ms) by rairemote - Rai 1 HD
2021/05/27 10:34:07 1C9F0716 c (ecm) local (183E@000000/0000/0D49/91:3A4C5AF6639B958ABB53E199CC001758): found (407 ms) by rairemote - Rai 1 HD
2021/05/27 10:34:17 1C9F0716 c (ecm) local (183E@000000/0000/0D49/91:F56C3A0E83EF155FD6C6651CDA60BB32): found (229 ms) by rairemote - Rai 1 HD
2021/05/27 10:34:27 1C9F0716 c (ecm) local (183E@000000/0000/0D49/91:FF0983B39D673000942D29C9FBA2AFFC): found (222 ms) by rairemote - Rai 1 HD
2021/05/27 10:34:33 1C9F0716 c (dvbapi) Demuxer 0 stopped descrambling for program 0D49 (Rai 1 HD)
2021/05/27 10:34:33 1C9F0716 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 183D ECM_PID: 14B6 PROVID: 000000
2021/05/27 10:34:33 1C9F0716 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 183E ECM_PID: 14E8 PROVID: 000000
2021/05/27 10:34:33 1C9F0716 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 1856 ECM_PID: 1646 PROVID: 000000
2021/05/27 10:34:33 1C9F0716 c (dvbapi) Demuxer 0 found 3 ECM pids and 3 STREAM pids in CA PMT
2021/05/27 10:34:33 1C9F0716 c (dvbapi) Demuxer 0 trying to descramble PID 1 CAID 183E PROVID 000000 ECMPID 14E8 ANY CHID PMTPID 0101 VPID 064A
2021/05/27 10:34:33 1C9F0716 c (ecm) local (183E@000000/0000/0002/91F110D7A80AFBDF25AB11EE22FF16527): found (2 ms) by rairemote
2021/05/27 10:34:39 1C9F0716 c (ecm) local (183E@000000/0000/0002/91:3BDB2F90636AA57E145CECF2DAE3ADAB): found (204 ms) by rairemote

 

[Willie52]

Als weiterer Nachtrag.

Der Server läuft mit

Spoiler: oscam Version Server

>> OSCam << cardserver log switched, version 1.20_svn, build r11691-798 (arm-newnigma2-linux-gnueabihf)

Der Client läuft mit

Spoiler: oscam Version Client

>> OSCam << cardserver log switched, version 1.20_svn, build r11678 (arm-linux-gnueabihf-libusb)

 

[Willie52]

Weiterer Nachtrag. DerClient (oben) kann RAI 1 HD hell machen, aber der Server nicht:

Spoiler: Log Auszug

2021/05/29 07:02:37 5ED870AA c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1856 ECM_PID: 0FA1 PROVID: 000000
2021/05/29 07:02:37 5ED870AA c (dvbapi) Demuxer 0 ecmpid 1 CAID: 0100 ECM_PID: 1069 PROVID: 000030 PBM: 800000000000000 DATE: 29-5-2021
2021/05/29 07:02:37 5ED870AA c (dvbapi) Demuxer 0 ecmpid 2 CAID: 181B ECM_PID: 1069 PROVID: 000030
2021/05/29 07:02:37 5ED870AA c (dvbapi) Demuxer 0 ecmpid 3 CAID: 183D ECM_PID: 10CD PROVID: 000000
2021/05/29 07:02:37 5ED870AA c (dvbapi) Demuxer 0 ecmpid 4 CAID: 183E ECM_PID: 1131 PROVID: 000000
2021/05/29 07:02:37 5ED870AA c (dvbapi) Demuxer 0 found 5 ECM pids and 10 STREAM pids in CA PMT
2021/05/29 07:02:37 5ED870AA c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 183E PROVID 000000 ECMPID 1131 ANY CHID PMTPID 044D VPID 01F5
2021/05/29 07:02:39 370970BF c (ecm) oben (183E@000000/0000/0D49/91:02E136DAA694985D0EC1BB6CF0B3630B): found (217 ms) by raieasy
2021/05/29 07:02:40 5ED870AA c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 4 disabled ecmpids!
2021/05/29 07:02:40 5ED870AA c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 183E PROVID 000000 ECMPID 1131 ANY CHID PMTPID 044D VPID 01F5
2021/05/29 07:02:41 5ED870AA c (dvbapi) Demuxer 0 stopped descrambling for program 0D49 (1856@000000:0D49 unknown)

Kann mir bitte jemand einen Tip geben, woran das liegen könnte.

 

[dodo83]

Moin,
wird der Sender laut OScam log angefragt überhaupt auf dem Server?
wenn nicht würde ich in Richtung Empfang gehen, eventuell neuen Sendersuchlauf erstmal machen

 

[Willie52]

Hab ich auch schon gemacht!

Ich habe jetzt auch die Kanal Informationen verglichen (Im Newnigma2 Haupmenue: Information>Kanal>PIDs) .
Sie waren identisch - bis auf:
PMT PID: 0x044d (1101d) - auf dem Client oben (wo es hell wird)
PMT PID: n/v - auf dem Server (wo es dunkel bleibt)

Was meinst du genau mit "wird der Sender laut OScam log angefragt überhaupt auf dem Server?"
Ist das nicht klar im Server Log Auszug (siehe #3)?
1.) Der Client ruft den folgenden Eintrag hervor:
2021/05/29 07:02:39 370970BF c (ecm) oben (183E@000000/0000/0D49/91:02E136DAA694985D0EC1BB6CF0B3630B): found (217 ms) by raieasy
2.) Der Server aber nur:
2021/05/29 07:02:40 5ED870AA c (dvbapi) Demuxer 0 restarting decoding requests after 1 ms with 1 enabled and 4 disabled ecmpids!
2021/05/29 07:02:40 5ED870AA c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 183E PROVID 000000 ECMPID 1131 ANY CHID PMTPID 044D VPID 01F5
2021/05/29 07:02:41 5ED870AA c (dvbapi) Demuxer 0 stopped descrambling for program 0D49 (1856@000000:0D49 unknown)
Komischerweise steht beim Server "stopped descrambling for caid=1856" (???).

Kann man im WebIf andere Log Debug Optionen einschalten um den Fehler auf dem Server (dvbapi) besser zu debuggen?

Zusätzliche Information:
Mittlerweile habe ich auch das gleiche OSCAM Bin auf Server und Client (Ich habe das Client oscam.bin auf den Server kopiert).
Und wie schon gesagt:
- ich habe die goldene Karte auf dem Server
- RAI 1 HD ist dunkel, aber Canale 5 ist hell auf dem Server.
- RAI 1 HD ist aber hell auf dem Client

 

[dodo83]

wie schaut es mit Rai 2 HD aus oder anderen Sendern auf diesem Transponder?
werden die auf dem Server hell?

 

[Willie52]

Nein, alle RAI HD Sender sind dunkel, alle Mediasat sind hell.

 

[dodo83]

teste mal einen anderen Receiver an dem Sat Anschluss wo der Server dran ist, bzw tausche mal den mit dem Client

 

[Phantom]

Ich habe es eben mal getestet. Meine Senderliste war überwiegend mit NA versehen wonach ich ein Sendersuchlauf gestartet habe. Die Sender RAI HD habe ich dann in der Satelliten Sortierung gefunden. Alle RAI Sender laufen. Ich muss demnach meine Senderliste anpassen

 

[Willie52]

Danke für den Hinweis!

Ich bin jetzt etwas weiter.
Als Vorbereitung auf den Umzug zur oberen DM920 UHD (Client) habe ich die goldene Tivusat Karte in den internen Reader der DM920 UHD (Server) gesteckt.
Und im internen Reader funktiomiert die Karte: RAI 1 HD wird hell sowohl auf dem Server als auch auf dem Client.

Also ist etwas faul mit den Smartcard Readern.
Ich habe an der Dreambox 920 UHD einen aktiven USB Hub mit 4 Anschüssen an denen 4 Smartcard Reader angeschlossen sind.
Das Problem ist beim 4. Smartcard Reader.

Was kann da alles schiefgehen?
Ich glaube nicht, dass er defekt ist (Gefühl?). Was muss ich an der Konfiguration kontrollieren.
Ich habe es vor Jahren konfiguriert und bisher waren nur 3 Smartcard Reader in Gebrauch.
Was kann erklären dass der Smartcard Reader nur halb (Mediasat hell; RAI HD dunkel) funktioniert?

 

[dodo83]

Was kann erklären dass der Smartcard Reader nur halb (Mediasat hell; RAI HD dunkel) funktioniert?

wenn beim Client alle Sender gehen und nur am Server nicht dann kann es am Reader selbst auch nicht sein der Fehler,
die hast du jetzt den Internen Reader konfiguriert da es läuft?

 

[Willie52]

Spoiler: Interner Reader (enabled) und Easymouse Reader (disabled)

[reader]
# Goldene Tivusat Karte
# Interner Reader
label = raiint
enable = 1
protocol = internal
device = /dev/sci0
caid = 183E
services = tivusat
autospeed = 0
rsakey = A9..B5
detect = cd
mhz = 450
cardmhz = 2700
group = 1
blockemm-unknown = 1

[reader]
# Goldene Tivusat Karte
# Einstellung: 5 V, phoenix, 6.00 MHz
label = raieasy
enable = 0
protocol = mouse
device = /dev/PRDR4
caid = 183E
services = tivusat
autospeed = 0
rsakey = A9..B5
detect = cd
mhz = 600
cardmhz = 357
group = 1
blockemm-unknown = 1

Jetzt geht es auch mit dem easymouse Reader.
Das einzig Auffällige ist, dass der erste ECM aus dem cache kommt. Vorher ein "cache Problem"???
Zusätzlich habe ich jetzt konsequent caid=183E und services=tivusat im Reader. Aber vorher war caid=183E im oscam.services File. Aber das kann meiner Meinung auch nicht den Fehler erklären.

Spoiler: Log Ausschnitt

2021/05/29 12:35:39 122F1662 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1856 ECM_PID: 0FA1 PROVID: 000000
2021/05/29 12:35:39 122F1662 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 0100 ECM_PID: 1069 PROVID: 000030 PBM: 800000000000000 DATE: 29-5-2021
2021/05/29 12:35:39 122F1662 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 181B ECM_PID: 1069 PROVID: 000030
2021/05/29 12:35:39 122F1662 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 183D ECM_PID: 10CD PROVID: 000000
2021/05/29 12:35:39 122F1662 c (dvbapi) Demuxer 0 ecmpid 4 CAID: 183E ECM_PID: 1131 PROVID: 000000
2021/05/29 12:35:39 122F1662 c (dvbapi) Demuxer 0 found 5 ECM pids and 10 STREAM pids in CA PMT
2021/05/29 12:35:39 122F1662 c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 183E PROVID 000000 ECMPID 1131 ANY CHID PMTPID 044D VPID 01F5
2021/05/29 12:35:39 122F1662 c (ecm) local (183E@000000/0000/0D49/91:3BA7750CE0B81956339534D0E0E47967): cache1 (0 ms) by raieasy - Rai 1 HD (lg)
2021/05/29 12:35:40 6741658B c (ecm) oben (183E@000000/0000/0D49/918A8750ECF73BFBCB476CEE681848DC5): found (218 ms) by raieasy - Rai 1 HD (lg)
2021/05/29 12:35:40 122F1662 c (ecm) local (183E@000000/0000/0D49/918A8750ECF73BFBCB476CEE681848DC5): cache2 (217 ms) by raieasy - Rai 1 HD
2021/05/29 12:35:48 122F1662 c (dvbapi) Demuxer 0 stopped descrambling for program 0D49 (Rai 1 HD)
2021/05/29 12:35:48 122F1662 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1856 ECM_PID: 0FA2 PROVID: 000000
2021/05/29 12:35:48 122F1662 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 0100 ECM_PID: 106A PROVID: 000030 PBM: 800000000000000 DATE: 29-5-2021
2021/05/29 12:35:48 122F1662 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 181B ECM_PID: 106A PROVID: 000030
2021/05/29 12:35:48 122F1662 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 183D ECM_PID: 10CE PROVID: 000000
2021/05/29 12:35:48 122F1662 c (dvbapi) Demuxer 0 ecmpid 4 CAID: 183E ECM_PID: 1132 PROVID: 000000
2021/05/29 12:35:48 122F1662 c (dvbapi) Demuxer 0 found 5 ECM pids and 10 STREAM pids in CA PMT
2021/05/29 12:35:48 122F1662 c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 183E PROVID 000000 ECMPID 1132 ANY CHID PMTPID 044E VPID 01F6
2021/05/29 12:35:48 122F1662 c (ecm) local (183E@000000/0000/0D4A/91:62FE6E8F3BAD23795B80A48D61043D77): found (205 ms) by raieasy (lg)
2021/05/29 12:35:50 6741658B c (ecm) oben (183E@000000/0000/0D49/91:02CE923482036A0A58AEF7C87979D7D6): found (217 ms) by raieasy - Rai 1 HD (lg)
2021/05/29 12:35:51 122F1662 c (ecm) local (183E@000000/0000/0D4A/9120C398CC0ADDB73393A2BEFAE3E5FBF): found (343 ms) by raieasy (lg)

Vielen Dank für die Hilfe! Ich bin auch Borussia Dortmund Fan.
Es tut mir leid, dass ich deine Zeit vergeudet haben und der Fehler sich dann im Nichts aufgelöst hat.
Aber wie sagt man: "Gute Fehler kommen immer wieder". Vielleicht finde ich dann den wahren Grund für dieses Problem.

 

[dodo83]

Es tut mir leid

muss dir nicht, ein Forum ist halt für Fragen da
geht jetzt der Sender auch auf dem Server wenn du nur mit einer Box zugreifst?
erklären kann ich mir das jetzt nicht wieso weshalb .... aber gut, wenn es läuft

Ich bin auch Borussia Dortmund Fan.

gute Entscheidung

 

[RuckZuck]

2021/05/29 07:02:41 5ED870AA c (dvbapi) Demuxer 0 stopped descrambling for program 0D49 (1856@000000:0D49 unknown)
Komischerweise steht beim Server "stopped descrambling for caid=1856"

es wird die falsche CAID abgefragt.
Im Reader fehlt zudem der Eintrag „caid = 183E“

 

[sodex]

PMT PID: n/v - auf dem Server (wo es dunkel bleibt)

das sagt doch alles zu deinen Problem
MfG