search

add-ons / plugin.video.vrt.nu

Kodi add-on to watch content from VRT MAX
https://www.facebook.com/groups/kodivlaanderen
GNU General Public License v3.0
110 stars 20 forks source link

Audio stream loopt achter #1086

Closed redzabu closed 1 year ago

redzabu commented 1 year ago

Describe the bug

Bij het overgrote deel van de programma's van de VRT loopt de audio steeds meer achter op het beeld; deze foutieve sync wordt dan na enige tijd 'bijgewerkt' doordat de video-stream en audio-stream (samen) hervatten waarna de audio dan opnieuw steeds meer achterop loopt - tot aan de volgende 'hickup'. Bij de overige add-ons (ARTE TV, NASA, ESA, Hardware, etc) doet dit probleem zich niet voor; ook sommige programma's bij de VRT vertonen dit euvel niet ?!?!

To Reproduce

Dit gedrag is consistent en (bij mij) perfect reproduceerbaar (al vrees ik zowat alleen te zijn daarbij)

Additional context

Log (if available)

2022-12-29 10:28:34.438 T:1131 INFO : ----------------------------------------------------------------------- 2022-12-29 10:28:34.438 T:1131 INFO : Starting Kodi (19.4 (19.4.0) Git:19.4-Matrix). Platform: Linux ARM 32-bit 2022-12-29 10:28:34.438 T:1131 INFO : Using Release Kodi x32 2022-12-29 10:28:34.438 T:1131 INFO : Kodi compiled 2022-03-05 by GCC 10.2.0 for Linux ARM 32-bit version 5.10.95 (330335) 2022-12-29 10:28:34.438 T:1131 INFO : Running on BCM2835 with LibreELEC (official): 10.0.2, kernel: Linux ARM 64-bit version 5.10.95 2022-12-29 10:28:34.438 T:1131 INFO : FFmpeg version/source: 4.3.2-Kodi 2022-12-29 10:28:34.438 T:1131 INFO : 4 CPU cores available 2022-12-29 10:28:34.438 T:1131 INFO : ARM Features: Neon enabled 2022-12-29 10:28:34.438 T:1131 INFO : special://xbmc/ is mapped to: /usr/share/kodi/ 2022-12-29 10:28:34.438 T:1131 INFO : special://xbmcbin/ is mapped to: /usr/lib/kodi 2022-12-29 10:28:34.439 T:1131 INFO : special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons 2022-12-29 10:28:34.439 T:1131 INFO : special://masterprofile/ is mapped to: /storage/.kodi/userdata 2022-12-29 10:28:34.439 T:1131 INFO : special://envhome/ is mapped to: /storage 2022-12-29 10:28:34.439 T:1131 INFO : special://home/ is mapped to: /storage/.kodi 2022-12-29 10:28:34.439 T:1131 INFO : special://temp/ is mapped to: /storage/.kodi/temp 2022-12-29 10:28:34.439 T:1131 INFO : special://logpath/ is mapped to: /storage/.kodi/temp 2022-12-29 10:28:34.439 T:1131 INFO : The executable running is: /usr/lib/kodi/kodi.bin 2022-12-29 10:28:34.439 T:1131 INFO : Local hostname: Kodi 2022-12-29 10:28:34.439 T:1131 INFO : Log File is located: /storage/.kodi/temp/kodi.log etc etc

====================================================================================================== Hier startte ik het 'weerbericht van 28/12/2022' - waar het dus fout loopt

======================================================================================================

