D02 Timeout

[agreen]

Hallo zusammen,

ich verwende einen PI als OSCAM Server und habe eine D02 Karte mit einer Smartcard angeschlossen.
Es funktioniert soweit alles, aber seit ca. 1 Woche bekomme ich einmal am Tag einen Timeout der Antwortzeiten.
Timeout 5000ms ...
Nach einem Neustart funktioniert alles wieder, aber leider nur für 1 - 2 Tage.
Hat jemand eine Idee?

Spoiler: Oscam.server

[reader]
label = d02
description = D02-KD
protocol = smartreader
device = Serial:Reader CAE9F10
caid = 1722,1834
boxkey = 1122334455667788
detect = cd
mhz = 600
cardmhz = 600
group = 2
emmcache = 1,1,2,0
saveemm-unknown = 1
saveemm-u = 1
saveemm-s = 1
saveemm-g = 1

und ja, ich weiss das ich keine Logs speichern muss

 

[FoooLs]

Poste bitte mal das Einlesen der Karte (ink. Start von oscam)

 

[4gx35]

seit wann muss man bei d02 emms speichern,
mach den unsinn mal weg

evtl. easymouse statt smargo testen

 

[datamen]

@4gx35 .... er hat doch extra dazu geschrieben das er die EMMs nur aus Spass speichert.

Wichtig zur Analyse wäre, wie oben schon erwähnt, dein oscam.log vom Start bis zum fertigen Einlesen der Karte und ein großzügiger Auschnitt aus dem Log, wenn es zu den Timeouts kommt.

 

[agreen]

hier der gewünschte Start Log

Spoiler: Log File

2017/03/15 21:50:59 00000000 s (main) Version = #911 Thu Sep 15 14:17:52 BST 2016
2017/03/15 21:50:59 00000000 s (main) Machine = armv6l
2017/03/15 21:50:59 00000000 s (main) creating pidfile /tmp/.oscam/oscam.pid with pid 566
2017/03/15 21:50:59 00000000 s (config) userdb reloaded: 14 accounts loaded, 0 expired, 0 disabled
2017/03/15 21:50:59 00000000 s (main) signal handling initialized
2017/03/15 21:50:59 00000000 s (config) 164 service-id's loaded in 5 ms
2017/03/15 21:50:59 00000000 s (net) cccam: initialized (fd=5, port=34044)
2017/03/15 21:50:59 00000000 s (reader) v14 [smartreader] creating thread for device Serial:Reader 64
2017/03/15 21:50:59 00000000 s (reader) d02 [smartreader] creating thread for device Serial:Reader CAE9F10
2017/03/15 21:50:59 00000000 s (stat) loadbalancer: could not open /tmp/.oscam/stat for reading (errno=2 No such file or directory)
2017/03/15 21:50:59 00000000 s (main) waiting for local card init
2017/03/15 21:50:59 52503168 h (webif) webif: decompressed 172807 bytes back into 413096 bytes
2017/03/15 21:51:00 52503168 h (webif) HTTP Server running. ip=0.0.0.0 port=17000
2017/03/15 21:51:00 34F83B31 r (reader) d02 [smartreader] Reader initialized (device=Serial:Reader CAE9F10, detect=cd, mhz= AUTO, cardmhz=600)
2017/03/15 21:51:00 23035CAC r (reader) v14 [smartreader] Reader initialized (device=Serial:Reader 64, detect=cd, mhz= AUTO, cardmhz=369)
2017/03/15 21:51:00 34F83B31 r (reader) d02 [smartreader] card detected
oguard2] ------------------------------------------------------------------
2017/03/15 21:51:23 34F83B31 r (reader) d02 [smartreader] ATR: 3B 9F 21 0E 49 52 xxxx
2017/03/15 21:51:23 34F83B31 r (reader) d02 [smartreader] Init card protocol T14, FI=2, F=558, D=1, N=0
2017/03/15 21:51:23 34F83B31 r (reader) d02 [smartreader] Calculated work ETU is 93.00 us reader mhz = 600
2017/03/15 21:51:23 34F83B31 r (reader) d02 [smartreader] Effective reader settings mhz =600 F= 558 D= 1 N=0 T=14 inv=0 parity=NONE
2017/03/15 21:51:24 34F83B31 r (reader) d02 [smartreader] ATR Fsmax is 6 MHz, clocking card to ATR Fsmax for smartreader cardspeed of 6.00 MHz (specified in reader->mhz)
2017/03/15 21:51:25 34F83B31 r (reader) d02 [smartreader] detect irdeto tunneled nagra card
2017/03/15 21:51:25 34F83B31 r (reader) d02 [smartreader] no rsa key configured -> using irdeto mode
2017/03/15 21:51:25 34F83B31 r (reader) d02 [smartreader] detect irdeto card
2017/03/15 21:51:25 34F83B31 r (reader) d02 [smartreader] caid: 1722, acs: 3.84, country code: TEL
2017/03/15 21:51:25 34F83B31 r (reader) d02 [smartreader] providers: 4, ascii serial: ##########, hex serial: ######, hex base: ##
2017/03/15 21:51:28 34F83B31 r (reader) d02 [smartreader] active providers: 2 (000000,000000)
2017/03/15 21:51:28 34F83B31 r (reader) d02 [smartreader] found card system irdeto
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] entitlements for provider: 2, id: 000000
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 05FB, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 0142, date: 2015/08/02 - 2016/04/12
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 0071, date: 2017/01/13 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 7D27, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 000F, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 0064, date: 2015/08/02 - 2016/04/12
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 05E7, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 05DD, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 05E8, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 0072, date: 2017/01/13 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 0106, date: 2017/01/13 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 010A, date: 2017/01/13 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 05DE, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 0073, date: 2017/01/13 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 05F1, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 05DF, date: 2017/01/23 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 02CB, date: 2017/01/13 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 0139, date: 2017/01/13 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 0147, date: 2015/08/02 - 2016/04/12
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] chid: 02CE, date: 2017/01/13 - 2017/05/04
2017/03/15 21:51:28 34F83B31 r (reader) d02 [irdeto] ready for requests
2017/03/15 21:51:29 00000000 s (main) init for all local cards done
2017/03/15 21:51:29 00000000 s (anticasc) anti cascading disabled
2017/03/15 21:51:52 2E551BC2 c (ecm) alex (1722@000000/0000/000B/93:67072B323253880415AE8F65AB0FED11): found (543 ms) by d02

