Bug #3772
Descrambler buffer is not working properly
0%
Description
i use 60000ms even 100000ms delay but always stops for each ecm
2016-05-03 10:56:27.354 descrambler: ECM - key late (25057 ms) for service "HBO-W"
2016-05-03 10:56:27.635 descrambler: ECM - key late (26723 ms) for service "WSJX"
2016-05-03 10:56:27.689 descrambler: ECM - key late (26323 ms) for service "WORA2"
2016-05-03 10:56:28.282 descrambler: ECM - key late (27123 ms) for service "WAPA"
2016-05-03 10:56:28.866 cwc: Got unexpected ECM reply (seqno: 497)
2016-05-03 10:56:29.010 cwc: Got unexpected ECM reply (seqno: 509)
2016-05-03 10:56:29.010 cwc: Got unexpected ECM reply (seqno: 500)
2016-05-03 10:56:29.010 cwc: Got unexpected ECM reply (seqno: 513)
2016-05-03 10:56:29.134 descrambler: ECM - key late (27425 ms) for service "WLII"
2016-05-03 10:56:29.878 cwc: Got unexpected ECM reply (seqno: 501)
2016-05-03 10:56:30.016 cwc: Got unexpected ECM reply (seqno: 502)
2016-05-03 10:56:30.016 cwc: Got unexpected ECM reply (seqno: 517)
2016-05-03 10:56:30.953 cwc: Got unexpected ECM reply (seqno: 503)
2016-05-03 10:56:30.953 cwc: Got unexpected ECM reply (seqno: 519)
2016-05-03 10:56:31.304 cwc: Got unexpected ECM reply (seqno: 515)
2016-05-03 10:56:41.923 descrambler: ECM - key late (29355 ms) for service "WSJP"
History
Updated by Jaroslav Kysela over 8 years ago
Don't use the delay. It makes things worse. It's for a special purpose.
Updated by Jaroslav Kysela over 8 years ago
I understood that the delay is ECM delay on the card server. ECM key must arrive in the time before next ECM key is requested. The increased buffer size helps only for streams with bigger bandwidth.
Updated by Jaroslav Kysela over 8 years ago
Provide '--trace descrambler,cwc' . https://tvheadend.org/projects/tvheadend/wiki/Traces
Updated by Un Known over 8 years ago
tvheadend log:Jul 01 01:35:15 pvr tvheadend[1973]: service: esfilter: "Kabel Deutschland/130MHz/13th Street HD" CA 006 001 06839 09c7 ffffffff EXCLUSIVE
#6831 Continuity counter error (total 1)
Jul 01 01:35:15 pvr tvheadend[1973]: mpegts: 130MHz in Kabel Deutschland - tuning on STV0367 DVB-C DVB-T : DVB-C #0
Jul 01 01:35:15 pvr tvheadend[1973]: capmt: oc: Starting CAPMT server for service "13th Street HD" on adapter 1
Jul 01 01:35:15 pvr tvheadend[1973]: subscription: 0036: "::ffff:192.168.100.90 [ bigbang | Kodi Media Center ]" subscribing on channel "13th Street HD", weight: 150, adapter: "STV0367 DVB-C DVB-T : DVB-C #0", network: "Kabel Deutschland", mux: "130MHz", provider: "KD Home", service: "13th Street HD", profile="htsp", hostname="::ffff:192.168.100.90", username="bigbang", client="Kodi Media Center"
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: mux 0x55beb7ee6ee0 open pid 1AB7 (6839) (flags 0x0100) for 0x7f88300832e8
Jul 01 01:35:16 pvr tvheadend[1973]: service: esfilter: "Kabel Deutschland/130MHz/13th Street HD" CA 006 001 06839 09c7 ffffffff EXCLUSIVE
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: CAT data (len 18)
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: 09 04 09 C7 F5 09 09 04 18 61 F5 07 09 04 18 34 .........a.....4
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: F5 05 ..
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: ECM message 81 (section 0, len 96, pid 6839) for service "13th Street HD"
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: initial stream key set to odd for service "13th Street HD"
Jul 01 01:35:16 pvr tvheadend[1973]: service: esfilter: "Kabel Deutschland/130MHz/13th Street HD" CA 006 001 06839 09c7 ffffffff EXCLUSIVE
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: Obtained keys from capmt-127.0.0.1-9000 for service "13th Street HD"
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: Obtained keys C88D89DE0715B0CC:0000000000000000 from capmt-127.0.0.1-9000 for service "13th Street HD"
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: Obtained keys 0000000000000000:C9FE01C8F050DC1C from capmt-127.0.0.1-9000 for service "13th Street HD"
Jul 01 01:35:16 pvr tvheadend[1973]: descrambler: info - service='13th Street HD' caid=09C7(videoguard) provid=000000 ecmtime=223 hops=0 reader='KD_G09' from='local' protocol='smartreader'
Jul 01 01:35:19 pvr tvheadend[1973]: mpegts: 130MHz in Kabel Deutschland scan complete
Jul 01 01:35:21 pvr tvheadend[1973]: descrambler: mux 0x55beb7ee6ee0 open pid 0011 (17) (flags 0x0000) for 0x7f88300832d0
Jul 01 01:35:21 pvr tvheadend[1973]: descrambler: EMM message 4a:f0:5d:ff (len 96, pid 17)
Jul 01 01:35:23 pvr tvheadend[1973]: descrambler: EMM message 42:f0:d4:27 (len 215, pid 17)
Jul 01 01:35:23 pvr tvheadend[1973]: descrambler: mux 0x55beb7ee6ee0 close pid 0011 (17) (flags 0x0000) for 0x7f88300832d0
Jul 01 01:35:24 pvr tvheadend[1973]: descrambler: ECM message 80 (section 0, len 96, pid 6839) for service "13th Street HD"
Jul 01 01:35:25 pvr tvheadend[1973]: descrambler: stream key changed to even for service "13th Street HD"
Jul 01 01:35:34 pvr tvheadend[1973]: descrambler: ECM message 81 (section 0, len 96, pid 6839) for service "13th Street HD"
Jul 01 01:35:35 pvr tvheadend[1973]: descrambler: stream key changed to odd for service "13th Street HD"
Jul 01 01:35:35 pvr tvheadend[1973]: descrambler: ECM - key late (18905 ms) for service "13th Street HD"
Jul 01 01:35:35 pvr tvheadend[1973]: descrambler: no key for service='13th Street HD'
Jul 01 01:35:35 pvr tvheadend[1973]: descrambler: stream key changed to odd for service "13th Street HD"
Jul 01 01:35:35 pvr tvheadend[1973]: descrambler: flush table data for service "13th Street HD"
Jul 01 01:35:35 pvr tvheadend[1973]: descrambler: ECM message 81 (section 0, len 96, pid 6839) for service "13th Street HD"
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: mux 0x55beb7ee6ee0 open pid 0001 (1) (flags 0x0000) for 0x7f88300832d0
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: EMM message 01:b0:1b:ff (len 30, pid 1)
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: mux 0x55beb7ee6ee0 close pid 0001 (1) (flags 0x0000) for 0x7f88300832d0
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: mux 0x55beb7ee6ee0 open pid 1509 (5385) (flags 0x0000) for 0x7f88300832d0
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: EMM message 82:70:3e:40 (len 65, pid 5385)
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: EMM message 82:70:2a:40 (len 45, pid 5385)
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ SNIP Lines 302 ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: EMM message 82:70:2e:40 (len 49, pid 5385)
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: EMM message 82:70:3c:40 (len 63, pid 5385)
Jul 01 01:35:36 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend[1973]: TS: Kabel Deutschland/130MHz/13th Street HD: H264
Jul 01 01:35:37 pvr tvheadend1973: TS: Kabel Deutschland/130MHz/13th Street HD: AC3 #6832 Continuity counter error (total 1)
#6833 Continuity counter error (total 1)
Jul 01 01:35:37 pvr tvheadend[1973]: TS: Kabel Deutschland/130MHz/13th Street HD: AC3
Jul 01 01:35:37 pvr tvheadend1973: descrambler: cannot decode packets for service "13th Street HD"
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:2a:40 (len 45, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ SNIP ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: cannot decode packets for service "13th Street HD"
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ SNIP ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:2a:40 (len 45, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:2a:40 (len 45, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: cannot decode packets for service "13th Street HD"
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:2a:40 (len 45, pid 5385)
Jul 01 01:35:37 pvr tvheadend1973: descrambler: EMM message 82:70:2a:40 (len 45, pid 5385)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ SNIP ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: cannot decode packets for service "13th Street HD"
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:2a:40 (len 45, pid 5385)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ SNIP 156 Lines ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: cannot decode packets for service "13th Street HD"
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:46 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ SNIP 78 Lines ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Jul 01 01:35:47 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend1973: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend1973: TS: Kabel Deutschland/130MHz/13th Street HD: H264 #6831 Continuity counter error (total 31)
#6832 Continuity counter error (total 19)
Jul 01 01:35:47 pvr tvheadend[1973]: TS: Kabel Deutschland/130MHz/13th Street HD: AC3
Jul 01 01:35:47 pvr tvheadend1973: TS: Kabel Deutschland/130MHz/13th Street HD: AC3 #6833 Continuity counter error (total 19)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: cannot decode packets for service "13th Street HD"
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:3c:40 (len 63, pid 5385)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ SNIP 136 Lines ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: cannot decode packets for service "13th Street HD"
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ SNIP ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: descrambler: EMM message 82:70:38:40 (len 59, pid 5385)
Jul 01 01:35:47 pvr tvheadend[1973]: subscription: 0036: "::ffff:192.168.100.90 [ bigbang | Kodi Media Center ]" unsubscribing from "13th Street HD", hostname="::ffff:192.168.100.90", username="bigbang", client="Kodi Media Center"
Jul 01 01:35:47 pvr tvheadend[1973]: capmt: oc: Removing CAPMT Server from service "13th Street HD" on adapter 1
oscam log:Jul 01 01:35:15 pvr oscam[326]: 42097F68 c (dvbapi) Demuxer 0 ecmpid 0 CAID: 09C7 ECM_PID: 1AB7 PROVID: 000000
Jul 01 01:35:15 pvr oscam[326]: 42097F68 c (dvbapi) Demuxer 0 found 1 ECMpids and 1 STREAMpids in caPMT
Jul 01 01:35:15 pvr oscam[326]: 42097F68 c (dvbapi) Demuxer 0 found channel in cache and matching prio -> start descrambling ecmpid 0
Jul 01 01:35:15 pvr oscam[326]: 42097F68 c (dvbapi) Demuxer 0 trying to descramble PID 0 CAID 09C7 PROVID 000000 ECMPID 1AB7 ANY CHID PMTPID 0000 VPID 1AAF
Jul 01 01:35:16 pvr oscam[326]: 42097F68 c (ecm) local_dvbapi (09C7@000000/0026/C354/60:402D3B0A8934EDACD9CC679E560CBB5A:0F06000000000000:KD Premium Serien HD): found (223 ms) by KD_G09 - 13th Street HD
Jul 01 01:35:24 pvr oscam[326]: 42097F68 c (ecm) local_dvbapi (09C7@000000/0026/C354/60:009309ED85F2D4430E84416D22C0F301:0F06000000000000:KD Premium Serien HD): found (228 ms) by KD_G09 - 13th Street HD
Jul 01 01:35:34 pvr oscam[326]: 42097F68 c (ecm) local_dvbapi (09C7@000000/0026/C354/60:E5B547F1C7A005566DD0D8112D5C784A:0F06000000000000:KD Premium Serien HD): found (225 ms) by KD_G09 - 13th Street HD
Jul 01 01:35:44 pvr oscam[326]: 42097F68 c (ecm) local_dvbapi (09C7@000000/0026/C354/60:9ECF4DF748679357C2D127D9C973A55B:0F06000000000000:KD Premium Serien HD): found (224 ms) by KD_G09 - 13th Street HD
Jul 01 01:35:45 pvr oscam[326]: 1D9106C3 r (reader) KD_G09 [videoguard2] local_dvbapi emmtype=global, len=155 (hex: 0x9B), cnt=1: written (164 ms)
Jul 01 01:35:45 pvr oscam[326]: 1D9106C3 r (reader) KD_G09 [videoguard2] local_dvbapi emmtype=global, len=36 (hex: 0x24), cnt=1: written (35 ms)
Jul 01 01:35:47 pvr oscam[326]: 42097F68 c (dvbapi) Demuxer 0 stop descrambling program number C354 (13th Street HD)
Updated by Jaroslav Kysela about 7 years ago
I'm closing this. It's outdated report without response from the reporter.