2022-12-29 10:34:38.464 T:1137 INFO : CActiveAESink::OpenSink - initialize sink 2022-12-29 10:34:38.464 T:1137 INFO : CAESinkALSA::Initialize - Attempting to open device "iec958:CARD=D10s,DEV=0" 2022-12-29 10:34:38.476 T:1137 INFO : CAESinkALSA::Initialize - Opened device "iec958:CARD=D10s,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 2022-12-29 10:34:38.479 T:1137 INFO : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2022-12-29 10:34:38.479 T:1137 INFO : CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 2022-12-29 10:34:41.841 T:1131 INFO : Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY 2022-12-29 10:34:42.017 T:1312 INFO : initializing python engine. 2022-12-29 10:34:45.077 T:1312 INFO : CPythonInvoker(7, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): script successfully run 2022-12-29 10:34:48.431 T:1312 INFO : initializing python engine. 2022-12-29 10:34:49.253 T:1131 INFO : VideoPlayer::OpenFile: plugin://plugin.video.vrt.nu/play/id/vid-9c7aec4c-00f3-4fc6-a372-6651f8477301/pbs-pub-d93b8cd4-9ac0-43b8-8cce-0f417f9f6c73/1671557579111 2022-12-29 10:34:49.260 T:1318 INFO : Creating InputStream 2022-12-29 10:34:49.306 T:1312 INFO : CPythonInvoker(7, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): script successfully run 2022-12-29 10:34:49.330 T:1318 INFO : AddOnLog: inputstream.adaptive: SetVideoResolution (1920 x 1080) 2022-12-29 10:34:49.445 T:1318 INFO : AddOnLog: inputstream.adaptive: Successfully parsed manifest file. #Periods: 1, #Streams in first period: 3, Type: VOD, Download speed: 7596148.5318 Bytes/s 2022-12-29 10:34:49.745 T:1318 INFO : Creating Demuxer 2022-12-29 10:34:49.745 T:1318 INFO : Opening stream: 1001 source: 256 2022-12-29 10:34:49.769 T:1318 INFO : Creating video codec with codec id: 27 2022-12-29 10:34:49.769 T:1318 INFO : CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper 2022-12-29 10:34:49.844 T:1318 INFO : Creating video thread 2022-12-29 10:34:49.844 T:1327 INFO : running thread: video_thread 2022-12-29 10:34:49.844 T:1318 INFO : Opening stream: 1002 source: 256 2022-12-29 10:34:49.877 T:1318 INFO : Finding audio codec for: 86018 2022-12-29 10:34:49.881 T:1318 INFO : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2022-12-29 10:34:49.881 T:1318 INFO : Creating audio thread 2022-12-29 10:34:49.882 T:1329 INFO : running thread: CVideoPlayerAudio::Process() 2022-12-29 10:34:49.882 T:1318 INFO : Opening stream: 1003 source: 256 2022-12-29 10:34:50.186 T:1329 INFO : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2022-12-29 10:34:50.186 T:1329 INFO : Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through) 2022-12-29 10:34:50.237 T:1137 INFO : CActiveAESink::OpenSink - initialize sink 2022-12-29 10:34:50.450 T:1137 INFO : CAESinkALSA::Initialize - Attempting to open device "iec958:CARD=D10s,DEV=0" 2022-12-29 10:34:50.457 T:1137 INFO : CAESinkALSA::Initialize - Opened device "iec958:CARD=D10s,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 2022-12-29 10:34:50.458 T:1137 INFO : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2022-12-29 10:34:50.458 T:1137 INFO : CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 2022-12-29 10:34:50.576 T:1131 INFO : Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 2022-12-29 10:35:11.671 T:1327 WARNING : OutputPicture - timeout waiting for buffer 2022-12-29 10:36:08.255 T:1329 INFO : CVideoPlayerAudio::Process - stream stalled 2022-12-29 10:36:08.271 T:1327 WARNING : OutputPicture - timeout waiting for buffer 2022-12-29 10:36:08.302 T:1318 INFO : AddOnLog: inputstream.adaptive: PosTime (77576) 2022-12-29 10:36:08.348 T:1318 INFO : AddOnLog: inputstream.adaptive: seekTime(77.6) for Stream:1 continues at 80.5 (PTS: 80531666) 2022-12-29 10:36:08.948 T:1318 INFO : AddOnLog: inputstream.adaptive: seekTime(80.5) for Stream:2 continues at 80.5 (PTS: 80512000) 2022-12-29 10:36:08.967 T:1318 INFO : AddOnLog: inputstream.adaptive: seekTime(80.5) for Stream:3 continues at 80.0 (PTS: 80000000) 2022-12-29 10:37:27.442 T:1329 INFO : CVideoPlayerAudio::Process - stream stalled 2022-12-29 10:37:27.471 T:1327 WARNING : OutputPicture - timeout waiting for buffer 2022-12-29 10:37:27.477 T:1318 INFO : AddOnLog: inputstream.adaptive: PosTime (158861) 2022-12-29 10:37:27.584 T:1318 INFO : AddOnLog: inputstream.adaptive: seekTime(158.9) for Stream:1 continues at 161.2 (PTS: 161171666) 2022-12-29 10:37:27.644 T:1318 INFO : AddOnLog: inputstream.adaptive: seekTime(161.2) for Stream:2 continues at 161.2 (PTS: 161152000) 2022-12-29 10:37:27.644 T:1318 INFO : AddOnLog: inputstream.adaptive: seekTime(161.2) for Stream:3 continues at 160.3 (PTS: 160333000) 2022-12-29 10:38:33.496 T:1329 INFO : CVideoPlayerAudio::Process - stream stalled 2022-12-29 10:38:40.962 T:1318 INFO : Process - eof reading from demuxer 2022-12-29 10:38:40.962 T:1318 INFO : CVideoPlayer::OnExit() 2022-12-29 10:38:40.962 T:1318 INFO : VideoPlayer: eof, waiting for queues to empty 2022-12-29 10:38:40.962 T:1318 INFO : Closing stream player 1 2022-12-29 10:38:40.964 T:1318 INFO : CDVDMessageQueue(audio)::WaitUntilEmpty 2022-12-29 10:38:40.964 T:1318 INFO : Waiting for audio thread to exit 2022-12-29 10:38:40.964 T:1329 ERROR : Got MSGQ_ABORT or MSGO_IS_ERROR return true 2022-12-29 10:38:40.964 T:1329 INFO : thread end: CVideoPlayerAudio::OnExit() 2022-12-29 10:38:40.964 T:1318 INFO : Closing audio device 2022-12-29 10:38:40.965 T:1136 WARNING : ActiveAE - large audio sync error: -7301.296086 2022-12-29 10:38:40.967 T:1318 INFO : Deleting audio codec 2022-12-29 10:38:40.967 T:1318 INFO : Closing stream player 2 2022-12-29 10:38:40.968 T:1318 INFO : CDVDMessageQueue(video)::WaitUntilEmpty 2022-12-29 10:38:41.072 T:1318 INFO : waiting for video thread to exit 2022-12-29 10:38:41.072 T:1327 ERROR : Got MSGQ_ABORT or MSGO_IS_ERROR return true 2022-12-29 10:38:41.072 T:1327 INFO : thread end: video_thread 2022-12-29 10:38:41.072 T:1318 INFO : deleting video codec 2022-12-29 10:38:41.087 T:1318 INFO : Closing stream player 3 2022-12-29 10:38:41.090 T:1318 INFO : ADDON: Dll Destroyed - InputStream Adaptive 2022-12-29 10:38:41.097 T:1354 INFO : Deleting settings information for files plugin://plugin.video.vrt.nu/play/id/vid-9c7aec4c-00f3-4fc6-a372-6651f8477301/pbs-pub-d93b8cd4-9ac0-43b8-8cce-0f417f9f6c73/1671557579111 2022-12-29 10:38:41.142 T:1131 INFO : CVideoPlayer::CloseFile() 2022-12-29 10:38:41.147 T:1131 INFO : VideoPlayer: waiting for threads to exit 2022-12-29 10:38:41.148 T:1131 INFO : VideoPlayer: finished waiting 2022-12-29 10:38:41.148 T:1131 INFO : CVideoPlayer::CloseFile() 2022-12-29 10:38:41.148 T:1131 INFO : VideoPlayer: waiting for threads to exit 2022-12-29 10:38:41.148 T:1131 INFO : VideoPlayer: finished waiting 2022-12-29 10:38:41.155 T:1137 INFO : CActiveAESink::OpenSink - initialize sink 2022-12-29 10:38:41.185 T:1131 ERROR : Control 55 in window 10025 has been asked to focus, but it can't 2022-12-29 10:38:41.198 T:1312 INFO : initializing python engine. 2022-12-29 10:38:41.367 T:1137 INFO : CAESinkALSA::Initialize - Attempting to open device "iec958:CARD=D10s,DEV=0" 2022-12-29 10:38:41.377 T:1137 INFO : CAESinkALSA::Initialize - Opened device "iec958:CARD=D10s,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 2022-12-29 10:38:41.379 T:1137 INFO : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2022-12-29 10:38:41.379 T:1137 INFO : CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 2022-12-29 10:38:42.577 T:1312 INFO : CPythonInvoker(7, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): script successfully run 2022-12-29 10:38:57.208 T:1131 INFO : Loading skin file: DialogButtonMenu.xml, load type: KEEP_IN_MEMORY 2022-12-29 10:39:01.404 T:1131 INFO : Saving exitCode 66 2022-12-29 10:39:01.637 T:1131 INFO : Quitting due to POSIX signal 2022-12-29 10:39:01.670 T:1131 INFO : Stopping player 2022-12-29 10:39:01.670 T:1131 INFO : Storing total System Uptime 2022-12-29 10:39:01.671 T:1131 INFO : Saving settings 2022-12-29 10:39:01.683 T:1131 INFO : Saving skin settings 2022-12-29 10:39:01.685 T:1131 INFO : Stopping all 2022-12-29 10:39:01.685 T:1131 INFO : ES: Stopping event server 2022-12-29 10:39:01.917 T:1163 INFO : ES: UDP Event server stopped 2022-12-29 10:39:02.039 T:1131 INFO : stopping upnp 2022-12-29 10:39:02.191 T:1131 INFO : stopping zeroconf publishing 2022-12-29 10:39:02.195 T:1131 INFO <CWebserver[8080]>: Stopped 2022-12-29 10:39:02.195 T:1131 INFO : stop dvd detect media

