No Matching Reader nach einiger Zeit

[utiltiy]

Hallo,

seit kurzem habe ich folgendes Problem

Spoiler

>> OSCam << cardserver log switched at Tue Aug 4 06:30:19 2015

2015/08/04 06:30:19 00000000 s >> OSCam << cardserver log switched, version 1.20-unstable_svn, build r10935 (x86_64-unknown-linux-gnu-ssl-libusb)
2015/08/04 06:30:19 00000000 s (main) System name = Linux

2015/08/04 06:30:29 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:9B73D2ED6698C2780D3A27B8DEBBD8F8): found (195 ms) by OSCam-S400 - National Geographic
2015/08/04 06:30:32 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:9FA478D03D632FD027997C16C83AD46A): found (86 ms) by OSCam-S400 - National Geographic
2015/08/04 06:30:39 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:A71DF81AD96D4252E10907BCCDC1EE5E): found (89 ms) by OSCam-S400 - National Geographic
2015/08/04 06:30:46 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:E35BC6C66175AD18672CCEA749FA0ACF): found (86 ms) by OSCam-S400 - National Geographic
2015/08/04 06:30:53 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:56C6646D77383A5E4C26A30E997767CB): found (95 ms) by OSCam-S400 - National Geographic
2015/08/04 06:30:59 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:8BF07F3F80C3C5C398F76B6AC63F2FCA): found (83 ms) by OSCam-S400 - National Geographic
2015/08/04 06:31:06 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:384A50E89BB63FA1087E583D118913F0): found (89 ms) by OSCam-S400 - National Geographic
2015/08/04 06:31:13 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98581E422F40905A1A6A61BAE96C84C8E): found (85 ms) by OSCam-S400 - National Geographic
2015/08/04 06:31:20 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:557487E7AFDE406E30E89593C8A6D663): found (87 ms) by OSCam-S400 - National Geographic
2015/08/04 06:31:27 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:76640BEE0735E37C9677D6A5BB30F0E7): found (87 ms) by OSCam-S400 - National Geographic
2015/08/04 06:31:34 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:EA0333C8039F0A0DFF0C09F5A6042694): found (85 ms) by OSCam-S400 - National Geographic
2015/08/04 06:31:41 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:044956B0500F0D7AB16C18BB4D0D23B5): found (91 ms) by OSCam-S400 - National Geographic
2015/08/04 06:31:48 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:79DA824CDC384F4206BA527CB7A1203D): found (98 ms) by OSCam-S400 - National Geographic
....
....
2015/08/04 07:25:03 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:6C2B743C4D96D9F82403627C0D4AD303): found (176 ms) by OSCam-S400 - National Geographic
2015/08/04 07:25:10 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:8B3F260C7A1C9AC372DF6D597C80F964): found (185 ms) by OSCam-S400 - National Geographic
2015/08/04 07:25:17 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98C54CBC282B5C51EC7367A089BBF66F3): found (403 ms) by OSCam-S400 - National Geographic
2015/08/04 07:25:24 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:4BB601F1CDB1F6D3B4EB8B0886ED0E40): not found (508 ms) by OSCam-S400 - National Geographic
2015/08/04 07:25:24 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:4BB601F1CDB1F6D3B4EB8B0886ED0E40): rejected group (0 ms) - National Geographic (no matching reader)
2015/08/04 07:25:27 612F07F6 c (-) -- Skipped 9 duplicated log lines --
2015/08/04 07:25:27 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:4BB601F1CDB1F6D3B4EB8B0886ED0E40): rejected group (1 ms) - National Geographic (no matching reader)
2015/08/04 07:25:27 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:4BB601F1CDB1F6D3B4EB8B0886ED0E40): rejected group (0 ms) - National Geographic (no matching reader)
2015/08/04 07:25:30 612F07F6 c (-) -- Skipped 11 duplicated log lines --
....
....
2015/08/04 07:25:57 612F07F6 c (dvbapi) Client connected: 'vdr-plugin-dvbapi 2.2.1 / VDR 2.2.0' (protocol version = 2)
2015/08/04 07:26:04 612F07F6 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1702 ECM_PID: 1722 PROVID: 000000
2015/08/04 07:26:04 612F07F6 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 1833 ECM_PID: 1822 PROVID: 000000
2015/08/04 07:26:04 612F07F6 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 09C4 ECM_PID: 1B22 PROVID: 000000
2015/08/04 07:26:04 612F07F6 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 09AF ECM_PID: 1F22 PROVID: 000000
2015/08/04 07:26:04 612F07F6 c (dvbapi) Demuxer 0 ecmpid 4 CAID: 098C ECM_PID: 1A22 PROVID: 000000
2015/08/04 07:26:04 612F07F6 c (dvbapi) Demuxer 0 found 5 ECMpids and 3 STREAMpids in PMT
2015/08/04 07:26:04 612F07F6 c (dvbapi) Demuxer 0 serving srvid 000D (1702@000000:000D unknown) on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/08/04 07:26:04 612F07F6 c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 098C PROVID 000000 ECMPID 1A22 ANY CHID PMTPID 0000 VPID 0CFF
2015/08/04 07:26:05 612F07F6 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1702 ECM_PID: 1722 PROVID: 000000
2015/08/04 07:26:05 612F07F6 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 1833 ECM_PID: 1822 PROVID: 000000
2015/08/04 07:26:05 612F07F6 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 09C4 ECM_PID: 1B22 PROVID: 000000
2015/08/04 07:26:05 612F07F6 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 09AF ECM_PID: 1F22 PROVID: 000000
2015/08/04 07:26:05 612F07F6 c (dvbapi) Demuxer 0 ecmpid 4 CAID: 098C ECM_PID: 1A22 PROVID: 000000
2015/08/04 07:26:05 612F07F6 c (dvbapi) Demuxer 0 found 5 ECMpids and 3 STREAMpids in PMT
2015/08/04 07:26:05 612F07F6 c (dvbapi) Demuxer 0 serving srvid 000D (1702@000000:000D unknown) on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/08/04 07:26:05 612F07F6 c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 098C PROVID 000000 ECMPID 1A22 ANY CHID PMTPID 0000 VPID 0CFF
2015/08/04 07:26:05 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:20B409E6E6C256AF413093B2A62BD8E0): rejected group (0 ms) - National Geographic (no matching reader)
2015/08/04 07:26:05 612F07F6 c (-) -- Skipped 2 duplicated log lines --
2015/08/04 07:26:05 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:C9B19F9521263005CBDD2444D4A049D1): rejected group (0 ms) - National Geographic (no matching reader)
....
....
2015/08/04 07:35:43 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:53B3027BBBC2C11ECCD9AA07A22199DA): rejected group (0 ms) - National Geographic (no matching reader)
2015/08/04 07:35:43 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:53B3027BBBC2C11ECCD9AA07A22199DA): rejected group (1 ms) - National Geographic (no matching reader)
2015/08/04 07:35:44 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:53B3027BBBC2C11ECCD9AA07A22199DA): rejected group (0 ms) - National Geographic (no matching reader)
2015/08/04 07:35:45 612F07F6 c (-) -- Skipped 3 duplicated log lines --
2015/08/04 07:35:45 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:53B3027BBBC2C11ECCD9AA07A22199DA): rejected group (1 ms) - National Geographic (no matching reader)
2015/08/04 07:35:45 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:53B3027BBBC2C11ECCD9AA07A22199DA): rejected group (0 ms) - National Geographic (no matching reader)
2015/08/04 07:35:46 27EC8B43 p (-) -- Skipped 4 duplicated log lines --
2015/08/04 07:35:46 27EC8B43 p (camd35) OSCam-S400 CMD05 AU request for caid: 098C auprovid: 000000
2015/08/04 07:35:46 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:53B3027BBBC2C11ECCD9AA07A22199DA): found (95 ms) by OSCam-S400 - National Geographic
2015/08/04 07:35:47 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:85A8ECECDF614A90B08D1BE58718F9E9): found (89 ms) by OSCam-S400 - National Geographic
2015/08/04 07:35:54 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:2594837ABFBBF7B3799872F20D530457): found (88 ms) by OSCam-S400 - National Geographic
2015/08/04 07:36:01 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:6398EFDDB6E68BE26D9C1DA07592B9DE): found (93 ms) by OSCam-S400 - National Geographic
2015/08/04 07:36:08 612F07F6 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:79C60A20BE3F140F806F7CB82EF33C0C): found (86 ms) by OSCam-S400 - National Geographic

