IPTV switching streams annoyance

[joeuser]

Quite often, when I switch from one IPTV stream to another, I get a strange behavior. When the second stream finishes buffering, it displays for a very brief time a new picture from the new stream. Then the picture becomes a mix of the last stream blended with the new stream.

It looks like the initial I-frame from the new stream is displayed, then for some reason it uses the last I-frame from the old stream as a reference and uses the new P-frames (and B-frames) from the new stream for the display. Having the effect of the picture blending from the old stream to the new stream (of course only where there is motion.) This continues until a new I-frame comes and then the picture is fine.


playing 4097:0:1:0:0:0:0:0:0:0:http%3a//zzzzzzzzzzzzzzzzzzz/playlist.m3u8:zzzzzzzzzzz
<275639.623034> [eServiceMP3] destruct!
<275639.628328> [eServiceMP3] construct!
getResolvedKey config.mediaplayer.useAlternateUserAgent failed !! (Typo??)
getResolvedKey config.mediaplayer.extraHeaders failed !! (Typo??)
<275639.631926> [eServiceMP3] playbin uri=http://zzzzzzzzzzzzzzzzz/playlist.m3u8
<275639.635435> [eServiceMP3] starting pipeline
resolved to PLAY
<275639.744068> [eServiceMP3] gst_element_query_position failed in getPlayPosition
resolved to PLAY
<275639.749468> [eServiceMP3] gst_element_query_position failed in getPlayPosition
resolved to PLAY
<275639.757758> [eServiceMP3] gst_element_query_position failed in getPlayPosition
RemovePopup, id = ZapError
<275639.803579> [eServiceMP3] state transition NULL -> READY
<275639.804090> [eServiceMP3] GST_STREAM_STATUS_TYPE_CREATE -> setting timeout on souphttpsrc to 60s
<275640.274522> [eServiceMP3] Buffering 2 percent done
<275640.308401> [eServiceMP3] Buffering 4 percent done
<275640.348358> [eServiceMP3] Buffering 5 percent done
<275640.395103> [eServiceMP3] Buffering 6 percent done
<275640.429072> [eServiceMP3] Buffering 7 percent done
<275640.504990> [eServiceMP3] Buffering 8 percent done
<275640.548465> [eServiceMP3] Buffering 9 percent done
<275640.822464> [eServiceMP3] Buffering 10 percent done
<275640.824461> [eServiceMP3] Buffering 11 percent done
<275640.825685> [eServiceMP3] Buffering 12 percent done
<275640.826906> [eServiceMP3] Buffering 13 percent done
<275640.828199> [eServiceMP3] Buffering 14 percent done
<275640.911007> [eServiceMP3] state transition READY -> PAUSED
<275640.914591> [eServiceMP3] loading cuesheet
<275640.914838> [eServiceMP3] cutfile not found!
<275640.950317> [eServiceMP3] async-done - 1 video, 1 audio, 0 subtitle
<275640.950659> [eServiceMP3] AUDIO STRUCT=audio/mpeg
<275640.951030> [eServiceMP3] audio stream=0 codec=MPEG-4 AAC language=und
<275641.122316> [eServiceMP3] Buffering 15 percent done
<275641.135972> [eServiceMP3] Buffering 16 percent done
<275641.137225> [eServiceMP3] Buffering 17 percent done
<275641.138453> [eServiceMP3] Buffering 18 percent done
<275641.197998> [eServiceMP3] Buffering 19 percent done
<275641.223375> [eServiceMP3] Buffering 20 percent done
<275641.224673> [eServiceMP3] Buffering 21 percent done
<275641.225901> [eServiceMP3] Buffering 22 percent done
<275641.227129> [eServiceMP3] Buffering 23 percent done
<275641.287328> [eServiceMP3] Buffering 24 percent done
<275641.288979> [eServiceMP3] Buffering 25 percent done
<275641.290472> [eServiceMP3] Buffering 26 percent done
<275641.303966> [eServiceMP3] Buffering 27 percent done
<275641.305218> [eServiceMP3] Buffering 28 percent done
<275641.306448> [eServiceMP3] Buffering 29 percent done
<275641.331965> [eServiceMP3] Buffering 30 percent done
<275641.333220> [eServiceMP3] Buffering 32 percent done


** (enigma2:2645): WARNING **: unhandled DVBAPI Video Event 9


