V14 + Oscam + vdr-plugin-dvbapi - keine EMMs?

[FloBo]

Moin,

ich versuche verzweifelt meine neue V14 in gange zu bekommen. Zuerst lief der Cardreader nicht mehr und nun bekomme ich keine EMMs. Mittlerweile habe ich einige confs ausprobiert und nix hat geholfen.

Das Oscam r10899 läuft auf einem RPI mit einer Smartmouse.
Der EMM Lieferant ist ein Ubuntu mit Kodi und VDR 2.2.0

oscam.conf

Spoiler

[global]
logfile = /var/log/oscam.log
nice = -1
maxlogsize = 256
preferlocalcards = 1
ecmfmt = c/s/l

[cache]

[cccam]
port = 55555
version = 2.3.0
reshare = 1

[dvbapi]
enabled = 1
au = 1
pmt_mode = 4
listen_port = 2000
user = vdr
boxtype = pc

[webif]
httpport = 8888
httphelplang = de
httprefresh = 10
httpallowed = 0.0.0.0-255.255.255.255
http_status_log = 1

oscam.user

Spoiler

[account]
disabled = 1
user = xbmc
pwd = xbmc
keepalive = 1
au = V14
group = 1,2
cccignorereshare = 0

[account]
user = vdr
caid = 098C
keepalive = 1
au = V14
emmreassembly = 1
group = 1,2
services = sky,sky-hd,sky-hdplus,sky3d,select_v14
cccignorereshare = 0

oscam.server

Spoiler

[reader]
label = V14
description = V14-Sky
protocol = mouse
device = /dev/ttyUSB0
services = sky,sky-hd,sky-hdplus,sky3d,select_v14
caid = 098C
boxid = 12345678
ins7e11 = 15
detect = cd
group = 1
emmcache = 1,2,10
blockemm-unknown = 1
blockemm-u = 1
blockemm-s = 1
blockemm-g = 1
saveemm-unknown = 1
saveemm-u = 1
ndsversion = 2

oscam.dvbapi

Spoiler

P:098C 1
I:0

oscam.log

Spoiler

