V13 Oscam <> Oscam Freezer

[DarkSu]

Hallo zusammen ..

Viel gelesen aber leider noch nicht die Lösung für mein Problem gefunden. Ich habe auf einem Pi Oscam als Server laufen und meine Clients hatten im HS cccam 2.3.0 drauf. Seit gestern habe ich meine Clients umgestellt auf Oscam. Seit besagter Zeit kommt es immer wieder zu Freezern mit fatalen Hängern. Das Log zeigt Timeouts .. Aber was tun dagegen ?

Client Log:

Spoiler

2017/04/16 19:20:44 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3CFCDCBB8B7075C23365EC5EDE277B10): found (138 ms) by Remote_cccam
2017/04/16 19:20:51 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:C566725F501C62F474B7F935F21686CD): found (137 ms) by Remote_cccam
2017/04/16 19:20:58 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:7BE09DC728FF00F904D95D8E2FE0E620): found (130 ms) by Remote_cccam
2017/04/16 19:21:05 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:08EC90CF1395A857BE8513015AD3FD71): found (139 ms) by Remote_cccam
2017/04/16 19:21:12 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:B2A858B6EBB554B6BE773E1B76EAFE96): found (132 ms) by Remote_cccam
2017/04/16 19:21:19 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:2C495D3C12DA8683916FC5F80C83C803): found (137 ms) by Remote_cccam
2017/04/16 19:21:26 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:C8A7A41F18742EDFB8EF599BDFD540DF): found (137 ms) by Remote_cccam
2017/04/16 19:21:33 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:7D9BC68A0BE176C1B4F9F223E230F56A): found (140 ms) by Remote_cccam
2017/04/16 19:21:44 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:8385DAA78EAE6B9F12428E8BB930FA2E): timeout (5001 ms) by Remote_cccam
2017/04/16 19:21:50 7FFE1658 c (-) -- Skipped 1 duplicated log lines --
2017/04/16 19:21:50 7FFE1658 c (dvbapi) Demuxer 0 restarting decodingrequests after 0 ms with 1 enabled and 2 disabled ecmpids!
2017/04/16 19:21:50 7FFE1658 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C4 PROVID 000000 ECMPID 1B66 ANY CHID PMTPID 0068 VPID 08FF
2017/04/16 19:21:51 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:3E4A83ED0E78C34F835248AA4CB24AB5): timeout (5001 ms) by Remote_cccam
2017/04/16 19:21:51 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:3E4A83ED0E78C34F835248AA4CB24AB5): not found (1184 ms) by Remote_cccam
2017/04/16 19:21:52 7FFE1658 c (dvbapi) Demuxer 0 restarting decodingrequests after 0 ms with 1 enabled and 2 disabled ecmpids!
2017/04/16 19:21:52 7FFE1658 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C4 PROVID 000000 ECMPID 1B66 ANY CHID PMTPID 0068 VPID 08FF
2017/04/16 19:21:57 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:3E4A83ED0E78C34F835248AA4CB24AB5): timeout (5001 ms) by Remote_cccam
2017/04/16 19:21:59 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:B501987A86F9BF127EE7C918AC46A2CF): timeout (5004 ms) by Remote_cccam
2017/04/16 19:21:59 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:B501987A86F9BF127EE7C918AC46A2CF): not found (1007 ms) by Remote_cccam
2017/04/16 19:21:59 7FFE1658 c (dvbapi) Demuxer 0 restarting decodingrequests after 0 ms with 1 enabled and 2 disabled ecmpids!
2017/04/16 19:21:59 7FFE1658 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C4 PROVID 000000 ECMPID 1B66 ANY CHID PMTPID 0068 VPID 08FF
2017/04/16 19:22:03 7FFE1658 c (dvbapi) Demuxer 0 stop descrambling program number 000C (09C4@000000:000C unknown)
2017/04/16 19:22:04 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:B501987A86F9BF127EE7C918AC46A2CF): timeout (5004 ms) by Remote_cccam
2017/04/16 19:22:05 7FFE1658 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 09C4 ECM_PID: 1B20 PROVID: 000000
2017/04/16 19:22:05 7FFE1658 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 098C ECM_PID: 1A20 PROVID: 000000
2017/04/16 19:22:05 7FFE1658 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 09AF ECM_PID: 1F20 PROVID: 000000
2017/04/16 19:22:05 7FFE1658 c (dvbapi) Demuxer 0 found 3 ECMpids and 2 STREAMpids in caPMT
2017/04/16 19:22:05 7FFE1658 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C4 PROVID 000000 ECMPID 1B20 ANY CHID PMTPID 006C VPID 0CFF
2017/04/16 19:22:05 7FFE1658 c (dvbapi) Demuxer 0 found 3 ECMpids and 2 STREAMpids in PMT
2017/04/16 19:22:05 7FFE1658 c (dvbapi) Demuxer 0 stop descrambling program number 000E (09C4@000000:000E unknown)
2017/04/16 19:22:05 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:13153BF950932BFD97F4776932845B71): timeout (5001 ms) by Remote_cccam
2017/04/16 19:22:06 7FFE1658 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 09C4 ECM_PID: 1B66 PROVID: 000000
2017/04/16 19:22:06 7FFE1658 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 098C ECM_PID: 1A66 PROVID: 000000
2017/04/16 19:22:06 7FFE1658 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 09AF ECM_PID: 1F66 PROVID: 000000
2017/04/16 19:22:06 7FFE1658 c (dvbapi) Demuxer 0 found 3 ECMpids and 3 STREAMpids in caPMT
2017/04/16 19:22:06 7FFE1658 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C4 PROVID 000000 ECMPID 1B66 ANY CHID PMTPID 0068 VPID 08FF
2017/04/16 19:22:06 7FFE1658 c (dvbapi) Demuxer 0 found 3 ECMpids and 3 STREAMpids in PMT
2017/04/16 19:22:10 7FFE1658 c (ecm) local (09C4@000000/0197/000E/B3:4295AEA66E775B0CD9BACFC44F654A89): timeout (5001 ms) by Remote_cccam
2017/04/16 19:22:10 7FFE1658 c (dvbapi) Demuxer 0 stop descrambling program number 000C (09C4@000000:000C unknown)
2017/04/16 19:22:11 7FFE1658 c (ecm) local (09C4@000000/00ED/000C/B3:13153BF950932BFD97F4776932845B71): timeout (5002 ms) by Remote_cccam
2017/04/16 19:22:11 7FFE1658 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 09C4 ECM_PID: 1B22 PROVID: 000000