Der Client wird versorgt, nach einiger Zeit findet er aber keinen Reader mehr welcher aber dann doch wieder, ebenfalls nach einiger Zeit, gefunden wird.

Kennt jemand dieses Verhalten und hat einen Tipp, bzw. braucht man noch irgendwelche Config's? Hab an den Settings eigentlich nichts geändert, das Problem kam von selbst.

 

[Radagast]

AW: No Matching Reader nach einiger Zeit

Hi,

hast du evtl den LB aktiviert und arbeitest bei dem Reader ohne lb_whitelist_services?

 

[utiltiy]

AW: No Matching Reader nach einiger Zeit

Ja hab ich, die Option verwende ich nicht.

 

[pehedima]

AW: No Matching Reader nach einiger Zeit

Poste bitte deine kompletten Configs (oscam.conf, oscam.user, oscam.server und ggfs. oscam.dvbapi).

 

[DarkStarXxX]

AW: No Matching Reader nach einiger Zeit

Interessant wäre zum Zeitpunkt des Rejected ein Log vom Server.

 

[Radagast]

AW: No Matching Reader nach einiger Zeit

Ja hab ich, die Option verwende ich nicht.

OK, wenn du den LB verwendest, dann solltest du auch die Option lb_whitelist_services verwenden. Diese wirken für den LB wie eine Art Freifahrtschein und er macht die Sender nicht dicht, falls mal auf Grund einer Fehlanfrage (aus welchen Gründen auch immer) die Anfrage nicht beantwortet werden kann.
Solltest du aber den LB nicht brauchen, dann deaktivere ihn und du bist das Problem auch los.

 

