HD04: Alle paar Tage Resync error

[Wurststulle1]

Moin zusammen,

ich benutze einen Raspberry Pi Model B (Rev. 2) mit nem "Smartmouse / Easymouse 2 USB Premium Programmer" (ASIN B004HRUI5O).
Ich betreibe es per IPC und eigentlich funktioniert auch alles, nur habe ich ca. alle 1–3 Tage ein Problem, bei dem das Bild für einen Zyklus hängt und OSCam den Reader neustartet.

Spoiler: Log: OSCam-Start

-------------------------------------------------------------------------------
>> OSCam <<  cardserver log switched at Fri Dec 28 19:33:31 2018
-------------------------------------------------------------------------------
2018/12/28 19:33:31 00000000 s >> OSCam <<  cardserver log switched, version 1.20_svn, build r11440 (arm-linux-gnueabihf)
2018/12/28 19:33:31 00000000 s     (main) System name    = Linux
2018/12/28 19:33:31 00000000 s     (main) Host name      = raspberrypi-brev2
2018/12/28 19:33:31 00000000 s     (main) Release        = 4.14.79+
2018/12/28 19:33:31 00000000 s     (main) Version        = #1159 Sun Nov 4 17:28:08 GMT 2018
2018/12/28 19:33:31 00000000 s     (main) Machine        = armv6l
2018/12/28 19:33:31 00000000 s     (main) creating pidfile /tmp/.oscam/oscam.pid with pid 365
2018/12/28 19:33:31 00000000 s   (config) services reloaded: 0 services freed, 0 services loaded, rejected 0
2018/12/28 19:33:31 00000000 s   (config) userdb reloaded: 3 accounts loaded, 0 expired, 2 disabled
2018/12/28 19:33:31 00000000 s     (main) signal handling initialized
2018/12/28 19:33:31 00000000 s   (config) 2 provid's loaded
2018/12/28 19:33:31 00000000 s   (config) 25 service-id's loaded in 6 ms
2018/12/28 19:33:31 00000000 s      (net) newcamd: initialized (fd=5, port=22000)
2018/12/28 19:33:31 00000000 s      (net) -> CAID: 186A PROVID: 003411
2018/12/28 19:33:31 00000000 s      (net) gbox: initialized (fd=6, port=7004)
2018/12/28 19:33:31 00000000 s   (reader) HD+ [mouse] creating thread for device /dev/ttyUSB0
2018/12/28 19:33:31 00000000 s     (stat) loadbalancer: could not open /tmp/.oscam/stat for reading (errno=2 No such file or directory)
2018/12/28 19:33:31 00000000 s     (main) waiting for local card init
2018/12/28 19:33:31 25C5E64F h    (webif) webif: decompressed 177774 bytes back into 437292 bytes
2018/12/28 19:33:31 25C5E64F h    (webif) HTTP Server running. ip=0.0.0.0 port=8888
2018/12/28 19:33:31 66E70EEC r   (reader) HD+ [mouse] Buffers readed  0 bytes total time_us 75455
2018/12/28 19:33:31 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 1 value 03 time_us 5347
2018/12/28 19:33:31 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 2 value 00 time_us 14820
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 3 value 56 time_us 16425
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 4 value ff time_us 15739
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 5 value 00 time_us 82
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 6 value 76 time_us 14250
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 7 value 7e time_us 16034
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 8 value 71 time_us 80
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 9 value 80 time_us 15401
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 10 value 15 time_us 14651
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 11 value ff time_us 82
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 12 value dd time_us 15377
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 13 value 8d time_us 15958
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 14 value 7d time_us 82
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 15 value 35 time_us 14104
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 16 value f5 time_us 15918
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 17 value d3 time_us 15980
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 18 value b3 time_us 78
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 19 value f3 time_us 15534
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 20 value fb time_us 14724
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 21 value b5 time_us 81
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 22 value 59 time_us 15450
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 23 value 91 time_us 15812
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 24 value 35 time_us 83
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 25 value 93 time_us 14243
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 26 value f3 time_us 15967
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Flush readed byte Nr 27 value 17 time_us 82
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Buffers readed  27 bytes total time_us 371746
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Reader initialized (device=/dev/ttyUSB0, detect=cd, mhz=357, cardmhz=357)
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] card detected
2018/12/28 19:33:32 66E70EEC r   (reader) HD+ [mouse] Buffers readed  0 bytes total time_us 75293
2018/12/28 19:33:34 66E70EEC r   (reader) HD+ [mouse] ATR: 3F FF 95 00 FF 91 81 71 FE 57 00 44 4E 41 53 50 34 32 30 20 52 65 76 53 36 30 17
2018/12/28 19:33:34 66E70EEC r   (reader) HD+ [mouse] HD-Plus 04 recognized
2018/12/28 19:33:34 66E70EEC r   (reader) HD+ [mouse] Setting baudrate to 111600 bps
2018/12/28 19:33:34 66E70EEC r   (reader) HD+ [mouse] Buffers readed  0 bytes total time_us 75298
2018/12/28 19:33:35 66E70EEC r   (reader) HD+ [mouse] Rom revision: DNASP420 RevS60
2018/12/28 19:33:37 66E70EEC r   (reader) HD+ [mouse] Card expire date: 2019/02/15
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [mouse] found card system Nagra_Merlin
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] THIS WAS A SUCCESSFUL START ATTEMPT No  1 out of max alloted of 1
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] ROM:    D N A S P 4 2 0
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] REV:    R e v S 6 0
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] SER:    ########  ##########
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] CAID:   186A
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] Prv.ID: 00 00 34 11 (sysid)
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] IRD ID: FF FF FF FF
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] -----------------------------------------
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] |id  |tier    |valid from  |valid to    |
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] +----+--------+------------+------------+
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] |8011|006A    |2018/12/22  |2019/12/22  |
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] |8011|006B    |2013/12/31  |2014/01/01  |
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] -----------------------------------------
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] Prv.ID: 00 00 00 00
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] Prv.ID: 00 00 80 11
2018/12/28 19:33:38 66E70EEC r   (reader) HD+ [Nagra_Merlin] ready for requests
2018/12/28 19:33:38 00000000 s     (main) init for all local cards done
2018/12/28 19:33:38 00000000 s (emmcache) loaded 86 emmcache records from /var/etc/oscam.emmcache in 7 ms
2018/12/28 19:33:38 00000000 s (emmcache) loaded 0 emmstat records from /var/etc/oscam.emmstat in 1 ms
2018/12/28 19:33:38 00000000 s (anticasc) anti cascading disabled
2018/12/28 19:33:38 060384C8 c   (client) plain dvbapi-client granted (dvbapi, au=auto (4 reader))
2018/12/28 19:33:38 060384C8 c   (dvbapi) dvbapi channelcache loaded from /var/etc/oscam.ccache
2018/12/28 19:33:38 060384C8 c   (dvbapi) Using TCP listen socket, API forced to DVBAPIv3 (0), userconfig boxtype: 11
2018/12/28 19:33:39 060384C8 c   (dvbapi) Client connected: 'mdvbapi v0.19' (protocol version = 2)

