EPG Scan

[humnab]

thanks for the great work ! One little issue I have on systems with more than one tuner, when the epg scan changes the PID's of scrambled channels, dvbapi looses the connection to oscam and the vdr reports "channel not available". Is this a know problem and is there a workaround ?

[pipelka]

Not known to me. But this would be a problem of VDR / dvbapi plugin.

[humnab]

Do you know if the Plugin works generally with more than one Tuner stable ? (I think the Problem is caused by the dual Tuner because I don't have it with the same Mainboard + Software Levels and a single Tuner Card)

This is what the Logs writes at the moment it happends (DVBAPI Log level was 0 unfortuantely):

vdr: [2308] changing pids of channel 62 from 1279+1279=27:0;1283=deu@106:0:32 to 1279+1279=27:0;1283=deu@106,1284=eng@106:0:32 vdr: [2250] retuning due to modification of channel 62 vdr: [2250] switching to channel 62 vdr: [2250] [softhddev]SetPlayMode: 0 vdr: [2250] [softhddev]SetVideoDisplayFormat: 1 vdr: [4280] ERROR: TS packet not accepted in Transfer Mode vdr: [4281] TS buffer on device 1 thread ended (pid=2250, tid=4281) vdr: [2250] info: Kanal nicht verfügbar!

[pipelka]

I'm using the dvbapi plugin on a dual tuner card in my server (with people watching simultaneously). I do not see this behaviour.

[humnab]

Ok, so it should work for me, too. I will enable the log Options of dvbapi and wait until the Problem will raise again. Perhaps you can see more than I in the Log.

[manio]

Hi The plugin was well tested on multiple tuner configurations. For me it works ok with two dvb cards. I cannot say more without logs...

[humnab]

Hi, here is a Log with Log Level 3:

Oct 5 09:41:00 hostname vdr: [14430] DVBAPI: DeCSASetCaDescr: index=0 Oct 5 09:41:00 hostname vdr: [14430] DVBAPI: SetDescr Oct 5 09:41:00 hostname vdr: [14430] DVBAPI: 0.0: even key set Oct 5 09:41:06 hostname vdr: [14372] DVBAPI: SetChannelDevice Oct 5 09:41:07 hostname vdr: [14430] DVBAPI: Action: Got CA_SET_DESCR request Oct 5 09:41:07 hostname vdr: [14430] DVBAPI: DeCSASetCaDescr: index=0 Oct 5 09:41:07 hostname vdr: [14430] DVBAPI: SetDescr Oct 5 09:41:07 hostname vdr: [14430] DVBAPI: 0.0: odd key set Oct 5 09:41:14 hostname vdr: [14430] DVBAPI: Action: Got CA_SET_DESCR request Oct 5 09:41:14 hostname vdr: [14430] DVBAPI: DeCSASetCaDescr: index=0 Oct 5 09:41:14 hostname vdr: [14430] DVBAPI: SetDescr Oct 5 09:41:14 hostname vdr: [14430] DVBAPI: 0.0: even key set Oct 5 09:41:16 hostname vdr: [14429] changing pids of channel 62 from 1279+1279=27:0;1283=deu@106,1284=eng@106:0:32 to 1279+1279=27:0;1283=deu@106:0:32 Oct 5 09:41:17 hostname vdr: [14372] retuning due to modification of channel 62 Oct 5 09:41:17 hostname vdr: [14372] switching to channel 62 Oct 5 09:41:17 hostname vdr: [14372] [softhddev]SetPlayMode: 0 Oct 5 09:41:17 hostname vdr: [14372] [softhddev]SetVideoDisplayFormat: 1 Oct 5 09:41:18 hostname vdr: [15206] ERROR: TS packet not accepted in Transfer Mode Oct 5 09:41:18 hostname vdr: [14372] DVBAPI: SetPid: on=0 Oct 5 09:41:18 hostname vdr: [14372] DVBAPI: ScActive Oct 5 09:41:18 hostname vdr: [14372] DVBAPI: SetPid: on=0 Oct 5 09:41:18 hostname vdr: [14372] DVBAPI: ScActive Oct 5 09:41:18 hostname vdr: [14372] DVBAPI: SetPid: on=0 Oct 5 09:41:18 hostname vdr: [14372] DVBAPI: ScActive Oct 5 09:41:18 hostname vdr: [15206] DVBAPI: CloseDvr Oct 5 09:41:18 hostname vdr: [14372] info: Kanal nicht verfügbar! Oct 5 09:41:18 hostname vdr: [20536] Text2Skin: message display update thread started (pid=14372, tid=20536) Oct 5 09:41:18 hostname vdr: [15207] TS buffer on device 1 thread ended (pid=14372, tid=15207) Oct 5 09:41:18 hostname vdr: [15206] DVBAPI: 0: reset state Oct 5 09:41:18 hostname vdr: [15206] DVBAPI: 0: set active off Oct 5 09:41:18 hostname vdr: [15206] buffer stats: 4194303 (100%) used Oct 5 09:41:18 hostname vdr: [15206] receiver on device 1 thread ended (pid=14372, tid=15206) Oct 5 09:41:18 hostname vdr: audio/alsa: wait underrun error? 'Datenübergabe unterbrochen (broken pipe)' Oct 5 09:41:19 hostname vdr: video: decoder buffer empty, duping frame (1155/1734172) 0 v-buf Oct 5 09:41:19 hostname vdr: video: 8:10:51.399+8888 0 0/\ms 0+5 v-buf Oct 5 09:41:21 hostname vdr: [20536] Text2Skin: message display update thread ended (pid=14372, tid=20536) Oct 5 09:41:21 hostname vdr: video: decoder buffer empty, duping frame (1240/1734172) 0 v-buf Oct 5 09:41:21 hostname vdr: video: 8:10:51.399+8888 0 0/\ms 0+5 v-buf Oct 5 09:41:21 hostname vdr: [14430] DVBAPI: Action: Got CA_SET_DESCR request Oct 5 09:41:21 hostname vdr: [14430] DVBAPI: DeCSASetCaDescr: index=0 Oct 5 09:41:21 hostname vdr: [14430] DVBAPI: SetDescr Oct 5 09:41:21 hostname vdr: [14430] DVBAPI: 0.0: odd key set Oct 5 09:41:22 hostname vdr: [14372] switching to channel 62 Oct 5 09:41:22 hostname vdr: [14372] info: Kanal nicht verfügbar! Oct 5 09:41:22 hostname vdr: [20539] Text2Skin: message display update thread started (pid=14372, tid=20539) Oct 5 09:41:24 hostname vdr: [20539] Text2Skin: message display update thread ended (pid=14372, tid=20539) Oct 5 09:41:27 hostname vdr: [14372] DVBAPI: SetChannelDevice Oct 5 09:41:27 hostname vdr: [14372] DVBAPI: SetChannelDevice Oct 5 09:41:33 hostname vdr: [14372] switching to channel 62 Oct 5 09:41:33 hostname vdr: [14372] info: Kanal nicht verfügbar! Oct 5 09:41:33 hostname vdr: [20540] Text2Skin: message display update thread started (pid=14372, tid=20540) Oct 5 09:41:35 hostname vdr: [20540] Text2Skin: message display update thread ended (pid=14372, tid=20540) Oct 5 09:41:44 hostname vdr: [14372] switching to channel 62 Oct 5 09:41:44 hostname vdr: [14372] info: Kanal nicht verfügbar! Oct 5 09:41:44 hostname vdr: [20541] Text2Skin: message display update thread started (pid=14372, tid=20541) Oct 5 09:41:46 hostname vdr: [20541] Text2Skin: message display update thread ended (pid=14372, tid=20541) Oct 5 09:41:48 hostname vdr: [14372] DVBAPI: SetChannelDevice Oct 5 09:41:48 hostname vdr: [14372] DVBAPI: SetChannelDevice Oct 5 09:41:55 hostname vdr: [14372] switching to channel 62 Oct 5 09:41:55 hostname vdr: [14372] info: Kanal nicht verfügbar!

[manio]

Hmmm... i think the problem may be related with: "retuning due to modification of channel". I don't know how to disable it - but you may try this config for tests: UpdateChannels = 0 i assume there was "CAM decrypt" line in the log (you did not provide it)...

is it working ok when you stop the channel and play it again?

[humnab]

This is the last CAM decrypt Ebtry I have, but its 9 hours before the Problem:

Oct 5 00:02:40 hostname vdr: [14430] DVBAPI: DeCSASetCaDescr: index=0 Oct 5 00:02:40 hostname vdr: [14430] DVBAPI: SetDescr Oct 5 00:02:40 hostname vdr: [14430] DVBAPI: 0.0: odd key set Oct 5 00:02:47 hostname vdr: [14430] DVBAPI: Action: Got CA_SET_DESCR request Oct 5 00:02:47 hostname vdr: [14430] DVBAPI: DeCSASetCaDescr: index=0 Oct 5 00:02:47 hostname vdr: [14430] DVBAPI: SetDescr Oct 5 00:02:47 hostname vdr: [14430] DVBAPI: 0.0: even key set Oct 5 00:02:54 hostname vdr: [14430] DVBAPI: Action: Got CA_SET_DESCR request Oct 5 00:02:54 hostname vdr: [14430] DVBAPI: DeCSASetCaDescr: index=0 Oct 5 00:02:54 hostname vdr: [14430] DVBAPI: SetDescr Oct 5 00:02:54 hostname vdr: [14430] DVBAPI: 0.0: odd key set Oct 5 00:02:58 hostname vdr: [14429] changing pids of channel 62 from 1279+1279=27:0;1283=deu@106:0:32 to 1279+1279=27:0;1283=deu@106,1284=eng@106:0:32 Oct 5 00:02:59 hostname vdr: [14372] retuning due to modification of channel 62 Oct 5 00:02:59 hostname vdr: [14372] switching to channel 62 Oct 5 00:02:59 hostname vdr: [14372] [softhddev]SetPlayMode: 0 Oct 5 00:02:59 hostname vdr: [14372] [softhddev]SetVideoDisplayFormat: 1 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: SetPid: on=0 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ScActive Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: SetPid: on=0 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ScActive Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: 0.0 CA_PMT decoding len=31 lm=5 prg=131 len=2b Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ci_cmd(G)=01 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: 0.0 got CA pmt ciCmd=1 caLm=5 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: 0.0 set CAM decrypt (SID 131, caLm 5, HasCaDescriptors 1) Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ProcessSIDRequest: closing socket i=0, socket_fd=35 Oct 5 00:02:59 hostname vdr: [14430] DVBAPI: Action: Got CA_SET_PID request Oct 5 00:02:59 hostname vdr: [14430] DVBAPI: DeCSASetCaPid: PID=1279, index=-1 Oct 5 00:02:59 hostname vdr: [14430] DVBAPI: DeCSASetCaPid: removal request - ignoring Oct 5 00:02:59 hostname vdr: [14430] DVBAPI: Action: Got CA_SET_PID request Oct 5 00:02:59 hostname vdr: [14430] DVBAPI: DeCSASetCaPid: PID=1283, index=-1 Oct 5 00:02:59 hostname vdr: [14430] DVBAPI: DeCSASetCaPid: removal request - ignoring Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: SetChannelDevice Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: SetPid: on=1 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ScActive Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: SetPid: on=1 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ScActive Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: SetPid: on=1 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ScActive Oct 5 00:02:59 hostname vdr: [15072] DVBAPI: CloseDvr Oct 5 00:02:59 hostname vdr: [15073] TS buffer on device 1 thread ended (pid=14372, tid=15073) Oct 5 00:02:59 hostname vdr: [15072] DVBAPI: 0: reset state Oct 5 00:02:59 hostname vdr: [15072] DVBAPI: 0: set active off Oct 5 00:02:59 hostname vdr: [15072] buffer stats: 422248 (10%) used Oct 5 00:02:59 hostname vdr: [15072] receiver on device 1 thread ended (pid=14372, tid=15072) Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: 0.0 CA_PMT decoding len=40 lm=4 prg=131 len=2b Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ci_cmd(G)=01 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: pid=2,04ff len=0 (0x0) Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: pid=6,0503 len=0 (0x0) Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: pid=6,0504 len=0 (0x0) Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: 0.0 got CA pmt ciCmd=1 caLm=4 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: 0.0 set CAM decrypt (SID 131, caLm 4, HasCaDescriptors 1) Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: ProcessSIDRequest: added: i=0 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: channelSid=0x83(131) Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: created socket with socket_fd=52 Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: socket_fd=52 length=64 toWrite=74 wrote=74 Oct 5 00:02:59 hostname vdr: [15206] receiver on device 1 thread started (pid=14372, tid=15206) Oct 5 00:02:59 hostname vdr: [15206] DVBAPI: OpenDvr Oct 5 00:02:59 hostname vdr: [15206] DVBAPI: CloseDvr Oct 5 00:02:59 hostname vdr: [15206] DVBAPI: ScActive

[manio]

it's strange so you don't have the set CAM decrypt line just before this problem? Was the channel still playing during this 9h period?

[humnab]

When believing the Logs, the Channel was Playing and decoding until Oct 5 09:41:18, I was not in front of the TV.

[humnab]

And no, the latest CAM decrypt Line is: Oct 5 00:02:59 hostname vdr: [14372] DVBAPI: 0.0 set CAM decrypt (SID 131, caLm 4, HasCaDescriptors 1)

[manio]

The line you provided is telling the oscam to start decrypting.

[humnab]

I will set UpdateChannels = 0 and if this does not work, I will setup up the box from scratch, modified a lot. But good to know that it should work generally.

[humnab]

After reinstalling the whole Box, the Problem didn't occur until now, I will close the issue. Thanks for helping me !