V14: Ab und zu ECM rejected

[sidamos77]

Ich habe eine V14 und bekomme ab und zu rejected ECMs, wenn ich dropbadcws eingeschaltet habe. Wenn ich die Option nicht einschalte, bekomme ich zwar in der Webstatistik und im Log keine rejected Fehler angezeigt, habe aber trotzdem einen kurzen Aussetzer in der Aufnahme.

Zuerst dachte ich, es passiert nur bei Fox HD und nur wenn ich HD+ gleichzeitig aufnehme. Jetzt ist es aber auch schon bei HD+ und bei Sky SD passiert. Es passiert 1-2 mal am Tag.

Hardware: Schneller PC (Core i5), Easymouse 2 Premium, V14
Software: Aktuelle Oscam-Versionen, sasc-ng r570, MythTV 0.26

Vorher hatte ich eine Infinity USB Smart als Reader, aber da hatte ich täglich einen Reader Neustart.

oscam.server:

Spoiler

[reader]
label                                   = sky
enable                                  = 1
protocol                               = mouse
device                                 = /dev/ttyUSB0
caid                                    = 098C
ident                                   = 098C:000000
lb_weight                               = 100
detect                                  = cd
mhz                                     = 357
cardmhz                                 = 357
group                                   = 1
emmcache                                = 1,1,2
boxid                                   = 12345678
ins7e11                                 = 15
ndsversion                              = 2
blockemm-unknown = 1
blockemm-s = 0
blockemm-g = 1
blockemm-u = 0
saveemm-u = 1
dropbadcws = 1
services = sky_098C_all,!sky_ppv_select_bluemovie,!sky_ppv_select_bluemovie_hd

Hier mal ein Beispiel aus dem Oscam Log (mit Debug 0) für den Fehler. Hier hatte ich testhalber mit 2 PCs jeweils die gleichen 2 Programme aufgenommen (daher die Antworten aus dem Cache):

Spoiler

2013/05/01 22:53:54  815DBD0 c B1 (098C&000000/0000/00DD/98:F442E4CE70F9C23B36D806C48829FB8D): found (74 ms) by sky - Sky Sport 1
2013/05/01 22:53:54  815F780 c A1 (098C&000000/0000/00DD/98:F442E4CE70F9C23B36D806C48829FB8D): cache2 (67 ms) by sky - Sky Sport 1
2013/05/01 22:53:54  815DBD0 c B1 (098C&000000/0000/00DF/98:CD51E5ABE8B88A20C3E03FD7AE76AC72): found (73 ms) by sky - Sky Bundesliga
2013/05/01 22:53:54  815F780 c A1 (098C&000000/0000/00DF/98:CD51E5ABE8B88A20C3E03FD7AE76AC72): cache2 (55 ms) by sky - Sky Bundesliga
2013/05/01 22:53:56  815DBD0 c B1 (098C&000000/0000/0081/98:62578FAF54A0E99619D3BECA50B2275B): found (76 ms) by sky - Sky Sport HD 1
2013/05/01 22:53:56  815F780 c A1 (098C&000000/0000/0081/98:62578FAF54A0E99619D3BECA50B2275B): cache2 (75 ms) by sky - Sky Sport HD 1
2013/05/01 22:53:56  815DBD0 c B1 (098C&000000/0000/0082/98:EC8646BC313233021D3E550C97B33810): found (74 ms) by sky - Discovery HD
2013/05/01 22:53:56  815F780 c A1 (098C&000000/0000/0082/98:EC8646BC313233021D3E550C97B33810): cache2 (74 ms) by sky - Discovery HD
2013/05/01 22:54:01  815F780 c A1 (098C&000000/0000/00DD/98:DC4D4D058101466360B9C9161B8079AB): rejected  (74 ms) by sky - Sky Sport 1
2013/05/01 22:54:01  815DBD0 c B1 (098C&000000/0000/00DD/98:DC4D4D058101466360B9C9161B8079AB): not found (64 ms) by sky - Sky Sport 1
2013/05/01 22:54:01  815DBD0 c B1 (098C&000000/0000/00DF/98:7AFF85416AE185B1FB58F1248663348C): found (74 ms) by sky - Sky Bundesliga
2013/05/01 22:54:01  815F780 c A1 (098C&000000/0000/00DF/98:7AFF85416AE185B1FB58F1248663348C): cache2 (60 ms) by sky - Sky Bundesliga
2013/05/01 22:54:01  815DBD0 c B1 (098C&000000/0000/00DD/98:DC4D4D058101466360B9C9161B8079AB): not found (0 ms) by sky - Sky Sport 1
2013/05/01 22:54:01  815F780 c A1 (098C&000000/0000/00DD/98:DC4D4D058101466360B9C9161B8079AB): not found (0 ms) by sky - Sky Sport 1
2013/05/01 22:54:03  815DBD0 c B1 (098C&000000/0000/0081/98:8A9F18623C2C9AEF5CE49348395922E4): found (77 ms) by sky - Sky Sport HD 1
2013/05/01 22:54:03  815F780 c A1 (098C&000000/0000/0081/98:8A9F18623C2C9AEF5CE49348395922E4): cache2 (71 ms) by sky - Sky Sport HD 1
2013/05/01 22:54:03  815DBD0 c B1 (098C&000000/0000/0082/98:717226972A04139AA99D1EDF3990A461): found (74 ms) by sky - Discovery HD
2013/05/01 22:54:03  815F780 c A1 (098C&000000/0000/0082/98:717226972A04139AA99D1EDF3990A461): cache2 (75 ms) by sky - Discovery HD