das habe ich gemacht, weil ich eine zeitlang Probleme hatte die EMM zu verlängern, ich musste die manuell schreiben.

 

[dodo83]

und ein großzügiger Auschnitt aus dem Log, wenn es zu den Timeouts kommt.

das wär noch eventuell interessant

 

[agreen]

so hier die Logs, aber da erkennt man nix :-(

Spoiler: logs

2017/03/16 18:01:17 42510BE1 c (reader) d02 [irdeto] Successfully added EMM to /usr/local/etc/d02_global_emm.log
2017/03/16 18:01:23 42510BE1 c (ecm) xxx (1722@000000/0000/001B/93:41B18630033AB5141F3CA0A97DB5656E): found (599 ms) by d02
2017/03/16 18:02:32 124CF17F c (client) encrypted cccam-client 192.168.150.26 granted (walot, au=on (1 reader))
2017/03/16 18:02:32 42510BE1 c (client) xxx disconnected from 192.168.150.26
2017/03/16 18:03:15 75A2916A c (ecm) xxx (1722@000000/0000/D108/93F967FC1F9AE66E7BE6D5CFC9F05C903): found (600 ms) by d02(btun 1834)
2017/03/16 18:03:17 124CF17F c (reader) d02 [irdeto] Successfully added EMM to /usr/local/etc/d02_global_emm.log
2017/03/16 18:04:43 124CF17F c (ecm) xxx (1722@000000/0000/001B/93:2FE7C8F8A640150D2BC5D202397E837A): found (600 ms) by d02
2017/03/16 18:04:45 75A2916A c (ecm) xxx (1722@000000/0000/D108/93:33E78B120EE16DBFBFC48A7B82DE99C0): found (604 ms) by d02(btun 1834)
2017/03/16 18:04:48 6444384C c (ecm) onkel (1722@000000/0000/D035/93:8336DF4473DD491A9A08EB89D4C6AF92): found (560 ms) by d02(btun 1834)
2017/03/16 18:04:54 17D2B9A4 c (client) encrypted cccam-client 192.168.150.26 granted (walot, au=on (1 reader))
2017/03/16 18:04:54 124CF17F c (client) walot disconnected from 192.168.150.26
2017/03/16 18:04:55 17D2B9A4 c (ecm) walot (1722@000000/0000/001B/93:63171ECE1E6E95DCE9EAF3CE1B8219C9): found (602 ms) by d02(btun 1834)
2017/03/16 18:05:15 75A2916A c (ecm) eltern (1722@000000/0000/D108/93:577FF81E0A0C41B72A9296B44F349387): found (605 ms) by d02(btun 1834)
2017/03/16 18:05:16 17D2B9A4 c (reader) d02 [irdeto] Successfully added EMM to /usr/local/etc/d02_global_emm.log
2017/03/16 18:05:18 6444384C c (ecm) onkel (1722@000000/0000/D035/93:588AC6D6BFF4B57570978AD52521960B): found (555 ms) by d02(btun 1834)
2017/03/16 18:05:22 0AA94C40 c (client) encrypted cccam-client 192.168.150.26 granted (walot, au=on (1 reader))
2017/03/16 18:05:22 17D2B9A4 c (client) walot disconnected from 192.168.150.26
2017/03/16 18:05:24 0AA94C40 c (ecm) walot (1722@000000/0000/001B/93:0C0E1E4EAF99072C5AF8DA0CF883354E): found (600 ms) by d02(btun 1834)
2017/03/16 18:05:25 75A2916A c (ecm) eltern (1722@000000/0000/D108/93:2A67B11E680D2377FBCC21B886D21F7D): found (614 ms) by d02(btun 1834)
2017/03/16 18:07:46 75A2916A c (ecm) eltern (1722@000000/0000/D108/93:9A6890736A2D73C2273F97CAC752A9BF): found (607 ms) by d02(btun 1834)
2017/03/16 18:07:53 0AA94C40 c (ecm) walot (1722@000000/0000/001B/93:B3C044C197A0101F8C5F30380647AD91): found (600 ms) by d02(btun 1834)
2017/03/16 18:07:56 75A2916A c (ecm) eltern (1722@000000/0000/D108/93:2E87C38EA0BA6C4AE9CCCB5AA30C1871): found (604 ms) by d02(btun 1834)
2017/03/16 18:08:08 0AA94C40 c (ecm) walot (1722@000000/0000/001B/93:FBBD4FB71A69F444DC1DB7248622F40F): timeout (5001 ms) by d02(btun 1834)
2017/03/16 18:08:10 75A2916A c (ecm) eltern (1722@000000/0000/D108/93:FC166DCF393A5CA9F07001DB9E315632): timeout (5000 ms) by d02(btun 1834)
2017/03/16 18:08:13 0AA94C40 c (ecm) walot (1722@000000/0000/001B/93:85807A211B93AB0735A4F5A5B3992B0B): timeout (5001 ms) by d02
2017/03/16 18:08:16 75A2916A c (ecm) eltern (1722@000000/0000/D108/93:370361671766C1692A71B858F0E8A54A): timeout (5001 ms) by d02
B885D2 c (ecm) walot (1722@000000/0000/C389/93:A5D7A26550019C38F0ABC2F366307943): timeout (5001 ms) by d02(btun 1834)
2017/03/16 18:19:21 0DB885D2 c (ecm) walot (1722@000000/0000/C389/93:AB21061784143684729DE25ECF467719): timeout (5000 ms) by d02(btun 1834)
2017/03/16 18:19:31 0DB885D2 c (ecm) walot (1722@000000/0000/C389/93:44FA6D4B10E25BFE031CD193EE0E7ED7): timeout (5001 ms) by d02(btun 1834)
2017/03/16 18:19:36 0DB885D2 c (ecm) walot (1722@000000/0000/C389/93:52D45FDD672FD842034FA86309FF1699): timeout (5000 ms) by d02(btun 1834)
2017/03/16 18:19:47 0DB885D2 c (ecm) walot (1722@000000/0000/C389/93:69C0A2EB7CA6F3DD9D5A4014F47AB194): timeout (5001 ms) by d02(btun 1834)
2017/03/16 18:19:54 0FA145BF c (client) encrypted cccam-client 192.168.150.26 granted (walot, au=on (1 reader))

man sieht genau, dass die Antwortzeiten auf einmal extrem hoch gehen.
kann es sein, dass meine SD Karte spinnt?

 

[datamen]

... also so rein aus'm Bauch heraus würde ich auf Netzwerkprobleme tippen.

Warum connecten / disconnecten sich deine Clients so oft?

keepalive = 1 bei den Usern drin?

 

[agreen]

nur ein User hat dieses Problem, ich werde dort mal auf Oscam umstellen.
Aber Netzwerk kann es nicht sein bzw. kann ich mir nicht vorstellen.

 

[Pilot]

Aber Netzwerk kann es nicht sein bzw. kann ich mir nicht vorstellen.

Nicht vorstellen können ist eine Sache.
Mit langem Lankabel testen eine andere Sache.

 

[datamen]

nur ein User hat dieses Problem, ich werde dort mal auf Oscam umstellen.
Aber Netzwerk kann es nicht sein bzw. kann ich mir nicht vorstellen.

.... auf deine Vorstellungskraft würde ich jetzt mal nicht so sehr vertrauen

Mach mal bei dir - auf dem Oscam Server - bei jedem User ein "keepalive = 1" rein. Oscam neu starten und dann mal beobachten.

 

[agreen]

bin auf easymouse umgestiegen ... Problem somit erledigt