Bug #2629
DVB-S tuning for 3.9 works not reliable in HEAD ("no data")
100%
Description
Hi
This bug may be related to #2586. For development purposes (and a bug causing MKV subtitles being encoded in a wrong way) I'm trying new builds of Tvheadend.
Some change between 3.9.r2161.gbb07ead and 3.9.r2236.g27e3b34 caused Tvheadend to not tune to transponders reliable anymore. I last tried 3.9.r2400.gaca5c6b.
It tunes sometimes to a mux successfully but right after that it fails again.
lsusb:
Bus 002 Device 003: ID 0b48:300a TechnoTrend AG TT-connect S2-3650 CI
tvheadend working example:
tvheadend[14041]: dvr: "Der Schatz im Silbersee" on "rbb Berlin HD" recorder starting tvheadend[14041]: mpegts: 10891.25H in DVB-S Astra 19.2 - tuning on STB0899 Multistandard : DVB-S #0 tvheadend[14041]: subscription: 0002: "DVR: Der Schatz im Silbersee" subscribing on "rbb Berlin HD", weight: 300, adapter: "STB0899 Multistandard : DVB-S #0", network: "DVB-S Astra 19.2", mux: "10891.25H", provider: "ARD", service: "rbb Berlin HD" ... tvheadend[14041]: STOP: Exiting HTS Tvheadend
Installing a newer build and trying it again results in:
tvheadend[14636]: START: HTS Tvheadend version 3.9.2301~ge2bd233 started, running as PID:14636 UID:1337 GID:91, CWD:/ CNF:/home/hts/.hts/tvheadend tvheadend[14636]: dvr: "Der Schatz im Silbersee" on "rbb Berlin HD" recorder starting tvheadend[14636]: mpegts: 10891.25H in DVB-S Astra 19.2 - tuning on STB0899 Multistandard : DVB-S #0 tvheadend[14636]: AVAHI: Service 'Tvheadend' successfully established. tvheadend[14636]: subscription: 0001: "DVR: Der Schatz im Silbersee" subscribing on "rbb Berlin HD", weight: 300, adapter: "STB0899 Multistandard : DVB-S #0", network: "DVB-S Astra 19.2", mux: "10891.25H", provider: "ARD", service: "rbb Berlin HD" tvheadend[14636]: mpegts: 10891.25H in DVB-S Astra 19.2 - scan no data, failed tvheadend[14636]: mpegts: 10891.25H in DVB-S Astra 19.2 - tuning on STB0899 Multistandard : DVB-S #0 tvheadend[14636]: subscription: 0002: "epggrab" subscribing to mux, weight: 3, adapter: "STB0899 Multistandard : DVB-S #0", network: "DVB-S Astra 19.2", mux: "10891.25H", hostname: "<N/A>", username: "<N/A>", client: "<N/A>" tvheadend[14636]: subscription: 0001: No input source available for subscription "DVR: Der Schatz im Silbersee" to channel "rbb Berlin HD" tvheadend[14636]: dvr: Recording unable to start: "/home/hts/rbb Berlin HD/Der Schatz im Silbersee.2014-12-29.13-00.mkv": No input detected tvheadend[14636]: mpegts: 11376.5V in DVB-S Astra 19.2 - tuning on STB0899 Multistandard : DVB-S #0 tvheadend[14636]: subscription: 0003: "scan" subscribing to mux, weight: 4, adapter: "STB0899 Multistandard : DVB-S #0", network: "DVB-S Astra 19.2", mux: "11376.5V", hostname: "<N/A>", username: "<N/A>", client: "<N/A>" tvheadend[14636]: subscription: 0001: No input source available for subscription "DVR: Der Schatz im Silbersee" to channel "rbb Berlin HD" tvheadend[14636]: subscription: 0002: "epggrab" unsubscribing tvheadend[14636]: subscription: 0001: No input source available for subscription "DVR: Der Schatz im Silbersee" to channel "rbb Berlin HD" tvheadend[14636]: mpegts: 11376.5V in DVB-S Astra 19.2 - scan no data, failed
Please let me know which traces may help.
Files
History
Updated by
Updated by T. Wolf almost 10 years ago
Updated by - File tvheadend-2100.log tvheadend-2100.log added
- File tvheadend-2400.err.log tvheadend-2400.err.log added
Please find attached the logs for a tuning to Eurosport. It failed for r2400 but works for r2161 tested afterwards. This is a reproducible behaviour even if the channels being affected seem to change over time. Furthermore tuning seems to be slower before an error is shown in the log (and trace) file.
Updated by Jaroslav Kysela almost 10 years ago
Could you add diseqc to logs ?
--trace linuxdvb,diseqc
Updated by B C almost 10 years ago
Updated by BTW, I had to revert c040ab05425f5ae75d03d21cf959a7647a26294e so that "fast" DiseqC worked again, although there was nothing missing in the logs which showed up why switching between low and hi band did not work any more. I just didn't have the time yet to investigate further and to bore you with it
Updated by T. Wolf almost 10 years ago
- File tvheadend-2161-diseqc.log tvheadend-2161-diseqc.log added
- File tvheadend-2400-diseqc.log tvheadend-2400-diseqc.log added
Please find the diseqc traces attached. This time for channel "RTLNITRO" and still without any differences between the log files (beside the status not changing for r2400). I already tried (not in the logs) setting "Full DiseqC" to enabled as well as the "power save" and "switch off LNB" options disabled and enabled. The results stayed the same.
Updated by Jaroslav Kysela almost 10 years ago
Thanks. All seems correct, but the driver probably does not support the fast optimized diseqc/LNB settings transitions. Could you try to enable 'Full Diseqc' in the tuner settings ?
Updated by Jaroslav Kysela almost 10 years ago
B C wrote:
BTW, I had to revert c040ab05425f5ae75d03d21cf959a7647a26294e so that "fast" DiseqC worked again, although there was nothing missing in the logs which showed up why switching between low and hi band did not work any more. I just didn't have the time yet to investigate further and to bore you with it
OK. Thanks. Unfortunately, I don't see any error in the logic. Perhaps, timing issue or so..
Updated by B C almost 10 years ago
yes I thought of a timing issue, played around with a few sleeps but it didn't help. On the other side there was no sign that the new code would do something wroong either, so I decided to not report it as a bug for now but take a deeper look at it myself when I have time.
Updated by T. Wolf almost 10 years ago
- File tvheadend-2161-full-diseqc.log tvheadend-2161-full-diseqc.log added
- File tvheadend-2400-full-diseqc.log tvheadend-2400-full-diseqc.log added
Please find attached the logs with a full DiseqC activated but I tried it already without success before. It looks for me like the log from yesterday. It does never get the status change it polls for and nearly instantly gets in r2161. The test has been done with a third channel for testing. It is called "ProSieben MAXX" this time.
Updated by Jaroslav Kysela almost 10 years ago
T. Wolf wrote:
Please find attached the logs with a full DiseqC activated but I tried it already without success before. It looks for me like the log from yesterday. It does never get the status change it polls for and nearly instantly gets in r2161. The test has been done with a third channel for testing. It is called "ProSieben MAXX" this time.
Have you tried to set the Tune repeats (tuner configuration) to 1 or 2 ?
Updated by T. Wolf almost 10 years ago
Yes I did that as well without success. As the trace file shows no difference between the builds is there any other trace option which might show a difference causing the issue?
Updated by Jaroslav Kysela almost 10 years ago
T. Wolf wrote:
Yes I did that as well without success. As the trace file shows no difference between the builds is there any other trace option which might show a difference causing the issue?
New sequence:
2015-01-21 09:47:12.232 [ NOTICE]:START: HTS Tvheadend version 3.9.2400~gaca5c6b started, running as PID:4051 UID:1337 GID:91, CWD:/ CNF:/home/hts/.hts/tvheadend 2015-01-21 09:47:20.000 [ INFO]:mpegts: 11376.5V in DVB-S Astra 19.2 - tuning on STB0899 Multistandard : DVB-S #0 2015-01-21 09:47:20.000 [ TRACE]:linuxdvb: STB0899 Multistandard : DVB-S #0 - opening FE /dev/dvb/adapter0/frontend0 (33) 2015-01-21 09:47:20.672 [ TRACE]:diseqc: set voltage 13V 2015-01-21 09:47:20.688 [ TRACE]:diseqc: initial tone off 2015-01-21 09:47:20.703 [ TRACE]:diseqc: set diseqc tone off 2015-01-21 09:47:20.737 [ DEBUG]:linuxdvb: STB0899 Multistandard : DVB-S #0 - starting 11376.5V in DVB-S Astra 19.2 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: STB0899 Multistandard : DVB-S #0 - tuning 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: tuner STB0899 Multistandard : DVB-S #0 tunning to DVBS2 pos 19.2E freq 11376500 V sym 22000000 fec 2/3 mod PSK/8 roff 35 (freq 1626500) 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 17 => 6 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 03 => 1626500 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 06 => 2 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 08 => 22000000 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 09 => 2 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 12 => 2 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 04 => 9 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 13 => 0 2015-01-21 09:47:20.737 [ TRACE]:linuxdvb: S2CMD 01 => 0
Old sequence:
2015-01-21 09:48:56.000 [ INFO]:mpegts: 11376.5V in DVB-S Astra 19.2 - tuning on STB0899 Multistandard : DVB-S #0 2015-01-21 09:48:56.000 [ TRACE]:linuxdvb: STB0899 Multistandard : DVB-S #0 - opening FE /dev/dvb/adapter0/frontend0 (21) 2015-01-21 09:48:56.000 [ TRACE]:linuxdvb: tuner STB0899 Multistandard : DVB-S #0 tunning to DVBS2 pos 19.2E freq 11376500 V sym 22000000 fec 2/3 mod PSK/8 roff 35 (freq 1626500) 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 17 => 6 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 03 => 1626500 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 06 => 2 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 08 => 22000000 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 09 => 2 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 12 => 2 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 04 => 9 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 13 => 0 2015-01-21 09:48:56.672 [ TRACE]:linuxdvb: S2CMD 01 => 0 2015-01-21 09:48:56.686 [ TRACE]:diseqc: set voltage 13V 2015-01-21 09:48:58.063 [ TRACE]:diseqc: set diseqc tone off 2015-01-21 09:48:58.097 [ DEBUG]:linuxdvb: STB0899 Multistandard : DVB-S #0 - starting 11376.5V in DVB-S Astra 19.2 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: STB0899 Multistandard : DVB-S #0 - tuning 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: tuner STB0899 Multistandard : DVB-S #0 tunning to DVBS2 pos 19.2E freq 11376500 V sym 22000000 fec 2/3 mod PSK/8 roff 35 (freq 1626500) 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 17 => 6 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 03 => 1626500 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 06 => 2 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 08 => 22000000 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 09 => 2 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 12 => 2 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 04 => 9 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 13 => 0 2015-01-21 09:48:58.097 [ TRACE]:linuxdvb: S2CMD 01 => 0
- missing the early tune
- the total setup time is 65ms for new code (voltage, tone setup) instead cca 330ms for old code (voltage, tone setup)
Unfortunately, I don't have DVB-S linuxdvb hardware to test this setup..
Could you try to activate back the early tuning - change '#if 0' in src/input/mpegts/linuxdvb/linuxdvb_satconf.c line 834 to '#if 1' ?
Updated by T. Wolf almost 10 years ago
Looks like you might have your finger at the right point. First test with "RTLNITRO" worked this time. I'll update the state tomorrow or over the weekend if nothing happens in the meantime.
Updated by Jaroslav Kysela almost 10 years ago
- Status changed from New to Fixed
- % Done changed from 0 to 100
Applied in changeset commit:tvheadend|1377fad8a03eaa73638dbc6c1d7788f75252a4df.
Updated by Jaroslav Kysela almost 10 years ago
OK. I added 'Tune Before DiseqC' option to the latest source tree (which is enabled by default). v3.9-2409-g1377fad