Meine Config schaut minimal aus:

oscam.conf

Spoiler

[global]
logfile = /var/log/oscam.log

[dvbapi]
enabled = 1
au = 1
user = local
boxtype = dreambox

[webif]
httpport = 83
httpuser = oscam
httppwd = oscam
httpallowed = 127.0.0.1,192.168.0.0-192.168.255.255
http_status_log = 1

oscam.server

Spoiler

[reader]
label = Remote_cccam
protocol = cccam
device = xxx.xxx.xxx.xx,12275
user = xxxxxxx
password = xxxxxxx
group = 1
cccversion = 2.3.0
cccwantemu = 1
ccckeepalive = 1

oscam.user

Spoiler

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

Vielleicht fällt ja jemand spontan was auf. Wie gesagt mit cccam 2.3.0 keine Freezer nichts.

Gruß DarkSu

Edit:
Server hat OSCam r11294
Client Version 1.20-unstable_svn

 

[dodo83]

was kommt am Server zu der Zeit im log?
Config sieht ja OK aus

 

[DarkSu]

Werde ich nachher mal anschauen und posten. Bin im Moment nur gerade ausserhalb.

DarkSu

 

[DarkSu]

So, heute endlich Zeit gefunden das ganze nochmal zu beobachten. Heute nicht so schlimm wie gestern ab zumindest Freezer.

