2015-06-19, 12:19
Hello,
i am using OE 6.0 Beta 2 right now and since the last update from vnsi i have massive problems with encrypted programms. After channel switch i have several error codes from vnsi 2,5 and 7.
vdr log says:
Jun 19 11:27:06 OpenELEC vdr[14422]: [15388] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1535
Jun 19 11:27:09 OpenELEC vdr[14422]: [15388] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1535
Jun 19 11:27:13 OpenELEC vdr[14422]: [15388] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1535
These problems are only on pay tv and therefore encrypted channels but the SmardCard is okay and activated and alive. Also SmartCard backend is configured and in a functional state... SmartCard Backend and vdr are not changed since march/april. The problems occured first since update of dvbapi/vnsi server mid/end may.
here some logs with enabled dvbapi log = 3
i had already problems with the last vnsi server version and picture artifacts every 20-30 seconds. This seems to be solved by setting priority once to -99 and back to -1.
when i switch to another channel and then back it is very likely the channel is decrypted.
any clues?
i am using OE 6.0 Beta 2 right now and since the last update from vnsi i have massive problems with encrypted programms. After channel switch i have several error codes from vnsi 2,5 and 7.
vdr log says:
Jun 19 11:27:06 OpenELEC vdr[14422]: [15388] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1535
Jun 19 11:27:09 OpenELEC vdr[14422]: [15388] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1535
Jun 19 11:27:13 OpenELEC vdr[14422]: [15388] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1535
These problems are only on pay tv and therefore encrypted channels but the SmardCard is okay and activated and alive. Also SmartCard backend is configured and in a functional state... SmartCard Backend and vdr are not changed since march/april. The problems occured first since update of dvbapi/vnsi server mid/end may.
here some logs with enabled dvbapi log = 3
Code:
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: inserting new filter, demux=0, filter_num=1
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got DMX_SET_FILTER request, adapter_index=0, pid=1008, demux_idx=0, filter_num=2
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: adapter=0 set FILTER pid=1008 start=1, demux=0, filter=2
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: --> FILTER: 82 40 00 00 00 00 00 8C 4B AA 00 00 00 00 00 00
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: --> MASK: FF C0 00 00 00 00 FF FF FF FF 00 00 00 00 00 00
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: inserting new filter, demux=0, filter_num=2
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got DMX_SET_FILTER request, adapter_index=0, pid=1008, demux_idx=0, filter_num=3
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: adapter=0 set FILTER pid=1008 start=1, demux=0, filter=3
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: --> FILTER: 82 40 00 00 00 00 00 00 00 00 00 8C 4B AA 00 00
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: --> MASK: FF C0 00 00 00 00 00 00 00 00 FF FF FF FF 00 00
Jun 19 12:02:08 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: inserting new filter, demux=0, filter_num=3
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: 1.0 CA_PMT decoding len=1f lm=5 prg=107 len=19
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: ci_cmd(G)=01
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: 1.0 got CA pmt ciCmd=1 caLm=5
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: 1.0 answer to query suppressed
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: 1.0 set CAM decrypt (SID 107 (0x006B), caLm 5, HasCaDescriptors 1)
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: send: channelSid=0x6b (107)
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: Write, sock=13
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: socket_fd=13 len=51 wrote=51
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: 1.0 CA_PMT decoding len=6 lm=3 prg=0 len=0
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: 1.0 got CA pmt ciCmd=-1 caLm=3
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: 1.0 answer to query suppressed
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] DVBAPI: 1.0 stop decrypt
Jun 19 12:02:09 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got DMX_STOP request, adapter_index=1, pid=1A0B, demux_idx=1, filter_num=0
Jun 19 12:02:09 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: adapter=1 set FILTER pid=1A0B start=0, demux=1, filter=0
Jun 19 12:02:09 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: deleting filter, demux=1, filter_num=0
Jun 19 12:02:09 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: deleted the last filter for pid=1A0B, removing list
Jun 19 12:02:09 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got CA_SET_PID request, adapter_index=1
Jun 19 12:02:09 OpenELEC vdr[21050]: [21111] DVBAPI: 0.-1: set pid 0x02ff
Jun 19 12:02:09 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got CA_SET_PID request, adapter_index=1
Jun 19 12:02:09 OpenELEC vdr[21050]: [21111] DVBAPI: 0.-1: set pid 0x0303
Jun 19 12:02:09 OpenELEC vdr[21050]: [21366] EEPG: setstatus 0
Jun 19 12:02:09 OpenELEC vdr[21050]: [21659] DVBAPI: 1.0 CA_PMT decoding len=15 lm=4 prg=116 len=0
Jun 19 12:02:09 OpenELEC vdr[21050]: [21659] DVBAPI: pid=2,03ff len=0 (0x0)
Jun 19 12:02:09 OpenELEC vdr[21050]: [21659] DVBAPI: pid=6,0403 len=0 (0x0)
Jun 19 12:02:09 OpenELEC vdr[21050]: [21659] DVBAPI: pid=6,0404 len=0 (0x0)
Jun 19 12:02:09 OpenELEC vdr[21050]: [21659] DVBAPI: 1.0 got CA pmt ciCmd=-1 caLm=4
Jun 19 12:02:09 OpenELEC vdr[21050]: [21659] DVBAPI: 1.0 answer to query suppressed
Jun 19 12:02:09 OpenELEC vdr[21050]: [21659] DVBAPI: 1.0 set CAM decrypt (SID 116 (0x0074), caLm 4, HasCaDescriptors 0)
Jun 19 12:02:09 OpenELEC vdr[21050]: [21659] DVBAPI: CA_PMT doesn't contain CA descriptors
Jun 19 12:02:10 OpenELEC vdr[21050]: [21110] EEPG: setstatus 0
Jun 19 12:02:10 OpenELEC vdr[21050]: [21110] EEPG: setstatus 1
Jun 19 12:02:10 OpenELEC vdr[21050]: [21110] EEPG: Filter Pid:0,Tid:0 added.
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: 0.0 CA_PMT decoding len=1f lm=5 prg=107 len=19
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: ci_cmd(G)=01
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: 0.0 got CA pmt ciCmd=1 caLm=5
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: 0.0 answer to query suppressed
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: 0.0 set CAM decrypt (SID 107 (0x006B), caLm 5, HasCaDescriptors 1)
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: SendStopDescrambling
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: Write, sock=13
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: socket_fd=13 len=8 wrote=8
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: 0.0 CA_PMT decoding len=6 lm=3 prg=0 len=0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: 0.0 got CA pmt ciCmd=-1 caLm=3
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: 0.0 answer to query suppressed
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] DVBAPI: 0.0 stop decrypt
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got DMX_STOP request, adapter_index=0, pid=1008, demux_idx=0, filter_num=1
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: adapter=0 set FILTER pid=1008 start=0, demux=0, filter=1
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: deleting filter, demux=0, filter_num=1
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got DMX_STOP request, adapter_index=0, pid=1008, demux_idx=0, filter_num=2
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: adapter=0 set FILTER pid=1008 start=0, demux=0, filter=2
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: deleting filter, demux=0, filter_num=2
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got DMX_STOP request, adapter_index=0, pid=1008, demux_idx=0, filter_num=3
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: adapter=0 set FILTER pid=1008 start=0, demux=0, filter=3
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: deleting filter, demux=0, filter_num=3
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: deleted the last filter for pid=1008, removing list
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got DMX_STOP request, adapter_index=0, pid=1A0B, demux_idx=0, filter_num=0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: adapter=0 set FILTER pid=1A0B start=0, demux=0, filter=0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: deleting filter, demux=0, filter_num=0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: SetFilter: deleted the last filter for pid=1A0B, removing list
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got CA_SET_PID request, adapter_index=0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: 0.-1: set pid 0x02ff
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: Action: Got CA_SET_PID request, adapter_index=0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21111] DVBAPI: 0.-1: set pid 0x0303
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] EEPG: setstatus 0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] EEPG: setstatus 0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] EEPG: setstatus 1
Jun 19 12:02:11 OpenELEC vdr[21050]: [21050] EEPG: Filter Pid:0,Tid:0 added.
Jun 19 12:02:11 OpenELEC vdr[21050]: [21660] EEPG: setstatus 0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21660] EEPG: setstatus 0
Jun 19 12:02:11 OpenELEC vdr[21050]: [21660] EEPG: setstatus 1
Jun 19 12:02:11 OpenELEC vdr[21050]: [21660] EEPG: Filter Pid:0,Tid:0 added.
Jun 19 12:02:11 OpenELEC vdr[21050]: [21110] EEPG: PMT scan idle
Jun 19 12:02:12 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:13 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:14 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:14 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:15 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:16 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:17 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:17 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:18 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:19 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:21 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:22 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:23 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:24 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:25 OpenELEC vdr[21050]: [21050] EEPG: setstatus 0
Jun 19 12:02:25 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:26 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:27 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:27 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size reached, pid: 1023
Jun 19 12:02:29 OpenELEC vdr[21050]: [21660] VNSI-Error: cParser::AddPESPacket - max buffer size rea
i had already problems with the last vnsi server version and picture artifacts every 20-30 seconds. This seems to be solved by setting priority once to -99 and back to -1.
when i switch to another channel and then back it is very likely the channel is decrypted.
any clues?