[hlp] Ubuntu VDR, Sky V14, Oscam Pairing

[pinco]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

log:

Spoiler

2015/09/30 07:25:50 7969C21C r   (reader) v14 [mouse] Reader initialized (device=/dev/Apollo, detect=cd, mhz=357, cardmhz=357)
2015/09/30 07:25:51 7969C21C r   (reader) v14 [mouse] card detected
2015/09/30 07:25:51 7969C21C r   (reader) v14 [mouse] Buffers readed  0 bytes total time_us 75337
2015/09/30 07:25:52 7969C21C r   (reader) v14 [mouse] ATR: 3F FD 15 25 02 50 80 0F 41 B0 0A 69 FF 4A 50 F0 00 00 50 31 03 
2015/09/30 07:25:52 7969C21C r   (reader) v14 [mouse] Init card protocol T0, FI=1, F=372, D=16, N=2
2015/09/30 07:25:52 7969C21C r   (reader) v14 [mouse] Setting baudrate to 153600 bps
2015/09/30 07:25:52 7969C21C r   (reader) v14 [mouse] Calculated work ETU is 6.51 us reader mhz = 357
2015/09/30 07:25:53 7969C21C r   (reader) v14 [mouse] Buffers readed  0 bytes total time_us 75221
2015/09/30 07:25:53 7969C21C r   (reader) v14 [mouse] ATR Fsmax is 5 MHz, clocking card to wanted user cardspeed off 3.57 MHz (specified in reader->mhz)
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] Card type:   P1TV
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] Rom version: 20AA
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] Credit available on card: 0 euro
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] Extended 4C detected
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] FuseByte: 25
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] Region Code: 00BO0102
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] Country Code: DEU
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] Pincode read: 4843
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] PCB settings: FF FF FF FF
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] type: VideoGuard Sky Austria/Germany (098C), caid: 098C
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] serial: ########, BoxID: ########, baseyear: 2004
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] ready for requests
2015/09/30 07:25:54 7969C21C r   (reader) v14 [mouse] found card system videoguard2
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] THIS WAS A SUCCESSFUL START ATTEMPT No  1 out of max alloted of 1
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] card detected
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] type: VideoGuard Sky Austria/Germany (098C)
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] ------------------------------------------------------------------
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |- class -|-- tier --|----- valid to ------|--- package name ----|
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] +---------+----------+---------------------+---------------------+
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0046 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0058 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0056 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0057 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0068 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0069 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 006b --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 006c --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0065 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 006d --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0066 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 006e --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0067 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 006f --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 007b --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 007d --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 0081 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 008e --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00c0 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00c8 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00c9 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00ca --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00cb --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00c7 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00fa --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00f3 --| 2015/09/29-01:00:00 | 
2015/09/30 07:25:54 7969C21C r   (reader) v14 [videoguard2] |-- 00 ---|-- 00ff --| 2015/09/29-01:00:00 |

debug level find ich nix auf der Status Seite (OSCam r11019)?

Hab grad erst gesehen, dass die Entitlements wohl gestern ausgelaufen sind!?
> dementsprechend laufen jetzt keine Sender mehr. Allerdings wird das heute dann auch so in Oscam vermeldet:

Spoiler

2015/09/30 07:38:48 07685424 c   (dvbapi) Demuxer 0 trying to descramble PID 3 CAID 098C PROVID 000000 ECMPID 1ABA ANY CHID PMTPID 0000 VPID 02FF
2015/09/30 07:38:48 07685424 c      (ecm) vdr (098C@000000/0259/0081/98:7B119549E1E3A92A249BC7D0D85E957B:0F06001020): not found (0 ms) by v14 - Sky Sport HD 1
2015/09/30 07:38:48 07685424 c   (dvbapi) Demuxer 0 restarting decodingrequests after 0 ms with 1 enabled and 3 disabled ecmpids!

EDIT: an welchen Thread sollte ich mich denn bzgl. "schreibbarkeit" meiner EMM's wenden? von EMM-Spy wurde mir letztens abgeraten...

Grüße

 

[Radagast]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

Hi,

welche EMMs hast du denn bis jetzt geschrieben und welche stehen im Moment zur Verfügung? Evtl können wir das auch hier in dem Thread direkt klären. Ansonsten kannst du dich auch gerne für die passenden EMMs an diesen Thread https://www.digital-eliteboard.com/...uen-v14-mit-oscam-nach-erstfreischaltung.html halten oder dort nachfragen.

 

[pinco]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

Zuletzt habe ich Mitte August einen EMM geschrieben, welcher mit den Buchstaben 827027 begonnen hat, die ganze Wurst dahinter brauchts ja nicht, richtig?
Ab 15.09. ist dann dreimal 82703B* gekommen, seit 26.09. fünfmal ein anderer 82703B*

- - - - - - - - - -

edit: paylod im web-if ist jetzt "00 10 20 00 00 11"

 

[Radagast]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

Hi,

dir wird nicht sehr viel anderes übrig bleiben als den zuletzt gekommenen 82703Ber zu schreiben.
Was mich allerdings etwas verwundert ist, dass du schreibst es sind seit dem 26.09. fünf unterschiedliche 82703B gekommen?!

 

[pinco]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

