Project

General

Profile

Bug #5595

Muxes Scan Result = Failed or too few services are found

Added by Thomas xyz over 5 years ago. Updated over 5 years ago.

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

0%

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

Description

Hello,
i use on my Cubietruck a ARMbian Image ARMBIAN Linux cubietruck 4.20.7-sunxi #5.75 SMP Fri Feb 8 09:02:10 CET 2019 armv7l GNU/Linux with a Hauppauge WinTV-dualHD
http://www.hauppauge.de/site/products/data_dualhd.html

Sometimes if i search for Muxes, only one or two Muxes were OK, all other were FAILED.

Sometimes TVH found 990 Muxes but by Mapping the Services 568 Ignored, 223 Failed and only 199 Mapped

When i tried to Map only "Pro Sieben HD" it shows me this error

2019-04-08 20:05:47.085 service-mapper: starting
2019-04-08 20:05:47.085 service-mapper: checking Kevag/426MHz/ProSieben HD
2019-04-08 20:05:47.085 service-mapper: waiting for input
2019-04-08 20:05:47.090 mpegts: 426MHz in Kevag - tuning on Silicon Labs Si2168 #0 : DVB-C #0
2019-04-08 20:05:47.091 subscription: 0404: "service_mapper" subscribing to service "Kevag/426MHz/ProSieben HD", weight: 7, adapter: "Silicon Labs Si2168 #0 : DVB-C #0", network: "Kevag", mux: "426MHz", provider: "Digital Free", client="service_mapper" 
2019-04-08 20:05:49.295 TS: Kevag/426MHz/ProSieben HD: H264 @ #6841 Continuity counter error (total 1)
2019-04-08 20:05:51.090 subscription: 0404: service instance is bad, reason: No access
2019-04-08 20:05:51.129 mpegts: 426MHz in Kevag - tuning on Silicon Labs Si2168 #1 : DVB-C #0
2019-04-08 20:05:51.129 subscription: 0404: "service_mapper" subscribing to service "Kevag/426MHz/ProSieben HD", weight: 7, adapter: "Silicon Labs Si2168 #1 : DVB-C #0", network: "Kevag", mux: "426MHz", provider: "Digital Free", client="service_mapper" 
2019-04-08 20:05:53.387 TS: Kevag/426MHz/ProSieben HD: H264 @ #6841 Continuity counter error (total 1)
2019-04-08 20:05:55.094 subscription: 0404: service instance is bad, reason: No access
2019-04-08 20:05:55.329 cwc: 127.0.0.1:24241: Got unexpected ECM reply (seqno: 2247)
2019-04-08 20:05:55.329 cwc: 127.0.0.1:24241: Got unexpected ECM reply (seqno: 2248)
2019-04-08 20:05:57.099 subscription: 0404: No input source available for subscription "service_mapper" to service "Kevag/426MHz/ProSieben HD" in mux "426MHz in Kevag" 
2019-04-08 20:05:57.099 subscription: 0404: "service_mapper" unsubscribing, client="service_mapper" 
2019-04-08 20:05:57.099 service-mapper: Kevag/426MHz/ProSieben HD: failed [reason: No access]
2019-04-08 20:05:57.099 service-mapper: idle

A Trace Log is found here
[URL=https://www.file-upload.net/download-13564710/Tvh.html]Tvh[/URL]

A Thread with the Problem in the Forum is here
https://tvheadend.org/boards/5/topics/37081?r=37103

History

#1

Updated by Flole Systems over 5 years ago

That channel is encrypted so you need a Smartcard for decryption. If you are using one it might not have access to that channel.

#2

Updated by Thomas xyz over 5 years ago

I have a Smartcard, and the Client is newcamd looks in TVH under CA's Green and OK 

2019-04-09 06:22:13.956 cwc: 127.0.0.1:24241: Attemping to connect to server
2019-04-09 06:22:13.965 cwc: 127.0.0.1:24241: Connected as user LibreElec_Wohnzimmer to a NagraVision-card-00000000 [CAID:1834] with 1 provider

and on the Oscam Server
2019/04/09 06:22:13 526D9D1B c  (newcamd) client connected to 24241 port
2019/04/09 06:22:13 526D9D1B c   (client) encrypted newcamd:24241-client 127.0.0.1 granted (LibreElec_Wohnzimmer, au=on (1 reader))
2019/04/09 06:22:13 526D9D1B c  (newcamd) user LibreElec_Wohnzimmer authenticated successfully (Tvheadend)
2019/04/09 06:22:13 526D9D1B c  (newcamd) AU disabled for user LibreElec_Wohnzimmer

and the card definitely has acces to the channels, because other Clients VU+ STB runns fine with it

#3

Updated by Flole Systems over 5 years ago

The logs don't show what happened with the ECM requested, so it's just guessing. Look for when the ECM was requested and what happened then in Oscam (if it was answered properly).

#4

Updated by Jaroslav Kysela over 5 years ago 

2019-04-08 06:38:01.428 [  DEBUG]:cwc: 127.0.0.1:24241: Sending ECM (PID 1048) section=0/0 for service "E! Entertainment" (seqno: 12)
2019-04-08 06:38:01.635 [  DEBUG]:cwc: 127.0.0.1:24241: Sending ECM (PID 1048) section=0/0 for service "E! Entertainment" (seqno: 13)
....
2019-04-08 06:38:09.282 [WARNING]:cwc: 127.0.0.1:24241: Got unexpected ECM reply (seqno: 12)

Not sure why there are two ECM requests. You may add 'descrambler' subsystem to your trace, too, to be more verbose at the ECM level. But the reply is received 8 seconds after the ECM was sent to the server. Something is really broken. Provide logs from the card server, too.

Anyway, create another bug for this issue. It seems not related to the scanning.

For scanning: Try to find the minimal failure (when one or two muxes fails). Try use only one tuner, too. It's difficult to find something in this big log. The problem with the scanning usually means that the driver is broken.

#5

Updated by Thomas xyz over 5 years ago

Jaroslav Kysela wrote:

[...]

Not sure why there are two ECM requests. You may add 'descrambler' subsystem to your trace, too, to be more verbose at the ECM level. But the reply is received 8 seconds after the ECM was sent to the server. Something is really broken. Provide logs from the card server, too.

Anyway, create another bug for this issue. It seems not related to the scanning.

For scanning: Try to find the minimal failure (when one or two muxes fails). Try use only one tuner, too. It's difficult to find something in this big log. The problem with the scanning usually means that the driver is broken.

OK,
i have make a new thread here
https://tvheadend.org/issues/5596

Also available in: Atom PDF