25 replies to this topic

[quatschkatze]

Hello,

 

i suppose I found a bug that occurs sometimes, when streaming from Gigablue UHD Quad 4k with GigaBlue DUAL DVB-S2x Multistream Tuner.

 

The bug is present in Teamblue 6.4, OpenATV 6.4 and OpenPLI 8.1.

 

To trigger the bug do the following:

 

- Use the Android DreamPlayer app for streaming from the box. The option to change the channel before streaming needs to be off (should be off by default). Alternatively you might use another Enigma2 receiver for streaming.

 

- Set up Gigablue UHD Quad 4k, so that you can switch in DreamPlayer/Enigma2 streaming device directly between one channel that is received by Tuner A and one channel that is received by tuner I.

E.g. easiest configuration: Two satellites. Tuner A can receive one, Tuner I the other.

 

 

- Switching on the streaming app/device from the channel received by Tuner I to the channel receiver by Tuner A will fail sometimes. Staying on the channel received by Tuner I before switching seems to increase the likelihood of the error.

 

The bug only occurs when streaming. It never happens during switchting tv channels ordinarily directly on the device. It occurs with the integrated streaming server, as well as with streamproxy.

 

 

I'd be glad if you could try to reproduce the bug.

I'd be happy to give further description if needed.

[quatschkatze]

Instead of using DreamPlayer or an enigma2 receiver for receiving the stream one can also use VLC media player for reproducing the bug.

[Pr2]

You didn't describe what your setup is, I mean how your tuners are configured?

 

You didn't describe the channel that you are watching on tuner A (which satellite on which TP) and on Tuner I.

 

Streaming is nothing more that recording, it is just recording redirected to network.

So you probably have the same problem if you try to record.

 

Most of the time this can of "bug" can be explained by a misconfiguration of the tuner.

 

Tuner A is equal to Tuner I means that you have two totally independent cable connected to each tuner but receiving the same channels.

Tuner I is loopback from tuner A means that you only have one cable coming to tuner A so there are limitation to use Tuner I (you need to be on the same polarity and quadrant on a standard setup) if you are using FBC/Jess tuner then you can overcome this limitation.

 

So I think that the problem it that your tuners settings doesn't match your antenna/LNB/DiseqC real setup.

[quatschkatze]

Thank you very much for your answer Pr2.

 

My current minimal "demonstration" configuration to pinpoint the issue consist of a TechniSat GIGASYSTEM 17/8 G and a TechniSat GIGASYSTEM 17/8 K, so effectively it is a 17/16 multiswitch. To make things not more complicated than needed for finding the problem I only have configured two satellites in my box, so this is everything I have configured in my Gigablue UHD Quad 4k box right now:

config.Nims.0.advanced.lnb.1.commitedDiseqcCommand=AB
config.Nims.0.advanced.lnb.1.diseqcMode=1_0
config.Nims.0.advanced.lnb.1.increased_voltage=true
config.Nims.0.advanced.lnb.1.sequenceRepeat=true
config.Nims.0.advanced.sat.282.lnb=1
config.Nims.0.advanced.sat.282.tonemode=off
config.Nims.0.advanced.sat.282.voltage=13V
config.Nims.0.configMode=advanced
config.Nims.8.advanced.lnb.1.commitedDiseqcCommand=AA
config.Nims.8.advanced.lnb.1.diseqcMode=1_0
config.Nims.8.advanced.lnb.1.increased_voltage=true
config.Nims.8.advanced.lnb.1.sequenceRepeat=true
config.Nims.8.advanced.sat.192.lnb=1
config.Nims.8.advanced.sat.192.tonemode=off
config.Nims.8.advanced.sat.192.voltage=13V
config.Nims.8.configMode=advanced

Actually both cables can independently carry the identical signals, so I could setup both satellite positions on tuner A (=0) and set tuner I (=8) equal to tuner A. But such a configuration wouldn't run me into the bug. My real life configuration is more complicated and switching from tuner I to tuner A is a common and reasonable thing to occur there, but for the demonstration of the bug this minimum configuration is sufficient.

 

I did not record on my box so far, so I can't really say much regarding recording. Although I suppose the issue won't show when recording, because I think it might be a race condition or something similar. So if I open up dreamplayer/VLC and start to stream a channel which gets tuned by tuner A this will always work. Only if i directly switch from a channel, which is tuned by tuner I to a channel which is tuned by tuner A this issue happens sometimes/quite often.

 

In the above configuration the error occurs when trying to switch from a tuner I channel like "Das Erste HD" (Astra 19.2E) to a tuner A channel like "BBC Two HD" (28.2E).

 

I hope this is a misconfiguration of the tuner or some setting on my side, but I don't think so: Watching on the box never runs me into the error, it only happens when streaming from the box.

 

 

 

I am quite confident that everyone who fulfills these requirements can provoke the bug:

 

- Gigablue UHD Quad 4K (but it might also be with similar models?)

- GigaBlue DUAL DVB-S2x Multistream Tuner ("GIGA DVB-S2X NIM (TS3L10) (DVB-S2X)")

- Reception of at least two satellites (with some trickery one could provoke the error on a single satellite setup by emulating a second satellite).

- dreamplayer app OR Enigma2 streaming device OR VLC media player with m3u-list
 

Just directly switch from a channel received by tuner I to a channel received by tuner A. Maybe stay for some time on the channel received by tuner I before switching to enhance the likelihood of the bug occurring.

[Dimitrij]

quatschkatze

 

Test OpenATV 7.0

"Customize System Settings"/"Http(s) stream start delay"

 

add new config.usage.http_startdelay setting

Edited by Dimitrij, 19 April 2022 - 05:40.

[twol]

Obviously this is not same configuration, but suggests its your configuration giving the issue (or the app)

So I have Giga UHD 4K in my lounge, with 28.2E on A (Unicable into Duraline DCR-5-1-8 switch), 27.5W on B (Unicable LNB), and 19.2E on I/J (Unicable into Spaun SU5581/33 NFA switch)
Using GB player on Ipad .............can switch to any service on any of the tuners without issues.

I have also a GBue4k, with a slight different config, but no issues switching between tuners
I am using OpenViX but the tuner configuration is as OpenPli.(OpenATV is different setup)
 

[quatschkatze]

Dimitrij
 

Hello Dimitrij and thank you for your hint.
I switched to "OE-Alliance 5.0, OpenATV 7.0.20220419 (2022-04-18), 4.1.20 / 20200723".

I inserted my config into the settings file:

config.Nims.0.dvbs.advanced.lnb.1.commitedDiseqcCommand=AB
config.Nims.0.dvbs.advanced.lnb.1.diseqcMode=1_0
config.Nims.0.dvbs.advanced.lnb.1.increased_voltage=true
config.Nims.0.dvbs.advanced.lnb.1.sequenceRepeat=true
config.Nims.0.dvbs.advanced.sat.282.lnb=1
config.Nims.0.dvbs.advanced.sat.282.tonemode=off
config.Nims.0.dvbs.advanced.sat.282.voltage=13V
config.Nims.0.dvbs.configMode=advanced
config.Nims.8.dvbs.advanced.lnb.1.commitedDiseqcCommand=AA
config.Nims.8.dvbs.advanced.lnb.1.diseqcMode=1_0
config.Nims.8.dvbs.advanced.lnb.1.increased_voltage=true
config.Nims.8.dvbs.advanced.lnb.1.sequenceRepeat=true
config.Nims.8.dvbs.advanced.sat.192.lnb=1
config.Nims.8.dvbs.advanced.sat.192.tonemode=off
config.Nims.8.dvbs.advanced.sat.192.voltage=13V
config.Nims.8.dvbs.configMode=advanced

Then I tried again, with the new option disabled and experienced the error.

So I switched the new option to 10 ms, 50 ms, 100 ms, 500 ms, 1000 ms and 2000 ms in the GUI. After saving each change I rebooted the receiver and tried again. Finally after I went through all the GUI options I manually changed it the setting to

config.usage.http_startdelay=5000

Unfortunately the issue still persists in every setting, when trying to switch from an arbitrary tuner I channel to an arbitrary tuner A channel.

I will upload an enigma2 debug log shortly.

 

 

twol

 

Obviously this is not same configuration, but suggests its your configuration giving the issue (or the app)

So I have Giga UHD 4K in my lounge, with 28.2E on A (Unicable into Duraline DCR-5-1-8 switch), 27.5W on B (Unicable LNB), and 19.2E on I/J (Unicable into Spaun SU5581/33 NFA switch)
Using GB player on Ipad .............can switch to any service on any of the tuners without issues.

I have also a GBue4k, with a slight different config, but no issues switching between tuners
I am using OpenViX but the tuner configuration is as OpenPli.(OpenATV is different setup)
 

Thank you twol for sharing your experience.

Your additional tuner, is it the DVB-S2X Multistream capable Dual-Tuner (GIGA DVB-S2X NIM (TS3L10) (DVB-S2X))?

If so: Please put your box in standby (so no live-tv on the box, as well as no recording) choose to stream a 19.2E channel (your tuner I or J), let it run for at least 20 seconds and directly switch the streaming app to a 28.2E or 27.5W channel. Please repeat this like maybe ten times. If every time switching works without an error then I suppose it is sure that for some reason your setup isn't haunted by this issue. :-)

[quatschkatze]

Dimitrij
 
Here is the debug log. I typed init 2 on the terminal to shutdown enigma2. Then I ran enigma2 on the terminal, this is an excerpt of the part where the error happens. This is with OpenATV 7.0.20220419 without the new option. I am using DreamPlayer in this log, the receiver isn't recording and is in standby throught the whole process. I anonymized the ip address in the log file.


At ~22:16:55 I start streaming "Das Erste HD" (19.2E) (successfully)

At ~22:17:30 I switch directly to "BBC Two HD" (fails because of the bug)

At ~22:18:25 I switch back from the non-working stream to "Das Erste HD" (successfully)

At ~22:19:30 I switch to "BBC Two HD" (successfully this time)