-------------------------------------------------------------------------------
>> OSCam << cardserver log switched at Sat Jul 25 19:45:38 2015
-------------------------------------------------------------------------------
2015/07/25 19:45:38 00000000 s (main) Release = 3.12.35+
2015/07/25 19:45:38 00000000 s (main) Version = #730 PREEMPT Fri Dec 19 18:31:24 GMT 2014
2015/07/25 19:45:38 00000000 s (main) Machine = armv6l
2015/07/25 19:45:38 00000000 s (main) creating pidfile /tmp/.oscam/oscam.pid with pid 10274
2015/07/25 19:45:38 00000000 s (config) services reloaded: 0 services freed, 5 services loaded, rejected 0
2015/07/25 19:45:38 00000000 s (config) userdb reloaded: 2 accounts loaded, 0 expired, 1 disabled
2015/07/25 19:45:38 00000000 s (main) signal handling initialized
2015/07/25 19:45:38 00000000 s (config) 194 service-id's loaded in 3 ms
2015/07/25 19:45:38 00000000 >> OSCam << cardserver log switched, version 1.20-unstable_svn, build r10899 (arm-linux-gnueabihf)
2015/07/25 19:45:38 00000000 s (config) 114 tier-id's loaded
2015/07/25 19:45:38 00000000 s (net) cccam: initialized (fd=5, port=55555)
2015/07/25 19:45:38 00000000 s (reader) V14 [mouse] creating thread for device /dev/ttyUSB0
2015/07/25 19:45:38 00000000 s (stat) loadbalancer: could not open /tmp/.oscam/stat for reading (errno=2 No such file or directory)
2015/07/25 19:45:38 00000000 s (main) waiting for local card init
2015/07/25 19:45:38 4CA6DE95 h (webif) webif: decompressed 164087 bytes back into 391120 bytes
2015/07/25 19:45:38 4CA6DE95 h (webif) HTTP Server running. ip=0.0.0.0 port=8888
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Buffers readed 0 bytes total time_us 75267
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 1 value 03 time_us 6897
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 2 value 40 time_us 1235
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 3 value 57 time_us 33584
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 4 value 5b time_us 2587
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 5 value bf time_us 1254
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 6 value f5 time_us 1235
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 7 value fe time_us 1304
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 8 value 0f time_us 1300
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 9 value 7d time_us 2575
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 10 value f2 time_us 1262
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 11 value af time_us 1302
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 12 value 69 time_us 1288
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 13 value 00 time_us 1293
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 14 value ad time_us 2553
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 15 value f5 time_us 1323
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 16 value f0 time_us 1290
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 17 value ff time_us 1238
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 18 value ff time_us 1232
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 19 value f5 time_us 2710
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 20 value 73 time_us 1250
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Flush readed byte Nr 21 value 3f time_us 1235
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Buffers readed 21 bytes total time_us 148168
2015/07/25 19:45:38 34ECB7F1 r (reader) V14 [mouse] Reader initialized (device=/dev/ttyUSB0, detect=cd, mhz=357, cardmhz=357)
2015/07/25 19:45:39 34ECB7F1 r (reader) V14 [mouse] card detected
2015/07/25 19:45:39 34ECB7F1 r (reader) V14 [mouse] Buffers readed 0 bytes total time_us 75136
2015/07/25 19:45:40 34ECB7F1 r (reader) V14 [mouse] ATR: ...
2015/07/25 19:45:40 34ECB7F1 r (reader) V14 [mouse] Init card protocol T0, FI=1, F=372, D=16, N=2
2015/07/25 19:45:40 34ECB7F1 r (reader) V14 [mouse] Setting baudrate to 153600 bps
2015/07/25 19:45:40 34ECB7F1 r (reader) V14 [mouse] Calculated work ETU is 6.51 us reader mhz = 357
2015/07/25 19:45:40 34ECB7F1 r (reader) V14 [mouse] Buffers readed 0 bytes total time_us 75191
2015/07/25 19:45:40 34ECB7F1 r (reader) V14 [mouse] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.57 MHz (specified in reader->mhz)
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] Card type: P1TV
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] Rom version: 20AA
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] Credit available on card: 0 euro
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] Extended 4C detected
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] FuseByte: 00
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] Region Code:
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] Country Code: DEU
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] Pincode read: ...
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] PCB settings: FF FF FF FF
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] type: VideoGuard Sky Austria/Germany (098C), caid: 098C
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] serial: ########, BoxID: ########, baseyear: 2004
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] ready for requests
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [mouse] found card system videoguard2
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [videoguard2] THIS WAS A SUCCESSFUL START ATTEMPT No 1 out of max alloted of 1
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [videoguard2] card detected
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [videoguard2] type: VideoGuard Sky Austria/Germany (098C)
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [videoguard2] ------------------------------------------------------------------
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [videoguard2] |- class -|-- tier --|----- valid to ------|--- package name ----|
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [videoguard2] +---------+----------+---------------------+---------------------+
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [videoguard2] |------- This card is not active, so no package available! ------|
2015/07/25 19:45:41 34ECB7F1 r (reader) V14 [videoguard2] ------------------------------------------------------from-ins70--
2015/07/25 19:45:42 00000000 s (main) init for all local cards done
2015/07/25 19:45:42 00000000 s (emmcache) loaded 0 emmcache records from /tmp/.oscam/oscam.emmcache in 0 ms
2015/07/25 19:45:42 00000000 s (emmcache) loaded 0 emmstat records from /tmp/.oscam/oscam.emmstat in 0 ms
2015/07/25 19:45:42 00000000 s (anticasc) anti cascading disabled
2015/07/25 19:45:42 21F1143B c (client) plain dvbapi-client granted (vdr, au=on (1 reader))
2015/07/25 19:45:42 21F1143B c (dvbapi) dvbapi channelcache loaded from /etc/oscam/oscam.ccache
2015/07/25 19:45:42 21F1143B c (dvbapi) Using TCP listen socket, API forced to DVBAPIv3 (0), userconfig boxtype: 11
2015/07/25 19:47:37 21F1143B c (dvbapi) Client connected: 'vdr-plugin-dvbapi 2.2.0-GIT-0489e01 / VDR 2.2.0' (protocol version = 2)
2015/07/25 19:47:37 21F1143B c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1702 ECM_PID: 1700 PROVID: 000000
2015/07/25 19:47:37 21F1143B c (dvbapi) Demuxer 0 ecmpid 1 CAID: 1833 ECM_PID: 1800 PROVID: 000000
2015/07/25 19:47:37 21F1143B c (dvbapi) Demuxer 0 ecmpid 2 CAID: 09C4 ECM_PID: 1B00 PROVID: 000000
2015/07/25 19:47:37 21F1143B c (dvbapi) Demuxer 0 ecmpid 3 CAID: 09AF ECM_PID: 1F00 PROVID: 000000
2015/07/25 19:47:37 21F1143B c (dvbapi) Demuxer 0 ecmpid 4 CAID: 098C ECM_PID: 1A00 PROVID: 000000
2015/07/25 19:47:37 21F1143B c (dvbapi) Demuxer 0 found 5 ECMpids and 2 STREAMpids in PMT
2015/07/25 19:47:37 21F1143B c (dvbapi) Demuxer 0 serving srvid 0011 (1702@000000:0011 unknown) on adapter 0000 camask 0001 index 0000 pmtpid 0000
2015/07/25 19:47:37 21F1143B c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 098C PROVID 000000 ECMPID 1A00 ANY CHID PMTPID 0000 VPID 00FF
2015/07/25 19:47:37 34ECB7F1 r (reader) V14 [videoguard2] classD3 ins54: no cw --> Card isn't active
2015/07/25 19:47:37 34ECB7F1 r (reader) V14 [videoguard2] classD3 ins54: no cw --> Card needs pairing/extra data
2015/07/25 19:47:37 34ECB7F1 r (reader) V14 [videoguard2] classD3 ins54: no cw --> No tier found
2015/07/25 19:47:37 34ECB7F1 r (reader) V14 [videoguard2] classD3 ins54: no cw --> Tier expired
2015/07/25 19:47:37 21F1143B c (ecm) vdr (098C/0011/98): not found (66 ms) by V14 - Sky Sport Info
2015/07/25 19:47:37 21F1143B c (ecm) vdr (098C/0011/98): not found (2 ms) by V14 - Sky Sport Info
2015/07/25 19:47:38 21F1143B c (-) -- Skipped 3 duplicated log lines --
2015/07/25 19:47:38 21F1143B c (ecm) vdr (098C/0011/98): not found (3 ms) by V14 - Sky Sport Info
2015/07/25 19:47:38 21F1143B c (ecm) vdr (098C/0011/98): not found (2 ms) by V14 - Sky Sport Info
2015/07/25 19:47:40 21F1143B c (-) -- Skipped 5 duplicated log lines --
2015/07/25 19:47:40 21F1143B c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1702 ECM_PID: 1700 PROVID: 000000
2015/07/25 19:47:40 21F1143B c (dvbapi) Demuxer 0 ecmpid 1 CAID: 1833 ECM_PID: 1800 PROVID: 000000
2015/07/25 19:47:40 21F1143B c (dvbapi) Demuxer 0 ecmpid 2 CAID: 09C4 ECM_PID: 1B00 PROVID: 000000
2015/07/25 19:47:40 21F1143B c (dvbapi) Demuxer 0 ecmpid 3 CAID: 09AF ECM_PID: 1F00 PROVID: 000000
2015/07/25 19:47:40 21F1143B c (dvbapi) Demuxer 0 ecmpid 4 CAID: 098C ECM_PID: 1A00 PROVID: 000000
2015/07/25 19:47:40 21F1143B c (dvbapi) Demuxer 0 found 5 ECMpids and 2 STREAMpids in PMT
2015/07/25 19:47:40 21F1143B c (dvbapi) Demuxer 0 serving srvid 0011 (1702@000000:0011 unknown) on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/07/25 19:47:40 21F1143B c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 098C PROVID 000000 ECMPID 1A00 ANY CHID PMTPID 0000 VPID 00FF

