search

manio / vdr-plugin-dvbapi

VDR dvbapi plugin for use with OSCam
http://www.streamboard.tv/wbb2/thread.php?threadid=40060
GNU General Public License v2.0
57 stars 25 forks source link

Black picture after watching recording #74

Closed MegaV0lt closed 9 years ago

MegaV0lt commented 9 years ago

after watching a recording i have black picture. On other channel there is a recording runnung (syfy) Live should be n-tv.

2014/10/17 17:09:29 73E070 c [DVBAPI] Demuxer #0 continue decoding of SRVID 007E 2014/10/17 17:09:29 73E070 c [SKIP STREAM 0] CAID: 1833 ECM_PID: 1852 PROVID: 000000 2014/10/17 17:09:29 73E070 c [SKIP STREAM 1] CAID: 09C4 ECM_PID: 1B52 PROVID: 000000 2014/10/17 17:09:29 73E070 c [SKIP STREAM 2] CAID: 09AF ECM_PID: 1F52 PROVID: 000000 2014/10/17 17:09:29 73E070 c [SKIP STREAM 3] CAID: 098C ECM_PID: 1A52 PROVID: 000000 2014/10/17 17:09:29 73E070 c Found 4 ECMpids and 2 STREAMpids in PMT 2014/10/17 17:09:29 73E070 c [DVBAPI] Receiver wants to demux srvid 007E on adapter 0002 camask 0004 index 0000 pmtpid 0000 2014/10/17 17:09:29 73E070 c New program number: 007E (Syfy HD) [pmt_list_management 1] 2014/10/17 17:09:29 73E070 c [ADD PID 0] CAID: 1843 ECM_PID: 19AE PROVID: 000000 2014/10/17 17:09:29 73E070 c [ADD PID 1] CAID: 098C ECM_PID: 1AAE PROVID: 000000 2014/10/17 17:09:29 73E070 c [ADD PID 2] CAID: 09C4 ECM_PID: 1BAE PROVID: 000000 2014/10/17 17:09:29 73E070 c [ADD PID 3] CAID: 1830 ECM_PID: 1CAE PROVID: 000000 2014/10/17 17:09:29 73E070 c [ADD PID 4] CAID: 1860 ECM_PID: 1EAE PROVID: 000000 2014/10/17 17:09:29 73E070 c [ADD PID 5] CAID: 0648 ECM_PID: 18AE PROVID: 000000 2014/10/17 17:09:29 73E070 c Found 6 ECMpids and 1 STREAMpids in PMT 2014/10/17 17:09:29 73E070 c [DVBAPI] Receiver wants to demux srvid EF14 on adapter 0000 camask 0001 index 0000 pmtpid 0000 2014/10/17 17:09:29 73E070 c New program number: EF14 (n-tv HD) [pmt_list_management 2] 2014/10/17 17:09:29 73E070 c [DVBAPI] Demuxer #1 trying to descramble PID #0 CAID 1843 PROVID 000000 ECMPID 19AE ANY CHID PMTPID 0000 VPID 04FF 2014/10/17 17:09:29 73E070 c vdr01 (1843&/EF14/89): found (369 ms) by HD02 (L/1/2/2) - n-tv HD 2014/10/17 17:09:33 73E070 c vdr01 (098C&/007E/98): found (72 ms) by V14 (L/1/2/2) - Syfy HD

Using aktual dvbapi and oscam.

No picture at all if i zap around scrambled channels In log i can see that ecms are delivered like in teh last line above, but no picture

manio commented 9 years ago

Please provide dvbapi loglevel=3 log

MegaV0lt commented 9 years ago

Not much to see:

Oct 17 17:22:33 [vdr] [13382] switching to channel 3 Oct 17 17:22:33 [vdr] [13382] DVBAPI: 0.0 set CAM decrypt (SID 134, caLm 5, HasCaDescriptors 1) Oct 17 17:22:33 [vdr] audio/alsa: using device 'hw:NVidia,7' Oct 17 17:22:33 [vdr] audio/alsa: start delay 336ms

Must i restart VDR after changing the loglevel?

manio commented 9 years ago

Yes you need to restart.

MegaV0lt commented 9 years ago

Well no change: Oct 17 17:42:53 [vdr] [5645] switching to channel 92 Oct 17 17:42:53 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 124, caLm 5, HasCaDescriptors 1) Oct 17 17:42:53 [vdr] audio/alsa: using device 'hw:NVidia,7' Oct 17 17:42:53 [vdr] audio/alsa: start delay 336ms Oct 17 17:42:54 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 4, HasCaDescriptors 0) Oct 17 17:42:55 [vdr] [5645] retuning due to modification of channel 92 Oct 17 17:42:55 [vdr] [5645] switching to channel 92 Oct 17 17:42:55 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 5, HasCaDescriptors 1) Oct 17 17:42:56 [vdr] audio/alsa: using device 'hw:NVidia,7' Oct 17 17:42:56 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 4, HasCaDescriptors 1) Oct 17 17:42:56 [vdr] audio/alsa: start delay 336ms