Bin für jeden Hinweis oder Tipp dankbar.

Niemand eine Idee?

 

[bombadil]

AW: V14: Ab und zu ECM rejected

Hi,

poste bitte ein komplettes Log, mit der Karteninitialisierung. Desweiteren auch die oscam.conf und oscam.user. Welcher Client ist das und hast du noch (wieviele) mehr am Server. Bekommst du die Caid noch von einem Proxy?

Die Services bei deinem Reader sollten nach Möglichkeit nur positiv sein. Damit verweigerst du schon alle, die dort nicht aufgeführt sind. Bei den Usern gibst du dann negativ ein, was sie nicht abfragen dürfen oder sollen. Gemischte werden früher oder später Probleme bereiten, wenn neue Sids eingepflegt werden müssen.

Grüße
Bombadil

 

[chaos99]

AW: V14: Ab und zu ECM rejected

Schonmal mit ohne "

dropbadcws = 1" versucht ?

 

[sidamos77]

Ja, dann zeigt Oscam zwar die Fehler nicht mehr an im Log und in der Web-Statistik, aber Streamfehler habe ich dann trotzdem ab und zu.

oscam.conf:

Spoiler

[global]
logfile                       = stdout;/video/log/oscam.log
clientmaxidle                 = 0
nice                          = -1
maxlogsize                    = 0


[newcamd]
port                          = 58435@098C:000000
key                           = xxxxxxxxxxxxxxxxxxxxx
keepalive                     = 1


[monitor]
port                          = 9880
aulow                         = 120
monlevel                      = 1


[webif]
httpport                      = 10001
httprefresh                   = 5
httpallowed                   = 127.0.0.1,192.168.0.0-192.168.255.255
aulow                         = 120

oscam.user:

Spoiler

[account]
user       = A1
Pwd        = xxx
group      = 1,2
caid       = 098C
au         = sky


[account]
user       = B1
Pwd        = xxx
group      = 1,2
caid       = 098C
au         =

Log vom Start:

Spoiler

2013/05/10 08:51:06        0 s >> OSCam <<  cardserver started, version 1.20-unstable_svn, build r8630 (i686-linux-gnu-libusb)
2013/05/10 08:51:06        0 s creating pidfile /tmp/.oscam/oscam.pid with pid 30372
2013/05/10 08:51:06        0 s services reloaded: 0 services freed, 35 services loaded, rejected 0
2013/05/10 08:51:06        0 s userdb reloaded: 5 accounts loaded, 0 expired, 0 disabled
2013/05/10 08:51:06        0 s signal handling initialized
2013/05/10 08:51:06        0 s 288 service-id's loaded in 0ms
2013/05/10 08:51:06        0 s monitor: initialized (fd=5, port=9880)
2013/05/10 08:51:06        0 s newcamd: initialized (fd=6, port=58435)
2013/05/10 08:51:06        0 s -> CAID: 098C PROVID: 000000
2013/05/10 08:51:06        0 s cccam: initialized (fd=7, port=58436)
2013/05/10 08:51:06        0 s sky [mouse] creating thread for device /dev/ttyUSB0
2013/05/10 08:51:06        0 s loadbalancer: can't read from file /tmp/.oscam/stat
2013/05/10 08:51:06        0 s waiting for local card init
2013/05/10 08:51:06 B7000468 h webif: decompressed 52558 bytes back into 150896 bytes
2013/05/10 08:51:06 B7000468 h HTTP Server listening on port 10001
2013/05/10 08:51:06  814A230 r sky [mouse] Reader initialized (device=/dev/ttyUSB0, detect=cd, mhz=357, cardmhz=357)
2013/05/10 08:51:07  814A230 r sky [mouse] card detected
2013/05/10 08:51:08  814A230 r sky [mouse] ATR: 3F FD 15 25 02 50 80 0F 41 B0 0A 69 FF 4A 50 F0 00 00 50 31 03
2013/05/10 08:51:08  814A230 r sky [mouse] Init card protocol T0, FI=1, F=372, D=16, N=2
2013/05/10 08:51:08  814A230 r sky [mouse] Setting baudrate to 153600 bps
2013/05/10 08:51:08  814A230 r sky [mouse] Calculated work ETU is 6.51 us
2013/05/10 08:51:08  814A230 r sky [mouse] ATR Fsmax is: 5 Mhz, clocking card to wanted user cardspeed of 3.57 Mhz (specified in reader->mhz)
2013/05/10 08:51:09  814A230 r sky [mouse] Card type:   P1TV
2013/05/10 08:51:09  814A230 r sky [mouse] Rom version: 20AA
2013/05/10 08:51:09  814A230 r sky [mouse] parental lock setting: FF FF FF FF
2013/05/10 08:51:09  814A230 r sky [mouse] type: VideoGuard Sky Germany (098C), caid: 098C
2013/05/10 08:51:09  814A230 r sky [mouse] serial: ########, BoxID: ########, baseyear: 2004
2013/05/10 08:51:09  814A230 r sky [mouse] ready for requests
2013/05/10 08:51:09  814A230 r sky [mouse] found card system videoguard2
2013/05/10 08:51:09  814A230 r sky [videoguard2] card detected
2013/05/10 08:51:09  814A230 r sky [videoguard2] type: VideoGuard Sky Germany (098C)
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0046, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0056, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0057, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0058, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 005a, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0065, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0066, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0067, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0068, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0069, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 006a, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 006b, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 006c, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 006d, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 006e, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 006f, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 007b, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 007d, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 0081, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 008e, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 00c0, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 00c7, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 00c8, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 00c9, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 00ca, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 00cb, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 00f3, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:09  814A230 r sky [videoguard2] tier: 00fa, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:10  814A230 r sky [videoguard2] tier: 00ff, expiry date: 2013/07/19-01:00:00
2013/05/10 08:51:12        0 s init for all local cards done
2013/05/10 08:51:12        0 s anti cascading disabled