Log changed to 128

2015/07/25 22:56:19 21F1143B c (dvbapi) 04 09 C4 FB 00 09 04 09 AF FF 00 09 04 09 8C FA
2015/07/25 22:56:19 21F1143B c (dvbapi) 00 02 00 FF 00 00 04 01 00 00 00
2015/07/25 22:56:19 21F1143B c (dvbapi) capmt:
2015/07/25 22:56:19 21F1143B c (dvbapi) 03 00 11 00 00 25 01 82 02 00 01 09 06 17 02 F7
2015/07/25 22:56:19 21F1143B c (dvbapi) 00 00 00 09 04 18 33 F8 00 09 04 09 C4 FB 00 09
2015/07/25 22:56:19 21F1143B c (dvbapi) 04 09 AF FF 00 09 04 09 8C FA 00 02 00 FF 00 00
2015/07/25 22:56:19 21F1143B c (dvbapi) 04 01 00 00 00
2015/07/25 22:56:19 21F1143B c (dvbapi) Receiver sends PMT command 3 for channel 0011
2015/07/25 22:56:19 21F1143B c (dvbapi) Receiver wants to demux srvid 0011 on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1702 ECM_PID: 1700 PROVID: 000000
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ecmpid 1 CAID: 1833 ECM_PID: 1800 PROVID: 000000
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ecmpid 2 CAID: 09C4 ECM_PID: 1B00 PROVID: 000000
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ecmpid 3 CAID: 09AF ECM_PID: 1F00 PROVID: 000000
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ecmpid 4 CAID: 098C ECM_PID: 1A00 PROVID: 000000
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 stream Videostream (type: 02 pid: 00ff length: 0)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 stream (type: 04 pid: 0100 length: 0)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 found 5 ECMpids and 2 STREAMpids in PMT
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 serving srvid 0011 (1702@000000:0011 unknown) on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0/16 lets start descrambling (srvid = 0011 fd = 18 ecmpids = 5)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 prio ecmpid 4 098C@000000:1A00:0000 (localrdr: V14 weight: 10)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ignore ecmpid 0 1702@000000:1700:0000 (file)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ignore ecmpid 1 1833@000000:1800:0000 (file)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ignore ecmpid 2 09C4@000000:1B00:0000 (file)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ignore ecmpid 3 09AF@000000:1F00:0000 (file)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ignore ecmpid 0 1702@000000:1700:10000 (no matching reader)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ignore ecmpid 1 1833@000000:1800:10000 (no matching reader)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ignore ecmpid 2 09C4@000000:1B00:10000 (no matching reader)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 ignore ecmpid 3 09AF@000000:1F00:10000 (no matching reader)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 forced ecmpid 4 098C@000000:1A00:0000
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 trying to descramble PID 4 CAID 098C PROVID 000000 ECMPID 1A00 ANY CHID PMTPID 0000 VPID 00FF
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 try to start new filter for caid: 098C, provid: 000000, pid: 1A00
2015/07/25 22:56:19 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=18):
2015/07/25 22:56:19 21F1143B c (dvbapi) 40 3C 6F 2B 01 00 00 1A 00 80 00 00 00 00 00 00
2015/07/25 22:56:19 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00
2015/07/25 22:56:19 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:19 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 0B B8 00 00 00
2015/07/25 22:56:19 21F1143B c (dvbapi) 04
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 098C provid 000000 pid 1A00)
2015/07/25 22:56:19 21F1143B c (dvbapi) Demuxer 0 has 5 ecmpids, 2 streampids, 1 ecmfilters and 0 of max 0 emmfilters
2015/07/25 22:56:20 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=18):
2015/07/25 22:56:20 21F1143B c (dvbapi) 40 3C 6F 2B 01 00 00 1A 00 80 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 04
2015/07/25 22:56:20 21F1143B c (ecm) vdr (098C/0011/98): not found (7 ms) by V14 - Sky Sport Info
2015/07/25 22:56:20 21F1143B c (dvbapi) PMT Update on socket 18.
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 Filter 1 fetched ECM data (ecmlength = 0x098):
2015/07/25 22:56:20 21F1143B c (dvbapi) 80 70 95 00 00 01 1D 8A 19 A7 06 00 68 AA 55 05
2015/07/25 22:56:20 21F1143B c (dvbapi) 20 01 00 00 80 40 50 2C 0B 35 FA 30 CE C9 79 03
2015/07/25 22:56:20 21F1143B c (dvbapi) 02 06 02 BE 73 7D 0B 0C 03 0C D3 DC 3A 9B D2 19
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 90 64 C1 01 2C 14 E7 DD A7 8C 5F E5 63 52
2015/07/25 22:56:20 21F1143B c (dvbapi) 65 D2 F2 8C DD 26 0D C3 48 29 6A A9 D4 D1 25 53
2015/07/25 22:56:20 21F1143B c (dvbapi) B7 38 4C 85 60 3B 68 AD D8 95 37 96 3F 92 5E CE
2015/07/25 22:56:20 21F1143B c (dvbapi) 81 98 7A A2 26 06 3E 60 F9 6A 57 80 46 55 97 64
2015/07/25 22:56:20 21F1143B c (dvbapi) 02 95 6A F1 20 2D 99 54 E6 B1 07 2A 92 3A 0A BC
2015/07/25 22:56:20 21F1143B c (dvbapi) F5 33 9F 66 2F 4D E5 20 A7 EA 65 C6 07 83 27 A4
2015/07/25 22:56:20 21F1143B c (dvbapi) 84 29 EB F7 90 72 73 52
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 098C PROVID 000000 ECMPID 1A00 FAKECHID 0068 (unique part in ecm)
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 098C PROVID 000000 FD 65535)
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 65535
2015/07/25 22:56:20 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=18):
2015/07/25 22:56:20 21F1143B c (dvbapi) 40 3C 6F 2B 01 00 00 1A 00 81 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 04
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 get controlword!
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 request controlword for ecm 098C/0011/98
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 no controlword received for PID 4 CAID 098C PROVID 000000 ECMPID 1A00 CHID 0068 VPID 00FF
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 not interested in response ecmhash 828660A0F9AF3AA1D58CABE53B0A548D (delivered cw is empty!)
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD+EVEN (CAID 098C PROVID 000000 FD 65535)
2015/07/25 22:56:20 21F1143B c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 65535
2015/07/25 22:56:20 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=18):
2015/07/25 22:56:20 21F1143B c (dvbapi) 40 3C 6F 2B 01 00 00 1A 00 80 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:20 21F1143B c (dvbapi) 04
2015/07/25 22:56:20 21F1143B c (ecm) vdr (098C/0011/98): not found (7 ms) by V14 - Sky Sport Info
2015/07/25 22:56:22 21F1143B c (dvbapi) 80 70 95 00 00 01 1D 8A 19 A7 06 00 68 AA 55 05
2015/07/25 22:56:22 21F1143B c (dvbapi) 20 01 00 00 80 40 50 2C 0B 35 FA 30 CE C9 79 03
2015/07/25 22:56:22 21F1143B c (dvbapi) 02 06 02 BE 73 7D 0B 0C 03 0C D3 DC 3A 9B D2 19
2015/07/25 22:56:22 21F1143B c (dvbapi) 00 00 90 64 C1 01 2C 14 E7 DD A7 8C 5F E5 63 52
2015/07/25 22:56:22 21F1143B c (dvbapi) 65 D2 F2 8C DD 26 0D C3 48 29 6A A9 D4 D1 25 53
2015/07/25 22:56:22 21F1143B c (dvbapi) B7 38 4C 85 60 3B 68 AD D8 95 37 96 3F 92 5E CE
2015/07/25 22:56:22 21F1143B c (dvbapi) 81 98 7A A2 26 06 3E 60 F9 6A 57 80 46 55 97 64
2015/07/25 22:56:22 21F1143B c (dvbapi) 02 95 6A F1 20 2D 99 54 E6 B1 07 2A 92 3A 0A BC
2015/07/25 22:56:22 21F1143B c (dvbapi) F5 33 9F 66 2F 4D E5 20 A7 EA 65 C6 07 83 27 A4
2015/07/25 22:56:22 21F1143B c (dvbapi) 84 29 EB F7 90 72 73 52
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 098C PROVID 000000 ECMPID 1A00 FAKECHID 0068 (unique part in ecm)
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 098C PROVID 000000 FD 65535)
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 65535
2015/07/25 22:56:22 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=18):
2015/07/25 22:56:22 21F1143B c (dvbapi) 40 3C 6F 2B 01 00 00 1A 00 81 00 00 00 00 00 00
2015/07/25 22:56:22 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00
2015/07/25 22:56:22 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:22 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:22 21F1143B c (dvbapi) 04
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 get controlword!
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 request controlword for ecm 098C/0011/98
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 no controlword received for PID 4 CAID 098C PROVID 000000 ECMPID 1A00 CHID 0068 VPID 00FF
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 not interested in response ecmhash 828660A0F9AF3AA1D58CABE53B0A548D (delivered cw is empty!)
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD+EVEN (CAID 098C PROVID 000000 FD 65535)
2015/07/25 22:56:22 21F1143B c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 65535
2015/07/25 22:56:22 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=18):
2015/07/25 22:56:22 21F1143B c (dvbapi) 40 3C 6F 2B 01 00 00 1A 00 80 00 00 00 00 00 00
2015/07/25 22:56:22 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00
2015/07/25 22:56:22 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:22 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/07/25 22:56:22 21F1143B c (dvbapi) 04
2015/07/25 22:56:22 21F1143B c (ecm) vdr (098C/0011/98): not found (7 ms) by V14 - Sky Sport Info
2015/07/25 22:56:23 21F1143B c (dvbapi) PMT Update on socket 18.
2015/07/25 22:56:23 21F1143B c (dvbapi) Parsing PMT object 1:
2015/07/25 22:56:23 21F1143B c (dvbapi) 9F 80 3F 04 83 02 00 FF
2015/07/25 22:56:23 21F1143B c (dvbapi) capmt 3f:
2015/07/25 22:56:23 21F1143B c (dvbapi) 9F 80 3F 04 83 02 00 FF
2015/07/25 22:56:23 21F1143B c (dvbapi) Demuxer 0 stop descrambling program number 0011 (1702@000000:0011 unknown)
2015/07/25 22:56:23 21F1143B c (dvbapi) Demuxer 0 stop Filter 1 (fd: 65535 api: 0, caid: 098C, provid: 000000, ecmpid: 1A00)
2015/07/25 22:56:23 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=18):
2015/07/25 22:56:23 21F1143B c (dvbapi) 00 00 6F 2A 01 00 00 1A 00