Logauszug Client:

Spoiler

2017/04/17 10:25:49 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:37EC0D020E366C5A1D59F6E1890A1F80): found (133 ms) by Remote_cccam
2017/04/17 10:25:56 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:66BD43097138F9F3BDE9F7B40070BA85): found (133 ms) by Remote_cccam
2017/04/17 10:26:08 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:F5AC1406435A621AC2656CBB50F1F6DB): timeout (5001 ms) by Remote_cccam
2017/04/17 10:26:13 0F2D4638 c (-) -- Skipped 1 duplicated log lines --
2017/04/17 10:26:13 0F2D4638 c (dvbapi) Demuxer 0 restarting decodingrequests after 0 ms with 1 enabled and 2 disabled ecmpids!
2017/04/17 10:26:13 0F2D4638 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C4 PROVID 000000 ECMPID 1B22 ANY CHID PMTPID 006B VPID 0BFF
2017/04/17 10:26:15 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3494EF65FEA3B322D6F12B9578539E16): timeout (5002 ms) by Remote_cccam
2017/04/17 10:26:15 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3494EF65FEA3B322D6F12B9578539E16): not found (993 ms) by Remote_cccam
2017/04/17 10:26:15 0F2D4638 c (dvbapi) Demuxer 0 restarting decodingrequests after 0 ms with 1 enabled and 2 disabled ecmpids!
2017/04/17 10:26:15 0F2D4638 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C4 PROVID 000000 ECMPID 1B22 ANY CHID PMTPID 006B VPID 0BFF
2017/04/17 10:26:20 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3494EF65FEA3B322D6F12B9578539E16): timeout (5001 ms) by Remote_cccam
2017/04/17 10:26:22 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:4E0142B9ACCDF54110D447CF6475F43A): timeout (5001 ms) by Remote_cccam
2017/04/17 10:26:22 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:4E0142B9ACCDF54110D447CF6475F43A): not found (1256 ms) by Remote_cccam
2017/04/17 10:26:22 0F2D4638 c (dvbapi) Demuxer 0 restarting decodingrequests after 0 ms with 1 enabled and 2 disabled ecmpids!
2017/04/17 10:26:22 0F2D4638 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C4 PROVID 000000 ECMPID 1B22 ANY CHID PMTPID 006B VPID 0BFF
2017/04/17 10:26:23 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:4E0142B9ACCDF54110D447CF6475F43A): found (895 ms) by Remote_cccam
2017/04/17 10:26:24 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:E79200770FB0E3CFF843821D44DB1282): found (134 ms) by Remote_cccam
2017/04/17 10:26:31 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:E7808D096C8B60A57DB6E2080A011BDB): found (133 ms) by Remote_cccam
2017/04/17 10:26:38 0F2D4638 c (ecm) local (09C4@000000/0198/000D/B3:F18BF09CE56E1E7810117F9CACA49D15): found (132 ms) by Remote_cccam

Logauszug Server:

Spoiler

2017/04/17 10:24:53 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:AFC47C3A684B83C6A087CA8A40A016A4:0F06000000000000:Sky Starter): found (127 ms) by V13 - National Geographic Germany
2017/04/17 10:25:00 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:16C9F140A00720318FC1AC251B00CDBF:0F06000000000000:Sky Starter): found (126 ms) by V13 - National Geographic Germany
2017/04/17 10:25:07 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:599A16C01D4DB11491FC81B81C7D67C8:0F06000000000000:Sky Starter): found (124 ms) by V13 - National Geographic Germany
2017/04/17 10:25:14 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:873602884615C1B258F297646C54B325:0F06000000000000:Sky Starter): found (127 ms) by V13 - National Geographic Germany
2017/04/17 10:25:21 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:21C248DC22F19F2D25743373ABAB1A7D:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:25:28 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:E6E6E691D31A899AE97B5CAC899DC655:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:25:35 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:6BD0692482A7CFC5EBDC7BFA1147AF99:0F06000000000000:Sky Starter): found (129 ms) by V13 - National Geographic Germany
2017/04/17 10:25:42 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:38ED020A18D8BF01EDCFACC7CBB07EF0:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:25:49 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:37EC0D020E366C5A1D59F6E1890A1F80:0F06000000000000:Sky Starter): found (126 ms) by V13 - National Geographic Germany
2017/04/17 10:25:56 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:66BD43097138F9F3BDE9F7B40070BA85:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany

2017/04/17 10:26:24 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:F5AC1406435A621AC2656CBB50F1F6DB:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:26:24 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:F5AC1406435A621AC2656CBB50F1F6DB:0F06000000000000:Sky Starter): cache2 (124 ms) by V13 - National Geographic Germany
2017/04/17 10:26:24 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3494EF65FEA3B322D6F12B9578539E16:0F06000000000000:Sky Starter): found (251 ms) by V13 - National Geographic Germany
2017/04/17 10:26:24 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3494EF65FEA3B322D6F12B9578539E16:0F06000000000000:Sky Starter): cache2 (252 ms) by V13 - National Geographic Germany
2017/04/17 10:26:24 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:4E0142B9ACCDF54110D447CF6475F43A:0F06000000000000:Sky Starter): found (375 ms) by V13 - National Geographic Germany
2017/04/17 10:26:24 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:4E0142B9ACCDF54110D447CF6475F43A:0F06000000000000:Sky Starter): cache2 (377 ms) by V13 - National Geographic Germany
2017/04/17 10:26:24 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:E79200770FB0E3CFF843821D44DB1282:0F06000000000000:Sky Starter): found (127 ms) by V13 - National Geographic Germany

2017/04/17 10:26:31 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:E7808D096C8B60A57DB6E2080A011BDB:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:26:38 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:F18BF09CE56E1E7810117F9CACA49D15:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:26:45 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:6394538907910815B3D2CD66DC3C1CB1:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:26:52 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:0506CE2131E61DED9D12A4A52B52ABB1:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:26:59 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:F5C46B88620765D0A46960F91477F957:0F06000000000000:Sky Starter): found (125 ms) by V13 - National Geographic Germany
2017/04/17 10:27:06 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:30D806E6A706373B59366F642FB72C0D:0F06000000000000:Sky Starter): found (126 ms) by V13 - National Geographic Germany
2017/04/17 10:27:13 1F2B84CB c (ecm) xxxxxxxx (09C4@000000/0198/000D/B3:10BA2B53DEC8739688DA71F326433670:0F06000000000000:Sky Starter): found (126 ms) by V13 - National Geographic Germany

Ich hab im Log des Servers mal 2 Leerzeichen eingefügt das man besser sieht wo er wohl stolpert. Ausserdem sind mir die Zeilen mit cache2 aufgefallen. Nur wie sind die zu deuten?

Gruß DarkSu

 

[supraracer]

Alle Clients von CCcam auf OScam umgestellt und die haben jetzt alle Freezer, ansonsten nichts geändert? Das mag ich fast nicht glauben. Was sind das für Clients? Wie sind die an den Server angebunden?

-supraracer

 

[DarkSu]

Es geht um 2 Gigablue Boxen im HS die openATV drauf haben. Die eine per WLAN, die andere per DLAN angebunden zu einer Fritte an der der Pi hängt. Nicht ideal, aber bei der cccam waren die freezer eben nicht. Seltsam, da ja das selbe Protokoll verwendet wird.

DarkSu

Update:

Auch wenn ich Oscam mit dem cs378x Protocol verwende sind Freezer da. Wenn ich cccam 2.3.0 als Cam verwende ist alles ok.

Prinzipiell ja kein Problem weiterhin die cccam als client zu verwenden, dennoch ein etwas seltsames Phänomen.

DarkSu

 

[webman2]

