Kodi constantly pauses/resumes movie while playing

morfik · November 29, 2022 at 8:40 AM

For some years, everything with my Kodi/LibreELEC installation was just fine, but recently (I think it started with the 10.0.2 update) some weird things started to happen. Basically when I start playing some movie on my TV (connected to RPI4B 2GRAM, LibreELEC 10.0.3), it plays well for some time (30min-1h, sometimes ever more), and then kodi interface pops up and the movie is repeatedly and constantly being paused and resumed again. It happens 3-5 times per second, every second till RPI is rebooted. When rebooted, everything backs to normal, but after some time, it stars all over again.

In the Kodi log, I can see the following when the pause-resume event starts to happen:

Code

2022-11-29 07:47:44.460 T:869      INFO <general>: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY
2022-11-29 07:47:56.083 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:52:53.221 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 07:53:03.973 T:1133     INFO <general>: Skipped 4 duplicate messages..
2022-11-29 07:53:03.973 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 07:53:39.945 T:1132     INFO <general>: Skipped 4 duplicate messages..
2022-11-29 07:53:39.945 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:53:55.794 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 07:54:00.028 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:54:17.718 T:1132     INFO <general>: Skipped 3 duplicate messages..
2022-11-29 07:54:17.718 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:54:30.361 T:1132     INFO <general>: Skipped 2 duplicate messages..
2022-11-29 07:54:30.361 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:54:33.170 T:1133     INFO <general>: Skipped 1 duplicate messages..
2022-11-29 07:54:33.170 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 07:54:35.394 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:54:35.880 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 07:54:44.407 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:54:49.022 T:1133     INFO <general>: Skipped 2 duplicate messages..
2022-11-29 07:54:49.022 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 07:55:17.835 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:55:32.549 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 07:57:02.495 T:1133     INFO <general>: Skipped 1 duplicate messages..
2022-11-29 07:57:02.495 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 07:57:06.530 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:58:32.115 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:58:46.011 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:59:11.568 T:1132     INFO <general>: Skipped 1 duplicate messages..
2022-11-29 07:59:11.568 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 07:59:36.981 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:01:47.466 T:879      INFO <general>: Skipped 3 duplicate messages..
2022-11-29 08:01:47.466 T:879      INFO <general>: CActiveAESink::OpenSink - initialize sink
2022-11-29 08:01:47.467 T:879      INFO <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
2022-11-29 08:01:47.475 T:879      INFO <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
2022-11-29 08:01:47.476 T:879      INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2022-11-29 08:01:47.477 T:879      INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2022-11-29 08:01:49.020 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:02:04.892 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:05:12.900 T:879      INFO <general>: CActiveAESink::OpenSink - initialize sink
2022-11-29 08:05:12.900 T:879      INFO <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
2022-11-29 08:05:12.904 T:879      INFO <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
2022-11-29 08:05:12.905 T:879      INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2022-11-29 08:05:12.905 T:879      INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2022-11-29 08:05:22.333 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:13:47.954 T:879      INFO <general>: CActiveAESink::OpenSink - initialize sink
2022-11-29 08:13:47.954 T:879      INFO <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
2022-11-29 08:13:47.962 T:879      INFO <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
2022-11-29 08:13:47.964 T:879      INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2022-11-29 08:13:47.964 T:879      INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2022-11-29 08:14:46.542 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:14:58.466 T:1133     INFO <general>: Skipped 1 duplicate messages..
2022-11-29 08:14:58.466 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 08:15:06.828 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:15:11.206 T:1133     INFO <general>: Skipped 1 duplicate messages..
2022-11-29 08:15:11.206 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 08:15:12.229 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:15:13.424 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 08:17:45.044 T:879      INFO <general>: Skipped 1 duplicate messages..
2022-11-29 08:17:45.044 T:879      INFO <general>: CActiveAESink::OpenSink - initialize sink
2022-11-29 08:17:45.044 T:879      INFO <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=vc4hdmi0,DEV=0"
2022-11-29 08:17:45.053 T:879      INFO <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=vc4hdmi0,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
2022-11-29 08:17:45.054 T:879      INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2022-11-29 08:17:45.054 T:879      INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2022-11-29 08:17:58.683 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:18:29.536 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:18:34.859 T:1133     INFO <general>: Skipped 2 duplicate messages..
2022-11-29 08:18:34.859 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 08:19:26.962 T:1132     INFO <general>: Skipped 2 duplicate messages..
2022-11-29 08:19:26.962 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:19:38.197 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:19:53.432 T:1132     INFO <general>: Skipped 1 duplicate messages..
2022-11-29 08:19:53.432 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:20:13.500 T:1132     INFO <general>: Skipped 4 duplicate messages..
2022-11-29 08:20:13.500 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:21:50.059 T:1132     INFO <general>: Skipped 1 duplicate messages..
2022-11-29 08:21:50.059 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:22:30.556 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer
2022-11-29 08:22:39.983 T:1133     INFO <general>: Skipped 2 duplicate messages..
2022-11-29 08:22:39.983 T:1133    ERROR <general>: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
2022-11-29 08:22:47.043 T:1132  WARNING <general>: OutputPicture - timeout waiting for buffer