Hier eindigt de sessie met nog wat rotzooi over ontbrekende Bluetooth - heb ik weggelaten ...

mediaminister commented 1 year ago

Graag voorbeelden van welke programma's dit euvel vertonen en welke niet.

redzabu commented 1 year ago

Programma's met foute sync: Het nieuws, het weer, blokken, en de meest actuele programma's (ook live tv)

Programma's die correct syncen (indeling zoals te vinden in de vrt-app): Categories / Documentary Thisisegypt2 Achter de artiest / Season 1 (all with good sync) Season 2 (all -for what i saw - with bad sync) Betty Davis Breaking Europe / 1x01 tot en met 1x12 (all with good sync) California Love / 2017x01 tem 2017x04 (all with good sync) Entertainment / 1 jaar gratis (blijkbaar allemaal goede sync)

... en toen was mijn geduld op ;-) ...

mediaminister commented 1 year ago

Duidelijk, 1080p50 video speelt slecht af op de Raspberry Pi 4 en veroorzaakt foute sync. 540p25 video speelt goed af.

Het ligt niet aan de VRT MAX add-on, maar het lijkt me eerder een probleem met decoding of videoinstellingen van LibreELEC op de Raspberry Pi. Je moet het zo instellen dat 1080p50 video wordt ondersteund. Misschien vind je op https://forum.libreelec.tv/ de correcte instellingen.