Den Grund für die freezer hast Du beschrieben:

Spoiler: freezer

Es geht um 2 Gigablue Boxen im HS die openATV drauf haben. Die eine per WLAN, die andere per DLAN angebunden zu einer Fritte an der der Pi hängt.

Bei der DLAN-Verbindung könnte der Energiesparmodus der Module die Probleme bereiten.

 

[bubbl]

Ich frage mich nur warum geht es mit cccam? Dort sollte es damit auch freezern

 

[webman2]

Dazu müsste man die Konfigs von server und der clients mal sehen.
Oben hat er nur die der clients gepostet.

 

[DarkSu]

Die configs des Servers sind kein Geheimnis .. hi
Hier mal zum drüber schauen ..

oscam.conf Server

Spoiler

[global]
logfile = /usr/local/log/oscam/oscam.log
disableuserfile = 0
usrfileflag = 1
fallbacktimeout = 2000
nice = -1
maxlogsize = 500
preferlocalcards = 1
dropdups = 1
usrfile = /usr/local/log/oscam/oscamuser.log
cwlogdir = /usr/local/log/oscam/cw
emmlogdir = /usr/local/log/oscam/emm

[cache]

[cs357x]
port = 30001

[cs378x]
port = 34001

[newcamd]
port = 31011@09C4:000000
key = xxxxxxxxxxxxxxxxxxxxxxxxx
keepalive = 1

[cccam]
port = 12275
nodeid = xxxxxxxxxxxxxxxxxx
version = 2.3.0
reshare = 2

[webif]
httpport = 83
httphelplang = de
httprefresh = 30
httppollrefresh = 30
httpallowed = 127.0.0.1,192.168.0.0-192.168.255.255

oscam.server Server

Spoiler

[reader]
label = V13
protocol = mouse
device = /dev/ttyUSB0
caid = 09C4
boxid = xxxxxxxx
ins7e11 = 15
detect = cd
group = 1
blockemm-unknown = 1
blockemm-u = 1
blockemm-s = 1
blockemm-g = 1
saveemm-u = 1
ndsversion = 2

oscam.user Server

Spoiler

[account]
user = Room1
pwd = xxxxxxxx
au = V13
group = 1
cccmaxhops = 1

[account]
user = Room2
pwd = xxxxxxxx
au = V13
group = 1
cccmaxhops = 1

Greetz DarkSu

Edit: Und nochmal die Behauptung "Bei Cccam keinerlei Freezer" ... DLAN hatte ich ja auch schon in Verdacht. Da aber Cccam funktioniert kann es das fast nicht sein.

 

[webman2]

Mir fällt auf, dass Du im Reader der V13 keine clocking-Werte vorgibst.
Die sollten so konfiguriert sein, wie Du sie an der Easymouse auch eingestellt hast.

Die Oscam-Dateien sind für cccam eingerichtet, da gibt es ja keine freezer.
Sind die Oscam-Versionen auch jeweils aktuell ?

 

[DarkSu]

Danke, die clocking-werte kann ich ja mal eingeben. Schaden kann es ja nicht.

Ob die Versionen aktuell sind? Hmmm ... Siehe Post #1 ... neuer ging es sicher für die Box. War aber keine neuere im Feed.

DarkSu

 

[webman2]

Server hat OSCam r11294 .. und die clients ?
Wenn es eine Version mindestens > SVN9848 ist sollte es passen.

 

[DarkSu]

Client hat:

Version: oscam-1.20-unstable_svn-r11214
Compiler: mipsel-openpli40-linux-gnu

DarkSu

 

[webman2]

Kann es vielleicht sein, dass Du das Protokoll cs357x (UDP) für das Anbinden der clients verwendet hast und doch nicht das cs378x (TCP) ? Das UDP-Protokoll ist für Echtzeitanwendungen konzipiert, für verbindungsorientierte Verbindungen ist das TCP-Protokoll geeigneter.

Bitte mal überprüfen.