Sound but no video? (4k, x264, non-HDR)

  • Noticed this recently with some videos. Sound plays ok, no video.

    Log says right after playing file:

    2022-02-20 00:04:30.547 T:1851 ERROR <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)

    2022-02-20 00:04:40.800 T:1852 INFO <general>: Skipped 3 duplicate messages..

    2022-02-20 00:04:40.800 T:1852 INFO <general>: CVideoPlayerAudio::Process - stream stalled

    2022-02-20 00:04:41.815 T:1078 INFO <general>: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT

    2022-02-20 00:04:45.559 T:1851 ERROR <general>: CDVDVideoCodecDRMPRIME::Reset - receive frame failed: Resource temporarily unavailable (-11)

    2022-02-20 00:05:00.982 T:1851 ERROR <general>: CDVDVideoCodecDRMPRIME::Reset - receive frame failed: Resource temporarily unavailable (-11)

    2022-02-20 00:05:04.649 T:1078 INFO <general>: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY

    2022-02-20 00:05:07.496 T:1851 ERROR <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)

    2022-02-20 00:05:17.969 T:1852 INFO <general>: Skipped 3 duplicate messages..

    2022-02-20 00:05:17.969 T:1852 INFO <general>: CVideoPlayerAudio::Process - stream stalled

    2022-02-20 00:05:20.981 T:1078 INFO <general>: CVideoPlayer::CloseFile()

    2022-02-20 00:05:20.981 T:1078 INFO <general>: VideoPlayer: waiting for threads to exit

    2022-02-20 00:05:20.989 T:1850 INFO <general>: CVideoPlayer::OnExit()

    2022-02-20 00:05:20.989 T:1850 INFO <general>: Closing stream player 1

    2022-02-20 00:05:20.989 T:1850 INFO <general>: Waiting for audio thread to exit

    2022-02-20 00:05:20.998 T:1852 INFO <general>: thread end: CVideoPlayerAudio::OnExit()

    2022-02-20 00:05:20.998 T:1850 INFO <general>: Closing audio device

    2022-02-20 00:05:20.999 T:1850 INFO <general>: Deleting audio codec

    2022-02-20 00:05:20.999 T:1850 INFO <general>: Closing stream player 2

    2022-02-20 00:05:20.999 T:1850 INFO <general>: waiting for video thread to exit

    2022-02-20 00:05:21.212 T:1084 INFO <general>: CActiveAESink::OpenSink - initialize sink

    2022-02-20 00:05:21.415 T:1084 INFO <general>: CAESinkALSA::Initialize - Attempting to open device "@:CARD=Audio,DEV=0"

    2022-02-20 00:05:21.439 T:1084 INFO <general>: CAESinkALSA::Initialize - Opened device "sysdefault:CARD=Audio"

    2022-02-20 00:05:21.441 T:1084 INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats

    2022-02-20 00:05:21.442 T:1084 INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE

    2022-02-20 00:05:22.511 T:1851 ERROR <general>: CDVDVideoCodecDRMPRIME::Reset - receive frame failed: Resource temporarily unavailable (-11)

    2022-02-20 00:05:22.517 T:1851 INFO <general>: thread end: video_thread

    2022-02-20 00:05:22.518 T:1850 INFO <general>: deleting video codec

    2022-02-20 00:05:22.564 T:1850 INFO <general>: Closing stream player 3

    2022-02-20 00:05:22.567 T:1078 INFO <general>: VideoPlayer: finished waiting

    2022-02-20 00:05:22.567 T:1078 INFO <general>: CVideoPlayer::CloseFile()

    2022-02-20 00:05:22.567 T:1078 INFO <general>: VideoPlayer: waiting for threads to exit

    2022-02-20 00:05:22.567 T:1078 INFO <general>: VideoPlayer: finished waiting

    2022-02-20 00:05:27.289 T:1078 INFO <general>: VideoPlayer::OpenFile: /var/media/sda1-usb-General_USB_Flas/somemovie.mkv

    2022-02-20 00:05:27.290 T:1855 INFO <general>: Creating InputStream

    2022-02-20 00:05:27.300 T:1855 INFO <general>: Creating Demuxer

    2022-02-20 00:05:27.381 T:1855 INFO <general>: Opening stream: 0 source: 256

    2022-02-20 00:05:27.381 T:1855 INFO <general>: Creating video codec with codec id: 27

    2022-02-20 00:05:27.381 T:1855 INFO <general>: CDVDVideoCodecDRMPRIME::Open - using decoder V4L2 mem2mem H.264 decoder wrapper

    2022-02-20 00:05:27.421 T:1855 INFO <general>: Creating video thread

    2022-02-20 00:05:27.421 T:1856 INFO <general>: running thread: video_thread

    2022-02-20 00:05:27.421 T:1855 INFO <general>: Opening stream: 1 source: 256

    2022-02-20 00:05:27.421 T:1855 INFO <general>: Finding audio codec for: 86056

    2022-02-20 00:05:27.422 T:1855 INFO <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder eac3

    2022-02-20 00:05:27.422 T:1855 INFO <general>: Creating audio thread

    2022-02-20 00:05:27.422 T:1857 INFO <general>: running thread: CVideoPlayerAudio::Process()

    2022-02-20 00:05:27.422 T:1855 INFO <general>: Opening stream: 2 source: 256

    2022-02-20 00:05:27.435 T:1857 INFO <general>: Creating audio stream (codec id: 86056, channels: 6, sample rate: 48000, no pass-through)

    2022-02-20 00:05:27.437 T:1084 INFO <general>: CActiveAESink::OpenSink - initialize sink

    2022-02-20 00:05:27.634 T:1084 INFO <general>: CAESinkALSA::Initialize - Attempting to open device "@:CARD=Audio,DEV=0"

    2022-02-20 00:05:27.654 T:1084 INFO <general>: CAESinkALSA::Initialize - Opened device "sysdefault:CARD=Audio"

    2022-02-20 00:05:27.658 T:1084 INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats

    2022-02-20 00:05:27.658 T:1084 INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE

    2022-02-20 00:05:34.132 T:1856 ERROR <general>: CDVDVideoCodecDRMPRIME::AddData - send packet failed: End of file (-541478725)

    Edited once, last by gn77b (February 20, 2022 at 11:03 AM).

  • It's not a complete debug log. There is no explanation of what hardware the problems is seen on. The filename shows it's something you've probably downloaded from a torrent site. Oh dear..

  • Code
    2022-02-21 09:48:57.848 T:1008     INFO <general>: VideoPlayer::OpenFile: /var/media/sda1-usb-General_USB_Flas/test/kodi/iphone6s_4k(h.264).mov

    RPi hardware (all generations including RPi4) does not support 4K H264 hardware decode, so the file is being software decoded and that's going to suck and drop frames like crazy.