sorry, da hab ich mich blöd ausgedrückt...
ab 15.09. 82703B*XY (3mal)
ab 26.09. 82703B*XZ (5mal) - also ein anderer wie der vom 15.09.!

werds mal versuchen und meld mich dann wieder!
Danke inzwischen!

Edit: OK, verlängert wurde erstmal. Muss Mittags dann abchecken, obs damit wieder läuft! Komisch nur, dass gestern (und auch schon am Samstag - BuLi) einige Sender gingen, andere nicht.

 

[pinco]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

Wie gesagt, Entitlements sind jetzt wieder vorhanden, aber sonst ist alles wieder wie gestern Abend. Es geht z.B. wieder Sport 2, Sport 1 jedoch nicht. Bekomme immer andere Meldungen vom VDR-VNSI-CLIENT, mal scrambled, mal "kanal zeichnet auf".Folgende Meldungen in den Logs:
Kodi

Spoiler

12:48:17 T:140675419322240  NOTICE: DVDPlayer: Opening: pvr://channels/tv/All channels/pvr.vdr.vnsi_714368046.pvr
12:48:17 T:140675419322240 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
12:48:17 T:140675419322240   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
12:48:17 T:140675419322240   DEBUG: LinuxRendererGL: Cleaning up GL resources
12:48:17 T:140675419322240   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
12:48:17 T:140674221446912  NOTICE: Thread DVDPlayer start, auto delete: false
12:48:17 T:140674221446912  NOTICE: Creating InputStream
12:48:17 T:140674221446912   DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'Sky Sport HD 1'
12:48:17 T:140674221446912   DEBUG: opening live stream for channel 'Sky Sport HD 1'
12:48:17 T:140674221446912  NOTICE: AddOnLog: VDR VNSI Client: Logged in at '1443610096+7200' to 'VDR-Network-Streaming-Interface (VNSI) Server' Version: '1.3.0' with protocol version '8'
12:48:17 T:140674221446912   DEBUG: AddOnLog: VDR VNSI Client: changing to channel 49
12:48:17 T:140674221446912   DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All channels/pvr.vdr.vnsi_714368046.pvr
12:48:17 T:140674221446912   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/All channels/pvr.vdr.vnsi_714368046.pvr
12:48:17 T:140674221446912  NOTICE: Creating Demuxer
12:48:17 T:140674221446912   ERROR: Get - failed to get stream

Server Syslog:

Spoiler

Sep 30 12:48:16 data vdr: [2998] VNSI: Welcome client 'XBMC Media Center' with protocol version '8'
Sep 30 12:48:16 data vdr: [2998] VNSI: LiveStreamer::Close - close
Sep 30 12:48:16 data vdr: [2998] VNSI: close video input ...
Sep 30 12:48:16 data vdr: [2998] CAM 2: assigned to device 2
Sep 30 12:48:16 data vdr: [2998] VNSI: Successfully found following device: 0x1450800 (2) for receiving
Sep 30 12:48:16 data vdr: [2999] device 2 receiver thread started (pid=1819, tid=2999, prio=high)
Sep 30 12:48:16 data vdr: [2998] VNSI: Successfully switched to channel 49 - Sky Sport HD 1
Sep 30 12:48:16 data vdr: [2998] VNSI: Started streaming of channel Sky Sport HD 1 (timeout 10 seconds)
Sep 30 12:48:16 data vdr: [3001] cLiveStreamer stream processor thread started (pid=1819, tid=3001, prio=high)
Sep 30 12:48:16 data vdr: [3000] VNSI: VideoInput: no pat/pmt within timeout, falling back to channel pids
Sep 30 12:48:16 data vdr: [3000] VNSI: Video Input - new pmt, attaching receiver
Sep 30 12:48:17 data vdr: [3005] device 2 TS buffer thread started (pid=1819, tid=3005, prio=high)
Sep 30 12:48:17 data vdr: [3000] DVBAPI: 1.0 set CAM decrypt (SID 129 (0x0081), caLm 4, HasCaDescriptors 1)
Sep 30 12:48:17 data vdr: [1845] changing pids of channel 49 (Sky Sport HD 1) from 767+767=27:0;770=deu@106,771=deu@106:0:32 to 767+767=27:0;770=deu@106,771=deu@106,772=deu@106:0:32
Sep 30 12:48:17 data vdr: [1845] VNSI: Video Input - new pmt, attaching receiver
Sep 30 12:48:17 data vdr: [1845] DVBAPI: 1.0 set CAM decrypt (SID 129 (0x0081), caLm 5, HasCaDescriptors 1)
Sep 30 12:48:17 data vdr: [1845] CAM 2: unassigned
Sep 30 12:48:17 data vdr: [3001] VNSI: Created stream for pid=767 and type=8
Sep 30 12:48:17 data vdr: [3001] VNSI: Created stream for pid=770 and type=1
Sep 30 12:48:17 data vdr: [3001] VNSI: Created stream for pid=771 and type=1
Sep 30 12:48:17 data vdr: [3001] VNSI: Created stream for pid=32 and type=11
Sep 30 12:48:26 data vdr: [3001] VNSI: Channel: scrambled 2
Sep 30 12:48:46 data vdr: message repeated 2 times: [ [3001] VNSI: Channel: scrambled 2]
Sep 30 12:48:51 data vdr: [1851] VNSI: Trigger EPG update for channel DAS VIERTE, id: 829532021
Sep 30 12:48:51 data vdr: [1851] VNSI: Trigger EPG update for channel Rai 3 S-BZ, id: 2014269270
Sep 30 12:48:56 data vdr: [3001] VNSI: Channel: scrambled 2
Sep 30 12:49:06 data vdr: [3001] VNSI: Channel: scrambled 2
Sep 30 12:49:11 data vdr: [1851] VNSI: Trigger EPG update for channel DAS VIERTE, id: 829532021
Sep 30 12:49:11 data vdr: [1851] VNSI: Trigger EPG update for channel Rai 3 S-BZ, id: 2014269270
Sep 30 12:49:16 data vdr: [3001] VNSI: Channel: scrambled 2