Spoiler: Log: Während des Problems

2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 0 CAID: 1830 ECM_PID: 1CEC PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 1 CAID: 1843 ECM_PID: 19EC PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 2 CAID: 09C4 ECM_PID: 1BEC PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 3 CAID: 098C ECM_PID: 1AEC PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 4 CAID: 1860 ECM_PID: 1EEC PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 5 CAID: 186A ECM_PID: 1DEC PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 6 CAID: 098D ECM_PID: 18EC PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 7 CAID: 186D ECM_PID: 1DED PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 8 CAID: 0500 ECM_PID: 1FEC PROVID: 051900
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 ecmpid 9 CAID: 06CB ECM_PID: 1BED PROVID: 000000
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 found 10 ECMpids and 4 STREAMpids in caPMT
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 trying to descramble PID 5 CAID 186A PROVID 000000 ECMPID 1DEC ANY CHID PMTPID 0000 VPID 01FF
2018/12/29 02:11:01 060384C8 c   (dvbapi) Demuxer 0 found 10 ECMpids and 4 STREAMpids in PMT
2018/12/29 02:11:01 66E70EEC r   (reader) HD+ [Nagra_Merlin] renew Session Key: CAS7
2018/12/29 02:11:01 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:83CCC8927F24AD20936713BC103A32Dx): found (167 ms) by HD+ - ProSieben HD
2018/12/29 02:11:08 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:14C125ACF35AF4942552E8AA5F2B328x): found (164 ms) by HD+ - ProSieben HD

