Bug #5596
To many muxes ignored or failed
0%
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 (1834@1F1F1F/0000/C47F/89:917FFCEA46BB7593E1DBE079EDA2306E): rejected group (1 ms) (no matching reader) 2019/04/10 20:47:55 70E6663C c (ecm) Ultimo (1722@000000/0000/002A/93:78C5B6A11E051A9A03CA44CD0FF89EEE): found (513 ms) by KD_HD_D02_Sky 2019/04/10 20:47:57 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/C3BF/89:91CA9C201D52D88ED7B1237597AC1B0D): rejected group (0 ms) (no matching reader) 2019/04/10 20:47:59 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/C3BF/89:7746BBF621748C7FF994AA7DDD00F083): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:03 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/C3BA/89:92CA3598FCEA0403653EF50440642454): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:04 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/C3BA/89:92130408205CD8594DB38F9D7EF9A2A1): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:05 70E6663C c (ecm) Ultimo (1722@000000/0000/002A/93:6C86D86640364441BFC357DADA5DAF2E): found (511 ms) by KD_HD_D02_Sky 2019/04/10 20:48:09 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/0141/89:BD2086AAD858F657822DCAFE8A68E1C6): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:14 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/0141/89:663539B3FA84E9EBDA012D9956A60818): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:15 70E6663C c (ecm) Ultimo (1722@000000/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 (1834@1F1F1F/0000/0141/89:302A4F9F475FD0D7FF0FA3CEB3CC3E14): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:25 70E6663C c (ecm) Ultimo (1722@000000/0000/002A/93:ED6A06EC2F12095A4D32925E471EF946): found (523 ms) by KD_HD_D02_Sky 2019/04/10 20:48:34 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/0141/89:DB928CC248B85C4F014576E2F993ACB4): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:35 70E6663C c (ecm) Ultimo (1722@000000/0000/002A/93:49C52D160416FB8C422195B859589E57): found (527 ms) by KD_HD_D02_Sky 2019/04/10 20:48:44 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/0137/89:F3664D76E53437C580764F2C5D6E8019): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:45 70E6663C c (ecm) Ultimo (1722@000000/0000/002A/93:BAEF40CBC5455836D1D54D8A1B005395): found (511 ms) by KD_HD_D02_Sky 2019/04/10 20:48:51 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/012D/89:99CD3AD1AC428A83413206B22F5899AA): rejected group (0 ms) (no matching reader) 2019/04/10 20:48:55 70E6663C c (ecm) Ultimo (1722@000000/0000/002A/93:E1BB7A826528F7845D9415023CC2A08E): found (523 ms) by KD_HD_D02_Sky 2019/04/10 20:48:57 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/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 (1722@000000/0000/0201/93:AC2356B9E7D0F5767E442519E45829A6): found (484 ms) by KD_HD_D02_Sky(btun 1834) 2019/04/10 20:49:05 70E6663C c (ecm) Ultimo (1722@000000/0000/002A/93:370C578544AE3C9819A39BE90DAE473C): found (537 ms) by KD_HD_D02_Sky 2019/04/10 20:49:05 010D0039 c (ecm) LibreElec_Wohnzimmer (1722@000000/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 (1722@000000/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 (1834@1F1F1F/0000/0029/89:3D85F0E7C9EFDE99C7C6DEEDCA65C7D1): rejected group (0 ms) (no matching reader) 2019/04/10 20:49:12 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/0019/89:EF2E35480208756AF5619283059DB844): rejected group (0 ms) (no matching reader) 2019/04/10 20:49:14 010D0039 c (ecm) LibreElec_Wohnzimmer (1834@1F1F1F/0000/0019/89:48225FC5D58C9DE47911EC7804D79559): rejected group (0 ms) (no matching reader) 2019/04/10 20:49:15 70E6663C c (ecm) Ultimo (1722@000000/0000/002A/93:05BFB29FF5C2F56FD029A412894501BC): found (556 ms) by KD_HD_D02_Sky 2019/04/10 20:49:19 010D0039 c (ecm) LibreElec_Wohnzimmer (1722@000000/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 (1722@000000/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 (1722@000000/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 (1722@000000/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 (1834@1F1F1F/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
Files
History
Updated by Thomas xyz over 5 years 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
Updated by Thomas xyz over 5 years 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?
Updated by Jaroslav Kysela over 5 years 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
Updated by Joe User over 5 years 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.
Updated by Jaroslav Kysela over 5 years 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).
Updated by Thomas xyz over 5 years ago
- File TVH_1722.jpg TVH_1722.jpg added
- File TVH_1834.jpg TVH_1834.jpg added
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?
Updated by Thomas xyz over 5 years ago
- File TVH_Newcamd_1722.jpg TVH_Newcamd_1722.jpg added
- File TVH_Newcamd_1834.jpg TVH_Newcamd_1834.jpg added
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 = 24241@1834:000000;24240@1722:00000 key = 0102030405060708091011121314