MegaV0lt commented 9 years ago

no picture one other recording of syfy is running and ok, but i am afraid when more recordings appear they will be empty

manio commented 9 years ago

It's not a full debug log :( Could you at least tell me which version is working ok?

MegaV0lt commented 9 years ago

a grep of dvbapi: 3514: Oct 17 17:40:04 [G2V runvdr] Starting /usr/local/bin/vdr --localedir=/usr/local/share/locale --dirnames=1024,128,1 --terminal=/dev/tty8 --grab=/tmp --lirc=/dev/lircd -u root -w 60 --config=/etc/vdr --shutdown='/_config/bin/vdrshutdown' --epgfile=/var/vdr/epg.data --log=3 --video=/video --record='/tmp/vdr/vdr_record' --lib=/usr/local/lib/vdr --plugin='dvbapi ' --plugin='softhddevice -d :0 -v vdpau -a hw:NVidia,7 -w alsa-close-open-delay' --plugin='epgsearch ' --plugin='markad ' --plugin='dvd ' --plugin='music ' --plugin='femon ' --plugin='dvdswitch -r /etc/vdr/plugins/dvdswitch/dvdswitch_read.sh -w /etc/vdr/plugins/dvdswitch/dvdswitch_write.sh' --plugin='skinflatplus ' --plugin='skindesigner ' --plugin='skinenigmang --epgimages=/var/vdr/epgimages' --plugin='skinnopacity ' --plugin='epgsearchonly ' --plugin='tvscraper ' --plugin='filebrowser ' --plugin='fritzbox ' --plugin='osdteletext -d /tmp/osdteletext -n 20' --plugin='xmltv2vdr ' --plugin='imonlcd -p ffdc' --plugin='dbus2vdr ' --p 3519: Oct 17 17:40:04 [vdr] [5645] loading plugin: /usr/local/lib/vdr/libvdr-dvbapi.so.2.1.6 3572: Oct 17 17:40:05 [vdr] [5645] initializing plugin: dvbapi (2.1.1-GIT-766f613): SoftCAM für OSCam 3608: Oct 17 17:40:05 [vdr] [5645] starting plugin: dvbapi 3609: Oct 17 17:40:05 [vdr] [5645] DVBAPI: plugin version 2.1.1-GIT-766f613 initializing (VDR 2.1.6) 3610: Oct 17 17:40:05 [vdr] [5645] DVBAPI: decryption library: libdvbcsa 3611: Oct 17 17:40:05 [vdr] [5645] DVBAPI: Creating sCCIAdapter for device 0 3612: Oct 17 17:40:05 [vdr] [5645] DVBAPI: Creating sCCIAdapter for device 1 3613: Oct 17 17:40:05 [vdr] [5645] DVBAPI: Creating sCCIAdapter for device 2 3614: Oct 17 17:40:05 [vdr] [5645] DVBAPI: Creating sCCIAdapter for device 3 3615: Oct 17 17:40:05 [vdr] [5645] DVBAPI: Creating sCCIAdapter for device 4 3616: Oct 17 17:40:05 [vdr] [5645] DVBAPI: plugin started 3620: Oct 17 17:40:06 [vdr] [6167] DVBAPI: 0.0: doReply changed, reset triggered 3621: Oct 17 17:40:06 [vdr] [6167] DVBAPI: 0.0: now using CAIDs version 1 3622: Oct 17 17:40:06 [vdr] [6167] DVBAPI: 0.0: status 'present' 3624: Oct 17 17:40:06 [vdr] [6170] DVBAPI: 3.0: doReply changed, reset triggered 3625: Oct 17 17:40:06 [vdr] [6170] DVBAPI: 3.0: now using CAIDs version 1 3626: Oct 17 17:40:06 [vdr] [6170] DVBAPI: 3.0: status 'present' 3628: Oct 17 17:40:06 [vdr] [6169] DVBAPI: 2.0: doReply changed, reset triggered 3629: Oct 17 17:40:06 [vdr] [6169] DVBAPI: 2.0: now using CAIDs version 1 3630: Oct 17 17:40:06 [vdr] [6169] DVBAPI: 2.0: status 'present' 3632: Oct 17 17:40:06 [vdr] [6168] DVBAPI: 1.0: doReply changed, reset triggered 3633: Oct 17 17:40:06 [vdr] [6168] DVBAPI: 1.0: now using CAIDs version 1 3634: Oct 17 17:40:06 [vdr] [6168] DVBAPI: 1.0: status 'present' 3636: Oct 17 17:40:06 [vdr] [6171] DVBAPI: 4.0: doReply changed, reset triggered 3637: Oct 17 17:40:06 [vdr] [6171] DVBAPI: 4.0: now using CAIDs version 1 3638: Oct 17 17:40:06 [vdr] [6171] DVBAPI: 4.0: status 'present' 3640: Oct 17 17:40:06 [vdr] [6167] DVBAPI: 0.0: status 'reset' 3642: Oct 17 17:40:06 [vdr] [6170] DVBAPI: 3.0: status 'reset' 3644: Oct 17 17:40:06 [vdr] [6169] DVBAPI: 2.0: status 'reset' 3646: Oct 17 17:40:06 [vdr] [6168] DVBAPI: 1.0: status 'reset' 3648: Oct 17 17:40:06 [vdr] [6171] DVBAPI: 4.0: status 'reset' 3651: Oct 17 17:40:07 [vdr] [6167] DVBAPI: 0.0: status 'ready' 3653: Oct 17 17:40:07 [vdr] [6170] DVBAPI: 3.0: status 'ready' 3655: Oct 17 17:40:07 [vdr] [6169] DVBAPI: 2.0: status 'ready' 3657: Oct 17 17:40:07 [vdr] [6168] DVBAPI: 1.0: status 'ready' 3659: Oct 17 17:40:07 [vdr] [6171] DVBAPI: 4.0: status 'ready' 3661: Oct 17 17:40:07 [vdr] [6167] DVBAPI: CaInfo: 0.0 sending CA info 3662: Oct 17 17:40:07 [vdr] [6170] DVBAPI: CaInfo: 3.0 sending CA info 3663: Oct 17 17:40:07 [vdr] [6169] DVBAPI: CaInfo: 2.0 sending CA info 3664: Oct 17 17:40:07 [vdr] [6168] DVBAPI: CaInfo: 1.0 sending CA info 3665: Oct 17 17:40:07 [vdr] [6171] DVBAPI: CaInfo: 4.0 sending CA info 3876: Oct 17 17:40:12 [vdr] [5645] DVBAPI: 1.0 set CAM decrypt (SID 126, caLm 4, HasCaDescriptors 0) 4046: Oct 17 17:40:15 [vdr] [5645] DVBAPI: 1.0 set CAM decrypt (SID 126, caLm 5, HasCaDescriptors 1) 4057: Oct 17 17:40:15 [vdr] [5645] DVBAPI: 1.0 set CAM decrypt (SID 126, caLm 4, HasCaDescriptors 1) 4412: Oct 17 17:42:05 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 124, caLm 4, HasCaDescriptors 0) 4423: Oct 17 17:42:06 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 124, caLm 5, HasCaDescriptors 1) 4426: Oct 17 17:42:06 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 124, caLm 4, HasCaDescriptors 1) 4448: Oct 17 17:42:53 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 124, caLm 5, HasCaDescriptors 1) 4451: Oct 17 17:42:54 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 4, HasCaDescriptors 0) 4455: Oct 17 17:42:55 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 5, HasCaDescriptors 1) 4457: Oct 17 17:42:56 [vdr] [5645] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 4, HasCaDescriptors 1)

