Project

General

Profile

Bug #5596

To many muxes ignored or failed

Added by Thomas xyz 2 months ago. Updated 2 months ago.

Status:
New
Priority:
Normal
Assignee:
-
Category:
-
Target version:
-
Start date:
2019-04-10
Due date:
% Done:

0%

Estimated time:
Found in version:
4.3-1733~g7d3aa1194
Affected Versions:

Description

Hi,
because of this Ticket https://tvheadend.org/issues/5595 , i make this new.

I have the problem that a lot of services can not mapped.

From 990 Muxes were only 258 Services found, i could be a newcamed and oscam Problem, my oscam Server works fine with Oscam Clients, but not with TVH newcamd.

The Oscam Log shows me this:

2019/04/10 20:13:53 00000000 s     (main) System name    = Linux
2019/04/10 20:13:53 00000000 s     (main) Host name      = cubietruck
2019/04/10 20:13:53 00000000 s     (main) Release        = 4.20.7-sunxi
2019/04/10 20:13:53 00000000 s     (main) Version        = #5.75 SMP Fri Feb 8 09:02:10 CET 2019
2019/04/10 20:13:53 00000000 s     (main) Machine        = armv7l
2019/04/10 20:13:53 00000000 s     (main) creating pidfile /tmp/.oscam/oscam.pid with pid 12969
2019/04/10 20:13:53 00000000 s   (config) userdb reloaded: 4 accounts loaded, 0 expired, 0 disabled
2019/04/10 20:13:53 00000000 s     (main) signal handling initialized
2019/04/10 20:13:53 00000000 s      (net) cs357x: initialized (fd=4, port=10000, prio=1 [ SO_PRIORITY IP_TOS ])
2019/04/10 20:13:53 00000000 s      (net) newcamd: initialized (fd=5, port=24241, prio=1 [ SO_PRIORITY IP_TOS ])
2019/04/10 20:13:53 00000000 s      (net) -> CAID: 1834 PROVID: 1F1F1F
2019/04/10 20:13:53 00000000 s   (reader) KD_HD_D02_Sky [mouse] creating thread for device /dev/Easymouse2
2019/04/10 20:13:53 00000000 s     (main) waiting for local card init
2019/04/10 20:13:53 401E10E1 h    (webif) webif: decompressed 180016 bytes back into 444204 bytes
2019/04/10 20:13:53 401E10E1 h    (webif) HTTP Server running. ip=0.0.0.0 port=2000
2019/04/10 20:13:53 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] Reader initialized (device=/dev/Easymouse2, detect=cd, mhz=600, cardmhz=600)
2019/04/10 20:13:54 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] card detected
2019/04/10 20:13:55 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] ATR: 3B 9F 21 0E 49 52 44 45 54 4F 20 41 43 53 03 84 55 FF 80 6D
2019/04/10 20:13:57 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] detect irdeto tunneled nagra card
2019/04/10 20:13:57 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] no rsa key configured -> using irdeto mode
2019/04/10 20:13:57 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] detect irdeto card
2019/04/10 20:13:57 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] caid: 1722, acs: 3.84, country code: TEL
2019/04/10 20:13:57 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] providers: 4, ascii serial: ##########, hex serial: ######, hex base: ##
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] active providers: 2 (000000,000000)
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [mouse] found card system irdeto
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] THIS WAS A SUCCESSFUL START ATTEMPT No  1 out of max alloted of 1
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] entitlements for provider: 2, id: 000000
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 05E2, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 05E6, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 010A, date: 2010/06/20 - 2011/03/01
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 00F0, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 0064, date: 2013/09/11 - 2014/05/23
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 0147, date: 2013/09/11 - 2014/05/23
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 7D27, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 7D23, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 04CE, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 05E1, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 0608, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 05E0, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 0607, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 000F, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 05E4, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 05E5, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] chid: 05E3, date: 2019/01/21 - 2019/06/02
2019/04/10 20:14:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] ready for requests
2019/04/10 20:14:01 00000000 s     (main) init for all local cards done
2019/04/10 20:14:01 00000000 s (emmcache) loaded 85 emmcache records from /tmp/.oscam/oscam.emmcache in 2 ms
2019/04/10 20:14:01 00000000 s (emmcache) loaded 85 emmstat records from /tmp/.oscam/oscam.emmstat in 1 ms
2019/04/10 20:14:01 00000000 s (anticasc) anti cascading disabled
2019/04/10 20:14:01 4E76310D c  (newcamd) client connected to 24241 port
2019/04/10 20:14:01 4E76310D c   (client) anonymous disconnected from 127.0.0.1
2019/04/10 20:14:01 165EFE80 c   (client) plain dvbapi-client granted (anonymous, au=off)
2019/04/10 20:14:01 165EFE80 c   (dvbapi) dvbapi channelcache loaded from /var/etc/oscam.ccache
2019/04/10 20:14:01 165EFE80 c   (dvbapi) Using TCP listen socket, API forced to DVBAPIv3 (0), userconfig boxtype: 11
2019/04/10 20:14:04 4EFAE14A c  (newcamd) client connected to 24241 port
2019/04/10 20:14:04 4EFAE14A c   (client) encrypted newcamd:24241-client 127.0.0.1 granted (LibreElec_Wohnzimmer, au=on (1 reader))
2019/04/10 20:14:04 4EFAE14A c  (newcamd) user LibreElec_Wohnzimmer authenticated successfully (Tvheadend)
2019/04/10 20:14:04 4EFAE14A c  (newcamd) AU disabled for user LibreElec_Wohnzimmer
2019/04/10 20:14:17 70E6663C c   (client) encrypted cs357x-client 192.168.188.53 granted (Ultimo, au=on (1 reader))
2019/04/10 20:14:18 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] Ultimo emmtype=shared, len=139 (hex: 0x8B), cnt=2: written (486 ms)
2019/04/10 20:47:53 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C47F/89:917FFCEA46BB7593E1DBE079EDA2306E): rejected group (1 ms) (no matching reader)
2019/04/10 20:47:55 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:78C5B6A11E051A9A03CA44CD0FF89EEE): found (513 ms) by KD_HD_D02_Sky
2019/04/10 20:47:57 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C3BF/89:91CA9C201D52D88ED7B1237597AC1B0D): rejected group (0 ms) (no matching reader)
2019/04/10 20:47:59 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C3BF/89:7746BBF621748C7FF994AA7DDD00F083): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:03 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C3BA/89:92CA3598FCEA0403653EF50440642454): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:04 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C3BA/89:92130408205CD8594DB38F9D7EF9A2A1): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:05 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:6C86D86640364441BFC357DADA5DAF2E): found (511 ms) by KD_HD_D02_Sky
2019/04/10 20:48:09 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0141/89:BD2086AAD858F657822DCAFE8A68E1C6): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:14 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0141/89:663539B3FA84E9EBDA012D9956A60818): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:15 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:55EDE5DFFAB06DB095CA6A8701C776F4): found (518 ms) by KD_HD_D02_Sky
2019/04/10 20:48:17 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] Ultimo emmtype=shared, len=139 (hex: 0x8B), cnt=2: written (517 ms)
2019/04/10 20:48:24 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0141/89:302A4F9F475FD0D7FF0FA3CEB3CC3E14): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:25 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:ED6A06EC2F12095A4D32925E471EF946): found (523 ms) by KD_HD_D02_Sky
2019/04/10 20:48:34 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0141/89:DB928CC248B85C4F014576E2F993ACB4): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:35 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:49C52D160416FB8C422195B859589E57): found (527 ms) by KD_HD_D02_Sky
2019/04/10 20:48:44 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0137/89:F3664D76E53437C580764F2C5D6E8019): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:45 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:BAEF40CBC5455836D1D54D8A1B005395): found (511 ms) by KD_HD_D02_Sky
2019/04/10 20:48:51 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/012D/89:99CD3AD1AC428A83413206B22F5899AA): rejected group (0 ms) (no matching reader)
2019/04/10 20:48:55 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:E1BB7A826528F7845D9415023CC2A08E): found (523 ms) by KD_HD_D02_Sky
2019/04/10 20:48:57 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0123/89:F9C06547F737EF4C32985D221E57B889): rejected group (0 ms) (no matching reader)
2019/04/10 20:49:00 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] Ultimo emmtype=shared, len=139 (hex: 0x8B), cnt=2: written (517 ms)
2019/04/10 20:49:04 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0201/93:AC2356B9E7D0F5767E442519E45829A6): found (484 ms) by KD_HD_D02_Sky(btun 1834)
2019/04/10 20:49:05 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:370C578544AE3C9819A39BE90DAE473C): found (537 ms) by KD_HD_D02_Sky
2019/04/10 20:49:05 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0201/93:999536A60A5B950AF0CC5F74AF2C0423): found (976 ms) by KD_HD_D02_Sky(btun 1834)
2019/04/10 20:49:06 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/00DD/93:CD44404070A403AB60D097E2A6286812): found (1183 ms) by KD_HD_D02_Sky(btun 1834)
2019/04/10 20:49:06 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0029/89:3D85F0E7C9EFDE99C7C6DEEDCA65C7D1): rejected group (0 ms) (no matching reader)
2019/04/10 20:49:12 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0019/89:EF2E35480208756AF5619283059DB844): rejected group (0 ms) (no matching reader)
2019/04/10 20:49:14 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/0019/89:48225FC5D58C9DE47911EC7804D79559): rejected group (0 ms) (no matching reader)
2019/04/10 20:49:15 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:05BFB29FF5C2F56FD029A412894501BC): found (556 ms) by KD_HD_D02_Sky
2019/04/10 20:49:19 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C355/93:B668AE1887E53AFEC89D79715B216FEB): not found (495 ms) by KD_HD_D02_Sky(btun 1834) (KD_HD_D02_Sky unsubscribed)
2019/04/10 20:49:20 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C355/93:A541A2A454F4C5EB560EE478D572C468): not found (601 ms) by KD_HD_D02_Sky(btun 1834) (KD_HD_D02_Sky unsubscribed)
2019/04/10 20:49:20 00000000       (stat) loadbalancer: statistic saved 195 records to /tmp/.oscam/stat in 3 ms
2019/04/10 20:49:24 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] Ultimo emmtype=global, len=137 (hex: 0x89), cnt=3: written (506 ms)
2019/04/10 20:49:25 70E6663C c      (ecm) Ultimo ([email protected]/0000/002A/93:415CA28FEC62FCE759E05ADC5EBFB3EE): found (540 ms) by KD_HD_D02_Sky
2019/04/10 20:49:25 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] Ultimo emmtype=shared, len=139 (hex: 0x8B), cnt=2: written (510 ms)
2019/04/10 20:49:26 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C362/93:EEB44C907D46462E2254702FF96280F2): found (675 ms) by KD_HD_D02_Sky(btun 1834)
2019/04/10 20:49:26 010D0039 c      (ecm) LibreElec_Wohnzimmer ([email protected]/0000/C367/89:A32EFE075AF9967AF885FE24CC5F533E): rejected group (0 ms) (no matching reader)
2019/04/10 20:49:28 739FC3C0 r   (reader) KD_HD_D02_Sky [irdeto] Ultimo emmtype=global, len=137 (hex: 0x89), cnt=3: written (487 ms)


