2520 replies to this topic

[dreamboxco]

Hello Friends,

 

here the new Log from updated Gstreamer today

 

m3u8 long zap Time at 10 seconds and Mosaik Pics

 

18:48:13.248 {   } Components/ActionMap.py:46 action action ->  InfobarChannelSelection switchChannelDown
18:48:13.257 {   } Navigation.py:245 playService playing 4097:0:1:0:0:0:0:0:0:0:http%3a//ipadress%3aport.m3u8:TV
18:48:13.261 [   ] service/servicemp3.cpp:782 stop eServiceMP3::stop http://ip:port.m3u8
18:48:13.455 {   } Screens/Screen.py:150 show [SCREENNAME]  InfoBar
18:48:13.501 < E > service/servicemp3.cpp:965 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PAUSED ret:ASYNC, doing seeking unpause
18:48:13.503 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
18:48:13.515 < E > service/servicemp3.cpp:965 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PLAYING ret:ASYNC, doing seeking unpause
18:48:13.517 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
18:48:13.526 { E } Tools/Notifications.py:36 RemovePopup RemovePopup, id = ZapError
18:48:13.530 [   ] driver/rcinput.cpp:23 handleCode 0 6c 1
18:48:13.534 {   } Screens/InfoBarGenerics.py:204 actionA KEY: 108 1 KEY_DOWN DOWN
18:48:13.566 [   ] service/servicemp3.cpp:1645 gstBusCall eServiceMP3::state transition NULL -> READY
18:48:13.611 {   } Components/AVSwitch.py:289 setAspect [VideoMode] setting aspect: 16:9
18:48:13.615 {   } Components/AVSwitch.py:300 setWss [VideoMode] setting wss: auto
18:48:13.618 {   } Components/AVSwitch.py:306 setPolicy43 [VideoMode] setting policy: bestfit
18:48:13.622 {   } Components/AVSwitch.py:313 setPolicy169 [VideoMode] setting policy2: letterbox

 

TS Streams Broken

 

18:49:17.904 {   } Screens/InfoBarGenerics.py:204 actionA KEY: 108 0 KEY_DOWN DOWN
18:49:17.942 {   } Components/ActionMap.py:46 action action ->  InfobarChannelSelection switchChannelDown
18:49:17.949 {   } Navigation.py:245 playService playing 4097:0:1:0:0:0:0:0:0:0:http%3a//ipadress%3aport.ts:TV
18:49:17.951 [   ] service/servicemp3.cpp:782 stop eServiceMP3::stop http://kanalturk.erc...adyodelisi.m3u8
18:49:18.052 {   } Screens/Screen.py:150 show [SCREENNAME]  InfoBar
18:49:18.100 < E > service/servicemp3.cpp:965 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PAUSED ret:ASYNC, doing seeking unpause
18:49:18.102 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
18:49:18.113 < E > service/servicemp3.cpp:965 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PLAYING ret:ASYNC, doing seeking unpause
18:49:18.115 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
18:49:18.124 { E } Tools/Notifications.py:36 RemovePopup RemovePopup, id = ZapError
18:49:18.129 [   ] driver/rcinput.cpp:23 handleCode 0 6c 1

[dreamboxco]

Log  with Ülke TV and CNN Türk

 

19:06:20.088 {   } Components/ActionMap.py:46 action action ->  InfobarChannelSelection switchChannelUp
19:06:20.099 {   } Navigation.py:245 playService playing 4097:0:1:0:0:0:0:0:0:0:http%3a//live.netd.com.tr/S1/HLS_LIVE/cnn_turk/1000/prog_index.m3u8:CNN TURK
19:06:20.105 [   ] service/servicemp3.cpp:782 stop eServiceMP3::stop http://livetr.gostre...q/playlist.m3u8
19:06:20.284 {   } Screens/Screen.py:150 show [SCREENNAME]  InfoBar
19:06:20.328 < E > service/servicemp3.cpp:965 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PAUSED ret:ASYNC, doing seeking unpause
19:06:20.331 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
19:06:20.343 < E > service/servicemp3.cpp:965 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PLAYING ret:ASYNC, doing seeking unpause
19:06:20.345 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
19:06:20.357 { E } Tools/Notifications.py:36 RemovePopup RemovePopup, id = ZapError
19:06:20.363 [   ] driver/rcinput.cpp:23 handleCode 0 67 1

