Bug #6083: Descrambling problems with OSCam-DVB-Api and Newcamd on some Sky Germany sports channels via DVB-C - Tvheadend

Bug #6083

Descrambling problems with OSCam-DVB-Api and Newcamd on some Sky Germany sports channels via DVB-C

Added by Ernie Bert over 3 years ago. Updated about 2 years ago.

Status:

New

Priority:

Normal

Assignee:

Category:

Descrambling

Target version:

Start date:

2021-08-30

Due date:

% Done:

Estimated time:

Found in version:

4.3-1975~g23754f9a6

Affected Versions:

Description

Hi, since a few days my tvh has decryption problems with some sports channels on Sky Germany. I'm on local cable network "Vodafone" and CAID is 09C7.
I am using FritzBox 6490 DVB-C with newest firmware 7.27 for Sat>IP server and newest TVheadend v4.3-1975 for SAT>IP client.
It makes no difference using OSCam-DVB-Api (OSCam net protocol (rev >= 10389)) or Newcamd for decrypting.
Error messages are the same.

Sky Sport Bundesliga 1 HD odd stream key[32] is not valid

cannot decode packets for service "Sky Sport 1 HD" 
cannot decode packets for service "Sky Sport 2 HD"

The strange thing is, that this morning for about 60-90 Minutes on "Sky Bundesliga 1 HD" decrypting problems went away but now they are back.
I made very detailled tvh-log with subsystems tbl-base,tbl-csa,descrambler,descrambler-emm,caclient,csa,capmt,cwc,cccam,dvbcam
But I think there are traceable keys in it, so I won't append log here in public. I will only mail log-file for support.
In other forum I read about workaround to use minisatip for decryption tasks. Testers have solved tvh descrambling problems by that.
So my problem is reproducible with other users.
Thanks for support.

Files

TVHStreamFilter.png (20.8 KB) TVHStreamFilter.png		Thomas xyz, 2021-09-18 13:20
TVHTeletextFilter.png (19.7 KB) TVHTeletextFilter.png		Thomas xyz, 2021-09-18 15:33

History

Updated by Ernie Bert over 3 years ago