I only have 4 cards, but 5 CAM's?

MegaV0lt commented 9 years ago

how do i get a full debug log? I have set LogLevel to 3 and restarted VDR Last working version was the one before today. i check old log... This one was working: Oct 17 02:33:55 [vdr] [4884] initializing plugin: dvbapi (2.1.1-GIT-2617a7f): SoftCAM für OSCam

3PO commented 9 years ago

i guess, same Problem as here: --> http://tinyurl.com/m666dmf

manio commented 9 years ago

Stop VDR. Edit the setup.conf and change the dvbapi.loglevel to 3. Start VDR.

MegaV0lt commented 9 years ago

hdvdr01 dvbapi # grep dvbapi /etc/vdr/setup.conf dvbapi.LogLevel = 3 dvbapi.OSCamHost = SheevaPlug dvbapi.OSCamNetwork = 1 dvbapi.OSCamPort = 20033

any tip?

How can i get the version back that was working with git pull ??

manio commented 9 years ago

git checkout 2617a7f (in the plugin dir)

manio commented 9 years ago

Sorry - no tip at the moment. It is working ok for me (I was trying hard to have it working also with old oscam). Will see if we have similar problems reported.

MegaV0lt commented 9 years ago

Well, please try to make one recording (scrambled) ond then try to zap to other scrambled channel. I will do mere test tomorrow...

manio commented 9 years ago

I was doing this kind of tests many times. Works like a charm for me.

manio commented 9 years ago

Just two questions: What mode (network/socket) you are using? What oscam version you have exactly?

MegaV0lt commented 9 years ago

