Device freezes if not immediately used

I’m running Kodi 18.5 on OSMC 2019.11-1 on a Raspberry Pi 3.

It mostly works very well for me, as I generally switch it on, watch somethng then switch it off. However, When I switch on my RPi, if I wait for a few minutes (not exactly sure how long) before selecting the video, when I select the USB stick containing my videos, I get the OSMC animated logo and teh only way to clear it is to power cycle the Pi.

Any ideas what might be going on? I’ve posted the last few lines of my log file below (I don’t know how much is useful).

This is repeatable. The steps to reproduce are:

  • insert USB stick contianing video files
  • switch on Raspberry Pi
  • wait 10 minutes
  • browse to auto-mounted USB drive.

For me, it happens every time.

2020-01-19 20:38:18.713 T:1916163632   DEBUG: ------ Window Deinit () ------
2020-01-19 20:38:18.940 T:1895822048   DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
2020-01-19 20:38:18.940 T:1607410400   DEBUG: Thread Timer start, auto delete: false
2020-01-19 20:38:18.963 T:1916163632   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
2020-01-19 20:38:19.188 T:1607410400   DEBUG: Thread Timer 1607410400 terminating
2020-01-19 20:38:19.212 T:1916163632   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
2020-01-19 20:38:19.212 T:1916163632   DEBUG: HandleKey: return (0xf00d) pressed, action is Select
2020-01-19 20:38:19.212 T:1874850528    INFO: CActiveAESink::OpenSink - initialize sink
2020-01-19 20:38:19.213 T:1874850528   DEBUG: CActiveAESink::OpenSink - trying to open device PI:Both
2020-01-19 20:38:19.213 T:1874850528   DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:Both
2020-01-19 20:38:19.213 T:1874850528   DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
2020-01-19 20:38:19.213 T:1916163632   DEBUG: CGUIMediaWindow::GetDirectory (library://video/files.xml/)
2020-01-19 20:38:19.213 T:1916163632   DEBUG:   ParentPath = []
2020-01-19 20:38:19.214 T:1874850528   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x597c54e0
2020-01-19 20:38:19.214 T:1607410400   DEBUG: Thread waiting start, auto delete: false
2020-01-19 20:38:19.215 T:1874850528   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_splitter input port 261 output port 262 m_handle 0x59660d98
2020-01-19 20:38:19.216 T:1874850528   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x59781da8
2020-01-19 20:38:19.217 T:1874850528   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_splitter) - port(261), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(17664), nBufferAlignment(32)
2020-01-19 20:38:19.219 T:1607410400   DEBUG: Thread waiting 1607410400 terminating
2020-01-19 20:38:19.220 T:1874850528   DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
2020-01-19 20:38:19.220 T:1874850528   DEBUG:   Output Device : HDMI and Analogue
2020-01-19 20:38:19.220 T:1874850528   DEBUG:   Sample Rate   : 44100
2020-01-19 20:38:19.220 T:1874850528   DEBUG:   Sample Format : AE_FMT_FLOAT
2020-01-19 20:38:19.220 T:1874850528   DEBUG:   Channel Count : 2
2020-01-19 20:38:19.220 T:1874850528   DEBUG:   Channel Layout: FL, FR
2020-01-19 20:38:19.220 T:1874850528   DEBUG:   Frames        : 2205
2020-01-19 20:38:19.220 T:1874850528   DEBUG:   Frame Size    : 8
2020-01-19 20:38:19.225 T:1607410400   DEBUG: Thread BackgroundLoader start, auto delete: false
2020-01-19 20:38:19.232 T:1916163632   DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'noop'
2020-01-19 20:38:19.238 T:1607410400   DEBUG: Thread BackgroundLoader 1607410400 terminating
2020-01-19 20:38:20.292 T:1895822048   DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
2020-01-19 20:38:20.292 T:1607410400   DEBUG: Thread Timer start, auto delete: false
2020-01-19 20:38:20.317 T:1916163632   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
2020-01-19 20:38:20.317 T:1916163632   DEBUG: HandleKey: down (0xf081) pressed, action is Down
2020-01-19 20:38:20.319 T:1916163632   DEBUG: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'noop'
2020-01-19 20:38:20.452 T:1607410400   DEBUG: Thread Timer 1607410400 terminating
2020-01-19 20:38:20.454 T:1916163632   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
2020-01-19 20:38:20.500 T:1895822048   DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
2020-01-19 20:38:20.500 T:1607410400   DEBUG: Thread Timer start, auto delete: false
2020-01-19 20:38:20.504 T:1916163632   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
2020-01-19 20:38:20.504 T:1916163632   DEBUG: HandleKey: down (0xf081) pressed, action is Down
2020-01-19 20:38:20.700 T:1607410400   DEBUG: Thread Timer 1607410400 terminating
2020-01-19 20:38:20.704 T:1916163632   DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0
2020-01-19 20:38:20.980 T:1895822048   DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 50ms
2020-01-19 20:38:20.980 T:1607410400   DEBUG: Thread Timer start, auto delete: false
2020-01-19 20:38:21.019 T:1916163632   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
2020-01-19 20:38:21.180 T:1607410400   DEBUG: Thread Timer 1607410400 terminating
2020-01-19 20:38:21.195 T:1916163632   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0
2020-01-19 20:38:21.195 T:1916163632   DEBUG: HandleKey: return (0xf00d) pressed, action is Select
2020-01-19 20:38:21.196 T:1916163632   DEBUG: CGUIMediaWindow::GetDirectory (/media/KDM32GB01/)
2020-01-19 20:38:21.196 T:1916163632   DEBUG:   ParentPath = [sources://video/]
2020-01-19 20:38:21.196 T:1607410400   DEBUG: Thread waiting start, auto delete: false
2020-01-19 20:38:21.296 T:1916163632   DEBUG: ------ Window Init (DialogBusy.xml) ------
2020-01-19 20:38:59.133 T:1699730144   DEBUG: [Amazon] DB Update already running
2020-01-19 20:39:21.572 T:1874850528   DEBUG: CAESinkPi:Drain delay:99ms now:0ms
2020-01-19 20:39:30.231 T:1599017696  NOTICE: [Amazon] getURL:
2020-01-19 20:39:31.573 T:1874850528   DEBUG: CAESinkPi:Deinitialize
2020-01-19 20:39:31.573 T:1874850528   DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
2020-01-19 20:39:31.579 T:1874850528   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_splitter handle 0x59660d98
2020-01-19 20:39:31.580 T:1874850528   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x597c54e0
2020-01-19 20:39:31.580 T:1874850528   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x59781da8

Please provide full logs:

To get a better understanding of the problem you are experiencing we need more information from you. The best way to get this information is for you to upload logs that demonstrate your problem. You can learn more about how to submit a useful support request here.

Depending on the used skin you have to set the settings-level to standard or higher, in summary:

  • enable debug logging at settings->system->logging

  • reboot the OSMC device

  • reproduce the issue

  • upload the log set either using the Log Uploader method within the My OSMC menu in the GUI or the ssh method invoking command grab-logs -A

  • publish the provided URL from the log set upload, here

Thanks for your understanding. We hope that we can help you get up and running again shortly.

OSMC skin screenshot:

Got it now. Here’s the log. Notice that at about 20:54, I played and paused a video /media/KDM32GB01/Video/Castle.2009.S08E11.720p.HDTV.X264-MRSK.mkv

At about 21:34, I unpaused it. it played for 5-10 seconds, then froze. The log data stopped changing in the lop-left corner, so it must’ve been pretty bad.

I also noticed that, when I made a terminal connection, to get the log files, the terminal was responsive, but fell over when I gave the grab-logs command. I had to reboot to get the logs.

While this time it was paused for an hour, it need only be 10 minutes or so for this effect to be seen.

https://paste.osmc.tv/yoqaquhiqo

Thanks.

Hi,

Try disabling the Amazon Prime Addon, the log is reporting:

[Amazon] DB Update already running

I think it runs this when it thinks the system is idle. Just a guess but I think this may be causing the the freeze.

Prime is dependent on [Amazon] DB.

Thanks Tom.

Tried disabling, but no joy. Changed to LibreELEC and the problem is no longer there, but now Amazon doesn’t work… Ho hum.

Your power supply is problematic.

I have the official 2.5A supply. I could switch it out. What’s the evidence? Why would changing the OS Make it work? Why would it require more power when it’s paused or idle?

The last update changed the CPU scheduler, which would consume more power. I’ll revert this in the next update

Sam

This has now been fixed – but you need to get a better PSU