Bug #3295
dvbapi descrambling via oscam broken?
0%
Description
Hi,
i've updated tvheadend yesterday, and since then, dvbapi decryption works extremely unstable, sometimes it works, but when it does, it usually freezes after a few seconds. The standard log shows not much usable exept
H264 @ #400 Continuity counter error (total 1)
(after starting capmt server & subscription).
oscam sometimes looks normal, sometimes it complains that there is no matching reader found, but everything is working via cwc to the same oscam...
I realise this report is pretty vague without more logging, can someone tell me what exact debugging option i have to turn on...?
Maybe related, https://tvheadend.org/boards/13/topics/18471
I've browsed through the commit-log, anything remotly related seems to be:
commit 66b12c40d384fa2b1b89c2d39f9bf7a73058fdf3
Author: Jaroslav Kysela <[email protected]>
Date: Thu Nov 12 09:32:55 2015 +0100
capmt: add OSCam new pc-nodmx mode (through unix socket)Is it possible that this broke something? I've tried to downgrade, but it segfaults, and (obviously, duh) i've accidentally deleted the old backup-configs...
History
Updated by Jaroslav Kysela almost 9 years ago
Updated by I doubt. I'm running against OSCAM r10937 without any issues. Trying latest oscam....
Updated by Jaroslav Kysela almost 9 years ago
r11137 seems fine here, too.. You may try to revert commits 39b1e510328830c0814a9bbac750469a27ae112b and 81dc8c87bd92a937264e7253689170fbbbeda7ff .
git revert /hash/
Updated by Hanspeter Müller almost 9 years ago
Updated by yeah you're right, it's not directly a problem in tvheadend, but in oscam, it works up and including Revision 11110, after that it fails.
svn log r 11111
-----------------------------------------------------------------------
r11111 | Aeon | 2015-11-12 05:25:01 +0100 (Thu, 12 Nov 2015) | 1 line
use real PMT if it can be filtered
------------------------------------------------------------------------
All changes after 11111 in the PMT stuff, with a lot of bugfixes, but none of em seems to work for me. I just dont get why it works for you. I'm using DVB-C with Mode 1... There seem to be others in the forum with the same problem, but noone without tvheadend that has a problem.
an oscam-log that works looks like:
Nov 15 10:07:11 idiotslantern oscam: 2B834573 c (dvbapi) Demuxer 0 found 1 ECMpids and 1 STREAMpids in PMT Nov 15 10:07:11 idiotslantern oscam: 2B834573 c (dvbapi) Demuxer 0 serving srvid 2373 (N24 HD) on adapter 0005 camask 0020 index 0000 pmtpid 0000 Nov 15 10:07:11 idiotslantern oscam: 2B834573 c (dvbapi) Demuxer 0 no suitable readers found that can be used for decoding! Nov 15 10:07:11 idiotslantern oscam: 2B834573 c (dvbapi) Demuxer 0 continue decoding of SRVID 2373 Nov 15 10:07:11 idiotslantern oscam: 2B834573 c (dvbapi) Demuxer 0 found 1 ECMpids and 1 STREAMpids in PMT Nov 15 10:07:11 idiotslantern oscam: 2B834573 c (dvbapi) Demuxer 0 serving srvid 2373 (N24 HD) on adapter 0005 camask 0020 index 0000 pmtpid 0000 Nov 15 10:07:11 idiotslantern oscam: 2B834573 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 1801 PROVID 007301 ECMPID 01D9 ANY CHID PMTPID 0000 VPID 0033
and one that does not work:
Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) ERROR: Can't open device /dev/dvb/adapter0/demux0 (errno=2 No such file or directory) Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1801 ECM_PID: 01D9 PROVID: 000000 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 stream Videostream (MPEG-1)(type: 01 pid: 0036 length: 6) Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 found 1 ECMpids and 1 STREAMpids in caPMT Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 serving srvid 2373 (N24 HD) on adapter 0005 camask 0020 index 0000 pmtpid 0000 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 no suitable readers found that can be used for decoding! Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 continue decoding of SRVID 2373 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 1801 ECM_PID: 01D9 PROVID: 000000 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 stream Videostream (MPEG-1)(type: 01 pid: 0036 length: 6) Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 found 1 ECMpids and 1 STREAMpids in caPMT Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 serving srvid 2373 (N24 HD) on adapter 0005 camask 0020 index 0000 pmtpid 0000 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 1801 PROVID 007301 ECMPID 01D9 ANY CHID PMTPID 0000 VPID 0036 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 1801 ECM_PID: 01D9 PROVID: 000000 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 ecmpid 2 CAID: 1850 ECM_PID: 01E3 PROVID: 000000 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 ecmpid 3 CAID: 1862 ECM_PID: 01ED PROVID: 000000 Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 stream Videostream (MPEG-4)(type: 1b pid: 01d0 length: 5) Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 stream Audiostream (MPEG-1)(type: 03 pid: 01da length: 13) Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 stream Data-/Audiostream (Subtitles/VBI and AC-3)(type: 06 pid: 01ee length: 10) Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 found 4 ECMpids and 3 STREAMpids in PMT Nov 15 10:12:13 idiotslantern oscam: 6BA6F2C2 c (dvbapi) Demuxer 0 serving srvid 2373 (N24 HD) on adapter 0005 camask 0020 index 0000 pmtpid 0000
the only obvious difference would be: ERROR: Can't open device /dev/dvb/adapter0/demux0 (errno=2 No such file or directory)
adapter0 is NOT a Tuner-Card, but a (disabled) CI.
Does this still point to a bug in oscam, or is it revealing an issue in tvheadend that just didnt cause a problem until now...?
Updated by
Updated by Jaroslav Kysela almost 9 years ago
Hanspeter Müller wrote:
the only obvious difference would be: ERROR: Can't open device /dev/dvb/adapter0/demux0 (errno=2 No such file or directory)
The OSCAM should not ever try to open the direct DVB devices for the network mode. It seems like a fatal bug in oscam somewhere. TVH just triggers it.
Updated by Jaroslav Kysela almost 9 years ago
You may try to enable dvbapi debugging is oscam - debug mask 128 .
Updated by Yuri K almost 9 years ago
r11140 worked ok
r11141 log:
2015/11/15 18:32:43 6C22504A h (webif) all debug_level=128
2015/11/15 18:32:45 34649141 c (dvbapi) PMT Update on socket 17.
2015/11/15 18:32:45 34649141 c (dvbapi) Parsing PMT object 1:
2015/11/15 18:32:45 34649141 c (dvbapi) 9F 80 3F 04 83 02 00 FF
2015/11/15 18:32:45 34649141 c (dvbapi) capmt 3f:
2015/11/15 18:32:45 34649141 c (dvbapi) 9F 80 3F 04 83 02 00 FF
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 stop descrambling program number 27DE (Ля-минор ТВ)
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 65535 api: 0, caid: 0001, provid: 000001, emmpid: 0000)
2015/11/15 18:32:45 34649141 c (dvbapi) Sending packet to dvbapi client (fd=17):
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 6F 2A 01 00 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 stop filter 2 try 1 (fd: 65535 api: 0, caid: 0500, provid: 040600, ecmpid: 0FA6)
2015/11/15 18:32:45 34649141 c (dvbapi) Sending packet to dvbapi client (fd=17):
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 6F 2A 01 00 01 0F A6
2015/11/15 18:32:45 34649141 c (dvbapi) Seems we received and parsed all PMT objects!
2015/11/15 18:32:45 34649141 c (dvbapi) PMT Update on socket 17.
2015/11/15 18:32:45 34649141 c (dvbapi) Parsing PMT object 1:
2015/11/15 18:32:45 34649141 c (dvbapi) 9F 80 32 82 00 40 03 08 9C 06 00 35 01 82 02 00
2015/11/15 18:32:45 34649141 c (dvbapi) 01 81 08 00 00 00 00 00 16 00 70 84 02 00 68 09
2015/11/15 18:32:45 34649141 c (dvbapi) 0F 05 00 F0 6C 00 00 00 00 00 00 14 00 04 06 20
2015/11/15 18:32:45 34649141 c (dvbapi) 09 0F 05 00 F0 08 00 00 00 00 00 00 14 00 04 06
2015/11/15 18:32:45 34649141 c (dvbapi) 10 01 00 77 00 06
2015/11/15 18:32:45 34649141 c (dvbapi) capmt:
2015/11/15 18:32:45 34649141 c (dvbapi) 03 08 9C 06 00 35 01 82 02 00 01 81 08 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 16 00 70 84 02 00 68 09 0F 05 00 F0 6C 00
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 00 00 00 14 00 04 06 20 09 0F 05 00 F0 08
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 00 00 00 00 14 00 04 06 10 01 00 77 00 06
2015/11/15 18:32:45 34649141 c (dvbapi) Receiver sends PMT command 3 for channel 089C
2015/11/15 18:32:45 34649141 c (dvbapi) Receiver wants to demux srvid 089C on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 try to start new filter for caid: 0001, provid: 000001, pid: 0000
2015/11/15 18:32:45 34649141 c (dvbapi) Sending packet to dvbapi client (fd=17):
2015/11/15 18:32:45 34649141 c (dvbapi) 40 3C 6F 2B 00 00 00 00 00 00 00 00 00 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 04
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 0001 provid 000001 pid 0000)
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 found pmt type: 81 length: 8 (assuming enigma private descriptor: namespace 0000 tsid 16 onid 70)
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 0500 ECM_PID: 106C PROVID: 040620
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 0500 ECM_PID: 1008 PROVID: 040610
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 stream Videostream (MPEG-1)(type: 01 pid: 0077 length: 6)
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 found 2 ECMpids and 1 STREAMpids in caPMT
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 serving srvid 089C (МАТЧ ТВ HD) on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0/16 lets start descrambling (srvid = 089C fd = 17 ecmpids = 2)
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 prio ecmpid 0 0500@040620:106C:0000 weight: 10 (file)
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 sorting the ecmpids took 0 ms
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 0500 PROVID 040620 ECMPID 106C ANY CHID PMTPID 0000 VPID 0077
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 try to start new filter for caid: 0500, provid: 040620, pid: 106C
2015/11/15 18:32:45 34649141 c (dvbapi) Sending packet to dvbapi client (fd=17):
2015/11/15 18:32:45 34649141 c (dvbapi) 40 3C 6F 2B 01 00 01 10 6C 80 00 00 00 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 0B B8 00 00 00
2015/11/15 18:32:45 34649141 c (dvbapi) 04
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 Filter 2 started successfully (caid 0500 provid 040620 pid 106C)
2015/11/15 18:32:45 34649141 c (dvbapi) Seems we received and parsed all PMT objects!
2015/11/15 18:32:45 34649141 c (dvbapi) Demuxer 0 has 2 ecmpids, 1 streampids, 1 ecmfilters and 0 of max 0 emmfilters
2015/11/15 18:32:55 34649141 c (dvbapi) Demuxer 0 try to start new filter for caid: 0001, provid: 000001, pid: 0011
2015/11/15 18:32:55 34649141 c (dvbapi) Sending packet to dvbapi client (fd=17):
2015/11/15 18:32:55 34649141 c (dvbapi) 40 3C 6F 2B 01 00 02 00 11 42 00 00 00 00 00 00
2015/11/15 18:32:55 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00
2015/11/15 18:32:55 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/11/15 18:32:55 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/11/15 18:32:55 34649141 c (dvbapi) 04
2015/11/15 18:32:55 34649141 c (dvbapi) Demuxer 0 Filter 3 started successfully (caid 0001 provid 000001 pid 0011)
2015/11/15 18:33:15 34649141 c (dvbapi) Demuxer 0 try to start new filter for caid: 0001, provid: 000001, pid: 0001
2015/11/15 18:33:15 34649141 c (dvbapi) Sending packet to dvbapi client (fd=17):
2015/11/15 18:33:15 34649141 c (dvbapi) 40 3C 6F 2B 01 00 03 00 01 01 00 00 00 00 00 00
2015/11/15 18:33:15 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00
2015/11/15 18:33:15 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/11/15 18:33:15 34649141 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2015/11/15 18:33:15 34649141 c (dvbapi) 04
2015/11/15 18:33:15 34649141 c (dvbapi) Demuxer 0 Filter 4 started successfully (caid 0001 provid 000001 pid 0001)
2015/11/15 18:33:16 34649141 c (dvbapi) Demuxer 0 has 2 ecmpids, 1 streampids, 1 ecmfilters and 1 of max 0 emmfilters
Updated by Jaroslav Kysela almost 9 years ago
Could you try oscam r11143 ? Also provide both oscam 128 and tvheadend --trace descrambler,capmt logs (same channel subscriptions - in one time)... https://tvheadend.org/projects/tvheadend/wiki/Traces
Updated by Yuri K almost 9 years ago
Oscam r13143 not descrybed.
2015-11-15 22:36:49.493 [ INFO]:subscription: 0001: "192.168.1.138 [ hall | Kodi Media Center ]" unsubscribing from "THT-Comedy TV", hostname="192.168.1.138", username="hall", client="Kodi Media Center" 2015-11-15 22:36:49.536 [ TRACE]:descrambler: mux 0x16c7088 - flush tables 2015-11-15 22:36:49.536 [ INFO]:capmt: catpmt_banana: Removing CAPMT Server from service "THT-Comedy TV" on adapter 1 2015-11-15 22:36:49.536 [ DEBUG]:capmt: catpmt_banana: capmt_enumerate_services: no subscribed services, closing socket, fd=25 2015-11-15 22:36:49.536 [ TRACE]:capmt: catpmt_banana: Sending message to socket 25 (sid 0) 2015-11-15 22:36:49.537 [ TRACE]:capmt: 9F 80 3F 04 83 02 00 FF ..?..... 2015-11-15 22:36:49.538 [ INFO]:mpegts: 11996L in dvbs - tuning on Technisat SkyStar USB HD (DVB-S/S2) : DVB-S #0 2015-11-15 22:36:49.539 [ INFO]:capmt: catpmt_banana: Starting CAPMT server for service "TB 1000 Русское кино" on adapter 1 seq 0x0002 2015-11-15 22:36:49.539 [ DEBUG]:capmt: catpmt_banana: New caid 0x0500 for service "TB 1000 Русское кино" 2015-11-15 22:36:49.539 [ DEBUG]:capmt: catpmt_banana: New caid 0x0500 for service "TB 1000 Русское кино" 2015-11-15 22:36:49.539 [ DEBUG]:capmt: catpmt_banana: adding ECMPID=0x106C (4204), CAID=0x500 (1280) PROVID=0x40620 (263712), SID=19104, ADAPTER=1 2015-11-15 22:36:49.539 [ DEBUG]:capmt: catpmt_banana: adding ECMPID=0x1008 (4104), CAID=0x500 (1280) PROVID=0x40610 (263696), SID=19104, ADAPTER=1 2015-11-15 22:36:49.539 [ DEBUG]:capmt: catpmt_banana: Trying to obtain key for service "TB 1000 Русское кино" 2015-11-15 22:36:49.539 [ INFO]:subscription: 0003: "192.168.1.138 [ hall | Kodi Media Center ]" subscribing on channel "TB 1000 Русское кино", weight: 150, adapter: "Technisat SkyStar USB HD (DVB-S/S2) : DVB-S #0", network: "dvbs", mux: "11996L", provider: "HTB+", service: "TB 1000 Русское кино", profile="htsp", hostname="192.168.1.138", username="hall", client="Kodi Media Center" 2015-11-15 22:36:49.539 [ TRACE]:capmt: catpmt_banana: Sending message to socket 25 (sid 19104) 2015-11-15 22:36:49.539 [ TRACE]:capmt: 9F 80 32 82 00 40 03 4A A0 02 00 35 01 82 02 00 [email protected].... 2015-11-15 22:36:49.539 [ TRACE]:capmt: 01 81 08 00 00 00 00 00 13 00 70 84 02 07 70 09 ..........p...p. 2015-11-15 22:36:49.539 [ TRACE]:capmt: 0F 05 00 F0 6C 00 00 00 00 00 00 14 00 04 06 20 ....l.......... 2015-11-15 22:36:49.539 [ TRACE]:capmt: 09 0F 05 00 F0 08 00 00 00 00 00 00 14 00 04 06 ................ 2015-11-15 22:36:49.539 [ TRACE]:capmt: 10 01 00 02 00 06 ...... 2015-11-15 22:36:50.002 [ TRACE]:descrambler: CAT data (len 70) 2015-11-15 22:36:50.002 [ TRACE]:descrambler: 09 0C 05 00 F4 50 13 01 20 14 03 05 0A 00 09 0C .....P.. ....... 2015-11-15 22:36:50.002 [ TRACE]:descrambler: 05 00 F0 04 13 01 20 14 03 04 06 10 09 0C 05 00 ...... ......... 2015-11-15 22:36:50.002 [ TRACE]:descrambler: F0 68 13 01 20 14 03 04 06 20 09 0C 05 00 F7 70 .h.. .... .....p 2015-11-15 22:36:50.002 [ TRACE]:descrambler: 13 01 20 14 03 06 0A 00 09 0C 05 00 F8 38 13 01 .. ..........8.. 2015-11-15 22:36:50.002 [ TRACE]:descrambler: 20 14 03 06 0C 00 ..... 2015-11-15 22:36:50.009 [ TRACE]:capmt: catpmt_banana: Received message from socket 25 2015-11-15 22:36:50.009 [ TRACE]:capmt: 00 00 6F 2A 01 00 02 00 11 ..o*..... 2015-11-15 22:36:50.009 [ TRACE]:capmt: catpmt_banana: stopping filter: adapter=1, demux=0, filter=2, pid=17 2015-11-15 22:36:50.009 [ TRACE]:capmt: catpmt_banana: Received message from socket 25 2015-11-15 22:36:50.009 [ TRACE]:capmt: 00 00 6F 2A 01 00 00 00 00 ..o*..... 2015-11-15 22:36:50.009 [ TRACE]:capmt: catpmt_banana: stopping filter: adapter=1, demux=0, filter=0, pid=0 2015-11-15 22:36:50.010 [ TRACE]:capmt: catpmt_banana: Received message from socket 25 2015-11-15 22:36:50.010 [ TRACE]:capmt: 00 00 6F 2A 01 00 01 0F A1 40 3C 6F 2B 00 00 00 ..o*.....@<o+... 2015-11-15 22:36:50.010 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:36:50.010 [ TRACE]:capmt: 00 00 FF 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:36:50.010 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:36:50.010 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 04 .......... 2015-11-15 22:36:50.010 [ TRACE]:capmt: catpmt_banana: stopping filter: adapter=1, demux=0, filter=1, pid=4001 2015-11-15 22:36:50.010 [ TRACE]:capmt: catpmt_banana: setting filter: adapter=0, demux=0, filter=0, pid=0 2015-11-15 22:36:50.010 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:36:50.010 [ TRACE]:capmt: FF 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:36:50.011 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:36:50.011 [ TRACE]:capmt: catpmt_banana: Received message from socket 25 2015-11-15 22:36:50.011 [ TRACE]:capmt: 40 3C 6F 2B 01 00 01 10 6C 80 00 00 00 00 00 00 @<o+....l....... 2015-11-15 22:36:50.011 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00 ................ 2015-11-15 22:36:50.011 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:36:50.011 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 0B B8 00 00 00 ................ 2015-11-15 22:36:50.011 [ TRACE]:capmt: 04 . 2015-11-15 22:36:50.011 [ TRACE]:capmt: catpmt_banana: setting filter: adapter=1, demux=0, filter=1, pid=4204 2015-11-15 22:37:00.400 [ TRACE]:capmt: catpmt_banana: Received message from socket 25 2015-11-15 22:37:00.400 [ TRACE]:capmt: 40 3C 6F 2B 01 00 02 00 11 42 00 00 00 00 00 00 @<o+.....B...... 2015-11-15 22:37:00.400 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 ................ 2015-11-15 22:37:00.400 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:37:00.400 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:37:00.400 [ TRACE]:capmt: 04 . 2015-11-15 22:37:00.400 [ TRACE]:capmt: catpmt_banana: setting filter: adapter=1, demux=0, filter=2, pid=17 2015-11-15 22:37:20.265 [ TRACE]:capmt: catpmt_banana: Received message from socket 25 2015-11-15 22:37:20.265 [ TRACE]:capmt: 40 3C 6F 2B 01 00 03 00 01 01 00 00 00 00 00 00 @<o+............ 2015-11-15 22:37:20.265 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 ................ 2015-11-15 22:37:20.265 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:37:20.265 [ TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................ 2015-11-15 22:37:20.265 [ TRACE]:capmt: 04 . 2015-11-15 22:37:20.265 [ TRACE]:capmt: catpmt_banana: setting filter: adapter=1, demux=0, filter=3, pid=1 2015-11-15 22:39:05.041 [ INFO]:htsp: 192.168.1.138 [ hall | Kodi Media Center ]: Disconnected 2015-11-15 22:39:05.041 [ INFO]:subscription: 0003: "192.168.1.138 [ hall | Kodi Media Center ]" unsubscribing from "TB 1000 Русское кино", hostname="192.168.1.138", username="hall", client="Kodi Media Center" 2015-11-15 22:39:05.081 [ TRACE]:descrambler: mux 0x1744a18 - flush tables 2015-11-15 22:39:05.084 [ INFO]:capmt: catpmt_banana: Removing CAPMT Server from service "TB 1000 Русское кино" on adapter 1 2015-11-15 22:39:05.084 [ DEBUG]:capmt: catpmt_banana: capmt_enumerate_services: no subscribed services, closing socket, fd=25 2015-11-15 22:39:05.084 [ TRACE]:capmt: catpmt_banana: Sending message to socket 25 (sid 0) 2015-11-15 22:39:05.084 [ TRACE]:capmt: 9F 80 3F 04 83 02 00 FF ..?..... 2015-11-15 22:39:05.085 [ INFO]:htsp: 192.168.1.185 [ | Kodi Media Center ]: Disconnected@
2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 stop descrambling program number 2B5D (THT-Comedy TV) 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 stop filter 3 try 1 (fd: 65535 api: 0, caid: 0001, provid: 000001, emmpid: 0011) 2015/11/15 22:36:50 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 6F 2A 01 00 02 00 11 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 65535 api: 0, caid: 0001, provid: 000001, emmpid: 0000) 2015/11/15 22:36:50 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 6F 2A 01 00 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 stop filter 2 try 1 (fd: 65535 api: 0, caid: 0500, provid: 040600, ecmpid: 0FA1) 2015/11/15 22:36:50 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 6F 2A 01 00 01 0F A1 2015/11/15 22:36:50 254102B4 c (dvbapi) Parsing PMT object 2: 2015/11/15 22:36:50 254102B4 c (dvbapi) 9F 80 32 82 00 40 03 4A A0 02 00 35 01 82 02 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 01 81 08 00 00 00 00 00 13 00 70 84 02 07 70 09 2015/11/15 22:36:50 254102B4 c (dvbapi) 0F 05 00 F0 6C 00 00 00 00 00 00 14 00 04 06 20 2015/11/15 22:36:50 254102B4 c (dvbapi) 09 0F 05 00 F0 08 00 00 00 00 00 00 14 00 04 06 2015/11/15 22:36:50 254102B4 c (dvbapi) 10 01 00 02 00 06 2015/11/15 22:36:50 254102B4 c (dvbapi) capmt: 2015/11/15 22:36:50 254102B4 c (dvbapi) 03 4A A0 02 00 35 01 82 02 00 01 81 08 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 13 00 70 84 02 07 70 09 0F 05 00 F0 6C 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 00 00 00 14 00 04 06 20 09 0F 05 00 F0 08 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 00 00 00 00 14 00 04 06 10 01 00 02 00 06 2015/11/15 22:36:50 254102B4 c (dvbapi) Receiver sends PMT command 3 for channel 4AA0 2015/11/15 22:36:50 254102B4 c (dvbapi) Receiver wants to demux srvid 4AA0 on adapter 0001 camask 0002 index 0000 pmtpid 0000 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 try to start new filter for caid: 0001, provid: 000001, pid: 0000 2015/11/15 22:36:50 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:36:50 254102B4 c (dvbapi) 40 3C 6F 2B 00 00 00 00 00 00 00 00 00 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 04 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 Filter 1 started successfully (caid 0001 provid 000001 pid 0000) 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 found pmt type: 81 length: 8 (assuming enigma private descriptor: namespace 0000 tsid 13 onid 70) 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 0500 ECM_PID: 106C PROVID: 040620 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 ecmpid 1 CAID: 0500 ECM_PID: 1008 PROVID: 040610 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 stream Videostream (MPEG-1)(type: 01 pid: 0002 length: 6) 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 found 2 ECMpids and 1 STREAMpids in caPMT 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 serving srvid 4AA0 (TB 1000 Русское кино) on adapter 0001 camask 0002 index 0000 pmtpid 0000 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0/16 lets start descrambling (srvid = 4AA0 fd = 22 ecmpids = 2) 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 prio ecmpid 0 0500@040620:106C:0000 weight: 10 (file) 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 sorting the ecmpids took 1 ms 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 0500 PROVID 040620 ECMPID 106C ANY CHID PMTPID 0000 VPID 0002 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 try to start new filter for caid: 0500, provid: 040620, pid: 106C 2015/11/15 22:36:50 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:36:50 254102B4 c (dvbapi) 40 3C 6F 2B 01 00 01 10 6C 80 00 00 00 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 0B B8 00 00 00 2015/11/15 22:36:50 254102B4 c (dvbapi) 04 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 Filter 2 started successfully (caid 0500 provid 040620 pid 106C) 2015/11/15 22:36:50 254102B4 c (dvbapi) Seems we received and parsed all PMT objects! 2015/11/15 22:36:50 254102B4 c (dvbapi) Demuxer 0 has 2 ecmpids, 1 streampids, 1 ecmfilters and 0 of max 0 emmfilters 2015/11/15 22:37:00 254102B4 c (dvbapi) Demuxer 0 try to start new filter for caid: 0001, provid: 000001, pid: 0011 2015/11/15 22:37:00 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:37:00 254102B4 c (dvbapi) 40 3C 6F 2B 01 00 02 00 11 42 00 00 00 00 00 00 2015/11/15 22:37:00 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 2015/11/15 22:37:00 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2015/11/15 22:37:00 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2015/11/15 22:37:00 254102B4 c (dvbapi) 04 2015/11/15 22:37:00 254102B4 c (dvbapi) Demuxer 0 Filter 3 started successfully (caid 0001 provid 000001 pid 0011) 2015/11/15 22:37:20 254102B4 c (dvbapi) Demuxer 0 try to start new filter for caid: 0001, provid: 000001, pid: 0001 2015/11/15 22:37:20 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:37:20 254102B4 c (dvbapi) 40 3C 6F 2B 01 00 03 00 01 01 00 00 00 00 00 00 2015/11/15 22:37:20 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 2015/11/15 22:37:20 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2015/11/15 22:37:20 254102B4 c (dvbapi) 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 2015/11/15 22:37:20 254102B4 c (dvbapi) 04 2015/11/15 22:37:20 254102B4 c (dvbapi) Demuxer 0 Filter 4 started successfully (caid 0001 provid 000001 pid 0001) 2015/11/15 22:37:20 254102B4 c (dvbapi) Demuxer 0 has 2 ecmpids, 1 streampids, 1 ecmfilters and 1 of max 0 emmfilters 2015/11/15 22:39:05 254102B4 c (dvbapi) PMT Update on socket 22. 2015/11/15 22:39:05 254102B4 c (dvbapi) Parsing PMT object 1: 2015/11/15 22:39:05 254102B4 c (dvbapi) 9F 80 3F 04 83 02 00 FF 2015/11/15 22:39:05 254102B4 c (dvbapi) capmt 3f: 2015/11/15 22:39:05 254102B4 c (dvbapi) 9F 80 3F 04 83 02 00 FF 2015/11/15 22:39:05 254102B4 c (dvbapi) Demuxer 0 stop descrambling program number 4AA0 (TB 1000 Русское кино) 2015/11/15 22:39:05 254102B4 c (dvbapi) Demuxer 0 stop filter 4 try 1 (fd: 65535 api: 0, caid: 0001, provid: 000001, emmpid: 0001) 2015/11/15 22:39:05 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:39:05 254102B4 c (dvbapi) 00 00 6F 2A 01 00 03 00 01 2015/11/15 22:39:05 254102B4 c (dvbapi) Demuxer 0 stop filter 3 try 1 (fd: 65535 api: 0, caid: 0001, provid: 000001, emmpid: 0011) 2015/11/15 22:39:05 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:39:05 254102B4 c (dvbapi) 00 00 6F 2A 01 00 02 00 11 2015/11/15 22:39:05 254102B4 c (main) Got sigpipe signal -> captured 2015/11/15 22:39:05 254102B4 c (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 65535 api: 0, caid: 0001, provid: 000001, emmpid: 0000) 2015/11/15 22:39:05 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:39:05 254102B4 c (dvbapi) 00 00 6F 2A 01 00 00 00 00 2015/11/15 22:39:05 254102B4 c (main) Got sigpipe signal -> captured 2015/11/15 22:39:05 254102B4 c (dvbapi) Demuxer 0 stop filter 2 try 1 (fd: 65535 api: 0, caid: 0500, provid: 040620, ecmpid: 106C) 2015/11/15 22:39:05 254102B4 c (dvbapi) Sending packet to dvbapi client (fd=22): 2015/11/15 22:39:05 254102B4 c (dvbapi) 00 00 6F 2A 01 00 01 10 6C 2015/11/15 22:39:05 254102B4 c (main) Got sigpipe signal -> captured 2015/11/15 22:39:05 254102B4 c (dvbapi) Socket 22 reported connection close 2015/11/15 22:39:05 254102B4 c (dvbapi) Socket 17 reported connection close
Updated by Yuri K almost 9 years ago
Then i revert at oscam r11141 only (delete one line), all work ok again:
trunk/module-dvbapi.c
r11140 r11141
3192 3192 demux[demux_id].onid = 0;
3193 3193 demux[demux_id].pmtpid = pmtpid;
3194 demux[demux_id].socket_fd = connfd;
3194 3195
3195 3196 if(pmtfile)
Updated by Jaroslav Kysela almost 9 years ago
Ok, it seems that the latest oscam's dvbapi module is under heavy rewrite. Report any issues to their bugtracker: http://www.streamboard.tv/oscam/report
Updated by Jaroslav Kysela almost 9 years ago
Latest oscam svn11152 - if you don't change the service, the PID filters are not installed again. I created this ticket: http://www.streamboard.tv/oscam/ticket/4353
Updated by Hanspeter Müller almost 9 years ago
Hi Jaroslav,
i can confirm what Yuri wrote, 11140 works, 11141 doesnt, no idea why on my testing earlier it stopped after 11110, maybe some caching in the oscam.ccache file...
So i reverted this commit:
svn diff -r 11140:11141
Index: module-dvbapi.c
===================================================================
--- module-dvbapi.c (revision 11140)
+++ module-dvbapi.c (revision 11141)
@@ -3191,6 +3191,7 @@
demux[demux_id].tsid = 0;
demux[demux_id].onid = 0;
demux[demux_id].pmtpid = pmtpid;
+ demux[demux_id].socket_fd = connfd;
if(pmtfile)
{
The log from commit 11141 is:
- Add missing connfd that is needed to start filters on dvbnetapi
Seems to work fine now with the current r11163. I was using Mode 1 (camd.socket), after your comment i changed it to Mode 5 (net protocol)
Any ideas...? Is this a bug in oscam, one in tvheadend or something special with some encryption systems? I've noticed (long ago) that on nagra, the PROVID is always 00000, and with earlier versions it was necessary to map them manually in oscam.dvbapi:
- HDplus HD01 ##################
M: 1830:000000 1830:003411
that example was there, so i've added
- UPC Cablecom ##################
M: 1801:000000 1801:007301
for my Card...
Thanks,
/hp
Updated by The Troll about 7 years ago
Updated by Hi,
coming back on comment #11, the issue seems to be still present.
It's not happening when using vdr dvbapi plugin.
Nagra's provid detection seems to be buggy..
Thanks
Updated by Jaroslav Kysela about 7 years ago
- Status changed from New to Fixed
I'm closing this bug. It's originally for 4.1, please, do a new report with --trace descrambler,capmt to analyze the issue.