1. Wo könnte das Problem mit den EMMs liegen?
2. Warum schreibt er (ecm) vdr (098C/0011/98): not found (3 ms) by V14 - Sky Sport Info
3. Woher kommt 1702 obwohl die caid ja 098C sein sollte?

Vielen Dank schon im voraus

FloBo

 

[FloBo]

Moin,

ich habe nun einige Tag mein System laufen lassen und bekomme nur folgende wiederholende Meldungen im Log:

Spoiler

• 2015/07/28 17:14:07 21F1143B c (ecm) vdr (098C/006C/98): not found (7 ms) by V14 - Sky Sport News HD
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=14):
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 40 3C 6F 2B 00 00 00 1A 0F 80 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 04
• 2015/07/28 17:14:09 21F1143B c (ecm) vdr (098C/006C/98): not found (7 ms) by V14 - Sky Sport News HD
• 2015/07/28 17:14:09 21F1143B c (dvbapi) PMT Update on socket 14.
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 Filter 1 fetched ECM data (ecmlength = 0x098):
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 80 70 95 00 00 01 1D 8A 1C 79 C0 0C 20 AA 55 05
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 20 01 00 00 80 40 16 AE 19 6C 51 47 4E C3 41 03
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 02 06 02 4E 73 7D 0B 0C 5B 1A 02 A3 18 19 4B ED
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 90 64 C1 01 E3 DE 99 B8 71 9C 57 C2 C2 39
• 2015/07/28 17:14:09 21F1143B c (dvbapi) C2 BC CE DF 25 FD A0 5D C9 60 BB 8B 75 65 CC A2
• 2015/07/28 17:14:09 21F1143B c (dvbapi) E0 1A 78 0F 1C 55 E1 FF 5B 9C 44 CE 65 4C 6B 77
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 6B 96 D6 47 C1 2B 2F 90 E9 8C E4 61 EE 43 7E 8E
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 62 05 95 29 A1 38 C0 57 D8 7B BF 86 1D 5C D8 F2
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 4C 87 91 0B AC 2F 52 78 7F 2A 01 A4 62 C0 AA C8
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 17 4C 23 11 69 87 80 1F
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 ECMTYPE 80 CAID 098C PROVID 000000 ECMPID 1A0F FAKECHID 0C20 (unique part in ecm)
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 098C PROVID 000000 FD 65535)
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 65535
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=14):
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 40 3C 6F 2B 00 00 00 1A 0F 81 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 04
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 get controlword!
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 request controlword for ecm 098C/006C/98
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 no controlword received for PID 3 CAID 098C PROVID 000000 ECMPID 1A0F CHID 0C20 VPID 03FF
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 not interested in response ecmhash 20BE963F0A215B17D4C7CEFDD16A27FC (delivered cw is empty!)
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD+EVEN (CAID 098C PROVID 000000 FD 65535)
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Demuxer 0 Filter 1 set chid to ANY CHID on fd 65535
• 2015/07/28 17:14:09 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=14):
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 40 3C 6F 2B 00 00 00 1A 0F 80 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
• 2015/07/28 17:14:09 21F1143B c (dvbapi) 04
• 2015/07/28 17:14:09 21F1143B c (ecm) vdr (098C/006C/98): not found (6 ms) by V14 - Sky Sport News HD
• 2015/07/28 17:14:10 21F1143B c (dvbapi) Sending packet to dvbapi client (fd=14)

Gruß

FloBo

Umstellung von VDR auf TVHeadend brachte die Lösung