[utiltiy]

AW: No Matching Reader nach einiger Zeit

Client

Spoiler

[global]
nice = -20
clienttimeout = 8
clientmaxidle = 120
bindwait = 60
preferlocalcards = 2
logfile = /tmp/oscam.log
#logFile = stdout
maxlogsize = 1024
sleep =
usrfile = /tmp/oscam-user.log
cwlogdir = /tmp/oscam-cw.log
emmlogdir = /var/lib/oscam/
lb_mode = 1
lb_nbest_readers = 2
lb_nfb_readers = 2
lb_min_ecmcount = 5
lb_max_ecmcount = 250
lb_reopen_seconds = 300
lb_retrylimit = 800
lb_save = 250
lb_stat_cleanup = 128
lb_savepath = /var/lib/oscam/oscam.stat
fallbacktimeout = 2000

[cache]
delay = 10

[monitor]
port = 55000
nocrypt = 192.168.178.0-192.168.178.255
monlevel = 0

[webif]
httpport = +8080
httpuser = oscam.vdr04
httppwd = mp65yPe8
httpcert = /var/lib/oscam/ssl/oscam.pem
httptpl = /var/lib/oscam/picon
httpshowpicons = 1
httppiconsize = 30
#httpcss = /var/lib/oscam/oscam.css
httprefresh = 15
httpdyndns = whonose.ath.cx
#httpallowed = 127.0.0.1,192.168.178.0-192.168.178.255
httpallowed = 192.168.178.0-192.168.178.255
httpreadonly = 0
httphelplang = de

#[lcd]
#enablelcd = 0
#lcd_outputpath = /tmp/.oscam
#lcd_hideidle = 0

[dvbapi]
enabled = 1
user = oscam-vdr04
boxtype = pc
au = 1
pmt_mode = 4
request_mode = 1
listen_port = 2000