On VDR: hdvdr01 dvbapi # grep dvbapi /etc/vdr/setup.conf dvbapi.LogLevel = 3 dvbapi.OSCamHost = SheevaPlug dvbapi.OSCamNetwork = 1 dvbapi.OSCamPort = 20033

OSCam on Server: Version: oscam-1.20-unstable_svn-r9918 ... [dvbapi] enabled = 1 au = 1 pmt_mode = 4 listen_port = 20033 delayer = 50 user = vdr01 boxtype = pc

with the older dvbapi-Plugin it is workin as expected without changing anything.

manio commented 9 years ago

Yes, for me it also works without changing anything. Please provide me a full dvbapi debug log. Without it I cannot help you. Change your system logger if necessary!

MegaV0lt commented 9 years ago

i changed metalog loglevel from 6 to 7 and log is now full debug. You can take a look. I was thinking it works, but is not https://dl.dropboxusercontent.com/u/1490505/VDR/G2V_V5/log/dvbapi.log

MegaV0lt commented 9 years ago

Too early. It is NOT Working:

Oct 18 10:12:34 [vdr] [8473] switching to channel 92 Oct 18 10:12:34 [vdr] [8473] [softhddev]SetPlayMode: 0 Oct 18 10:12:34 [vdr] video: slow down video, duping frame Oct 18 10:12:34 [vdr] video: decoder buffer empty, duping frame (324/12) 0 v-buf Oct 18 10:12:34 [vdr] video: --:--:--.--- +0 0 240/\ms 0+5 v-buf Oct 18 10:12:34 [vdr] [8473] [softhddev]SetVideoDisplayFormat: 0 Oct 18 10:12:34 [vdr] [8473] [softhddev]GetSpuDecoder: Oct 18 10:12:34 [vdr] [17927] osdteletext-receiver thread ended (pid=8473, tid=17927) Oct 18 10:12:34 [vdr] [8473] buffer stats: 0 (0%) used Oct 18 10:12:34 [vdr] audio/alsa: using device 'hw:NVidia,7'_ Oct 18 10:12:34 [vdr] [8473] CAM 3: assigned to device 3 Oct 18 10:12:34 [vdr] [8473] DVBAPI: 2.0 CA_PMT decoding len=18e lm=4 prg=61204 len=17e Oct 18 10:12:34 [vdr] [8473] DVBAPI: ci_cmd(G)=01 Oct 18 10:12:34 [vdr] [8473] DVBAPI: pid=2,04ff len=0 (0x0) Oct 18 10:12:34 [vdr] [8473] DVBAPI: pid=6,0503 len=0 (0x0) Oct 18 10:12:34 [vdr] [8473] DVBAPI: 2.0 got CA pmt ciCmd=1 caLm=4 Oct 18 10:12:34 [vdr] [8473] DVBAPI: 2.0 answer to query suppressed Oct 18 10:12:34 [vdr] [8473] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 4, HasCaDescriptors 1) Oct 18 10:12:34 [vdr] [8473] DVBAPI: send: channelSid=0x204 (516) Oct 18 10:12:34 [vdr] [8473] DVBAPI: Write, sock=72 Oct 18 10:12:34 [vdr] [8473] DVBAPI: socket_fd=72 len=59 wrote=59 Oct 18 10:12:34 [vdr] [8473] DVBAPI: send: channelSid=0xef14 (61204) Oct 18 10:12:34 [vdr] [8473] DVBAPI: Write, sock=72 Oct 18 10:12:34 [vdr] [8473] DVBAPI: socketfd=72 len=408 wrote=408 Oct 18 10:12:34 [vdr] [17932] receiver on device 3 thread started (pid=8473, tid=17932, prio=high) Oct 18 10:12:34 [vdr] [17934] TS buffer on device 3 thread started (pid=8473, tid=17934, prio=high) Oct 18 10:12:34 [vdr] [17933] osdteletext-receiver thread started (pid=8473, tid=17933, prio=high) Oct 18 10:12:34 [vdr] audio/alsa: start delay 336ms Oct 18 10:12:34 [vdr] [8908] DVBAPI: Action: Got DMX_SET_FILTER request, adapter_index=2, pid=1, demux_idx=1, filter_num=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: adapter=2 set FILTER pid=0001 start=1, demux=1, filter=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> FILTER: 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> MASK: FF 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: inserting new filter, demux=1, filter_num=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: Action: Got DMX_SET_FILTER request, adapter_index=2, pid=19AE, demux_idx=1, filter_num=1 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: adapter=2 set FILTER pid=19AE start=1, demux=1, filter=1 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> FILTER: 80 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> MASK: F0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: inserting new filter, demux=1, filter_num=1 Oct 18 10:12:34 [vdr] [17932] DVBAPI: Analyze: all data in one TS packet, immediate send Oct 18 10:12:34 [vdr] [17932] DVBAPI: Write, sock=72 Oct 18 10:12:34 [vdr] [17932] DVBAPI: socket_fd=72 len=48 wrote=48 Oct 18 10:12:34 [vdr] [8908] DVBAPI: Action: Got DMX_STOP request, adapter_index=2, pid=1, demux_idx=1, filter_num=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: adapter=2 set FILTER pid=0001 start=0, demux=1, filter=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: deleting filter, demux=1, filter_num=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: deleted the last filter for pid=0001, removing list Oct 18 10:12:34 [vdr] [8908] DVBAPI: Action: Got DMX_SET_FILTER request, adapter_index=2, pid=1005, demux_idx=1, filter_num=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: adapter=2 set FILTER pid=1005 start=1, demux=1, filter=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> FILTER: 82 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> MASK: FF 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: inserting new filter, demux=1, filter_num=0 Oct 18 10:12:34 [vdr] [8908] DVBAPI: Action: Got DMX_SET_FILTER request, adapter_index=2, pid=1005, demux_idx=1, filter_num=2 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: adapter=2 set FILTER pid=1005 start=1, demux=1, filter=2 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> FILTER: 83 22 CF 48 00 10 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> MASK: FF FF FF FF FF FF 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: inserting new filter, demux=1, filter_num=2 Oct 18 10:12:34 [vdr] [8908] DVBAPI: Action: Got DMX_SET_FILTER request, adapter_index=2, pid=1005, demux_idx=1, filter_num=3 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: adapter=2 set FILTER pid=1005 start=1, demux=1, filter=3 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> FILTER: 83 22 CF 48 22 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> MASK: FF FF FF FF FF FF 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: inserting new filter, demux=1, filternum=3 Oct 18 10:12:34 [vdr] [8473] [softhddev]SetPlayMode: 1 Oct 18 10:12:34 [vdr] [17932] DVBAPI: Analyze: all data in one TS packet, immediate send Oct 18 10:12:34 [vdr] [17932] DVBAPI: Write, sock=72 Oct 18 10:12:34 [vdr] [17932] DVBAPI: socketfd=72 len=143 wrote=143 Oct 18 10:12:34 [vdr] [softhddev] invalid PES video packet Oct 18 10:12:34 [vdr] [8908] DVBAPI: Action: Got DMX_SET_FILTER request, adapter_index=2, pid=19AE, demux_idx=1, filter_num=1 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: adapter=2 set FILTER pid=19AE start=1, demux=1, filter=1 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> FILTER: 81 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: --> MASK: FF 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Oct 18 10:12:34 [vdr] [8908] DVBAPI: SetFilter: filter update, demux=1, filternum=1 Oct 18 10:12:34 [vdr] audio/alsa: using device 'hw:NVidia,7' Oct 18 10:12:35 [vdr] audio/alsa: start delay 336ms_ Oct 18 10:12:35 [vdr] [8908] DVBAPI: Action: Got CA_SET_PID request, adapter_index=2