IN TVH i see this

2019-04-10 20:49:12.616 subscription: 0849: No input source available for subscription "service_mapper" to service "Kevag/362MHz/Sky Cinema Hits" in mux "362MHz in Kevag" 
2019-04-10 20:49:12.616 subscription: 0849: "service_mapper" unsubscribing, client="service_mapper" 
2019-04-10 20:49:12.616 service-mapper: Kevag/362MHz/Sky Cinema Hits: failed [reason: No access]
2019-04-10 20:49:12.616 service-mapper: checking Kevag/362MHz/Disney Cinemagic
2019-04-10 20:49:12.616 service-mapper: waiting for input
2019-04-10 20:49:12.622 subscription: 084D: "service_mapper" subscribing to service "Kevag/362MHz/Disney Cinemagic", weight: 7, adapter: "Silicon Labs Si2168 #0 : DVB-C #0", network: "Kevag", mux: "362MHz", provider: "SKY", client="service_mapper" 
2019-04-10 20:49:12.803 cwc: 192.168.188.47:24241: Can not descramble service "Disney Cinemagic", access denied (seqno: 547 Req delay: 4 ms) from newcamd-192.168.188.47:24241-1834
2019-04-10 20:49:14.840 cwc: 192.168.188.47:24241: Can not descramble service "Disney Cinemagic", access denied (seqno: 548 Req delay: 4 ms) from newcamd-192.168.188.47:24241-1834
2019-04-10 20:49:16.622 subscription: 084D: service instance is bad, reason: No access