Display More

What could be wrong with my Kodi/LibreELEC installation? Does the problem lie in RPI, my old TV (15+ years), or the software is to blame?

**chewitt** · November 29, 2022 at 11:17 AM

Start by sharing a full debug log and not the micro-snippet that you think is useful .. we want to see more.

morfik · November 29, 2022 at 11:20 AM

"the micro-snippet that you think is useful" -- I don't think it's useful or not -- there's no messages in the log while playing a video, but when the weird things start happening, it throws the above messages in the log. So that's why I posted them.

If you need any additional logs, just post commands you want me to run, and I provide you with their output.

**chewitt** · November 29, 2022 at 1:40 PM

Please provide a full debug log.

How to post a log (wiki)

1. Enable debugging in Settings>System Settings>Logging
2. Restart Kodi
3. Replicate the problem
4. Generate a log URL (do not post/upload logs to the forum)

use "Settings > LibreELEC > System > Paste system logs" or run "pastekodi" over SSH, then post the URL link

morfik · November 29, 2022 at 3:27 PM

Ok, here's the log:

http://ix.io/4hc8

I can't even play anything because something is pressing some UI buttons, so it was hard to do anything. I assume, when some movie is playing something also is pressing the pause button repeatedly.

**popcornmix** · November 29, 2022 at 4:44 PM

You are getting repeated select button presses over CEC (i.e. from TV or other HDMI connected device).

Try disabling CEC in settings.

morfik · November 29, 2022 at 5:00 PM

I don't think this TV has such setting, because it's just a normal TV without any OS (like android) inside, it's also without WiFi and other modern equipment. I checked in the TV settings for anything like CEC and I couldn't find anything useful -- it's just a few settings there and none of them allow enabling/disabling CEC. So probably the TV starts to die, or something else is to blame. I checked the second HDMI port, and also a different HDMI cable, but none of them helped.

**popcornmix** · November 29, 2022 at 5:07 PM

You can disable it in kodi: https://kodi.wiki/view/Settings/System/Input

There is almost certainly a similar setting on TV. It may be referred to by one of these names.

morfik · November 29, 2022 at 5:17 PM

I've found it in TV -- SimpLink -- I disabled this. I'll see whether this helps in anything.

**chewitt** · November 30, 2022 at 5:39 AM

You may want to experiment with the RPi removed from the case. We see lots of random weird bug reprots from Argon One users caused by bad connectors and such. And yes it might have worked fine in the past, but.. that's not always a relevant observation.

morfik · November 30, 2022 at 5:49 AM

I'm testing the CEC-disabled solution (both in tv and in kodi), and it looks like the problem disappeared -- I watched a few movies, and everything was just fine. So probably CEC was the case, but I need more testing and if in the coming days the issue doesn't return, I think the problem will be solved.

Also thanks to pointing out that the Argon case can cause issues, I would have never thought of it as a source of problems.

morfik · December 4, 2022 at 6:51 PM

It looks like that after disabling CEC, the problem went away.