LE 11 - live tv sometime fail to launch

celedhrim · February 6, 2023 at 7:51 PM

Hello,

I'm on pi4.
I've update to nexus with the first libreelec beta and I have a dedicated Pi4 with raspbian and tvheadend ( untouched during upgrade )

Everything is fine BUT sometimes live tv refuse to launch , when is click on a tv channel , spinner stay.

If a reboot , live tv work fine again.

Quick point :
* untouched separated tvheadend server
* fresh install beta
* Never had such issue before LE11 beta 1 (LE10 , LE9 , etc )
* Movies /tvshow , music still play fine ( form what I saw)
* Same behavior with latest nightly

Currently I have no clue about the trigger but in kodi log I have this :

Code: NOT LAUNCHING LIVE TV

2023-02-06 19:10:05.687 T:32673    info <general>: Opening stream: 1005 source: 256
2023-02-06 19:10:05.692 T:32674    info <general>: CVideoPlayerAudio: display reset occurred, checking for passthrough
2023-02-06 19:10:05.693 T:32674    info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder eac3
2023-02-06 19:10:05.693 T:32674    info <general>: Creating audio stream (codec id: 86056, channels: 2, sample rate: 48000, no pass-through)
2023-02-06 19:10:05.694 T:885      info <general>: CActiveAESink::OpenSink - initialize sink
2023-02-06 19:10:05.694 T:885      info <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
2023-02-06 19:10:05.702 T:885      info <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
2023-02-06 19:10:05.702 T:885      info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-02-06 19:10:05.702 T:885      info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2023-02-06 19:10:08.882 T:32683   error <general>: CDVDVideoCodecDRMPRIME::ProcessFilterOut - buffersink get frame failed: Invalid argument (-22)
2023-02-06 19:10:21.682 T:32683    info <general>: Skipped 3 duplicate messages..
2023-02-06 19:10:21.682 T:32683   error <general>: CDVDVideoCodecDRMPRIME::ProcessFilterOut - buffersink get frame failed: Invalid argument (-22)
2023-02-06 19:10:34.482 T:32683    info <general>: Skipped 3 duplicate messages..
2023-02-06 19:10:34.482 T:32683   error <general>: CDVDVideoCodecDRMPRIME::ProcessFilterOut - buffersink get frame failed: Invalid argument (-22)
2023-02-06 19:10:47.282 T:32683    info <general>: Skipped 3 duplicate messages..
2023-02-06 19:10:47.282 T:32683   error <general>: CDVDVideoCodecDRMPRIME::ProcessFilterOut - buffersink get frame failed: Invalid argument (-22)
2023-02-06 19:10:48.058 T:872      info <general>: CVideoPlayer::CloseFile()
2023-02-06 19:10:48.058 T:872      info <general>: VideoPlayer: waiting for threads to exit
2023-02-06 19:10:48.060 T:32673    info <general>: CVideoPlayer::OnExit()
2023-02-06 19:10:48.060 T:32673    info <general>: Closing stream player 1
2023-02-06 19:10:48.060 T:32673    info <general>: Waiting for audio thread to exit
2023-02-06 19:10:48.060 T:32674    info <general>: thread end: CVideoPlayerAudio::OnExit()
2023-02-06 19:10:48.060 T:32673    info <general>: Closing audio device
2023-02-06 19:10:48.106 T:32673    info <general>: Deleting audio codec
2023-02-06 19:10:48.106 T:32673    info <general>: Closing stream player

Display More

After reboot :

Code: LAUNCHING FINE

