Playback start-time, music video posters not visible

Tabaluga · 14 January 2022 10:40

Hi there,

since the last update I am realising on both of my Veros two changes:

The start of a video - independent of its type - takes up to 10-20 sec. after pushing the “OK” button. The files are on a 10 Gb-NAS and - due to issues with the SMB-protocol - the shares are accessed via nfs. This was much faster in the past. What might be the reason?

At the startup-screen of Kodi the posters of my music videos are not displayed any more, only the fanarts. Instead of the posters you see a standard video-icon. For movies and tv shows it is working properly. Any idea what might be the reason? The naming of the posters are exactly the same ("-poster.jpg") as for the movies.

Thanks for your support,

Tabaluga

fzinken · 14 January 2022 10:43

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 twice(!)
reproduce the issue
upload the log set (all configs and logs!) 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:

Smurphy · 14 January 2022 11:04

Having just rebuild my DB (been migrating it since early xmdb - I thought it needed a cleanup phase).
After applying all latest updates etc., run the scrapper on a new DB: not all posters are missing, but a large bunch → roughly 50%.
The error I get out of the logs for the poster on my device is:

2022-01-14 12:01:57.492 T:26509   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:01:57.498 T:26509   ERROR <general>: CCurlFile::Exists - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/y2qJoYxOhzyidsA60Mqn29H38Lk.jpg
2022-01-14 12:01:57.622 T:26507   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:01:57.627 T:26507   ERROR <general>: CCurlFile::Exists - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/2UfyLAvd9j9TEf6Gwts8SNd7amH.jpg
2022-01-14 12:01:57.643 T:26505   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:01:57.646 T:26505   ERROR <general>: CCurlFile::Exists - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/dJPnTe1qOcO7XqsJvESFph83m6m.jpg
2022-01-14 12:01:57.747 T:26480   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:01:57.750 T:26480   ERROR <general>: CCurlFile::Exists - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/tLMifq12pXknOIWqoFxA5QjLO4g.jpg
2022-01-14 12:01:57.862 T:26509   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:01:57.935 T:26509   ERROR <general>: CCurlFile::Exists - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/aZeX4XNSqa08TdMHRB1gDLO6GOi.jpg
2022-01-14 12:01:57.947 T:26505   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:01:57.949 T:26505   ERROR <general>: CCurlFile::Exists - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/kCxebZZIQ90uDgYbIwZDK0vHNsO.jpg
2022-01-14 12:01:58.081 T:26481   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:01:58.086 T:26481   ERROR <general>: CCurlFile::Exists - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/qmOMjEyxrZ91EM469GWPazAoPax.jpg
2022-01-14 12:01:58.149 T:26480   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:01:58.150 T:26480   ERROR <general>: CCurlFile::Exists - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/awrTLDolqJ0T5e43QzSMLoxjKRU.jpg

and it is correct that cf2.imgobject.com is not resolved through DNS at all.

Playing back the movie, I get this error-log. Notice the gap of 7 seconds:

2022-01-14 12:09:28.865 T:3753     INFO <general>: CAESinkALSA::Initialize - Requested layout: FL, FR
2022-01-14 12:09:28.866 T:3753     INFO <general>: CAESinkALSA::Initialize - set digital_codec 0
2022-01-14 12:09:28.866 T:3753     INFO <general>: CAESinkALSA::Initialize - Attempting to open device "default"
2022-01-14 12:09:28.872 T:3753     INFO <general>: CAESinkALSA::Initialize - Opened device "default"
2022-01-14 12:09:28.873 T:3753     INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2022-01-14 12:09:28.873 T:3753     INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4
2022-01-14 12:09:28.915 T:3753     INFO <general>: CAESinkALSA::Initialize - speaker layout 0
2022-01-14 12:09:29.177 T:3740     INFO <general>: GLES: Maximum texture width: 4096
2022-01-14 12:09:30.172 T:27479   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:09:30.295 T:27479   ERROR <general>: CCurlFile::Stat - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/uXvYBgbuJhySVqshcgrfeqQkK7y.jpg
2022-01-14 12:09:30.434 T:27475   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:09:30.440 T:27475   ERROR <general>: CCurlFile::Stat - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/vwCrxKBQCajZ3g67c28cXoSN91z.jpg
2022-01-14 12:09:30.551 T:27476   ERROR <general>: Unable to lookup host: 'cf2.imgobject.com'
2022-01-14 12:09:30.555 T:27476   ERROR <general>: CCurlFile::Stat - Failed: Couldn't resolve host name(6) for http://cf2.imgobject.com/t/p/original/4jTdPL5h1TxiJPNIig14E5L64DE.jpg
2022-01-14 12:09:37.253 T:3752     INFO <general>: CAESinkALSA::EnumerateDevice - device default description 
2022-01-14 12:09:37.284 T:3752     INFO <general>: CAESinkALSA - Unable to open device "btaudio" for playback
2022-01-14 12:09:37.286 T:3752     INFO <general>: CAESinkALSA::EnumerateDevice - device hdmi:CARD=AMLMESONAUDIO,DEV=0 description AML-MESONAUDIO,

Seems to be a similar issue here.
I can reduce the movie playback time by adding cf2.imgobject.com into my rpz (Response Policy Zone - kind of a DNS blacklist), but that won’t fix the Poster issue.

fzinken · 14 January 2022 11:11

That is defunct for quite sometime so I guess that information is stored somewhere in your local database or nfo files.

Smurphy · 14 January 2022 11:23