Client ist sasc-ng r570 (Variante von vdr-sc, die ein dvbloopback device zur Verfügung stellt). Momentan habe ich davon 2: Produktivsystem und Testsystem. Es tritt bei beiden mal auf.

Mittlerweile habe ich auch mal den Oscam ECM-Cache probehalber deaktiviert, damit die Clients einen echten Retry machen können und bei beiden Clients das gleiche aufgenommen. Dann passiert der Fehler ("rejected") bei beiden zu diesem Zeitpunkt. Also schickten beide offenbar das selbe ECM zu Oscam und der weiter zur Smartcard und die lehnte beide ab, wie es aussieht.

Proxy oder andere Clients habe ich nicht.

Wie gesagt, es passiert nur alle 1-2 Tage, sonst geht es immer.

 

[bombadil]

AW: V14: Ab und zu ECM rejected

Hi,

bei den Clients bin ich überfragt. Du hast parallel auch noch das cccam Protokoll aktiviert.

2013/05/10 08:51:06 0 s cccam: initialized (fd=7, port=58436)

Kannst du dieses Protokoll auf den Clients versuchen, ob der Fehler dann auch auftritt? Wenn nicht, lösch das aus der oscam.conf. Es sollte dort nichts stehen was nicht benötigt wird.

Grüße
Bombadil

 

[sidamos77]

AW: V14: Ab und zu ECM rejected

Achja, das hatte ich mal versuchsweise aktiviert, weil ich dachte, vielleicht hat newcamd einen Bug bzgl. ECM-Übertragung. Es trat also auch ohne den Eintrag auf. Leider lief cccam nicht stabil mit dem Client.

 

[speckthehut]

AW: V14: Ab und zu ECM rejected

Hallo!

ich hatte seit einiger zeit den selben Fehler aber mit einer V13.
Seit einigen Tagen habe ich auch das "dropbadcw" abgeschaltet - dadurch läuft es bei mir vollkommen fehlerfrei!

Der Fehler trat auch nur bis zu zweimal am Tag auf, egal welches ubertragungsprotokoll. Habe vieles getestet wie cccam, camd35, newcamd und camd37.

Bei mir kamen auch die selben ECM bei mehreren clienten, alle wurden mit rejected und hinterher mit not found beantwortet.

 

[sidamos77]

AW: V14: Ab und zu ECM rejected

OK, ich werde es nochmal ohne probieren. Wird aber ein paar Tage dauern, bis die Situation eintritt, dass der Fehler während einer Aufnahme (nicht während EPG-Scan) aufgetreten wäre.

Und Du bist sicher, dass Deine Aufnahmen alle fehlerfrei sind? MythTV loggt mir sofort, wenn es während der Aufnahme Fehler gibt.

 

[speckthehut]

AW: V14: Ab und zu ECM rejected

Ja ganz sicher und bei mir ist es ja genau die selbe Problematik gewesen.
Ich habe das logging im server an und in meiner dreambox mit oscam. Nirgends taucht der Fehler auf.

Ob sky da cw's verschickt die nicht ganz mit oscam kompatibel sind?!
das ganze kann ja fast nur noch ein Bug von oscam sein. Eigentlich wollte ich auch mal das logging im server hochstellen doch kommen da in ein paar Stunden einige Megabyte zusammen, da muss man den Fehler erst einmal finden können.
Gibt es eine Möglichkeit den log Level so einzustellen das nur die genausesten verschlisselungsdaten gelogt werden?

 

[sidamos77]

AW: V14: Ab und zu ECM rejected

Also, bei mir passiert der Fehler immer noch. Heute Nacht hatte ich wieder die üblichen 7 Sekunden Streamfehler in einer Aufnahme (Syfy HD). Der einzige Unterschied ohne "dropbadcws" ist, dass Oscam es nicht loggt.