# Geblockte Caids
I:1801 # Nagra2
I:1837 # Nagra3
I:1843 # HD NEW
I:1861 # Nagra3 ??
I:9c4 # NDS Sky V13
I:9c7 # NDS KD
I:9aF # NDS Telecolumbus
I:b00 # Conax
I:d22 # CryptoWorks
#I:647 # für Elite HD

# AustriaSat blocken
I:0D05:000000:003E # AustriaSat - AXN Action
I:0D05:000000:07FF # AustriaSat - Animax
I:0D05:000000:4462 # AustriaSat - Kabel eins Classics
I:0D05:000000:4461 # AustriaSat - Sat.1 Comedy
I:0D05:000000:2EFE # AustriaSat - RTL Living
I:0D05:000000:6FEE # AustriaSat - MTV Hits
I:0D05:000000:6FEF # AustriaSat - MTV Dance
I:0D05:000000:6FF0 # AustriaSat - VH-1 (Video Hits One)
I:0D05:000000:6FF3 # AustriaSat - MTV ROCKS
I:0D05:000000:6FFF # AustriaSat - MTV Music
I:0D05:000000:4F55 # AustriaSat - Hustler TV
I:0D05:000000:07FD # AustriaSat - Dorcel TV
I:0D05:000000:0046 # AustriaSat - Alpenglühen TVX
I:0D05:000000:0045 # AustriaSat - Sportdigital TV

# Priorisierte Caids
P:0100 # MTV HD
P:0500 # MTV HD
P:0D05:000004:132F # ORF
P:0D05:000004:1330 # ORF
P:0D05:000010 # AustriaSat
P:098c 1 # NDS Sky V14


# OSCam-Server (S400)
[reader]
label = OSCam-S400
enable = 1
protocol = cs357x
lb_weight = 300
device = 192.168.178.5,20255
user = oscam-vdr04
password = paswd
group = 2
#services = share



# OSCam Client
[account]
user = oscam-vdr04
pwd = paswd
au = OSCam-S400
#au = MultiCAM
group = 1,2,3,4,5

Server

Spoiler

[global]
nice = -20
clienttimeout = 5
clientmaxidle = 120
bindwait = 60
serialreadertimeout = 1500
waitforcards = 1
preferlocalcards = 2
disableuserfile = 0
failbancount = 3
failbantime = 15
#usrfile = /var/log/oscam-user.log
usrfile = /tmp/oscam-user.log
usrfileflag = 1
#logfile = stdout
#logfile = /var/log/oscam.log
logfile = /tmp/oscam.log
emmlogdir = /var/keys/
maxlogsize = 2048
sleep = 240
lb_mode = 1
lb_min_ecmcount = 5
lb_max_ecmcount = 500
lb_reopen_seconds = 900
lb_retrylimit = 800
lb_save = 400
lb_stat_cleanup = 128
lb_savepath = /var/keys/oscam.stat
fallbacktimeout = 2000
serverip = 192.168.178.5

[cache]
delay = 10

[monitor]
port = 55000
nocrypt = 192.168.178.0-192.168.178.255
monlevel = 0

[cs357x]
port = 20255
suppresscmd08 = 1



# 2 Cardreader Easymouse (S400)
[reader]
label = Easy-S400
enable = 1
protocol = mouse
boxid = 12345678
lb_weight = 300
device = /dev/EASYMOUSE
mhz = 600
autospeed = 0
ins7e11 = 15
caid = 098C
emmcache = 1,1,2
blockemm-u = 1
blockemm-s = 1
blockemm-g = 1
blockemm-unknown = 1
#blockemm-bylen = 1-31,36,38,40-41,65-72,74-124,126,128-129,131,141-255
saveemm-u = 1
group = 2
services = skyfilm, skywelt, skywelt-extra, skyselect, skysport, skysporthd, skybuli, skybulihd, skyfilmhd, sky3d, !bluemovie, skysonstige, hdplusnds