(bis hier normaler Betrieb)

2018/12/29 02:40:53 66E70EEC r   (reader) HD+ [Nagra_Merlin] renew Session Key: CAS7
2018/12/29 02:40:53 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:B6731DD978657C045EF5C17447CBE7Cx): found (165 ms) by HD+ - ProSieben HD
2018/12/29 02:41:06 66E70EEC r   (reader) HD+ [Nagra_Merlin] Resync error: readtimeouts 0/0 (max/min) us, writetimeouts 0/0 (max/min) us
2018/12/29 02:41:08 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): timeout (301 ms) by HD+ - ProSieben HD

(hier jede Menge timeout-Zeilen)

2018/12/29 02:41:19 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): timeout (300 ms) by HD+ - ProSieben HD
2018/12/29 02:41:19 66E70EEC r   (reader) HD+ [Nagra_Merlin] T1 Resync command error, trying to reactivate!
2018/12/29 02:41:19 66E70EEC r   (reader) HD+ [Nagra_Merlin] Buffers readed  0 bytes total time_us 75235
2018/12/29 02:41:19 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): timeout (301 ms) by HD+ - ProSieben HD
2018/12/29 02:41:20 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): timeout (300 ms) by HD+ - ProSieben HD
2018/12/29 02:41:20 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): timeout (301 ms) by HD+ - ProSieben HD
2018/12/29 02:41:20 66E70EEC r   (reader) HD+ [Nagra_Merlin] ATR: 3F FF 95 00 FF 91 81 71 FE 57 00 44 4E 41 53 50 34 32 30 20 52 65 76 53 36 30 17
2018/12/29 02:41:20 66E70EEC r   (reader) HD+ [Nagra_Merlin] HD-Plus 04 recognized
2018/12/29 02:41:20 66E70EEC r   (reader) HD+ [Nagra_Merlin] Setting baudrate to 111600 bps
2018/12/29 02:41:20 66E70EEC r   (reader) HD+ [Nagra_Merlin] Buffers readed  0 bytes total time_us 75303
2018/12/29 02:41:20 66E70EEC r   (reader) HD+ [Nagra_Merlin] (ECM) Reader will be restart now cause: 6F 01 card answer!!!
2018/12/29 02:41:20 66E70EEC r   (reader) HD+ [Nagra_Merlin] renew Session Key: CAS7
2018/12/29 02:41:20 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:20 66E70EEC r   (reader) HD+ [Nagra_Merlin] renew Session Key: CAS7
2018/12/29 02:41:21 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (1 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:21 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:21 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (1 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:22 66E70EEC r        (-) -- Skipped 1 duplicated log lines --
2018/12/29 02:41:22 66E70EEC r   (reader) HD+ [Nagra_Merlin] creating thread for device /dev/ttyUSB0
2018/12/29 02:41:22 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Buffers readed  0 bytes total time_us 75212
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 1 value 03 time_us 5576
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 2 value 00 time_us 15907
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 3 value 56 time_us 14702
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 4 value ff time_us 15952
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 5 value 00 time_us 80
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 6 value 76 time_us 15472
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 7 value 7e time_us 14672
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 8 value 71 time_us 81
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 9 value 80 time_us 15308
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 10 value 15 time_us 15959
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 11 value ff time_us 77
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 12 value dd time_us 14296
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 13 value 8d time_us 15960
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 14 value 7d time_us 76
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 15 value 35 time_us 15421
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 16 value f5 time_us 14706
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 17 value d3 time_us 15951
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 18 value b3 time_us 79
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 19 value f3 time_us 15492
2018/12/29 02:41:22 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (1 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 20 value fb time_us 14735
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 21 value b5 time_us 76
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 22 value 59 time_us 15458
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 23 value 91 time_us 15975
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 24 value 35 time_us 81
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 25 value 93 time_us 14060
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 26 value f3 time_us 15943
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Flush readed byte Nr 27 value 17 time_us 77
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Buffers readed  27 bytes total time_us 368842
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] Reader initialized (device=/dev/ttyUSB0, detect=cd, mhz=357, cardmhz=357)
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [Nagra_Merlin] card detected
2018/12/29 02:41:22 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:22 29216BA5 r   (reader) HD+ [mouse] Buffers readed  0 bytes total time_us 75212
2018/12/29 02:41:22 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (1 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:23 060384C8 c        (-) -- Skipped 1 duplicated log lines --
2018/12/29 02:41:23 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:1C4F31C5916CE065839382A731D0D01x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:23 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:23 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (1 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:24 29216BA5 r        (-) -- Skipped 1 duplicated log lines --
2018/12/29 02:41:24 29216BA5 r   (reader) HD+ [mouse] ATR: 3F FF 95 00 FF 91 81 71 FE 57 00 44 4E 41 53 50 34 32 30 20 52 65 76 53 36 30 17
2018/12/29 02:41:24 29216BA5 r   (reader) HD+ [mouse] HD-Plus 04 recognized
2018/12/29 02:41:24 29216BA5 r   (reader) HD+ [mouse] Setting baudrate to 111600 bps
2018/12/29 02:41:24 29216BA5 r   (reader) HD+ [mouse] Buffers readed  0 bytes total time_us 75229
2018/12/29 02:41:24 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (1 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:24 060384C8 c        (-) -- Skipped 2 duplicated log lines --
2018/12/29 02:41:24 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:25 29216BA5 r   (reader) HD+ [mouse] Rom revision: DNASP420 RevS60
2018/12/29 02:41:25 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:25 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (1 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:25 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:27 29216BA5 r        (-) -- Skipped 7 duplicated log lines --
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [mouse] Card expire date: 2019/02/15
2018/12/29 02:41:27 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (1 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [mouse] found card system Nagra_Merlin
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] THIS WAS A SUCCESSFUL START ATTEMPT No  1 out of max alloted of 1
2018/12/29 02:41:27 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] ROM:    D N A S P 4 2 0
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] REV:    R e v S 6 0
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] SER:    ########  ##########
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] CAID:   186A
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] Prv.ID: 00 00 34 11 (sysid)
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] IRD ID: FF FF FF FF
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] -----------------------------------------
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] |id  |tier    |valid from  |valid to    |
2018/12/29 02:41:27 29216BA5 r   (reader) HD+ [Nagra_Merlin] +----+--------+------------+------------+
2018/12/29 02:41:28 29216BA5 r   (reader) HD+ [Nagra_Merlin] |8011|006A    |2018/12/22  |2019/12/22  |
2018/12/29 02:41:28 29216BA5 r   (reader) HD+ [Nagra_Merlin] |8011|006B    |2013/12/31  |2014/01/01  |
2018/12/29 02:41:28 29216BA5 r   (reader) HD+ [Nagra_Merlin] -----------------------------------------
2018/12/29 02:41:28 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): rejected group (0 ms) - ProSieben HD (no matching reader)
2018/12/29 02:41:28 29216BA5 r   (reader) HD+ [Nagra_Merlin] Prv.ID: 00 00 00 00
2018/12/29 02:41:28 29216BA5 r   (reader) HD+ [Nagra_Merlin] Prv.ID: 00 00 80 11
2018/12/29 02:41:28 29216BA5 r   (reader) HD+ [Nagra_Merlin] ready for requests
2018/12/29 02:41:28 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:061700DF6CB660A91DB5F51A400F9E9x): found (167 ms) by HD+ - ProSieben HD
2018/12/29 02:41:38 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:60CDD4366CE7FCCCAD97076B15D0508x): found (166 ms) by HD+ - ProSieben HD
2018/12/29 02:41:53 060384C8 c      (ecm) dvbapi (186A@000000/0000/EF75/8F:D1C615A9BFF1A95304613AED43CEACEx): found (168 ms) by HD+ - ProSieben HD