The trace LOg shows this

TVH (12.4 MB) TVH Thomas xyz, 2019-04-10 19:06
TVH (43.4 MB) TVH Thomas xyz, 2019-04-13 11:31
TVH_1722.jpg (59.6 KB) TVH_1722.jpg Thomas xyz, 2019-04-14 11:11
TVH_1834.jpg (58.9 KB) TVH_1834.jpg Thomas xyz, 2019-04-14 11:13
TVH_Newcamd_1834.jpg (157 KB) TVH_Newcamd_1834.jpg Thomas xyz, 2019-04-14 11:24
TVH_Newcamd_1722.jpg (191 KB) TVH_Newcamd_1722.jpg Thomas xyz, 2019-04-14 11:24

History

#1 Updated by Thomas xyz 2 months ago

When i mapped the Servicees without "Check availability:" then much more services will be mapped, and i can see the decrypted and encrypted without problems.

It seems to be a mapping problem, not a encrypting problem

#2 Updated by Thomas xyz 2 months ago

A few Channels were not found, like Sky Bundesliga.
When i search on the same Cabel, with the Same Tuner on TVH on my Wetek Play 2 with CoreElec, this Channels were be foun, why not here?

#3 Updated by Jaroslav Kysela 2 months ago

It appears that the ECM processing is delayed at tvh side. Show '--trace descrambler,caclient,cwc' traces : https://tvheadend.org/projects/tvheadend/wiki/Traces