Je kan de resolutie in de VRT MAX add-on wel verlagen naar 720p50 door bij "Playback" de maximale bandbreedte op 4096 te zetten. (Of 3072 voor 540p25)

redzabu commented 1 year ago

Yup - Problem solved!

In mijn overmoed, had ik de video (Kodi - system) ingesteld op 4K; als ik deze terug breng naar 1920x1080p50 loopt alles prima!

Tnx!

On Fri, 30 Dec 2022 at 15:17, mediaminister @.***> wrote:

Duidelijk, 1080p50 video speelt slecht af op de Raspberry Pi 4 en veroorzaakt foute sync. 540p25 video speelt goed af.

Het ligt niet aan de VRT MAX add-on, maar het lijkt me eerder een probleem met decoding of videoinstellingen van LibreELEC op de Raspberry Pi. Je moet het zo instellen dat 1080p50 video wordt ondersteund. Misschien vind je op https://forum.libreelec.tv/ de correcte instellingen.

Je kan de resolutie in de VRT MAX add-on wel verlagen naar 720p50 door bij "Playback" de maximale bandbreedte op 4096 te zetten.

— Reply to this email directly, view it on GitHub https://github.com/add-ons/plugin.video.vrt.nu/issues/1086#issuecomment-1367943092, or unsubscribe https://github.com/notifications/unsubscribe-auth/AI2GHBAG2KWWXRFCT25YJ7LWP3VIHANCNFSM6AAAAAATL66KGM . You are receiving this because you authored the thread.Message ID: @.***>

mediaminister commented 1 year ago

Oké, goed te horen dat het probleem opgelost is.