(hier wieder normaler Betrieb)

Die Global-EMM-Zeilen habe ich aus dem Log der Übersichtkeit halber gelöscht. Momentan werden die globalen EMMs testweise sowieso alle geblockt. Hat aber nicht geholfen. Auch höhere Timeout-Zeiten (bspw. 5000ms) in der Config machen keinen Unterschied.

Spoiler: oscam.conf

# oscam.conf generated automatically by Streamboard OSCAM 1.20_svn SVN r11440
# Read more: http://www.streamboard.tv/svn/oscam/trunk/Distribution/doc/txt/oscam.conf.txt

[global]
loghistorylines               = 1024
logfile                       = /var/log/ipc/OScam.log
clienttimeout                 = 300
maxlogsize                    = 99999
readerrestartseconds          = 1
emmlogdir                     = /var/log/ipc/emm/

[cache]

[dvbapi]
enabled                       = 1
au                            = 1
pmt_mode                      = 1
listen_port                   = 2000
delayer                       = 200
user                          = dvbapi
read_sdt                      = 1
write_sdt_prov                = 1
boxtype                       = pc

[webif]
httpport                      = ...
httpuser                      = ...
httppwd                       = ...
httprefresh                   = 15
httpallowed                   = 0.0.0.0,127.0.0.1,192.168.0.0-192.168.255.255,10.0.0.0-10.255.255.255,172.16.0.0-172.31.255.255
httpemmsclean                 = 256
httpemmgclean                 = 256