#4 Updated by Thomas xyz 2 months ago

Hi,
here the new trace

#5 Updated by Joe User 2 months ago

It appears that tvheadend wastes a lot of time trying to descramble using CAIDs you do not have a card for. You should try to use a CA filter to use CAID 1722 "exclusively".
(Configuration->Steam->Stream Filters->CA Stream Filters)

Also, it appears some channels do not have an ECM pid for CAID 1722, so those channels would not be available to you.

#6 Updated by Jaroslav Kysela 2 months ago

Note that it would be probably better to define two connections (clients) to the card server - separate for each CAID. TVH bails out after first "access denied" reply, because the code thinks that every CAIDs are available and have equal priority per connection. When you have two clients, tvh tries them all (together).

#7 Updated by Thomas xyz 2 months ago

Joe User wrote:

It appears that tvheadend wastes a lot of time trying to descramble using CAIDs you do not have a card for. You should try to use a CA filter to use CAID 1722 "exclusively".
(Configuration->Steam->Stream Filters->CA Stream Filters)

Also, it appears some channels do not have an ECM pid for CAID 1722, so those channels would not be available to you.

OK,
is this right?

#8 Updated by Thomas xyz 2 months ago

Jaroslav Kysela wrote:

Note that it would be probably better to define two connections (clients) to the card server - separate for each CAID. TVH bails out after first "access denied" reply, because the code thinks that every CAIDs are available and have equal priority per connection. When you have two clients, tvh tries them all (together).

I have tried to make two newcamd Clients in TVH, seem the screenshots.
But the client .....1834 will not connectet, the oscam Server config looks like

[newcamd]
port = [email protected]:000000;[email protected]:00000
key = 0102030405060708091011121314

Also available in: Atom PDF