[mx3L]

As I can currently not merge a commit in the mediasink Eric needs to push these commits...

 

Sorry for all the discussions.... it is now clear to me...!

 

No problem

 

I have one question left. Does this workaround shortcomings in the drivers (mediasink) of certain manufacterers or does this benefit all models in general, by principle? If the latter, I wonder why DMM didn't implement it themselves. Maybe their drivers have shortcomings that prevent it, very well possible. Any idea?

 

In gstreamer-0.10 AFAIK only filesrc was able to unpause after longer time in paused state, souphttpsrc timed out, so unpausing pipeline after long time did produce EOS event. It was fixed only in gstreamer-1.0. Then there was problem with not prerolling buffer for dvbaudiosink. So I guess it was not done because of limited possibility(only filesrc) and unpredictable nature of unpausing pipeline.

 

Applied both patches.

 

If it breaks, we will know soon enough.

Thanks, yes I guess so. I can see that you pushed it also to master branch. It was not necessary, since in servicemp3 is new unpause behaviour enabled only for gstreamer-1.0, but at least we will see if it async setting doesn't break something.

 

Only thing that is different is that audio buffers will now go little sooner or later than video buffers, after seeking and on start of the stream, since we are waiting only for dvbvideosink to preroll.

 

If there is some problem with start of the stream(no audio, stuck video) or after seeking and it wasn't there before it means that async setting for dvbaudiosink has to be reverted and another solution has to be found.

I did many tests on et4x00 and vusolose, and I have the same behaviour after seeking and on start as with async setting for dvbaudiosink enabled.

Edited by mx3L, 19 November 2015 - 19:40.

[dreamboxco]

so only m3u8 Stream i have create a new iptv list an zaptime is very good

 

but in the LOG Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []

 

What is setPixmapNum(0) failed?

 

19:54:07.072 {   } Navigation.py:245 playService playing 4097:0:1:0:0:0:0:0:0:0:http%3a//livetr.gostream.nl/kanal7hq/kanal7hq/playlist.m3u8:KANAL 7 HD
19:54:07.073 [   ] service/servicemp3.cpp:782 stop eServiceMP3::stop http://foxtv-i.mncdn...3/playlist.m3u8
19:54:07.246 {   } Screens/Screen.py:150 show [SCREENNAME]  InfoBar
19:54:07.287 < E > service/servicemp3.cpp:965 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PAUSED ret:ASYNC, doing seeking unpause
19:54:07.289 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
19:54:07.300 < E > service/servicemp3.cpp:965 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PLAYING ret:ASYNC, doing seeking unpause
19:54:07.302 { E } Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
19:54:07.311 { E } Tools/Notifications.py:36 RemovePopup RemovePopup, id = ZapError
19:54:07.315 [   ] driver/rcinput.cpp:23 handleCode 0 67 1
19:54:07.318 {   } Screens/InfoBarGenerics.py:204 actionA KEY: 103 1 KEY_UP UP

 

Edit: no Problems with audio and stuck video, only at zap i have mosaik pic for 1-2 seconds

Edited by dreamboxco, 19 November 2015 - 19:59.

[athoik]

but in the LOG Components/Pixmap.py:133 setPixmapNum setPixmapNum(0) failed! defined pixmaps: []
What is setPixmapNum(0) failed?

Ask your image builder, this is not log from OpenPLi (based) image.

[dreamboxco]