Spoiler: oscam.user

# oscam.user generated automatically by Streamboard OSCAM 1.20_svn SVN r11440
# Read more: http://www.streamboard.tv/svn/oscam/trunk/Distribution/doc/txt/oscam.user.txt

[account]
user                          = dvbapi
au                            = 1
group                         = 1,2

Spoiler: oscam.server

# oscam.server generated automatically by Streamboard OSCAM 1.20_svn SVN r11440
# Read more: http://www.streamboard.tv/svn/oscam/trunk/Distribution/doc/txt/oscam.server.txt

[reader]
label                         = HD+
protocol                      = mouse
device                        = /dev/ttyUSB0
caid                          = 186A
mod1                          = 9D...
data50                        = B6...
mod50                         = DB...
key60                         = 99...
exp60                         = 26...
nuid                          = 84...
cwekey                        = 1B...
detect                        = cd
group                         = 1
emmcache                      = 1,1,14,0
blockemm-g                    = 1
saveemm-u                     = 1
saveemm-s                     = 1
saveemm-g                     = 1

Spoiler: oscam.dvbapi

P: 186A 1
I: 0

Die meiste Zeit läuft es wie gesagt ohne diesen Fehler. Auffällig ist, dass es WENN, dann immer kurz nach der Zeile [Nagra_Merlin] renew Session Key: CAS7 passiert.
Zuvor habe ich mit der gleichen Hardware eine Kombi aus einem alten OScam und gbox benutzt (hiermit gab es dieses Problem nie), bevor ich kürzlich ein neues raspbian-Image drübergebügelt und IPC installiert habe.