** (enigma2:2645): WARNING **: unhandled DVBAPI Video Event 5
<275642.007166> [eServiceMP3] Buffering 33 percent done
<275642.613682> [eServiceMP3] state transition PAUSED -> PLAYING
<275642.616002> [eServiceMP3] Buffering 34 percent done
<275642.618997> [eServiceMP3] Buffering 35 percent done
<275642.620056> [eServiceMP3] Buffering 37 percent done
<275642.620630> [eServiceMP3] Buffering 39 percent done
<275643.066358> [eServiceMP3] Buffering 41 percent done
<275643.073382> [eServiceMP3] Buffering 42 percent done
<275643.074060> [eServiceMP3] Buffering 44 percent done
<275643.074644> [eServiceMP3] Buffering 46 percent done
<275643.078242> [eServiceMP3] Buffering 48 percent done
<275643.078880> [eServiceMP3] Buffering 49 percent done
<275643.083010> [eServiceMP3] Buffering 50 percent done
<275643.161358> [eServiceMP3] Buffering 51 percent done
<275643.162011> [eServiceMP3] Buffering 53 percent done
<275643.162611> [eServiceMP3] Buffering 54 percent done
<275643.163981> [eServiceMP3] Buffering 55 percent done
<275643.336544> [eServiceMP3] Buffering 57 percent done
<275643.337167> [eServiceMP3] Buffering 58 percent done
<275643.337754> [eServiceMP3] Buffering 59 percent done
<275643.338343> [eServiceMP3] Buffering 60 percent done
<275643.338925> [eServiceMP3] Buffering 61 percent done
<275643.425629> [eServiceMP3] Buffering 62 percent done
<275643.426263> [eServiceMP3] Buffering 64 percent done
<275643.426846> [eServiceMP3] Buffering 65 percent done
<275643.434576> [eServiceMP3] Buffering 66 percent done
<275643.435382> [eServiceMP3] Buffering 67 percent done
<275643.436171> [eServiceMP3] Buffering 68 percent done
<275643.436725> [eServiceMP3] Buffering 69 percent done
<275643.437305> [eServiceMP3] Buffering 70 percent done
<275643.466038> [eServiceMP3] Buffering 71 percent done
<275643.467032> [eServiceMP3] Buffering 73 percent done
<275643.467711> [eServiceMP3] Buffering 74 percent done
<275643.468311> [eServiceMP3] Buffering 75 percent done
<275643.468869> [eServiceMP3] Buffering 76 percent done
<275643.469595> [eServiceMP3] Buffering 77 percent done
<275643.470186> [eServiceMP3] Buffering 78 percent done
<275643.470774> [eServiceMP3] Buffering 79 percent done
<275643.471472> [eServiceMP3] Buffering 81 percent done
<275643.472075> [eServiceMP3] Buffering 82 percent done
<275643.597460> [eServiceMP3] Buffering 84 percent done
<275643.598095> [eServiceMP3] Buffering 85 percent done
<275643.598687> [eServiceMP3] Buffering 86 percent done
<275643.599673> [eServiceMP3] Buffering 87 percent done
<275643.600293> [eServiceMP3] Buffering 88 percent done
<275643.601217> [eServiceMP3] Buffering 90 percent done
<275643.601823> [eServiceMP3] Buffering 91 percent done
<275643.602404> [eServiceMP3] Buffering 93 percent done
<275643.603113> [eServiceMP3] Buffering 95 percent done
<275643.605135> [eServiceMP3] Buffering 96 percent done
<275643.605728> [eServiceMP3] Buffering 97 percent done
<275643.606310> [eServiceMP3] Buffering 98 percent done
<275643.606889> [eServiceMP3] Buffering 100 percent done
playing 4097:0:1:0:0:0:0:0:0:0:http%3a//xxxxxxx/playlist.m3u8:xxxxx
<276031.183617> [eServiceMP3] destruct!
<276031.188877> [eServiceMP3] construct!
getResolvedKey config.mediaplayer.useAlternateUserAgent failed !! (Typo??)
getResolvedKey config.mediaplayer.extraHeaders failed !! (Typo??)
<276031.192213> [eServiceMP3] playbin uri=http://xxxxxxxxxxxxx/playlist.m3u8
<276031.195789> [eServiceMP3] starting pipeline
resolved to PLAY
<276031.296708> [eServiceMP3] gst_element_query_position failed in getPlayPosition
resolved to PLAY
<276031.302220> [eServiceMP3] gst_element_query_position failed in getPlayPosition
resolved to PLAY
<276031.310487> [eServiceMP3] gst_element_query_position failed in getPlayPosition
RemovePopup, id = ZapError
<276031.355500> [eServiceMP3] state transition NULL -> READY
<276031.356019> [eServiceMP3] GST_STREAM_STATUS_TYPE_CREATE -> setting timeout on souphttpsrc to 60s
<276031.905687> [eServiceMP3] Buffering 3 percent done
<276031.935280> [eServiceMP3] Buffering 5 percent done
<276031.976680> [eServiceMP3] Buffering 7 percent done
<276032.032526> [eServiceMP3] Buffering 9 percent done
<276032.064058> [eServiceMP3] Buffering 11 percent done
<276032.162194> [eServiceMP3] Buffering 13 percent done
<276032.388416> [eServiceMP3] Buffering 14 percent done
<276032.465598> [eServiceMP3] Buffering 16 percent done
<276032.466872> [eServiceMP3] state transition READY -> PAUSED
<276032.469929> [eServiceMP3] loading cuesheet
<276032.470173> [eServiceMP3] cutfile not found!
<276032.505442> [eServiceMP3] async-done - 1 video, 1 audio, 0 subtitle
<276032.505789> [eServiceMP3] AUDIO STRUCT=audio/mpeg
<276032.506158> [eServiceMP3] audio stream=0 codec=MPEG-4 AAC language=und
<276032.642994> [eServiceMP3] Buffering 17 percent done
<276032.668530> [eServiceMP3] Buffering 18 percent done
<276032.671381> [eServiceMP3] Buffering 19 percent done
<276032.672664> [eServiceMP3] Buffering 20 percent done
<276032.673854> [eServiceMP3] Buffering 22 percent done
<276032.675049> [eServiceMP3] Buffering 23 percent done
<276032.688427> [eServiceMP3] Buffering 25 percent done


** (enigma2:2645): WARNING **: unhandled DVBAPI Video Event 9
<276033.376314> [eServiceMP3] Buffering 26 percent done
<276033.378747> [eServiceMP3] state transition PAUSED -> PLAYING


