Project

General

Profile

Bug #3338

descrambler: ***** even stream key is not valid

Added by Markus S about 9 years ago. Updated almost 9 years ago.

Status:
Fixed
Priority:
Normal
Assignee:
-
Category:
Descrambling
Target version:
-
Start date:
2015-11-22
Due date:
% Done:

0%

Estimated time:
Found in version:
4.1-1040~g5cb5a0c
Affected Versions:

Description

I Update my setup from version 3.1.
now the descrambler brings some error to not working.

Hardware:
DVBSKy S952 Dual DVB-S/S2 PCIe

System:
Debian kernal: 3.19.3-64

Log:

2015-11-22 20:08:10.258 descrambler: ***** even stream key is not valid
2015-11-22 20:08:20.272 TS: Astra 19_2E/10832.25H/*****: H264 @ #1535 Continuity counter error (total 7)
2015-11-22 20:08:20.303 TS: Astra 19_2E/10832.25H/*****: H264 @ #1535: Invalid start code 59:c6:46
2015-11-22 20:08:20.393 TS: Astra 19_2E/10832.25H/*****: AC3 @ #1539: Invalid start code 38:20:72
2015-11-22 20:08:30.270 descrambler: ***** even stream key is not valid
2015-11-22 20:08:40.271 TS: Astra 19_2E/10832.25H/*****: H264 @ #1535 Continuity counter error (total 8)
2015-11-22 20:08:40.271 TS: Astra 19_2E/10832.25H/*****: AC3 @ #1539 Continuity counter error (total 1)
2015-11-22 20:08:40.357 TS: Astra 19_2E/10832.25H/*****: AC3 @ #1539: Invalid start code 78:e1:8d
2015-11-22 20:08:40.370 TS: Astra 19_2E/10832.25H/*****: H264 @ #1535: Invalid start code 1f:d4:ad
2015-11-22 20:08:50.270 descrambler: ***** even stream key is not valid
2015-11-22 20:09:00.271 TS: Astra 19_2E/10832.25H/*****: H264 @ #1535 Continuity counter error (total 9)
2015-11-22 20:09:00.320 TS: Astra 19_2E/10832.25H/*****: H264 @ #1535: Invalid start code 08:8e:30
2015-11-22 20:09:00.322 TS: Astra 19_2E/10832.25H/*****: AC3 @ #1539: Invalid start code 18:48:bb
2015-11-22 20:09:10.270 descrambler: ***** even stream key is not valid
2015-11-22 20:09:20.270 TS: Astra 19_2E/10832.25H/*****: H264 @ #1535 Continuity counter error (total 10)
2015-11-22 20:09:20.284 TS: Astra 19_2E/10832.25H/*****: H264 @ #1535: Invalid start code dc:06:21
2015-11-22 20:09:20.294 TS: Astra 19_2E/10832.25H/*****: AC3 @ #1539: Invalid start code a4:c3:bb
2015-11-22 20:09:30.283 descrambler: ***** even stream key is not valid

History

#1

Updated by Jaroslav Kysela about 9 years ago

You probably misconfigured something. The mux is working here (tested with RTL HD).

#2

Updated by Markus S about 9 years ago

Jaroslav Kysela wrote:

You probably misconfigured something. The mux is working here (tested with RTL HD).

I had running version 3.1 before just fine.
has something on the oscam interface significantly changed?

#3

Updated by Jaroslav Kysela almost 9 years ago

I know nothing about your configuration, so it's difficult to guess what's wrong. I tested it with oscam r11137, DVBAPI network protocol v2. The older DVBAPI / CAPMT interfaces might not work correctly.

#4

Updated by Markus S almost 9 years ago

i tried differed DVB-Api / oscam configurations but I was not able to get it working, again.

now I use the Newcamd interface this is working so far.

#5

Updated by Jaroslav Kysela almost 9 years ago

Provide traces for descrambler,capmt and debug log from oscam 128 mask. https://tvheadend.org/projects/tvheadend/wiki/Traces

#6

Updated by Markus S almost 9 years ago

current oscam config:

[dvbapi]
enabled                       = 1
au                            = 1
pmt_mode                      = 4
listen_port                   = 54689
user                          = dvbapi
boxtype                       = pc

current tvheadend config:

{
    "mode": 5,
    "camdfilename": "127.0.0.1",
    "port": 54689,
    "class": "caclient_capmt",
    "index": 3,
    "enabled": true,
    "name": "oscam",
    "comment": "" 
}

oscam log:

2015/11/24 13:32:12 66EC0711 h    (webif) all debug_level=128
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Got packet with opcode 9F803282 and size 72
2015/11/24 13:32:19 1ED51210 c   (dvbapi) PMT Update on socket 27.
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Parsing PMT object:
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   9F 80 32 82 00 42 03 EF 10 06 00 37 01 82 02 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   01 81 08 00 00 00 00 04 21 00 01 84 02 00 60 09 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 18 6A FF CC 09 04 09 C4 FB CC 09 04 09 8C FA 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   CC 09 04 18 60 FE CC 09 04 18 43 F9 CC 09 04 18 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   30 FC CC 01 00 16 00 06 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) capmt:
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   03 EF 10 06 00 37 01 82 02 00 01 81 08 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 04 21 00 01 84 02 00 60 09 04 18 6A FF CC 09 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 09 C4 FB CC 09 04 09 8C FA CC 09 04 18 60 FE 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   CC 09 04 18 43 F9 CC 09 04 18 30 FC CC 01 00 16 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 06 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Receiver sends PMT command 3 for channel EF10
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Receiver wants to demux srvid EF10 on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 try to start new filter for caid: 0001, provid: 000001, pid: 0000
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=27):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 00 02 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 Filter 1 started successfully (caid 0001 provid 000001 pid 0000)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 found pmt type: 81 length: 8 (assuming enigma private descriptor: namespace 0000 tsid 421 onid 01)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ecmpid 0 CAID: 186A ECM_PID: 1FCC PROVID: 000000 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ecmpid 1 CAID: 09C4 ECM_PID: 1BCC PROVID: 000000 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ecmpid 2 CAID: 098C ECM_PID: 1ACC PROVID: 000000 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ecmpid 3 CAID: 1860 ECM_PID: 1ECC PROVID: 000000 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ecmpid 4 CAID: 1843 ECM_PID: 19CC PROVID: 000000 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ecmpid 5 CAID: 1830 ECM_PID: 1CCC PROVID: 000000 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 stream Videostream (MPEG-1)(type: 01 pid: 0016 length: 6)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 found 6 ECMpids and 1 STREAMpids in caPMT
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 serving srvid EF10 (186A@000000:EF10 unknown) on adapter 0001 camask 0002 index 0000 pmtpid 0000
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 0 stop filter 3 try 1 (fd: 65535 api: 0, caid: 0001, provid: 000001, emmpid: 0001)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 6F 2A 01 00 02 00 01 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 stop filter 3 try 1 (fd: 65535 api: 0, caid: 098C, provid: 000000, emmpid: 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 6F 2A 00 01 02 10 08 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 stop filter 4 try 1 (fd: 65535 api: 0, caid: 098C, provid: 000000, emmpid: 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 6F 2A 00 01 03 10 08 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 stop filter 5 try 1 (fd: 65535 api: 0, caid: 098C, provid: 000000, emmpid: 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 6F 2A 00 01 04 10 08 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 stop filter 6 try 1 (fd: 65535 api: 0, caid: 098C, provid: 000000, emmpid: 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 6F 2A 00 01 05 10 08 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 stop filter 7 try 1 (fd: 65535 api: 0, caid: 098C, provid: 000000, emmpid: 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 6F 2A 00 01 06 10 08 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 stop filter 8 try 1 (fd: 65535 api: 0, caid: 098C, provid: 000000, emmpid: 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 6F 2A 00 01 07 10 08 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 stop filter 9 try 1 (fd: 65535 api: 0, caid: 098C, provid: 000000, emmpid: 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 6F 2A 00 01 08 10 08 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2/16 lets start descrambling (srvid = EF10 fd = 27 ecmpids = 6)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ignore ecmpid 0 186A@000000:1FCC:10000 (no matching reader)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ignore ecmpid 1 09C4@000000:1BCC:10000 (no matching reader)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ignore ecmpid 3 1860@000000:1ECC:10000 (no matching reader)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ignore ecmpid 4 1843@000000:19CC:10000 (no matching reader)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 ignore ecmpid 5 1830@000000:1CCC:10000 (no matching reader)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 prio ecmpid 2 098C@000000:1ACC:10000 weight: 14 (1 local and 1 cacheex readers)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 found in channel cache: 098C@000000/0641/EF10/00:(null)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 prio ecmpid 2 098C@000000:1ACC weight: 15 (found caid/provid/srvid in cache)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 found channel in cache and matching prio -> start descrambling ecmpid 2 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 sorting the ecmpids took 1 ms
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 trying to descramble PID 2 CAID 098C PROVID 000000 ECMPID 1ACC ANY CHID PMTPID 0000 VPID 0016
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 try to start new filter for caid: 098C, provid: 000000, pid: 1ACC
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=27):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 01 02 01 1A CC 80 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 0B B8 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 Filter 2 started successfully (caid 098C provid 000000 pid 1ACC)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 0 has 6 ecmpids, 1 streampids, 1 ecmfilters and 0 of max 31 emmfilters
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 0 try to start new filter for caid: 0001, provid: 000001, pid: 0001
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 01 00 02 00 01 01 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 0 Filter 3 started successfully (caid 0001 provid 000001 pid 0001)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 has 3 ecmpids, 1 streampids, 1 ecmfilters and 0 of max 31 emmfilters
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 matching reader SkyV14 against available emmpids -> START!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 reader SkyV14 emmfilter 1/7 type match -> ENABLE!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 00 01 02 10 08 82 40 XX XX XX XX 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 FF C0 FF FF FF FF 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 3 started successfully (caid 098C provid 000000 pid 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 3 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 started emm filter type UNIQUE, pid: 0x1008
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   82 40 XX XX XX XX 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF C0 FF FF FF FF 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 reader SkyV14 emmfilter 2/7 type match -> ENABLE!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 00 01 03 10 08 82 50 00 00 00 00 XX 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   XX XX XX 00 00 00 00 00 00 FF C0 00 00 00 00 FF 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF FF FF 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 4 started successfully (caid 098C provid 000000 pid 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 4 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 started emm filter type UNIQUE, pid: 0x1008
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   82 50 00 00 00 00 XX XX XX XX 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF C0 00 00 00 00 FF FF FF FF 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 reader SkyV14 emmfilter 3/7 type match -> ENABLE!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 00 01 04 10 08 82 60 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 XX XX XX XX 00 00 FF E0 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 FF FF FF FF 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 5 started successfully (caid 098C provid 000000 pid 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 5 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 started emm filter type UNIQUE, pid: 0x1008
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   82 60 00 00 00 00 00 00 00 00 XX XX XX XX 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF E0 00 00 00 00 00 00 00 00 FF FF FF FF 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 reader SkyV14 emmfilter 4/7 type match -> ENABLE!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 00 01 05 10 08 82 80 00 86 65 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 FF C0 FF FF FF 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 6 started successfully (caid 098C provid 000000 pid 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 6 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 started emm filter type SHARED, pid: 0x1008
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   82 80 00 86 65 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF C0 FF FF FF 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 reader SkyV14 emmfilter 5/7 type match -> ENABLE!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 00 01 06 10 08 82 90 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   86 65 00 00 00 00 00 00 00 FF C0 00 00 00 00 FF 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF FF 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 7 started successfully (caid 098C provid 000000 pid 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 7 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 started emm filter type SHARED, pid: 0x1008
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   82 90 00 00 00 00 00 86 65 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF C0 00 00 00 00 FF FF FF 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 reader SkyV14 emmfilter 6/7 type match -> ENABLE!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 00 01 07 10 08 82 A0 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 86 65 00 00 00 FF E0 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 FF FF FF 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 8 started successfully (caid 098C provid 000000 pid 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 8 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 started emm filter type SHARED, pid: 0x1008
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   82 A0 00 00 00 00 00 00 00 00 00 86 65 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF E0 00 00 00 00 00 00 00 00 FF FF FF 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 reader SkyV14 emmfilter 7/7 type match -> ENABLE!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Sending packet to dvbapi client (fd=18):
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   40 3C 6F 2B 00 01 08 10 08 82 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 FF C0 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   04 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 9 started successfully (caid 098C provid 000000 pid 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 Filter 9 added to active emmfilters (CAID 098C PROVID 000000 EMMPID 1008)
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 started emm filter type GLOBAL, pid: 0x1008
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   82 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi)   FF C0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 matching reader SkyV14 against available emmpids -> DONE!
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 handles 7 emm filters
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 2 has 6 ecmpids, 1 streampids, 1 ecmfilters and 0 of max 31 emmfilters
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 0 has 6 ecmpids, 1 streampids, 1 ecmfilters and 1 of max 31 emmfilters
2015/11/24 13:32:19 1ED51210 c   (dvbapi) Demuxer 1 has 3 ecmpids, 1 streampids, 1 ecmfilters and 7 of max 31 emmfilters

tvheadend log:

2015-11-24 13:32:19.321 [   INFO]:mpegts: 10832.25H in Astra 19_2E - tuning on Montage M88DS3103 : DVB-S #1
2015-11-24 13:32:19.325 [   INFO]:capmt: oscam: Starting CAPMT server for service "RTL HD" on adapter 1 seq 0x0016
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: New caid 0x1830 for service "RTL HD" 
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: New caid 0x1843 for service "RTL HD" 
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: New caid 0x1860 for service "RTL HD" 
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: New caid 0x098C for service "RTL HD" 
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: New caid 0x09C4 for service "RTL HD" 
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: New caid 0x186A for service "RTL HD" 
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: adding ECMPID=0x1FCC (8140), CAID=0x186A (6250) PROVID=0x0 (0), SID=61200, ADAPTER=1
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: adding ECMPID=0x1BCC (7116), CAID=0x9C4 (2500) PROVID=0x0 (0), SID=61200, ADAPTER=1
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: adding ECMPID=0x1ACC (6860), CAID=0x98C (2444) PROVID=0x0 (0), SID=61200, ADAPTER=1
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: adding ECMPID=0x1ECC (7884), CAID=0x1860 (6240) PROVID=0x0 (0), SID=61200, ADAPTER=1
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: adding ECMPID=0x19CC (6604), CAID=0x1843 (6211) PROVID=0x0 (0), SID=61200, ADAPTER=1
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: adding ECMPID=0x1CCC (7372), CAID=0x1830 (6192) PROVID=0x0 (0), SID=61200, ADAPTER=1
2015-11-24 13:32:19.325 [  DEBUG]:capmt: oscam: Trying to obtain key for service "RTL HD" 
2015-11-24 13:32:19.325 [   INFO]:subscription: 01B7: "192.168.2.35 [ xbmc | XBMC Media Center ]" subscribing on channel "RTL", weight: 150, adapter: "Montage M88DS3103 : DVB-S #1", network: "Astra 19_2E", mux: "10832.25H", provider: "CBC", service: "RTL HD", profile="htsp", hostname="192.168.2.35", username="xbmc", client="XBMC Media Center" 
2015-11-24 13:32:19.325 [  TRACE]:capmt: oscam: Sending message to socket 34 (sid 61200)
2015-11-24 13:32:19.325 [  TRACE]:capmt: 9F 80 32 82 00 42 03 EF 10 06 00 37 01 82 02 00 ..2..B.....7....
2015-11-24 13:32:19.325 [  TRACE]:capmt: 01 81 08 00 00 00 00 04 21 00 01 84 02 00 60 09 ........!.....`.
2015-11-24 13:32:19.325 [  TRACE]:capmt: 04 18 6A FF CC 09 04 09 C4 FB CC 09 04 09 8C FA ..j.............
2015-11-24 13:32:19.325 [  TRACE]:capmt: CC 09 04 18 60 FE CC 09 04 18 43 F9 CC 09 04 18 ....`.....C.....
2015-11-24 13:32:19.325 [  TRACE]:capmt: 30 FC CC 01 00 16 00 06                         0.......        
2015-11-24 13:32:19.327 [  TRACE]:capmt: oscam: Received message from socket 34
2015-11-24 13:32:19.327 [  TRACE]:capmt: 40 3C 6F 2B 00 02 00 00 00 00 00 00 00 00 00 00 @<o+............
2015-11-24 13:32:19.327 [  TRACE]:capmt: 00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 ................
2015-11-24 13:32:19.327 [  TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2015-11-24 13:32:19.327 [  TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2015-11-24 13:32:19.327 [  TRACE]:capmt: 04                                              .               
2015-11-24 13:32:19.327 [  TRACE]:capmt: oscam: setting filter: adapter=0, demux=2, filter=0, pid=0
2015-11-24 13:32:19.327 [  TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2015-11-24 13:32:19.327 [  TRACE]:capmt: FF 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2015-11-24 13:32:19.327 [  TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2015-11-24 13:32:19.328 [  TRACE]:capmt: oscam: Received message from socket 34
2015-11-24 13:32:19.328 [  TRACE]:capmt: 40 3C 6F 2B 01 02 01 1A CC 80 00 00 00 00 00 00 @<o+............
2015-11-24 13:32:19.328 [  TRACE]:capmt: 00 00 00 00 00 00 00 00 00 F0 00 00 00 00 00 00 ................
2015-11-24 13:32:19.328 [  TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
2015-11-24 13:32:19.328 [  TRACE]:capmt: 00 00 00 00 00 00 00 00 00 00 00 0B B8 00 00 00 ................
2015-11-24 13:32:19.328 [  TRACE]:capmt: 04                                              .               
2015-11-24 13:32:19.328 [  TRACE]:capmt: oscam: setting filter: adapter=1, demux=2, filter=1, pid=6860
2015-11-24 13:32:20.444 [  TRACE]:descrambler: CAT data (len 48)
2015-11-24 13:32:20.444 [  TRACE]:descrambler: 09 04 06 48 E2 58 09 04 06 50 E2 59 09 04 18 30 ...H.X...P.Y...0
2015-11-24 13:32:20.444 [  TRACE]:descrambler: F0 03 09 04 18 43 F0 05 09 04 18 60 F0 07 09 04 .....C.....`....
2015-11-24 13:32:20.444 [  TRACE]:descrambler: 09 C4 F0 08 09 04 09 8C F0 08 09 04 18 6A F0 13 .............j..
2015-11-24 13:32:21.429 [  ERROR]:http: 192.168.2.232: HTTP/1.1 POST /api/epg/events/grid -- 401

note: I removed the serial number of the card.

#7

Updated by Jaroslav Kysela almost 9 years ago

It seems that you're using multiple DVBAPI clients together. It's not allowed. Only one client can use OSCAM at a time. You should setup OSCAM <- cccam -> OSCAM proxy for the second access.

#8

Updated by Markus S almost 9 years ago

I have only one tvheadend and one ocsam instance.
there are no other Programms running at my server that uses the DVBAPI.
but my DVBS2 tuner is a twin, so I can watch 2 downstreams a one tvheadend instance.
may this is the problem?

#9

Updated by Jaroslav Kysela almost 9 years ago

OK, do you have only one DVBAPI client configured in TVH? I'm a bit confused - I see many opened file descriptors in OSCAM. The OSCAM can descramble all services through one socket connection.

Also the logs are short. There should be some ECM messages which are not there.

#10

Updated by Jaroslav Kysela almost 9 years ago

Also, try OSCAM r11160 - the latest seems broken.

#11

Updated by Markus S almost 9 years ago

yes one config for oscam.
current version r11164 will try to get r11160.

I start the logging and then i use kodi to subscribe to a channel,
since i not get any image on the screen I stop the log.

#12

Updated by Petar Ivanov almost 9 years ago

I test last verion on OSCAM have many problem with DVBAPI modul, try you to back r11140 and see how work, because i have same problem with lastest verion on Oscam have many trable with DVBAPI

#13

Updated by C K almost 9 years ago

Confirmed, Oscam r11160 doesn't work atm, rr11140 works fine

#14

Updated by Jaroslav Kysela almost 9 years ago

Testing r11174:

My OSCAM fixes:
http://www.streamboard.tv/oscam/ticket/4367
http://www.streamboard.tv/oscam/ticket/4368

I'm able to decode multiple channels with above two changes in oscam and latest updates in TVH: v4.1-1066-g2ddbb07 .

#15

Updated by Jaroslav Kysela almost 9 years ago

Both changes are in oscam r11176 now. Thanks to @theparasol .

#16

Updated by Petar Ivanov almost 9 years ago

Jaroslav Kysela wrote:

Testing r11174:

My OSCAM fixes:
http://www.streamboard.tv/oscam/ticket/4367
http://www.streamboard.tv/oscam/ticket/4368

I'm able to decode multiple channels with above two changes in oscam and latest updates in TVH: v4.1-1066-g2ddbb07 .

With this change, back old bug, when start second channel from same mux stop first channel and unsubscribing first and start again stop second.

Same like this thread: https://tvheadend.org/issues/2031#change-9334

#17

Updated by Jaroslav Kysela almost 9 years ago

Fixed in v4.1-1068-gf9eea8f .

#18

Updated by Markus S almost 9 years ago

git verion 3346bcbc928c080c8edb6b097623f7cb4ebd8154 + oscam r11176
is working here.

#19

Updated by Jaroslav Kysela almost 9 years ago

  • Status changed from New to Fixed

Also available in: Atom PDF