Latency between button press to Play a Movie and the real start of playback

Help and Support Vero 4K / Vero 4K +
1

Hello,
Got my vero 4K+ a week ago. All runs fine except one point.

But first my setup:
Have my movies on a DS1817 shared via SMB. All movies are scraped already with Tiny Media Manager. So I use local Information scraper.

Database was build very fast. All movies are inside. The database has approx. 3000 Movies.

Now my problem. If I want to start playback out of the “Movie” database/section it takes between 1 and 2 Minutes between pressing the button on the remote and the start of the playback.

If I use the way “Video” -> “Files” -> Share and Go Directly into the file structure of the NAS the playback starts immediately.

I also own a rpi 3 (on a non 4K TV) with Kodi and use the same number of movies from the same NAS and there the playback starts immediately.

Any proposals/Suggestions what to do?

Greets,
Joerg

PS: I am on the latest Version of OSMC which is available via the update function. It is also not a wakeup issue of the NAS, this I tested already.

2

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:

normal_logging
normal_logging1920×1080 764 KB

3

Hi fzinken,

Wanted to upload the logs but it seems the last update broke a lot more.

What I did:
(I use Estuary Skin as standard, for me better then OSMC skin as in the Movie section I have a view with Movie descriptions which I miss in OSMC Skin, this Info we need later)

  • activated Log
  • rebooted
  • redproduced the failure
  • wanted to start the OSMC App tu upload the script --> Did not start

Then I remembered another post from yesterday that this problem happens not in OSMC Skin.
So I switched to OSMC skin and again I did:

  • activated Log
  • rebooted
  • failure does no more happen my Movies start immediately

I tested also with Unity and Nebula Skin. Also here the failure happens. Although I tested only this 3 other skins it seems so that only OSMC is working well.

My problem is now that I can not upload the log of the problem (at least with the OSMC app mechnism) in the different skinsas the osmc app there not starts.

Greets,
Joerg

4

Use the SSH method from command line when the issue occurs under any skin.

5

Yes I did now:
here the upload: https://paste.osmc.tv/zivejidayu

And here the begin of the interesting time stamps (iirc)

====================== Kodi Old Log =================== 2qaAc90c
11:19:47.877 T:4077376080 NOTICE: special://profile/ is mapped to: special://masterprofile/
11:19:47.877 T:4077376080 NOTICE: -----------------------------------------------------------------------
11:19:47.877 T:4077376080 NOTICE: Starting Kodi (17.6). Platform: Linux ARM (Thumb) 32-bit
11:19:47.877 T:4077376080 NOTICE: Using Release Kodi x32 build (version for Vero)
11:19:47.877 T:4077376080 NOTICE: Kodi compiled Dec 31 2018 by GCC 6.3.0 for Linux ARM (Thumb) 32-bit version 4.9.30 (264478)
11:19:47.877 T:4077376080 NOTICE: Running on Open Source Media Center 2018.12-1, kernel: Linux ARM 32-bit version 3.14.29-134-osmc

Greets,
Joerg

6

Looked into the logs

11:20:19.316 T:4077372160   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:20:19.316 T:4077372160   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
…
11:21:46.341 T:4077372160   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:21:46.348 T:4077372160   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:21:46.352 T:4077376080   DEBUG: Loading settings for smb://192.168.0.9/work/Video_HD/Video_HD_14/Sherlock Gnomes (2018)/Sherlock Gnomes (2018).mkv
…
11:21:47.253 T:4077372160   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
11:21:47.253 T:4077372160   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
11:21:47.256 T:4077376080   DEBUG: LIRC: Update - NEW at 118499:160 0 KEY_OK_UP linux-input-layer (KEY_OK_UP)
…
11:21:58.089 T:4077372160   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
11:21:58.429 T:4077372160   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
11:21:59.185 T:4077376080   DEBUG: LIRC: Update - NEW at 130427:67 0 KEY_UP linux-input-layer (KEY_UP)
11:21:59.185 T:4077376080   DEBUG: OnKey: 166 (0xa6, obc89) pressed, action is ChapterOrBigStepForward
11:21:59.190 T:4056937216   DEBUG: CVideoPlayer::SetCaching - caching state 1
11:21:59.190 T:4056937216   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
11:21:59.190 T:3653235456   DEBUG: CAMLCodec::SetSpeed, speed(0)
11:21:59.191 T:3537892096    INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)

Looks similiar to What are the CAnnouncementManager lines in the debug logs?.

So, your player setting states:


   <videoplayer>
        <adjustrefreshrate>2</adjustrefreshrate>
        <autoplaynextitem>true</autoplaynextitem>

What will happen if you deactivate the autoplay of the next video item?

7

Thanks,
That Solved the problem!

Greets, Joerg

1 Like