** (enigma2:2645): WARNING **: unhandled DVBAPI Video Event 5
<276033.730579> [eServiceMP3] Buffering 28 percent done
<276033.873052> [eServiceMP3] Buffering 29 percent done
<276034.118851> [eServiceMP3] Buffering 31 percent done
<276034.127758> [eServiceMP3] Buffering 32 percent done
<276034.244720> [eServiceMP3] Buffering 34 percent done
<276034.245359> [eServiceMP3] Buffering 35 percent done
<276034.245942> [eServiceMP3] Buffering 37 percent done
<276034.246530> [eServiceMP3] Buffering 38 percent done
<276034.247214> [eServiceMP3] Buffering 40 percent done
<276034.378059> [eServiceMP3] Buffering 41 percent done
<276034.380443> [eServiceMP3] Buffering 43 percent done
<276034.381380> [eServiceMP3] Buffering 45 percent done
<276034.492874> [eServiceMP3] Buffering 46 percent done
<276034.493498> [eServiceMP3] Buffering 47 percent done
<276034.494098> [eServiceMP3] Buffering 48 percent done
<276034.494681> [eServiceMP3] Buffering 50 percent done
<276034.535939> [eServiceMP3] Buffering 51 percent done
<276034.698410> [eServiceMP3] Buffering 52 percent done
<276034.716312> [eServiceMP3] Buffering 53 percent done
<276035.218086> [eServiceMP3] Buffering 54 percent done
<276035.218718> [eServiceMP3] Buffering 55 percent done
<276035.284085> [eServiceMP3] Buffering 56 percent done
<276035.354011> [eServiceMP3] Buffering 58 percent done
<276035.432993> [eServiceMP3] Buffering 60 percent done
<276035.506045> [eServiceMP3] Buffering 61 percent done
<276035.526509> [eServiceMP3] Buffering 63 percent done
<276035.594533> [eServiceMP3] Buffering 64 percent done
<276035.677451> [eServiceMP3] Buffering 66 percent done
<276035.761610> [eServiceMP3] Buffering 67 percent done
<276035.817672> [eServiceMP3] Buffering 69 percent done
<276035.886258> [eServiceMP3] Buffering 70 percent done
<276036.095029> [eServiceMP3] Buffering 72 percent done
<276036.179681> [eServiceMP3] Buffering 74 percent done
<276036.230956> [eServiceMP3] Buffering 76 percent done
<276036.270163> [eServiceMP3] Buffering 77 percent done
<276036.365330> [eServiceMP3] Buffering 79 percent done
<276036.368574> [eServiceMP3] Buffering 80 percent done
<276036.425310> [eServiceMP3] Buffering 82 percent done
<276036.441323> [eServiceMP3] Buffering 83 percent done
<276036.479448> [eServiceMP3] Buffering 85 percent done
<276036.509620> [eServiceMP3] Buffering 86 percent done
<276036.551357> [eServiceMP3] Buffering 87 percent done
<276036.589581> [eServiceMP3] Buffering 88 percent done
<276036.611460> [eServiceMP3] Buffering 90 percent done
<276036.648973> [eServiceMP3] Buffering 91 percent done
<276036.699957> [eServiceMP3] Buffering 93 percent done
<276036.757435> [eServiceMP3] Buffering 96 percent done
<276036.824405> [eServiceMP3] Buffering 98 percent done
<276036.865631> [eServiceMP3] Buffering 100 percent done

first playlist.m3u8:

#EXTM3U#EXT-X-VERSION:3
#EXT-X-STREAM-INF:BANDWIDTH=947056,RESOLUTION=854x480,CODECS="avc1.64001e,mp4a.40.2"
chunks.m3u8?nimblesessionid=42248361


second playlist.m3u8

#EXTM3U#EXT-X-VERSION:3
#EXT-X-STREAM-INF:BANDWIDTH=1900933,RESOLUTION=854x480,CODECS="avc1.66.30,mp4a.40.2"
chunks.m3u8?nimblesessionid=42248364

[joeuser]

I built my own image from duckbox-developers git and found the same problem. I also just checked the latest PKT Hyperion V5.1 image and see the same.

[santa]

I think we have to wait, in openpli will be a lot of Tests and fixes with issues like yours.

[rantanplan]

Oha... da will aber jemand Werbung machen, oder sehe ich das falsch:rolleyes:.
Lange nix gehört von den Jungs aus dem Osten.

Grüße

[santa]

Du siehst das falsch, mit dem pkt hat er das Problem ja auch, aber ich weiß nicht ob die überhaupt noch aktuelle Images herausbringen. Ihm ging es nur darum zu sagen wo der Fehler überall auftritt.

[rantanplan]

Echt?
Na gut, hat es direkt 2mal erwähnt:rolleyes: zur Sicherheit. Also ich weiß jetzt das die was aktuelles raus gebracht haben.

Egal...
Ich würde auch lieber den Fix in eurem Image sehen.
Seit dem 14258 sind die Images wegen bekanntem Fehler (kein Abspielen eigener Aufnahmen) nix.
Vielleicht löscht ihr die einfach, denn es ist schon ärgerlich. Gerade für User die sich nicht so auskennen.
Wie gesagt 14258 läuft.
Im git Verlauf kann man auch irgendwie sehen, dass da am dvb-Service mehrfach gebastelt wurde. (War wohl ne Verschlimmbesserung:o)

Grüße

[santa]

Sollte im nächsten image weg sein, das baut wahrscheinlich oder hoffentlich morgen.

[joeuser]

I think we have to wait, in openpli will be a lot of Tests and fixes with issues like yours.

I am pretty sure it is not a problem with openpli, it is a problem with player2.

[DboxOldie]

I am pretty sure it is not a problem with openpli, it is a problem with player2.

That´s Nonsens !
Neutrino MP uses also Duckbox Developers Git > there all works fine on SH4 STB´s ( self Building for ufs910/912/913 and Spark7162 )
The base is used without modifikations except > no gstreamer is used, because ffmpeg is well enough. :)

[rantanplan]

Try Change player2 from OE-git?

@DboxOldie

Reines ffmpg e2 wäre durchaus mal interessant. Die Liebe zum gst verstehe ich eh nicht. Bin aber ja auch ein DAU.
Alles was ich aber umleite auf ffmpg läuft besser.
Zudem machen ja auch die mipsel-Boxen einige Zicken mit gst.

Taapat baut ja auch ein reines ffmpg und da läuft auch alles wie es soll.

[santa]

Unser e2 git lässt derzeit kein reines ffm zu, da ist irgendwas nicht ok, aber man kann es ja umleiten wie man will über die id

[DboxOldie]

Nun ob ffmpeg oder gst > werde ich mich nicht einmischen, für Neutrino geht alles mit ffmpeg.
Aber den player2 zu verdächtigen.....oder auch pti.ko Tauscherei.....
führt garantiert nicht zum Ziel > den Bug zu finden.
Da ist einfach was am handling falsch was die Treiber Base nutzt.

[max]

Was war überhaupt der Grund im HDMU sh4 e2, das das Frontend Handling geändert wurde?
Wenn ich mir da andere sh4 e2 Gits ansehe, wurde da nichts in der Hinsicht geändert.

[joeuser]