Oscam:

Spoiler

2015/09/30 12:48:17 154C8158 c   (dvbapi) Demuxer 0 ecmpid 0 CAID: 1833 ECM_PID: 18BA PROVID: 000000
2015/09/30 12:48:17 154C8158 c   (dvbapi) Demuxer 0 ecmpid 1 CAID: 09C4 ECM_PID: 1BBA PROVID: 000000
2015/09/30 12:48:17 154C8158 c   (dvbapi) Demuxer 0 ecmpid 2 CAID: 09AF ECM_PID: 1FBA PROVID: 000000
2015/09/30 12:48:17 154C8158 c   (dvbapi) Demuxer 0 ecmpid 3 CAID: 098C ECM_PID: 1ABA PROVID: 000000
2015/09/30 12:48:17 154C8158 c   (dvbapi) Demuxer 0 found 4 ECMpids and 3 STREAMpids in PMT
2015/09/30 12:48:17 154C8158 c   (dvbapi) Demuxer 0 serving srvid 0081 (Sky Sport HD 1) on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/09/30 12:48:17 154C8158 c   (dvbapi) Demuxer 0 found channel in cache and matching prio -> start descrambling ecmpid 3 
2015/09/30 12:48:17 154C8158 c   (dvbapi) Demuxer 0 trying to descramble PID 3 CAID 098C PROVID 000000 ECMPID 1ABA ANY CHID PMTPID 0000 VPID 02FF

 

[Radagast]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

Hi,

dein Log von OScam ist "etwas" zu kurz geraten und was du nicht angegeben hast ist wie der aktuelle Payload nach dem schreiben des EMMs aussieht. Das du jetzt wieder entsprechende Entis hast besagt leider nicht, dass deine Karte weiterhin offen ist.

 

[pinco]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

Guten Morgen!
Okay, hab in den livelog settings nun auf 2+4 gesetzt und beim umschalten auf einen abonnierten sender u.a. das im log entdeckt:

2015/10/01 07:23:53 236C8F76 r   (reader)   00 00 0E 02 03 00 0F 06 00 00 00 00 00 01 20 04

Heißt also laut hier "0F 06 00 00 00 -> Karte aktiviert und mit Entitlements versorgt" oder?
Brauchst du den ganzen Log oder reicht das?

Hier evtl. der Syslog

Spoiler

Oct  1 07:23:52 data vdr: [1850] loading /var/lib/vdr/plugins/vnsiserver/allowed_hosts.conf
Oct  1 07:23:52 data vdr: [1850] VNSI: Client with ID 15 connected: 192.168.178.212:34303
Oct  1 07:23:52 data vdr: [14973] VNSI: Welcome client 'XBMC Media Center' with protocol version '8'
Oct  1 07:23:52 data vdr: [14973] VNSI: LiveStreamer::Close - close
Oct  1 07:23:52 data vdr: [14973] VNSI: close video input ...
Oct  1 07:23:52 data vdr: [14973] CAM 2: assigned to device 2
Oct  1 07:23:52 data vdr: [14973] VNSI: Successfully found following device: 0x1450800 (2) for receiving
Oct  1 07:23:52 data vdr: [14973] VNSI: Successfully switched to channel 50 - Sky Sport HD 2
Oct  1 07:23:52 data vdr: [14973] VNSI: Started streaming of channel Sky Sport HD 2 (timeout 10 seconds)
Oct  1 07:23:52 data vdr: [14974] device 2 receiver thread started (pid=1819, tid=14974, prio=high)
Oct  1 07:23:52 data vdr: [14976] cLiveStreamer stream processor thread started (pid=1819, tid=14976, prio=high)
Oct  1 07:23:52 data vdr: [14975] VNSI: VideoInput: no pat/pmt within timeout, falling back to channel pids
Oct  1 07:23:52 data vdr: [14975] VNSI: Video Input - new pmt, attaching receiver
Oct  1 07:23:52 data vdr: [14977] device 2 TS buffer thread started (pid=1819, tid=14977, prio=high)
Oct  1 07:23:53 data vdr: [14975] DVBAPI: 1.0 set CAM decrypt (SID 114 (0x0072), caLm 4, HasCaDescriptors 1)
Oct  1 07:23:53 data vdr: [14976] VNSI: Created stream for pid=1023 and type=8
Oct  1 07:23:53 data vdr: [14976] VNSI: Created stream for pid=1026 and type=1
Oct  1 07:23:53 data vdr: [14976] VNSI: Created stream for pid=1027 and type=1
Oct  1 07:23:53 data vdr: [14976] VNSI: Created stream for pid=1028 and type=1
Oct  1 07:23:53 data vdr: [14976] VNSI: Created stream for pid=32 and type=11
Oct  1 07:24:02 data vdr: [14976] VNSI: Channel: scrambled 3
Oct  1 07:24:03 data vdr: [1844] frontend 1/0 timed out while tuning to channel 36 (BBC HD), tp 210847
Oct  1 07:24:06 data vdr: [14973] VNSI: cxSocket::read: eof, connection closed
Oct  1 07:24:07 data vdr: [14976] VNSI: exit streamer thread
Oct  1 07:24:07 data vdr: [14976] cLiveStreamer stream processor thread ended (pid=1819, tid=14976)
Oct  1 07:24:07 data vdr: [14973] VNSI: LiveStreamer::Close - close
Oct  1 07:24:07 data vdr: [14973] VNSI: close video input ...
Oct  1 07:24:07 data vdr: [14973] VNSI: call retune ...
Oct  1 07:24:07 data vdr: [14973] CAM 2: unassigned
Oct  1 07:24:07 data vdr: [14973] VNSI: call retune ...
Oct  1 07:24:07 data vdr: [14973] VNSI: close video input ...
Oct  1 07:24:07 data vdr: [1851] VNSI: Client with ID 15 seems to be disconnected, removing from client list
Oct  1 07:24:07 data vdr: [14977] device 2 TS buffer thread ended (pid=1819, tid=14977)
Oct  1 07:24:07 data vdr: [14974] buffer stats: 172584 (3%) used
Oct  1 07:24:07 data vdr: [14974] device 2 receiver thread ended (pid=1819, tid=14974)