ok is the new gstreamer in latest openpli image?

[mx3L]

@dreamboxco

I tried CNN Türk and works nicely with reverted patch by @Beeker

Stream doesn't work at all?

 

I can see that you're using latest adjustments in servicemp3, according to log.

[eServiceMP3] trickSeek - invalid state, state:READY pending:PAUSED ret:ASYNC, doing seeking unpause

This one is weird it looks like that we are calling trickSeek when only in READY state. It shouldn't do any harm, but I will check it, why are we doing that.

Edited by mx3L, 19 November 2015 - 23:05.

[MastaG]

I have them all built.
Hope to have everything onlibe tomorrow.

[christophecvr]

@Beeker,

 

Verry thank's, I just wanted to start my trace off the commit today, I do not have to do that anymore

[athoik]

I think we need to revert those two as well (they just reverted upstream), it might solve issues with h264parse..

http://cgit.freedesk...35bf4c509d7bfa3
http://cgit.freedesk...6d2605707116d46

[christophecvr]

I think we need to revert those two as well (they just reverted upstream), it might solve issues with h264parse..

http://cgit.freedesk...35bf4c509d7bfa3
http://cgit.freedesk...6d2605707116d46

Do You have an example stream h264 with parse problems. ?

 

Currently back on gst-1.6.1 tagged, I just applied patch of Beeker to gstreamer.

 

On vuduo2 all 100 % now.

[athoik]

We to add video/h264 parsed=true to make videos work, maybe it fixes that issue. There is a SkyIT link few pages below I think.

[christophecvr]

We to add video/h264 parsed=true to make videos work, maybe it fixes that issue. There is a SkyIT link few pages below I think.

Well it could be a cause.

 

But the tagged-1.6.1 did not had those patches , which are just reversed.

 

What is well with tagged-1.6.1 build with patch off Beeker to revert the use-new-gst-adapter-get-buffer.patch.

 

All works fine, except by some live streams stiil sink issues . But all filesrc media works fine now .

[christophecvr]

Ok I found the stream.

 

its the italian sky tg24.

 

Yes that one works perfect on 1.5.1 . But whit the tagged 1.6.1 even with last adaptation indeed its a problem and yes it a h264. Serious sync issues.

 

It's the only stream by me on vuduo2 (from the one I have) which does not work ok.

[MastaG]

Yeah, some fragmented HLS streams are still not playing correctly.

But I'm not sure whether Sky TG 24 follows the spec.

 

Could you re-test this stream?

#SERVICE 4097:0:0:0:0:0:0:0:0:0:http%3a//wildhitz.rr.kpnstreaming.nl/b2b/vod/wildhitz/mp4s/Tech_N9ne_-_Strangeulation_Cypher/index.ism/index.m3u8:Tech%20N9ne%20TEST%202
#DESCRIPTION Tech N9ne TEST 2

 

That one follows the spec 100% correctly.

It's served by USP (mp4split).

[christophecvr]

Yeah, some fragmented HLS streams are still not playing correctly.

But I'm not sure whether Sky TG 24 follows the spec.

 

Could you re-test this stream?

#SERVICE 4097:0:0:0:0:0:0:0:0:0:http%3a//wildhitz.rr.kpnstreaming.nl/b2b/vod/wildhitz/mp4s/Tech_N9ne_-_Strangeulation_Cypher/index.ism/index.m3u8:Tech%20N9ne%20TEST%202
#DESCRIPTION Tech N9ne TEST 2

That one follows the spec 100% correctly.

It's served by USP (mp4split).

that stream is playing fine by me. But it always played fine the full range from 1.5.1 up 1.6.1 tagged and also with last master head.

[dreamboxco]

I have Zap Time now from 1-2 seconds with the same iptvchannnels

 

http://forums.openpl...es/#entry515034

Edited by dreamboxco, 20 November 2015 - 16:54.

[babsy98]