2023-02-06 19:14:02.427 T:960      info <general>: Opening stream: 1002 source: 256
2023-02-06 19:14:02.427 T:960      info <general>: Finding audio codec for: 86056
2023-02-06 19:14:02.428 T:960      info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder eac3
2023-02-06 19:14:02.428 T:960      info <general>: Creating audio thread
2023-02-06 19:14:02.428 T:999      info <general>: running thread: CVideoPlayerAudio::Process()
2023-02-06 19:14:02.428 T:960      info <general>: Opening stream: 1005 source: 256
2023-02-06 19:14:02.483 T:876      info <general>: VideoPlayer: OnResetDisplay received
2023-02-06 19:14:02.483 T:876   warning <general>: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
2023-02-06 19:14:02.483 T:885      info <general>: CActiveAESink::OpenSink - initialize sink
2023-02-06 19:14:02.483 T:885      info <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
2023-02-06 19:14:02.487 T:885      info <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
2023-02-06 19:14:02.488 T:885      info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-02-06 19:14:02.488 T:885      info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2023-02-06 19:14:02.627 T:960      info <general>: Opening stream: 1001 source: 256
2023-02-06 19:14:02.627 T:960      info <general>: [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 25.000, 3D: false
2023-02-06 19:14:02.627 T:960      info <general>: Display resolution ADJUST : 1920x1080 @ 25.000000 Hz (30) (weight: -nan)
2023-02-06 19:14:02.643 T:960      info <general>: Creating video codec with codec id: 27
2023-02-06 19:14:02.643 T:960      info <general>: CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper
2023-02-06 19:14:02.689 T:960      info <general>: Creating video thread
2023-02-06 19:14:02.689 T:1004     info <general>: running thread: video_thread
2023-02-06 19:14:02.690 T:960      info <general>: Opening stream: 1002 source: 256
2023-02-06 19:14:02.690 T:960      info <general>: Finding audio codec for: 86056
2023-02-06 19:14:02.690 T:960      info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder eac3
2023-02-06 19:14:02.691 T:960      info <general>: Opening stream: 1005 source: 256
2023-02-06 19:14:02.699 T:999      info <general>: CVideoPlayerAudio: display reset occurred, checking for passthrough
2023-02-06 19:14:02.700 T:999      info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder eac3
2023-02-06 19:14:02.701 T:999      info <general>: Creating audio stream (codec id: 86056, channels: 2, sample rate: 48000, no pass-through)
2023-02-06 19:14:02.701 T:885      info <general>: CActiveAESink::OpenSink - initialize sink
2023-02-06 19:14:02.701 T:885      info <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
2023-02-06 19:14:02.705 T:885      info <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
2023-02-06 19:14:02.706 T:885      info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-02-06 19:14:02.706 T:885      info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2023-02-06 19:14:02.961 T:906      info <general>: JSONRPC Server: New connection added
2023-02-06 19:14:03.403 T:876      info <general>: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
2023-02-06 19:14:03.411 T:876      info <general>: [WHITELIST] Searching the whitelist for: width: 1920, height: 1080, fps: 50.000, 3D: false
2023-02-06 19:14:03.411 T:876      info <general>: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (26) (weight: 0.000)
2023-02-06 19:14:03.603 T:899      info <general>: [ script.embuary.info ] Finished next airing database refreshing
2023-02-06 19:14:03.659 T:876      info <general>: VideoPlayer: OnLostDisplay received
2023-02-06 19:14:03.659 T:876      info <general>: GLES: Maximum texture width: 4096
2023-02-06 19:14:03.690 T:876      info <general>: VideoPlayer: OnResetDisplay received
2023-02-06 19:14:03.690 T:885      info <general>: CActiveAESink::OpenSink - initialize sink
2023-02-06 19:14:03.690 T:885      info <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
2023-02-06 19:14:03.694 T:885      info <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
2023-02-06 19:14:03.694 T:885      info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-02-06 19:14:03.695 T:885      info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2023-02-06 19:14:03.697 T:999      info <general>: CVideoPlayerAudio: display reset occurred, checking for passthrough
2023-02-06 19:14:03.698 T:999      info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder eac3
2023-02-06 19:14:03.869 T:1004  warning <general>: OutputPicture - timeout waiting for buffer
2023-02-06 19:14:28.341 T:922      info <general>: Skipped 2 duplicate messages..
2023-02-06 19:14:28.341 T:922      info <general>: PVR Manager: Starting cleanup of cached images.
2023-02-06 19:14:30.355 T:922      info <general>: PVR Manager: Cleaned up 14 cached images.
2023-02-06 19:16:59.651 T:876      info <general>: NFS is idle. Closing the remaining connections.
2023-02-06 19:35:46.180 T:876      info <general>: Loading skin file: DialogPVRChannelsOSD.xml, load type: LOAD_EVERY_TIME
2023-02-06 19:36:05.956 T:876      info <general>: CVideoPlayer::CloseFile()
2023-02-06 19:36:05.956 T:876      info <general>: VideoPlayer: waiting for threads to exit
2023-02-06 19:36:06.008 T:960      info <general>: CVideoPlayer::OnExit()

Display More

Because a reboot solve this, maybe it's memory or buffer somewhere that fill , I don't know , and I don't find many this about CDVDVideoCodecDRMPRIME::ProcessFilterOut - buffersink get frame failed: Invalid argument (-22) on internet.

Regards,

celedhrim · February 6, 2023 at 10:57 PM

It just happen again .

It's correlated with these messages from dmesg :

Code

[ 7086.203327] bcm2835_mmal_vchiq: timed out waiting for sync completion
[ 7086.203357] bcm2835_mmal_vchiq: vchiq_mmal_component_init: failed to create component -62 (Not enough GPU mem?)
[ 7086.203374] bcm2835-codec bcm2835-codec: bcm2835_codec_create_component: failed to create component ril.video_decode
[ 7089.616660] bcm2835_mmal_vchiq: timed out waiting for sync completion
[ 7089.616678] bcm2835_mmal_vchiq: vchiq_mmal_component_init: failed to create component -62 (Not enough GPU mem?)
[ 7089.616688] bcm2835-codec bcm2835-codec: bcm2835_codec_create_component: failed to create component ril.image_fx

I have increase gpu_mem from 76 to 128 , I'll keep you inform

celedhrim · February 8, 2023 at 8:18 AM

Issue persist.

I've rollback to LE10 Matrix because reboot is a not a workaround for my girlfriend

Don't hesitate to tell me if you have any idea or if you wish a test something ( it's just a sd card switch for me )

celedhrim · February 19, 2023 at 8:39 AM

Same behavior with beta2

celedhrim · March 14, 2023 at 7:35 PM

Still the same with https://test.libreelec.tv/11.0/RPi/RPi4/…-8b01ceb.img.gz ( kodi 20.1)

celedhrim · March 24, 2023 at 7:30 PM

Closed because no activity