und so schauts bei kodi aus

Spoiler

7:23:53 T:140040749148032  NOTICE: DVDPlayer: Opening: pvr://channels/tv/All channels/pvr.vdr.vnsi_1036994494.pvr
07:23:53 T:140040749148032 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
07:23:53 T:140040749148032   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
07:23:53 T:140040749148032   DEBUG: LinuxRendererGL: Cleaning up GL resources
07:23:53 T:140040749148032   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
07:23:53 T:140039463847680  NOTICE: Thread DVDPlayer start, auto delete: false
07:23:53 T:140039463847680  NOTICE: Creating InputStream
07:23:53 T:140039463847680   DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'Sky Sport HD 2'
07:23:53 T:140039463847680   DEBUG: opening live stream for channel 'Sky Sport HD 2'
07:23:53 T:140039463847680  NOTICE: AddOnLog: VDR VNSI Client: Logged in at '1443677032+7200' to 'VDR-Network-Streaming-Interface (VNSI) Server' Version: '1.3.0' with protocol version '8'
07:23:53 T:140039463847680   DEBUG: AddOnLog: VDR VNSI Client: changing to channel 50
07:23:53 T:140039463847680   DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All channels/pvr.vdr.vnsi_1036994494.pvr
07:23:53 T:140039463847680   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/All channels/pvr.vdr.vnsi_1036994494.pvr
07:23:53 T:140039463847680  NOTICE: Creating Demuxer
07:23:53 T:140039463847680   ERROR: Get - failed to get stream
07:23:53 T:140039463847680   DEBUG: CDVDPlayer::SetCaching - caching state 2
07:23:53 T:140039463847680 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
07:23:53 T:140039463847680 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
07:23:53 T:140039463847680   DEBUG: OnPlayBackStarted: play state was 1, starting 1
07:23:53 T:140040749148032   DEBUG: PlayFile: OpenFile succeed, play state 2
07:23:53 T:140040749148032   DEBUG: OnPlayBackStarted: play state was 2, starting 0
07:23:53 T:140040749148032   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All channels/pvr.vdr.vnsi_1036994494.pvr)
07:23:53 T:140040749148032   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
07:23:53 T:140040749148032   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
07:23:53 T:140040749148032   DEBUG: LIRC: Update - NEW at 95112:1c 0 KEY_ENTER_UP devinput (KEY_ENTER_UP)
07:24:03 T:140039463847680   DEBUG: AddOnLog: VDR VNSI Client: StreamStatus - Channel: scrambled (3)
07:24:03 T:140039463847680   DEBUG: CAddonCallbacksAddon - QueueNotification - VDR VNSI Client - Info Message : 'Channel: scrambled (3)'
07:24:03 T:140040749148032   DEBUG: ------ Window Init (DialogKaiToast.xml) ------
07:24:06 T:140040749148032   DEBUG: LIRC: Update - NEW at 108122:80 0 KEY_STOP devinput (KEY_STOP)
07:24:06 T:140040749148032   DEBUG: OnKey: guide (0xe0) pressed, action is Stop
07:24:06 T:140040749148032  NOTICE: CDVDPlayer::CloseFile()
07:24:06 T:140040749148032  NOTICE: DVDPlayer: waiting for threads to exit
07:24:07 T:140039463847680  NOTICE: CDVDPlayer::OnExit()
07:24:07 T:140039463847680   DEBUG: CDVDPlayer::SetCaching - caching state 0
07:24:07 T:140039463847680   DEBUG: CDVDInputStreamPVRManager::Close - stream closed
07:24:07 T:140039463847680   DEBUG: OnPlayBackStopped: play state was 2, starting 0
07:24:07 T:140039463847680   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
07:24:07 T:140039463847680   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
07:24:07 T:140039463847680   DEBUG: Thread DVDPlayer 140039463847680 terminating
07:24:07 T:140039933642496   DEBUG: DoWork - Saving file state for video item pvr://channels/tv/All channels/pvr.vdr.vnsi_1036994494.pvr
07:24:07 T:140040749148032  NOTICE: DVDPlayer: finished waiting
07:24:07 T:140040749148032   DEBUG: LinuxRendererGL: Cleaning up GL resources
07:24:07 T:140040749148032  NOTICE: CDVDPlayer::CloseFile()
07:24:07 T:140040749148032  NOTICE: DVDPlayer: waiting for threads to exit
07:24:07 T:140040749148032  NOTICE: DVDPlayer: finished waiting
07:24:07 T:140040749148032   DEBUG: LinuxRendererGL: Cleaning up GL resources
07:24:07 T:140040749148032  NOTICE: CDVDPlayer::CloseFile()
07:24:07 T:140040749148032  NOTICE: DVDPlayer: waiting for threads to exit
07:24:07 T:140040749148032  NOTICE: DVDPlayer: finished waiting
07:24:07 T:140040749148032   DEBUG: LinuxRendererGL: Cleaning up GL resources
07:24:07 T:140040749148032   DEBUG: LIRC: Update - NEW at 108848:80 0 KEY_STOP_UP devinput (KEY_STOP_UP)
07:24:07 T:140040749148032   DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
07:24:19 T:140040749148032   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)
07:24:19 T:140040749148032   DEBUG: Unloading: /ImageLib-x86_64-linux.so
07:24:37 T:140040446408448   DEBUG: Thread JobWorker 140040446408448 terminating (autodelete)
07:24:37 T:140039505811200   DEBUG: Thread JobWorker 140039505811200 terminating (autodelete)
07:24:37 T:140039430371072   DEBUG: Thread JobWorker 140039430371072 terminating (autodelete)
07:24:37 T:140039933642496   DEBUG: Thread JobWorker 140039933642496 terminating (autodelete)
07:24:39 T:140039438763776   DEBUG: AddOnLog: VDR VNSI Client: Server requested Epg update for channel: 1802951231
07:24:40 T:140039337010944   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'Rai 2' from client '797'
07:24:40 T:140039530989312   DEBUG: CPVRTimers - Update - updating timers
07:26:24 T:140039438763776   DEBUG: AddOnLog: VDR VNSI Client: Server requested Epg update for channel: 598943092
07:26:25 T:140039337010944   DEBUG: EPG - UpdateFromScraper - updating EPG for channel 'ORF1 HD' from client '797'
07:26:25 T:140039530989312   DEBUG: CPVRTimers - Update - updating timers
07:27:07 T:140040749148032   DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc
07:27:07 T:140040749148032   DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated

Gruß

 

[pinco]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

okay, noch was neues! hab gerade den vdr neu gestartet, da ich in der channellist was geändert hab:

Spoiler

Oct  1 07:37:55 data vdr: [16932] VDR version 2.2.0 started
Oct  1 07:37:55 data vdr: [16932] switched to user 'vdr'
Oct  1 07:37:55 data vdr: [16932] codeset is 'UTF-8' - known
Oct  1 07:37:55 data vdr: [16932] found 28 locales in /usr/share/locale
Oct  1 07:37:55 data vdr: [16932] loading plugin: /usr/lib/vdr/plugins/libvdr-dvbapi.so.2.2.0
Oct  1 07:37:55 data vdr: [16932] loading plugin: /usr/lib/vdr/plugins/libvdr-streamdev-server.so.2.2.0
Oct  1 07:37:55 data vdr: [16932] loading plugin: /usr/lib/vdr/plugins/libvdr-vnsiserver.so.2.2.0
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/setup.conf
Oct  1 07:37:55 data vdr: [16932] ERROR: unknown config parameter: JumpPlay = 0
Oct  1 07:37:55 data vdr: [16932] ERROR: unknown config parameter: JumpSeconds = 60
Oct  1 07:37:55 data vdr: [16932] ERROR: unknown config parameter: JumpSecondsSlow = 10
Oct  1 07:37:55 data vdr: [16932] ERROR: unknown config parameter: PauseLastMark = 0
Oct  1 07:37:55 data vdr: [16932] ERROR: unknown config parameter: PlayJump = 0
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/sources.conf
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/diseqc.conf
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/scr.conf
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/channels.conf
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/timers.conf
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/commands.conf
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/reccmds.conf
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/svdrphosts.conf
Oct  1 07:37:55 data vdr: [16932] loading /var/lib/vdr/keymacros.conf
Oct  1 07:37:55 data vdr: [16932] registered source parameters for 'A - ATSC'
Oct  1 07:37:55 data vdr: [16948] video directory scanner thread started (pid=16932, tid=16948, prio=high)
Oct  1 07:37:55 data vdr: [16932] registered source parameters for 'C - DVB-C'
Oct  1 07:37:55 data vdr: [16948] video directory scanner thread ended (pid=16932, tid=16948)
Oct  1 07:37:55 data vdr: [16932] registered source parameters for 'S - DVB-S'
Oct  1 07:37:55 data vdr: [16932] registered source parameters for 'T - DVB-T'
Oct  1 07:37:55 data vdr: [16950] epg data reader thread started (pid=16932, tid=16950, prio=high)
Oct  1 07:37:55 data vdr: [16949] video directory scanner thread started (pid=16932, tid=16949, prio=high)
Oct  1 07:37:55 data vdr: [16950] reading EPG data from /var/cache/vdr/epg.data
Oct  1 07:37:55 data vdr: [16949] video directory scanner thread ended (pid=16932, tid=16949)
Oct  1 07:37:55 data vdr: [16932] probing /dev/dvb/adapter0/frontend0
Oct  1 07:37:55 data vdr: [16932] creating cDvbDevice
Oct  1 07:37:55 data vdr: [16932] new device number 1
Oct  1 07:37:55 data vdr: [16932] DVB API version is 0x050A (VDR was built with 0x050A)
Oct  1 07:37:55 data vdr: [16932] frontend 0/0 provides DVB-T,DVB-T2,DVB-C with QPSK,QAM16,QAM32,QAM64,QAM128,QAM256 ("Sony CXD2820R")
Oct  1 07:37:55 data vdr: [16932] cTimeMs: using monotonic clock (resolution is 1 ns)
Oct  1 07:37:55 data vdr: [16932] probing /dev/dvb/adapter1/frontend0
Oct  1 07:37:55 data vdr: [16932] creating cDvbDevice
Oct  1 07:37:55 data vdr: [16932] new device number 2
Oct  1 07:37:55 data vdr: [16953] device 1 section handler thread started (pid=16932, tid=16953, prio=low)
Oct  1 07:37:55 data vdr: [16952] frontend 0/0 tuner thread started (pid=16932, tid=16952, prio=high)
Oct  1 07:37:55 data kernel: [68328.488816] ds3000_firmware_ondemand: Waiting for firmware upload (dvb-fe-ds3000.fw)...
Oct  1 07:37:55 data kernel: [68328.488983] ds3000_firmware_ondemand: Waiting for firmware upload(2)...
Oct  1 07:37:56 data vdr: [16950] epg data reader thread ended (pid=16932, tid=16950)
Oct  1 07:37:56 data vdr: [16932] frontend 1/0 provides DVB-S,DVB-S2 with QPSK ("Montage Technology DS3000")
Oct  1 07:37:56 data vdr: [16957] frontend 1/0 tuner thread started (pid=16932, tid=16957, prio=high)
Oct  1 07:37:56 data vdr: [16932] found 2 DVB devices
Oct  1 07:37:56 data vdr: [16958] device 2 section handler thread started (pid=16932, tid=16958, prio=low)
Oct  1 07:37:56 data vdr: [16932] initializing plugin: dvbapi (2.2.1): SoftCAM für OSCam
Oct  1 07:37:56 data vdr: [16932] initializing plugin: streamdev-server (0.6.1-git): VDR Streaming Server
Oct  1 07:37:56 data vdr: [16932] initializing plugin: vnsiserver (1.3.0): VDR-Network-Streaming-Interface (VNSI) Server
Oct  1 07:37:56 data vdr: [16932] setting primary device to 1
Oct  1 07:37:56 data vdr: [16932] device 1 has no MPEG decoder
Oct  1 07:37:56 data vdr: [16932] assuming manual start of VDR
Oct  1 07:37:56 data vdr: [16932] SVDRP listening on port 6419
Oct  1 07:37:56 data vdr: [16932] setting current skin to "lcars"
Oct  1 07:37:56 data vdr: [16932] loading /var/lib/vdr/themes/lcars-default.theme
Oct  1 07:37:56 data vdr: [16932] starting plugin: dvbapi
Oct  1 07:37:56 data vdr: [16932] DVBAPI: plugin version 2.2.1 initializing (VDR 2.2.0)
Oct  1 07:37:56 data vdr: [16932] DVBAPI: decryption library: libdvbcsa
Oct  1 07:37:56 data vdr: [16932] DVBAPI: Creating sCCIAdapter for device 0
Oct  1 07:37:56 data vdr: [16959] Socket Handler thread started (pid=16932, tid=16959, prio=high)
Oct  1 07:37:56 data vdr: [16932] DVBAPI: Creating sCCIAdapter for device 1
Oct  1 07:37:56 data vdr: [16960] SC-CI adapter on device 0 thread started (pid=16932, tid=16960, prio=high)
Oct  1 07:37:56 data vdr: [16932] DVBAPI: plugin started
Oct  1 07:37:56 data vdr: [16932] starting plugin: streamdev-server
Oct  1 07:37:56 data vdr: [16961] SC-CI adapter on device 1 thread started (pid=16932, tid=16961, prio=high)
Oct  1 07:37:56 data vdr: [16932] loading /var/lib/vdr/plugins/streamdev-server/streamdevhosts.conf
Oct  1 07:37:56 data vdr: [16932] starting plugin: vnsiserver
Oct  1 07:37:56 data vdr: [16932] VNSI: Starting vnsi server at port=34890
Oct  1 07:37:56 data vdr: [16932] VNSI: VNSI Server started
Oct  1 07:37:56 data vdr: [16932] VNSI: Channel streaming timeout: 10 seconds
Oct  1 07:37:56 data vdr: [16962] streamdev server thread started (pid=16932, tid=16962, prio=high)
Oct  1 07:37:56 data vdr: [16962] Streamdev: Listening (VTP) on port 2004
Oct  1 07:37:56 data vdr: [16963] VDR VNSI Server thread started (pid=16932, tid=16963, prio=high)
Oct  1 07:37:56 data vdr: [16962] Streamdev: Listening (HTTP) on port 3000
Oct  1 07:37:56 data vdr: [16960] DVBAPI: 0.0: doReply changed, reset triggered
Oct  1 07:37:56 data vdr: [16960] DVBAPI: 0.0: now using CAIDs version 1
Oct  1 07:37:56 data vdr: [16960] DVBAPI: 0.0: status 'present'
Oct  1 07:37:56 data vdr: [16960] CAM 1: module present
Oct  1 07:37:56 data vdr: [16932] DVBAPI: 1.0: doReply changed, reset triggered
Oct  1 07:37:56 data vdr: [16932] DVBAPI: 1.0: now using CAIDs version 1
Oct  1 07:37:56 data vdr: [16932] DVBAPI: 1.0: status 'present'
Oct  1 07:37:56 data vdr: [16932] DVBAPI: 0.0: status 'reset'
Oct  1 07:37:57 data vdr: [16932] DVBAPI: 1.0: status 'reset'
Oct  1 07:37:57 data vdr: [16963] loading /var/lib/vdr/plugins/vnsiserver/allowed_hosts.conf
Oct  1 07:37:57 data vdr: [16963] VNSI: Client with ID 0 connected: 192.168.178.212:34941
Oct  1 07:37:57 data vdr: [16968] VNSI: Welcome client 'XBMC Media Center' with protocol version '8'
Oct  1 07:37:57 data vdr: [16932] DVBAPI: 0.0: status 'ready'
Oct  1 07:37:57 data vdr: [16964] VNSI: Requesting clients to reload channel list
Oct  1 07:37:57 data vdr: [16960] CAM 1: module ready
Oct  1 07:37:57 data vdr: [16961] DVBAPI: 1.0: status 'ready'
Oct  1 07:37:57 data vdr: [16961] CAM 2: module ready
Oct  1 07:37:57 data vdr: [16960] DVBAPI: CaInfo: 0.0 sending CA info
Oct  1 07:37:57 data vdr: [16960] CAM 1: system ids: FFFF
Oct  1 07:37:57 data vdr: [16961] DVBAPI: CaInfo: 1.0 sending CA info
Oct  1 07:37:57 data vdr: [16961] CAM 2: system ids: FFFF
Oct  1 07:37:58 data vdr: [16960] CAM 1: replies to QUERY - multi channel decryption possible
Oct  1 07:37:58 data vdr: [16961] CAM 2: replies to QUERY - multi channel decryption possible
[B]Oct  1 07:37:58 data vdr: [16932] switching to channel 1 (Das Erste HD)[/B]
Oct  1 07:37:58 data vdr: [16932] OSD size changed to 720x480 @ 1
Oct  1 07:37:58 data vdr: [16952] ERROR (dvbdevice.c,858): Vorgang wird nicht unterstützt
Oct  1 07:37:58 data vdr: [16952] ERROR (dvbdevice.c,859): Vorgang wird nicht unterstützt
Oct  1 07:37:58 data vdr: [16952] ERROR: frontend 0/0: Das Argument ist ungültig
Oct  1 07:37:58 data vdr: [16932] ERROR: no OSD provider available - using dummy OSD!
[B]Oct  1 07:37:58 data vdr: [16932] ERROR: Kanal blockiert (zeichnet auf)![/B]