manio commented 9 years ago

Acording to your log all should be fine. The keys are obtained, filters are attached... I don't know what could be wrong. I don't have similar problems reported from other users :(

manio commented 9 years ago

Is it better with http://pastebin.com/ixPmRs6H ?

MegaV0lt commented 9 years ago

Can not test now, but wednesday. I will report here when test is done. thanks for helping!

MegaV0lt commented 9 years ago

Sorry. With patch also no picture on other tuners, when one recording. Log: https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/log_patch.txt

from start of VDR. Recording scrambeld n-tv (92) - Switch to Ska Atlantic (5) > No Picture. On OSCam i see ECM are delivered. OSCam 9928 from today!

manio commented 9 years ago

I am analysing your log and I don't see anything unusual. All is done properly. After VDR start you started SID 61204 on adapter 2. It started fine and keys are provided by oscam, next you started SID 110 on adapter 0. And it was running simultaneously fine for a while, then you stopped the SID 110 on adapter 0 and the SID 61204 was still running. So overally what is wrong? Recording are quite different thing - it is not handled by the dvbapi plugin.

Maybe you can try the latest OSCam and FFdecsa? I think your problems may be because of your complex configuration. Every plugin you have can influence on VDR somehow and overall you have problems. Your commandline to run VDR is:

Starting /usr/local/bin/vdr --localedir=/usr/local/share/locale --dirnames=1024,255,1 --terminal=/dev/tty8 --grab=/tmp --lirc=/dev/lircd -u root -w 60 --config=/etc/vdr --shutdown='/_config/bin/vdrshutdown' --epgfile=/var/vdr/epg.data --log=3 --video=/video --record='/tmp/vdr/vdr_record' --lib=/usr/local/lib/vdr --plugin='dvbapi ' --plugin='softhddevice -d :0 -v vdpau -a hw:NVidia,7 -w alsa-close-open-delay' --plugin='epgsearch ' --plugin='markad ' --plugin='dvd ' --plugin='music ' --plugin='femon ' --plugin='dvdswitch -r /etc/vdr/plugins/dvdswitch/dvdswitch_read.sh -w /etc/vdr/plugins/dvdswitch/dvdswitch_write.sh' --plugin='skinflatplus ' --plugin='skindesigner ' --plugin='skinelchi --logos=/etc/vdr/plugins/skinelchi --icache=/var/vdr/epgimages' --plugin='skinenigmang --epgimages=/var/vdr/epgimages' --plugin='epgsearchonly ' --plugin='tvscraper ' --plugin='filebrowser ' --plugin='fritzbox ' --plugin='osdteletext -d /tmp/osdteletext -n 20' --plugin='xmltv2vdr ' --plugin='imonlcd -p ffdc' --plugin='dbus2vdr ' --plugin='admin '

While mine is:

vdr -Pdvbapi -Pxvdr

Do you really need all this plugins? Try if you still have problems when you run vdr like me or with some minimal plugin set.

manio commented 9 years ago

Maybe 3PO has right indeed. I found your problem! See: This is your switching:

grep switching log_patch.txt
Oct 22 11:13:08 [vdr] [4060] switching to channel 93
Oct 22 11:13:08 [vdr] [4060] switching device 3 to channel 92
Oct 22 11:13:11 [vdr] [4060] switching device 3 to channel 92
Oct 22 11:13:27 [vdr] [4060] switching to channel 92
Oct 22 11:14:09 [vdr] [4060] switching to channel 5
Oct 22 11:14:10 [vdr] [4060] switching to channel 5
Oct 22 11:14:33 [vdr] [4060] switching to channel 92

here is how VDR was assigning a CAMs:

grep "assigned" log_patch.txt
Oct 22 11:13:08 [vdr] [4060] CAM 3: assigned to device 3
Oct 22 11:13:11 [vdr] [4060] CAM 3: unassigned
Oct 22 11:13:11 [vdr] [4060] CAM 3: assigned to device 3
Oct 22 11:14:09 [vdr] [4060] CAM 1: assigned to device 1
Oct 22 11:14:10 [vdr] [4060] CAM 1: unassigned
Oct 22 11:14:10 [vdr] [4060] CAM 1: assigned to device 1
Oct 22 11:14:34 [vdr] [4060] CAM 1: unassigned

This assigned/unassigned is ok due to no CA descriptor data for the first zap. The fact is that no CAM was assigned for your channel 5. And this is the main problem here.

and finally here is what my plugin was requested for decrypt:

grep "CAM decrypt" log_patch.txt
Oct 22 11:13:09 [vdr] [4060] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 4, HasCaDescriptors 0)
Oct 22 11:13:11 [vdr] [4060] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 5, HasCaDescriptors 1)
Oct 22 11:13:11 [vdr] [4060] DVBAPI: 2.0 set CAM decrypt (SID 61204, caLm 4, HasCaDescriptors 1)
Oct 22 11:14:09 [vdr] [4060] DVBAPI: 0.0 set CAM decrypt (SID 110, caLm 4, HasCaDescriptors 0)
Oct 22 11:14:10 [vdr] [4060] DVBAPI: 0.0 set CAM decrypt (SID 110, caLm 5, HasCaDescriptors 1)
Oct 22 11:14:10 [vdr] [4060] DVBAPI: 0.0 set CAM decrypt (SID 110, caLm 4, HasCaDescriptors 1)
Oct 22 11:14:34 [vdr] [4060] DVBAPI: 0.0 set CAM decrypt (SID 110, caLm 5, HasCaDescriptors 1)