# OSCam-VDR04
[account]
user = oscam-vdr04
disabled = 0
pwd = paswd
monlevel = 4
group = 1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,24,25
au = Smargo-S400,Easy-S400

 

[janni1]

AW: No Matching Reader nach einiger Zeit

Hi,
wenn du nur einen Reader pro Caid hast, würde ich den Loadbalancer abschalten.
Wenn nicht, würde ich Standartwerte für den LB werwenden und lb_whitelist_Services für die lokale Karte.

Auf dem Clienten brauchst du gar keinen LB. Dort hast du nur einen Reader, oder?

Gruß
janni1

 

[Radagast]

AW: No Matching Reader nach einiger Zeit

Hi,

wenn der Client nur auf einen Server zugreifen kann/darf, dann macht der aktivierte LB dort keinen Sinn.

Bei den Services setzt du entweder positive oder negative. Beides zusammen ist überflüssig, den Rest macht der LB eigentlich.

 

[utiltiy]

AW: No Matching Reader nach einiger Zeit

Server Logausgabe

Spoiler

Sowas kommt vor:
2015/08/04 07:15:56 50F35A99 r (reader) Easy-S400 [videoguard2] classD3 ins54: no cw --> Bad/wrong ECM

Danach zieht er den Kanal aus dem Share bis es "abbricht"

2015/08/04 07:25:24 50F35A99 r (reader) Easy-S400 [videoguard2] Not a valid ecm

Dann kommt dies:
2015/08/04 07:36:07 0CC2ED14 c (client) encrypted cs357x-client 192.168.178.18 granted (oscam-vdr04, au=on (2 reader))
2015/08/04 07:36:07 0CC2ED14 c (camd35) oscam-vdr04 emm-request sent (reader=Easy-S400, caid=098C, auprovid=000000)
2015/08/04 07:36:07 0CC2ED14 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:53B3027BBBC2C11ECCD9AA07A22199DA:000000): found (86 ms) by Easy-S400 (L/1/2/17) - National Geographic
2015/08/04 07:36:07 0CC2ED14 c (ecm) oscam-vdr04 (098C@000000/0198/000D/98:85A8ECECDF614A90B08D1BE58718F9E9:000000): found (85 ms) by Easy-S400 (L/1/2/17) - National Geographic

- - - - - - - - - -

Dort hast du nur einen Reader, oder?

Einen aktiven zur Zeit.

 

[DarkStarXxX]

AW: No Matching Reader nach einiger Zeit

2015/08/04 07:15:56 50F35A99 r (reader) Easy-S400 [videoguard2] classD3 ins54: no cw --> Bad/wrong ECM


Da kannst du umstellen was du willst, damit änderst du nichts.
Dein Client schickt fehlerhafte ECM's an die Karte und deswegen gibt's den "no matching Reader"

 

[utiltiy]

AW: No Matching Reader nach einiger Zeit

Dein Client schickt fehlerhafte ECM's an die Karte und deswegen gibt's den "no matching Reader"

AU rausnehmen?

 

[Radagast]

AW: No Matching Reader nach einiger Zeit

Hi,

für AU werden EMMs benötigt. Das hat also recht wenig mit den fehlerhaften ECMs zu tun, die dir dein Client schickt.
Deswegen rate ich dir ja auch mit lb_whitelist_services zu arbeiten. Damit macht der LB den Sender nicht dicht.

 

[pehedima]

AW: No Matching Reader nach einiger Zeit

Das hat mit AU nichts zu tun.

Wechsel bitte mal das Protokoll von cs357x auf cs378x am Server und Client.

Oscam neu starten, oscam.log posten.

 

[janni1]

AW: No Matching Reader nach einiger Zeit

Hi,
ich würde es trotzdem mal ohne LB oder mit lb_whitelist_services versuchen,
damit dir genau nach diesem fehlerhaften ECM der LB nicht den Sender dicht macht.
Bei dir gleich mal für 900 sec. (lb_reopen_seconds = 900)

Auf dem Client den LB am besten ganz weglassen.

Gruß
janni1