hi great work , on my problem stb m3u stream allways stop and blocking, now with atv 22.11 all ok 

[babsy98]

hm not 100% after zapping 3-4 m3u  e2 is blocking ,

 

08:38:27.662 {   } Navigation.py:245 playService playing 4097:0:0:0:0:0:0:0:0:0:http%3a//live2_sr-lh.akamaihd.net/i/sr_universal02@107595/master.m3u8:SR Fernsehen

08:38:27.832 < E > service/servicemp3.cpp:966 trickSeek [eServiceMP3] trickSeek - invalid state, state:READY pending:PAUSED ret:ASYNC, doing seeking unpause

08:38:28.153 [   ] service/servicemp3.cpp:1646 gstBusCall eServiceMP3::state transition NULL -> READY

08:38:29.080 {   } Screens/Screen.py:150 show [SCREENNAME]  BufferIndicator

08:38:30.052 [   ] service/servicemp3.cpp:1646 gstBusCall eServiceMP3::state transition READY -> PAUSED
08:38:30.054 [   ] service/servicemp3.cpp:2920 loadCuesheet [eServiceMP3] loading cuesheet
08:38:30.054 [ E ] service/servicemp3.cpp:2952 loadCuesheet [eServiceMP3] cutfile not found!
08:38:30.090 [   ] service/servicemp3.cpp:1905 gstBusCall eServiceMP3::async-done - 1 video, 1 audio, 0 subtitle
08:38:30.090 [   ] service/servicemp3.cpp:1929 gstBusCall AUDIO STRUCT=audio/mpeg
08:38:30.090 [   ] service/servicemp3.cpp:1954 gstBusCall eServiceMP3::audio stream=0 codec=MPEG-4 AAC language=und

08:38:35.091 [   ] gdi/grc.cpp:194 thread main thread is non-idle! display spinner!

[athoik]

It would be better to report issues from OpenPLi Enigma2.

Try the following patch:

diff --git a/lib/service/servicemp3.cpp b/lib/service/servicemp3.cpp
index 43c5598..45e641d 100644
--- a/lib/service/servicemp3.cpp
+++ b/lib/service/servicemp3.cpp
@@ -757,11 +757,8 @@ RESULT eServiceMP3::start()
 #else
                gst_element_set_state (m_gst_playbin, GST_STATE_PAUSED);
 #endif
-               updateEpgCacheNowNext();
        }

-       m_event(this, evStart);
-
        return 0;
 }

@@ -1731,6 +1728,8 @@ void eServiceMP3::gstBusCall(GstMessage *msg)
                                        setPCMDelay(pcm_delay);
                                        if(!m_cuesheet_loaded) /* cuesheet CVR */
                                                loadCuesheet();
+                                       updateEpgCacheNowNext();
+                                       m_event(this, evStart);
                                }       break;
                                case GST_STATE_CHANGE_PAUSED_TO_PLAYING:
                                {

It will send event start on enigma when state changes from READY to PAUSED, normally only once and only when stream is valid.

PS. The stream http://live2_sr-lh.a...595/master.m3u8 is not working for me (Enigma2, VLC whatever).

< 49491.353964> [eServiceMP3] construct!
< 49491.354655> [eServiceMP3] playbin uri=http://live2_sr-lh.akamaihd.net/i/sr_universal02@107595/master.m3u8
< 49491.357595> [eServiceMP3] starting pipeline
< 49491.421553> [eServiceMP3] state transition NULL -> READY
< 49491.422210> [eServiceMP3] GST_STREAM_STATUS_TYPE_CREATE -> setting timeout on souphttpsrc to 30s
< 49491.891289> [eServiceMP3] Gstreamer error: Forbidden (15) from source
< 49491.892295> [eServiceMP3] Gstreamer error: Internal data flow error. (1) from source
< 49491.893168> [eServiceMP3] Gstreamer error: Stream contains not enough data. (4) from typefindelement14