Here is an encrypted link with some logs. [[https://www.dropbox.com/s/mfogamjyqozdnio/tvh-logs.zip?dl=0]] Please contact me directely to ask for password. I can't publish clear logs here because of traceable keys.
In the newest log (yesterday 31st) tvh connects via Newcamd directely to the card. At first, Newcamd is not connected, then Newcamd is started. Later I added Sky Thriller HD to tvh (same transponder 554 MHz). This channel get decrypted perfectely. Thanks for support.

Updated by Anonymous over 3 years ago

Same Problem here.¶

No Problems with Enigma 2 Receivers but only with Tvheadend Version 4.3-1940~gfe0e5f1f9.

OSCam Log Debug 128¶

2021/09/01 21:39:51 00000000 s     (main) System name    = Linux
2021/09/01 21:39:51 00000000 s     (main) Host name      = espressobin
2021/09/01 21:39:51 00000000 s     (main) Release        = 5.10.16-mvebu64
2021/09/01 21:39:51 00000000 s     (main) Version        = #21.02.2 SMP PREEMPT Sun Feb 14 22:00:18 CET 2021
2021/09/01 21:39:51 00000000 s     (main) Machine        = aarch64
2021/09/01 21:39:51 00000000 s     (main) creating pidfile /tmp/.oscam/oscam.pid with pid 3916
2021/09/01 21:39:51 00000000 s   (config) userdb reloaded: 2 accounts loaded, 0 expired, 0 disabled
2021/09/01 21:39:51 00000000 s     (main) signal handling initialized
2021/09/01 21:39:51 00000000 s   (config) 11 provid's loaded
2021/09/01 21:39:51 00000000 s   (config) 2 service-id's loaded in 1 ms
2021/09/01 21:39:51 00000000 s      (net) newcamd: initialized (fd=5, port=5000)
2021/09/01 21:39:51 00000000 s      (net) -> CAID: 09C7 PROVID: 000000
2021/09/01 21:39:51 00000000   >> OSCam << cardserver log switched, version CyCeC+, build r11695 (rpi_aarch64-ssl-libusb-pcsc)
2021/09/01 21:39:51 00000000 s   (reader) KD_G09 [cccam] creating thread for device 
2021/09/01 21:39:51 00000000 s     (main) waiting for local card init
2021/09/01 21:39:51 32F369A9 h    (webif) webif: decompressed 200193 bytes back into 511408 bytes
2021/09/01 21:39:51 32F369A9 h    (webif) HTTP Server running. ip=0.0.0.0 port=83
2021/09/01 21:39:51 05F31013 p   (reader) : resolved ip=
2021/09/01 21:39:51 05F31013 p   (reader) KD_G09 [cccam] connecting to 
2021/09/01 21:39:52 00000000 s     (main) init for all local cards done
2021/09/01 21:39:52 00000000 s (emmcache) loading emmcache disabled since no reader is using it!
2021/09/01 21:39:52 00000000 s (emmcache) loading emmstats disabled since no reader is using it!
2021/09/01 21:39:52 00000000 s (anticasc) anti cascading disabled
2021/09/01 21:39:52 00000000 s     (gbox) local gbox failed init
2021/09/01 21:39:52 37BC9FE5 c   (client) plain dvbapi-client granted (dvbapiau, au=auto (8 reader))
2021/09/01 21:39:52 37BC9FE5 c   (dvbapi) dvbapi channelcache loaded from /usr/local/etc/oscam.ccache
2021/09/01 21:39:52 37BC9FE5 c   (dvbapi) Using TCP listen socket, API forced to DVBAPIv3 (0), userconfig boxtype: 11
2021/09/01 21:39:52 05F31013 p   (reader) KD_G09 [cccam] proxy initialized, server 
2021/09/01 21:40:31 05F31013 p   (reader) KD_G09 [cccam] disconnected: reason inactivity
2021/09/01 21:40:51 37BC9FE5 c   (dvbapi) Client connected: 'Tvheadend 4.3-1940~gfe0e5f1f9' (protocol version = 2)
2021/09/01 21:41:20 37BC9FE5 c   (dvbapi) Demuxer 0 ecmpid 0 CAID: 09C7 ECM_PID: 1D0D PROVID: 000000 
2021/09/01 21:41:20 37BC9FE5 c   (dvbapi) Demuxer 0 found 1 ECM pids and 4 STREAM pids in CA PMT
2021/09/01 21:41:20 37BC9FE5 c   (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C7 PROVID 000000 ECMPID 1D0D ANY CHID PMTPID 0060 VPID 00FF
2021/09/01 21:41:21 05F31013 p   (reader) KD_G09 [cccam] connecting to 
2021/09/01 21:41:25 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:1EAEF1DACCD25327A050DFB13FA80611): found  (320 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:41:25 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:8BBE92EE62E069376189CE467F1E2B71): found  (4322 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:41:35 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:E7C974F6B1ADD3B2ED0D4953732AA254): found  (305 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:41:45 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:E5914D59AC1A882BF56DAECC665042DE): found  (318 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:41:47 37BC9FE5 c   (dvbapi) Demuxer 0 stopped descrambling for program 0069 (Sky Sport Bundesliga 1 HD)
2021/09/01 21:42:00 37BC9FE5 c   (dvbapi) Demuxer 0 ecmpid 0 CAID: 09C7 ECM_PID: 1D0D PROVID: 000000 
2021/09/01 21:42:00 37BC9FE5 c   (dvbapi) Demuxer 0 found 1 ECM pids and 4 STREAM pids in CA PMT
2021/09/01 21:42:00 37BC9FE5 c   (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C7 PROVID 000000 ECMPID 1D0D ANY CHID PMTPID 0060 VPID 00FF
2021/09/01 21:42:01 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:6F554617F858EFD5129C8EFF13739F17): found  (233 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:42:05 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:6190B60CFBF5CEBCB5D87567BF40005E): found  (303 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:42:15 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:F64ADD8C0ED3F28A389329C9F530A628): found  (313 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:42:18 32F369A9 h    (webif) all debug_level=128
2021/09/01 21:42:21 37BC9FE5 c   (dvbapi) Demuxer 0 try to start new filter for caid: 0001, provid: 000001, pid: 0001
2021/09/01 21:42:21 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:21 37BC9FE5 c   (dvbapi)   40 3C 6F 2B 00 00 01 00 01 01 00 00 00 00 00 00 
2021/09/01 21:42:21 37BC9FE5 c   (dvbapi)   00 00 00 00 00 00 00 00 00 FF 00 00 00 00 00 00 
2021/09/01 21:42:21 37BC9FE5 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:21 37BC9FE5 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:21 37BC9FE5 c   (dvbapi)   04 
2021/09/01 21:42:21 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 2 started successfully (caid 0001 provid 000001 pid 0001)
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Got packetdata (msgid size: 0, clientprotocol: 2)
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi)   FF FF 00 00 00 01 01 B0 2D FF FF CD 00 00 09 04 
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi)   18 54 F5 0B 09 04 09 EF F5 09 09 04 09 C7 F5 09 
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi)   09 04 18 61 F5 07 09 04 18 34 F5 05 09 04 17 22 
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi)   F5 00 A4 57 B5 09 
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) This is a DVBAPI_FILTER_DATA packet with size 54 => lets process it!
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Demuxer 0 filter2[0] = 01, filter mask[0] = FF, flt&mask = 01 , buffer[0] = 01, buffer[0] & mask = 01
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Demuxer 0 filter 2 fetched CAT data (length = 0x030):
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi)   01 B0 2D FF FF CD 00 00 09 04 18 54 F5 0B 09 04 
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi)   09 EF F5 09 09 04 09 C7 F5 09 09 04 18 61 F5 07 
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi)   09 04 18 34 F5 05 09 04 17 22 F5 00 A4 57 B5 09 
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Demuxer 0 stop filter 2 try 1 (fd: 65535 api: 0, caid: 0001, provid: 000001, emmpid: 0001)
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi)   00 00 6F 2A 00 00 01 00 01 
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Added new emmpid 0 CAID: 1854 EMM_PID: 150B PROVID: 000000    TYPE :UNIQUE:SHARED:GLOBAL:
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Added new emmpid 1 CAID: 09EF EMM_PID: 1509 PROVID: 000000    TYPE :UNIQUE:SHARED:GLOBAL:
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Added new emmpid 2 CAID: 09C7 EMM_PID: 1509 PROVID: 000000    TYPE :UNIQUE:SHARED:GLOBAL:
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Added new emmpid 3 CAID: 1861 EMM_PID: 1507 PROVID: 000000    TYPE :UNIQUE:SHARED:GLOBAL:
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Added new emmpid 4 CAID: 1834 EMM_PID: 1505 PROVID: 000000    TYPE :UNIQUE:SHARED:GLOBAL:
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Added new emmpid 5 CAID: 1722 EMM_PID: 1500 PROVID: 000000    TYPE :UNIQUE:SHARED:GLOBAL:
2021/09/01 21:42:22 37BC9FE5 c   (dvbapi) Processing socketdata completed after 1 packets with 0 bytes left unprocessed
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Got packetdata (msgid size: 0, clientprotocol: 2)
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   FF FF 00 00 00 00 80 70 A3 00 00 01 1E D4 01 9D 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   47 06 92 AA 55 81 20 01 00 00 80 40 0E 25 E6 0A 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   C8 12 DB 1A 3D 03 03 05 FE 20 29 80 7D 13 1C EE 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   74 6B 22 25 AE AA 4A 00 00 33 0D 47 98 FA FE A2 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   9C 90 69 C0 01 B2 76 35 49 AC F3 E9 10 7B AA 86 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   D6 3E 24 B3 0A A8 0E EE A9 87 B8 16 66 FA A9 FF 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   AD 8A 05 EA 8F 8E CE F1 D9 06 66 D4 E5 FB 13 F7 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   E9 04 B3 B6 AB F7 CE 62 31 83 DD 48 6B 09 B2 D5 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   39 C4 41 58 B0 CD 8C 55 C9 5A 76 F5 23 36 51 41 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   7C D6 60 34 F4 5E A0 BD CF 82 E5 9E D8 03 B3 45 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   65 11 AA D0 70 8F F7 72 7B C2 B4 C4 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) This is a DVBAPI_FILTER_DATA packet with size 172 => lets process it!
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[9] = 06, filter mask[9] = FF, flt&mask = 06 , buffer[11] = 06, buffer[11] & mask = 06
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[10] = 92, filter mask[10] = FF, flt&mask = 92 , buffer[12] = 92, buffer[12] & mask = 92
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x0A6):
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   80 70 A3 00 00 01 1E D4 01 9D 47 06 92 AA 55 81 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   20 01 00 00 80 40 0E 25 E6 0A C8 12 DB 1A 3D 03 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   03 05 FE 20 29 80 7D 13 1C EE 74 6B 22 25 AE AA 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   4A 00 00 33 0D 47 98 FA FE A2 9C 90 69 C0 01 B2 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   76 35 49 AC F3 E9 10 7B AA 86 D6 3E 24 B3 0A A8 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   0E EE A9 87 B8 16 66 FA A9 FF AD 8A 05 EA 8F 8E 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   CE F1 D9 06 66 D4 E5 FB 13 F7 E9 04 B3 B6 AB F7 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   CE 62 31 83 DD 48 6B 09 B2 D5 39 C4 41 58 B0 CD 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   8C 55 C9 5A 76 F5 23 36 51 41 7C D6 60 34 F4 5E 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   A0 BD CF 82 E5 9E D8 03 B3 45 65 11 AA D0 70 8F 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   F7 72 7B C2 B4 C4 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 ECMTYPE 80 CAID 09C7 PROVID 000000 ECMPID 1D0D FAKECHID 0692 (unique part in ecm)
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 09C7 PROVID 000000 FD 65535)
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set chid to 0692 on fd 65535
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   40 3C 6F 2B 00 00 00 1D 0D 81 00 00 00 00 00 00 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   00 00 06 92 00 00 00 00 00 FF 00 00 00 00 00 00 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   00 00 FF FF 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   04 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 request controlword for ecm 09C7@000000/0692/0069/A6:2FC5D75E8C25FC8BCB0ABE27C46FC6C2
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Processing socketdata completed after 1 packets with 0 bytes left unprocessed
2021/09/01 21:42:25 00000000   >> OSCam << cardserver log switched, version CyCeC+, build r11695 (rpi_aarch64-ssl-libusb-pcsc)
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 control word received for PID 0 CAID 09C7 PROVID 000000 ECMPID 1D0D CHID 0692 VPID 00FF
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 requested controlword for ecm 2FC5D75E8C25FC8BCB0ABE27C46FC6C2 on fd 65535
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0020 -> write odd part of cw!
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 writing odd part (DC027C5A025AC21E) of controlword, replacing expired (A6429D850601A1A8)
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 write cw0 index: 0 (ca0)
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   40 10 6F 86 00 00 00 00 00 00 00 00 00 DC 02 7C 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   5A 02 5A C2 1E 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0021 -> skip, odd part of cw already written!
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 00FF -> skip, odd part of cw already written!
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0102 -> skip, odd part of cw already written!
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Using 1 of 64 total descramblers
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   FF FF 00 03 00 00 69 09 C7 1D 0D 00 00 00 00 00 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   00 01 4E 0A 76 69 64 65 6F 67 75 61 72 64 06 4B 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   44 5F 47 30 39 0B 65 37 2E 6B 69 6B 73 2E 70 72 
2021/09/01 21:42:25 37BC9FE5 c   (dvbapi)   6F 05 63 63 63 61 6D 01 
2021/09/01 21:42:25 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:2FC5D75E8C25FC8BCB0ABE27C46FC6C2): found  (334 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Got packetdata (msgid size: 0, clientprotocol: 2)
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   FF FF 00 00 00 00 81 70 A3 00 00 01 1E D4 01 9D 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   4C 06 92 AA 55 81 20 01 00 00 80 40 27 D0 2D 58 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   0E 56 AE 6B 8B 03 03 05 FE 20 83 80 7D 13 1C 67 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   3F 8B 05 A2 5C 5E C8 00 00 8C 32 22 51 3E 14 33 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   05 90 69 C0 01 E4 9C 3A F8 79 97 14 14 2A A7 0D 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   69 72 18 6A 53 D1 7A 1A 2B 7E B6 7B AB 41 65 CE 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   6A 74 F9 88 49 DA FE 0B EC 4F 0C B4 18 1F C6 AC 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   9C 76 12 20 26 AC 87 E4 E0 43 B7 34 A8 52 5E 25 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   27 8D E4 4E 00 64 C1 BF 08 8B 96 70 8A 6C B8 3C 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   2C 3C 52 F4 F3 4B 10 97 03 C3 FA 0F DF 44 0A 49 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   03 74 45 8C 96 8A FC 01 E1 22 F6 2D 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) This is a DVBAPI_FILTER_DATA packet with size 172 => lets process it!
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[9] = 06, filter mask[9] = FF, flt&mask = 06 , buffer[11] = 06, buffer[11] & mask = 06
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[10] = 92, filter mask[10] = FF, flt&mask = 92 , buffer[12] = 92, buffer[12] & mask = 92
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x0A6):
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   81 70 A3 00 00 01 1E D4 01 9D 4C 06 92 AA 55 81 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   20 01 00 00 80 40 27 D0 2D 58 0E 56 AE 6B 8B 03 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   03 05 FE 20 83 80 7D 13 1C 67 3F 8B 05 A2 5C 5E 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   C8 00 00 8C 32 22 51 3E 14 33 05 90 69 C0 01 E4 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   9C 3A F8 79 97 14 14 2A A7 0D 69 72 18 6A 53 D1 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   7A 1A 2B 7E B6 7B AB 41 65 CE 6A 74 F9 88 49 DA 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   FE 0B EC 4F 0C B4 18 1F C6 AC 9C 76 12 20 26 AC 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   87 E4 E0 43 B7 34 A8 52 5E 25 27 8D E4 4E 00 64 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   C1 BF 08 8B 96 70 8A 6C B8 3C 2C 3C 52 F4 F3 4B 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   10 97 03 C3 FA 0F DF 44 0A 49 03 74 45 8C 96 8A 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   FC 01 E1 22 F6 2D 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 ECMTYPE 81 CAID 09C7 PROVID 000000 ECMPID 1D0D FAKECHID 0692 (unique part in ecm)
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set ecmtable to EVEN (CAID 09C7 PROVID 000000 FD 65535)
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set chid to 0692 on fd 65535
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   40 3C 6F 2B 00 00 00 1D 0D 80 00 00 00 00 00 00 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   00 00 06 92 00 00 00 00 00 FF 00 00 00 00 00 00 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   00 00 FF FF 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   04 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 request controlword for ecm 09C7@000000/0692/0069/A6:197D282DDC0024C701DDCF342FFF7268
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Processing socketdata completed after 1 packets with 0 bytes left unprocessed
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 control word received for PID 0 CAID 09C7 PROVID 000000 ECMPID 1D0D CHID 0692 VPID 00FF
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 requested controlword for ecm 197D282DDC0024C701DDCF342FFF7268 on fd 65535
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0020 -> write even part of cw!
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 writing even part (6C45D7881D0EE00B) of controlword, replacing expired (4C8714E7B3F4DD84)
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 write cw1 index: 0 (ca0)
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   40 10 6F 86 00 00 00 00 00 00 00 00 01 6C 45 D7 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   88 1D 0E E0 0B 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0021 -> skip, even part of cw already written!
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 00FF -> skip, even part of cw already written!
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0102 -> skip, even part of cw already written!
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Using 1 of 64 total descramblers
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   FF FF 00 03 00 00 69 09 C7 1D 0D 00 00 00 00 00 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   00 01 25 0A 76 69 64 65 6F 67 75 61 72 64 06 4B 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   44 5F 47 30 39 0B 65 37 2E 6B 69 6B 73 2E 70 72 
2021/09/01 21:42:35 37BC9FE5 c   (dvbapi)   6F 05 63 63 63 61 6D 01 
2021/09/01 21:42:35 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:197D282DDC0024C701DDCF342FFF7268): found  (293 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Got packetdata (msgid size: 0, clientprotocol: 2)
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   FF FF 00 00 00 00 80 70 A3 00 00 01 1E D4 01 9D 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   51 06 92 AA 55 81 20 01 00 00 80 40 B6 C7 1D 4F 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   EE 8A 4F 75 D2 03 03 05 FE 20 FB 80 7D 13 1C 63 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   F9 7F B2 8E 48 5F BE 00 00 3A 60 53 85 A4 BE A9 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   A9 90 69 C0 01 A8 F6 AA 8E C9 08 A7 3F 00 85 AD 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   24 45 B2 79 96 B2 BC 49 3F 17 E4 C7 F2 29 75 40 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   A0 DC 92 7E A8 FA A5 D0 10 ED 71 FA 88 36 E0 7B 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   18 10 40 61 7C FD 52 41 D4 9A 44 29 B1 DF 74 48 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   DC 2A 8F 27 5A 98 2A 1E 20 97 B8 C4 B5 57 3B 65 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   71 A5 30 9A E8 FD 60 2F 7A A5 98 5E 4B 57 9E 9A 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   96 1E 61 20 AA F2 0A 7E 91 68 D8 65 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) This is a DVBAPI_FILTER_DATA packet with size 172 => lets process it!
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[9] = 06, filter mask[9] = FF, flt&mask = 06 , buffer[11] = 06, buffer[11] & mask = 06
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[10] = 92, filter mask[10] = FF, flt&mask = 92 , buffer[12] = 92, buffer[12] & mask = 92
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x0A6):
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   80 70 A3 00 00 01 1E D4 01 9D 51 06 92 AA 55 81 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   20 01 00 00 80 40 B6 C7 1D 4F EE 8A 4F 75 D2 03 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   03 05 FE 20 FB 80 7D 13 1C 63 F9 7F B2 8E 48 5F 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   BE 00 00 3A 60 53 85 A4 BE A9 A9 90 69 C0 01 A8 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   F6 AA 8E C9 08 A7 3F 00 85 AD 24 45 B2 79 96 B2 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   BC 49 3F 17 E4 C7 F2 29 75 40 A0 DC 92 7E A8 FA 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   A5 D0 10 ED 71 FA 88 36 E0 7B 18 10 40 61 7C FD 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   52 41 D4 9A 44 29 B1 DF 74 48 DC 2A 8F 27 5A 98 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   2A 1E 20 97 B8 C4 B5 57 3B 65 71 A5 30 9A E8 FD 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   60 2F 7A A5 98 5E 4B 57 9E 9A 96 1E 61 20 AA F2 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   0A 7E 91 68 D8 65 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 ECMTYPE 80 CAID 09C7 PROVID 000000 ECMPID 1D0D FAKECHID 0692 (unique part in ecm)
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 09C7 PROVID 000000 FD 65535)
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set chid to 0692 on fd 65535
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   40 3C 6F 2B 00 00 00 1D 0D 81 00 00 00 00 00 00 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   00 00 06 92 00 00 00 00 00 FF 00 00 00 00 00 00 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   00 00 FF FF 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   04 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 request controlword for ecm 09C7@000000/0692/0069/A6:83B137A079CF0B9B310B2054E32E6DB6
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Processing socketdata completed after 1 packets with 0 bytes left unprocessed
2021/09/01 21:42:45 00000000   >> OSCam << cardserver log switched, version CyCeC+, build r11695 (rpi_aarch64-ssl-libusb-pcsc)
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 control word received for PID 0 CAID 09C7 PROVID 000000 ECMPID 1D0D CHID 0692 VPID 00FF
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 requested controlword for ecm 83B137A079CF0B9B310B2054E32E6DB6 on fd 65535
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0020 -> write odd part of cw!
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 writing odd part (31DD1927D168CE07) of controlword, replacing expired (DC027C5A025AC21E)
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 write cw0 index: 0 (ca0)
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   40 10 6F 86 00 00 00 00 00 00 00 00 00 31 DD 19 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   27 D1 68 CE 07 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0021 -> skip, odd part of cw already written!
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 00FF -> skip, odd part of cw already written!
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0102 -> skip, odd part of cw already written!
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Using 1 of 64 total descramblers
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   FF FF 00 03 00 00 69 09 C7 1D 0D 00 00 00 00 00 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   00 01 E6 0A 76 69 64 65 6F 67 75 61 72 64 06 4B 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   44 5F 47 30 39 0B 65 37 2E 6B 69 6B 73 2E 70 72 
2021/09/01 21:42:45 37BC9FE5 c   (dvbapi)   6F 05 63 63 63 61 6D 01 
2021/09/01 21:42:45 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:83B137A079CF0B9B310B2054E32E6DB6): found  (486 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:42:52 37BC9FE5 c   (dvbapi) Demuxer 0 matching reader KD_G09 against available emmpids -> START!
2021/09/01 21:42:52 37BC9FE5 c   (dvbapi) Demuxer 0 matching reader KD_G09 against available emmpids -> DONE!
2021/09/01 21:42:52 37BC9FE5 c   (dvbapi) Demuxer 0 handles 0 emm filters
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Got packetdata (msgid size: 0, clientprotocol: 2)
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   FF FF 00 00 00 00 81 70 A3 00 00 01 1E D4 01 9D 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   56 06 92 AA 55 81 20 01 00 00 80 40 60 A0 8E 8A 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   6A F6 20 A8 B5 03 03 05 FE 20 FE 80 7D 13 1C F0 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   62 9E A2 E9 22 88 B3 00 00 E7 38 9F 27 69 A5 2F 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   61 90 69 C0 01 93 31 07 5A E5 F0 F1 A1 05 75 49 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   35 A9 4B 33 DB AE 0D 2D 24 B2 D3 E4 C5 FC F2 77 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   8B 5B 5A 6E F6 85 3B 83 D5 31 71 0A CA 99 AE DB 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   A2 2A D0 C6 4F 30 0E E8 17 7F 1D 9B 7A E1 E4 E2 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   E3 19 45 78 B6 60 2B DD 16 47 4C 99 68 90 CF C7 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   52 07 DD E8 2D 34 1E DA F8 51 81 02 01 B2 23 2F 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   C1 0D ED 00 A0 88 53 1D B0 8D 0E 40 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) This is a DVBAPI_FILTER_DATA packet with size 172 => lets process it!
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[0] = 81, filter mask[0] = FF, flt&mask = 81 , buffer[0] = 81, buffer[0] & mask = 81
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[9] = 06, filter mask[9] = FF, flt&mask = 06 , buffer[11] = 06, buffer[11] & mask = 06
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[10] = 92, filter mask[10] = FF, flt&mask = 92 , buffer[12] = 92, buffer[12] & mask = 92
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x0A6):
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   81 70 A3 00 00 01 1E D4 01 9D 56 06 92 AA 55 81 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   20 01 00 00 80 40 60 A0 8E 8A 6A F6 20 A8 B5 03 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   03 05 FE 20 FE 80 7D 13 1C F0 62 9E A2 E9 22 88 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   B3 00 00 E7 38 9F 27 69 A5 2F 61 90 69 C0 01 93 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   31 07 5A E5 F0 F1 A1 05 75 49 35 A9 4B 33 DB AE 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   0D 2D 24 B2 D3 E4 C5 FC F2 77 8B 5B 5A 6E F6 85 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   3B 83 D5 31 71 0A CA 99 AE DB A2 2A D0 C6 4F 30 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   0E E8 17 7F 1D 9B 7A E1 E4 E2 E3 19 45 78 B6 60 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   2B DD 16 47 4C 99 68 90 CF C7 52 07 DD E8 2D 34 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   1E DA F8 51 81 02 01 B2 23 2F C1 0D ED 00 A0 88 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   53 1D B0 8D 0E 40 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 ECMTYPE 81 CAID 09C7 PROVID 000000 ECMPID 1D0D FAKECHID 0692 (unique part in ecm)
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set ecmtable to EVEN (CAID 09C7 PROVID 000000 FD 65535)
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set chid to 0692 on fd 65535
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   40 3C 6F 2B 00 00 00 1D 0D 80 00 00 00 00 00 00 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   00 00 06 92 00 00 00 00 00 FF 00 00 00 00 00 00 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   00 00 FF FF 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   04 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 request controlword for ecm 09C7@000000/0692/0069/A6:98D9115705F13C136D2506147BAE6A77
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Processing socketdata completed after 1 packets with 0 bytes left unprocessed
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 control word received for PID 0 CAID 09C7 PROVID 000000 ECMPID 1D0D CHID 0692 VPID 00FF
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 requested controlword for ecm 98D9115705F13C136D2506147BAE6A77 on fd 65535
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0020 -> write even part of cw!
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 writing even part (92F8A9334DC16F7D) of controlword, replacing expired (6C45D7881D0EE00B)
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 write cw1 index: 0 (ca0)
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   40 10 6F 86 00 00 00 00 00 00 00 00 01 92 F8 A9 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   33 4D C1 6F 7D 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0021 -> skip, even part of cw already written!
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 00FF -> skip, even part of cw already written!
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0102 -> skip, even part of cw already written!
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Using 1 of 64 total descramblers
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   FF FF 00 03 00 00 69 09 C7 1D 0D 00 00 00 00 00 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   00 01 E3 0A 76 69 64 65 6F 67 75 61 72 64 06 4B 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   44 5F 47 30 39 0B 65 37 2E 6B 69 6B 73 2E 70 72 
2021/09/01 21:42:55 37BC9FE5 c   (dvbapi)   6F 05 63 63 63 61 6D 01 
2021/09/01 21:42:55 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:98D9115705F13C136D2506147BAE6A77): found  (483 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:42:55 00000000   >> OSCam << cardserver log switched, version CyCeC+, build r11695 (rpi_aarch64-ssl-libusb-pcsc)
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Got packetdata (msgid size: 0, clientprotocol: 2)
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   FF FF 00 00 00 00 80 70 A3 00 00 01 1E D4 01 9D 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   5B 06 92 AA 55 81 20 01 00 00 80 40 14 8C DD 6A 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   5A D7 3B 09 4E 03 03 05 FE 20 B8 80 7D 13 1C 7B 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   75 BC 71 72 EC 50 E7 00 00 40 22 43 DB E6 CE 7F 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   72 90 69 C0 01 30 D6 D6 EF BE B6 A9 FA 64 5B 9C 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   B9 F7 A2 5D 21 FA 23 3E 99 8B 23 C7 85 7C 6E 9D 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   B3 F8 75 16 56 EF 1C 7D E8 FD E3 38 64 DC C8 CD 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   A0 36 2C D3 BD C5 CF 17 17 28 62 23 BC 2D EC EC 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   E1 D1 AC 04 BF A2 1E 9D 04 88 80 60 E1 74 D3 6E 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   64 AE 4D 68 19 90 B9 87 66 DA A7 A2 18 7F 27 5E 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   C0 64 FA A8 D4 A1 B8 CF 38 FA CA 5D 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) This is a DVBAPI_FILTER_DATA packet with size 172 => lets process it!
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[0] = 80, filter mask[0] = FF, flt&mask = 80 , buffer[0] = 80, buffer[0] & mask = 80
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[9] = 06, filter mask[9] = FF, flt&mask = 06 , buffer[11] = 06, buffer[11] & mask = 06
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 filter1[10] = 92, filter mask[10] = FF, flt&mask = 92 , buffer[12] = 92, buffer[12] & mask = 92
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 filter 1 fetched ECM data (length = 0x0A6):
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   80 70 A3 00 00 01 1E D4 01 9D 5B 06 92 AA 55 81 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   20 01 00 00 80 40 14 8C DD 6A 5A D7 3B 09 4E 03 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   03 05 FE 20 B8 80 7D 13 1C 7B 75 BC 71 72 EC 50 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   E7 00 00 40 22 43 DB E6 CE 7F 72 90 69 C0 01 30 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   D6 D6 EF BE B6 A9 FA 64 5B 9C B9 F7 A2 5D 21 FA 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   23 3E 99 8B 23 C7 85 7C 6E 9D B3 F8 75 16 56 EF 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   1C 7D E8 FD E3 38 64 DC C8 CD A0 36 2C D3 BD C5 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   CF 17 17 28 62 23 BC 2D EC EC E1 D1 AC 04 BF A2 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   1E 9D 04 88 80 60 E1 74 D3 6E 64 AE 4D 68 19 90 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   B9 87 66 DA A7 A2 18 7F 27 5E C0 64 FA A8 D4 A1 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   B8 CF 38 FA CA 5D 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 ECMTYPE 80 CAID 09C7 PROVID 000000 ECMPID 1D0D FAKECHID 0692 (unique part in ecm)
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set ecmtable to ODD (CAID 09C7 PROVID 000000 FD 65535)
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 Filter 1 set chid to 0692 on fd 65535
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   40 3C 6F 2B 00 00 00 1D 0D 81 00 00 00 00 00 00 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   00 00 06 92 00 00 00 00 00 FF 00 00 00 00 00 00 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   00 00 FF FF 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   04 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 request controlword for ecm 09C7@000000/0692/0069/A6:7AAB0E0C6A29F88D81AFC049AD81C9DB
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Processing socketdata completed after 1 packets with 0 bytes left unprocessed
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 control word received for PID 0 CAID 09C7 PROVID 000000 ECMPID 1D0D CHID 0692 VPID 00FF
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 requested controlword for ecm 7AAB0E0C6A29F88D81AFC049AD81C9DB on fd 65535
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0020 -> write odd part of cw!
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 writing odd part (622E2DBD103C519D) of controlword, replacing expired (31DD1927D168CE07)
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 write cw0 index: 0 (ca0)
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   40 10 6F 86 00 00 00 00 00 00 00 00 00 62 2E 2D 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   BD 10 3C 51 9D 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0021 -> skip, odd part of cw already written!
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 00FF -> skip, odd part of cw already written!
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Demuxer 0 ca0 is using index 0 for streampid 0102 -> skip, odd part of cw already written!
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Using 1 of 64 total descramblers
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   FF FF 00 03 00 00 69 09 C7 1D 0D 00 00 00 00 00 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   00 01 14 0A 76 69 64 65 6F 67 75 61 72 64 06 4B 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   44 5F 47 30 39 0B 65 37 2E 6B 69 6B 73 2E 70 72 
2021/09/01 21:43:05 37BC9FE5 c   (dvbapi)   6F 05 63 63 63 61 6D 01 
2021/09/01 21:43:05 37BC9FE5 c      (ecm) dvbapiau    (09C7@000000/0692/0069/A6:7AAB0E0C6A29F88D81AFC049AD81C9DB): found  (276 ms) by KD_G09      - Sky Sport Bundesliga 1 
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Got packetdata (msgid size: 0, clientprotocol: 2)
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi)   9F 80 3F 04 83 02 00 FF 
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) This is a DVBAPI_AOT_CA_STOP packet with size 8 => lets process it!
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Received DVBAPI_AOT_CA_STOP object on socket 6:
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Demuxer 0 stopped descrambling for program 0069 (Sky Sport Bundesliga 1 HD)
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Remove streampid 0020 using indexer 0 from ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Removed last indexer of streampid 0020 from ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Demuxer 0 disable stream 1 pid=0x0020 index=-1 on ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi)   40 08 6F 87 00 00 00 00 20 FF FF FF FF 
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Remove streampid 0021 using indexer 0 from ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Removed last indexer of streampid 0021 from ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Demuxer 0 disable stream 2 pid=0x0021 index=-1 on ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi)   40 08 6F 87 00 00 00 00 21 FF FF FF FF 
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Remove streampid 00FF using indexer 0 from ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Removed last indexer of streampid 00FF from ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Demuxer 0 disable stream 3 pid=0x00ff index=-1 on ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi)   40 08 6F 87 00 00 00 00 FF FF FF FF FF 
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Remove streampid 0102 using indexer 0 from ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Removed last indexer of streampid 0102 from ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Demuxer 0 disable stream 4 pid=0x0102 index=-1 on ca0
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi)   40 08 6F 87 00 00 00 01 02 FF FF FF FF 
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Demuxer 0 stop filter 1 try 1 (fd: 65535 api: 0, caid: 09C7, provid: 000000, ecmpid: 1D0D)
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Sending packet to dvbapi client (fd=6):
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi)   00 00 6F 2A 00 00 00 1D 0D 
2021/09/01 21:43:06 37BC9FE5 c   (dvbapi) Processing socketdata completed after 1 packets with 0 bytes left unprocessed

