[VU+ Ultimo4K] Problem with file player

[lincsat]

Have just built and installed the new 6.2.001 (DEV) image on my Ultimo4k. When using the media player to play a recording, using any of the skip forward or back buttons (1,3,4,6,7,9 or the FF or REW, the box crashes. I've attached the crash log, looks like a problem with libc.so.6

Attached log is from the image without a settings restore

 

[ccs]

I'm having no problems on the same box running 6.2.001.002, using the standard player via movielist.

Are you using the mediaplayer plugin?

 

[lincsat]

No, that was a straight flash, no restore and nothing added. I'll try building the 6.2.001.002 later

 

[ronand]

No such problems on 6.2.001.002 on my h7

 

[lincsat]

No such problems on 6.2.001.001 on my h7 - just on my Ultimo4K. I'm building 6.2.001.002 now

 

[lincsat]

OK, had a play around with 6.2.001.002 and it's doing the same. It appears to not like anything recorded from an IPTV stream with the filetype .stream If I change the suffix from .stream to .ts, then it plays back and skips OK.

So the bug appears to be that the new images don't like to play back recorded streams, the problem also exists on the ZGemma H7

 

[twol]

OK, had a play around with 6.2.001.002 and it's doing the same. It appears to not like anything recorded from an IPTV stream with the filetype .stream If I change the suffix from .stream to .ts, then it plays back and skips OK.

So the bug appears to be that the new images don't like to play back recorded streams, the problem also exists on the ZGemma H7

Can you post debug logs with .stream & .ts ?

 

[lincsat]

OK, the 1.log is playing a .ts that didn't crash and the 2.log is playing the .stream that did crash

I restarted the GUI before starting the experiment. This is on a H7 with a full restore


EDIT - Just tried changing the suffix of a recorded off satellite (Channel4HD) .ts to .stream and the stream crashes. I have attached debug logs of these as well. ts.log is the file named as the default .ts and the stream.log is playing the same file renamed to .stream

 

[twol]

So question, I tried recording an IPTV stream last night and I ended up with a .ts flie ... so why do you get stream?
So mine was in a bouquet and I hit record from the grid EPG-------> .ts file

Maybe the debug log around the start of the recording would help me to see why you get .stream.

 

[lincsat]

I have attached the log but have had to obfuscate the IPTV URL details. I do have all IPTV channels set as 4097 service ref rather than 1 as it's proven to be more stable

 

[ccs]

Bit of a discussion here....

https://forums.openpli.org/topic/81375-recording-stream-issue/

 

[twol]

@ccs - thanks, yep kind of interesting, but not our issue. As lincsat has noted, issue is the change to 4097 to produce a .stream and subsequent playing that stream with the number keys.
I w will not get time until later in the week to test further, but will do so as soon as I can

 

[lincsat]

@ccs - thanks, yep kind of interesting, but not our issue. As lincsat has noted, issue is the change to 4097 to produce a .stream and subsequent playing that stream with the number keys.
I w will not get time until later in the week to test further, but will do so as soon as I can

If you want me to test any experimental fix, just let me know mate

 

[twol]

If you want me to test any experimental fix, just let me know mate

Thanks for the reminder - been tied up!

 

[lincsat]

Did you get anywhere with this mate? I tried a new build and still the same issue

 

[lincsat]

As an update, I compiled an OpenATV dev image and it plays stream files OK

 

[twol]

@Lincsat - so rerigged everything to produce Bouquets with 4097.
Recorded a stream
Played back that stream skipping backwards and forwards - no issues.

BUT initially I had serviceapp enabled and I had issues.
Disabled serviceapp, no problems.

So if you don't have Serviceapp or similar installed, post me a stream and I will run on my systems and test again

 

[lincsat]

I have serviceapp installed but playback set to original. I have tried uninstalling the plugin and the issues remain.

I can send you a stream if needed but I have recorded a program from Freesat which records as a .ts file, changed the suffix to .stream and that gives the same issue

I have tried the 6.2.001.002 image on both a VU Ultimo4K and a Zgemma H7 both without any settings restore, just the base image and I get the issue, so can't be anything that I've installed.

If you can't replicate, do you have a 6.2 image for either of those boxes that I can download to rule out a build error

 

[ccs]

I'm running 6.2.002.007 on an Ultimo4k, I'll give it a go, freeview rather than freesat, though.

 

[ccs]

The picture freezes after a few seconds, fortunately the stop button returns to movielist ok. I can skip forward after it freezes, the picture pixelates, recovers, and then hangs a few seconds later.

14:17:54.2986 [eServiceMP3] construct!
14:17:54.2986 [eServiceMP3] playbin uri=file:///media/hdd/movie/.Trash/20220503%201825%20-%20BBC%20ONE%20Yk&Li%20-%20Look%20North%20(East%20Yorkshire%20and%20Lincolnshire).stream
14:17:54.2994 [eServiceMP3] starting pipeline
14:17:54.3033 [eServiceMP3] state transition NULL -> READY
14:17:54.3956 [eServiceMP3] state transition READY -> PAUSED
14:17:54.4412 [eServiceMP3] trickSeek no need to unpause!
14:17:54.4415 [Pixmap] setPixmapNum(0) failed! defined pixmaps: []
14:17:54.4425 [Pixmap] setPixmapNum(0) failed! defined pixmaps: []
14:17:54.4479 [Skin] Processing screen 'MessageBoxSimple' from list 'MessageBoxSimple, MessageBox', position=(480, 532), size=(960 x 15) for module 'MessageBox'.
14:17:54.4631 [Screen] Warning: Skin is missing element 'icon' in <class 'Screens.MessageBox.MessageBox'>(Do you want to resume playback?
Resume position at 0:01:51).14:17:54.4632
14:17:54.4644 [Pixmap] setPixmapNum(0) failed! defined pixmaps: []
14:17:54.4649 [MessageBox] Timeout set to 30 seconds.
14:17:54.4673 [Skin] Processing screen 'MessageBoxSimple_summary' from list 'MessageBoxSimpleSummary, MessageBoxSummary, ScreenSummary, MessageBoxSimple_summary, MessageBox_summary, SimpleSummary', position=(0, 0), size=(800 x 480) for module 'ScreenSummary'.
14:17:54.4707 [Notifications] RemovePopup, id = ZapError
14:17:54.4711 [eServiceMP3] subsink properties set!
14:17:54.4720 [eServiceMP3] loading cuesheet
14:17:54.4727 [eServiceMP3] cuts file has 3 entries
14:17:54.5185 [eServiceMP3] async-done - 1 video, 2 audio, 1 subtitle
14:17:54.5186 [eServiceMP3] AUDIO STRUCT=audio/mpeg
14:17:54.5187 [eServiceMP3] audio stream=0 codec=MPEG-1 Layer 2 (MP2) language=en
14:17:54.5188 [eServiceMP3] AUDIO STRUCT=audio/mpeg
14:17:54.5189 [eServiceMP3] audio stream=1 codec=MPEG-1 Audio language=en
14:17:54.5189 [eServiceMP3] subtitle stream=0 language=und codec=(null)
14:17:54.5193 [eServiceMP3] getSubtitleType::unidentifiable subtitle stream!
14:17:54.5283 [eServiceMP3] switched to subtitle stream 0
14:17:54.5609 [eServiceMP3] state transition PAUSED -> PLAYING
14:17:54.7899 [eServiceMP3] Gstreamer error: Internal data stream error. (1) from tsdemux1
14:17:54.8361 [eInputDeviceInit] 0 160 (352) 1
14:17:54.8361 [eRCDeviceInputDev] emit: 0
14:17:54.8383 [InfoBarGenerics] Key: 352 (Break) KeyID='KEY_OK' Binding='('OK',)'.
14:17:55.7386 [AVSwitch] setting aspect: 16:9
14:17:55.7390 [AVSwitch] setting wss: auto
14:17:55.7393 [AVSwitch] setting policy: panscan
14:17:55.7396 [AVSwitch] setting policy2: letterbox
14:17:56.2413 [DVBCAHandler] no more services
14:17:56.5544 [eInputDeviceInit] 1 6c (108) 1
14:17:56.5545 [eRCDeviceInputDev] emit: 1
14:17:56.5564 [InfoBarGenerics] Key: 108 (Make) KeyID='KEY_DOWN' Binding='('DOWN',)'.
14:17:56.5566 [ActionMap] Keymap 'DirectionActions' -> Action = 'down'.
14:17:56.5567 [MessageBox] Timeout stopped by user input!
14:17:56.8909 [eInputDeviceInit] 0 6c (108) 1
14:17:56.8909 [eRCDeviceInputDev] emit: 0
14:17:56.8928 [InfoBarGenerics] Key: 108 (Break) KeyID='KEY_DOWN' Binding='('DOWN',)'.
14:17:57.2985 [eDVBResourceManager] release cached channel (timer timeout)
14:17:57.2985 [eDVBLocalTimerHandler] remove channel 0xcc2668
14:17:57.2986 [eEPGTransponderDataReader] remove channel 0xcc2668
14:17:57.2989 [eDVBResourceManager] stop release channel timer
14:17:57.3661 [eInputDeviceInit] 1 160 (352) 1
14:17:57.3661 [eRCDeviceInputDev] emit: 1
14:17:57.3680 [InfoBarGenerics] Key: 352 (Make) KeyID='KEY_OK' Binding='('OK',)'.
14:17:57.3682 [ActionMap] Keymap 'MsgBoxActions' -> Action = 'ok'.
14:17:57.7019 [eInputDeviceInit] 0 160 (352) 1
14:17:57.7019 [eRCDeviceInputDev] emit: 0
14:17:57.7039 [InfoBarGenerics] Key: 352 (Break) KeyID='KEY_OK' Binding='('OK',)'.
14:18:00.2996 [eDVBFrontend0] close frontend
14:18:00.2996 [eDVBFrontend0] sendTone allowed only in feSatellite (2)
14:18:00.2997 [eDVBFrontend0] setVoltage FE_ENABLE_HIGH_LNB_VOLTAGE 0 FE_SET_VOLTAGE 2

This is what a .ts file does.

14:38:35.4872 [Trashcan] Debug path /media/hdd/movie/.Trash/ => /media/hdd/movie/.Trash
14:38:35.4941 [eDVBTSTools] setSource loading streaminfo for /media/hdd/movie/.Trash/20220502 1845 - BBC ONE Yk&Li - Look North (East Yorkshire and Lincolnshire).ts
14:38:35.5108 [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 29516, 7659803383 (85108s), fixup to: 29516, 9031 (0s)
14:38:35.8060 [eInputDeviceInit] 0 160 (352) 1
14:38:35.8060 [eRCDeviceInputDev] emit: 0
14:38:35.8082 [InfoBarGenerics] Key: 352 (Break) KeyID='KEY_OK' Binding='('OK',)'.
14:38:37.8398 [eInputDeviceInit] 1 6c (108) 1
14:38:37.8398 [eRCDeviceInputDev] emit: 1
14:38:37.8417 [InfoBarGenerics] Key: 108 (Make) KeyID='KEY_DOWN' Binding='('DOWN',)'.
14:38:37.8420 [ActionMap] Keymap 'DirectionActions' -> Action = 'down'.
14:38:38.1760 [eInputDeviceInit] 0 6c (108) 1
14:38:38.1760 [eRCDeviceInputDev] emit: 0
14:38:38.1783 [InfoBarGenerics] Key: 108 (Break) KeyID='KEY_DOWN' Binding='('DOWN',)'.
14:38:40.8866 [eInputDeviceInit] 1 160 (352) 1
14:38:40.8867 [eRCDeviceInputDev] emit: 1
14:38:40.8887 [InfoBarGenerics] Key: 352 (Make) KeyID='KEY_OK' Binding='('OK',)'.
14:38:40.8889 [ActionMap] Keymap 'OkCancelActions' -> Action = 'ok'.
14:38:40.9044 [Skin] Processing screen 'Screensaver', position=(0, 0), size=(1920 x 1080) for module 'Screensaver'.
14:38:40.9084 [Skin] Parsing embedded skin '<embedded-in-HideVBILine>'.
14:38:40.9091 [Skin] Processing screen '<embedded-in-HideVBILine>', position=(0, 0), size=(1920 x 4) for module 'HideVBILine'.
14:38:40.9143 [Skin] Processing screen 'PVRState', position=(192, 172), size=(225 x 45) for module 'PVRState'.
14:38:40.9149 [Screen] Warning: Skin is missing element 'eventname' in <class 'Screens.PVRState.PVRState'>.
14:38:40.9154 [Screen] Warning: Skin is missing element 'speed' in <class 'Screens.PVRState.PVRState'>.
14:38:40.9158 [Screen] Warning: Skin is missing element 'statusicon' in <class 'Screens.PVRState.PVRState'>.
14:38:40.9167 [Notifications] RemovePopup, id = ZapError
14:38:40.9217 [Navigation] playing ref 1:0:0:0:0:0:0:0:0:0:/media/hdd/movie/.Trash/20220502 1845 - BBC ONE Yk&Li - Look North (East Yorkshire and Lincolnshire).ts
14:38:40.9220 [eDVBCAService] free slot 0 demux 0 for service 1:0:19:4484:4089:233A:EEEE0000:0:0:0:
14:38:40.9220 [eDVBCAService] free service 1:0:19:4484:4089:233A:EEEE0000:0:0:0:
14:38:40.9360 [Pixmap] setPixmapNum(0) failed! defined pixmaps: []
14:38:40.9375 [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
14:38:40.9376 [eDVBPCR0] DEMUX_STOP ok
14:38:40.9376 [eDVBPCR0] destroy
14:38:40.9376 [eDVBVideo0] DEMUX_STOP  ok
14:38:40.9411 [eDVBVideo0] VIDEO_STOP ok
14:38:40.9448 [eDVBVideo0] destroy
14:38:40.9448 [eDVBAudio0] AUDIO_STOP ok
14:38:40.9728 [eDVBAudio0] DEMUX_STOP ok
14:38:40.9728 [eDVBAudio0] AUDIO_CONTINUE ok
14:38:40.9816 [eDVBAudio0] destroy
14:38:40.9817 [decoder][eDVBText] resetPID
14:38:40.9817 [decoder][eDVBText] freeCache
14:38:40.9830 [eDVBResourceManager] start release channel timer
14:38:40.9833 [servicedvb][eDVBServicePlay] now running: m_is_streamx set by m_is_stream 0
14:38:40.9833 [servicedvb][eDVBServicePlay] now running: m_is_pvr set to; 1
14:38:40.9834 [servicedvb][eDVBServicePlay][start] m_is_stream set to: 0
14:38:40.9834 [servicedvb][eDVBServicePlay][start] m_is_pvr set to; 1
14:38:40.9835 [eDVBServicePMTHandler] alloc PVR
14:38:40.9836 [eDVBLocalTimerHandler] remove channel 0xcbae00
14:38:40.9836 [eEPGTransponderDataReader] remove channel 0xcbae00
14:38:40.9874 [eEPGChannelData] abort caching events !!
14:38:40.9893 [eDVBChannel] getDemux cap=00
14:38:40.9893 [eDVBResourceManager] allocate demux cap=00
14:38:40.9894 [eDVBResourceManager] allocating demux adapter=0, demux=22, source=-1 fesource=-1
14:38:40.9894 [eDVBDemux] open demux /dev/dvb/adapter0/demux22
14:38:40.9895 [eDVBTSTools] setSource loading streaminfo for /media/hdd/movie/.Trash/20220502 1845 - BBC ONE Yk&Li - Look North (East Yorkshire and Lincolnshire).ts
14:38:40.9910 [eDVBDemux] open dvr /dev/dvb/adapter0/dvr22
14:38:40.9911 [eDVBServicePlay] cuts file has 2 entries
14:38:40.9912 [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 29516, 7659803383 (85108s), fixup to: 29516, 9031 (0s)
14:38:40.9950 [setIoPrio] best-effort level 0 ok
14:38:40.9950 [eDVBServicePMTHandler] ok ... now we start!!
14:38:40.9950 [eFilePushThread] START thread
14:38:40.9950 [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
14:38:40.9950 [eDVBServicePlay] have 1 video stream(s) (0065), and 1 audio stream(s) (0066), and the pcr pid is 0065, and the text pid is ffffffff
14:38:40.9952 [decoder][eDVBText] initCache
14:38:40.9980 [eTSMPEGDecoder] decoder state: play, vpid=0065, apid=0066
14:38:41.0022 [eDVBAudio0] DMX_SET_PES_FILTER pid=0x0066 ok
14:38:41.0022 [eDVBAudio0] DEMUX_START ok
14:38:41.0024 [eDVBAudio0] AUDIO_SET_BYPASS bypass=1 ok
14:38:41.0105 [eDVBAudio0] AUDIO_PAUSE ok
14:38:41.0140 [eDVBAudio0] AUDIO_PLAY ok
14:38:41.0206 [eDVBVideo] Video Device: /dev/dvb/adapter0/video0
14:38:41.0207 [eDVBVideo] demux device: /dev/dvb/adapter0/demux22
14:38:41.0253 [eDVBVideo0] VIDEO_SET_STREAMTYPE 0 - ok
14:38:41.0295 [eDVBVideo0] DMX_SET_PES_FILTER pid=0x0065 ok
14:38:41.0296 [eDVBVideo0] DEMUX_START ok
14:38:41.0297 [eDVBVideo0] VIDEO_FREEZE ok
14:38:41.0334 [eDVBVideo0] VIDEO_PLAY ok
14:38:41.0425 [decoder][eDVBText] resetPID
14:38:41.0425 [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
14:38:41.0468 [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
14:38:41.0517 [eDVBVideo0] VIDEO_CONTINUE ok
14:38:41.0558 [eDVBAudio0] AUDIO_CONTINUE ok
14:38:41.0645 [servicedvb][eDVBServicePlay] m_teletext_parser active
14:38:41.0645 [eDVBTeletextParser] Starting!
14:38:41.0645 [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
14:38:41.0646 [eDVBPESReader] Created. Opening demux
14:38:41.0646 [eDVBDemux] open demux /dev/dvb/adapter0/demux22
14:38:41.0647 [eDVBTeletextParser] created teletext subtitle PES reader!
14:38:41.0648 [eDVBPESReader] Created. Opening demux
14:38:41.0648 [eDVBDemux] open demux /dev/dvb/adapter0/demux22
14:38:41.0649 [eDVBTeletextParser] starting PES reader on pid=ffffffff
14:38:41.0655 [Pixmap] setPixmapNum(0) failed! defined pixmaps: []
14:38:41.0657 [eDVBServicePlay] seekTo 0
14:38:41.0657 [eDVBChannel] seek.
14:38:41.0657 [eFilePushThread] waiting for pause
14:38:41.0749 [eFilePushThread] PAUSED
14:38:41.0999 [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
14:38:41.1186 [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
14:38:41.1287 [eDVBChannel] ok, resolved skip (rel: 0, diff 0), now at                0
14:38:41.1531 [eDVBDemux] open demux /dev/dvb/adapter0/demux22
14:38:41.1532 [eDVBSectionReader] DMX_SET_FILTER pid=0
14:38:41.1538 [eDVBDemux] open demux /dev/dvb/adapter0/demux22
14:38:41.1539 [eDVBSectionReader] DMX_SET_FILTER pid=18
14:38:41.1549 [Notifications] RemovePopup, id = ZapError
14:38:41.1797 1:0:1:104F:104F:233A:EEEE0000:0:0:0::BBC ONE Yk&Li 1:0:1:104F:104F:233A:EEEE0000:0:0:0::BBC ONE Yk&Li
14:38:41.1806 [eDVBServicePlay] unpause
14:38:41.1810 [Pixmap] setPixmapNum(0) failed! defined pixmaps: []
14:38:41.1816 [Pixmap] setPixmapNum(0) failed! defined pixmaps: []
14:38:41.1822 [Notifications] RemovePopup, id = ZapError
14:38:41.1828 [Skin] Processing screen 'MoviePlayer', position=(150, 67), size=(1620 x 150) for module 'MoviePlayer'.
14:38:41.2086 [Screen] Warning: Skin is missing element 'key_yellow' in <class 'Screens.InfoBar.MoviePlayer'>.
14:38:41.2091 [Screen] Warning: Skin is missing element 'key_blue' in <class 'Screens.InfoBar.MoviePlayer'>.
14:38:41.2095 [Screen] Warning: Skin is missing element 'key_green' in <class 'Screens.InfoBar.MoviePlayer'>.
14:38:41.2099 [Screen] Warning: Skin is missing element 'eventname' in <class 'Screens.InfoBar.MoviePlayer'>.
14:38:41.2104 [Screen] Warning: Skin is missing element 'state' in <class 'Screens.InfoBar.MoviePlayer'>.
14:38:41.2278 [Skin] Processing screen 'InfoBarMoviePlayerSummary', position=(0, 0), size=(800 x 480) for module 'InfoBarMoviePlayerSummary'.
14:38:41.2589 1:0:1:104F:104F:233A:EEEE0000:0:0:0::BBC ONE Yk&Li 1:0:1:104F:104F:233A:EEEE0000:0:0:0::BBC ONE Yk&Li
14:38:41.2670 [eDVBChannel] pvrEvent evtStopped