Negative. I checked all the nfo files - and that FQDN cf2.imgobject.com does not exist in any of the nfo files.

One example of missing poster:

smurphy@terminus:/export/Movies/Edge of Tomorrow$ ls -l
total 3680888
-rw-rw-r-- 1 smurphy mdl      94932 Jun 10  2017 'Edge of Tomorrow (2014).de.srt'
-rw-rw-r-- 1 smurphy mdl      95470 Jun 10  2017 'Edge of Tomorrow (2014).en.srt'
-rw-rw-r-- 1 smurphy mdl     105749 Jun 10  2017 'Edge of Tomorrow (2014).fr.srt'
-rw-rw-r-- 1 smurphy mdl 3768907254 Jun 10  2017 'Edge of Tomorrow (2014).mkv'
-rw-rw-r-- 1 smurphy mdl      15876 Jan 13 14:09 'Edge of Tomorrow (2014).nfo'
smurphy@terminus:/export/Movies/Edge of Tomorrow$ grep "cf2.imgobject.com" Edge\ of\ Tomorrow\ \(2014\).nfo 
smurphy@terminus:/export/Movies/Edge of Tomorrow$

There is no mention of that FQDN in the nfo file, yet the poster is not visible.
This is a new scrap, everything was updated (system), and existing addons.
After I backed up the databases (remote MySQL database), I removed the Thumbnails folder and the Texturecache13.db and the complete temp content - and ran the scrapper.
Maybe I missed a DB? or a cache?

Tabaluga · 14 January 2022 11:39

Regarding the missing posters for the music videos I forgot to mention that I only work with local info and that I am not scrapping the information via KODI. So the local -fanart.jpg are displayed but not the -poster.jpg.

Try to upload the corrsponding logs later that day.

Tabaluga · 14 January 2022 12:53

Here is the link: xxx

It took approx. 20s to start the movie. Hope that helps.

Smurphy · 14 January 2022 13:00

You have a DNS issue → showing up quite some times.

ERROR <general>: Unable to lookup host: 'TABALUGA-QNAP'

But it seems this is not the same as what I have. I’ll retire from this thread then.

fzinken · 14 January 2022 13:34

Ok as @Smurphy wrote for TABALUGA-QNAP you have a DNS lookup issue. Either change it to IP Address, fix the lookup on your DNS Server or just add the entry to /etc/hosts

For starting the move the first 5 seconds delay come here

2022-01-14 13:47:21.628 T:2776    DEBUG <general>: CDVDClock::ErrorAdjust - CVideoPlayerAudio::OutputPacket - error:-36431.166226, adjusted:-40000.000000
2022-01-14 13:47:25.221 T:2774    DEBUG <general>: CPtsTracker: detected pattern of length 1: 39999.91, frameduration: 40000.000000

Maybe someone has a idea there but frameduration look strange.

Also not sure but it looks you also have some older SMB entries in your Database

smb://TABALUGA-QNAP/M

Tabaluga · 14 January 2022 13:35

Hm, any clue how to solve that? In general I also have issues with SMB. Whatever protocol I am setting up, OSMC is not able to connect via SMB. Also, when limitting the SMB protocol to 1.0

Tabaluga · 14 January 2022 13:37

Generally, the nfs-connection is based on the direct IP-address. But I could try to access the hosts and update it.

That also caught me by surprise, as I am no longer using SMB… How can I get rid of it?

fzinken · 14 January 2022 13:39

You have configured that one via name in your sources

        <default pathversion="1"></default>
        <source>
            <name>Konzerte</name>
            <path pathversion="1">smb://TABALUGA-QNAP/Multimedia/Musik/Konzerte/</path>
            <allowsharing>true</allowsharing>
        </source>
    </files>

Tabaluga · 14 January 2022 13:43

This is a complete old link that is now longer existing. Even the path is wrong. I checked the “Konzerte” and it is linked via nfs.

Seems that there are somewhere old pathes… How to find and delete them?

fzinken · 14 January 2022 13:54

True checked that was just in files category your Video source is NFS and IP.
So means the old ones you would need to do a database clean up.

But to be honest I am not sure if that is the problem of the slow movie start at all.

Tabaluga · 14 January 2022 14:05

Hm, I am regualrly cleaning up my database, but I did it once more a couple of minutes ago. No effect.

I added 192.168.128.xx TABALUGA-QNAP to /etc/hosts without any effect

What I else recognized during testing: The issue with the 20s delay is just for the first file I like to play back after every restart. All following files a promply started… Curious…

fzinken · 14 January 2022 14:06

Can you upload a new log?

Tabaluga · 14 January 2022 14:13

This time I started the movie twice. Hope one could see the difference.

Tabaluga · 14 January 2022 14:21

Hm, what is this:

“GetImageHash - unable to stat url smb://TABALUGA-QNAP/…”

Maybe it is worth making a fresh installation?

Tabaluga · 14 January 2022 15:56

SOLUTION “PLAYBACK START-TIME”: I made a fresh install to my second Vero and this fresh install finally solved the issue of the delay. Seems that I have to do this also on my second device.

NOT SOLVED is the issue with the missing music video posters. Even more strange is that in the music video main menu also videos are shown that are not available in the corresponding share folder but in an totally different… I am not sure whether this is an issue of KODI?

darwindesign · 14 January 2022 19:34

Music video type items don’t use “poster” artwork they use the “thumbnail” type. Therefore instead of using *-poster.ext you would use *-thumb.ext for your art. Also make sure that in the scraper you do NOT have the “use folder names for lookups” option enabled as this seems to mess with scraping.