22:16:54.5939 [eDVBServiceStream] stream ref: 1:0:19:283D:3FB:1:C00000:0:0:0:
22:16:54.5941 [eDVBResourceManager] allocate channel.. 03fb:0001
22:16:54.5941 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5942 [eDVBSatelliteEquipmentControl] canTune 1
22:16:54.5942 [eDVBSatelliteEquipmentControl] lnb 0 found
22:16:54.5942 [eDVBSatelliteEquipmentControl] 0 option(s) at position 192
22:16:54.5943 [eDVBSatelliteEquipmentControl] final score 0
22:16:54.5943 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5943 [eDVBSatelliteEquipmentControl] canTune 2
22:16:54.5943 [eDVBSatelliteEquipmentControl] final score 0
22:16:54.5944 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5944 [eDVBSatelliteEquipmentControl] canTune 8
22:16:54.5944 [eDVBSatelliteEquipmentControl] final score 0
22:16:54.5945 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5945 [eDVBSatelliteEquipmentControl] canTune 16
22:16:54.5945 [eDVBSatelliteEquipmentControl] final score 0
22:16:54.5945 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5946 [eDVBSatelliteEquipmentControl] canTune 32
22:16:54.5946 [eDVBSatelliteEquipmentControl] final score 0
22:16:54.5946 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5946 [eDVBSatelliteEquipmentControl] canTune 64
22:16:54.5947 [eDVBSatelliteEquipmentControl] final score 0
22:16:54.5947 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5947 [eDVBSatelliteEquipmentControl] canTune 128
22:16:54.5947 [eDVBSatelliteEquipmentControl] final score 0
22:16:54.5948 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5948 [eDVBSatelliteEquipmentControl] canTune 256
22:16:54.5948 [eDVBSatelliteEquipmentControl] lnb 1 found
22:16:54.5948 [eDVBSatelliteEquipmentControl] 1 option(s) at position 192
22:16:54.5949 [eDVBSatelliteEquipmentControl] sat 192 found
22:16:54.5949 ret1 10000
22:16:54.5949 [eDVBSatelliteEquipmentControl] ret5 10000
22:16:54.5949 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:16:54.5950 [eDVBSatelliteEquipmentControl] score new 10000
22:16:54.5950 [eDVBSatelliteEquipmentControl] final score 10005
22:16:54.5950 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.5950 [eDVBSatelliteEquipmentControl] canTune 512
22:16:54.5950 [eDVBSatelliteEquipmentControl] final score 0
22:16:54.5953 [eDVBFrontend] m_need_delivery_system_workaround = 0
22:16:54.5953 [eDVBFrontend] opening frontend 8
22:16:54.6175 [eDVBFrontend8] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2
22:16:54.6283 tune tuner 8
22:16:54.6285 [eDVBSatelliteEquipmentControl] direct_connected 1
22:16:54.6285 [eDVBSatelliteEquipmentControl] canTune 256
22:16:54.6286 [eDVBSatelliteEquipmentControl] lnb 1 found
22:16:54.6287 [eDVBSatelliteEquipmentControl] 1 option(s) at position 192
22:16:54.6287 [eDVBSatelliteEquipmentControl] sat 192 found
22:16:54.6288 ret1 10000
22:16:54.6288 [eDVBSatelliteEquipmentControl] ret5 10000
22:16:54.6288 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:16:54.6289 [eDVBSatelliteEquipmentControl] score new 10000
22:16:54.6289 [eDVBSatelliteEquipmentControl] final score 10005
22:16:54.6290 has_external_rotor /home/root/rotor_8 no
22:16:54.6291 [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
22:16:54.6291 [eDVBFrontend8] prepare_sat System 1 Freq 11493750 Pol 0 SR 22000000 INV 2 FEC 2 orbpos 192 system 1 modulation 2 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0, t2mi_plp_id -1 t2mi_pid 4096
22:16:54.6292 tuning to 1743 MHz
22:16:54.6292 [eDVBChannel] OURSTATE: tuner 8 tuning
22:16:54.6293 [eDVBServicePMTHandler] allocate Channel: res 0
22:16:54.6294 [eDVBCIInterfaces] addPMTHandler 1:0:19:283D:3FB:1:C00000:0:0:0:
22:16:54.6295 [eDVBChannel] getDemux cap=00
22:16:54.6296 [eDVBResourceManager] allocate demux cap=00
22:16:54.6296 [eDVBResourceManager] allocating demux adapter=0, demux=0, source=0
22:16:54.6297 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:54.6299 [eDVBServiceStream] try it again when we are tuned in
22:16:54.6301 [SEC] set static current limiting
22:16:54.6303 [eDVBFrontend] [SEC] tuner 8 setVoltage 3
22:16:54.6304 [eDVBFrontend8] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 1 FE_SET_VOLTAGE 0
22:16:54.6404 [eDVBFrontend] [SEC] tuner 8 sleep 200ms
22:16:54.8405 [SEC] invalidate current switch params
22:16:54.8625 [SEC] tuner 8 sendDiseqc: e00000(DiSEqC reset)22:16:54.8627 [eDVBFrontend] [SEC] diseqc ioctl duration: 21 ms -> extra guard delay 31 ms22:16:54.8938 [eDVBFrontend] [SEC] tuner 8 sleep 50ms
22:16:54.9655 [SEC] tuner 8 sendDiseqc: e00003(DiSEqC peripherial power on)22:16:54.9657 [eDVBFrontend] [SEC] diseqc ioctl duration: 21 ms -> extra guard delay 31 ms22:16:54.9968 [eDVBFrontend] [SEC] tuner 8 sleep 150ms
22:16:55.1785 [SEC] tuner 8 sendDiseqc: e01038f222:16:55.1787 [eDVBFrontend] [SEC] diseqc ioctl duration: 31 ms -> extra guard delay 35 ms22:16:55.2138 [eDVBFrontend] [SEC] tuner 8 sleep 100ms
22:16:55.3139 [eDVBFrontend] [SEC] tuner 8 sleep 70ms
22:16:55.4055 [SEC] tuner 8 sendDiseqc: e01038f222:16:55.4056 [eDVBFrontend] [SEC] diseqc ioctl duration: 21 ms -> extra guard delay 45 ms22:16:55.4506 [eDVBFrontend] [SEC] tuner 8 sleep 100ms
22:16:55.5508 [SEC] update current switch params
22:16:55.5509 [SEC] tuner 8 startTuneTimeout 5000
22:16:55.5510 [SEC] tuner 8 setFrontend: events enabled
22:16:55.5511 setting frontend 8 events: on
22:16:55.5604 [eDVBFrontend] (8)fe event: status 0, inversion off, m_tuning 1
22:16:55.5605 [eDVBFrontend] [SEC] tuner 8 sleep 500ms
22:16:55.9155 [eDVBFrontend] (8)fe event: status 10, inversion off, m_tuning 2
22:16:55.9156 [eDVBChannel] OURSTATE: tuner 8 ok
22:16:55.9157 [eDVBLocalTimerHandler] Channel 0xc8eaa0 running.
22:16:55.9158 [eDVBChannel] getDemux cap=00
22:16:55.9158 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9159 [eDVBSectionReader] DMX_SET_FILTER pid=20
22:16:55.9164 [eEPGTransponderDataReader] channel 0xc8eaa0 running
22:16:55.9165 [eDVBChannel] getDemux cap=00
22:16:55.9166 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9167 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9168 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9168 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9169 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9169 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9170 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9171 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9171 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9172 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9172 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9173 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9173 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9175 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9175 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9176 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9177 [eDVBResourceManager] stop release channel timer
22:16:55.9178 [eEPGChannelData] next update in 47 min
22:16:55.9179 [eDVBChannel] getDemux cap=01
22:16:55.9179 [eDVBResourceManager] allocate demux cap=01
22:16:55.9180 [eDVBResourceManager] allocating shared demux adapter=0, demux=0, source=8
22:16:55.9180 [eDVBServicePMTHandler] ok ... now we start!!
22:16:55.9181 [eDVBServiceStream] STREAM service event 5
22:16:55.9181 [eDVBServiceStream] try it again when we are tuned in
22:16:55.9182 [eDVBCAService] new service 1:0:19:283D:3FB:1:C00000:0:0:0:
22:16:55.9183 [eDVBCAService] add demux 0 to slot 0 service 1:0:19:283D:3FB:1:C00000:0:0:0:
22:16:55.9185 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9186 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:16:55.9189 [eDVBServiceStream] STREAM service event 6
22:16:55.9190 [eDVBServiceStream] tuned.. m_state 1 m_want_record 1
22:16:55.9190 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9191 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:16:55.9195 [eDVBRecordStreamThread] allocated 40 buffers of 192 kB
22:16:55.9196 [eDVBServiceStream] start streaming...
22:16:55.9197 [eDVBServiceStream] no cached pids found
22:16:55.9197 [eDVBServiceStream] have 1 video stream(s) (13ed), and 1 audio stream(s) (13f2), and the pcr pid is 13ed, and the text pid is 13f022:16:55.9201 [eDVBServiceStream] ADD PID: 0000
22:16:55.9202 [eDVBServiceStream] ADD PID: 0012
22:16:55.9202 [eDVBServiceStream] ADD PID: 0014
22:16:55.9203 [eDVBServiceStream] ADD PID: 13ed
22:16:55.9203 [eDVBServiceStream] ADD PID: 13f0
22:16:55.9204 [eDVBServiceStream] ADD PID: 13f2
22:16:55.9209 [setIoPrio] realtime level 7 ok
22:16:55.9210 [eFilePushThreadRecorder] THREAD START
22:16:55.9216 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:55.9217 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:16:56.0056 [eDVBServicePMTHandler] PATready
22:16:56.0057 [eDVBServicePMTHandler] PAT TSID: 0x03fb (1019)
22:16:56.0058 [eDVBServicePMTHandler] use pmtpid 13ec for service_id 283d
22:16:56.0058 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.0059 [eDVBSectionReader] DMX_SET_FILTER pid=5100
22:16:56.0063 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.0064 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:16:56.0072 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.0072 [eDVBSectionReader] DMX_SET_FILTER pid=5100
22:16:56.0078 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.0079 [eDVBSectionReader] DMX_SET_FILTER pid=17
22:16:56.0606 [SEC] set dynamic current limiting
22:16:56.1803 [eDVBLocalTimerHandler] Transponder time is 19/04/2022 22:16:55.
22:16:56.1803 [eDVBLocalTimerHandler] Difference is -1.
22:16:56.1804 [eDVBLocalTimerHandler] Difference less than 120, use transponder time.
22:16:56.1804 [eDVBLocalTimerHandler] Set RTC time.
22:16:56.1806 [eDVBLocalTimerHandler] Update RTC.
22:16:56.1806 [eDVBLocalTimerHandler] Time updated to 22:16:55.
22:16:56.1806 [eDVBLocalTimerHandler] Time difference is -1.
22:16:56.1807 [eDVBLocalTimerHandler] Slewing Linux time by -1 seconds.
22:16:56.1822 [eDVBChannel] getDemux cap=00
22:16:56.2292 [eDVBServiceStream] STREAM service event 5
22:16:56.2293 [eDVBServiceStream] start streaming...
22:16:56.2294 [eDVBServiceStream] no cached pids found
22:16:56.2298 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.2299 [eDVBSectionReader] DMX_SET_FILTER pid=1170
22:16:56.2307 [eDVBServiceStream] have 1 video stream(s) (13ed), and 3 audio stream(s) (13ee, 13ef, 13f2) (13f1), and the pcr pid is 13ed, and the text pid is 13f022:16:56.2309 [eDVBServiceStream] ADD PID: 0492
22:16:56.2375 [eDVBServiceStream] ADD PID: 13ec
22:16:56.2376 [eDVBServiceStream] ADD PID: 13ee
22:16:56.2377 [eDVBServiceStream] ADD PID: 13ef
22:16:56.2385 [eDVBServiceStream] ADD PID: 13f1
22:16:56.2386 [eDVBCIInterfaces] gotPMT
22:16:56.2386 [eDVBCAService] don't build/send the same CA PMT twice
22:16:56.2388 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.2388 [eDVBSectionReader] DMX_SET_FILTER pid=5100
22:16:56.2399 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.2400 [eDVBSectionReader] DMX_SET_FILTER pid=5110
22:16:56.4539 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.4540 [eDVBSectionReader] DMX_SET_FILTER pid=5120
22:16:56.6270 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:56.6272 [eDVBSectionReader] DMX_SET_FILTER pid=5130
22:16:57.1536 [eDVBServicePMTHandler] AITready
22:16:57.1538 [eDVBServicePMTHandler] Section Length : 1007, Total Section Length : 1010
22:16:57.1539 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 1
22:16:57.1539 [eDVBServicePMTHandler] last_section_number 1 > section_number 0
22:16:57.1541 [eDVBServicePMTHandler] Save Data Len : [1010]
22:16:57.1541 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:57.1542 [eDVBSectionReader] DMX_SET_FILTER pid=5172
22:16:57.1547 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 2
22:16:57.1548 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 4
22:16:57.1549 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:57.1550 [eDVBSectionReader] DMX_SET_FILTER pid=5172
22:16:57.1553 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 5
22:16:57.1554 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 20
22:16:57.1555 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 3
22:16:57.1556 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 35
22:16:57.1556 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 39
22:16:57.1557 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 192
22:16:57.1557 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 155
22:16:57.1558 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 157
22:16:57.1559 [eDVBServicePMTHandler] Section Length : 107, Total Section Length : 110
22:16:57.1559 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 222
22:16:57.1559 [eDVBServicePMTHandler] Found : control[1], name[HBBTV-Start-DSMCC+Web], url[http://itv.ard.de/ardstart/index.html]
22:16:57.1560 [eDVBServicePMTHandler] Found : control[2], name[EPG], url[http://itv.ard.de/ardepg/index.php]
22:16:57.1560 [eDVBServicePMTHandler] Found : control[2], name[VTX-Mandant-ARD-Text-Web+DSMCC], url[http://vtx.ard.de/app/_ard.html]
22:16:57.1561 [eDVBServicePMTHandler] Found : control[2], name[Tagesschau], url[http://hbbtv.tagesschau.de/index.html]
22:16:57.1561 [eDVBServicePMTHandler] Found : control[2], name[Mediathek DasErste], url[http://tv.ardmediathek.de/index.html?client=daserste]
22:16:57.1562 [eDVBServicePMTHandler] Found : control[2], name[HBBTV ARD-Mediathek], url[http://tv.ardmediathek.de/index.html]
22:16:57.1562 [eDVBServicePMTHandler] Found : control[2], name[BR-Mediathek], url[http://hbbtv-mediathek.br.de/index.html]
22:16:57.1563 [eDVBServicePMTHandler] Found : control[2], name[Sportschau], url[http://tv.ardmediathek.de/index.html?client=sportschau]
22:16:57.1564 [eDVBServicePMTHandler] Found : control[2], name[Replay], url[http://itv.ard.de/replay/index.php]
22:16:57.1565 [eDVBServicePMTHandler] Found : control[2], name[Replay Test], url[http://itv.ard.de/replay/index.php]
22:16:57.1565 [eDVBServicePMTHandler] Found : control[2], name[KiKA-Player ARD], url[http://cdn.hbbtv.kika.de/player/index.php?ard=1]
22:16:57.1566 [eDVBServicePMTHandler] Found : control[2], name[Teaser Test App], url[http://itv.mit-xperts.com/ardstart/index.php]
22:16:57.1567 [eDVBServiceStream] STREAM service event 11
22:16:57.3361 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:16:57.3366 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:16:57.3725 [eDVBServicePMTHandler] sdt update done!
22:16:57.7216 [eDVBServicePMTHandler] OCready
22:17:30.5643 [eDVBServiceStream] stop streaming m_state 2
22:17:30.5663 [eFilePushThreadRecorder] stopping thread.
22:17:30.5667 [eDVBRecordStreamThread] cancelling aio
22:17:30.5667 [eDVBRecordStreamThread] all done
22:17:30.5668 [eDVBRecordFileThread] waiting for aio to complete
22:17:30.5668 [eDVBRecordFileThread] wait: aio_return returned failure: Function not implemented
22:17:30.5669 [eDVBRecordFileThread] buffer usage histogram (40 buffers of 192 kB)
22:17:30.5670 [eDVBRecordFileThread]   1:    355
22:17:30.5670 [eDVBRecordFileThread]   2:      9
22:17:30.5671 [eDVBRecordFileThread]   3:      3
22:17:30.5671 [eDVBRecordFileThread]   4:      4
22:17:30.5672 [eFilePushThreadRecorder] THREAD STOP
22:17:30.5673 [eDVBServiceStream] stop streaming m_state 0
22:17:30.5691 [eDVBCAService] free slot 0 demux 0 for service 1:0:19:283D:3FB:1:C00000:0:0:0:
22:17:30.5692 [eDVBCAService] free service 1:0:19:283D:3FB:1:C00000:0:0:0:
22:17:30.5697 [eDVBResourceManager] start release channel timer
22:17:30.6261 [eDVBServiceStream] stream ref: 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:17:30.6263 [eDVBLocalTimerHandler] Remove channel 0xc8eaa0.
22:17:30.6264 [eEPGTransponderDataReader] remove channel 0xc8eaa0
22:17:30.6276 [eDVBResourceManager] allocate channel.. 0802:0002
22:17:30.6276 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6277 [eDVBSatelliteEquipmentControl] canTune 1
22:17:30.6277 [eDVBSatelliteEquipmentControl] lnb 0 found
22:17:30.6277 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
22:17:30.6278 [eDVBSatelliteEquipmentControl] sat 282 found
22:17:30.6278 ret1 10000
22:17:30.6278 [eDVBSatelliteEquipmentControl] ret5 10000
22:17:30.6278 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:17:30.6279 [eDVBSatelliteEquipmentControl] score new 10000
22:17:30.6279 [eDVBSatelliteEquipmentControl] final score 10005
22:17:30.6279 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6279 [eDVBSatelliteEquipmentControl] canTune 2
22:17:30.6280 [eDVBSatelliteEquipmentControl] final score 0
22:17:30.6280 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6280 [eDVBSatelliteEquipmentControl] canTune 8
22:17:30.6280 [eDVBSatelliteEquipmentControl] final score 0
22:17:30.6281 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6281 [eDVBSatelliteEquipmentControl] canTune 16
22:17:30.6281 [eDVBSatelliteEquipmentControl] final score 0
22:17:30.6282 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6282 [eDVBSatelliteEquipmentControl] canTune 32
22:17:30.6282 [eDVBSatelliteEquipmentControl] final score 0
22:17:30.6282 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6283 [eDVBSatelliteEquipmentControl] canTune 64
22:17:30.6283 [eDVBSatelliteEquipmentControl] final score 0
22:17:30.6283 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6283 [eDVBSatelliteEquipmentControl] canTune 128
22:17:30.6284 [eDVBSatelliteEquipmentControl] final score 0
22:17:30.6284 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6285 [eDVBSatelliteEquipmentControl] canTune 256
22:17:30.6285 [eDVBSatelliteEquipmentControl] lnb 1 found
22:17:30.6285 [eDVBSatelliteEquipmentControl] 0 option(s) at position 282
22:17:30.6285 [eDVBSatelliteEquipmentControl] final score 0
22:17:30.6286 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6286 [eDVBSatelliteEquipmentControl] canTune 512
22:17:30.6286 [eDVBSatelliteEquipmentControl] final score 0
22:17:30.6289 [eDVBFrontend] m_need_delivery_system_workaround = 0
22:17:30.6289 [eDVBFrontend] opening frontend 0
22:17:30.6291 [eDVBFrontend0] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2
22:17:30.6298 tune tuner 0
22:17:30.6299 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:30.6299 [eDVBSatelliteEquipmentControl] canTune 1
22:17:30.6299 [eDVBSatelliteEquipmentControl] lnb 0 found
22:17:30.6299 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
22:17:30.6299 [eDVBSatelliteEquipmentControl] sat 282 found
22:17:30.6300 ret1 10000
22:17:30.6300 [eDVBSatelliteEquipmentControl] ret5 10000
22:17:30.6300 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:17:30.6300 [eDVBSatelliteEquipmentControl] score new 10000
22:17:30.6300 [eDVBSatelliteEquipmentControl] final score 10005
22:17:30.6300 has_external_rotor /home/root/rotor_0 no
22:17:30.6301 [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
22:17:30.6301 [eDVBFrontend0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0, t2mi_plp_id -1 t2mi_pid 4096
22:17:30.6301 tuning to 1097 MHz
22:17:30.6301 [eDVBChannel] OURSTATE: tuner 0 tuning
22:17:30.6302 [eDVBServicePMTHandler] allocate Channel: res 0
22:17:30.6302 [eDVBCIInterfaces] addPMTHandler 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:17:30.6302 [eDVBChannel] getDemux cap=00
22:17:30.6302 [eDVBResourceManager] allocate demux cap=00
22:17:30.6303 [eDVBResourceManager] allocating demux adapter=0, demux=0, source=8
22:17:30.6303 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:30.6305 [eDVBServiceStream] try it again when we are tuned in
22:17:30.6305 [SEC] set static current limiting
22:17:30.6307 [eDVBFrontend] [SEC] tuner 0 setVoltage 3
22:17:30.6307 [eDVBFrontend0] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 1 FE_SET_VOLTAGE 0
22:17:30.6307 [eDVBFrontend] [SEC] tuner 0 sleep 200ms
22:17:30.8308 [SEC] invalidate current switch params
22:17:30.9635 [SEC] tuner 0 sendDiseqc: e00000(DiSEqC reset)22:17:30.9638 [eDVBFrontend] [SEC] diseqc ioctl duration: 132 ms22:17:30.9639 [eDVBFrontend] [SEC] tuner 0 sleep 50ms
22:17:31.1014 [SEC] tuner 0 sendDiseqc: e00003(DiSEqC peripherial power on)22:17:31.1016 [eDVBFrontend] [SEC] diseqc ioctl duration: 87 ms22:17:31.1017 [eDVBFrontend] [SEC] tuner 0 sleep 150ms
22:17:31.3560 [SEC] tuner 0 sendDiseqc: e01038f422:17:31.3561 [eDVBFrontend] [SEC] diseqc ioctl duration: 104 ms22:17:31.3565 [eDVBFrontend] [SEC] tuner 0 sleep 100ms
22:17:31.4565 [eDVBFrontend] [SEC] tuner 0 sleep 70ms
22:17:31.6607 [SEC] tuner 0 sendDiseqc: e01038f422:17:31.6608 [eDVBFrontend] [SEC] diseqc ioctl duration: 134 ms22:17:31.6611 [eDVBFrontend] [SEC] tuner 0 sleep 100ms
22:17:31.7611 [SEC] update current switch params
22:17:31.7612 [SEC] tuner 0 startTuneTimeout 5000
22:17:31.7613 [SEC] tuner 0 setFrontend: events enabled
22:17:31.7613 setting frontend 0 events: on
22:17:31.8948 [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
22:17:31.8949 [eDVBFrontend] [SEC] tuner 0 sleep 500ms
22:17:31.9951 [eDVBFrontend] (0)fe event: status 1, inversion off, m_tuning 2
22:17:32.3950 [SEC] set dynamic current limiting
22:17:32.5694 [DVBCAHandler] no more services
22:17:33.6275 [eDVBFrontend] try to close frontend 8
22:17:33.6600 [eDVBFrontend8] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2
22:17:36.7612 [eDVBChannel] OURSTATE: tuner 0 failed, retune
22:17:36.7613 tune tuner 0
22:17:36.7615 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:36.7615 [eDVBSatelliteEquipmentControl] canTune 1
22:17:36.7615 [eDVBSatelliteEquipmentControl] lnb 0 found
22:17:36.7616 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
22:17:36.7616 [eDVBSatelliteEquipmentControl] sat 282 found
22:17:36.7616 ret1 10000
22:17:36.7616 [eDVBSatelliteEquipmentControl] ret5 10000
22:17:36.7617 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:17:36.7617 [eDVBSatelliteEquipmentControl] score new 10000
22:17:36.7617 [eDVBSatelliteEquipmentControl] final score 10005
22:17:36.7618 has_external_rotor /home/root/rotor_0 no
22:17:36.7618 [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
22:17:36.7619 [eDVBFrontend0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0, t2mi_plp_id -1 t2mi_pid 4096
22:17:36.7619 tuning to 1097 MHz
22:17:36.7619 [eDVBChannel] OURSTATE: tuner 0 tuning
22:17:36.7620 [eDVBServicePMTHandler] tune failed.
22:17:36.7620 [eDVBServiceStream] STREAM service event 1
22:17:36.7620 [eDVBServiceStream] stream failed to tune
22:17:36.7621 [eDVBServiceStream] stop streaming m_state 1
22:17:36.7622 [eDVBResourceManager] start release channel timer
22:17:36.7624 [SEC] set static current limiting
22:17:36.7625 [SEC] invalidate current switch params
22:17:36.8520 [SEC] tuner 0 sendDiseqc: e00000(DiSEqC reset)22:17:36.8522 [eDVBFrontend] [SEC] diseqc ioctl duration: 89 ms22:17:36.8528 [eDVBFrontend] [SEC] tuner 0 sleep 50ms
22:17:36.9925 [SEC] tuner 0 sendDiseqc: e00003(DiSEqC peripherial power on)22:17:36.9927 [eDVBFrontend] [SEC] diseqc ioctl duration: 89 ms22:17:36.9929 [eDVBFrontend] [SEC] tuner 0 sleep 150ms
22:17:36.9932 [eDVBServiceStream] stream ref: 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:17:36.9934 [eDVBLocalTimerHandler] Channel 0xc8eaa0 running.
22:17:36.9935 [eDVBChannel] getDemux cap=00
22:17:36.9935 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9937 [eDVBSectionReader] DMX_SET_FILTER pid=20
22:17:36.9942 [eEPGTransponderDataReader] channel 0xc8eaa0 running
22:17:36.9942 [eDVBChannel] getDemux cap=00
22:17:36.9943 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9944 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9945 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9945 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9946 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9946 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9946 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9947 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9947 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9948 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9948 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9949 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9949 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9950 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9951 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9951 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9952 [eDVBResourceManager] stop release channel timer
22:17:36.9952 [eEPGChannelData] next update in 2 sec
22:17:36.9953 [eDVBResourceManager] start release channel timer
22:17:36.9953 [eDVBResourceManager] use cached_channel, frontend=0
22:17:36.9953 [eDVBResourceManager] stop release channel timer
22:17:36.9954 [eDVBServicePMTHandler] allocate Channel: res 0
22:17:36.9955 [eDVBCIInterfaces] addPMTHandler 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:17:36.9956 [eDVBChannel] getDemux cap=01
22:17:36.9956 [eDVBResourceManager] allocate demux cap=01
22:17:36.9956 [eDVBResourceManager] allocating shared demux adapter=0, demux=0, source=0
22:17:36.9957 [eDVBServicePMTHandler] ok ... now we start!!
22:17:36.9957 [eDVBServiceStream] STREAM service event 5
22:17:36.9958 [eDVBCAService] new service 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:17:36.9959 [eDVBCAService] add demux 0 to slot 0 service 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:17:36.9960 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9961 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:17:36.9966 [eDVBServiceStream] STREAM service event 6
22:17:36.9966 [eDVBServiceStream] tuned.. m_state 1 m_want_record 0
22:17:36.9967 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:17:36.9968 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:17:36.9971 [eDVBChannel] getDemux cap=00
22:17:36.9974 [eDVBRecordStreamThread] allocated 40 buffers of 192 kB
22:17:36.9975 [eDVBServiceStream] start streaming...
22:17:36.9975 [eDVBServiceStream] no cached pids found
22:17:36.9976 [eDVBServiceStream] have 1 video stream(s) (157c), and 1 audio stream(s) (157d), and the pcr pid is 157c, and the text pid is 157f22:17:36.9978 [eDVBServiceStream] ADD PID: 0000
22:17:36.9978 [eDVBServiceStream] ADD PID: 0012
22:17:36.9979 [eDVBServiceStream] ADD PID: 0014
22:17:36.9979 [eDVBServiceStream] ADD PID: 157c
22:17:36.9979 [eDVBServiceStream] ADD PID: 157d
22:17:36.9979 [eDVBServiceStream] ADD PID: 157f
22:17:36.9986 [setIoPrio] realtime level 7 ok
22:17:36.9987 [eFilePushThreadRecorder] THREAD START
22:17:37.2465 [SEC] tuner 0 sendDiseqc: e01038f422:17:37.2467 [eDVBFrontend] [SEC] diseqc ioctl duration: 103 ms22:17:37.2468 [eDVBFrontend] [SEC] tuner 0 sleep 100ms
22:17:37.3469 [eDVBFrontend] [SEC] tuner 0 sleep 70ms
22:17:37.5206 [SEC] tuner 0 sendDiseqc: e01038f422:17:37.5209 [eDVBFrontend] [SEC] diseqc ioctl duration: 103 ms22:17:37.5209 [eDVBFrontend] [SEC] tuner 0 sleep 100ms
22:17:37.6210 [SEC] update current switch params
22:17:37.6211 [SEC] tuner 0 startTuneTimeout 5000
22:17:37.6211 [SEC] tuner 0 setFrontend: events enabled
22:17:37.6212 setting frontend 0 events: on
22:17:37.6213 [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
22:17:37.6213 [eDVBFrontend] [SEC] tuner 0 sleep 500ms
22:17:37.7211 [eDVBFrontend] (0)fe event: status 1, inversion off, m_tuning 2
22:17:38.1214 [SEC] set dynamic current limiting
22:17:39.0034 [eEPGChannelData] start reading events(1650399459)
22:17:39.0035 [eDVBSectionReader] DMX_SET_FILTER pid=3842
22:17:39.0040 [eDVBSectionReader] DMX_SET_FILTER pid=3003
22:17:39.0043 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:17:39.0049 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:17:39.0056 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:17:39.0065 [eDVBSectionReader] DMX_SET_FILTER pid=5000
22:17:39.0067 [eDVBSectionReader] DMX_SET_FILTER pid=5000
22:17:39.0072 [huffman] read.. '/usr/share/enigma2/otv_011a0000_0002_0802.dict'
22:17:39.0072 [eEPGChannelData] abort non avail OpenTV EIT reading
22:17:39.0073 [eDVBSectionReader] DMX_SET_FILTER pid=57
22:17:42.6212 [eDVBChannel] OURSTATE: tuner 0 failed, retune
22:17:42.6213 tune tuner 0
22:17:42.6213 [eDVBSatelliteEquipmentControl] direct_connected 1
22:17:42.6213 [eDVBSatelliteEquipmentControl] canTune 1
22:17:42.6213 [eDVBSatelliteEquipmentControl] lnb 0 found
22:17:42.6214 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
22:17:42.6214 [eDVBSatelliteEquipmentControl] sat 282 found
22:17:42.6214 ret1 10000
22:17:42.6214 [eDVBSatelliteEquipmentControl] ret5 10000
22:17:42.6214 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:17:42.6215 [eDVBSatelliteEquipmentControl] score new 10000
22:17:42.6215 [eDVBSatelliteEquipmentControl] final score 10005
22:17:42.6215 has_external_rotor /home/root/rotor_0 no
22:17:42.6215 [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
22:17:42.6216 [eDVBFrontend0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0, t2mi_plp_id -1 t2mi_pid 4096
22:17:42.6216 tuning to 1097 MHz
22:17:42.6216 [eDVBChannel] OURSTATE: tuner 0 tuning
22:17:42.6216 [eDVBServicePMTHandler] tune failed.
22:17:42.6216 [eDVBServiceStream] STREAM service event 1
22:17:42.6217 [eDVBServiceStream] stream failed to tune
22:17:42.6217 [eDVBServiceStream] stop streaming m_state 2
22:17:42.6233 [eFilePushThreadRecorder] stopping thread.
22:17:42.6236 [eDVBRecordStreamThread] cancelling aio
22:17:42.6237 [eDVBRecordStreamThread] all done
22:17:42.6237 [eDVBRecordFileThread] waiting for aio to complete
22:17:42.6237 [eDVBRecordFileThread] buffer usage histogram (40 buffers of 192 kB)
22:17:42.6237 [eFilePushThreadRecorder] THREAD STOP
22:17:42.6241 [eDVBCAService] free slot 0 demux 0 for service 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:17:42.6242 [eDVBCAService] free service 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:17:42.6245 [eDVBResourceManager] start release channel timer
22:17:42.6248 [SEC] set static current limiting
22:17:42.6249 [SEC] invalidate current switch params
22:17:42.7148 [SEC] tuner 0 sendDiseqc: e00000(DiSEqC reset)22:17:42.7151 [eDVBFrontend] [SEC] diseqc ioctl duration: 89 ms22:17:42.7572 [OpenWebif] -D- tuner '0' 'DVB-S2X NIM(45308X FBC)' 'Tuner A'
22:17:42.7575 [OpenWebif] -D- tuner '1' 'DVB-S2X NIM(45308X FBC)' 'Tuner B'
22:17:42.7576 [OpenWebif] -D- tuner '2' 'DVB-S2X NIM(45308X FBC)' 'Tuner C'
22:17:42.7579 [OpenWebif] -D- tuner '3' 'DVB-S2X NIM(45308X FBC)' 'Tuner D'
22:17:42.7581 [OpenWebif] -D- tuner '4' 'DVB-S2X NIM(45308X FBC)' 'Tuner E'
22:17:42.7582 [OpenWebif] -D- tuner '5' 'DVB-S2X NIM(45308X FBC)' 'Tuner F'
22:17:42.7584 [OpenWebif] -D- tuner '6' 'DVB-S2X NIM(45308X FBC)' 'Tuner G'
22:17:42.7586 [OpenWebif] -D- tuner '7' 'DVB-S2X NIM(45308X FBC)' 'Tuner H'
22:17:42.7587 [OpenWebif] -D- tuner '8' 'GIGA DVB-S2X NIM (TS3L10)' 'Tuner I'
22:17:42.7588 [OpenWebif] -D- tuner '9' 'GIGA DVB-S2X NIM (TS3L10)' 'Tuner J'
22:17:42.7710 "::ffff:123.456.789.12" - - [19/Apr/2022:20:16:26 +0000] "GET /web/deviceinfo HTTP/1.1" 200 1870 "-" "Dalvik/2.1.0 (Linux; U; Android 12; Pixel 4a Build/SP2A.220405.003)"
22:17:42.7729 [eDVBFrontend] [SEC] tuner 0 sleep 50ms
22:17:42.9127 [SEC] tuner 0 sendDiseqc: e00003(DiSEqC peripherial power on)22:17:42.9129 [eDVBFrontend] [SEC] diseqc ioctl duration: 89 ms22:17:42.9549 [OpenWebif] -D- tuner '0' 'DVB-S2X NIM(45308X FBC)' 'Tuner A'
22:17:42.9551 [OpenWebif] -D- tuner '1' 'DVB-S2X NIM(45308X FBC)' 'Tuner B'
22:17:42.9553 [OpenWebif] -D- tuner '2' 'DVB-S2X NIM(45308X FBC)' 'Tuner C'
22:17:42.9554 [OpenWebif] -D- tuner '3' 'DVB-S2X NIM(45308X FBC)' 'Tuner D'
22:17:42.9556 [OpenWebif] -D- tuner '4' 'DVB-S2X NIM(45308X FBC)' 'Tuner E'
22:17:42.9557 [OpenWebif] -D- tuner '5' 'DVB-S2X NIM(45308X FBC)' 'Tuner F'
22:17:42.9558 [OpenWebif] -D- tuner '6' 'DVB-S2X NIM(45308X FBC)' 'Tuner G'
22:17:42.9559 [OpenWebif] -D- tuner '7' 'DVB-S2X NIM(45308X FBC)' 'Tuner H'
22:17:42.9560 [OpenWebif] -D- tuner '8' 'GIGA DVB-S2X NIM (TS3L10)' 'Tuner I'
22:17:42.9562 [OpenWebif] -D- tuner '9' 'GIGA DVB-S2X NIM (TS3L10)' 'Tuner J'
22:17:42.9643 'NoneType' object has no attribute 'info'
22:17:42.9696 "::ffff:123.456.789.12" - - [19/Apr/2022:20:17:42 +0000] "GET /web/about HTTP/1.1" 200 2422 "-" "Dalvik/2.1.0 (Linux; U; Android 12; Pixel 4a Build/SP2A.220405.003)"
22:17:42.9702 [eDVBFrontend] [SEC] tuner 0 sleep 150ms
22:17:43.0628 "::ffff:123.456.789.12" - - [19/Apr/2022:20:17:42 +0000] "GET /web/powerstate HTTP/1.1" 200 105 "-" "Dalvik/2.1.0 (Linux; U; Android 12; Pixel 4a Build/SP2A.220405.003)"
22:17:43.2237 [SEC] tuner 0 sendDiseqc: e01038f422:17:43.2241 [eDVBFrontend] [SEC] diseqc ioctl duration: 103 ms22:17:43.2243 [eDVBFrontend] [SEC] tuner 0 sleep 100ms
22:17:43.2244 [eDVBServiceStream] stop streaming m_state 0
22:17:43.2245 [eDVBServiceStream] stop streaming m_state 0
22:17:43.3244 [eDVBFrontend] [SEC] tuner 0 sleep 70ms
22:17:43.4985 [SEC] tuner 0 sendDiseqc: e01038f422:17:43.4986 [eDVBFrontend] [SEC] diseqc ioctl duration: 104 ms22:17:43.4986 [eDVBFrontend] [SEC] tuner 0 sleep 100ms
22:17:43.5987 [SEC] update current switch params
22:17:43.5988 [SEC] tuner 0 startTuneTimeout 5000
22:17:43.5989 [SEC] tuner 0 setFrontend: events enabled
22:17:43.5990 setting frontend 0 events: on
22:17:43.5991 [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
22:17:43.5991 [eDVBFrontend] [SEC] tuner 0 sleep 500ms
22:17:43.6990 [eDVBFrontend] (0)fe event: status 1, inversion off, m_tuning 2
22:17:44.0995 [SEC] set dynamic current limiting
22:17:44.6242 [DVBCAHandler] no more services
22:17:45.6246 [eDVBResourceManager] release cached channel (timer timeout)
22:17:45.6248 [eDVBLocalTimerHandler] Remove channel 0xc8eaa0.
22:17:45.6252 [eEPGTransponderDataReader] remove channel 0xc8eaa0
22:17:45.6253 [eEPGChannelData] abort caching events !!
22:17:45.6272 [eDVBResourceManager] stop release channel timer
22:17:48.6282 [eDVBFrontend] try to close frontend 0
22:17:48.6284 [eDVBFrontend0] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2
22:18:25.0179 [eDVBServiceStream] stream ref: 1:0:19:283D:3FB:1:C00000:0:0:0:
22:18:25.0181 [eDVBResourceManager] allocate channel.. 03fb:0001
22:18:25.0181 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0182 [eDVBSatelliteEquipmentControl] canTune 1
22:18:25.0182 [eDVBSatelliteEquipmentControl] lnb 0 found
22:18:25.0182 [eDVBSatelliteEquipmentControl] 0 option(s) at position 192
22:18:25.0183 [eDVBSatelliteEquipmentControl] final score 0
22:18:25.0183 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0183 [eDVBSatelliteEquipmentControl] canTune 2
22:18:25.0183 [eDVBSatelliteEquipmentControl] final score 0
22:18:25.0184 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0184 [eDVBSatelliteEquipmentControl] canTune 8
22:18:25.0184 [eDVBSatelliteEquipmentControl] final score 0
22:18:25.0185 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0185 [eDVBSatelliteEquipmentControl] canTune 16
22:18:25.0185 [eDVBSatelliteEquipmentControl] final score 0
22:18:25.0185 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0186 [eDVBSatelliteEquipmentControl] canTune 32
22:18:25.0186 [eDVBSatelliteEquipmentControl] final score 0
22:18:25.0186 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0186 [eDVBSatelliteEquipmentControl] canTune 64
22:18:25.0187 [eDVBSatelliteEquipmentControl] final score 0
22:18:25.0187 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0187 [eDVBSatelliteEquipmentControl] canTune 128
22:18:25.0187 [eDVBSatelliteEquipmentControl] final score 0
22:18:25.0188 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0188 [eDVBSatelliteEquipmentControl] canTune 256
22:18:25.0188 [eDVBSatelliteEquipmentControl] lnb 1 found
22:18:25.0188 [eDVBSatelliteEquipmentControl] 1 option(s) at position 192
22:18:25.0189 [eDVBSatelliteEquipmentControl] sat 192 found
22:18:25.0189 ret1 10000
22:18:25.0189 [eDVBSatelliteEquipmentControl] ret5 10000
22:18:25.0189 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:18:25.0189 [eDVBSatelliteEquipmentControl] score new 10000
22:18:25.0190 [eDVBSatelliteEquipmentControl] final score 10005
22:18:25.0190 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0190 [eDVBSatelliteEquipmentControl] canTune 512
22:18:25.0190 [eDVBSatelliteEquipmentControl] final score 0
22:18:25.0193 [eDVBFrontend] m_need_delivery_system_workaround = 0
22:18:25.0193 [eDVBFrontend] opening frontend 8
22:18:25.0415 [eDVBFrontend8] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2
22:18:25.0522 tune tuner 8
22:18:25.0523 [eDVBSatelliteEquipmentControl] direct_connected 1
22:18:25.0523 [eDVBSatelliteEquipmentControl] canTune 256
22:18:25.0523 [eDVBSatelliteEquipmentControl] lnb 1 found
22:18:25.0524 [eDVBSatelliteEquipmentControl] 1 option(s) at position 192
22:18:25.0524 [eDVBSatelliteEquipmentControl] sat 192 found
22:18:25.0524 ret1 10000
22:18:25.0524 [eDVBSatelliteEquipmentControl] ret5 10000
22:18:25.0524 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:18:25.0525 [eDVBSatelliteEquipmentControl] score new 10000
22:18:25.0525 [eDVBSatelliteEquipmentControl] final score 10005
22:18:25.0525 has_external_rotor /home/root/rotor_8 no
22:18:25.0525 [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
22:18:25.0526 [eDVBFrontend8] prepare_sat System 1 Freq 11493750 Pol 0 SR 22000000 INV 2 FEC 2 orbpos 192 system 1 modulation 2 pilot 2, rolloff 0, is_id -1, pls_mode 1, pls_code 0, t2mi_plp_id -1 t2mi_pid 4096
22:18:25.0526 tuning to 1743 MHz
22:18:25.0526 [eDVBChannel] OURSTATE: tuner 8 tuning
22:18:25.0526 [eDVBServicePMTHandler] allocate Channel: res 0
22:18:25.0527 [eDVBCIInterfaces] addPMTHandler 1:0:19:283D:3FB:1:C00000:0:0:0:
22:18:25.0527 [eDVBChannel] getDemux cap=00
22:18:25.0527 [eDVBResourceManager] allocate demux cap=00
22:18:25.0527 [eDVBResourceManager] allocating demux adapter=0, demux=0, source=0
22:18:25.0528 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:25.0529 [eDVBServiceStream] try it again when we are tuned in
22:18:25.0530 [SEC] set static current limiting
22:18:25.0531 [eDVBFrontend] [SEC] tuner 8 setVoltage 3
22:18:25.0532 [eDVBFrontend8] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 1 FE_SET_VOLTAGE 0
22:18:25.0634 [eDVBFrontend] [SEC] tuner 8 sleep 200ms
22:18:25.2635 [SEC] invalidate current switch params
22:18:25.2855 [SEC] tuner 8 sendDiseqc: e00000(DiSEqC reset)22:18:25.2856 [eDVBFrontend] [SEC] diseqc ioctl duration: 21 ms -> extra guard delay 31 ms22:18:25.3166 [eDVBFrontend] [SEC] tuner 8 sleep 50ms
22:18:25.3885 [SEC] tuner 8 sendDiseqc: e00003(DiSEqC peripherial power on)22:18:25.3886 [eDVBFrontend] [SEC] diseqc ioctl duration: 21 ms -> extra guard delay 31 ms22:18:25.4203 [eDVBFrontend] [SEC] tuner 8 sleep 150ms
22:18:25.5935 [SEC] tuner 8 sendDiseqc: e01038f222:18:25.5936 [eDVBFrontend] [SEC] diseqc ioctl duration: 22 ms -> extra guard delay 44 ms22:18:25.6376 [eDVBFrontend] [SEC] tuner 8 sleep 100ms
22:18:25.7377 [eDVBFrontend] [SEC] tuner 8 sleep 70ms
22:18:25.8295 [SEC] tuner 8 sendDiseqc: e01038f222:18:25.8298 [eDVBFrontend] [SEC] diseqc ioctl duration: 21 ms -> extra guard delay 45 ms22:18:25.8749 [eDVBFrontend] [SEC] tuner 8 sleep 100ms
22:18:25.9750 [SEC] update current switch params
22:18:25.9751 [SEC] tuner 8 startTuneTimeout 5000
22:18:25.9752 [SEC] tuner 8 setFrontend: events enabled
22:18:25.9752 setting frontend 8 events: on
22:18:25.9844 [eDVBFrontend] (8)fe event: status 0, inversion off, m_tuning 1
22:18:25.9845 [eDVBFrontend] [SEC] tuner 8 sleep 500ms
22:18:26.3394 [eDVBFrontend] (8)fe event: status 10, inversion off, m_tuning 2
22:18:26.3395 [eDVBChannel] OURSTATE: tuner 8 ok
22:18:26.3396 [eDVBLocalTimerHandler] Channel 0xc8eaa0 running.
22:18:26.3396 [eDVBChannel] getDemux cap=00
22:18:26.3397 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3397 [eDVBSectionReader] DMX_SET_FILTER pid=20
22:18:26.3401 [eEPGTransponderDataReader] channel 0xc8eaa0 running
22:18:26.3402 [eDVBChannel] getDemux cap=00
22:18:26.3402 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3403 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3403 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3404 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3405 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3405 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3405 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3406 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3406 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3406 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3407 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3407 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3408 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3408 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3408 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3409 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3409 [eDVBResourceManager] stop release channel timer
22:18:26.3410 [eDVBChannel] getDemux cap=01
22:18:26.3410 [eEPGChannelData] next update in 45 min
22:18:26.3410 [eDVBResourceManager] allocate demux cap=01
22:18:26.3410 [eDVBResourceManager] allocating shared demux adapter=0, demux=0, source=8
22:18:26.3411 [eDVBServicePMTHandler] ok ... now we start!!
22:18:26.3411 [eDVBServiceStream] STREAM service event 5
22:18:26.3411 [eDVBServiceStream] try it again when we are tuned in
22:18:26.3412 [eDVBCAService] new service 1:0:19:283D:3FB:1:C00000:0:0:0:
22:18:26.3412 [eDVBCAService] add demux 0 to slot 0 service 1:0:19:283D:3FB:1:C00000:0:0:0:
22:18:26.3414 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3415 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:18:26.3419 [eDVBServiceStream] STREAM service event 6
22:18:26.3419 [eDVBServiceStream] tuned.. m_state 1 m_want_record 1
22:18:26.3420 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3420 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:18:26.3423 [eDVBRecordStreamThread] allocated 40 buffers of 192 kB
22:18:26.3424 [eDVBServiceStream] start streaming...
22:18:26.3424 [eDVBServiceStream] no cached pids found
22:18:26.3425 [eDVBServiceStream] have 1 video stream(s) (13ed), and 1 audio stream(s) (13f2), and the pcr pid is 13ed, and the text pid is 13f022:18:26.3427 [eDVBServiceStream] ADD PID: 0000
22:18:26.3427 [eDVBServiceStream] ADD PID: 0012
22:18:26.3427 [eDVBServiceStream] ADD PID: 0014
22:18:26.3427 [eDVBServiceStream] ADD PID: 13ed
22:18:26.3428 [eDVBServiceStream] ADD PID: 13f0
22:18:26.3428 [eDVBServiceStream] ADD PID: 13f2
22:18:26.3434 [setIoPrio] realtime level 7 ok
22:18:26.3435 [eFilePushThreadRecorder] THREAD START
22:18:26.3439 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.3439 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:18:26.4355 [eDVBServicePMTHandler] PATready
22:18:26.4356 [eDVBServicePMTHandler] PAT TSID: 0x03fb (1019)
22:18:26.4356 [eDVBServicePMTHandler] use pmtpid 13ec for service_id 283d
22:18:26.4357 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.4358 [eDVBSectionReader] DMX_SET_FILTER pid=5100
22:18:26.4364 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.4365 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:18:26.4376 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.4377 [eDVBSectionReader] DMX_SET_FILTER pid=5100
22:18:26.4385 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.4386 [eDVBSectionReader] DMX_SET_FILTER pid=17
22:18:26.4846 [SEC] set dynamic current limiting
22:18:26.6042 [eDVBLocalTimerHandler] Transponder time is 19/04/2022 22:18:26.
22:18:26.6043 [eDVBLocalTimerHandler] Difference is 0.
22:18:26.6043 [eDVBLocalTimerHandler] Difference less than 120, use transponder time.
22:18:26.6043 [eDVBLocalTimerHandler] Not changed.
22:18:26.6046 [eDVBChannel] getDemux cap=00
22:18:26.6604 [eDVBServiceStream] STREAM service event 5
22:18:26.6605 [eDVBServiceStream] start streaming...
22:18:26.6605 [eDVBServiceStream] no cached pids found
22:18:26.6608 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.6609 [eDVBSectionReader] DMX_SET_FILTER pid=1170
22:18:26.6616 [eDVBServiceStream] have 1 video stream(s) (13ed), and 3 audio stream(s) (13ee, 13ef, 13f2) (13f1), and the pcr pid is 13ed, and the text pid is 13f022:18:26.6618 [eDVBServiceStream] ADD PID: 0492
22:18:26.6619 [eDVBServiceStream] ADD PID: 13ec
22:18:26.6620 [eDVBServiceStream] ADD PID: 13ee
22:18:26.6622 [eDVBServiceStream] ADD PID: 13ef
22:18:26.6623 [eDVBServiceStream] ADD PID: 13f1
22:18:26.6624 [eDVBCIInterfaces] gotPMT
22:18:26.6624 [eDVBCAService] don't build/send the same CA PMT twice
22:18:26.6626 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.6626 [eDVBSectionReader] DMX_SET_FILTER pid=5100
22:18:26.6637 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.6638 [eDVBSectionReader] DMX_SET_FILTER pid=5110
22:18:26.8853 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:26.8855 [eDVBSectionReader] DMX_SET_FILTER pid=5120
22:18:27.0302 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:27.0304 [eDVBSectionReader] DMX_SET_FILTER pid=5130
22:18:27.6595 [eDVBServicePMTHandler] sdt update done!
22:18:27.6600 [eDVBServicePMTHandler] AITready
22:18:27.6601 [eDVBServicePMTHandler] Section Length : 1007, Total Section Length : 1010
22:18:27.6601 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 1
22:18:27.6601 [eDVBServicePMTHandler] last_section_number 1 > section_number 0
22:18:27.6602 [eDVBServicePMTHandler] Save Data Len : [1010]
22:18:27.6603 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:27.6604 [eDVBSectionReader] DMX_SET_FILTER pid=5172
22:18:27.6608 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 2
22:18:27.6608 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 4
22:18:27.6610 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:27.6611 [eDVBSectionReader] DMX_SET_FILTER pid=5172
22:18:27.6613 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 5
22:18:27.6614 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 20
22:18:27.6615 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 3
22:18:27.6615 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 35
22:18:27.6615 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 39
22:18:27.6616 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 192
22:18:27.6616 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 155
22:18:27.6617 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 157
22:18:27.6617 [eDVBServicePMTHandler] Section Length : 107, Total Section Length : 110
22:18:27.6617 [eDVBServicePMTHandler] found applicaions ids >> pid : adfb7bb8, orgid : 19, appid : 222
22:18:27.6617 [eDVBServicePMTHandler] Found : control[1], name[HBBTV-Start-DSMCC+Web], url[http://itv.ard.de/ardstart/index.html]
22:18:27.6618 [eDVBServicePMTHandler] Found : control[2], name[EPG], url[http://itv.ard.de/ardepg/index.php]
22:18:27.6618 [eDVBServicePMTHandler] Found : control[2], name[VTX-Mandant-ARD-Text-Web+DSMCC], url[http://vtx.ard.de/app/_ard.html]
22:18:27.6618 [eDVBServicePMTHandler] Found : control[2], name[Tagesschau], url[http://hbbtv.tagesschau.de/index.html]
22:18:27.6618 [eDVBServicePMTHandler] Found : control[2], name[Mediathek DasErste], url[http://tv.ardmediathek.de/index.html?client=daserste]
22:18:27.6619 [eDVBServicePMTHandler] Found : control[2], name[HBBTV ARD-Mediathek], url[http://tv.ardmediathek.de/index.html]
22:18:27.6619 [eDVBServicePMTHandler] Found : control[2], name[BR-Mediathek], url[http://hbbtv-mediathek.br.de/index.html]
22:18:27.6619 [eDVBServicePMTHandler] Found : control[2], name[Sportschau], url[http://tv.ardmediathek.de/index.html?client=sportschau]
22:18:27.6620 [eDVBServicePMTHandler] Found : control[2], name[Replay], url[http://itv.ard.de/replay/index.php]
22:18:27.6620 [eDVBServicePMTHandler] Found : control[2], name[Replay Test], url[http://itv.ard.de/replay/index.php]
22:18:27.6620 [eDVBServicePMTHandler] Found : control[2], name[KiKA-Player ARD], url[http://cdn.hbbtv.kika.de/player/index.php?ard=1]
22:18:27.6620 [eDVBServicePMTHandler] Found : control[2], name[Teaser Test App], url[http://itv.mit-xperts.com/ardstart/index.php]
22:18:27.6621 [eDVBServiceStream] STREAM service event 11
22:18:27.6842 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:18:27.6843 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:18:29.1777 [eDVBServicePMTHandler] OCready
22:19:29.4580 [eDVBServiceStream] stop streaming m_state 2
22:19:29.4595 [eFilePushThreadRecorder] stopping thread.
22:19:29.4598 [eDVBRecordStreamThread] cancelling aio
22:19:29.4599 [eDVBRecordStreamThread] not all cancelled
22:19:29.4599 [eDVBRecordFileThread] waiting for aio to complete
22:19:29.4599 [eDVBRecordFileThread] Waiting for I/O to complete
22:19:29.4682 [eDVBRecordFileThread] buffer usage histogram (40 buffers of 192 kB)
22:19:29.4682 [eDVBRecordFileThread]   1:    653
22:19:29.4682 [eDVBRecordFileThread]   2:      2
22:19:29.4682 [eDVBRecordFileThread]   3:      1
22:19:29.4683 [eFilePushThreadRecorder] THREAD STOP
22:19:29.4685 [eDVBServiceStream] stop streaming m_state 0
22:19:29.4703 [eDVBCAService] free slot 0 demux 0 for service 1:0:19:283D:3FB:1:C00000:0:0:0:
22:19:29.4703 [eDVBCAService] free service 1:0:19:283D:3FB:1:C00000:0:0:0:
22:19:29.4709 [eDVBResourceManager] start release channel timer
22:19:29.5462 [eDVBServiceStream] stream ref: 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:19:29.5467 [eDVBLocalTimerHandler] Remove channel 0xc8eaa0.
22:19:29.5471 [eEPGTransponderDataReader] remove channel 0xc8eaa0
22:19:29.5541 [eDVBResourceManager] allocate channel.. 0802:0002
22:19:29.5560 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5560 [eDVBSatelliteEquipmentControl] canTune 1
22:19:29.5561 [eDVBSatelliteEquipmentControl] lnb 0 found
22:19:29.5561 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
22:19:29.5561 [eDVBSatelliteEquipmentControl] sat 282 found
22:19:29.5561 ret1 10000
22:19:29.5562 [eDVBSatelliteEquipmentControl] ret5 10000
22:19:29.5562 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:19:29.5562 [eDVBSatelliteEquipmentControl] score new 10000
22:19:29.5562 [eDVBSatelliteEquipmentControl] final score 10005
22:19:29.5563 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5563 [eDVBSatelliteEquipmentControl] canTune 2
22:19:29.5563 [eDVBSatelliteEquipmentControl] final score 0
22:19:29.5563 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5564 [eDVBSatelliteEquipmentControl] canTune 8
22:19:29.5568 [eDVBSatelliteEquipmentControl] final score 0
22:19:29.5571 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5574 [eDVBSatelliteEquipmentControl] canTune 16
22:19:29.5577 [eDVBSatelliteEquipmentControl] final score 0
22:19:29.5579 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5582 [eDVBSatelliteEquipmentControl] canTune 32
22:19:29.5588 [eDVBSatelliteEquipmentControl] final score 0
22:19:29.5591 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5593 [eDVBSatelliteEquipmentControl] canTune 64
22:19:29.5597 [eDVBSatelliteEquipmentControl] final score 0
22:19:29.5599 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5602 [eDVBSatelliteEquipmentControl] canTune 128
22:19:29.5607 [eDVBSatelliteEquipmentControl] final score 0
22:19:29.5609 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5609 [eDVBSatelliteEquipmentControl] canTune 256
22:19:29.5610 [eDVBSatelliteEquipmentControl] lnb 1 found
22:19:29.5610 [eDVBSatelliteEquipmentControl] 0 option(s) at position 282
22:19:29.5610 [eDVBSatelliteEquipmentControl] final score 0
22:19:29.5610 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5611 [eDVBSatelliteEquipmentControl] canTune 512
22:19:29.5611 [eDVBSatelliteEquipmentControl] final score 0
22:19:29.5615 [eDVBFrontend] m_need_delivery_system_workaround = 0
22:19:29.5617 [eDVBFrontend] opening frontend 0
22:19:29.5640 [eDVBFrontend0] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2
22:19:29.5671 tune tuner 0
22:19:29.5682 [eDVBSatelliteEquipmentControl] direct_connected 1
22:19:29.5692 [eDVBSatelliteEquipmentControl] canTune 1
22:19:29.5701 [eDVBSatelliteEquipmentControl] lnb 0 found
22:19:29.5709 [eDVBSatelliteEquipmentControl] 1 option(s) at position 282
22:19:29.5709 [eDVBSatelliteEquipmentControl] sat 282 found
22:19:29.5710 ret1 10000
22:19:29.5710 [eDVBSatelliteEquipmentControl] ret5 10000
22:19:29.5711 [eDVBSatelliteEquipmentControl] ret 10000, score old 0
22:19:29.5711 [eDVBSatelliteEquipmentControl] score new 10000
22:19:29.5712 [eDVBSatelliteEquipmentControl] final score 10005
22:19:29.5712 has_external_rotor /home/root/rotor_0 no
22:19:29.5713 [eDVBSatelliteEquipmentControl] RotorCmd ffffffff, lastRotorCmd ffffffff
22:19:29.5713 [eDVBFrontend0] prepare_sat System 1 Freq 10847000 Pol 1 SR 23000000 INV 2 FEC 3 orbpos 282 system 1 modulation 2 pilot 2, rolloff 1, is_id -1, pls_mode 1, pls_code 0, t2mi_plp_id -1 t2mi_pid 4096
22:19:29.5720 tuning to 1097 MHz
22:19:29.5730 [eDVBChannel] OURSTATE: tuner 0 tuning
22:19:29.5739 [eDVBServicePMTHandler] allocate Channel: res 0
22:19:29.5747 [eDVBCIInterfaces] addPMTHandler 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:19:29.5759 [eDVBChannel] getDemux cap=00
22:19:29.5760 [eDVBResourceManager] allocate demux cap=00
22:19:29.5760 [eDVBResourceManager] allocating demux adapter=0, demux=0, source=8
22:19:29.5761 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:29.5763 [eDVBServiceStream] try it again when we are tuned in
22:19:29.5781 [SEC] set static current limiting
22:19:29.5810 [eDVBFrontend] [SEC] tuner 0 setVoltage 3
22:19:29.5811 [eDVBFrontend0] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 1 FE_SET_VOLTAGE 0
22:19:29.8582 [eDVBFrontend] [SEC] tuner 0 sleep 200ms
22:19:30.0584 [SEC] invalidate current switch params
22:19:30.1482 [SEC] tuner 0 sendDiseqc: e00000(DiSEqC reset)22:19:30.1485 [eDVBFrontend] [SEC] diseqc ioctl duration: 89 ms22:19:30.1487 [eDVBFrontend] [SEC] tuner 0 sleep 50ms
22:19:30.2886 [SEC] tuner 0 sendDiseqc: e00003(DiSEqC peripherial power on)22:19:30.2889 [eDVBFrontend] [SEC] diseqc ioctl duration: 89 ms22:19:30.2894 [eDVBFrontend] [SEC] tuner 0 sleep 150ms
22:19:30.5428 [SEC] tuner 0 sendDiseqc: e01038f422:19:30.5431 [eDVBFrontend] [SEC] diseqc ioctl duration: 103 ms22:19:30.5433 [eDVBFrontend] [SEC] tuner 0 sleep 100ms
22:19:30.6433 [eDVBFrontend] [SEC] tuner 0 sleep 70ms
22:19:30.8169 [SEC] tuner 0 sendDiseqc: e01038f422:19:30.8172 [eDVBFrontend] [SEC] diseqc ioctl duration: 103 ms22:19:30.8178 [eDVBFrontend] [SEC] tuner 0 sleep 100ms
22:19:30.9179 [SEC] update current switch params
22:19:30.9180 [SEC] tuner 0 startTuneTimeout 5000
22:19:30.9181 [SEC] tuner 0 setFrontend: events enabled
22:19:30.9181 setting frontend 0 events: on
22:19:30.9182 [eDVBFrontend] (0)fe event: status 0, inversion off, m_tuning 1
22:19:30.9183 [eDVBFrontend] [SEC] tuner 0 sleep 500ms
22:19:31.0180 [eDVBFrontend] (0)fe event: status 1f, inversion off, m_tuning 2
22:19:31.0181 [eDVBChannel] OURSTATE: tuner 0 ok
22:19:31.0182 [eDVBLocalTimerHandler] Channel 0xc8eaa0 running.
22:19:31.0182 [eDVBChannel] getDemux cap=00
22:19:31.0183 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0183 [eDVBSectionReader] DMX_SET_FILTER pid=20
22:19:31.0188 [eEPGTransponderDataReader] channel 0xc8eaa0 running
22:19:31.0189 [eDVBChannel] getDemux cap=00
22:19:31.0189 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0190 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0190 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0191 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0191 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0191 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0192 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0192 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0192 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0193 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0193 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0194 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0194 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0195 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0195 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0195 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0196 [eDVBResourceManager] stop release channel timer
22:19:31.0196 [eDVBChannel] getDemux cap=01
22:19:31.0197 [eDVBResourceManager] allocate demux cap=01
22:19:31.0196 [eEPGChannelData] next update in 2 sec
22:19:31.0197 [eDVBResourceManager] allocating shared demux adapter=0, demux=0, source=0
22:19:31.0197 [eDVBServicePMTHandler] ok ... now we start!!
22:19:31.0198 [eDVBServiceStream] STREAM service event 5
22:19:31.0198 [eDVBServiceStream] try it again when we are tuned in
22:19:31.0199 [eDVBCAService] new service 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:19:31.0199 [eDVBCAService] add demux 0 to slot 0 service 1:0:19:1B1C:802:2:11A0000:0:0:0:
22:19:31.0201 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0201 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:19:31.0206 [eDVBServiceStream] STREAM service event 6
22:19:31.0206 [eDVBServiceStream] tuned.. m_state 1 m_want_record 1
22:19:31.0207 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0207 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:19:31.0211 [eDVBRecordStreamThread] allocated 40 buffers of 192 kB
22:19:31.0211 [eDVBServiceStream] start streaming...
22:19:31.0212 [eDVBServiceStream] no cached pids found
22:19:31.0212 [eDVBServiceStream] have 1 video stream(s) (157c), and 1 audio stream(s) (157d), and the pcr pid is 157c, and the text pid is 157f22:19:31.0214 [eDVBServiceStream] ADD PID: 0000
22:19:31.0214 [eDVBServiceStream] ADD PID: 0012
22:19:31.0214 [eDVBServiceStream] ADD PID: 0014
22:19:31.0215 [eDVBServiceStream] ADD PID: 157c
22:19:31.0215 [eDVBServiceStream] ADD PID: 157d
22:19:31.0215 [eDVBServiceStream] ADD PID: 157f
22:19:31.0220 [setIoPrio] realtime level 7 ok
22:19:31.0221 [eFilePushThreadRecorder] THREAD START
22:19:31.0225 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0225 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:19:31.0353 [eDVBServicePMTHandler] PATready
22:19:31.0354 [eDVBServicePMTHandler] PAT TSID: 0x0802 (2050)
22:19:31.0355 [eDVBServicePMTHandler] use pmtpid 0102 for service_id 1b1c
22:19:31.0355 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0356 [eDVBSectionReader] DMX_SET_FILTER pid=258
22:19:31.0360 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0361 [eDVBSectionReader] DMX_SET_FILTER pid=0
22:19:31.0368 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0369 [eDVBSectionReader] DMX_SET_FILTER pid=266
22:19:31.0372 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.0372 [eDVBSectionReader] DMX_SET_FILTER pid=17
22:19:31.2380 [eDVBServiceStream] STREAM service event 5
22:19:31.2380 [eDVBServiceStream] start streaming...
22:19:31.2380 [eDVBServiceStream] no cached pids found
22:19:31.2386 [eDVBServiceStream] have 1 video stream(s) (157c), and 2 audio stream(s) (157e, 157d) (1580, 157f), and the pcr pid is 157c, and the text pid is 157f22:19:31.2387 [eDVBServiceStream] ADD PID: 0102
22:19:31.2389 [eDVBServiceStream] ADD PID: 157e
22:19:31.2390 [eDVBServiceStream] ADD PID: 1580
22:19:31.2391 [eDVBCIInterfaces] gotPMT
22:19:31.2391 [eDVBCAService] don't build/send the same CA PMT twice
22:19:31.2392 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.2393 [eDVBSectionReader] DMX_SET_FILTER pid=258
22:19:31.2399 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.2400 [eDVBSectionReader] DMX_SET_FILTER pid=258
22:19:31.4185 [SEC] set dynamic current limiting
22:19:31.4381 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.4382 [eDVBSectionReader] DMX_SET_FILTER pid=260
22:19:31.6409 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.6411 [eDVBSectionReader] DMX_SET_FILTER pid=256
22:19:31.8397 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.8399 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:19:31.8436 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:31.8437 [eDVBSectionReader] DMX_SET_FILTER pid=259
22:19:32.0336 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:32.0338 [eDVBSectionReader] DMX_SET_FILTER pid=268
22:19:32.3441 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:32.3443 [eDVBSectionReader] DMX_SET_FILTER pid=262
22:19:32.4398 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:32.4400 [eDVBSectionReader] DMX_SET_FILTER pid=257
22:19:32.5538 [eDVBFrontend] try to close frontend 8
22:19:32.5855 [eDVBFrontend8] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2
22:19:32.6390 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:32.6392 [eDVBSectionReader] DMX_SET_FILTER pid=263
22:19:32.6403 [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:19:32.6404 [eDVBSectionReader] DMX_SET_FILTER pid=269
22:19:32.7660 [eDVBServicePMTHandler] sdt update done!
22:19:33.0204 [eEPGChannelData] start reading events(1650399573)
22:19:33.0205 [eDVBSectionReader] DMX_SET_FILTER pid=3842
22:19:33.0209 [eDVBSectionReader] DMX_SET_FILTER pid=3003
22:19:33.0212 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:19:33.0219 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:19:33.0228 [eDVBSectionReader] DMX_SET_FILTER pid=18
22:19:33.0239 [eDVBSectionReader] DMX_SET_FILTER pid=5000
22:19:33.0243 [eDVBSectionReader] DMX_SET_FILTER pid=5000
22:19:33.0248 [huffman] read.. '/usr/share/enigma2/otv_011a0000_0002_0802.dict'
22:19:33.0249 [eEPGChannelData] abort non avail OpenTV EIT reading
22:19:33.0249 [eDVBSectionReader] DMX_SET_FILTER pid=57
22:19:36.0435 [eDVBLocalTimerHandler] Transponder time is 19/04/2022 22:19:36.
22:19:36.0435 [eDVBLocalTimerHandler] Difference is 0.
22:19:36.0436 [eDVBLocalTimerHandler] Difference less than 120, use transponder time.
22:19:36.0436 [eDVBLocalTimerHandler] Not changed.
22:19:36.0438 [eDVBChannel] getDemux cap=00
22:19:40.0252 [eEPGChannelData] abort non avail schedule reading
22:19:40.0263 [eEPGChannelData] abort non avail schedule other reading
22:19:40.0269 [eEPGChannelData] abort non avail netmed schedule reading
22:19:40.0273 [eEPGChannelData] abort non avail netmed schedule other reading
22:19:40.0274 [eEPGChannelData] abort non avail viasat reading
22:19:40.1279 [eEPGChannelData] nownext finished(1650399580)

 

[quatschkatze]

Dimitrij

 

In OpenATV i stumbled upon the option "Force LNB Power", which is off by default. I didn't see such an option in the TeamBlue image. I activated the option:

config.tunermisc.forceLnbPower=True

And now the switching seems to be working reliably.

 

As this option isn't on by default and there's no GUI in some images for it i guess it is a workaround, rather than a "clean solution". So this option has to be on for streaming, but not for watching tv? Does this observation make sense to you?

 

If there is any additional information you need I'd be happy to provide it. If one of the developers needs the (Gigablue UHD Quad 4k) box or the tuner (GIGA DVB-S2X NIM (TS3L10) (DVB-S2X)) for testing, feel free to send my a PM, so I could mail it to you.

[Abu Baniaz]

Before I stopped using my GB UE 4K owing to HDMI fault, I too would get a dying out stream. I was using an LNB, so power would come from receiver, not an external power supply that people with switches would use.

 

Maybe that power commit of mine should be reverted, so the LNB power option is visible again. I have not checked if it was before or after GB changes their drivers. i.e, use new BCM SDK, those drivers stopped USB tuners from working.

[Dimitrij]

quatschkatze

Plugin - Power LNB/ToneBurst in standby for FBC tuners

This plugin for OpenPli image.

Edited by Dimitrij, 20 April 2022 - 05:31.

[Dimitrij]

You can test with a multimeter first(not use plugin).

 

Not standby:

 

Live TV Tuner I

LNB power Tuner B

 

Live TV Tuner B / LNB power Tuner B

 

 

Standby:

 

Stream Tuner I

LNB power Tuner B

 

Stream Tuner B(not work) / LNB power Tuner B

and

Stream Tuner B(work) / LNB power Tuner B

Edited by Dimitrij, 20 April 2022 - 06:48.

[quatschkatze]

Dimitrij

 

How to test with the multimeter?

Do I understand correctly, that in each of the states I should remove the coax cable from the multiswitch and then measure the voltage between the inner conductor and the outer wire?

[Dimitrij]

 

 

How to test with the multimeter?

Do I understand correctly, that in each of the states I should remove the coax cable from the multiswitch and then measure the voltage between the inner conductor and the outer wire?

Yes.

If you are of course interested.

And connect the cable, just in case, only in standby mode, when there is no power to the LNB, that is, the tuner is not active.

[quatschkatze]

Thank you for the explanations. I will report back presumably on Saturday.

[quatschkatze]

I just connected the Tuner I directly to Horizontal-Low cable of the Quattro-LNB (to receive Das Erste HD on Astra 19.2E) and Tuner A directly to the Vertical-Low cable of the Quattro LNB (to receivee BBC Two HD on Astra 28.2E).

 

Even with

config.tunermisc.forceLnbPower=False

the streaming switching between the two channels works reliable this way. So I guess the bug might have to do with DiSEqC switching timings.

 

 

I will report back the multimeter measurements soon.

[Dimitrij]

Fine tuning DiSEqC install plugin SatelliteEquipmentControl.

[quatschkatze]

Fine tuning DiSEqC install plugin SatelliteEquipmentControl.

 

Thank you. I will take a look at it.



All Measurements on OpenATV 7.0.20220419 (2022-04-18) without the enigma2-plugin-systemplugins-forceFbcLNBpower_1.0_all.ipk, but with OpenATV Force LNB Power option set to No (or to Yes later).

- Force LNB Power: No



- Live-TV:
   

Das Erste HD on Tuner I. Voltage on Tuner A: 0 V

BBC Two HD on Tuner A, Voltage: 13.5 V continously lasting.


---


Standby, no Live-TV:

Streaming Das Erste HD on Tuner I. Voltage on Tuner A: 0 V


Streaming BBC Two HD on Tuner A. Voltage on Tuner A: 13.5 V, drops off after some seconds to 0 V.


Non-working Stream BBC Two HD on Tuner A. Voltage on Tuner A: 0 V - when should I measure the voltage? After the moment DreamPlayer throws an error it is 0 V. I don't know about the time before, because how can I know it will work or not? Maybe I could switch streaming from Das Erste HD to BBC Two HD very often and observe the voltage from the beginning - but I haven't done this so far.


Additional test: Streaming Das Erste HD on Tuner I. Voltage on Tuner I is 13.1 V, after some seconds it drops to 0 V.


------------------------------------------------------


Force LNB Power: Yes


- Live-TV:
   

Das Erste HD on Tuner I. Voltage on Tuner A: 13.5 V continously lasting, even when the STB goes to standby.

BBC Two HD on Tuner A, Voltage: 13.5 V continously lasting.


---


Standby, no Live-TV:


Streaming Das Erste HD on Tuner I. Voltage on Tuner A: 13.5 V


Streaming BBC Two HD on Tuner A. Voltage on Tuner A: 13.5 V continously lasting.

[Dimitrij]

So,"Force LNB Power: Yes",solves your problems?

[quatschkatze]

So,"Force LNB Power: Yes",solves your problems?

 

 

On OpenATV with

config.tunermisc.forceLnbPower=True

and on TeamBlue and OpenPli with your

enigma2-plugin-systemplugins-forceFbcLNBpower_1.0_all.ipk

plugin installed and enabled every switching under any circumstances works absolutely reliable. This is true for my minimal testing configuration, as well as my usual 12 satellites configuration.

 

So, for me this is a very good bug workaround I am happy to life with in the long term.

 

---

 

At the same time I do think, that my setup (GB UHD Quad 4k with additional Dual Multistream tuners) and a Technisat 17/8 multiswitch is neither a popular, nor a very rare setup.

The situations this bug occurs (during standby without recordings: Switching between streaming from additional tuner channel to streaming from fixed tuner channel AND even just in maybe on one of four tries) are so special that I guess this bug is probably present in a lot of configurations without ever getting identified.

With streaming from E2 boxes getting more common these days I think the bug should be addressed. The fact that it happens during streaming, but not live tv is what makes this bug nasty.

 

So if any developer wants this box or just the additional tuner for testing feel free to send me a message, so I can send the box/tuner via mail.