That´s Nonsens !
Neutrino MP uses also Duckbox Developers Git > there all works fine on SH4 STB´s ( self Building for ufs910/912/913 and Spark7162 )
The base is used without modifikations except > no gstreamer is used, because ffmpeg is well enough. :)

Yes, I built neutrino and tried it and the problem does not exist. It does play a little differently though. Sometimes there is audio briefly before the picture. I assume that is because it is waiting for a good I-frame before starting video display.

I also tested with OpenSPA and an older version of HDMU (13885) and they both do not have the issue.(However they both do not play the streams well - lots of small freezes, then eventually complete freeze.) Both use older gstreamer. So, maybe it is a problem with the new gstreamer?

Again, it is just a small annoyance, overall the playback is much, much better...

- - - Updated - - -


Nun ob ffmpeg oder gst > werde ich mich nicht einmischen, für Neutrino geht alles mit ffmpeg.
Aber den player2 zu verdächtigen.....oder auch pti.ko Tauscherei.....
führt garantiert nicht zum Ziel > den Bug zu finden.
Da ist einfach was am handling falsch was die Treiber Base nutzt.

Replacing pti.ko fixes another issue and should not affect playback of mp4 streams.
From st-pti.c

/* PTI is only started if the source is one of two frontends or if playback via SWTS is activated. Otherwise playback would
unnecessarily waste a buffer (might lead to loss of a second
recording). */

Regardless, I always test new images first with the original pti module from the image (and the problem is the same.)

BTW - there is no need to replace the pti module in PKT images because they do not have the problem of not tuning after using multiple tuners simultaneously.

[DboxOldie]

From st-pti.c


/* PTI is only started if the source is one of two frontends or
if playback via SWTS is activated. Otherwise playback would
unnecessarily waste a buffer (might lead to loss of a second
recording). */



This has normally nothing to do with file-playback.
There is meant: the live-tv playback from the frontends or in spezial case SWTS, if a scrambled file from HDD has to be descarmbled.
But then the SWTS parameter must be set to 1.....and some other things.
Try in Telnet: cat /sys/module/stmdvb/parameters/swts I'm sure is set to 0.

If you have a look in st-merger, there has j00zek added a part which uses SWTS for DVB-T USB frontends for some STB's.


/* >>> DVBT-USB
Step2-Driver injects data to SWTS here
When properly registered, driver should be visible in list of frontends,
if you get error just after start of manual scan, you have neighter correct frontendX defined nor incorrect tsm definitions in this file */
#if defined(ADB_BOX) || defined(SAGEMCOM88) || defined(ARIVALINK200) || defined(SPARK7162)
//injecting stream from DVB-T USB driver to SWTS


File playback and web-tv is handled by the internal eplayer3 ( Neutrino ) > ffmpeg, and then injected to the video / audio devices.
It's never routed through ts-merger or pti.
I think e2 with gst do the same.

greetings

[joeuser]

Yes, it was bad word choice from me "playback of mp4 stream." When it is about "playing of mp4 streams from webtv." In either case, it is like you said, "It's never routed through ts-merger or pti."
Thanks for tip about "/sys/module/stmdvb/parameters/swts" it could be useful for other debugging.

Unfortunately, I do not have time these days for in-depth debugging, which would involve building some debug images. I have enabled printk in the kernel, but when looking through mos of the code, I see that most printk lines are commented out.

[DboxOldie]

Well > to much printk´s are not good for performance in drivers..
But if you activated CONFIG_PRINTK in kernel, the most and important things work.
Often there´s used dprintk > own debug Output.
For example :
In st-common.c is : int debug = 1; in the Moment.
If in kernel printk is activated > debug outut will work for stmdvb.
For other Drivers > you must look, and outcommented > also change if you Need them.

[joeuser]

Yes, too many printks are bad, I need to go through and enable some at a time to narrow down the problems.
Slowly I am finding my way around the source code and seeing the big picture of how things inter-operate. Thanks for your tips.
Also, like I said, this issue is just an small annoyance and not worth my time to investigate too much, but looking through the code makes it easier to debug other problems when you are familiar with the code.
Of course the main problem is I do not have the source code for pti_np - that is where the other main problem is...

[DboxOldie]

I repeat once more:
pti_np is not the reason, my be that some other Versions act better.
I think that the the Gui handles the sources (frontends) wrong. You see this : older builds work with the Drivers.
And mp4 : in neutrino the included aac audio of that streams are re-encoded with ffmpeg before injecting to audio device.
That´s a problem of gst I think.

[joeuser]

Yes I know, that is why I said "other" main problem - referring to the problem of not tuning after using multiple tuners simultaneously. This is certainly a problem with the pti module.

[DboxOldie]

Well,
pti.ko ist used to steer the hardware descrambler...
stmdvb.ko steers pti.ko...
stmdvb is a part of player2...
the Gui uses player2... and so on.

But simultaneasly using of multiple tuners work with Neutrino by using the same drivers ???
And the Problem of "not tuning" doesn´t occur....
Therefore: I don´t believe that the "base" is the reason.

[joeuser]

Yes, it is possible it is not, but by just replacing that single module, the problem disappears in the hdmu image. So, the pti module is the most likely cause of the problem. Maybe later I will try replacing the pti module in the PKT image with the one from hdmu and see if the problem occurs. (That is if it will load ok. If I have to load other modules for compatibility, the test becomes less accurate...) So you are using the exact same source for the pti module with neutrino that hdmu uses for enigma2? OpenSPA has the same problem, but they are reluctant to say where they get their pti module from... PKT and GOS images do not have the problem, and I am guessing they are using the same source, but I could be wrong.

BTW - the problem is not with using multiple tuners simultaneously, it is AFTER using them together that the problem arises.

[DboxOldie]

No, also using both tuners for records and looking live a third one.
Then stopping both records > both tuners ( and the dvb-t tuner too ) work right.

But I tested another thing:
I took pti.ko from hdmu-4001 and put it in my neutrino > it doesn't load...
Additional I putted the hdmu stmdvb.ko to neutrino > sytem hangs completely > no more reaction in rs232, telnet or ftp.
Sorry : I really don't know which things are so different, because the same gcc and kernel version is used.