Irgendwelche Ideen, woran es liegen könnte? Wäre für alle Tipps sehr dankbar!

 

[Wurststulle1]

Ich habe inzwischen mal alle USB-Kabel ausgewechselt und auch ein anderes Netzteil probiert, aber das Problem tritt noch immer auf.
Das Smartmouse-Gerät ist folgendermaßen konfiguriert: 5V, Phoenix, 3,579 MHz, Y5.

 

[dodo83]

aktualisiere mal die OScam,
oder teste auch mal auf 6MHZ, meine hat ab und zu auch "Aussetzer" aber nicht so oft

 

[Nacht^_^Eule]

Habe das selbe Problem, 1mal täglich ca (bei wenig TV geht's auch mal länger) Reader steht danach immer auf Needinit und nur ein manueller Reader Neustart hilft und die Karte wird wieder eingelesen mit ok....

 

[Wurststulle1]

Wenn ich das Gerät auf „→“ und „6 MHz“ stelle, bekomme ich leider nur (reader) HD+ [mouse] Error activating card.. Aber ich werde mal OSCam auf r11444 aktualisieren, danke

 

[dodo83]

würde mal den Parameter auf 2 oder 1 setzen und schauen

Sie müssen registriert sein, um Links zu sehen.

 

[Verdi-Fan]

Habe das selbe Problem, 1mal täglich ca (bei wenig TV geht's auch mal länger) Reader steht danach immer auf Needinit und nur ein manueller Reader Neustart hilft und die Karte wird wieder eingelesen mit ok....

Um Himmelswillen. Da machst du dir zuviel Arbeit. Bitte im Reader folgenden Eintrag ergänzen. Dann macht der diesen Neustart automatisch, und du merkst das gar nicht mehr.

ecmnotfoundlimit              = 5

Viel Erfolg!
VF

 

[Nacht^_^Eule]

Danke verdifan, habe den Eintrag schon aber nur mit 3, evtl ist das zu kurz und hängt dann, ich probiere es mal mit 5 aus...


Aber das löst ja nicht das Problem.... Ach hoffentlich ist die Karte bald abgelaufen dann nehme ich wieder die alte wenn es nicht richtig läuft....

 

[Verdi-Fan]

Wie gesagt, mit diesem Eintrag merke ich von dem Keywechsel nichts und die Karte läuft wochenlang. Dass das nur ein "Workaround" ist, das will ich nicht bestreiten.

Spoiler: HD04

[reader]
label = HD04
protocol = mouse
device = /dev/HD04
caid = 186A
ecmnotfoundlimit = 5
detect = cd
mhz = 600
group = 2
emmcache = 1,1,15,0
blockemm-u = 1
saveemm-u = 1

Hier mal im Kontext ...
Grüße
VF

 

[Wurststulle1]

mhz = 600

Wie sind die Switches von deiner Easymouse eingestellt?

 

[dodo83]

5V Phoenix Oben 6 MHZ

 

[_DIE_HARD_]

Für 6 MHz musst du den dritten Schalter auf Oben stellen und den vierten auf 6MHz (meist auch ganz Oben).

 

[Wurststulle1]

Hmm, genau so habe ich es versucht. Dazu manuell noch jeweils 600 bei MHz und CardMHz.
Klappt bei mir irgendwie nicht. Gibt es vielleicht verschiedene Arten von HD04-Karten oder woran liegt das?

 

[_DIE_HARD_]

Die CardMHz nicht ändern, das ist die nominale Frequenz für die Karte.

 

[Wurststulle1]

Ah, ich dachte das muss matchen, da lag wohl mein Fehler, danke!
Auch danke an alle anderen, ich werde das jetzt mal beobachten.