TVHeadend Debug Log¶

with tbl-base,tbl-csa,descrambler,descrambler-emm,caclient,csa,capmt,cwc,cccam,dvbcam

2021-09-01 21:42:00.858 [ INFO]:http: 192.168.0.71: using ticket 72c079a122e0bf598f3b2088571be08eb5eb1141 for /stream/channel/7033ab2f955cdf005281c799023a0afc
2021-09-01 21:42:00.860 [ INFO]:mpegts: 554MHz in Berlin - tuning on SAT>IP DVB-C Tuner #1 (192.168.0.55@UDP)
2021-09-01 21:42:00.861 [ INFO]:capmt: dvbapiau: Starting CAPMT server for service "Sky Sport Bundesliga 1 HD" on adapter 0
2021-09-01 21:42:00.861 [ DEBUG]:capmt: dvbapiau: New caid 0x09C7:0x000000 (pid 0x1D0D) for service "Sky Sport Bundesliga 1 HD"
2021-09-01 21:42:00.861 [ DEBUG]:capmt: dvbapiau: adding ECMPID=0x1D0D (7437), CAID=0x9C7 (2503) PROVID=0x0 (0), SID=105, ADAPTER=0
2021-09-01 21:42:00.861 [ DEBUG]:capmt: dvbapiau: Trying to obtain key for service "Sky Sport Bundesliga 1 HD"
2021-09-01 21:42:00.861 [ INFO]:subscription: 00F9: "HTTP" subscribing on channel "Sky Sport Bundesliga 1 HD", weight: 100, adapter: "SAT>IP DVB-C Tuner #1 (192.168.0.55@UDP)", network: "Berlin", mux: "554MHz", provider: "SKY", service: "Sky Sport Bundesliga 1 HD", profile="pass", hostname="192.168.0.71", client="VLC/3.0.16 LibVLC/3.0.16"
2021-09-01 21:42:01.225 [ DEBUG]:tbl-base: cat: caid 1854 (6228) pid 150B (5387)
2021-09-01 21:42:01.225 [ DEBUG]:tbl-base: cat: caid 09EF (2543) pid 1509 (5385)
2021-09-01 21:42:01.225 [ DEBUG]:tbl-base: cat: caid 09C7 (2503) pid 1509 (5385)
2021-09-01 21:42:01.225 [ DEBUG]:tbl-base: cat: caid 1861 (6241) pid 1507 (5383)
2021-09-01 21:42:01.225 [ DEBUG]:tbl-base: cat: caid 1834 (6196) pid 1505 (5381)
2021-09-01 21:42:01.225 [ DEBUG]:tbl-base: cat: caid 1722 (5922) pid 1500 (5376)
2021-09-01 21:42:01.281 [ DEBUG]:tbl-base: pmt: sid 0069 (105)
2021-09-01 21:42:01.281 [ DEBUG]:tbl-base: pmt: pcr_pid 00FF
2021-09-01 21:42:01.281 [ DEBUG]:tbl-base: pmt: caid 1834 (NagraVision) provider 00000000 pid 1C0D
2021-09-01 21:42:01.281 [ DEBUG]:tbl-base: pmt: caid 09FD (NDS) provider 00000000 pid 1E0D
2021-09-01 21:42:01.281 [ DEBUG]:tbl-base: pmt: caid 09C7 (NDS) provider 00000000 pid 1D0D
2021-09-01 21:42:01.281 [ DEBUG]:tbl-base: pmt: caid 1861 (NagraVision) provider 00000000 pid 190D
2021-09-01 21:42:01.286 [ DEBUG]:tbl-base: pmt: caid 1854 (NagraVision) provider 00000000 pid 1B0D
2021-09-01 21:42:01.289 [ DEBUG]:tbl-base: pmt: caid 09EF (NDS) provider 00000000 pid 1A0D
2021-09-01 21:42:01.291 [ DEBUG]:tbl-base: pmt: pid 0020 estype 6
2021-09-01 21:42:01.294 [ DEBUG]:tbl-base: pmt: type TELETEXT position 0
2021-09-01 21:42:01.296 [ DEBUG]:tbl-base: pmt: pid 0021 estype 6
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: type DVBSUB position 0
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: language ger
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: composition_id 1
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: ancillary_id 1
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: pid 00FF estype 27
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: type H264 position 0
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: pid 0102 estype 6
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: type AC3 position 0
2021-09-01 21:42:01.297 [ DEBUG]:tbl-base: pmt: language qaa
2021-09-01 21:42:01.321 [ DEBUG]:tbl-base: pat: 0x558cfa1930: tsid 000C (12)
2021-09-01 21:42:01.322 [ DEBUG]:tbl-base: pat: sid 000B (11) on pid 0061 (97)
2021-09-01 21:42:01.322 [ DEBUG]:tbl-base: pat: sid 0069 (105) on pid 0060 (96)
2021-09-01 21:42:01.322 [ DEBUG]:tbl-base: pat: sid 006C (108) on pid 0063 (99)
2021-09-01 21:42:01.322 [ DEBUG]:tbl-base: pat: sid 0122 (290) on pid 006E (110)
2021-09-01 21:42:01.322 [ DEBUG]:tbl-base: pat: sid 0229 (553) on pid 0065 (101)
2021-09-01 21:42:01.433 [ DEBUG]:tbl-base: cat: completed pid 1 table 00000001 / 000000ff
2021-09-01 21:42:01.480 [ DEBUG]:tbl-base: pmt: completed pid 96 table 00000002 / 000000ff
2021-09-01 21:42:01.482 [ DEBUG]:tbl-base: pat: completed pid 0 table 00000000 / 00000000
2021-09-01 21:42:01.504 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index -1 pid 32 (0x0020)
2021-09-01 21:42:01.504 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index 0 pid 32 (0x0020)
2021-09-01 21:42:01.504 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index -1 pid 33 (0x0021)
2021-09-01 21:42:01.504 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index 0 pid 33 (0x0021)
2021-09-01 21:42:01.505 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index -1 pid 255 (0x00ff)
2021-09-01 21:42:01.505 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index 0 pid 255 (0x00ff)
2021-09-01 21:42:01.505 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index -1 pid 258 (0x0102)
2021-09-01 21:42:01.505 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index 0 pid 258 (0x0102)
2021-09-01 21:42:01.505 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 0 idx 0 8312881d1e1cb2ec
2021-09-01 21:42:01.505 [ DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "Sky Sport Bundesliga 1 HD"
2021-09-01 21:42:01.505 [ DEBUG]:descrambler: Obtained CSA keys from capmt-192.168.0.74-9000 for service "Sky Sport Bundesliga 1 HD"
2021-09-01 21:42:01.505 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 1 idx 0 014d53a1b5075511
2021-09-01 21:42:01.505 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=233 hops=1 reader='KD_G09' from='192.168.0.74' protocol='cccam'
2021-09-01 21:42:01.505 [ DEBUG]:capmt: dvbapiau: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=233 hops=1 reader=KD_G09 from=192.168.0.74 protocol=cccam
2021-09-01 21:42:01.721 [ DEBUG]:tbl-base: nit: network F009 (61449) [Kabel Deutschland]
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: network F009 (61449) []
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: DVB-C freq 402000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2712 (10002) mux 554MHz in Berlin
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C3BE (50110) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C419 (50201) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C41A (50202) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C41C (50204) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C41E (50206) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C41F (50207) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C420 (50208) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C423 (50211) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C424 (50212) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C425 (50213) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C427 (50215) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C42B (50219) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C42D (50221) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C42E (50222) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C42F (50223) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C430 (50224) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C432 (50226) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C433 (50227) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C437 (50231) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C438 (50232) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C439 (50233) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C43A (50234) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C43B (50235) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C43C (50236) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C613 (50707) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service CF09 (53001) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service CF0F (53007) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service CF76 (53110) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service D04E (53326) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service D09C (53404) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service D09F (53407) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service D16B (53611) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service D16D (53613) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service D174 (53620) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service CFE4 (53220) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service C4F1 (50417) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: DVB-C freq 450000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: onid 0001 (1) tsid 0437 (1079) mux 554MHz in Berlin
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 2B66 (11110) type 19 (25)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6D66 (28006) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6D67 (28007) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6D68 (28008) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6D6B (28011) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6D6C (28012) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6D6D (28013) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6D6E (28014) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6D71 (28017) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 286F (10351) type 19 (25)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 2BA2 (11170) type 19 (25)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: DVB-C freq 458000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: onid 0001 (1) tsid 0431 (1073) mux 554MHz in Berlin
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E2D (28205) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E2E (28206) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E40 (28224) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E41 (28225) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E42 (28226) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E43 (28227) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E44 (28228) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E45 (28229) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6E46 (28230) type 01 (1)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F0C (28428) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F0D (28429) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F0E (28430) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F0F (28431) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F10 (28432) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F11 (28433) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F12 (28434) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F13 (28435) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F15 (28437) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F16 (28438) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F17 (28439) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F18 (28440) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F19 (28441) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F1A (28442) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F1B (28443) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F1C (28444) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F1D (28445) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F20 (28448) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F21 (28449) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F22 (28450) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F24 (28452) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F25 (28453) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F26 (28454) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F27 (28455) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F28 (28456) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F29 (28457) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F31 (28465) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F32 (28466) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F33 (28467) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F34 (28468) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F35 (28469) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F36 (28470) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F37 (28471) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F38 (28472) type 02 (2)
2021-09-01 21:42:02.243 [ DEBUG]:tbl-base: nit: service 6F03 (28419) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F04 (28420) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F05 (28421) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F06 (28422) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F07 (28423) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F08 (28424) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F2D (28461) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F2E (28462) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F2F (28463) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F3B (28475) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F3C (28476) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F3D (28477) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F3E (28478) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F3F (28479) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F40 (28480) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F41 (28481) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F42 (28482) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F43 (28483) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2856 (10326) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 286E (10350) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2858 (10328) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2857 (10327) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 285A (10330) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2871 (10353) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2872 (10354) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBB0 (52144) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBB1 (52145) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBB2 (52146) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBB3 (52147) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 283F (10303) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBA7 (52135) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBAA (52138) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F1E (28446) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F1F (28447) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBA5 (52133) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBA0 (52128) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CB9E (52126) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CB9F (52127) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CB9B (52123) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBA3 (52131) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CB9D (52125) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBA1 (52129) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service CBA4 (52132) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: DVB-C freq 338000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: onid 0001 (1) tsid 044D (1101) mux 554MHz in Berlin
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6DCA (28106) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6DCB (28107) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6DCC (28108) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6DCF (28111) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6DD1 (28113) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF0 (28400) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF1 (28401) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF2 (28402) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF3 (28403) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF4 (28404) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF5 (28405) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF6 (28406) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF8 (28408) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2855 (10325) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2859 (10329) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2870 (10352) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EF7 (28407) type 02 (2)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: DVB-C freq 330000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: onid 0001 (1) tsid 041B (1051) mux 554MHz in Berlin
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2B5C (11100) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F47 (28487) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 7031 (28721) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 7032 (28722) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 7034 (28724) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 7035 (28725) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 2840 (10304) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6EA5 (28325) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 6F46 (28486) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: DVB-C freq 362000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: onid 0085 (133) tsid 0003 (3) mux 554MHz in Berlin
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 000D (13) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 000E (14) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0016 (22) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 002B (43) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 013D (317) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 013E (318) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0140 (320) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0147 (327) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0148 (328) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 014A (330) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0204 (516) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0206 (518) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 001D (29) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: DVB-C freq 378000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: onid 0085 (133) tsid 0004 (4) mux 554MHz in Berlin
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0011 (17) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 001C (28) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 000F (15) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0010 (16) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0075 (117) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0133 (307) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0134 (308) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0136 (310) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0151 (337) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0152 (338) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 014F (335) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0154 (340) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0195 (405) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0197 (407) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: DVB-C freq 386000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: onid 0085 (133) tsid 0001 (1) mux 554MHz in Berlin
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0008 (8) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0017 (23) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0065 (101) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 007E (126) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 007F (127) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0085 (133) type 19 (25)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 008A (138) type 01 (1)
2021-09-01 21:42:02.244 [ DEBUG]:tbl-base: nit: service 0101 (257) type 19 (25)
2021-09-01 21:42:02.245 [ DEBUG]:tbl-base: nit: service 0102 (258) type 19 (25)
2021-09-01 21:42:02.245 [ DEBUG]:tbl-base: nit: service 0104 (260) type 19 (25)
2021-09-01 21:42:02.245 [ DEBUG]:tbl-base: nit: service 0129 (297) type 19 (25)
2021-09-01 21:42:02.245 [ DEBUG]:tbl-base: nit: service 012A (298) type 19 (25)
2021-09-01 21:42:02.245 [ DEBUG]:tbl-base: nit: service 012C (300) type 19 (25)
2021-09-01 21:42:02.245 [ DEBUG]:tbl-base: nit: service 0144 (324) type 19 (25)
2021-09-01 21:42:02.245 [ DEBUG]:tbl-base: nit: service 0013 (19) type 01 (1)
2021-09-01 21:42:02.769 [ DEBUG]:tbl-base: nit: network F009 (61449) []
2021-09-01 21:42:02.769 [ DEBUG]:tbl-base: nit: DVB-C freq 522000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: DVB-C freq 530000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: onid 0085 (133) tsid 000D (13) mux 554MHz in Berlin
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 006B (107) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 006E (110) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 006F (111) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0070 (112) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0072 (114) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0074 (116) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0093 (147) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0115 (277) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0116 (278) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0118 (280) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: DVB-C freq 554000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: onid 0085 (133) tsid 000C (12) mux 554MHz in Berlin
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 000B (11) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0069 (105) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 006C (108) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 011F (287) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0120 (288) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service 0122 (290) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: DVB-C freq 442000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2718 (10008) mux 554MHz in Berlin
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C3BA (50106) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C47F (50303) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E0 (50400) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C60C (50700) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C612 (50706) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C614 (50708) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C617 (50711) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C619 (50713) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CF0A (53002) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CFD8 (53208) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D048 (53320) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D0A5 (53413) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D167 (53607) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D175 (53621) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D176 (53622) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D17B (53627) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: DVB-C freq 394000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2713 (10003) mux 554MHz in Berlin
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C3BC (50108) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C615 (50709) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C618 (50712) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CF12 (53010) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D035 (53301) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D099 (53401) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D0A0 (53408) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D0A2 (53410) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D0A3 (53411) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D0A4 (53412) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D162 (53602) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D163 (53603) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D164 (53604) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D166 (53606) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D178 (53624) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C3CB (50123) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: DVB-C freq 546000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2714 (10004) mux 554MHz in Berlin
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C43F (50239) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C441 (50241) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C442 (50242) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C443 (50243) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C445 (50245) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C446 (50246) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C447 (50247) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E1 (50401) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E2 (50402) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E3 (50403) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E4 (50404) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E6 (50406) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E7 (50407) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E8 (50408) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4E9 (50409) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CF7F (53119) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CFDA (53210) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D09B (53403) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D100 (53504) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D101 (53505) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D102 (53506) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D103 (53507) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D16E (53614) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D16F (53615) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C3B5 (50101) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CF6E (53102) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D04F (53327) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CF0C (53004) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CF80 (53120) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4F2 (50418) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C4EA (50410) type 02 (2)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: DVB-C freq 466000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2717 (10007) mux 554MHz in Berlin
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C3BB (50107) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C47D (50301) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C480 (50304) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C481 (50305) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C610 (50704) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service CF0E (53006) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D09A (53402) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D0A1 (53409) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D169 (53609) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D170 (53616) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D171 (53617) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D17E (53630) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C487 (50311) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service D17C (53628) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C488 (50312) type 01 (1)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: DVB-C freq 498000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2716 (10006) mux 554MHz in Berlin
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C363 (50019) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C365 (50021) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C36A (50026) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C36B (50027) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C36C (50028) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C36D (50029) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: service C3A5 (50085) type 19 (25)
2021-09-01 21:42:02.770 [ DEBUG]:tbl-base: nit: DVB-C freq 538000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2726 (10022) mux 554MHz in Berlin
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C75F (51039) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C761 (51041) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service CF72 (53106) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service CF77 (53111) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service CFD4 (53204) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service CFD6 (53206) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service CFD9 (53209) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D172 (53618) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D049 (53321) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D04B (53323) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D04C (53324) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D04D (53325) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D104 (53508) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D105 (53509) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D16C (53612) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C61A (50714) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C369 (50025) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C36A (50026) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: DVB-C freq 610000000 sym 6900000 mod QAM/64 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2725 (10021) mux 554MHz in Berlin
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C757 (51031) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C763 (51043) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C759 (51033) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C75A (51034) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C75D (51037) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C762 (51042) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D03B (53307) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D046 (53318) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C75E (51038) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service D03A (53306) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C3CA (50122) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C75C (51036) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C767 (51047) type 01 (1)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: DVB-C freq 434000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 271C (10012) mux 554MHz in Berlin
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C356 (50006) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C357 (50007) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C358 (50008) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C361 (50017) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C364 (50020) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C368 (50024) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C39F (50079) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: DVB-C freq 426000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 271D (10013) mux 554MHz in Berlin
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C351 (50001) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C352 (50002) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C354 (50004) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C35D (50013) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C35F (50015) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C389 (50057) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C3A6 (50086) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: DVB-C freq 418000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 271E (10014) mux 554MHz in Berlin
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service 2B70 (11120) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C359 (50009) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C35A (50010) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C360 (50016) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C366 (50022) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service 2873 (10355) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: DVB-C freq 122000000 sym 6900000 mod QAM/64 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: DVB-C freq 346000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 271F (10015) mux 554MHz in Berlin
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C355 (50005) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C35B (50011) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C35C (50012) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C362 (50018) type 19 (25)
2021-09-01 21:42:02.771 [ DEBUG]:tbl-base: nit: service C367 (50023) type 19 (25)
2021-09-01 21:42:02.869 [ DEBUG]:tbl-base: sdt: onid 0085 (133) tsid 000C (12)
2021-09-01 21:42:02.871 [ DEBUG]:tbl-base: sdt: mux 554MHz in Berlin
2021-09-01 21:42:02.871 [ DEBUG]:tbl-base: sdt: sid 0069 (105) running 4 free_ca 1
2021-09-01 21:42:02.872 [ DEBUG]:tbl-base: sdt: sid 011F (287) running 1 free_ca 1
2021-09-01 21:42:02.873 [ DEBUG]:tbl-base: sdt: sid 0122 (290) running 4 free_ca 1
2021-09-01 21:42:02.875 [ DEBUG]:tbl-base: sdt: sid 0120 (288) running 1 free_ca 1
2021-09-01 21:42:02.876 [ DEBUG]:tbl-base: sdt: sid 006C (108) running 4 free_ca 1
2021-09-01 21:42:02.877 [ DEBUG]:tbl-base: sdt: sid 0228 (552) running 1 free_ca 1
2021-09-01 21:42:02.877 [ DEBUG]:tbl-base: sdt: sid 0229 (553) running 4 free_ca 1
2021-09-01 21:42:02.877 [ DEBUG]:tbl-base: sdt: sid 022A (554) running 4 free_ca 1
2021-09-01 21:42:02.877 [ DEBUG]:tbl-base: sdt: sid 000B (11) running 4 free_ca 1
2021-09-01 21:42:02.877 [ DEBUG]:tbl-base: sdt: completed pid 17 table 00000040 / 000000f8
2021-09-01 21:42:03.006 [ DEBUG]:tbl-base: nit: network F009 (61449) []
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: DVB-C freq 562000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2721 (10017) mux 554MHz in Berlin
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service CF0B (53003) type 01 (1)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C756 (51030) type 01 (1)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service CF11 (53009) type 01 (1)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service CF14 (53012) type 01 (1)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service CF13 (53011) type 01 (1)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service CF16 (53014) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service 2B8E (11150) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C378 (50040) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C4EC (50412) type 02 (2)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C4ED (50413) type 02 (2)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C4EE (50414) type 02 (2)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C4EF (50415) type 02 (2)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C4F0 (50416) type 02 (2)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service 6D6F (28015) type 02 (2)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C3AA (50090) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: DVB-C freq 354000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2720 (10016) mux 554MHz in Berlin
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C371 (50033) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C373 (50035) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C36F (50031) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C370 (50032) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C372 (50034) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C36E (50030) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C3A4 (50084) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: DVB-C freq 474000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2719 (10009) mux 554MHz in Berlin
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C37B (50043) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C37D (50045) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C37F (50047) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service 2B98 (11160) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service 2B7A (11130) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service 285B (10331) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: DVB-C freq 490000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 271B (10011) mux 554MHz in Berlin
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C387 (50055) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C38B (50059) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C38C (50060) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C388 (50056) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C38A (50058) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C3A2 (50082) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C396 (50070) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: DVB-C freq 482000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 271A (10010) mux 554MHz in Berlin
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C381 (50049) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C382 (50050) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C384 (50052) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C385 (50053) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C386 (50054) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C3A0 (50080) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C3A9 (50089) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: DVB-C freq 570000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2727 (10023) mux 554MHz in Berlin
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C39E (50078) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C39C (50076) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service 2888 (10376) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C394 (50068) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C3A8 (50088) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C377 (50039) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: DVB-C freq 578000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2724 (10020) mux 554MHz in Berlin
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C375 (50037) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C398 (50072) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C37E (50046) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C39A (50074) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C393 (50067) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: service C3A7 (50087) type 19 (25)
2021-09-01 21:42:03.007 [ DEBUG]:tbl-base: nit: DVB-C freq 586000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2722 (10018) mux 554MHz in Berlin
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service C38E (50062) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service C38D (50061) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service C390 (50064) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service C38F (50063) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service C391 (50065) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service C392 (50066) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service C3AB (50091) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: DVB-C freq 594000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2723 (10019) mux 554MHz in Berlin
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CBC2 (52162) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CBC3 (52163) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CBC4 (52164) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CBC5 (52165) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CBC6 (52166) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CBC7 (52167) type 19 (25)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: DVB-C freq 410000000 sym 6900000 mod QAM/256 fec 3/5 ds 0 plp -1
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: onid F001 (61441) tsid 2715 (10005) mux 554MHz in Berlin
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CF6D (53101) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CFD1 (53201) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CFD2 (53202) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CFD3 (53203) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CFD5 (53205) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service CFDC (53212) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D0FD (53501) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D0FF (53503) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D106 (53510) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D107 (53511) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D108 (53512) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D109 (53513) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D10A (53514) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D173 (53619) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D179 (53625) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: service D117 (53527) type 01 (1)
2021-09-01 21:42:03.008 [ DEBUG]:tbl-base: nit: completed pid 16 table 00000000 / 00000000
2021-09-01 21:42:03.790 [ DEBUG]:tbl-base: sdt: onid 0085 (133) tsid 0006 (6)
2021-09-01 21:42:03.791 [ DEBUG]:tbl-base: sdt: completed pid 17 table 00000040 / 000000f8
2021-09-01 21:42:05.360 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 0 idx 0 a6429d850601a1a8
2021-09-01 21:42:05.361 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=303 hops=1 reader='KD_G09' from='192.168.0.74' protocol='cccam'
2021-09-01 21:42:05.362 [ DEBUG]:capmt: dvbapiau: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=303 hops=1 reader=KD_G09 from=192.168.0.74 protocol=cccam
2021-09-01 21:42:06.784 [ DEBUG]:tbl-base: bat: bouquet 5005 (20485) [PREMIERE HOTEL]
2021-09-01 21:42:06.784 [ DEBUG]:tbl-base: bat: completed pid 17 table 00000048 / 000000f8
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: onid 0085 (133) tsid 000D (13)
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: mux 530MHz in Berlin
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 0072 (114) running 4 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 0116 (278) running 1 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 0118 (280) running 4 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 0115 (277) running 1 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 006F (111) running 4 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 0074 (116) running 4 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 0070 (112) running 4 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 006E (110) running 4 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 0093 (147) running 4 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: sid 006B (107) running 4 free_ca 1
2021-09-01 21:42:06.877 [ DEBUG]:tbl-base: sdt: completed pid 17 table 00000040 / 000000f8
2021-09-01 21:42:07.651 [ DEBUG]:tbl-base: bat: bouquet 5001 (20481) [BetaDigital]
2021-09-01 21:42:07.651 [ DEBUG]:tbl-base: bat: completed pid 17 table 00000048 / 000000f8
2021-09-01 21:42:07.739 [ DEBUG]:tbl-base: bat: bouquet 5003 (20483) [Sky Service Config]
2021-09-01 21:42:07.739 [ DEBUG]:tbl-base: bat: completed pid 17 table 00000048 / 000000f8
2021-09-01 21:42:07.755 [ DEBUG]:tbl-base: bat: bouquet FF00 (65280) [SSU Bouquet]
2021-09-01 21:42:07.755 [ DEBUG]:tbl-base: bat: completed pid 17 table 00000048 / 000000f8
2021-09-01 21:42:08.565 [ DEBUG]:tbl-base: sdt: onid 0085 (133) tsid 0004 (4)
2021-09-01 21:42:08.565 [ DEBUG]:tbl-base: sdt: mux 378MHz in Berlin
2021-09-01 21:42:08.566 [ DEBUG]:tbl-base: sdt: sid 0197 (407) running 4 free_ca 1
2021-09-01 21:42:08.566 [ DEBUG]:tbl-base: sdt: sid 001C (28) running 4 free_ca 1
2021-09-01 21:42:08.567 [ DEBUG]:tbl-base: sdt: sid 0195 (405) running 4 free_ca 1
2021-09-01 21:42:08.569 [ DEBUG]:tbl-base: sdt: sid 0133 (307) running 1 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0134 (308) running 1 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0136 (310) running 1 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 014F (335) running 1 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0155 (341) running 1 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0A83 (2691) running 4 free_ca 0
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0DAE (3502) running 4 free_ca 0
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0DAF (3503) running 4 free_ca 0
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0075 (117) running 4 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0151 (337) running 1 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0154 (340) running 4 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0152 (338) running 1 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0011 (17) running 4 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 000F (15) running 4 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: sid 0010 (16) running 4 free_ca 1
2021-09-01 21:42:08.570 [ DEBUG]:tbl-base: sdt: completed pid 17 table 00000040 / 000000f8
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: onid 0085 (133) tsid 0003 (3)
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: mux 362MHz in Berlin
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: sid 0016 (22) running 4 free_ca 1
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: sid 0206 (518) running 4 free_ca 1
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: sid 00C8 (200) running 4 free_ca 0
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: sid 0DAC (3500) running 4 free_ca 0
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: sid 0DAD (3501) running 4 free_ca 0
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: sid 0204 (516) running 4 free_ca 1
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: sid 002B (43) running 4 free_ca 1
2021-09-01 21:42:08.820 [ DEBUG]:tbl-base: sdt: sid 0F8C (3980) running 4 free_ca 0
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 0F9A (3994) running 4 free_ca 0
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 0DAB (3499) running 4 free_ca 0
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 013D (317) running 1 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 0147 (327) running 1 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 0140 (320) running 4 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 014A (330) running 4 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 013E (318) running 1 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 0148 (328) running 1 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 000D (13) running 4 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 000E (14) running 4 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: sid 001D (29) running 4 free_ca 1
2021-09-01 21:42:08.821 [ DEBUG]:tbl-base: sdt: completed pid 17 table 00000040 / 000000f8
2021-09-01 21:42:09.644 [ DEBUG]:descrambler: no key for service='Sky Sport Bundesliga 1 HD'
2021-09-01 21:42:10.631 [ DEBUG]:tbl-base: sdt: onid 0085 (133) tsid 0001 (1)
2021-09-01 21:42:10.631 [ DEBUG]:tbl-base: sdt: mux 386MHz in Berlin
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 0085 (133) running 4 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 007F (127) running 4 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 007E (126) running 4 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 0065 (101) running 4 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 0017 (23) running 4 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 0129 (297) running 1 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 012A (298) running 1 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 012C (300) running 4 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 0144 (324) running 1 free_ca 1
2021-09-01 21:42:10.632 [ DEBUG]:tbl-base: sdt: sid 0F03 (3843) running 4 free_ca 0
2021-09-01 21:42:10.634 [ DEBUG]:tbl-base: sdt: sid 008A (138) running 4 free_ca 1
2021-09-01 21:42:10.636 [ DEBUG]:tbl-base: sdt: sid 0008 (8) running 4 free_ca 1
2021-09-01 21:42:10.636 [ DEBUG]:tbl-base: sdt: sid 0101 (257) running 1 free_ca 1
2021-09-01 21:42:10.636 [ DEBUG]:tbl-base: sdt: sid 0104 (260) running 4 free_ca 1
2021-09-01 21:42:10.636 [ DEBUG]:tbl-base: sdt: sid 0102 (258) running 1 free_ca 1
2021-09-01 21:42:10.636 [ DEBUG]:tbl-base: sdt: sid 0013 (19) running 1 free_ca 1
2021-09-01 21:42:10.636 [ DEBUG]:tbl-base: sdt: completed pid 17 table 00000040 / 000000f8
2021-09-01 21:42:11.535 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: H264 #255 Continuity counter error (total 1) 2021-09-01 21:42:11.536 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: TELETEXT #32 Continuity counter error (total 1)
2021-09-01 21:42:11.536 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: AC3 #258 Continuity counter error (total 1) 2021-09-01 21:42:11.538 [ ERROR]:descrambler: cannot decode packets for service "Sky Sport Bundesliga 1 HD" 2021-09-01 21:42:11.698 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: DVBSUB #33 Continuity counter error (total 1)
2021-09-01 21:42:15.374 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 1 idx 0 4c8714e7b3f4dd84
2021-09-01 21:42:15.374 [ DEBUG]:descrambler: Obtained CSA keys from capmt-192.168.0.74-9000 for service "Sky Sport Bundesliga 1 HD"
2021-09-01 21:42:15.374 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=313 hops=1 reader='KD_G09' from='192.168.0.74' protocol='cccam'
2021-09-01 21:42:15.374 [ DEBUG]:capmt: dvbapiau: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=313 hops=1 reader=KD_G09 from=192.168.0.74 protocol=cccam
2021-09-01 21:42:17.702 [WARNING]:descrambler: Sky Sport Bundesliga 1 HD even stream key³² is not valid
2021-09-01 21:42:21.164 [ DEBUG]:descrambler: no key for service='Sky Sport Bundesliga 1 HD'
2021-09-01 21:42:21.468 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: H264 #255 Continuity counter error (total 4624) 2021-09-01 21:42:21.468 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: AC3 #258 Continuity counter error (total 1668)
2021-09-01 21:42:21.468 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: TELETEXT #32 Continuity counter error (total 405) 2021-09-01 21:42:21.470 [ ERROR]:descrambler: cannot decode packets for service "Sky Sport Bundesliga 1 HD" 2021-09-01 21:42:22.032 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: DVBSUB #33 Continuity counter error (total 20)
2021-09-01 21:42:25.392 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 0 idx 0 dc027c5a025ac21e
2021-09-01 21:42:25.392 [ DEBUG]:descrambler: Obtained CSA keys from capmt-192.168.0.74-9000 for service "Sky Sport Bundesliga 1 HD"
2021-09-01 21:42:25.393 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=334 hops=1 reader='KD_G09' from='192.168.0.74' protocol='cccam'
2021-09-01 21:42:25.393 [ DEBUG]:capmt: dvbapiau: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=334 hops=1 reader=KD_G09 from=192.168.0.74 protocol=cccam
2021-09-01 21:42:27.817 [WARNING]:descrambler: Sky Sport Bundesliga 1 HD odd stream key³² is not valid
2021-09-01 21:42:31.670 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: TELETEXT #32 Continuity counter error (total 773) 2021-09-01 21:42:31.670 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: H264 #255 Continuity counter error (total 8812)
2021-09-01 21:42:31.670 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: AC3 #258 Continuity counter error (total 3164) 2021-09-01 21:42:31.674 [ ERROR]:descrambler: cannot decode packets for service "Sky Sport Bundesliga 1 HD" 2021-09-01 21:42:32.498 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: DVBSUB #33 Continuity counter error (total 37)
2021-09-01 21:42:35.369 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 1 idx 0 6c45d7881d0ee00b
2021-09-01 21:42:35.369 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=293 hops=1 reader='KD_G09' from='192.168.0.74' protocol='cccam'
2021-09-01 21:42:35.369 [ DEBUG]:capmt: dvbapiau: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=293 hops=1 reader=KD_G09 from=192.168.0.74 protocol=cccam
2021-09-01 21:42:39.714 [ DEBUG]:descrambler: no key for service='Sky Sport Bundesliga 1 HD'
2021-09-01 21:42:41.739 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: H264 #255 Continuity counter error (total 12151) 2021-09-01 21:42:41.740 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: AC3 #258 Continuity counter error (total 4380)
2021-09-01 21:42:41.740 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: TELETEXT #32 Continuity counter error (total 1079) 2021-09-01 21:42:41.747 [ ERROR]:descrambler: cannot decode packets for service "Sky Sport Bundesliga 1 HD" 2021-09-01 21:42:42.920 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: DVBSUB #33 Continuity counter error (total 51)
2021-09-01 21:42:45.583 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 0 idx 0 31dd1927d168ce07
2021-09-01 21:42:45.583 [ DEBUG]:descrambler: Obtained CSA keys from capmt-192.168.0.74-9000 for service "Sky Sport Bundesliga 1 HD"
2021-09-01 21:42:45.588 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=486 hops=1 reader='KD_G09' from='192.168.0.74' protocol='cccam'
2021-09-01 21:42:45.588 [ DEBUG]:capmt: dvbapiau: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=486 hops=1 reader=KD_G09 from=192.168.0.74 protocol=cccam
2021-09-01 21:42:51.827 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: TELETEXT #32 Continuity counter error (total 1461) 2021-09-01 21:42:51.827 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: H264 #255 Continuity counter error (total 16470)
2021-09-01 21:42:51.827 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: AC3 #258 Continuity counter error (total 5913) 2021-09-01 21:42:51.835 [ ERROR]:descrambler: cannot decode packets for service "Sky Sport Bundesliga 1 HD" 2021-09-01 21:42:53.088 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: DVBSUB #33 Continuity counter error (total 68)
2021-09-01 21:42:55.585 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 1 idx 0 92f8a9334dc16f7d
2021-09-01 21:42:55.593 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=483 hops=1 reader='KD_G09' from='192.168.0.74' protocol='cccam'
2021-09-01 21:42:55.593 [ DEBUG]:capmt: dvbapiau: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=483 hops=1 reader=KD_G09 from=192.168.0.74 protocol=cccam
2021-09-01 21:42:59.840 [ DEBUG]:descrambler: no key for service='Sky Sport Bundesliga 1 HD'
2021-09-01 21:43:02.426 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: H264 #255 Continuity counter error (total 20031) 2021-09-01 21:43:02.426 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: AC3 #258 Continuity counter error (total 7209)
2021-09-01 21:43:02.426 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: TELETEXT #32 Continuity counter error (total 1779) 2021-09-01 21:43:02.437 [ ERROR]:descrambler: cannot decode packets for service "Sky Sport Bundesliga 1 HD" 2021-09-01 21:43:03.744 [WARNING]:TS: Berlin/554MHz/Sky Sport Bundesliga 1 HD: DVBSUB #33 Continuity counter error (total 83)
2021-09-01 21:43:05.401 [ DEBUG]:capmt: dvbapiau: CA_SET_DESCR adapter 0 par 0 idx 0 622e2dbd103c519d
2021-09-01 21:43:05.401 [ DEBUG]:descrambler: Obtained CSA keys from capmt-192.168.0.74-9000 for service "Sky Sport Bundesliga 1 HD"
2021-09-01 21:43:05.403 [ DEBUG]:descrambler: info - service='Sky Sport Bundesliga 1 HD' caid=09C7(videoguard) provid=000000 ecmtime=276 hops=1 reader='KD_G09' from='192.168.0.74' protocol='cccam'
2021-09-01 21:43:05.403 [ DEBUG]:capmt: dvbapiau: ECM_INFO: adapter=0 sid=105 caid=09C7(videoguard) pid=1D0D provid=000000 ecmtime=276 hops=1 reader=KD_G09 from=192.168.0.74 protocol=cccam
2021-09-01 21:43:06.329 [ INFO]:subscription: 00F9: "HTTP" unsubscribing from "Sky Sport Bundesliga 1 HD", hostname="192.168.0.71", client="VLC/3.0.16 LibVLC/3.0.16"
2021-09-01 21:43:06.330 [ DEBUG]:capmt: dvbapiau: Removing CAPMT Server from service "Sky Sport Bundesliga 1 HD" on adapter 0
2021-09-01 21:43:06.330 [ DEBUG]:capmt: dvbapiau: capmt_enumerate_services: no subscribed services, closing socket, fd=25
2021-09-01 21:43:06.340 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index -1 pid 32 (0x0020)
2021-09-01 21:43:06.341 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index -1 pid 33 (0x0021)
2021-09-01 21:43:06.341 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index -1 pid 255 (0x00ff)
2021-09-01 21:43:06.341 [ DEBUG]:capmt: dvbapiau: CA_SET_PID adapter 0 index -1 pid 258 (0x0102)

Updated by Flole Systems over 3 years ago

You have continuity errors. Either bad signal or bad drivers are causing that. Also your ECMs arrive very late, you need to reduce the latency there.

Updated by Anonymous over 3 years ago

The continuity errors comes if the cw Key comes to late. Easy to reproduce.

I compile the 4.2 release from the git and there is all fine with Sky Bundesliga 1 HD and Sky Sport F1 HD und Sky Sport UHD.

Greetings

StarSurfer

Updated by Ralf Müller over 3 years ago

I have the same problem! The cables are all good.
It can't be due to the Friz box tuner either. I have tested a tuner from Muton 51HD Receiver and it is also "Continuity counter error".

best regards

Updated by Anonymous over 3 years ago

Unfortunately, I have to correct myself.
With the 4.2.8 I have the problem, too, Sky has to do something in the cable network and it only affects the sports channels.
Every now and then it runs and the stations swap with it.

Updated by j k over 3 years ago

I can confirm this issue with latest git version running on archlinux.
Hardware: Hauppauge WinTV
Channel: Sky Sport F1 HD
Local Card 09C7 in local Oscam
dvbapi nor newcamd nor cccam work properly anymore :/

Updated by Ralf Müller over 3 years ago

More and more people are reporting the same problem.
It was also reported that it doesn't futon with a USB tuner.

https://www.digital-eliteboard.com/threads/tvheadend-probleme-mit-entschluesselung-nur-bei-sky-bundesliga-und-sky-sport.503116/

Updated by Daniel M. over 3 years ago

I can confirm this issue too on two different locations. It's definitely not a tuner or bad signal problem. It always affects the same channels and it seems that there is a descrambling bug/problem with tvheadend only. There is no problem with other receiver hardware or software. And even tvheadend works again as soon as you use something like minisatip for decoding and tvheadend has nothing to do with the descrambling.

#10

Updated by Thomas xyz over 3 years ago

I can conform the issue too, on LibreElec with TVH. With my VU+ i don't have the issue on the same cabel. I can't be a tuner problem because all other Chennels running fine, only Sky Sport, Sky Bundesliga and Sky F1 make problems

#11

Updated by Lorem Ipsum over 3 years ago

File tvh_key.log added

I have the same problem. Sky Sport, Sky Bundesliga and Sky F1 have these problems, but the funny thing is these channels sometimes work without any problem, but most times not!

I tried to investigate the problem in the descrambler log (see appended file). The first few seconds everything seems to work normally, keys are chaning from even to odd, but then suddenly the message "no key for service" appears and things are getting messed up. From then on only odd keys are logged:

2021-09-11 14:11:19.537 [  TRACE]:descrambler: ECM: Even key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:19.537 [  TRACE]:descrambler: ECM: Odd key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:19.739 [  TRACE]:descrambler: ECM: Even key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:19.739 [  TRACE]:descrambler: ECM: Odd key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:19.961 [  TRACE]:descrambler: stream key[0] changed to even for service "Sky Sport 1 HD" 
2021-09-11 14:11:19.961 [  DEBUG]:descrambler: no key for service='Sky Sport 1 HD'
2021-09-11 14:11:19.961 [  TRACE]:descrambler: capmt-127.0.0.1-9000: key state changed from RESOLVED to READY for "Fritzbox/522MHz/Sky Sport 1 HD" 
2021-09-11 14:11:19.961 [  TRACE]:descrambler: service "Fritzbox/522MHz/Sky Sport 1 HD": 1 descramblers (0 ok 0 failed 0 fatal)
2021-09-11 14:11:19.961 [  TRACE]:descrambler: flush table data for service "Sky Sport 1 HD" 
2021-09-11 14:11:19.961 [  TRACE]:descrambler: stream key[0] changed to even for service "Sky Sport 1 HD" 
2021-09-11 14:11:19.961 [  TRACE]:descrambler: update key[0] interval for "Fritzbox/522MHz/Sky Sport 1 HD" to 5000ms
2021-09-11 14:11:20.138 [  TRACE]:descrambler: ECM message 80:70 (section 0, len 166, pid 7610) for service "Sky Sport 1 HD" 
2021-09-11 14:11:20.341 [  TRACE]:descrambler: ECM: Odd key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:20.542 [  TRACE]:descrambler: ECM: Odd key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:20.739 [  TRACE]:descrambler: ECM: Odd key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:20.936 [  TRACE]:descrambler: ECM: Odd key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:21.130 [  TRACE]:descrambler: ECM: Odd key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:21.540 [  TRACE]:descrambler: ECM: Odd key[0] for service "Sky Sport 1 HD" still valid
2021-09-11 14:11:21.540 [WARNING]:TS: Fritzbox/522MHz/Sky Sport 1 HD: H264 @ #767 Continuity counter error (total 1)
2021-09-11 14:11:21.540 [WARNING]:TS: Fritzbox/522MHz/Sky Sport 1 HD: AC3 @ #770 Continuity counter error (total 1)
2021-09-11 14:11:21.540 [WARNING]:TS: Fritzbox/522MHz/Sky Sport 1 HD: AC3 @ #772 Continuity counter error (total 1)
2021-09-11 14:11:21.540 [WARNING]:TS: Fritzbox/522MHz/Sky Sport 1 HD: TELETEXT @ #32 Continuity counter error (total 1)

#12

Updated by Flole Systems over 3 years ago

File deleted (~~tvh_key.log~~)

#13

Updated by rado 84 over 3 years ago

I'm actually trying to fix that bug but might need some technical feedback from someone who is familiar with the descrambler code. Here's a representative log comparing the Sky descrambling to another channel.

The following things I find strange:
1. The "working" Channel has a regular pattern of. 1. toggling between EVEN + ODD and receiving one ECM Message (with two keys) in between.
2. The Sky descrambling is all over the place. Toggling between EVEN/ODD multiple times between even receiving an new ECM message.
3. The "no key" often comes right before a new ECM message. But I don't believe it's a timing problem, but rather key_late condition being wrongly applied to Sky.

I can actually FIX the descrambling issues when ignoring the if condition: (key_late(dr, tk, ki, dd->dd_timestamp)) to (0 && key_late(dr, tk, ki, dd->dd_timestamp)). So that would be a quickfix for some people here. I still need to understand the function better to perform a correct fix.

2021-09-16 21:47:34.463 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 7437) section=0/0 for service "Sky Sport Bundesliga 1 HD" (seqno: 2)
2021-09-16 21:47:34.464 [  TRACE]:descrambler: ECM message 81:70 (section 0, len 166, pid 7437) for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:34.660 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 7437) for service "Sky Sport Bundesliga 1 HD" [0] (seqno: 2 Req delay: 195 ms)
2021-09-16 21:47:34.660 [  DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:34.679 [  TRACE]:descrambler: stream key[0] changed to odd for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:34.679 [  TRACE]:descrambler: stream key[0] changed to even for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:36.634 [  TRACE]:descrambler: stream key[0] changed to odd for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:38.685 [  TRACE]:descrambler: stream key[0] changed to even for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:40.673 [  TRACE]:descrambler: stream key[0] changed to odd for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:40.673 [  DEBUG]:descrambler: no key for service='Sky Sport Bundesliga 1 HD'
2021-09-16 21:47:40.703 [  TRACE]:descrambler: stream key[0] changed to odd for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:40.859 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 7437) section=0/0 for service "Sky Sport Bundesliga 1 HD" (seqno: 3)
2021-09-16 21:47:40.859 [  TRACE]:descrambler: ECM message 81:70 (section 0, len 166, pid 7437) for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:40.970 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 7437) for service "Sky Sport Bundesliga 1 HD" [0] (seqno: 3 Req delay: 111 ms)
2021-09-16 21:47:40.970 [  DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:41.075 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 7437) section=0/0 for service "Sky Sport Bundesliga 1 HD" (seqno: 4)
2021-09-16 21:47:41.075 [  TRACE]:descrambler: ECM message 80:70 (section 0, len 166, pid 7437) for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:41.275 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 7437) for service "Sky Sport Bundesliga 1 HD" [0] (seqno: 4 Req delay: 199 ms)
2021-09-16 21:47:41.275 [  DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:42.970 [  TRACE]:descrambler: stream key[0] changed to even for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:44.657 [  TRACE]:descrambler: stream key[0] changed to odd for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:46.674 [  TRACE]:descrambler: stream key[0] changed to even for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:46.674 [  DEBUG]:descrambler: no key for service='Sky Sport Bundesliga 1 HD'
2021-09-16 21:47:46.674 [  TRACE]:descrambler: stream key[0] changed to even for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:46.860 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 7437) section=0/0 for service "Sky Sport Bundesliga 1 HD" (seqno: 5)
2021-09-16 21:47:46.860 [  TRACE]:descrambler: ECM message 80:70 (section 0, len 166, pid 7437) for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:46.971 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 7437) for service "Sky Sport Bundesliga 1 HD" [0] (seqno: 5 Req delay: 111 ms)
2021-09-16 21:47:46.971 [  DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:49.444 [  TRACE]:descrambler: stream key[0] changed to odd for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:50.681 [  TRACE]:descrambler: stream key[0] changed to even for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:51.054 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 7437) section=0/0 for service "Sky Sport Bundesliga 1 HD" (seqno: 6)
2021-09-16 21:47:51.054 [  TRACE]:descrambler: ECM message 81:70 (section 0, len 166, pid 7437) for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:51.260 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 7437) for service "Sky Sport Bundesliga 1 HD" [0] (seqno: 6 Req delay: 203 ms)
2021-09-16 21:47:51.260 [  DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:52.678 [  TRACE]:descrambler: stream key[0] changed to odd for service "Sky Sport Bundesliga 1 HD" 
2021-09-16 21:47:56.647 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 1739) section=0/0 for service "DELUXE MUSIC HD" (seqno: 7)
2021-09-16 21:47:56.647 [  TRACE]:descrambler: ECM message 81:70 (section 0, len 109, pid 1739) for service "DELUXE MUSIC HD" 
2021-09-16 21:47:56.838 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 1739) for service "DELUXE MUSIC HD" [0] (seqno: 7 Req delay: 191 ms)
2021-09-16 21:47:56.838 [  DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "DELUXE MUSIC HD" 
2021-09-16 21:48:01.836 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 1739) section=0/0 for service "DELUXE MUSIC HD" (seqno: 9)
2021-09-16 21:48:01.836 [  TRACE]:descrambler: ECM message 80:70 (section 0, len 109, pid 1739) for service "DELUXE MUSIC HD" 
2021-09-16 21:48:02.021 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 1739) for service "DELUXE MUSIC HD" [0] (seqno: 9 Req delay: 183 ms)
2021-09-16 21:48:02.021 [  DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "DELUXE MUSIC HD" 
2021-09-16 21:48:02.336 [  TRACE]:descrambler: stream key[0] changed to even for service "DELUXE MUSIC HD" 
2021-09-16 21:48:11.837 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 1739) section=0/0 for service "DELUXE MUSIC HD" (seqno: 10)
2021-09-16 21:48:11.837 [  TRACE]:descrambler: ECM message 81:70 (section 0, len 109, pid 1739) for service "DELUXE MUSIC HD" 
2021-09-16 21:48:12.025 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 1739) for service "DELUXE MUSIC HD" [0] (seqno: 10 Req delay: 187 ms)
2021-09-16 21:48:12.025 [  DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "DELUXE MUSIC HD" 
2021-09-16 21:48:12.282 [  TRACE]:descrambler: stream key[0] changed to odd for service "DELUXE MUSIC HD" 
2021-09-16 21:48:21.841 [  DEBUG]:cwc: 192.168.184.15:15000: Sending ECM (PID 1739) section=0/0 for service "DELUXE MUSIC HD" (seqno: 11)
2021-09-16 21:48:21.841 [  TRACE]:descrambler: ECM message 80:70 (section 0, len 109, pid 1739) for service "DELUXE MUSIC HD" 
2021-09-16 21:48:22.029 [  DEBUG]:cwc: 192.168.184.15:15000: Received ECM reply (PID 1739) for service "DELUXE MUSIC HD" [0] (seqno: 11 Req delay: 187 ms)
2021-09-16 21:48:22.029 [  DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "DELUXE MUSIC HD" 
2021-09-16 21:48:22.317 [  TRACE]:descrambler: stream key[0] changed to even for service "DELUXE MUSIC HD"

#14

Updated by rado 84 over 3 years ago

Sorry ignore my previous fix suggestion, I must have tested in the one minute where it was actually working. If I comment the ecm_reset part or if condition I do actually get descrambling errors, proving that the key is in fact late.

#15

Updated by rado 84 over 3 years ago

Ok, I think I found the root cause for this. The problem seems to be that the even/odd transition of the transport_scrambling_control are not synchronized between all PIDs. Specifically, the TELETEXT PID (32) is constantly lacking behind. This causes ODD/EVEN packages to come in concurrently to the descrambler, messing up the key transition (which works on all PIDs concurrently).

PID: 2047 (0x07ff)  [= ]
transport_scrambling_control: 2 (0x02)  [= TS packet scrambled with Even Key]
PID: 32 (0x0020)  [= ]
transport_scrambling_control: 3 (0x03)  [= TS packet scrambled with Odd Key]
PID: 2047 (0x07ff)  [= ]
transport_scrambling_control: 2 (0x02)  [= TS packet scrambled with Even Key]

I feel what Sky is doing might not be in accordance to the mpegts specification. TVHeadend could only fix this edge case in two ways:

1. apply key-transition logic to each PID and not to the overall stream.
2. "dumb" down the key-transition logic of TVH to model key timing logic, but just blindly access the current odd/even key, independent of previous transitions.

I think a temp fix for this is to configure a Stream filter for the offending PID (32) or just all TELETEXTs. Who needs those anyway.

#16

Updated by Thomas xyz over 3 years ago

Can you explain how can i test your fix?

#17

Updated by Thomas xyz over 3 years ago

File TVHStreamFilter.png TVHStreamFilter.png added

I have tryed the fix and make a Stream Filter like this in the screen, with this it runs fine more then ten minutes

#18

Updated by Flole Systems over 3 years ago

Why did you create a Video filter when you want to filter Teletext?

#19

Updated by Thomas xyz over 3 years ago

File TVHTeletextFilter.png TVHTeletextFilter.png added

Sorry my fault, this is the right image

#20

Updated by Markus Bonet over 3 years ago

I did both modifications:

- added TELETEXT filter (IGNORE)
- adjusted if statement in descrambler.c (1975 and re-compiled of course)

But still the problem occurs from time to time.

#21

Updated by sharky :-) over 3 years ago

Does this issue only occur on Bundesliga 1 HD ? I do have a problem with this channel since a lot of month , but I thought my client is doing something wrong, because . I don't have glitches with other clients. Could test if anyone is able to create a patch for this.

#22

Updated by Markus Bonet about 3 years ago

sharky :-) wrote:

Does this issue only occur on Bundesliga 1 HD ?

No, this happens on various Sports channels, both on DVB-C and DVB-S.
What I've observed: This happens on Saturdays, when multiple matches are aired live.
There may be different reasons for it. Either it's on purpose by Sky or it's due to the fact that on other days the other channels are barely used.
I don't think it's really on purpose, because then why restrict it to the Sports channels only? I didn't observe this with cinema channels.
So maybe there is some misconfiguration when the transponders start to fill up.

As mentioned, I did both modifications and the problem is unresolved.

#23

Updated by Ernie Bert about 3 years ago

Try to use also "Subtitle Stream Filter". Works for me ...

#24

Updated by Trex the Daemon about 2 years ago

I am facing the same issue with all SKY DE channels. I've created a teletext stream filter and also a Subtitle (teletext) filter.
But I am still getting invalid start code errors and the stream won't start.

#25

Updated by Trex the Daemon about 2 years ago

I forgot to mention, I've built the latest tvheadend binary from source from the repo...

Also available in: Atom PDF

Project

General

Profile

Tvheadend

Issues

Custom queries

Bug #6083

Descrambling problems with OSCam-DVB-Api and Newcamd on some Sky Germany sports channels via DVB-C

History

Updated by Ernie Bert over 3 years ago

Updated by Anonymous over 3 years ago

Same Problem here.¶

OSCam Log Debug 128¶

TVHeadend Debug Log¶

Updated by Flole Systems over 3 years ago

Updated by Anonymous over 3 years ago

Updated by Ralf Müller over 3 years ago

Updated by Anonymous over 3 years ago

Updated by j k over 3 years ago

Updated by Ralf Müller over 3 years ago

Updated by Daniel M. over 3 years ago

Updated by Thomas xyz over 3 years ago

Updated by Lorem Ipsum over 3 years ago

Updated by Flole Systems over 3 years ago

Updated by rado 84 over 3 years ago

Updated by rado 84 over 3 years ago

Updated by rado 84 over 3 years ago

Updated by Thomas xyz over 3 years ago

Updated by Thomas xyz over 3 years ago

Updated by Flole Systems over 3 years ago

Updated by Thomas xyz over 3 years ago

Updated by Markus Bonet over 3 years ago

Updated by sharky :-) over 3 years ago

Updated by Markus Bonet about 3 years ago

Updated by Ernie Bert about 3 years ago

Updated by Trex the Daemon about 2 years ago

Updated by Trex the Daemon about 2 years ago