Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[Omega] Cannot terminate kodi when addon is enabled #738

Closed
rien333 opened this issue May 1, 2024 · 22 comments
Closed

[Omega] Cannot terminate kodi when addon is enabled #738

rien333 opened this issue May 1, 2024 · 22 comments
Labels
bug Something isn't working

Comments

@rien333
Copy link

rien333 commented May 1, 2024

Context

Please provide any relevant information about your setup

  • Add-on Version: 7.0.5 (official repo's)
  • Kodi Version: 21.0
  • Kodi GUI Language: English
  • Operating System: Arch Linux
  • Operating System Language: English

Expected Behavior

If I have the youtube addon enabled, and quit kodi from the UI (or by issuing /bin/kodi-send -a "ShutDown()"), kodi should exit normally. Likewise, if I run the kodi binary directly, it should exit normally when I type Ctrl-c .


Current Behavior

If I have this addon enabled, kodi will not quit, except by sending it a force quit signal (e.g. something like killall -9 kodi). Kodi does exit normally, however, if I disable this addon.

As the log shows, kodi gets part of the way there (for instance, the UI is completely torn down), but ultimately fails to terminate completely.

I did not see this behavior on nexus (kodi v20).


Steps to Reproduce

Please provide detailed steps for reproducing the issue.

  1. Enable the youtube add-on
  2. Quit kodi from the UI/remote app/terminal

Log

https://gist.github.com/rien333/b63bb1b05c6fcb4be72c73b5ab3893fd


Additional Information

It should be emphasized that this behavior did not occur with nexus. Moreover, I've noticed that enabling a select group of other addons (notably, the Twitch and Netflix addons) leads to the exact same problem.

I'm 100% sure this problem only occurs when this is the only addon enabled (Together, of course, with its dependencies, such as inputstream adaptive. I cannot reproduce the issue with only inputstream adaptive enabled.)

Reports from the forum indicate that addons can sometimes cause these types of issues. Just some examples:

https://forum.kodi.tv/showthread.php?tid=358820
https://forum.kodi.tv/showthread.php?tid=288333


@rien333 rien333 added the bug Something isn't working label May 1, 2024
@rien333 rien333 changed the title Cannot terminate kodi when addon is enabled [Omega] Cannot terminate kodi when addon is enabled May 1, 2024
@neo-neo1
Copy link

neo-neo1 commented May 1, 2024

Interesting, I just upgraded to Kodi 21 Omega today and Kodi froze and locked up when I attempted to "Restart Kodi" from the UI Power menu (CoreELEC). I even waited 10 mins.

I had to SSH into the Kodi box and "systemctl restart kodi". Never had to do this in Kodi 20 Nexus

@rien333
Copy link
Author

rien333 commented May 1, 2024

Interesting, I just upgraded to Kodi 21 Omega today and Kodi froze and locked up when I attempted to "Restart Kodi" from the UI Power menu (CoreELEC). I even waited 10 mins.

I had to SSH into the Kodi box and "systemctl restart kodi". Never had to do this in Kodi 20 Nexus

While somewhat similar, these observations may not be the product of the same issue. Can you reproduce this consistently? Does this only happen with this addon enabled?

(my guess is that it isn't, since systemctl restart still worked, which indicates that kodi could quit gracefully. Unless, of course, systemd actually send a sigkill, which may indeed happen in some circumstances)

@MoojMidge
Copy link
Collaborator

MoojMidge commented May 2, 2024

This doesn't seem to be directly related to this plugin.

In your log you can see that the plugin service has responded to the abort signal and properly shutdown. The hangs that can occur on shutdown due to plugins, occur when this doesn't happen.

The Python invoker thread that is running the plugin service is also being successfully terminated. At that point the plugin is completely out of the picture as far as I can tell.

However your log does appear to be a bit odd. The FDEventMonitor thread is not being terminated for example. Suggest raising an issue in Kodi repository directly

Edit: Perhaps try running with Valgrind and see what it shows?

@rien333
Copy link
Author

rien333 commented May 2, 2024

However your log does appear to be a bit odd. The FDEventMonitor thread is not being terminated for example.

Good find, but this doesn't seem to be the cause. To quote xbmc/xbmc#21240 (comment):

ALSA output uses FDEventMonitor and destroys the thread after the logging was destroyed.

So, FDEventMonitor is alsa specific. If I launch kodi with --audio-backend=pulseaudio FDEventMonitor never gets created in the first place, but everything else is as before: kodi fails to quit.

The Python invoker thread that is running the plugin service is also being successfully terminated. At that point the plugin is completely out of the picture as far as I can tell.

Good point, you are probably correct here. My reason for still bothering addons developers about this is that, nonetheless, the issue only happens with specific addons enabled. I think your suggesting to try Valgrind/debugging tools is the way forward, as it would allow me to determine who's responsible here (the kodi devs, the addon devs, or if this is all entirely on me).

@rien333

This comment was marked as outdated.

@MoojMidge
Copy link
Collaborator

Wasn't suggesting that FDEventMonitor was the cause or even related, only that the fact it wasn't terminating was odd.

The referenced issue is identifying that the FDEventMonitor thread was being terminated at some random time after logging was stopped, and that was what was causing a segfault. It was a bug that this was occuring, which was fixed, and afaik it should now be terminating properly and you should see it logged.

Plugins don't have any direct control over such low level Kodi internals, so your best bet is to open a Kodi issue. But I am curious to see the gdb and Valgrind output.

As an aside, I have started (trying) to make sure that long lived instances of the Kodi specific Python modules are now being explicitly deleted, based on some unusual behaviour that is observed with interactions of multiple Addon and Settings instances. Don't have any evidence that they are not being properly destructed and garbage collected, and don't know how any of those modules would be holding windowing components open but you can try and see if there is any difference

#740

@rien333
Copy link
Author

rien333 commented May 3, 2024

The referenced issue [about FDEventMonitor] was a bug and was fixed

I know, I wasn't trying to draw a connection between this and that FDEventMonitor issue (after all, that one is a about a segfault, while mine isn't). Sorry for creating a misunderstanding.

FWIW: FDEventMonitor will get terminated, it is just terminated quite late, apparently. The fact that it is not shown to be terminated in my logs is just because having certain addons enabled causes kodi to hang somewhere. From my preliminary gdb experiments, I think kodi hanging in turn has something to do with some kind of mutex deadlock. (though I'm far from an expert on this kind of stuff)

so your best bet is to open a Kodi issue

On it! Still poking around with gdb (I've never debugged multithreaded programs, so there's a bit of a learning curve)

but you can try and see if there is any difference (#740)

I'm going to try this in a second!

Thanks for the help and the interest 🙂

@rien333
Copy link
Author

rien333 commented May 3, 2024

Unfortunately, #740 did not change anything.

You can find my valgrind log in the second post over at xbmc/xbmc#25114, though it's not too interesting (since the output is the same, regardless of addons enabled)

EDIT: weird, the output of valgrind without any potentially problematic addons enabed is incredibly long?

@neo1973
Copy link

neo1973 commented May 3, 2024

weird, the output of valgrind without any potentially problematic addons enabed is incredibly long?

Expected, Kodi has many non problematic memory leaks that Valgrind lists. When Kodi hangs Valgrind never reaches the stage where it checks for memory leaks.

@pezz
Copy link

pezz commented May 4, 2024

I noticed this as well with 7.0.5 after recent upgrades. None of the 7.0.6.x versions do this on my system.

@rien333
Copy link
Author

rien333 commented May 4, 2024

I mean, I tried with 7.0.7 (beta) and 7.0.6, so that seems like a different issue?

@MoojMidge
Copy link
Collaborator

Any difference if you disable InputStream.Adaptive to prevent the http server from starting? Settings > Advanced > Use InputStream Adaptive -> Off

@rien333
Copy link
Author

rien333 commented May 5, 2024

Any difference if you disable InputStream.Adaptive to prevent the http server from starting (Any difference if you disable InputStream.Adaptive to prevent the http server from starting)

No, that unfortunately does not make a difference in terms of kodi being able to exit gracefully. Inputstream.adaptive having to do with this is a good guess though, because all/most affected addons use that. When I find the time, I might look at gdb again.

@rien333
Copy link
Author

rien333 commented May 5, 2024

I found something!! So, Arch shipped kodi omega and python 3.12 pretty close to each other. This got me wondering: did 3.12 change anything wrt to threading code? Or perhaps something else of importance?

Turns out, it might have, since I cannot reproduce this issue with python 3.11.8!

@nebulosa2007
Copy link

I'm tring also to find out why Kodi can't exit, reboot or power off the system after updating python to 3.12.

Journalctl shows me this:

May 05 20:30:53 archbox xinit[136266]: [E] pw.loop [loop.c:67 pw_loop_new()] 0x60d3009f4d80: can't make support.system handle: No such file or directory
May 05 20:38:44 archbox kernel: LanguageInvoker[136291]: segfault at 79852c3a40d0 ip 0000798558772d11 sp 000079851a9ff5a8 error 4 in libpython3.12.so.1.0[79855867c000+266000] likely on CPU 0 (core 0, socket 0)
May 05 20:38:44 archbox kernel: Code: ff ff 48 89 ee ff d0 e9 cf fe ff ff 66 66 2e 0f 1f 84 00 00 00 00 00 66 90 f3 0f 1e fa 48 8b 57 f0 48 85 d2 74 26 48 8b 4f f8 <48> 8b 42 08 48 83 e1 fc 83 e0 03 48 09 c8 48 89 11 48 89 42 08 48
May 05 20:38:44 archbox systemd[1]: Started Process Core Dump (PID 136495/UID 0).
May 05 20:39:12 archbox systemd-coredump[136496]: [🡕] Process 136266 (kodi.bin) of user 1000 dumped core.

kodi_crashlog-20240505_203913.log and then:

May 05 20:39:13 archbox systemd[1]: [email protected]: Deactivated successfully.
May 05 20:39:13 archbox systemd[1]: [email protected]: Consumed 22.071s CPU time.
May 05 20:39:13 archbox xinit[136261]: /usr/bin/kodi: line 215: 136266 Segmentation fault      (core dumped) ${KODI_BINARY} ${ENV_ARGS} $SAVED_ARGS
May 05 20:39:13 archbox xinit[136261]: Crash report available at /home/user/kodi_crashlog-20240505_203913.log

@neo1973
Copy link

neo1973 commented May 5, 2024

Crashes with Python 3.12 are probably due to an upstream issue, see xbmc/xbmc#24440. To verify you have to install GDB and produce a stack trace (ideally with enabled Debuginfod).

@MoojMidge
Copy link
Collaborator

No, that unfortunately does not make a difference in terms of kodi being able to exit gracefully. Inputstream.adaptive having to do with this is a good guess though, because all/most affected addons use that. When I find the time, I might look at gdb again.

I found something!! So, Arch shipped kodi omega and python 3.12 pretty close to each other. This got me wondering: did 3.12 change anything wrt to threading code? Or perhaps something else of importance?

Turns out, it might have, since I cannot reproduce this issue with python 3.11.8!

I don't think this is related to InputStream.Adaptive, but I asked you to disable it in the plugin settings because when it is enabled the plugin will launch a http server running in a separate thread, and an uneducated look at the gdb output does seem to indicate that it is related to Python threading

The plugin checks the GUI settings to determine whether debug logging is enabled and will then prints additional details, including plugin settings, to the log. Unfortunately you have debug logging enabled via advancedsettings.xml, which cannot be identified by the plugin, and so I can't see those additional details.

Enabling any of the following settings will enable the http server:

Settings > API > Enable API configuration page
Settings > Advanced > Support alternative player
Settings > Advanced > Use InputStream Adaptive

Can you check these settings are all disabled when using #740 (note that it has been updated since you last tried it, please pull latest changes if you can), and see if the same thing still happens?

I noticed this as well with 7.0.5 after recent upgrades. None of the 7.0.6.x versions do this on my system.

pezz is also using Arch with Python 3.12, but in v7.0.6.x they are having an issue where the http server is not launching and the associated player monitor threads were closing immediately, which may explain why they see this issue in v7.0.5 but not in v7.0.6.x

@rien333
Copy link
Author

rien333 commented May 10, 2024

Running from moojmidge's master branch (i.e. most recent version of #740), the issue still exist under python 3.12, even if I make sure the http server is not started by disabling aforementioned settings. (I double checked this by scanning for open ports using netstat -tulpn — port 50912 was not opened for listenting, nor mentioned).

Here's the debug log, this time with debug logging enabled through the GUI:

debug log
2024-05-10 13:03:02.766 T:11093    info <general>: -----------------------------------------------------------------------
2024-05-10 13:03:02.766 T:11093    info <general>: Starting Kodi (21.0 (21.0.0) Git:20240426-nogitfound). Platform: Linux x86 64-bit
2024-05-10 13:03:02.766 T:11093    info <general>: Using Release Kodi x64
2024-05-10 13:03:02.766 T:11093    info <general>: Kodi compiled 2024-04-26 by GCC 13.2.1 for Linux x86 64-bit version 6.7.0 (395008)
2024-05-10 13:03:02.766 T:11093    info <general>: Running on Arch Linux 0.0, kernel: Linux x86 64-bit version 6.6.30-1-lts
2024-05-10 13:03:02.766 T:11093    info <general>: FFmpeg version/source: 6.0.1-Kodi
2024-05-10 13:03:02.766 T:11093    info <general>: Host CPU: Intel(R) Celeron(R) CPU J3455 @ 1.50GHz, 4 cores available
2024-05-10 13:03:02.766 T:11093    info <general>: special://xbmc/ is mapped to: /usr/share/kodi
2024-05-10 13:03:02.766 T:11093    info <general>: special://xbmcbin/ is mapped to: /usr/lib/kodi
2024-05-10 13:03:02.766 T:11093    info <general>: special://xbmcbinaddons/ is mapped to: /usr/lib/kodi/addons
2024-05-10 13:03:02.766 T:11093    info <general>: special://masterprofile/ is mapped to: /home/rw/.kodi/userdata
2024-05-10 13:03:02.766 T:11093    info <general>: special://envhome/ is mapped to: /home/rw
2024-05-10 13:03:02.766 T:11093    info <general>: special://home/ is mapped to: /home/rw/.kodi
2024-05-10 13:03:02.766 T:11093    info <general>: special://temp/ is mapped to: /home/rw/.kodi/temp
2024-05-10 13:03:02.766 T:11093    info <general>: special://logpath/ is mapped to: /home/rw/.kodi/temp
2024-05-10 13:03:02.766 T:11093    info <general>: Webserver extra whitelist paths: 
2024-05-10 13:03:02.766 T:11093    info <general>: The executable running is: /usr/lib/kodi/kodi.bin
2024-05-10 13:03:02.766 T:11093    info <general>: Local hostname: nuc
2024-05-10 13:03:02.766 T:11093    info <general>: Log File is located: /home/rw/.kodi/temp/kodi.log
2024-05-10 13:03:02.766 T:11093    info <general>: -----------------------------------------------------------------------
2024-05-10 13:03:02.766 T:11093    info <general>: loading settings
2024-05-10 13:03:02.767 T:11093    info <general>: special://profile/ is mapped to: special://masterprofile/
2024-05-10 13:03:02.780 T:11093    info <general>: No settings file to load (special://xbmc/system/advancedsettings.xml)
2024-05-10 13:03:02.780 T:11093    info <general>: Loaded settings file from special://profile/advancedsettings.xml
2024-05-10 13:03:02.781 T:11093    info <general>: Contents of special://profile/advancedsettings.xml are...
                                                   <advancedsettings>
                                                     <video>
                                                       <usetimeseeking>true</usetimeseeking>
                                                       <smallstepbackseconds>7</smallstepbackseconds>
                                                       <timeseekforward>10</timeseekforward>
                                                       <timeseekbackward>-10</timeseekbackward>
                                                       <timeseekforwardbig>60</timeseekforwardbig>
                                                       <timeseekbackwardbig>-60</timeseekbackwardbig>
                                                       <percentseekforward>2</percentseekforward>
                                                       <percentseekbackward>-2</percentseekbackward>
                                                       <percentseekforwardbig>10</percentseekforwardbig>
                                                       <percentseekbackwardbig>-10</percentseekbackwardbig>
                                                       <ignoresecondsatstart>25</ignoresecondsatstart>
                                                     </video>
                                                     <scanirserver>false</scanirserver>
                                                     <!-- <loglevel>1</loglevel> -->
                                                     <imagequalityjpeg>2</imagequalityjpeg>
                                                     <showexitbutton>false</showexitbutton>
                                                     <splash>false</splash>
                                                     <gui>
                                                       <fronttobackrendering>true</fronttobackrendering>
                                                       <geometryclear>false</geometryclear>
                                                     </gui>
                                                     <videolibrary>
                                                       <recentlyaddeditems>35</recentlyaddeditems>
                                                     </videolibrary>
                                                   </advancedsettings>
                                                   
2024-05-10 13:03:02.781 T:11093 warning <CSettingsManager>: missing version attribute
2024-05-10 13:03:02.781 T:11093    info <general>: Default Video Player: VideoPlayer
2024-05-10 13:03:02.781 T:11093    info <general>: Default Audio Player: paplayer
2024-05-10 13:03:02.781 T:11093    info <general>: Enabled debug logging due to GUI setting (2)
2024-05-10 13:03:02.781 T:11093    info <general>: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
2024-05-10 13:03:02.782 T:11093   debug <general>: CSkinSettings: no <skinsettings> tag found
2024-05-10 13:03:02.787 T:11093    info <general>: New Cache GUI Settings (replacement of cache in advancedsettings.xml) are:
                                                     Buffer Mode: 4
                                                     Memory Size: 512 MB
                                                     Read Factor: 7.00 x 
                                                     Chunk Size : 524288 bytes
2024-05-10 13:03:02.787 T:11093    info <general>: creating subdirectories
2024-05-10 13:03:02.787 T:11093    info <general>: userdata folder: special://masterprofile/
2024-05-10 13:03:02.787 T:11093    info <general>: recording folder: 
2024-05-10 13:03:02.787 T:11093    info <general>: screenshots folder: 
2024-05-10 13:03:02.790 T:11093    info <general>: Running database version Addons33
2024-05-10 13:03:02.794 T:11093   debug <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: inputstream.adaptive
2024-05-10 13:03:02.797 T:11093   debug <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: screensaver.xbmc.builtin.dim
2024-05-10 13:03:02.800 T:11093   debug <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: audioencoder.kodi.builtin.aac
2024-05-10 13:03:02.804 T:11093   debug <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: screensaver.xbmc.builtin.black
2024-05-10 13:03:02.817 T:11093   debug <general>: CAddonInfoBuilder::ParseXMLTypes: Binary addon found: audioencoder.kodi.builtin.wma
2024-05-10 13:03:02.829 T:11093   debug <general>: CAddonMgr::FindAddons: Addon 'metadata.common.fanart.tv' already present with version 3.6.3 at '/usr/share/kodi/addons/metadata.common.fanart.tv/' replaced with version 3.6.4 at '/home/rw/.kodi/addons/metadata.common.fanart.tv/'
2024-05-10 13:03:02.831 T:11093   debug <general>: CAddonMgr::FindAddons: Addon 'metadata.tvshows.themoviedb.org.python' already present with version 1.6.0 at '/usr/share/kodi/addons/metadata.tvshows.themoviedb.org.python/' replaced with version 1.6.6 at '/home/rw/.kodi/addons/metadata.tvshows.themoviedb.org.python/'
2024-05-10 13:03:02.834 T:11093   debug <general>: CAddonMgr::FindAddons: Addon 'metadata.themoviedb.org.python' already present with version 1.3.1+matrix.1 at '/usr/share/kodi/addons/metadata.themoviedb.org.python/' replaced with version 3.0.1 at '/home/rw/.kodi/addons/metadata.themoviedb.org.python/'
2024-05-10 13:03:02.841 T:11093    info <general>: CAddonMgr::FindAddons: audioencoder.kodi.builtin.aac v1.0.2 installed
2024-05-10 13:03:02.841 T:11093    info <general>: CAddonMgr::FindAddons: audioencoder.kodi.builtin.wma v1.0.2 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: game.controller.default v1.0.42 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: game.controller.keyboard v1.1.40 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: game.controller.mouse v1.0.26 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: game.controller.snes v1.0.40 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: inputstream.adaptive v21.4.6 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.global.audioengine v1.1.1 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.global.filesystem v1.1.8 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.global.general v1.0.5 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.global.gui v5.15.0 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.global.main v2.0.2 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.global.network v1.0.4 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.global.tools v1.0.4 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.audiodecoder v4.0.0 installed
2024-05-10 13:03:02.842 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.audioencoder v3.0.0 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.game v3.0.2 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.imagedecoder v3.0.1 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.inputstream v3.3.0 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.peripheral v3.0.2 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.pvr v8.3.0 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.screensaver v2.2.0 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.vfs v3.0.1 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.videocodec v2.1.0 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.binary.instance.visualization v4.0.0 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: kodi.resource v1.0.0 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: metadata.album.universal v3.1.4 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: metadata.artists.universal v4.3.3 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: metadata.common.allmusic.com v3.2.2 installed
2024-05-10 13:03:02.843 T:11093    info <general>: CAddonMgr::FindAddons: metadata.common.fanart.tv v3.6.4 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: metadata.common.musicbrainz.org v2.2.4 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: metadata.common.theaudiodb.com v2.0.3 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: metadata.generic.albums v1.0.14 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: metadata.generic.artists v1.0.18 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: metadata.local v1.0.1 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: metadata.themoviedb.org.python v3.0.1 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: metadata.tvshows.themoviedb.org.python v1.6.6 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: plugin.video.mubi v1.1.1 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: plugin.video.netflix v1.23.3+matrix.1 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: plugin.video.twitch v3.0.2 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: plugin.video.uzg v4.0.4 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: plugin.video.youtube v7.0.7+beta.1 installed
2024-05-10 13:03:02.844 T:11093    info <general>: CAddonMgr::FindAddons: repository.xbmc.org v3.4.0 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: resource.images.weathericons.default v1.1.9 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: resource.language.en_gb v2.0.2 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: resource.uisounds.kodi v1.0.1 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: screensaver.xbmc.builtin.black v1.0.34 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: screensaver.xbmc.builtin.dim v1.0.66 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.addon.signals v0.0.6+matrix.1 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.certifi v2023.5.7 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.chardet v5.1.0 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.dateutil v2.8.2 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.idna v3.4.0 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.inputstreamhelper v0.6.1+matrix.1 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.kodiswift v0.0.11 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.myconnpy v8.0.33 installed
2024-05-10 13:03:02.845 T:11093    info <general>: CAddonMgr::FindAddons: script.module.pil v5.1.0 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: script.module.pycryptodome v3.4.3 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: script.module.python.twitch v3.0.2 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: script.module.requests v2.31.0 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: script.module.simplecache v2.0.2 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: script.module.six v1.16.0+matrix.1 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: script.module.urllib3 v2.1.0 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: service.subtitles.opensubtitles-com v1.0.4 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: service.xbmc.versioncheck v0.5.27+matrix.1 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: skin.estuary v4.0.0 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: skin.estuary.pkscout.mod v21.0.5 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: webinterface.default v19.x-2.4.8 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: xbmc.addon v21.0.0 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: xbmc.core v0.1.0 installed
2024-05-10 13:03:02.846 T:11093    info <general>: CAddonMgr::FindAddons: xbmc.gui v5.17.0 installed
2024-05-10 13:03:02.847 T:11093    info <general>: CAddonMgr::FindAddons: xbmc.json v13.5.0 installed
2024-05-10 13:03:02.847 T:11093    info <general>: CAddonMgr::FindAddons: xbmc.metadata v2.1.0 installed
2024-05-10 13:03:02.847 T:11093    info <general>: CAddonMgr::FindAddons: xbmc.python v3.0.1 installed
2024-05-10 13:03:02.847 T:11093    info <general>: CAddonMgr::FindAddons: xbmc.webinterface v1.0.0 installed
2024-05-10 13:03:02.848 T:11093   debug <general>: CFavourites::Load - no system favourites found, skipping
2024-05-10 13:03:02.849 T:11093   debug <general>: DBus method call to org.freedesktop.ConsoleKit.Manager.CanStop at /org/freedesktop/ConsoleKit/Manager of org.freedesktop.ConsoleKit failed: org.freedesktop.DBus.Error.ServiceUnknown - The name is not activatable
2024-05-10 13:03:02.849 T:11093    info <general>: Selected Logind/UPower as PowerSyscall
2024-05-10 13:03:03.090 T:11093   debug <general>: LogindUPowerSyscall - inhibit lock taken, fd 17
2024-05-10 13:03:03.092 T:11093    info <general>: Selected UPower as PowerSyscall
2024-05-10 13:03:03.093 T:11093   error <general>: DBus error: org.freedesktop.DBus.Error.InvalidArgs - No such property “CanSuspend”
2024-05-10 13:03:03.094 T:11093   error <general>: DBus error: org.freedesktop.DBus.Error.InvalidArgs - No such property “CanHibernate”
2024-05-10 13:03:03.095 T:11093   debug <general>: DBus method call to org.freedesktop.DBus.Peer.Ping at /org/freedesktop/UDisks2 of org.freedesktop.UDisks2 failed: org.freedesktop.DBus.Error.ServiceUnknown - The name is not activatable
2024-05-10 13:03:03.095 T:11093   debug <general>: DBus method call to org.freedesktop.UDisks.EnumerateDevices at /org/freedesktop/UDisks of org.freedesktop.UDisks failed: org.freedesktop.DBus.Error.ServiceUnknown - The name is not activatable
2024-05-10 13:03:03.096 T:11093   debug <general>: Selected UDev as storage provider
2024-05-10 13:03:03.100 T:11093    info <general>: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
2024-05-10 13:03:03.101 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Estonian QWERTY" successfully loaded
2024-05-10 13:03:03.101 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
2024-05-10 13:03:03.101 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
2024-05-10 13:03:03.102 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "French AZERTY" successfully loaded
2024-05-10 13:03:03.102 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Chinese BasePY" successfully loaded
2024-05-10 13:03:03.102 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
2024-05-10 13:03:03.102 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Slovak QWERTZ" successfully loaded
2024-05-10 13:03:03.102 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Slovak QWERTY" successfully loaded
2024-05-10 13:03:03.102 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Slovak ABC" successfully loaded
2024-05-10 13:03:03.102 T:11093 warning <general>: CKeyboardLayoutManager: unable to open special://xbmc/system/keyboardlayouts/persian.xml
2024-05-10 13:03:03.103 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
2024-05-10 13:03:03.103 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Thai Kedmanee" successfully loaded
2024-05-10 13:03:03.103 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Silesian QWERTY" successfully loaded
2024-05-10 13:03:03.103 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Icelandic QWERTY" successfully loaded
2024-05-10 13:03:03.103 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Korean ㄱㄴㄷ" successfully loaded
2024-05-10 13:03:03.104 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
2024-05-10 13:03:03.104 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
2024-05-10 13:03:03.104 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Portuguese (Brazil) QWERTY" successfully loaded
2024-05-10 13:03:03.104 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
2024-05-10 13:03:03.104 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
2024-05-10 13:03:03.104 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
2024-05-10 13:03:03.105 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Czech QWERTZ" successfully loaded
2024-05-10 13:03:03.105 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
2024-05-10 13:03:03.105 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
2024-05-10 13:03:03.105 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
2024-05-10 13:03:03.105 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
2024-05-10 13:03:03.105 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
2024-05-10 13:03:03.106 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
2024-05-10 13:03:03.106 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
2024-05-10 13:03:03.106 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Slovenian QWERTZ" successfully loaded
2024-05-10 13:03:03.106 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
2024-05-10 13:03:03.106 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
2024-05-10 13:03:03.106 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
2024-05-10 13:03:03.107 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
2024-05-10 13:03:03.107 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
2024-05-10 13:03:03.107 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
2024-05-10 13:03:03.107 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "German ABC" successfully loaded
2024-05-10 13:03:03.107 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
2024-05-10 13:03:03.107 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
2024-05-10 13:03:03.107 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Croatian QWERTY" successfully loaded
2024-05-10 13:03:03.108 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
2024-05-10 13:03:03.108 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Italian QWERTY" successfully loaded
2024-05-10 13:03:03.108 T:11093   debug <general>: CKeyboardLayoutManager: keyboard layout "Italian ABC" successfully loaded
2024-05-10 13:03:03.108 T:11093   debug <general>: CApplication::CreateGUI - trying to init wayland windowing system
2024-05-10 13:03:03.113 T:11093   debug <general>: CWinSystemWayland::InitWindowSystem - WAYLAND_DISPLAY env not set
2024-05-10 13:03:03.113 T:11093   debug <general>: CApplication::CreateGUI - unable to init wayland windowing system
2024-05-10 13:03:03.113 T:11093   debug <general>: CApplication::CreateGUI - trying to init x11 windowing system
2024-05-10 13:03:03.113 T:11093   debug <general>: CWinSystemX11::InitWindowSystem - DISPLAY env not set
2024-05-10 13:03:03.113 T:11093   debug <general>: CApplication::CreateGUI - unable to init x11 windowing system
2024-05-10 13:03:03.113 T:11093   debug <general>: CApplication::CreateGUI - trying to init gbm windowing system
2024-05-10 13:03:03.658 T:11093   debug <general>: [xkb] Compiling from RMLVO: rules 'evdev', model 'pc105', layout 'us', variant '(null)', options '(null)'
                                                   
2024-05-10 13:03:03.659 T:11093   debug <general>: [xkb] Compiling from KcCGST: keycodes 'evdev+aliases(qwerty)', types 'complete', compat 'complete', symbols 'pc+us+inet(evdev)'
                                                   
2024-05-10 13:03:03.659 T:11093   debug <general>: [xkb] Compiling xkb_keycodes "(unnamed)"
                                                   
2024-05-10 13:03:03.661 T:11093   debug <general>: [xkb] Compiling xkb_types "(unnamed)"
                                                   
2024-05-10 13:03:03.663 T:11093   debug <general>: [xkb] Compiling xkb_compatibility "(unnamed)"
                                                   
2024-05-10 13:03:03.664 T:11093   debug <general>: [xkb] The "group" statement in compat is unsupported; Ignored
                                                   
2024-05-10 13:03:03.664 T:11093    info <general>: Skipped 2 duplicate messages..
2024-05-10 13:03:03.664 T:11093   debug <general>: [xkb] The "allowExplicit" field in indicator statements is unsupported; Ignored
                                                   
2024-05-10 13:03:03.665 T:11093    info <general>: Skipped 3 duplicate messages..
2024-05-10 13:03:03.665 T:11093   debug <general>: [xkb] The "indicatorDrivesKeyboard" field in indicator statements is unsupported; Ignored
                                                   
2024-05-10 13:03:03.666 T:11093   debug <general>: [xkb] The "allowExplicit" field in indicator statements is unsupported; Ignored
                                                   
2024-05-10 13:03:03.667 T:11093   debug <general>: [xkb] Indicator name "Shift Lock" was not declared in the keycodes section; Adding new indicator
                                                   
2024-05-10 13:03:03.667 T:11093   debug <general>: [xkb] Indicator name "Group 2" was not declared in the keycodes section; Adding new indicator
                                                   
2024-05-10 13:03:03.667 T:11093   debug <general>: [xkb] Indicator name "Mouse Keys" was not declared in the keycodes section; Adding new indicator
                                                   
2024-05-10 13:03:03.667 T:11093   debug <general>: [xkb] Compiling xkb_symbols "(unnamed)"
                                                   
2024-05-10 13:03:03.675 T:11093   debug <general>: [xkb] created compose table from locale C with path /usr/share/X11/locale/en_US.UTF-8/Compose
                                                   
2024-05-10 13:03:03.686 T:11117   debug <general>: Thread libinput start, auto delete: false
2024-05-10 13:03:03.686 T:11093   debug <general>: [threads] name: 'libinput' priority: '1'
2024-05-10 13:03:03.686 T:11093    info <general>: RetroPlayer[RENDER]: Registering renderer factory for DMA
2024-05-10 13:03:03.686 T:11093    info <general>: RetroPlayer[RENDER]: Registering renderer factory for OpenGL
2024-05-10 13:03:03.687 T:11093   debug <general>: CDRMUtils::OpenDrm - drm devices found: 1
2024-05-10 13:03:03.752 T:11093    info <general>: CDRMUtils::FindConnector - using connector: DP-1
2024-05-10 13:03:03.752 T:11093   debug <general>: CDRMUtils::OpenDrm - opened device: /dev/dri/card1
2024-05-10 13:03:03.752 T:11093   debug <general>: CDRMUtils::PrintDrmDeviceInfo - DRM Device Info:
                                                     available_nodes: 0x05
                                                     nodes:
                                                       nodes[0]: /dev/dri/card1
                                                       nodes[2]: /dev/dri/renderD128
                                                     bustype: 0x00
                                                       pci:
                                                         domain: 0x00
                                                         bus:    0x0
                                                         dev:    0x2
                                                         func:   0
                                                     deviceinfo:
                                                       pci:
                                                         vendor_id:    0x8086
                                                         device_id:    0x5a85
                                                         subvendor_id: 0x8086
                                                         subdevice_id: 0x2067
2024-05-10 13:03:03.753 T:11093   debug <general>: CDRMUtils::OpenDrm - opened render node: /dev/dri/renderD128
2024-05-10 13:03:03.764 T:11098    info <general>: Loading special://xbmc/system/Lircmap.xml
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'mceusb'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'XboxDVDDongle'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'Microsoft_Xbox'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'anysee'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'iMON-PAD'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'Antec_Veris_RM200'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'MCE_via_iMON'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'TwinHanRemote'
2024-05-10 13:03:03.768 T:11098    info <general>: * Adding remote mapping for device 'linux-input-layer'
2024-05-10 13:03:03.768 T:11098    info <general>: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
2024-05-10 13:03:03.769 T:11098    info <general>: * Adding remote mapping for device 'mediacenter'
2024-05-10 13:03:03.769 T:11098    info <general>: * Adding remote mapping for device 'devinput'
2024-05-10 13:03:03.769 T:11098    info <general>: * Adding remote mapping for device 'devinput-32'
2024-05-10 13:03:03.769 T:11098    info <general>: * Adding remote mapping for device 'devinput-64'
2024-05-10 13:03:03.769 T:11098   debug <general>: CIRTranslator::Load - no userdata Lircmap.xml found, skipping
2024-05-10 13:03:03.819 T:11093    info <general>: CDRMUtils::FindConnector - using connector: DP-1
2024-05-10 13:03:03.819 T:11093    info <general>: CDRMUtils::FindEncoder - using encoder: 110
2024-05-10 13:03:03.819 T:11093   debug <general>: CDRMUtils::FindCrtc - original crtc mode: 1920x1080 @ 60 Hz
2024-05-10 13:03:03.819 T:11093    info <general>: CDRMUtils::FindPlanes - using crtc: 59
2024-05-10 13:03:03.819 T:11093   debug <general>: CDRMUtils::FindPlanes - using 10bit gui plane 31
2024-05-10 13:03:03.819 T:11093   debug <general>: CDRMAtomic::InitDrm - initialized atomic DRM
2024-05-10 13:03:03.820 T:11093   error <general>: [display-info] Error parsing EDID:
2024-05-10 13:03:03.820 T:11093   error <general>: [display-info] ----------------------------------------------
2024-05-10 13:03:03.820 T:11093   error <general>: [display-info] Block 1, CTA-861 Extension Block:
2024-05-10 13:03:03.820 T:11093   error <general>: [display-info]   Video Capability Data Block: Set Selectable RGB Quantization to avoid interop issues.
2024-05-10 13:03:03.820 T:11093   error <general>: [display-info] 
2024-05-10 13:03:03.820 T:11093   error <general>: [display-info] ----------------------------------------------
2024-05-10 13:03:03.820 T:11093    info <general>: [display-info] make: 'HP Inc.' model: 'HP 24fw'
2024-05-10 13:03:03.887 T:11117   debug <general>: CLibInputHandler::DeviceAdded - keyboard type device added: Power Button (event2)
2024-05-10 13:03:03.887 T:11117   debug <general>: CLibInputKeyboard::GetRepeat - could not get key repeat for event2 (Function not implemented)
2024-05-10 13:03:03.887 T:11117   debug <general>: CLibInputKeyboard::GetRepeat - delay: 400ms repeat: 80ms for Power Button (event2)
2024-05-10 13:03:03.900 T:11117   debug <general>: CLibInputHandler::DeviceAdded - keyboard type device added: Video Bus (event4)
2024-05-10 13:03:03.901 T:11117   debug <general>: CLibInputKeyboard::GetRepeat - could not get key repeat for event4 (Function not implemented)
2024-05-10 13:03:03.901 T:11117   debug <general>: CLibInputKeyboard::GetRepeat - delay: 400ms repeat: 80ms for Video Bus (event4)
2024-05-10 13:03:03.914 T:11117   debug <general>: CLibInputHandler::DeviceAdded - keyboard type device added: Power Button (event0)
2024-05-10 13:03:03.914 T:11117   debug <general>: CLibInputKeyboard::GetRepeat - could not get key repeat for event0 (Function not implemented)
2024-05-10 13:03:03.914 T:11117   debug <general>: CLibInputKeyboard::GetRepeat - delay: 400ms repeat: 80ms for Power Button (event0)
2024-05-10 13:03:03.927 T:11117   debug <general>: CLibInputHandler::DeviceAdded - keyboard type device added: Sleep Button (event1)
2024-05-10 13:03:03.927 T:11117   debug <general>: CLibInputKeyboard::GetRepeat - could not get key repeat for event1 (Function not implemented)
2024-05-10 13:03:03.927 T:11117   debug <general>: CLibInputKeyboard::GetRepeat - delay: 400ms repeat: 80ms for Sleep Button (event1)
2024-05-10 13:03:04.002 T:11093   debug <general>: CWinSystemGbm::InitWindowSystem - initialized DRM
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1920x1080 with 1920x1080 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1920x1080 with 1920x1080 @ 75.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1920x1080 with 1920x1080 @ 59.940063 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1920x1080 with 1920x1080 @ 50.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1680x1050 with 1680x1050 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1600x900 with 1600x900 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1280x1024 with 1280x1024 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1440x900 with 1440x900 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1280x800 with 1280x800 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1280x720 with 1280x720 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1280x720 with 1280x720 @ 59.940063 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1280x720 with 1280x720 @ 50.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 1024x768 with 1024x768 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 800x600 with 800x600 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 720x576 with 720x576 @ 50.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 720x480 with 720x480 @ 59.940063 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 720x480 with 720x480 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 640x480 with 640x480 @ 60.000000 Hz
2024-05-10 13:03:04.002 T:11093    info <general>: Skipped 1 duplicate messages..
2024-05-10 13:03:04.002 T:11093    info <general>: Found resolution 720x400 with 720x400 @ 70.000000 Hz
2024-05-10 13:03:04.005 T:11093    info <general>: EGL_VERSION = 1.5
2024-05-10 13:03:04.006 T:11093    info <general>: EGL_VENDOR = Mesa Project
2024-05-10 13:03:04.006 T:11093    info <general>: EGL_EXTENSIONS = EGL_ANDROID_blob_cache EGL_ANDROID_native_fence_sync EGL_EXT_buffer_age EGL_EXT_create_context_robustness EGL_EXT_image_dma_buf_import EGL_EXT_image_dma_buf_import_modifiers EGL_EXT_query_reset_notification_strategy EGL_IMG_context_priority EGL_KHR_cl_event2 EGL_KHR_config_attribs EGL_KHR_context_flush_control EGL_KHR_create_context EGL_KHR_create_context_no_error EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_colorspace EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_EXT_pixel_format_float EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_gl_interop EGL_MESA_image_dma_buf_export EGL_MESA_query_driver EGL_WL_bind_wayland_display 
2024-05-10 13:03:04.006 T:11093    info <general>: EGL_CLIENT_EXTENSIONS = EGL_EXT_device_base EGL_EXT_device_enumeration EGL_EXT_device_query EGL_EXT_platform_base EGL_KHR_client_get_all_proc_addresses EGL_EXT_client_extensions EGL_KHR_debug EGL_EXT_platform_device EGL_EXT_explicit_device EGL_EXT_platform_wayland EGL_KHR_platform_wayland EGL_EXT_platform_x11 EGL_KHR_platform_x11 EGL_EXT_platform_xcb EGL_MESA_platform_gbm EGL_KHR_platform_gbm EGL_MESA_platform_surfaceless
2024-05-10 13:03:04.006 T:11093   debug <general>: EGL Config Attributes:
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_BUFFER_SIZE: 32
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_ALPHA_SIZE: 2
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_BLUE_SIZE: 10
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_GREEN_SIZE: 10
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_RED_SIZE: 10
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_DEPTH_SIZE: 16
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_STENCIL_SIZE: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_CONFIG_CAVEAT: 0x3038
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_CONFIG_ID: 2
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_LEVEL: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_MAX_PBUFFER_HEIGHT: 4096
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_MAX_PBUFFER_PIXELS: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_MAX_PBUFFER_WIDTH: 4096
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_NATIVE_RENDERABLE: 1
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_NATIVE_VISUAL_ID: 808669761
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_NATIVE_VISUAL_TYPE: 0x3038
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_SAMPLES: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_SAMPLE_BUFFERS: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_SURFACE_TYPE: 4
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_TRANSPARENT_TYPE: 0x3038
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_TRANSPARENT_BLUE_VALUE: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_TRANSPARENT_GREEN_VALUE: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_TRANSPARENT_RED_VALUE: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_BIND_TO_TEXTURE_RGB: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_BIND_TO_TEXTURE_RGBA: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_MIN_SWAP_INTERVAL: 1
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_MAX_SWAP_INTERVAL: 1
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_LUMINANCE_SIZE: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_ALPHA_MASK_SIZE: 0
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_COLOR_BUFFER_TYPE: 0x308e
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_RENDERABLE_TYPE: 77
2024-05-10 13:03:04.006 T:11093   debug <general>:   EGL_CONFORMANT: 77
2024-05-10 13:03:04.018 T:11093   debug <general>: Vaapi2 EGL interop test results: general yes, deepColor yes
2024-05-10 13:03:04.019 T:11093   debug <general>: libva info: VA-API version 1.21.0
2024-05-10 13:03:04.019 T:11093   debug <general>: libva info: Trying to open /usr/lib/dri/iHD_drv_video.so
2024-05-10 13:03:04.019 T:11093   debug <general>: libva info: Found init function __vaDriverInit_1_21
2024-05-10 13:03:04.021 T:11093   debug <general>: libva info: va_openDriver() returns 0
2024-05-10 13:03:04.021 T:11093    info <general>: VAAPI::Close - closing decoder context
2024-05-10 13:03:04.021 T:11093   debug <general>: CUDMABufferObject::Register - unable to open /dev/udmabuf: Permission denied
2024-05-10 13:03:04.021 T:11093   debug <general>: CDMAHeapBufferObject::Register unable to open /dev/dma_heap/reserved: No such file or directory
2024-05-10 13:03:04.021 T:11093   debug <general>: CDMAHeapBufferObject::Register unable to open /dev/dma_heap/linux,cma: No such file or directory
2024-05-10 13:03:04.021 T:11093   debug <general>: CDMAHeapBufferObject::Register unable to open /dev/dma_heap/system: Permission denied
2024-05-10 13:03:04.021 T:11093    info <general>: CApplication::CreateGUI - using the gbm windowing system
2024-05-10 13:03:04.021 T:11093    info <general>: Checking resolution 16
2024-05-10 13:03:04.021 T:11093   debug <general>: OnLostDevice - notify display change event
2024-05-10 13:03:04.021 T:11093   debug <general>: CWinSystemGbmEGLContext::DestroyWindow - deinitialized GBM
2024-05-10 13:03:04.021 T:11093   debug <general>: CDRMUtils::SetMode - found crtc mode: 1920x1080 @ 60 Hz
2024-05-10 13:03:04.021 T:11093   debug <general>: CGBMUtils::CreateSurface - created surface with size 1920x1080
2024-05-10 13:03:04.032 T:11093   debug <general>: CWinSystemGbmEGLContext::CreateNewWindow - initialized GBM
2024-05-10 13:03:04.032 T:11093    info <general>: CRenderSystemGL::InitRenderSystem - Version: 4.6 (Core Profile) Mesa 24.0.6-arch1.2, Major: 4, Minor: 6
2024-05-10 13:03:04.032 T:11093    info <general>: GL_VENDOR = Intel
2024-05-10 13:03:04.032 T:11093    info <general>: GL_RENDERER = Mesa Intel(R) HD Graphics 500 (APL 2)
2024-05-10 13:03:04.032 T:11093    info <general>: GL_VERSION = 4.6 (Core Profile) Mesa 24.0.6-arch1.2
2024-05-10 13:03:04.032 T:11093    info <general>: GL_SHADING_LANGUAGE_VERSION = 4.60
2024-05-10 13:03:04.033 T:11093    info <general>: GL_EXTENSIONS = GL_3DFX_texture_compression_FXT1 GL_AMD_conservative_depth GL_AMD_depth_clamp_separate GL_AMD_draw_buffers_blend GL_AMD_gpu_shader_int64 GL_AMD_multi_draw_indirect GL_AMD_performance_monitor GL_AMD_query_buffer_object GL_AMD_seamless_cubemap_per_texture GL_AMD_shader_stencil_export GL_AMD_shader_trinary_minmax GL_AMD_texture_texture4 GL_AMD_vertex_shader_layer GL_AMD_vertex_shader_viewport_index GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_ARB_ES2_compatibility GL_ARB_ES3_1_compatibility GL_ARB_ES3_2_compatibility GL_ARB_ES3_compatibility GL_ARB_arrays_of_arrays GL_ARB_base_instance GL_ARB_blend_func_extended GL_ARB_buffer_storage GL_ARB_clear_buffer_object GL_ARB_clear_texture GL_ARB_clip_control GL_ARB_compressed_texture_pixel_storage GL_ARB_compute_shader GL_ARB_compute_variable_group_size GL_ARB_conditional_render_inverted GL_ARB_conservative_depth GL_ARB_copy_buffer GL_ARB_copy_image GL_ARB_cull_distance GL_ARB_debug_output GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_derivative_control GL_ARB_direct_state_access GL_ARB_draw_buffers GL_ARB_draw_buffers_blend GL_ARB_draw_elements_base_vertex GL_ARB_draw_indirect GL_ARB_draw_instanced GL_ARB_enhanced_layouts GL_ARB_explicit_attrib_location GL_ARB_explicit_uniform_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_layer_viewport GL_ARB_fragment_shader GL_ARB_fragment_shader_interlock GL_ARB_framebuffer_no_attachments GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_get_program_binary GL_ARB_get_texture_sub_image GL_ARB_gl_spirv GL_ARB_gpu_shader5 GL_ARB_gpu_shader_fp64 GL_ARB_gpu_shader_int64 GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_indirect_parameters GL_ARB_instanced_arrays GL_ARB_internalformat_query GL_ARB_internalformat_query2 GL_ARB_invalidate_subdata GL_ARB_map_buffer_alignment GL_ARB_map_buffer_range GL_ARB_multi_bind GL_ARB_multi_draw_indirect GL_ARB_occlusion_query2 GL_ARB_parallel_shader_compile GL_ARB_pipeline_statistics_query GL_ARB_pixel_buffer_object GL_ARB_point_sprite GL_ARB_polygon_offset_clamp GL_ARB_post_depth_coverage GL_ARB_program_interface_query GL_ARB_provoking_vertex GL_ARB_query_buffer_object GL_ARB_robust_buffer_access_behavior GL_ARB_robustness GL_ARB_sample_shading GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_seamless_cubemap_per_texture GL_ARB_separate_shader_objects GL_ARB_shader_atomic_counter_ops GL_ARB_shader_atomic_counters GL_ARB_shader_ballot GL_ARB_shader_bit_encoding GL_ARB_shader_clock GL_ARB_shader_draw_parameters GL_ARB_shader_group_vote GL_ARB_shader_image_load_store GL_ARB_shader_image_size GL_ARB_shader_objects GL_ARB_shader_precision GL_ARB_shader_stencil_export GL_ARB_shader_storage_buffer_object GL_ARB_shader_subroutine GL_ARB_shader_texture_image_samples GL_ARB_shader_texture_lod GL_ARB_shader_viewport_layer_array GL_ARB_shading_language_420pack GL_ARB_shading_language_include GL_ARB_shading_language_packing GL_ARB_spirv_extensions GL_ARB_stencil_texturing GL_ARB_sync GL_ARB_tessellation_shader GL_ARB_texture_barrier GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_buffer_object_rgb32 GL_ARB_texture_buffer_range GL_ARB_texture_compression_bptc GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map_array GL_ARB_texture_filter_anisotropic GL_ARB_texture_float GL_ARB_texture_gather GL_ARB_texture_mirror_clamp_to_edge GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_query_levels GL_ARB_texture_query_lod GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_stencil8 GL_ARB_texture_storage GL_ARB_texture_storage_multisample GL_ARB_texture_swizzle GL_ARB_texture_view GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transform_feedback3 GL_ARB_transform_feedback_instanced GL_ARB_transform_feedback_overflow_query GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_attrib_64bit GL_ARB_vertex_attrib_binding GL_ARB_vertex_buffer_object GL_ARB_vertex_shader GL_ARB_vertex_type_10f_11f_11f_rev GL_ARB_vertex_type_2_10_10_10_rev GL_ARB_viewport_array GL_ATI_blend_equation_separate GL_ATI_texture_float GL_EXT_EGL_image_storage GL_EXT_EGL_sync GL_EXT_abgr GL_EXT_blend_equation_separate GL_EXT_debug_label GL_EXT_demote_to_helper_invocation GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_framebuffer_multisample_blit_scaled GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_memory_object GL_EXT_memory_object_fd GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_pixel_buffer_object GL_EXT_polygon_offset_clamp GL_EXT_provoking_vertex GL_EXT_semaphore GL_EXT_semaphore_fd GL_EXT_shader_framebuffer_fetch GL_EXT_shader_framebuffer_fetch_non_coherent GL_EXT_shader_integer_mix GL_EXT_shader_samples_identical GL_EXT_texture_array GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_filter_anisotropic GL_EXT_texture_integer GL_EXT_texture_sRGB GL_EXT_texture_sRGB_R8 GL_EXT_texture_sRGB_decode GL_EXT_texture_shadow_lod GL_EXT_texture_shared_exponent GL_EXT_texture_snorm GL_EXT_texture_swizzle GL_EXT_timer_query GL_EXT_transform_feedback GL_EXT_vertex_array_bgra GL_EXT_vertex_attrib_64bit GL_IBM_multimode_draw_arrays GL_INTEL_blackhole_render GL_INTEL_conservative_rasterization GL_INTEL_performance_query GL_INTEL_shader_atomic_float_minmax GL_INTEL_shader_integer_functions2 GL_KHR_blend_equation_advanced GL_KHR_blend_equation_advanced_coherent GL_KHR_context_flush_control GL_KHR_debug GL_KHR_no_error GL_KHR_parallel_shader_compile GL_KHR_robust_buffer_access_behavior GL_KHR_robustness GL_KHR_texture_compression_astc_ldr GL_KHR_texture_compression_astc_sliced_3d GL_MESA_framebuffer_flip_y GL_MESA_pack_invert GL_MESA_shader_integer_functions GL_MESA_texture_const_bandwidth GL_MESA_texture_signed_rgba GL_NV_alpha_to_coverage_dither_control GL_NV_compute_shader_derivatives GL_NV_conditional_render GL_NV_copy_image GL_NV_depth_clamp GL_NV_fragment_shader_interlock GL_NV_packed_depth_stencil GL_NV_texture_barrier GL_OES_EGL_image GL_S3_s3tc 
2024-05-10 13:03:04.054 T:11093   debug <general>: OnLostDevice - notify display change event
2024-05-10 13:03:04.054 T:11093   debug <general>: CDRMUtils::SetMode - found crtc mode: 1920x1080 @ 60 Hz
2024-05-10 13:03:04.059 T:11093    info <general>: GL: Maximum texture width: 16384
2024-05-10 13:03:04.059 T:11093   debug <general>: guilib: Fill viewport on change for solving rendering passes
2024-05-10 13:03:04.059 T:11093    info <general>: load keymapping
2024-05-10 13:03:04.060 T:11093    info <general>: Loading special://xbmc/system/keymaps/appcommand.xml
2024-05-10 13:03:04.060 T:11093    info <general>: Loading special://xbmc/system/keymaps/customcontroller.AppleRemote.xml
2024-05-10 13:03:04.061 T:11093    info <general>: Loading special://xbmc/system/keymaps/customcontroller.Harmony.xml
2024-05-10 13:03:04.061 T:11093    info <general>: Loading special://xbmc/system/keymaps/customcontroller.SiriRemote.xml
2024-05-10 13:03:04.062 T:11093    info <general>: Loading special://xbmc/system/keymaps/gamepad.xml
2024-05-10 13:03:04.062 T:11093    info <general>: Loading special://xbmc/system/keymaps/joystick.xml
2024-05-10 13:03:04.063 T:11093    info <general>: Loading special://xbmc/system/keymaps/keyboard.xml
2024-05-10 13:03:04.065 T:11093    info <general>: Loading special://xbmc/system/keymaps/mouse.xml
2024-05-10 13:03:04.066 T:11093    info <general>: Loading special://xbmc/system/keymaps/remote.xml
2024-05-10 13:03:04.067 T:11093    info <general>: Loading special://xbmc/system/keymaps/touchscreen.xml
2024-05-10 13:03:04.068 T:11093    info <general>: GUI format 1920x1080, Display 1920x1080 @ 60.000000 Hz
2024-05-10 13:03:04.068 T:11122   debug <general>: Thread ActiveAE start, auto delete: false
2024-05-10 13:03:04.069 T:11123   debug <general>: Thread AESink start, auto delete: false
2024-05-10 13:03:04.069 T:11122   debug <general>: [threads] name: 'AESink' priority: '0'
2024-05-10 13:03:04.070 T:11122    info <general>: Found 1 Lists of Devices
2024-05-10 13:03:04.070 T:11122    info <general>: Enumerated PIPEWIRE devices:
2024-05-10 13:03:04.070 T:11122    info <general>:     Device 1
2024-05-10 13:03:04.070 T:11122    info <general>:         m_deviceName      : Default
2024-05-10 13:03:04.070 T:11122    info <general>:         m_displayName     : Default
2024-05-10 13:03:04.070 T:11122    info <general>:         m_displayNameExtra: Default Output Device (PIPEWIRE)
2024-05-10 13:03:04.070 T:11122    info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-05-10 13:03:04.070 T:11122    info <general>:         m_channels        : FL, FR
2024-05-10 13:03:04.070 T:11122    info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-05-10 13:03:04.070 T:11122    info <general>:         m_dataFormats     : AE_FMT_RAW,AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S24NE3,AE_FMT_FLOAT
2024-05-10 13:03:04.070 T:11122    info <general>:         m_streamTypes     : No passthrough capabilities
2024-05-10 13:03:04.070 T:11122    info <general>:     Device 2
2024-05-10 13:03:04.070 T:11122    info <general>:         m_deviceName      : alsa_output.pci-0000_00_0e.0.3.analog-stereo
2024-05-10 13:03:04.070 T:11122    info <general>:         m_displayName     : Built-in Audio Analog Stereo
2024-05-10 13:03:04.070 T:11122    info <general>:         m_displayNameExtra: Built-in Audio Analog Stereo (PIPEWIRE)
2024-05-10 13:03:04.070 T:11122    info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-05-10 13:03:04.070 T:11122    info <general>:         m_channels        : FL, FR
2024-05-10 13:03:04.070 T:11122    info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-05-10 13:03:04.070 T:11122    info <general>:         m_dataFormats     : AE_FMT_RAW,AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_S24NE3,AE_FMT_FLOAT
2024-05-10 13:03:04.070 T:11122    info <general>:         m_streamTypes     : No passthrough capabilities
2024-05-10 13:03:04.070 T:11123    info <general>: CActiveAESink::OpenSink - initialize sink
2024-05-10 13:03:04.070 T:11123   debug <general>: CActiveAESink::OpenSink - trying to open device PIPEWIRE:Default
2024-05-10 13:03:04.070 T:11123   debug <general>: CPipewireStream::StateChanged - stream state changed unconnected -> connecting
2024-05-10 13:03:04.075 T:11123   debug <general>: CAESinkPipewire::Initialize - rate: 44100
2024-05-10 13:03:04.075 T:11123   debug <general>: CAESinkPipewire::Initialize - channels: 2
2024-05-10 13:03:04.075 T:11123   debug <general>: CAESinkPipewire::Initialize - format: f32
2024-05-10 13:03:04.075 T:11123   debug <general>: CAESinkPipewire::Initialize - samplesize: 4
2024-05-10 13:03:04.075 T:11123   debug <general>: CAESinkPipewire::Initialize - framesize: 8
2024-05-10 13:03:04.075 T:11123   debug <general>: CAESinkPipewire::Initialize - latency: 2205/44100 (0.017s)
2024-05-10 13:03:04.075 T:11123   debug <general>: CAESinkPipewire::Initialize - waiting
2024-05-10 13:03:04.075 T:11097   debug <general>: CPipewireStream::StateChanged - stream state changed connecting -> paused
2024-05-10 13:03:04.076 T:11123   debug <general>: CAESinkPipewire::Initialize - initialized
2024-05-10 13:03:04.076 T:11123   debug <general>: CActiveAESink::OpenSink - PIPEWIRE Initialized:
2024-05-10 13:03:04.076 T:11123   debug <general>:   Output Device : Default
2024-05-10 13:03:04.076 T:11123   debug <general>:   Sample Rate   : 44100
2024-05-10 13:03:04.076 T:11123   debug <general>:   Sample Format : AE_FMT_FLOAT
2024-05-10 13:03:04.076 T:11123   debug <general>:   Channel Count : 2
2024-05-10 13:03:04.076 T:11123   debug <general>:   Channel Layout: FL, FR
2024-05-10 13:03:04.076 T:11123   debug <general>:   Frames        : 2205
2024-05-10 13:03:04.076 T:11123   debug <general>:   Frame Size    : 8
2024-05-10 13:03:04.076 T:11093    info <general>: CLangInfo: loading resource.language.en_gb language information...
2024-05-10 13:03:04.077 T:11093   debug <general>: trying to set locale to en_US.UTF-8
2024-05-10 13:03:04.078 T:11093    info <general>: global locale set to en_US.UTF-8
2024-05-10 13:03:04.078 T:11093    info <general>: CLangInfo: loading resource.language.en_gb language strings...
2024-05-10 13:03:04.087 T:11093   debug <general>: LocalizeStrings: loaded 4419 strings from file resource://resource.language.en_gb/strings.po
2024-05-10 13:03:04.088 T:11093   debug <general>: LocalizeStrings: loaded 3 strings from file /usr/share/kodi/addons/audioencoder.kodi.builtin.aac/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.088 T:11093   debug <general>: LocalizeStrings: loaded 3 strings from file /usr/share/kodi/addons/audioencoder.kodi.builtin.wma/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.089 T:11093   debug <general>: LocalizeStrings: loaded 18 strings from file /usr/share/kodi/addons/game.controller.default/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.089 T:11093   debug <general>: LocalizeStrings: loaded 141 strings from file /usr/share/kodi/addons/game.controller.keyboard/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.089 T:11093   debug <general>: LocalizeStrings: loaded 11 strings from file /usr/share/kodi/addons/game.controller.mouse/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.089 T:11093   debug <general>: LocalizeStrings: loaded 13 strings from file /usr/share/kodi/addons/game.controller.snes/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.090 T:11093   debug <general>: LocalizeStrings: loaded 60 strings from file /usr/share/kodi/addons/inputstream.adaptive/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.090 T:11093   debug <general>: LocalizeStrings: loaded 17 strings from file /usr/share/kodi/addons/metadata.album.universal/resources/language/English/strings.po
2024-05-10 13:03:04.091 T:11093   debug <general>: LocalizeStrings: loaded 32 strings from file /usr/share/kodi/addons/metadata.artists.universal/resources/language/English/strings.po
2024-05-10 13:03:04.092 T:11093   debug <general>: LocalizeStrings: loaded 17 strings from file /usr/share/kodi/addons/metadata.generic.albums/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.092 T:11093   debug <general>: LocalizeStrings: loaded 15 strings from file /usr/share/kodi/addons/metadata.generic.artists/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.093 T:11093   debug <general>: LocalizeStrings: loaded 20 strings from file /home/rw/.kodi/addons/metadata.themoviedb.org.python/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.093 T:11093   debug <general>: LocalizeStrings: loaded 34 strings from file /home/rw/.kodi/addons/metadata.tvshows.themoviedb.org.python/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.094 T:11093   debug <general>: LocalizeStrings: loaded 296 strings from file /home/rw/.kodi/addons/plugin.video.netflix/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.095 T:11093   debug <general>: LocalizeStrings: loaded 244 strings from file /home/rw/.kodi/addons/plugin.video.twitch/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.095 T:11093   debug <general>: LocalizeStrings: loaded 4 strings from file /home/rw/.kodi/addons/plugin.video.uzg/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.095 T:11093   debug <general>: LocalizeStrings: loaded 371 strings from file /home/rw/.kodi/addons/plugin.video.youtube/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.096 T:11093   debug <general>: LocalizeStrings: loaded 0 strings from file /usr/share/kodi/addons/repository.xbmc.org/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.096 T:11093   debug <general>: LocalizeStrings: loaded 3 strings from file /usr/share/kodi/addons/screensaver.xbmc.builtin.dim/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.097 T:11093   debug <general>: LocalizeStrings: loaded 89 strings from file /home/rw/.kodi/addons/script.module.inputstreamhelper/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.098 T:11093   debug <general>: LocalizeStrings: loaded 0 strings from file /home/rw/.kodi/addons/script.module.python.twitch/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.099 T:11093   debug <general>: LocalizeStrings: loaded 14 strings from file /home/rw/.kodi/addons/service.subtitles.opensubtitles-com/resources/language/resource.language.en_GB/strings.po
2024-05-10 13:03:04.099 T:11093   debug <general>: LocalizeStrings: loaded 24 strings from file /usr/share/kodi/addons/service.xbmc.versioncheck/resources/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.101 T:11124   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:03:04.101 T:11124   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:03:04.101 T:11124   debug <general>: Initialize, updating databases...
2024-05-10 13:03:04.102 T:11124    info <general>: Running database version Addons33
2024-05-10 13:03:04.103 T:11124    info <general>: Running database version ViewModes6
2024-05-10 13:03:04.104 T:11124    info <general>: Running database version Textures13
2024-05-10 13:03:04.106 T:11124    info <general>: Running database version MyMusic83
2024-05-10 13:03:04.109 T:11124    info <general>: Running database version MyVideos131
2024-05-10 13:03:04.110 T:11124    info <general>: Running database version TV44
2024-05-10 13:03:04.111 T:11124    info <general>: Running database version Epg16
2024-05-10 13:03:04.111 T:11124   debug <general>: Initialize, updating databases... DONE
2024-05-10 13:03:04.112 T:11124   debug <general>: LoadUserFonts: Updating user fonts cache...
2024-05-10 13:03:04.113 T:11124   debug <general>: LoadUserFonts: Updating user fonts cache... DONE
2024-05-10 13:03:04.133 T:11093    info <general>: Unloaded skin
2024-05-10 13:03:04.136 T:11093    info <general>:   load skin from: /home/rw/.kodi/addons/skin.estuary.omega.pkscout.mod/ (version: 21.0.5)
2024-05-10 13:03:04.136 T:11093    info <general>:   load fonts for skin...
2024-05-10 13:03:04.137 T:11093    info <general>: Loading skin includes from /home/rw/.kodi/addons/skin.estuary.omega.pkscout.mod/xml/Includes.xml
2024-05-10 13:03:04.190 T:11093    info <general>: LoadFontsFromFile: Loading <fontset> with name 'Default' from '/home/rw/.kodi/addons/skin.estuary.omega.pkscout.mod/xml/Font.xml'
2024-05-10 13:03:04.198 T:11093   debug <general>: LocalizeStrings: loaded 191 strings from file /home/rw/.kodi/addons/skin.estuary.omega.pkscout.mod/language/resource.language.en_gb/strings.po
2024-05-10 13:03:04.198 T:11093    info <general>: LoadTimers: Trying to load skin timers from /home/rw/.kodi/addons/skin.estuary.omega.pkscout.mod/xml/Timers.xml
2024-05-10 13:03:04.199 T:11093    info <general>:   load new skin...
2024-05-10 13:03:04.199 T:11093    info <general>: Loading custom window XMLs from skin path /home/rw/.kodi/addons/skin.estuary.omega.pkscout.mod/xml
2024-05-10 13:03:04.205 T:11093   debug <general>: Load Skin XML: 6.16 ms
2024-05-10 13:03:04.205 T:11093    info <general>:   initialize new skin...
2024-05-10 13:03:04.205 T:11093   debug <general>: guilib: Fill viewport on change for solving rendering passes
2024-05-10 13:03:04.205 T:11093    info <general>: Loading skin file: Custom_1109_TopBarOverlay.xml, load type: LOAD_ON_GUI_INIT
2024-05-10 13:03:04.213 T:11093   debug <general>: OpenBundle - Opened bundle /home/rw/.kodi/addons/skin.estuary.omega.pkscout.mod/media/Textures.xbt
2024-05-10 13:03:04.214 T:11093    info <general>: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
2024-05-10 13:03:04.215 T:11093    info <general>: Loading skin file: DialogNotification.xml, load type: LOAD_ON_GUI_INIT
2024-05-10 13:03:04.216 T:11093    info <general>: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
2024-05-10 13:03:04.228 T:11093    info <general>: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
2024-05-10 13:03:04.230 T:11093    info <general>: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
2024-05-10 13:03:04.231 T:11093    info <general>: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
2024-05-10 13:03:04.231 T:11093    info <general>: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
2024-05-10 13:03:04.232 T:11093    info <general>: Loading resource://resource.uisounds.kodi/sounds.xml
2024-05-10 13:03:04.253 T:11093    info <general>:   skin loaded...
2024-05-10 13:03:04.253 T:11093   debug <general>: Activating window ID: 12997
2024-05-10 13:03:04.253 T:11093   debug <general>: ------ Window Init () ------
2024-05-10 13:03:04.254 T:11093   debug <general>: Activating window ID: 12999
2024-05-10 13:03:04.254 T:11093   debug <general>: ------ Window Init (Startup.xml) ------
2024-05-10 13:03:04.254 T:11093    info <general>: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
2024-05-10 13:03:04.262 T:11125   debug <general>: Thread Timer start, auto delete: false
2024-05-10 13:03:04.267 T:11125   debug <general>: Thread Timer 135005317625536 terminating
2024-05-10 13:03:04.267 T:11126   debug <general>: Thread Timer start, auto delete: false
2024-05-10 13:03:04.272 T:11126   debug <general>: Thread Timer 135005317625536 terminating
2024-05-10 13:03:04.272 T:11127   debug <general>: Thread Timer start, auto delete: false
2024-05-10 13:03:04.276 T:11093   debug <general>: Activating window ID: 10000
2024-05-10 13:03:04.276 T:11093   debug <general>: ------ Window Deinit (Startup.xml) ------
2024-05-10 13:03:04.276 T:11093   debug <general>: ------ Window Init (Home.xml) ------
2024-05-10 13:03:04.276 T:11093    info <general>: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
2024-05-10 13:03:04.326 T:11093   debug <general>: CDirectoryProvider[videodb://movies/titles/]: refreshing..
2024-05-10 13:03:04.326 T:11093   debug <general>: CDirectoryProvider[videodb://tvshows/titles/]: refreshing..
2024-05-10 13:03:04.326 T:11093   debug <general>: CDirectoryProvider[special://skin/playlists/inprogress_movies.xsp]: refreshing..
2024-05-10 13:03:04.327 T:11128   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:03:04.327 T:11128   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:03:04.327 T:11093   debug <general>: CDirectoryProvider[special://skin/playlists/recent_unwatched_movies.xsp]: refreshing..
2024-05-10 13:03:04.328 T:11129   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:03:04.328 T:11129   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:03:04.328 T:11093   debug <general>: CDirectoryProvider[videodb://inprogresstvshows]: refreshing..
2024-05-10 13:03:04.328 T:11093   debug <general>: CDirectoryProvider[special://skin/playlists/unwatched_tvshows.xsp]: refreshing..
2024-05-10 13:03:04.328 T:11093   debug <general>: CDirectoryProvider[addons://sources/video/]: refreshing..
2024-05-10 13:03:04.328 T:11093   debug <general>: CDirectoryProvider[addons://sources/executable/]: refreshing..
2024-05-10 13:03:04.352 T:11093   debug <general>: CDirectoryProvider[library://video/]: refreshing..
2024-05-10 13:03:04.352 T:11093   debug <general>: CDirectoryProvider[sources://video/]: refreshing..
2024-05-10 13:03:04.352 T:11093   debug <general>: CDirectoryProvider[special://videoplaylists/]: refreshing..
2024-05-10 13:03:04.352 T:11093   debug <general>: CDirectoryProvider[favourites://]: refreshing..
2024-05-10 13:03:04.352 T:11093   debug <general>: CDirectoryProvider[addons://sources/game/]: refreshing..
2024-05-10 13:03:04.361 T:11093   debug <general>: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
2024-05-10 13:03:04.361 T:11093 warning <general>: JSONRPC: Could not parse type "Setting.Details.SettingList"
2024-05-10 13:03:04.361 T:11093    info <general>: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
2024-05-10 13:03:04.362 T:11093    info <general>: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
2024-05-10 13:03:04.372 T:11128   debug <general>: LoadItemLookup: trying to extract filestream details from video file videodb://movies/titles/138?xsp=%7b%22limit%22%3a15%2c%22order%22%3a%7b%22direction%22%3a%22descending%22%2c%22ignorefolders%22%3a0%2c%22method%22%3a%22dateadded%22%7d%2c%22rules%22%3a%7b%22and%22%3a%5b%7b%22field%22%3a%22dateadded%22%2c%22operator%22%3a%22after%22%2c%22value%22%3a%5b%222012-01-01%22%5d%7d%2c%7b%22field%22%3a%22playcount%22%2c%22operator%22%3a%22lessthan%22%2c%22value%22%3a%5b%221%22%5d%7d%5d%7d%2c%22type%22%3a%22movies%22%7d
2024-05-10 13:03:04.374 T:11093    info <general>: JSONRPC v13.5.0: Successfully initialized
2024-05-10 13:03:04.374 T:11093    info <general>: [Media Detection] starting service for optical media detection
2024-05-10 13:03:04.374 T:11130   debug <general>: Thread DetectDVDMedia start, auto delete: false
2024-05-10 13:03:04.374 T:11130   debug <general>: Compiled with libcdio Version 0.20100
2024-05-10 13:03:04.375 T:11130   debug <general>: Thread DetectDVDMedia 135005292447424 terminating
2024-05-10 13:03:04.376 T:11093   debug <general>: LoadMappings: loaded node "Motorola Nyxboard Hybrid"
2024-05-10 13:03:04.376 T:11093   debug <general>: LoadMappings: loaded node "CEC Adapter"
2024-05-10 13:03:04.376 T:11093   debug <general>: LoadMappings: loaded node "Pulse-Eight CEC Adapter"
2024-05-10 13:03:04.376 T:11093   debug <general>: LoadMappings: loaded node "iMON HID device"
2024-05-10 13:03:04.376 T:11093   debug <general>: LoadMappings: loaded node "WETEK Play remote"
2024-05-10 13:03:04.376 T:11093   debug <general>: LoadMappings: loaded node "OSMC RF Remote"
2024-05-10 13:03:04.376 T:11093   debug <general>: LoadMappings: loaded node "joystick"
2024-05-10 13:03:04.377 T:11132   debug <general>: Thread PeripBusUSBUdev start, auto delete: false
2024-05-10 13:03:04.377 T:11093   debug <general>: [threads] name: 'PeripBusUSBUdev' priority: '1'
2024-05-10 13:03:04.377 T:11132   debug <general>: Process - initialised udev monitor
2024-05-10 13:03:04.377 T:11133   debug <general>: Thread PeripBusCEC start, auto delete: false
2024-05-10 13:03:04.380 T:11093   debug <general>: [threads] name: 'PeripBusCEC' priority: '1'
2024-05-10 13:03:04.382 T:11134   debug <general>: Thread PeripBusAddon start, auto delete: false
2024-05-10 13:03:04.382 T:11093   debug <general>: [threads] name: 'PeripBusAddon' priority: '1'
2024-05-10 13:03:04.382 T:11093   debug <general>: Initialise - initialised peripheral on 'keyboard' with 1 features and 0 sub devices
2024-05-10 13:03:04.382 T:11093    info <general>: Register - new keyboard device registered on application->keyboard: Keyboard (0000:0000)
2024-05-10 13:03:04.382 T:11093   debug <general>: Initialise - initialised peripheral on 'mouse' with 1 features and 0 sub devices
2024-05-10 13:03:04.382 T:11093    info <general>: Register - new mouse device registered on application->mouse: Mouse (0000:0000)
2024-05-10 13:03:04.383 T:11135   debug <general>: Thread PeripEventScan start, auto delete: false
2024-05-10 13:03:04.383 T:11093   debug <general>: ContextMenuManager: addon menus reloaded.
2024-05-10 13:03:04.383 T:11093    info <general>: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerCoreConfig::<ctor>: created player VideoPlayer
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerCoreConfig::<ctor>: created player PAPlayer
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerCoreConfig::<ctor>: created player RetroPlayer
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: system rules
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: mms/udp
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: rtmp
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: rtsp
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: streams
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: mp2
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: dvd
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: discimage
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: nsv
2024-05-10 13:03:04.383 T:11093   debug <general>: CPlayerSelectionRule::Initialize: creating rule: radio
2024-05-10 13:03:04.383 T:11093    info <general>: Loaded playercorefactory configuration
2024-05-10 13:03:04.383 T:11093    info <general>: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
2024-05-10 13:03:04.384 T:11093    info <general>: special://masterprofile/playercorefactory.xml does not exist. Skipping.
2024-05-10 13:03:04.384 T:11093    info <general>: removing tempfiles
2024-05-10 13:03:04.384 T:11093   debug <general>: CServiceAddonManager: starting plugin.video.youtube
2024-05-10 13:03:04.384 T:11136   debug <general>: Thread LanguageInvoker start, auto delete: false
2024-05-10 13:03:04.384 T:11093    info <general>: initialize done
2024-05-10 13:03:04.384 T:11136   debug <general>: initializing python engine.
2024-05-10 13:03:04.384 T:11093    info <general>: Running the application...
2024-05-10 13:03:04.385 T:11093   debug <general>: NetworkMessage - Starting network services
2024-05-10 13:03:04.398 T:11093   debug <general>: CZeroconfAvahi::clientCallback: client is up and running
2024-05-10 13:03:04.398 T:11093    info <general>: starting zeroconf publishing
2024-05-10 13:03:04.399 T:11138   debug <general>: Thread EventServer start, auto delete: false
2024-05-10 13:03:04.399 T:11138    info <general>: ES: Starting UDP Event server on port 9777
2024-05-10 13:03:04.399 T:11138   debug <general>: CheckIPv6: IPv6 socket bound successfully
2024-05-10 13:03:04.399 T:11138    info <general>: UDP: Listening on port 9777 (ipv6 : true)
2024-05-10 13:03:04.404 T:11093    info <general>: JSONRPC Server: Unable to get bluetooth socket
2024-05-10 13:03:04.407 T:11093    info <general>: JSONRPC Server: Successfully initialized
2024-05-10 13:03:04.408 T:11140   debug <general>: Thread TCPServer start, auto delete: false
2024-05-10 13:03:04.410 T:11093    info <CWebserver[8080]>: Started
2024-05-10 13:03:04.410 T:11143   debug <general>: Thread WSDiscoveryListenerUDP start, auto delete: false
2024-05-10 13:03:04.410 T:11093   debug <general>: [threads] name: 'WSDiscoveryListenerUDP' priority: '1'
2024-05-10 13:03:04.410 T:11093    info <general>: CWSDiscoveryListenerUDP::Start - Started
2024-05-10 13:03:04.430 T:11129   debug <general>: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
2024-05-10 13:03:04.430 T:11129   debug <general>: Getting Directory (special://profile/playlists/video)
2024-05-10 13:03:04.435 T:11129   debug <general>: Getting Directory (special://profile/playlists/mixed)
2024-05-10 13:03:04.436 T:11129   debug <general>: CMultiPathDirectory::MergeItems, items = 2
2024-05-10 13:03:04.436 T:11129   debug <general>: Testing path: [000] special://profile/playlists/video/--movies.xsp
2024-05-10 13:03:04.436 T:11129   debug <general>: CMultiPathDirectory::MergeItems, items = 2,  took 0 ms
2024-05-10 13:03:04.452 T:11144   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:03:04.453 T:11144   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:03:04.477 T:11093   debug <general>: ------ Window Init () ------
2024-05-10 13:03:04.488 T:11136   debug <general>: CPythonInvoker(0, /home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py): start processing
2024-05-10 13:03:04.491 T:11124   debug <general>: LoadItemLookup: trying to extract filestream details from video file videodb://movies/titles/138
2024-05-10 13:03:04.507 T:11129   debug <general>: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
2024-05-10 13:03:04.511 T:11129   debug <general>: GetRecentlyAddedAlbumSongs() query: SELECT songview.*, songartistview.* FROM (SELECT idAlbum, dateAdded FROM album ORDER BY dateAdded DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum = recentalbums.idAlbum JOIN songartistview ON songview.idSong = songartistview.idSong ORDER BY recentalbums.dateAdded DESC, songview.idAlbum DESC, songview.idSong, songartistview.idRole, songartistview.iOrder 
2024-05-10 13:03:04.512 T:11129   debug <general>: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY dateAdded DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY dateAdded DESC, albumview.idAlbum desc, albumartistview.iOrder 
2024-05-10 13:03:04.513 T:11129   debug <general>: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
2024-05-10 13:03:04.515 T:11093   debug <general>: EGL Debugging:
                                                   Error: EGL_BAD_SURFACE
                                                   Command: eglSwapBuffers
                                                   Type: EGL_DEBUG_MSG_ERROR_KHR
                                                   Message: dri2_swap_buffers
2024-05-10 13:03:04.515 T:11093   debug <general>: CDRMUtils::DrmFbGetFromBo - using modifier: INTEL_X_TILED
2024-05-10 13:03:04.515 T:11093   debug <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2024-05-10 13:03:04.519 T:11093   debug <general>: CWinSystemGbmGLContext::PresentRender - Sending display reset to all clients
2024-05-10 13:03:04.557 T:11129   debug <general>: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
2024-05-10 13:03:04.571 T:11093   debug <general>: CDRMUtils::DrmFbGetFromBo - using modifier: INTEL_X_TILED
2024-05-10 13:03:04.583 T:11136   debug <general>: -->Python Interpreter Initialized<--
2024-05-10 13:03:04.583 T:11136   debug <general>: 
                                                   
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0, /home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py): the source file to load is "/home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py"
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0): full python path:
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):   custom python path:
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /home/rw/.kodi/addons/plugin.video.youtube/resources/lib
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /home/rw/.kodi/addons/script.module.certifi/lib
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /home/rw/.kodi/addons/script.module.chardet/lib
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /home/rw/.kodi/addons/script.module.idna/lib
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /home/rw/.kodi/addons/script.module.inputstreamhelper/lib
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /home/rw/.kodi/addons/script.module.requests/lib
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /home/rw/.kodi/addons/script.module.urllib3/lib
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):   default python path:
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /usr/lib/python312.zip
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /usr/lib/python3.12
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /usr/lib/python3.12/lib-dynload
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):     /usr/lib/python3.12/site-packages
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0): adding args:
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0):  
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0, /home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py): entering source directory /home/rw/.kodi/addons/plugin.video.youtube/resources/lib
2024-05-10 13:03:04.583 T:11136   debug <general>: CPythonInvoker(0, /home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 3.0.0 of the xbmc.python api
2024-05-10 13:03:04.586 T:11093   debug <general>: CDRMUtils::DrmFbGetFromBo - using modifier: INTEL_X_TILED
2024-05-10 13:03:04.591 T:11129   debug <general>: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:nuc port:9777
2024-05-10 13:03:04.591 T:11129   debug <general>: CZeroconfAvahi::addService() named: nuc type: _xbmc-events._udp port:9777
2024-05-10 13:03:04.593 T:11129   debug <general>: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tcp type: _xbmc-jsonrpc._tcp name:nuc port:9090
2024-05-10 13:03:04.593 T:11129   debug <general>: CZeroconfAvahi::addService() named: nuc type: _xbmc-jsonrpc._tcp port:9090
2024-05-10 13:03:04.594 T:11128   debug <general>: ffmpeg[0x58772bf27dc0]: Format mov,mp4,m4a,3gp,3g2,mj2 detected only with low score of 1, misdetection possible!
2024-05-10 13:03:04.594 T:11128   debug <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2024-05-10 13:03:04.594 T:11128   error <general>: ffmpeg[0x58772bf27dc0]: [mov,mp4,m4a,3gp,3g2,mj2] moov atom not found
2024-05-10 13:03:04.594 T:11128   error <general>: Open - Error, could not open file /home/rw/movies/Supporting Characters (2012)/Supporting.Characters.2012.1080p.WEBRip.x264.AAC-[YTS.MX].mp4
2024-05-10 13:03:04.595 T:11129   debug <general>: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:nuc port:8080
2024-05-10 13:03:04.595 T:11129   debug <general>: CZeroconfAvahi::addService() named: nuc type: _http._tcp port:8080
2024-05-10 13:03:04.602 T:11129   debug <general>: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:nuc port:8080
2024-05-10 13:03:04.602 T:11129   debug <general>: CZeroconfAvahi::addService() named: nuc type: _xbmc-jsonrpc-h._tcp port:8080
2024-05-10 13:03:04.605 T:11093   debug <general>: CGBMUtils - using 4 buffers
2024-05-10 13:03:04.605 T:11093   debug <general>: CDRMUtils::DrmFbGetFromBo - using modifier: INTEL_X_TILED
2024-05-10 13:03:04.711 T:11124   debug <general>: ffmpeg[0x58772b3005d0]: Format mov,mp4,m4a,3gp,3g2,mj2 detected only with low score of 1, misdetection possible!
2024-05-10 13:03:04.711 T:11124   debug <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2024-05-10 13:03:04.711 T:11124   error <general>: ffmpeg[0x58772b3005d0]: [mov,mp4,m4a,3gp,3g2,mj2] moov atom not found
2024-05-10 13:03:04.711 T:11124   error <general>: Open - Error, could not open file /home/rw/movies/Supporting Characters (2012)/Supporting.Characters.2012.1080p.WEBRip.x264.AAC-[YTS.MX].mp4
2024-05-10 13:03:04.760 T:11144   debug <general>: ffmpeg[0x58772bfc6bd0]: Format mov,mp4,m4a,3gp,3g2,mj2 detected only with low score of 1, misdetection possible!
2024-05-10 13:03:04.760 T:11144   debug <general>: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
2024-05-10 13:03:04.760 T:11144   error <general>: ffmpeg[0x58772bfc6bd0]: [mov,mp4,m4a,3gp,3g2,mj2] moov atom not found
2024-05-10 13:03:04.760 T:11144   error <general>: Open - Error, could not open file /home/rw/movies/Supporting Characters (2012)/Supporting.Characters.2012.1080p.WEBRip.x264.AAC-[YTS.MX].mp4
2024-05-10 13:03:04.760 T:11144   error <general>: ExtractThumbToTexture: Error creating demuxer
2024-05-10 13:03:04.776 T:11127   debug <general>: Thread Timer 135005317625536 terminating
2024-05-10 13:03:04.886 T:11093   debug <general>: LogindUPowerSyscall - Received unknown signal NameAcquired
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.access_token) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.access_token.expires) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.alternative_player.web.urls) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.fanart.show) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.hdr) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.limit.30) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.live_streams) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.proxy.port) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.playback.history) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.refresh_token) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.video.quality.mpd) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.view.default) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.view.episodes) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (kodion.view.override) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (simple.requests.ssl.verify) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (youtube.api.last.hash) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (youtube.channel.fanart.show) was not found.
2024-05-10 13:03:05.312 T:11136   debug <CSettingsManager>: requested setting (youtube.view.description.show_channel_name) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.access_token) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.access_token.expires) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.alternative_player.web.urls) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.fanart.show) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.hdr) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.limit.30) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.live_streams) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.proxy.port) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.playback.history) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.refresh_token) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.video.quality.mpd) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.view.default) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.view.episodes) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (kodion.view.override) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (simple.requests.ssl.verify) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (youtube.api.last.hash) was not found.
2024-05-10 13:03:05.327 T:11136   debug <CSettingsManager>: requested setting (youtube.channel.fanart.show) was not found.
2024-05-10 13:03:05.328 T:11136   debug <CSettingsManager>: requested setting (youtube.view.description.show_channel_name) was not found.
2024-05-10 13:03:05.328 T:11136   debug <general>: [plugin.video.youtube] Get |requests.ssl.verify|: True (bool, success)
2024-05-10 13:03:05.328 T:11136   debug <general>: [plugin.video.youtube] Get |requests.timeout.connect|: 9 (int, success)
2024-05-10 13:03:05.328 T:11136   debug <general>: [plugin.video.youtube] Get |requests.timeout.read|: 27 (int, success)
2024-05-10 13:03:05.341 T:11136   debug <CSettingsManager>: requested setting (kodion.access_token) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.access_token.expires) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.alternative_player.web.urls) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.fanart.show) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.hdr) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.limit.30) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.live_streams) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.proxy.port) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.playback.history) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.refresh_token) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.video.quality.mpd) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.view.default) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.view.episodes) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (kodion.view.override) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (simple.requests.ssl.verify) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (youtube.api.last.hash) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (youtube.channel.fanart.show) was not found.
2024-05-10 13:03:05.342 T:11136   debug <CSettingsManager>: requested setting (youtube.view.description.show_channel_name) was not found.
2024-05-10 13:03:05.355 T:11137   debug <general>: CZeroconfAvahi::groupCallback: Service successfully established
2024-05-10 13:03:05.429 T:11136    info <CSettingsManager>: Skipped 3 duplicate messages..
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.access_token) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.access_token.expires) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.alternative_player.web.urls) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.fanart.show) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.hdr) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.limit.30) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.live_streams) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.mpd.proxy.port) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.playback.history) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.refresh_token) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.video.quality.mpd) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.view.default) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.view.episodes) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (kodion.view.override) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (simple.requests.ssl.verify) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (youtube.api.last.hash) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (youtube.channel.fanart.show) was not found.
2024-05-10 13:03:05.429 T:11136   debug <CSettingsManager>: requested setting (youtube.view.description.show_channel_name) was not found.
2024-05-10 13:03:05.430 T:11136   debug <general>: [plugin.video.youtube] YouTube service initialization...
2024-05-10 13:03:05.430 T:11136   debug <general>: [plugin.video.youtube] Get |kodion.video.quality.isa|: False (bool, success)
2024-05-10 13:03:05.430 T:11136   debug <general>: [plugin.video.youtube] Get |youtube.api.config.page|: False (bool, success)
2024-05-10 13:03:05.430 T:11136   debug <general>: [plugin.video.youtube] Get |kodion.support.alternative_player|: False (bool, success)
2024-05-10 13:03:05.430 T:11136   debug <general>: [plugin.video.youtube] Get |kodion.http.listen|: "192.168.0.70" (str, success)
2024-05-10 13:03:05.430 T:11136   debug <general>: [plugin.video.youtube] Get |kodion.http.port|: 50912 (int, success)
2024-05-10 13:03:05.430 T:11136   debug <general>: [plugin.video.youtube] Get |kodion.http.ip.whitelist|: "" (str, success)
2024-05-10 13:03:34.722 T:11128   debug <general>: Thread JobWorker 135005309232832 terminating (autodelete)
2024-05-10 13:03:34.723 T:11124   debug <general>: Thread JobWorker 135005804164800 terminating (autodelete)
2024-05-10 13:03:34.760 T:11144   debug <general>: Thread JobWorker 135004369708736 terminating (autodelete)
2024-05-10 13:03:34.762 T:11129   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:03:44.720 T:11165   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:03:44.720 T:11165   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:04:14.754 T:11165   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:04:24.738 T:11209   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:04:24.738 T:11209   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:04:46.859 T:11140   debug <general>: JSONRPC Server: New connection detected
2024-05-10 13:04:46.859 T:11140    info <general>: JSONRPC Server: New connection added
2024-05-10 13:04:54.759 T:11209   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:05:04.755 T:11214   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:05:04.755 T:11214   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:05:34.788 T:11214   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:05:42.136 T:11140    info <general>: JSONRPC Server: Disconnection detected
2024-05-10 13:05:44.772 T:11227   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:05:44.772 T:11227   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:06:14.799 T:11227   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:06:24.789 T:11269   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:06:24.790 T:11269   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:06:54.839 T:11269   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:07:04.806 T:11498   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:07:04.806 T:11498   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:07:34.826 T:11498   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:07:44.824 T:11590   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:07:44.824 T:11590   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:08:14.844 T:11590   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:08:24.841 T:11674   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:08:24.841 T:11674   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:08:54.876 T:11674   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:09:04.858 T:11680   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:09:04.858 T:11680   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:09:34.879 T:11680   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:09:39.506 T:11140   debug <general>: JSONRPC Server: New connection detected
2024-05-10 13:09:39.506 T:11140    info <general>: JSONRPC Server: New connection added
2024-05-10 13:09:39.506 T:11140    info <general>: WebSocket: missing Sec-WebSocket-Version
2024-05-10 13:09:44.859 T:11685   debug <general>: Thread JobWorker start, auto delete: true
2024-05-10 13:09:44.859 T:11685   debug <general>: [threads] name: 'JobWorker' priority: '1'
2024-05-10 13:09:54.675 T:11687   debug <CWebserver[8080]>: request received for /
2024-05-10 13:09:54.731 T:11688   debug <CWebserver[8080]>: request received for /themes/base/css/base.css
2024-05-10 13:09:54.731 T:11689   debug <CWebserver[8080]>: request received for /js/kodi-webinterface.js
2024-05-10 13:09:55.735 T:11690   debug <CWebserver[8080]>: request received for /themes/base/fonts/opensans/opensans-regular-webfont.woff
2024-05-10 13:09:55.735 T:11691   debug <CWebserver[8080]>: request received for /themes/base/fonts/opensans/opensans-light-webfont.woff
2024-05-10 13:09:57.015 T:11692   debug <CWebserver[8080]>: request received for /lang/_strings/en.json
2024-05-10 13:09:57.052 T:11693   debug <CWebserver[8080]>: request received for /icon-152.png
2024-05-10 13:09:57.052 T:11694   debug <CWebserver[8080]>: request received for /icon.png
2024-05-10 13:09:57.065 T:11695   debug <CWebserver[8080]>: request received for /images/fanart_default/tweeter.jpg
2024-05-10 13:09:57.066 T:11696   debug <CWebserver[8080]>: request received for /themes/base/images/logo.png?1620330984
2024-05-10 13:09:57.080 T:11697   debug <CWebserver[8080]>: request received for /jsonrpc?Addons.GetAddons
2024-05-10 13:09:57.081 T:11700   debug <CWebserver[8080]>: request received for /jsonrpc?Application.GetProperties
2024-05-10 13:09:57.081 T:11699   debug <CWebserver[8080]>: request received for /jsonrpc?PlaylistCollection
2024-05-10 13:09:57.082 T:11698   debug <CWebserver[8080]>: request received for /jsonrpc?Addons.GetAddons
2024-05-10 13:09:57.096 T:11701   debug <CWebserver[8080]>: request received for /themes/base/fonts/material/Material-Design-Icons.woff
2024-05-10 13:09:57.096 T:11700   debug <CWebserver[8080]>: request received for /themes/base/fonts/icomoon/fonts/icomoon.woff
2024-05-10 13:09:57.098 T:11699   debug <CWebserver[8080]>: request received for /jsonrpc?Player.GetActivePlayers
2024-05-10 13:09:57.119 T:11699   debug <CWebserver[8080]>: request received for /jsonrpc?PlaylistCollection
2024-05-10 13:09:57.159 T:11140   debug <general>: JSONRPC Server: New connection detected
2024-05-10 13:09:57.159 T:11140    info <general>: JSONRPC Server: New connection added
2024-05-10 13:09:58.744 T:11140    info <general>: WebSocket: connection closed by client
2024-05-10 13:09:58.744 T:11140    info <general>: JSONRPC Server: Disconnection detected
2024-05-10 13:10:23.631 T:11140    info <general>: JSONRPC Server: Disconnection detected
2024-05-10 13:10:33.227 T:11140   debug <general>: JSONRPC Server: New connection detected
2024-05-10 13:10:33.228 T:11140    info <general>: JSONRPC Server: New connection added
2024-05-10 13:10:33.228 T:11140    info <general>: WebSocket: missing Sec-WebSocket-Version
2024-05-10 13:10:40.919 T:11140   debug <general>: JSONRPC Server: New connection detected
2024-05-10 13:10:40.919 T:11140    info <general>: JSONRPC Server: New connection added
2024-05-10 13:10:44.720 T:11093    info <general>: Stopping the application...
2024-05-10 13:10:44.720 T:11093    info <general>: Stopping player
2024-05-10 13:10:44.720 T:11093    info <general>: Storing total System Uptime
2024-05-10 13:10:44.720 T:11093    info <general>: Saving settings
2024-05-10 13:10:44.727 T:11093    info <general>: Saving skin settings
2024-05-10 13:10:44.730 T:11093    info <general>: Stopping all
2024-05-10 13:10:44.730 T:11685   debug <general>: Thread JobWorker 135005300840128 terminating (autodelete)
2024-05-10 13:10:44.730 T:11093   debug <general>: NetworkMessage - Signaling network services to stop
2024-05-10 13:10:44.730 T:11093    info <general>: ES: Stopping event server
2024-05-10 13:10:44.730 T:11093   debug <general>: CZeroconfAvahi::doRemoveService named: servers.jsonrpc-tcp
2024-05-10 13:10:44.785 T:11138    info <general>: ES: UDP Event server stopped
2024-05-10 13:10:44.785 T:11138   debug <general>: Thread EventServer 135004881401536 terminating
2024-05-10 13:10:45.714 T:11140   debug <general>: Thread TCPServer 135004873008832 terminating
2024-05-10 13:10:47.444 T:11143   debug <general>: Thread WSDiscoveryListenerUDP 135004235491008 terminating
2024-05-10 13:10:47.444 T:11093    info <general>: CWSDiscoveryListenerUDP::Stop - Stopped
2024-05-10 13:10:47.444 T:11093   debug <general>: NetworkMessage - Waiting for network services to stop
2024-05-10 13:10:47.444 T:11093    info <general>: stopping zeroconf publishing
2024-05-10 13:10:47.448 T:11093    info <CWebserver[8080]>: Stopped
2024-05-10 13:10:47.448 T:11093   debug <general>: CServiceAddonManager: stopping plugin.video.youtube.
2024-05-10 13:10:47.448 T:11093   debug <general>: CPythonInvoker(0, /home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py): trigger Monitor abort request
2024-05-10 13:10:47.448 T:11136   debug <general>: [plugin.video.youtube] PlayerMonitor active threads: ||
2024-05-10 13:10:47.448 T:11136   debug <general>: CPythonInvoker(0, /home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py): script successfully run
2024-05-10 13:10:47.448 T:11136   debug <general>: onExecutionDone(0, /home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py)
2024-05-10 13:10:47.448 T:11136   debug <general>: .
2024-05-10 13:10:47.505 T:11136    info <general>: Skipped 1 duplicate messages..
2024-05-10 13:10:47.505 T:11136   debug <general>: Python interpreter interrupted by user
2024-05-10 13:10:47.505 T:11093   debug <general>: CPythonInvoker(0, /home/rw/.kodi/addons/plugin.video.youtube/resources/lib/service.py): script termination took 58ms
2024-05-10 13:10:47.505 T:11136   debug <general>: Thread LanguageInvoker 135004898186944 terminating
2024-05-10 13:10:47.505 T:11122   debug <general>: Thread ActiveAE 135006068397760 terminating
2024-05-10 13:10:47.505 T:11123   debug <general>: Thread AESink 135005816747712 terminating
2024-05-10 13:10:47.508 T:11093    info <general>: Application stopped
2024-05-10 13:10:47.709 T:11135   debug <general>: Thread PeripEventScan 135004906579648 terminating
2024-05-10 13:10:47.790 T:11132   debug <general>: Thread PeripBusUSBUdev 135005275662016 terminating
2024-05-10 13:10:47.790 T:11133   debug <general>: Thread PeripBusCEC 135005267269312 terminating
2024-05-10 13:10:47.791 T:11134   debug <general>: Thread PeripBusAddon 135004914972352 terminating
2024-05-10 13:10:47.960 T:11093    info <general>: unload skin
2024-05-10 13:10:47.965 T:11093   debug <general>: ------ Window Deinit () ------
2024-05-10 13:10:47.965 T:11093   debug <general>: ------ Window Deinit (Home.xml) ------
2024-05-10 13:10:47.965 T:11093   debug <general>: FreeVisualisation() done
2024-05-10 13:10:47.972 T:11093    info <general>: Skipped 1 duplicate messages..
2024-05-10 13:10:47.972 T:11093   debug <general>: CloseBundle - Closed bundle
2024-05-10 13:10:47.977 T:11093    info <general>: Unloaded skin
2024-05-10 13:10:47.978 T:11093   debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 135
2024-05-10 13:10:47.992 T:11093   debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 134
2024-05-10 13:10:47.992 T:11093   debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 132
2024-05-10 13:10:47.992 T:11093   debug <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 129
2024-05-10 13:10:47.992 T:11093   debug <general>: CWinSystemGbmEGLContext::DestroyWindow - deinitialized GBM
2024-05-10 13:10:47.995 T:11093    info <general>: unload sections
2024-05-10 13:10:47.999 T:11093    info <general>: CWSDiscoveryListenerUDP::Stop - Stopped
2024-05-10 13:10:47.999 T:11093   debug <general>: LogindUPowerSyscall - delay lock sleep released
2024-05-10 13:10:48.001 T:11093   debug <general>: object 0 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 1 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 2 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 3 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 4 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 5 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 6 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 7 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 8 --> 0 instances
2024-05-10 13:10:48.001 T:11093   debug <general>: object 9 --> 0 instances
2024-05-10 13:10:48.008 T:11093   debug <general>: CWinSystemGbm::DestroyWindowSystem - deinitialized DRM
2024-05-10 13:10:48.075 T:11117   debug <general>: Thread libinput 135006353512128 terminating
2024-05-10 13:10:48.306 T:11093   error <general>: CDRMUtils::RestoreOriginalMode - failed to set original crtc mode

@MoojMidge
Copy link
Collaborator

Thanks for checking. I'm all out of ideas unfortunately.

From your log this plugin should no longer be creating any additional threads, but I have no idea whether Python may be trying to obtain a lock for some other plugin thread or lock, or whether it is trying to obtain a lock for some other functionality that is not directly related to specifically creating a new thread in the running Python code.

No idea as well what the difference may be between your setup and the one that pezz is using.

One last random check would be to try Python 3.13 to see whether any of the threading related changes also resolve this issue, but not even sure whether Kodi would compile successfully

@nebulosa2007
Copy link

nebulosa2007 commented May 12, 2024

According to this discussion (python/cpython#118618) the issue has probably already been solved.

@rien333
Copy link
Author

rien333 commented May 12, 2024

@nebulosa2007 While I'm curious to try python3.13, I'm not entirely sure how that issue relates. Also, as I've pointed out before, our issues are different, at least in terms of their symptoms: you are seeing a crash, I am seeing a hang. Those are not the same things.

@rien333
Copy link
Author

rien333 commented May 16, 2024

Confirmed this as a python issue. If apply this backported patch, everything works fine again.

Sorry for the noise, and thanks for all your troubles and assistance!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

No branches or pull requests

6 participants