[joeuser]

Yes, I also have similar problems. I tried once building my own kernel from duckbox developers git (to enable printk) but was unable to swap hdmu modules into it. But, the pti module I use with up to (but not including) the latest hdmu image is from a GOS image which uses stm24_214.

Here is a link to a post describing how I recreate the problem (includes serial console output, unfortunately without printk enabled.)
http://www.hdmedia-universe.com/board/showthread.php?8296-No-picture-after-stop-recording&p=95929&viewfull=1#post95929


EDIT:
I swapped out the PKT pti.ko with the one from HDMU and it loaded ok and.... the problem now occurs with PKT.


While watching channel 1 on tuner C (fixed dish) start streaming same channel 1 to PC:
************************************************** **************************************


recording service: <enigma.eServiceReference; proxy of <Swig Object of type 'eServiceReference *' at 0x2d3367b8> >
< 211.823178> [eDVBResourceManager] use cached_channel
< 211.823450> [eDVBServicePMTHandler] allocate Channel: res 0
< 211.823795> [eDVBCIInterfaces] addPMTHandler 1:0:1: channel 1 on fixed dish
< 211.824213> [eDVBChannel] getDemux cap=00
< 211.824343> [eDVBServicePMTHandler] ok ... now we start!!
< 211.824436> [eDVBServiceRecord] RECORD service event 5
< 211.837514> [eDVBCAService] add demux 0 to slot 1 service 1:0:1: channel 1 on
< 211.839016> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 211.839326> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 211.840449> [eDVBServiceRecord] RECORD service event 6
< 211.844904> [eDVBServiceRecord] tuned..
< 211.845062> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 211.845310> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 211.853104> [eDVBChannel] getDemux cap=00
< 211.869416> [eDVBServiceRecord] start streaming...
< 211.937505> [eDVBServicePMTHandler] PATready
< 211.937753> [eDVBServicePMTHandler] use pmtpid 01b3 for service_id 10dd
< 211.937936> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 211.938187> [eDVBSectionReader] DMX_SET_FILTER pid=435
< 211.939244> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 211.939559> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 211.966209> [eDVBServiceRecord] RECORD service event 5
< 211.966399> [eDVBServiceRecord] start streaming...
< 211.979270> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 211.979574> [eDVBSectionReader] DMX_SET_FILTER pid=5102
< 211.991427> [eDVBCIInterfaces] gotPMT
< 211.991660> [eDVBCAService] don't build/send the same CA PMT twice
< 211.991852> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 211.992100> [eDVBSectionReader] DMX_SET_FILTER pid=435
[ePopen] command: fp_control -i 38 1 -i 39 1 -i 40 1
< 213.022434> [eConsoleAppContainer] Starting /bin/sh
< 213.149918> [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 68(16)
< 213.490966> [eDVBServiceRecord] now running: CLIFFORD (5172 seconds)
< 213.510189> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 213.510531> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 223.993541> [eDVBServicePMTHandler] AITready


Switch to watching channel on tuner b (motorized dish)
************************************************** *********


< 236.106782> [eDVBServicePlay] timeshift
playing 1:0:1: channel 2 on tuner b
< 236.122981> [eDVBCAService] free slot 0 demux 0 for service 1:0:1: channel 1 on tuner C freed
< 236.123170> [eDVBCAService] don't build/send the same CA PMT twice
< 236.185124> [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
< 236.185592> [eDVBPCR0] DEMUX_STOP ok
< 236.185825> [eDVBPCR0] destroy
< 236.186029> [eDVBVideo0] DEMUX_STOP ok
< 236.186199> [eDVBVideo0] VIDEO_STOP ok
< 236.373447> [eDVBVideo0] destroy
< 236.373899> [eDVBAudio0] AUDIO_STOP ok
< 236.533122> [eDVBAudio0] DEMUX_STOP ok
< 236.533478> [eDVBAudio0] AUDIO_CONTINUE ok
< 236.533735> [eDVBAudio0] destroy
cleaning up
TuxTxt cache cleared
getResolvedKey config.usage.remote_fallback failed !! (Typo??)
< 236.681198> [eDVBServicePlay] timeshift
< 236.688872> [eDVBServicePlay] timeshift
< 236.697085> [eDVBServicePlay] timeshift
RemovePopup, id = ZapError
< 236.705088> [eDVBResourceManager] allocate channel.. 0001:0001
< 236.705286> [eDVBResourceManager] available channel.. c670:0xx
< 236.705601> [eDVBFrontend] opening frontend 1
< 236.818324> [eDVBFrontend] (1)tune
< 236.818887> [eDVBSatelliteEquipmentControl] Entry for 356,0? not in Rotor Table found... i try gotoXX?
< 236.819013> [eDVBSatelliteEquipmentControl] siteLatitude = 5x.xxx, siteLongitude = 1x.xxxxx, 356.000000 degrees
< 236.819385> [eDVBSatelliteEquipmentControl] PolarmountHourAngle=xxx.447405
< 236.819634> [eDVBSatelliteEquipmentControl] RotorCmd = d146
< 236.819756> [eDVBSatelliteEquipmentControl] RotorCmd d146, lastRotorCmd ffffffff
< 236.819921> [eDVBSatelliteEquipmentControl] set rotor timeout to 360 seconds
< 236.820122> [eDVBFrontend] prepare_sat System 0 Freq 10935000 Pol 0 SR 13750000 INV 2 FEC 4 orbpos 3560 system 0 modulation 1 pilot 2, rolloff 0
< 236.820254> [eDVBFrontend] tuning to 1185 mhz
< 236.820366> [eDVBChannel] OURSTATE: tuning
< 236.820510> [eDVBServicePMTHandler] allocate Channel: res 0
< 236.825169> [eDVBCIInterfaces] addPMTHandler 1:0:1: channel 2 on tuner B
< 236.825444> [eDVBChannel] getDemux cap=00
< 236.825560> [eDVBResourceManager] allocate demux cap=00
< 236.825655> [eDVBResourceManager] allocating demux adapter=0, demux=1, source=-1 fesource=1
< 236.825768> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 236.915938> [eDVBServicePlay] timeshift
< 237.185842> [eDVBFrontend] set sequence pos 3
< 237.186523> [eDVBFrontend] setVoltage 1
< 237.201735> [eDVBFrontend] sleep 900ms
< 238.104910> [eDVBFrontend] set sequence pos 9
< 238.105247> [eDVBFrontend] invalidate current rotorparams
< 238.216800> [eDVBFrontend] sendDiseqc: e0316ed146(?)
< 238.278423> [eDVBFrontend] sleep 1000ms
< 239.281159> [eDVBFrontend] sleep 2000ms
< 241.281421> [eDVBFrontend] setVoltage 2
< 241.297809> [eDVBFrontend] set sequence pos 3
< 241.298128> [eDVBFrontend] setFrontend 0
< 241.298249> [eDVBFrontend] setting frontend 1
< 241.298512> [eDVBFrontend] set timeout 1440
< 241.298781> [eDVBFrontend] sleep 250ms
< 241.563818> [eDVBFrontend] locked step 0 ok
< 241.564269> [eDVBFrontend] set sequence pos -3
< 241.564505> [eDVBFrontend] sleep 250ms
< 241.817804> [eDVBFrontend] locked step 1 ok
< 241.818230> [eDVBFrontend] set sequence pos -3
< 241.818468> [eDVBFrontend] sleep 250ms
< 242.069808> [eDVBFrontend] locked step 2 ok
< 242.070241> [eDVBFrontend] set sequence pos -3
< 242.070481> [eDVBFrontend] sleep 250ms
< 242.321841> [eDVBFrontend] locked step 3 ok
< 242.322261> [eDVBFrontend] set sequence pos -3
< 242.322497> [eDVBFrontend] sleep 250ms
< 242.573827> [eDVBFrontend] locked step 4 ok
< 242.574037> [eDVBFrontend] ok > 4 .. goto 3


< 242.574134> [eDVBFrontend] set sequence pos 3
< 242.574222> [eDVBChannel] OURSTATE: ok
< 242.574320> [eDVBLocalTimerHandler] channel 0xe7c068 running
< 242.574462> [eDVBChannel] getDemux cap=00
< 242.574588> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.574831> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 242.575254> [eEPGCache] channel 0xe7c068 running
< 242.575449> [eDVBChannel] getDemux cap=00
< 242.575565> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.575790> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.576034> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.576275> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.576511> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.581082> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.581337> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.581586> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.581825> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.582059> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.582312> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.582549> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.582783> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.583103> [eEPGCache] next update in 2 sec
< 242.583309> [eDVBResourceManager] stop release channel timer
< 242.583428> [eDVBChannel] getDemux cap=00
< 242.583521> [eDVBServicePMTHandler] ok ... now we start!!
< 242.583615> [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
< 242.583788> [eDVBServicePlay] have 1 video stream(s) (0456), and 1 audio stream(s) (044c), and the pcr pid is 0456, and the text pid is 040e
< 242.584168> [eDVBChannel] getDemux cap=01
< 242.584258> [eDVBResourceManager] allocate demux cap=01
TuxTxt cache cleared
< 242.627267> [eTSMPEGDecoder] decoder state: play, vpid=0456, apid=044c
< 242.627579> [eDVBPCR0] DMX_SET_PES_FILTER pid=0x0456 ok
ok
< 242.628462> [eDVBAudio0] AUDIO_SET_BYPASS bypass=1 ok
< 242.628602> [eDVBAudio0] AUDIO_PLAY ok
< 242.657490> [eDVBVideo] Video Device: /dev/dvb/adapter0/video0
< 242.657754> [eDVBVideo] demux device: /dev/dvb/adapter0/demux1
< 242.657921> [eDVBVideo0] VIDEO_SET_STREAMTYPE 0 - ok
< 242.658063> [eDVBVideo0] DMX_SET_PES_FILTER pid=0x0456 ok
< 242.658414> [eDVBVideo0] VIDEO_PLAY ok
< 242.697454> [eDVBText0] DMX_SET_PES_FILTER pid=0x040e ok
TuxTxt cache cleared
TuxTxt: initialized
TuxTxt service started 40e
< 242.733910> [eDVBVideo0] VIDEO_CONTINUE ok
< 242.734094> [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
< 242.734225> [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
< 242.734380> [eDVBAudio0] AUDIO_CONTINUE ok
< 242.734777> [eDVBTeletextParser] Starting!
< 242.734909> [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
< 242.735037> [eDVBPESReader] Created. Opening demux
< 242.735140> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.735522> [eDVBTeletextParser] created teletext subtitle PES reader!
< 242.735794> [eDVBPESReader] Created. Opening demux
< 242.735919> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.736334> [eDVBTeletextParser] starting PES reader on pid=040e
< 242.736532> [eDVBPESReader] DMX_SET_PES_FILTER pid=040e
TuxTxt running thread...(40e)
< 242.956325> [eDVBCAService] new service 1:0:1: channel 2 on tuner B
< 242.956685> [eDVBCAService] add demux 1 to slot 0 service 1:0:1: channel 2 on tuner B
< 242.961154> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.961462> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 242.962557> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.962856> [eDVBSectionReader] DMX_SET_FILTER pid=18
RemovePopup, id = ZapError
< 242.972230> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 242.972530> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 243.028317> [eDVBFrontend] update current rotorparams 1435 d146 3560
< 243.049007> [eDVBFrontend] set sequence pos 4
< 243.061005> [eDVBFrontend] update current switch params
< 243.073120> [eDVBFrontend] sleep 500ms
< 243.357946> [eDVBServicePMTHandler] PATready
< 243.358178> [eDVBServicePMTHandler] use pmtpid 1483 for service_id 00fb
< 243.358351> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.358594> [eDVBSectionReader] DMX_SET_FILTER pid=5251
< 243.359114> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.359404> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 243.365178> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.365466> [eDVBSectionReader] DMX_SET_FILTER pid=5002
< 243.365964> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.366259> [eDVBSectionReader] DMX_SET_FILTER pid=17
< 243.440415> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.444971> [eDVBSectionReader] DMX_SET_FILTER pid=5020
< 243.498060> [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
< 243.508100> [eDVBServicePlay] have 1 video stream(s) (0456), and 3 audio stream(s) (044c, 025e, 0452), and the pcr pid is 0456, and the text pid is 040e
< 243.514055> [eTSMPEGDecoder] decoder state: play, vpid=0456, apid=044c
< 243.720041> [eDVBCIInterfaces] gotPMT
< 243.724886> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.725194> [eDVBSectionReader] DMX_SET_FILTER pid=5251
< 243.738047> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.738383> [eDVBSectionReader] DMX_SET_FILTER pid=5042
< 243.825920> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.826254> [eDVBSectionReader] DMX_SET_FILTER pid=5049
< 243.946144> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 243.946472> [eDVBSectionReader] DMX_SET_FILTER pid=5051
[Dish] tuning failed
< 244.049970> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 244.050305> [eDVBSectionReader] DMX_SET_FILTER pid=5052
< 244.109980> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 244.110309> [eDVBSectionReader] DMX_SET_FILTER pid=5201
< 244.230013> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 244.230343> [eDVBSectionReader] DMX_SET_FILTER pid=5202
< 244.589003> [eEPGCache] start caching events(1448562532)
< 244.589276> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 244.590201> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 244.591197> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 244.969975> [eDVBDemux] open demux /dev/dvb/adapter0/demux1
< 244.970314> [eDVBSectionReader] DMX_SET_FILTER pid=5203
< 244.991108> [eDVBServicePMTHandler] sdt update done!
< 251.628959> [eEPGCache] abort non avail nownext reading
< 251.629794> [eEPGCache] abort non avail schedule reading
< 251.630489> [eEPGCache] abort non avail schedule other reading
< 251.749044> [eDVBLocalTimerHandler] diff is 1
< 251.749259> [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
< 251.749712> [eDVBLocalTimerHandler] update RTC
< 251.749897> [eDVBLocalTimerHandler] time update to 19:29:00
< 251.750006> [eDVBLocalTimerHandler] m_time_difference is 1
< 251.750099> [eDVBLocalTimerHandler] set Linux Time
< 251.751049> [eDVBChannel] getDemux cap=00


Stop streaming channel 1 on tuner C
***************************************


< 258.164118> [eDVBServiceRecord] stop recording!
< 258.164461> [eDVBServiceRecord] saving cutlist failed because tstools failed
< 258.171029> [eDVBCAService] free slot 1 demux 0 for service 1:0:1: channel 1 on tuner C
< 258.171383> [eDVBCAService] free service 1:0:1: channel 1 on tuner C
< 258.175160> [eDVBLocalTimerHandler] remove channel 0xe574a8
< 258.175435> [eEPGCache] remove channel 0xe574a8
Unhandled error in Deferred:
Unhandled Error
Traceback (most recent call last):
Failure: twisted.internet.error.ConnectionDone: Connection was closed cleanly.
[ePopen] command: fp_control -i 15 0 -i 38 0 -i 39 0 -i 40 0
< 259.199218> [eConsoleAppContainer] Starting /bin/sh
< 260.218007> [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 29(16)
< 261.185127> [eDVBFrontend] close frontend 2


Try to watch channel 3 on tuner B
************************************


< 272.651454> [eDVBServicePlay] timeshift
playing 1:0:1:1:2:2222:DE80000:0:0:0:
< 272.656883> [eDVBCAService] free slot 0 demux 1 for service 1:0:1: channel 2 on tuner B
< 272.657331> [eDVBCAService] free service 1:0:1: channel 2 on tuner B
< 272.676800> [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
< 272.677087> [eDVBPCR0] DEMUX_STOP ok
< 272.677565> [eDVBPCR0] destroy
< 272.677789> [eDVBVideo0] DEMUX_STOP ok
< 272.678439> [eDVBVideo0] VIDEO_STOP ok
< 272.777830> [eDVBVideo0] destroy
< 272.778113> [eDVBAudio0] AUDIO_STOP ok
< 272.877449> [eDVBAudio0] DEMUX_STOP ok
< 272.878122> [eDVBAudio0] AUDIO_CONTINUE ok
< 272.878391> [eDVBAudio0] destroy
< 272.878582> [eDVBText0] DEMUX_STOP ok
TuxTxt stopped service 40e
< 272.881471> [eDVBText0] destroy
cleaning up
TuxTxt cache cleared
< 272.906256> [eDVBResourceManager] start release channel timer
getResolvedKey config.usage.remote_fallback failed !! (Typo??)
< 272.949432> [eDVBServicePlay] timeshift
< 272.952698> [eDVBServicePlay] timeshift
< 272.959788> [eDVBServicePlay] timeshift
RemovePopup, id = ZapError
< 272.963647> [eDVBLocalTimerHandler] remove channel 0xe7c068
< 272.963920> [eEPGCache] remove channel 0xe7c068
< 272.971466> [eDVBResourceManager] allocate channel.. 0002:2222
< 272.975076> [eDVBFrontend] (1)tune
< 272.975318> [eDVBSatelliteEquipmentControl] Entry for 356,0? not in Rotor Table found... i try gotoXX?
< 272.975434> [eDVBSatelliteEquipmentControl] siteLatitude = 5x.xxxxx, siteLongitude = 1x.xxxxxxxx, 356.000000 degrees
< 272.975694> [eDVBSatelliteEquipmentControl] PolarmountHourAngle=xxxxxxxxxxxxx.447405
< 272.975840> [eDVBSatelliteEquipmentControl] RotorCmd = d146
< 272.975931> [eDVBSatelliteEquipmentControl] RotorCmd d146, lastRotorCmd d146
< 272.976088> [eDVBFrontend] prepare_sat System 0 Freq 10888000 Pol 0 SR 11570000 INV 2 FEC 3 orbpos 3560 system 0 modulation 1 pilot 2, rolloff 0
< 272.976211> [eDVBFrontend] tuning to 1138 mhz
< 272.976346> [eDVBChannel] OURSTATE: tuning
< 272.976482> [eDVBServicePMTHandler] allocate Channel: res 0
< 272.976826> [eDVBCIInterfaces] addPMTHandler 1:0:1:1: channel 3 on tuner B
< 272.977027> [eDVBChannel] getDemux cap=00
< 272.977213> [eDVBResourceManager] allocate demux cap=00
< 272.977322> [eDVBResourceManager] allocating demux adapter=0, demux=0, source=2 fesource=1
< 272.977443> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.019634> [eDVBServicePlay] timeshift
< 273.088400> [eDVBFrontend] set sequence pos 3
< 273.093209> [eDVBFrontend] set sequence pos 3
< 273.093531> [eDVBFrontend] update current switch params
< 273.093741> [eDVBFrontend] startTuneTimeout 10000
< 273.093952> [eDVBFrontend] setFrontend 1
< 273.094092> [eDVBFrontend] setting frontend 1
< 273.363829> [eDVBFrontend] (1)fe event: status 0, inversion off, m_tuning 1
< 273.364048> [eDVBFrontend] (1)fe event: status 1f, inversion off, m_tuning 2
< 273.364160> [eDVBChannel] OURSTATE: ok
< 273.364248> [eDVBLocalTimerHandler] channel 0x2c4078b0 running
< 273.364398> [eDVBChannel] getDemux cap=00
< 273.364529> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.364769> [eDVBSectionReader] DMX_SET_FILTER pid=20
< 273.369231> [eEPGCache] channel 0x2c4078b0 running
< 273.369407> [eDVBChannel] getDemux cap=00
< 273.369540> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.369770> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.369998> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.370246> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.370484> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.370752> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.371011> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.371247> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.371477> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.371731> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.371963> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.372196> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.372446> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.372744> [eEPGCache] next update in 2 sec
< 273.372967> [eDVBResourceManager] stop release channel timer
< 273.373096> [eDVBChannel] getDemux cap=00
< 273.377448> [eDVBServicePMTHandler] ok ... now we start!!
< 273.377544> [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
< 273.377680> [eDVBServicePlay] have 1 video stream(s) (0101), and 1 audio stream(s) (0102), and the pcr pid is 0101, and the text pid is 0104
< 273.378080> [eDVBChannel] getDemux cap=01
< 273.378171> [eDVBResourceManager] allocate demux cap=01
TuxTxt cache cleared
< 273.416175> [eTSMPEGDecoder] decoder state: play, vpid=0101, apid=0102
< 273.416631> [eDVBPCR0] DMX_SET_PES_FILTER pid=0x0101 ok
ok
< 273.418297> [eDVBAudio0] AUDIO_SET_BYPASS bypass=1 ok
< 273.418527> [eDVBAudio0] AUDIO_PLAY ok
< 273.435153> [eDVBVideo] Video Device: /dev/dvb/adapter0/video0
< 273.435411> [eDVBVideo] demux device: /dev/dvb/adapter0/demux0
< 273.435597> [eDVBVideo0] VIDEO_SET_STREAMTYPE 0 - ok
< 273.435712> [eDVBVideo0] DMX_SET_PES_FILTER pid=0x0101 ok
< 273.436071> [eDVBVideo0] VIDEO_PLAY ok
< 273.455189> [eDVBText0] DMX_SET_PES_FILTER pid=0x0104 ok
TuxTxt cache cleared
TuxTxt: initialized
TuxTxt service started 104
< 273.478429> [eDVBVideo0] VIDEO_CONTINUE ok
< 273.478632> [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
< 273.478765> [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
< 273.478895> [eDVBAudio0] AUDIO_CONTINUE ok
< 273.479399> [eDVBTeletextParser] Starting!
< 273.479534> [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
< 273.479643> [eDVBPESReader] Created. Opening demux
< 273.479739> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.480152> [eDVBTeletextParser] created teletext subtitle PES reader!
< 273.480407> [eDVBPESReader] Created. Opening demux
< 273.480527> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
TuxTxt running thread...(104)
< 273.480970> [eDVBTeletextParser] starting PES reader on pid=0104
< 273.482064> [eDVBPESReader] DMX_SET_PES_FILTER pid=0104
< 273.597047> [eDVBCAService] new service 1:0:1:1: channel 3 on tuner B
< 273.597562> [eDVBCAService] add demux 0 to slot 0 service 1:0:1:1: channel 3 on tuner B
< 273.597746> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.598006> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 273.599022> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.599305> [eDVBSectionReader] DMX_SET_FILTER pid=18
RemovePopup, id = ZapError
< 273.604889> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 273.605293> [eDVBSectionReader] DMX_SET_FILTER pid=0
< 273.608637> [eDVBFrontend] sleep 500ms
[Dish] tuning failed
< 275.385186> [eEPGCache] start caching events(1448562564)
< 275.385455> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 275.386386> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 275.387384> [eDVBSectionReader] DMX_SET_FILTER pid=18
< 277.622052> [eDVBDemux] open demux /dev/dvb/adapter0/demux0
< 277.622380> [eDVBSectionReader] DMX_SET_FILTER pid=17
< 282.425287> [eEPGCache] abort non avail nownext reading
< 282.426083> [eEPGCache] abort non avail schedule reading
< 282.426776> [eEPGCache] abort non avail schedule other reading
< 293.614153> [eDVBServicePMTHandler] PATready
< 293.614368> [eDVBServicePlay] DVB service failed to tune - error 2
RemovePopup, id = ZapError
AddPopup, id = ZapError
< 309.382302> [eDVBLocalTimerHandler] no transponder tuned... or no TDT/TOT avail .. try to use RTC :)
< 309.382984> [eDVBLocalTimerHandler] getRTC returned time=0. RTC problem?
< 309.383278> [eDVBChannel] getDemux cap=00
< 337.648585> [eDVBScan] the current channel's ID was not corrected - not adding channel.
< 337.648905> [eDVBServicePMTHandler] ignore sdt update data.... incorrect transponder tuned!!!