I don't see any request about channel 5 (Ska Atlantic). It is not dvbapi plugin problem. Report it to VDR. Closing.

MegaV0lt commented 9 years ago

I do not understand why it should not be an issue with dvbapi.. When i go back to 2617a7f27e25c68ed85953afb21b94e01b723acb it is working perfect.

Most of the plugins are needed. I can disable 'dvd' 'music' 'femon' 'dvdswitch' 'skindesigner' 'skinelchi' 'skinenigmang' 'epgsearchonly' 'filebrowser'

so i must stay with old version of plugin until problem in VDr is fixed?

manio commented 9 years ago

Your logs is telling me that it is not a dvbapi plugin problem.

If you stay at your opinion, can you tell me exactly which commit introduce the problem? You can check: 4baab74, 16f717e, d08846b, 14210a7, b5bab85, 136554f

MegaV0lt commented 9 years ago

Hi Manio,

i tested the versions. Form GIT-16f717e on it is not working and i get strange log. at end of file there is also part of oscam log: https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/DVBAPI_%20plugin%20version%202.1.1-GIT-16f717e%20initializing%20%28VDR%202.1.6%29.txt

So the 4baab749c97b85ebc66660a404a6b2c58a5cdb72 is the last working one for me

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 22.10.2014 17:10

Your logs is telling me that it is not a dvbapi plugin problem.