dazu zwei Fragen:
1) Warum versucht der VDR von sich aus auf channel 1 zu schalten? (und was sind die 4 Error Meldungen danach?)
2) Warum ist der Kanal blockiert (wüsste nicht, wo ich aufzeichne...)?

Gruß

 

[Radagast]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

Och lass dir doch bitte nicht alles aus der Nase ziehen. Wenn die Anfragen bei OScam ankommen, dann ist es eigentlich ein Problem bei OScam und wir sind hier komplett OT und es sollte im OScam Bereich gepostet werden. Du postest jetzt zwar den aktuellen Payload, der mir etwas seltsam mit der 01 vorkommt, aber ein entsprechendes Log von OScam fehlt.

 

[pinco]

AW: [hlp] Ubuntu VDR, Sky V14, Oscam Pairing

Sorry, hier dann der volle log:

Spoiler

2015/10/01 07:23:53 154C8158 c   (dvbapi) Demuxer 0 serving srvid 0072 (Sky Sport HD 2) on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/10/01 07:23:53 154C8158 c   (dvbapi) Demuxer 0 found channel in cache and matching prio -> start descrambling ecmpid 3 
2015/10/01 07:23:53 154C8158 c   (dvbapi) Demuxer 0 trying to descramble PID 3 CAID 098C PROVID 000000 ECMPID 1A01 ANY CHID PMTPID 0000 VPID 03FF
2015/10/01 07:23:53 236C8F76 r 02 06 02 B7 73 7D 0B 0C 87 14 25 A9 91 65 93 85 
2015/10/01 07:23:53 236C8F76 r 00 00 90 64 C1 01 D9 02 20 E4 FD 4E 4E 1E 91 F0 
2015/10/01 07:23:53 236C8F76 r 8A 37 19 29 87 03 52 29 19 98 20 35 1D 7B C1 95 
2015/10/01 07:23:53 236C8F76 r 73 D2 9E 28 B5 0B B2 B1 4B 7B 09 32 86 DE AE 99 
2015/10/01 07:23:53 236C8F76 r 8D 7F AB E7 B0 9E 7D D6 1C 2D 59 21 1D 91 C5 C2 
2015/10/01 07:23:53 236C8F76 r 81 6C 51 43 9B B8 30 9B 47 51 E4 36 D0 16 53 DF 
2015/10/01 07:23:53 236C8F76 r 55 05 1C 87 F0 B2 C6 D7 E9 C7 25 D9 DD 00 AD E7 
2015/10/01 07:23:53 236C8F76 r 48 83 FF 21 8E 73 3E AE 
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] cardreader_do_checkhealth: reader->card_status = 2, ret = 1
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] cardreader_do_ecm: cardreader_do_checkhealth returned rc=1
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] write to cardreader
2015/10/01 07:23:53 236C8F76 r   (reader)   D1 40 00 80 74 00 7D 0B 0C 87 14 25 A9 91 65 93 
2015/10/01 07:23:53 236C8F76 r   (reader)   85 00 00 90 64 C1 01 D9 02 20 E4 FD 4E 4E 1E 91 
2015/10/01 07:23:53 236C8F76 r   (reader)   F0 8A 37 19 29 87 03 52 29 19 98 20 35 1D 7B C1 
2015/10/01 07:23:53 236C8F76 r   (reader)   95 73 D2 9E 28 B5 0B B2 B1 4B 7B 09 32 86 DE AE 
2015/10/01 07:23:53 236C8F76 r   (reader)   99 8D 7F AB E7 B0 9E 7D D6 1C 2D 59 21 1D 91 C5 
2015/10/01 07:23:53 236C8F76 r   (reader)   C2 81 6C 51 43 9B B8 30 9B 47 51 E4 36 D0 16 53 
2015/10/01 07:23:53 236C8F76 r   (reader)   DF 55 05 1C 87 F0 B2 C6 D7 E9 C7 25 D9 DD 00 AD 
2015/10/01 07:23:53 236C8F76 r   (reader)   E7 48 83 FF 21 8E 73 3E AE 
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] Answer from cardreader:
2015/10/01 07:23:53 236C8F76 r   (reader)   90 00 
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] write to cardreader
2015/10/01 07:23:53 236C8F76 r   (reader)   D3 54 00 00 5E 
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] Answer from cardreader:
2015/10/01 07:23:53 236C8F76 r   (reader)   EC 02 0F 4D 96 2A 38 D1 A3 9F 4D 50 02 90 7E 80 
2015/10/01 07:23:53 236C8F76 r   (reader)   C7 A0 1D 49 85 72 29 FD 65 FE BC 63 22 77 BA 81 
2015/10/01 07:23:53 236C8F76 r   (reader)   BC 4C 3B 67 B8 4E B1 D7 92 B3 98 A0 FA 3B 10 36 
2015/10/01 07:23:53 236C8F76 r   (reader)   B6 23 CF 7C F7 05 98 F5 30 EF F2 30 55 22 5E 3F 
2015/10/01 07:23:53 236C8F76 r   (reader)   3D 83 48 0F DF FC FA BA 63 68 1A 43 BA 75 95 60 
2015/10/01 07:23:53 236C8F76 r   (reader)   1D 83 C4 99 F6 FF AC D3 69 5B 96 B5 30 F5 90 00 
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] Decrypted payload
2015/10/01 07:23:53 236C8F76 r   (reader)   8A BB 91 D6 8B 7C 40 47 00 00 00 CB 00 01 22 02 
2015/10/01 07:23:53 236C8F76 r   (reader)   00 00 0E 02 03 00 0F 06 00 00 00 00 00 01 20 04 
2015/10/01 07:23:53 236C8F76 r   (reader)   00 00 00 00 25 11 00 00 00 00 00 00 00 00 00 00 
2015/10/01 07:23:53 236C8F76 r   (reader)   00 00 00 00 00 00 00 2A 04 00 CB 00 00 55 01 28 
2015/10/01 07:23:53 236C8F76 r   (reader)   56 08 00 00 00 00 00 00 00 00 2B 02 00 00 
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] cardreader_do_ecm: after csystem->do_ecm rc=1
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] cardreader_do_ecm: ret rc=1
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] cardreader_process_ecm: cardreader_do_ecm returned rc=1 (ERROR=0)
2015/10/01 07:23:53 236C8F76 r   (reader) v14 [videoguard2] ecm hash: 4C71502D81671BF8A8F7344C5BE9B65E real time: 121 ms
2015/10/01 07:23:53 154C8158 c      (ecm) vdr (098C@000000/0BB9/0072/98:4C71502D81671BF8A8F7344C5BE9B65E:0F06000000:): found (123 ms) by v14 - Sky Sport HD 2
2015/10/01 07:23:53 154C8158 c      (ecm) cw:
2015/10/01 07:23:53 154C8158 c      (ecm)   00 00 00 00 00 00 00 00 8A BB 91 D6 8B 7C 40 47