If you stay at your opinion, can you tell me exactly which commit introduce the problem? You can check: 4baab74 https://github.com/manio/vdr-plugin-dvbapi/commit/4baab749c97b85ebc66660a404a6b2c58a5cdb72, 16f717e https://github.com/manio/vdr-plugin-dvbapi/commit/16f717ee70fa219986e0cced1d2565842cb65c7e, d08846b https://github.com/manio/vdr-plugin-dvbapi/commit/d08846b4f2ad167a6ed283647f4c7c39198935cb, 14210a7 https://github.com/manio/vdr-plugin-dvbapi/commit/14210a7312667df47775d7662205a1e92048bc9e, b5bab85 https://github.com/manio/vdr-plugin-dvbapi/commit/b5bab856597a07c97c1b51bc8131fc66983bc9ec, 136554f https://github.com/manio/vdr-plugin-dvbapi/commit/136554fa54d02ae5cc058b440f2b5b993a6ce201

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60100260.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

manio commented 9 years ago

Oh sorry if you compiled 16f717e then you can't use it with newest oscam. In this case you need to test on d08846b4f2ad167a6ed283647f4c7c39198935cb. I assume it's not working on this version too?

Please provide me plugin loglevel=3 + oscam loglevel=128.

manio commented 9 years ago

And to be sure you can do a quick test on my latest plugin version. Apply this and see if it works ok: http://skyboo.net/pub/disable_proto1.diff

MegaV0lt commented 9 years ago

Ok, compiled d088...

Logs: https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/gitd08846b.txt https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/oscamwitgit08846b.txt

Start recording of channel5 and then switch to next and so on. black picture too...

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 23.10.2014 12:02

Oh sorry if you compiled 16f717e https://github.com/manio/vdr-plugin-dvbapi/commit/16f717ee70fa219986e0cced1d2565842cb65c7e then you can't use it with newest oscam. In this case you need to test on d08846b https://github.com/manio/vdr-plugin-dvbapi/commit/d08846b4f2ad167a6ed283647f4c7c39198935cb. I assume it's not working on this version too?

Please provide me plugin loglevel=3 + oscam loglevel=128.

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60217376.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

manio commented 9 years ago

Thanks! Now please do:

  1. Send me the same logs but for clean oscam and my latest master plugin (clean).
  2. Apply the http://skyboo.net/pub/disable_proto1.diff patch to my plugin and retest.
MegaV0lt commented 9 years ago

Thanks for the patch. I did as you said. but updated oscam from 9929 to actual 9931

Logs for 1. No picture after recording and zapping: https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/master_clean.txt https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/r9931.log

Logs for 2. Surprise! Firt test is ok... https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/master_patched.txt https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/r9931-2.log

I hope the logs are useful for you and you can see what is going wrong...

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 23.10.2014 12:56

Thanks! Now please do:

  1. Send me the same logs but for clean oscam and my latest master plugin (clean).
  2. Apply the http://skyboo.net/pub/disable_proto1.diff patch to my plugin and retest.

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60222395.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

manio commented 9 years ago

Ok... I am starting to tracking the problem down. Please apply this patch to clean oscam: http://skyboo.net/pub/oscam-capid.diff and use my master plugin. It will not work, but i need the OSCam 128 log.

MegaV0lt commented 9 years ago

Hi Manio, thank you for the Patch. As expected no picture on other channels when recording...

LoG: https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/r9931-debug.log

After start of recording of channel 92 i was zapping...

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 23.10.2014 14:50

Ok... I am starting to tracking the problem down. Please apply this patch to clean oscam: http://skyboo.net/pub/oscam-capid.diff and use my master plugin. It will not work, but i need the OSCam 128 log.

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60233483.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

MegaV0lt commented 9 years ago

just in case: Hereis the vdr log from that time: https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/master_clean-1.txt

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 23.10.2014 14:50

Ok... I am starting to tracking the problem down. Please apply this patch to clean oscam: http://skyboo.net/pub/oscam-capid.diff and use my master plugin. It will not work, but i need the OSCam 128 log.

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60233483.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

manio commented 9 years ago

Ok. There may be some align problem. What platform you are using where oscam is running? Please apply this patch on clean oscam and resend me oscam logs: http://skyboo.net/pub/oscam-capid.diff

MegaV0lt commented 9 years ago

Hi,

i am using a SheevaPlug as OSCam Server. CPU is:

[SheevaPlug] ~ > uname -a Linux SheevaPlug 3.7.10 #3 PREEMPT Wed Feb 27 23:18:20 MST 2013 armv5tel GNU/Linux [SheevaPlug] ~ > cat /proc/cpuinfo Processor : Feroceon 88FR131 rev 1 (v5l) BogoMIPS : 1191.11 Features : swp half thumb fastmult edsp CPU implementer : 0x56 CPU architecture: 5TE CPU variant : 0x2 CPU part : 0x131 CPU revision : 1

Hardware : Marvell SheevaPlug Reference Board Revision : 0000 Serial : 0000000000000000

Should be similar to raspberry, dockstar and so...

The patch is the same you already sent to me! I already sent log with pached oscam: https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/r9931-debug.log

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 24.10.2014 08:58

Ok. There may be some align problem. What platform you are using where oscam is running? Please apply this patch on clean oscam and resend me oscam logs: http://skyboo.net/pub/oscam-capid.diff

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60351203.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

manio commented 9 years ago

Ah damn, I meant this patch: http://skyboo.net/pub/oscam-capid-v2.diff

MegaV0lt commented 9 years ago

Hi,

the patch does not fit to th eactual build 9945 - gets rejkected. But as ju said it may have to do with alignement i googled and found that i hav eto add

echo 2 > /proc/cpu/alignment

to the startscript of oscam.

when i do this i have picture! when i echo 0 > /pr... the picture freezes again...

So i think the alignement fixes it so far, but is bad for performance, becuase kernel is fixxing bad alignements. Maybe the recent oscam fixes are for that problem? But still not working with 0. so oscam is still missaligning the things...

strange enought that everything else works perfekt. only the new proto for dvbapi is failing ...

For now i keep the echo 2 ... in my startskript. Can you add an notice to the plugins Readme?

Thank you very much for your help

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 24.10.2014 12:44

Ah damn, I mean this patch: http://skyboo.net/pub/oscam-capid-v2.diff

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60371590.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

manio commented 9 years ago

No, it has to be fixed properly. Hold on with the readme :) Hey, isn't it fixed now in the oscam?

MegaV0lt commented 9 years ago

Hi Manio,

you are right. I made a restart of oscam and noe even with echo 0 ... i get a goot picture! seems to be fixed... at least we need oscam 9945 to be used with the new protocol feature an arm. that can be in readme i think -------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 24.10.2014 15:07

No, it has to be fixed properly. Hold on with the readme :) Hey, isn't it fixed now in the oscam?

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60384047.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

manio commented 9 years ago

So they was faster then me :) Thank you for your time, and I'm sorry about ignoring you previously... according to your logs I was almost sure that it is not related with the plugin, and finally it was an oscam problem.

Have a good weekend! :)

MegaV0lt commented 9 years ago

Hi Manio,

well it is me that finaly have to thank you for the great work and the plugin!

Nice weekend to youu too

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 24.10.2014 17:38

So they was faster then me :) Thank you for your time, and I'm sorry about ignoring you previously... according to your logs I was almost sure that it is not related with the plugin, and finally it was an oscam problem.

Have a good weekend! :)

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60404835.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

manio commented 9 years ago

Please do one more thing for me: Please test if it still (and also works) with this patch on top of latest oscam: http://skyboo.net/pub/oscam-capid-v3.diff

manio commented 9 years ago

Don't test it - it's crashing, ok forget about it :)

manio commented 9 years ago

Fixed, please try this: http://skyboo.net/pub/oscam-capid-v3.diff

MegaV0lt commented 9 years ago

Hi Manio,

i can test is today from 12 oclock on, because i must go out ... i will mail you the result then!

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 24.10.2014 18:24

Fixed, please try this: http://skyboo.net/pub/oscam-capid-v3.diff

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60411668.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com

MegaV0lt commented 9 years ago

Hi Manio,

with the V3 i do not have any scrambled program not even live. i tried wit echo 0 and 2. https://dl.dropboxusercontent.com/u/1490505/VDR/dvbapi/r9949_patch-crash.log

OSCam is also crashing... So i go back to normal one...

-------- Original-Nachricht -------- Betreff: Re: [vdr-plugin-dvbapi] Black picture after watching recording (#74) Von: Mariusz Białończyk notifications@github.com An: manio/vdr-plugin-dvbapi vdr-plugin-dvbapi@noreply.github.com Datum: 24.10.2014 18:24

Fixed, please try this: http://skyboo.net/pub/oscam-capid-v3.diff

— Reply to this email directly or view it on GitHub https://github.com/manio/vdr-plugin-dvbapi/issues/74#issuecomment-60411668.

Diese E-Mail wurde von Avast Antivirus-Software auf Viren geprüft. http://www.avast.com