Closed drrak closed 1 week ago
Doctor: What is the problem? Patient: I am sick and have been sick for several months. I won't tell you anything else, or allow you to examine me because I am too lazy. Since I am sick, it must be easy enough for you to also get sick so that you can figure out what is wrong with me. Doctor: Ok, appointment over, you can pay your bill at the front desk.
The main difference with respect to this plugin is that the time wasted, dealing with stuff like this, is being offered for free.
I won't waste time adding logs because someone always complains about "forbidden" plugins. F**k that and such overzealous people.
Suit yourself. Just as you won't waste your time, I won't waste mine.
If you have a problem and want it fixed, then you know what you need to do.
Is that enough?
2024-11-01 20:25:49.348 T:29536 info <general>: [plugin.video.youtube] Running: YouTube (7.1.0.1) on Omega (Kodi-21.0) with Python 3.11.9
Path: /channel/mine/playlist/PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd/
Params: {'category_label': 'muzyka do czytania'}
2024-11-01 20:25:49.349 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.setup_wizard.forced_runs|: 5 (int, success)
2024-11-01 20:25:49.349 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.setup_wizard|: False (bool, success)
2024-11-01 20:25:49.349 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.fanart.selection|: 0 (int, success)
2024-11-01 20:25:49.350 T:29536 debug <general>: [plugin.video.youtube] JSONStore.load - loading:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json|
2024-11-01 20:25:49.350 T:29536 debug <general>: [plugin.video.youtube] JSONStore.save - data unchanged:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json|
2024-11-01 20:25:49.350 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.cache.size|: 20 (int, success)
2024-11-01 20:25:49.354 T:29536 debug <general>: [plugin.video.youtube] JSONStore.load - loading:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/api_keys.json|
2024-11-01 20:25:49.354 T:29536 debug <general>: [plugin.video.youtube] JSONStore.save - data unchanged:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/api_keys.json|
2024-11-01 20:25:49.354 T:29536 debug <general>: [plugin.video.youtube] JSONStore.load - loading:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json|
2024-11-01 20:25:49.355 T:29536 debug <general>: [plugin.video.youtube] JSONStore.save - data unchanged:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json|
2024-11-01 20:25:49.355 T:29536 debug <general>: [plugin.video.youtube] Get |youtube.api.key|: "AIz...Usc" (str, success)
2024-11-01 20:25:49.355 T:29536 debug <general>: [plugin.video.youtube] Get |youtube.api.id|: "867...q4kvi" (str, success)
2024-11-01 20:25:49.355 T:29536 debug <general>: [plugin.video.youtube] Get |youtube.api.secret|: "GOC...uTl" (str, success)
2024-11-01 20:25:49.355 T:29536 debug <general>: [plugin.video.youtube] User: |0|, Using API key set: |own|
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Selecting YouTube config "All"
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Access token count: |2|, refresh token count: |2|
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Get |youtube.language|: "pl" (str, success)
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Get |youtube.region|: "PL" (str, success)
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.content.max_per_page|: 30 (int, success)
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Get |requests.ssl.verify|: True (bool, success)
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Get |requests.timeout.connect|: 9 (int, success)
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Get |requests.timeout.read|: 27 (int, success)
2024-11-01 20:25:49.356 T:29536 debug <general>: [plugin.video.youtube] Get |requests.proxy.source|: 1 (int, success)
2024-11-01 20:25:49.357 T:29536 debug <general>: [plugin.video.youtube] User is logged in
2024-11-01 20:25:49.358 T:29536 debug <general>: [plugin.video.youtube] API request:
version: |3|
method: |GET|
path: |playlistItems|
params: |{'maxResults': '30', 'part': 'snippet', 'prettyPrint': 'false', 'playlistId': 'PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd'}|
post_data: |None|
headers: |{'Accept': '*/*', 'Authorization': '|logged in|', 'Host': 'www.googleapis.com', 'Accept-Language': 'en-US,en;q=0.5', 'Accept-Charset': 'ISO-8859-1,utf-8;q=0.7,*;q=0.7', 'DNT': '1', 'Accept-Encoding': 'gzip, deflate', 'User-Agent': 'Mozilla/5.0 (Linux; Android 10; SM-G981B) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/80.0.3987.162 Mobile Safari/537.36'}|
2024-11-01 20:25:49.684 T:29536 debug <general>: [plugin.video.youtube] API response: |200|
headers: |{'Content-Type': 'application/json; charset=UTF-8', 'Vary': 'Origin, X-Origin, Referer', 'Content-Encoding': 'gzip', 'Date': 'Fri, 01 Nov 2024 19:25:49 GMT', 'Server': 'scaffolding on HTTPServer2', 'Cache-Control': 'private', 'X-XSS-Protection': '0', 'X-Frame-Options': 'SAMEORIGIN', 'X-Content-Type-Options': 'nosniff', 'Alt-Svc': 'h3=":443"; ma=2592000,h3-29=":443"; ma=2592000', 'Transfer-Encoding': 'chunked'}|
2024-11-01 20:25:49.686 T:29536 debug <general>: [plugin.video.youtube] Got items for playlists:
|[('PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd', 0)]|
2024-11-01 20:25:49.689 T:29536 debug <general>: [plugin.video.youtube] Cached data for items:
|['PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd,0']|
2024-11-01 20:25:49.689 T:29536 debug <general>: [plugin.video.youtube] Get |youtube.view.hide_videos|: "[]" (str list, success)
2024-11-01 20:25:49.689 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.thumbnail.size|: 1 (int, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Found cached data for videos:
|['-QoGWSrY0Fo', 'MNoRc0WW6Bc', 'QMkU597XBcQ', 'TG4ZAGnlPOY', 'U08GM50chVI', 'XN-QRGhJ8TY', 'XbS3tPO9sUs', 'gCTBPDkrjuE', 'lNi1wAoKjxA', 'vLxAQ4c9jY0', 'xbibCPr7R6Y']|
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |kodion.history.local|: False (bool, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |youtube.folder.watch_later.playlist|: "" (str, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |kodion.support.alternative_player|: False (bool, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |kodion.default_player.web_urls|: False (bool, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |kodion.video.quality.isa|: True (bool, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |kodion.mpd.videos|: True (bool, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |kodion.mpd.stream.select|: 3 (int, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |kodion.audio_only|: False (bool, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |youtube.view.channel_name.aliases|: "['cast']" (str list, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |youtube.view.description.details|: True (bool, success)
2024-11-01 20:25:49.698 T:29537 debug <general>: [plugin.video.youtube] Get |kodion.subtitle.languages.num|: 0 (int, success)
2024-11-01 20:25:49.699 T:29537 debug <general>: [plugin.video.youtube] Get |youtube.view.label.color.viewCount|: "ffadd8e6" (str, success)
2024-11-01 20:25:49.699 T:29537 debug <general>: [plugin.video.youtube] Get |youtube.view.label.color.likeCount|: "ff00ff00" (str, success)
2024-11-01 20:25:49.699 T:29537 debug <general>: [plugin.video.youtube] Get |youtube.view.label.color.commentCount|: "ff00ffff" (str, success)
2024-11-01 20:25:49.748 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0|
2024-11-01 20:25:49.750 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=lNi1wAoKjxA|
2024-11-01 20:25:49.750 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=gCTBPDkrjuE|
2024-11-01 20:25:49.751 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=-QoGWSrY0Fo|
2024-11-01 20:25:49.752 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=XbS3tPO9sUs|
2024-11-01 20:25:49.753 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=MNoRc0WW6Bc|
2024-11-01 20:25:49.754 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=XN-QRGhJ8TY|
2024-11-01 20:25:49.754 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=U08GM50chVI|
2024-11-01 20:25:49.755 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=TG4ZAGnlPOY|
2024-11-01 20:25:49.756 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=QMkU597XBcQ|
2024-11-01 20:25:49.757 T:29536 debug <general>: [plugin.video.youtube] Converting VideoItem |plugin://plugin.video.youtube/play/?video_id=xbibCPr7R6Y|
2024-11-01 20:25:49.759 T:29536 debug <general>: [plugin.video.youtube] Applying content-type: |videos| for |/channel/mine/playlist/PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd/|
2024-11-01 20:25:49.759 T:29536 debug <general>: [plugin.video.youtube] Get |youtube.view.label.details|: False (bool, success)
2024-11-01 20:25:49.762 T:29535 debug <general>: Thread waiting 545356906240 terminating
2024-11-01 20:25:49.788 T:29443 debug <general>: ------ Window Deinit (DialogBusy.xml) ------
2024-11-01 20:25:49.788 T:29443 debug <general>: Saving fileitems [plugin://plugin.video.youtube/channel/mine/playlist/PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd/?category_label=muzyka%20do%20czytania]
2024-11-01 20:25:49.789 T:29443 debug <general>: -- items: 11, sort method: 0, ascending: false
2024-11-01 20:25:49.793 T:29540 debug <general>: Thread BackgroundLoader start, auto delete: false
2024-11-01 20:25:49.793 T:29443 debug <general>: [threads] name: 'BackgroundLoader' priority: '1'
2024-11-01 20:25:49.801 T:29540 debug <general>: Thread BackgroundLoader 545356906240 terminating
2024-11-01 20:25:49.831 T:29536 debug <general>: [plugin.video.youtube] Profiling stats: 10766 function calls (10654 primitive calls) in 0.415 seconds
Ordered by: cumulative time, internal time
List reduced from 902 to 20 due to restriction <20>
ncalls tottime percall cumtime percall filename:lineno(function)
1 0.000 0.000 0.412 0.412 xbmc_plugin.py:68(run)
1 0.000 0.000 0.399 0.399 abstract_provider.py:162(navigate)
1 0.000 0.000 0.399 0.399 provider.py:380(on_playlist)
1 0.000 0.000 0.339 0.339 resource_manager.py:184(get_playlist_items)
1 0.000 0.000 0.328 0.328 youtube.py:888(get_playlist_items)
1 0.000 0.000 0.328 0.328 youtube.py:2013(api_request)
1 0.000 0.000 0.327 0.327 requests.py:78(request)
1 0.000 0.000 0.326 0.326 sessions.py:502(request)
1 0.000 0.000 0.316 0.316 sessions.py:673(send)
1 0.000 0.000 0.313 0.313 adapters.py:434(send)
1 0.000 0.000 0.310 0.310 connectionpool.py:595(urlopen)
1 0.000 0.000 0.308 0.308 connectionpool.py:380(_make_request)
1 0.000 0.000 0.207 0.207 connection.py:435(getresponse)
1 0.000 0.000 0.206 0.206 client.py:1351(getresponse)
1 0.000 0.000 0.206 0.206 client.py:318(begin)
9 0.000 0.000 0.205 0.023 socket.py:692(readinto)
9 0.000 0.000 0.205 0.023 ssl.py:1299(recv_into)
9 0.000 0.000 0.205 0.023 ssl.py:1157(read)
9 0.205 0.023 0.205 0.023 {method 'read' of '_ssl._SSLSocket' objects}
23 0.000 0.000 0.205 0.009 {method 'readline' of '_io.BufferedReader' objects}
2024-11-01 20:25:49.831 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): script successfully run
2024-11-01 20:25:51.212 T:29474 debug <general>: PushCecKeypress - received key a7 duration 0
2024-11-01 20:25:51.221 T:29443 debug <general>: HandleKey: 167 (0xa7, obc88) pressed, window 10025, action is Down
2024-11-01 20:25:51.439 T:29474 debug <general>: PushCecKeypress - received key a7 duration 227
2024-11-01 20:25:51.948 T:29474 debug <general>: PushCecKeypress - received key fc duration 0
2024-11-01 20:25:51.971 T:29443 debug <general>: HandleKey: 252 (0xfc, obc3) pressed, window 10025, action is contextmenu
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers(plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0)
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=false
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=true
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: added 1 players
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers(plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0)
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2024-11-01 20:25:51.972 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=false
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=true
2024-11-01 20:25:51.973 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: added 1 players
2024-11-01 20:25:51.974 T:29443 info <general>: Loading skin file: DialogContextMenu.xml, load type: KEEP_IN_MEMORY
2024-11-01 20:25:51.977 T:29443 debug <general>: ------ Window Init (DialogContextMenu.xml) ------
2024-11-01 20:25:52.174 T:29474 debug <general>: PushCecKeypress - received key fc duration 226
2024-11-01 20:25:52.903 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:25:52.904 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10106, action is Up
2024-11-01 20:25:53.364 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:25:53.371 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10106, action is Up
2024-11-01 20:25:53.470 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:25:53.471 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10106, action is Up
2024-11-01 20:25:53.578 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:25:53.604 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10106, action is Up
2024-11-01 20:25:53.686 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:25:53.704 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10106, action is Up
2024-11-01 20:25:53.794 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:25:53.804 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10106, action is Up
2024-11-01 20:25:53.904 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:25:53.904 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10106, action is Up
2024-11-01 20:25:53.911 T:29443 debug <general>: ------ Window Deinit (DialogNotification.xml) ------
2024-11-01 20:25:54.011 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:25:54.038 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10106, action is Up
2024-11-01 20:25:54.135 T:29474 debug <general>: PushCecKeypress - received key a6 duration 1232
2024-11-01 20:25:54.638 T:29474 debug <general>: PushCecKeypress - received key b duration 0
2024-11-01 20:25:54.671 T:29443 debug <general>: HandleKey: 11 (0xb, obc244) pressed, window 10106, action is Select
2024-11-01 20:25:54.861 T:29474 debug <general>: PushCecKeypress - received key b duration 222
2024-11-01 20:25:54.943 T:29443 debug <general>: ------ Window Deinit (DialogContextMenu.xml) ------
2024-11-01 20:25:54.971 T:29443 debug <general>: CScriptRunner: running add-on script YouTube('plugin://plugin.video.youtube/play/', '-1', '?video_id=vLxAQ4c9jY0&audio_only=True')
2024-11-01 20:25:54.971 T:29443 debug <general>: GetLanguageInvoker - Reusing LanguageInvokerThread 4 for script /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py
2024-11-01 20:25:54.971 T:29536 debug <general>: initializing python engine.
2024-11-01 20:25:54.971 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): start processing
2024-11-01 20:25:54.971 T:29536 debug <general>: CPythonInvoker(4): adding args:
2024-11-01 20:25:54.971 T:29536 debug <general>: CPythonInvoker(4): plugin://plugin.video.youtube/play/
2024-11-01 20:25:54.971 T:29536 debug <general>: CPythonInvoker(4): -1
2024-11-01 20:25:54.971 T:29536 debug <general>: CPythonInvoker(4): ?video_id=vLxAQ4c9jY0&audio_only=True
2024-11-01 20:25:54.971 T:29536 debug <general>: CPythonInvoker(4): resume:false
2024-11-01 20:25:54.971 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): entering source directory /storage/.kodi/addons/plugin.video.youtube/resources/lib
2024-11-01 20:25:54.972 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 3.0.0 of the xbmc.python api
2024-11-01 20:25:54.973 T:29536 debug <general>: [plugin.video.youtube] Starting Kodion framework by bromix...
2024-11-01 20:25:54.975 T:29536 info <general>: [plugin.video.youtube] Running: YouTube (7.1.0.1) on Omega (Kodi-21.0) with Python 3.11.9
Path: /play/
Params: {'video_id': 'vLxAQ4c9jY0', 'audio_only': True}
2024-11-01 20:25:54.986 T:29536 debug <general>: [plugin.video.youtube] Profiling stats: 253 function calls (252 primitive calls) in 0.003 seconds
Ordered by: cumulative time, internal time
List reduced from 99 to 20 due to restriction <20>
ncalls tottime percall cumtime percall filename:lineno(function)
1 0.000 0.000 0.002 0.002 xbmc_plugin.py:68(run)
1 0.000 0.000 0.001 0.001 xbmc_context.py:759(wakeup)
1 0.000 0.000 0.001 0.001 xbmc_context.py:629(send_notification)
1 0.000 0.000 0.001 0.001 methods.py:288(jsonrpc)
1 0.000 0.000 0.001 0.001 xbmc_context.py:327(init)
1 0.000 0.000 0.001 0.001 __init__.py:299(loads)
1 0.000 0.000 0.001 0.001 decoder.py:332(decode)
2 0.001 0.000 0.001 0.000 {method 'match' of 're.Pattern' objects}
1 0.000 0.000 0.000 0.000 abstract_provider.py:162(navigate)
1 0.000 0.000 0.000 0.000 yt_play.py:308(process)
2 0.000 0.000 0.000 0.000 abstract_context.py:433(log)
7 0.000 0.000 0.000 0.000 xbmc_context_ui.py:148(pop_property)
1 0.000 0.000 0.000 0.000 parse.py:452(urlsplit)
1 0.000 0.000 0.000 0.000 abstract_context.py:257(create_uri)
2 0.000 0.000 0.000 0.000 logger.py:27(log)
1 0.000 0.000 0.000 0.000 parse.py:943(urlencode)
1 0.000 0.000 0.000 0.000 abstract_context.py:445(log_debug)
2 0.000 0.000 0.000 0.000 {built-in method xbmc.log}
4 0.000 0.000 0.000 0.000 parse.py:901(quote_plus)
1 0.000 0.000 0.000 0.000 __init__.py:183(dumps)
2024-11-01 20:25:54.986 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): script successfully run
2024-11-01 20:25:55.005 T:29443 debug <general>: ------ Window Init (DialogContextMenu.xml) ------
2024-11-01 20:25:56.312 T:29474 debug <general>: PushCecKeypress - received key b duration 0
2024-11-01 20:25:56.338 T:29443 debug <general>: HandleKey: 11 (0xb, obc244) pressed, window 10106, action is Select
2024-11-01 20:25:56.540 T:29474 debug <general>: PushCecKeypress - received key b duration 228
2024-11-01 20:25:56.610 T:29443 debug <general>: ------ Window Deinit (DialogContextMenu.xml) ------
2024-11-01 20:25:56.638 T:29443 debug <general>: OnPlayMedia plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0
2024-11-01 20:25:56.640 T:29443 debug <general>: CScriptRunner: running add-on script YouTube('plugin://plugin.video.youtube/play/', '1', '?video_id=vLxAQ4c9jY0')
2024-11-01 20:25:56.640 T:29443 debug <general>: GetLanguageInvoker - Reusing LanguageInvokerThread 4 for script /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py
2024-11-01 20:25:56.640 T:29536 debug <general>: initializing python engine.
2024-11-01 20:25:56.640 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): start processing
2024-11-01 20:25:56.641 T:29536 debug <general>: CPythonInvoker(4): adding args:
2024-11-01 20:25:56.641 T:29536 debug <general>: CPythonInvoker(4): plugin://plugin.video.youtube/play/
2024-11-01 20:25:56.641 T:29536 debug <general>: CPythonInvoker(4): 1
2024-11-01 20:25:56.641 T:29536 debug <general>: CPythonInvoker(4): ?video_id=vLxAQ4c9jY0
2024-11-01 20:25:56.641 T:29536 debug <general>: CPythonInvoker(4): resume:true
2024-11-01 20:25:56.641 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): entering source directory /storage/.kodi/addons/plugin.video.youtube/resources/lib
2024-11-01 20:25:56.641 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): instantiating addon using automatically obtained id of "plugin.video.youtube" dependent on version 3.0.0 of the xbmc.python api
2024-11-01 20:25:56.642 T:29536 debug <general>: [plugin.video.youtube] Starting Kodion framework by bromix...
2024-11-01 20:25:56.642 T:29536 info <general>: [plugin.video.youtube] Running: YouTube (7.1.0.1) on Omega (Kodi-21.0) with Python 3.11.9
Path: /play/
Params: {'video_id': 'vLxAQ4c9jY0', 'resume': True}
2024-11-01 20:25:56.644 T:29536 debug <general>: [plugin.video.youtube] playlist_id: 1, position - 1/1
2024-11-01 20:25:56.661 T:29542 debug <general>: Thread ScriptObs start, auto delete: false
2024-11-01 20:25:56.861 T:29443 debug <general>: ------ Window Init (DialogBusy.xml) ------
2024-11-01 20:25:56.984 T:29536 debug <general>: [plugin.video.youtube] Wakeup |server_wakeup| in 100ms
2024-11-01 20:25:56.987 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.age.gate|: False (bool, success)
2024-11-01 20:25:57.338 T:29536 debug <general>: [plugin.video.youtube] Retrieved video info - video_id: vLxAQ4c9jY0, client: ios, auth: True
2024-11-01 20:25:57.487 T:29536 debug <general>: [plugin.video.youtube] Retrieved video info - video_id: vLxAQ4c9jY0, client: android_vr, auth: True
2024-11-01 20:25:57.489 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.history.remote|: False (bool, success)
2024-11-01 20:25:57.490 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.subtitle.download|: False (bool, success)
2024-11-01 20:25:57.491 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.mpd.quality.selection|: 4 (int, success)
2024-11-01 20:25:57.533 T:29536 debug <general>: [plugin.video.youtube] Get |kodion.live_stream.selection.1|: 1 (int, success)
2024-11-01 20:25:57.533 T:29536 debug <general>: [plugin.video.youtube] Select stream: Audio only
2024-11-01 20:25:57.533 T:29536 debug <general>: [plugin.video.youtube] Available streams: 3
2024-11-01 20:25:57.535 T:29536 debug <general>: [plugin.video.youtube] Stream 0:
{'title': '[B]opus@160[/B] (webm / opus@160)', 'url': 'https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=<redacted>&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=50561231&dur=3005.021&lmt=1641340040503488&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJwL3hCt3NGN1dQ_MNRErtsAuLqRnV_R7x1GMWEMyU_4AiBGDXfXWx8CX1cTCjhET7L50MIMdXeflc52Gm8NjRMQxQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-50561231', 'meta': {'id': 'vLxAQ4c9jY0', 'title': 'Ethereal Cinematic Space Ambient Music [ Cryostasis · A Unique Atmospheric Journey Through Space ]', 'status': {'unlisted': False, 'private': False, 'crawlable': True, 'family_safe': False, 'live': False}, 'channel': {'id': 'UCLGeMsRzG1nY4jOfEqnLICA', 'author': 'Cosmic Sounding'}, 'thumbnails': {'default': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/default.jpg', 'size': 10800, 'ratio': 1.3333333333333333}, 'medium': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/mqdefault.jpg', 'size': 57600, 'ratio': 1.7777777777777777}, 'high': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/hqdefault.jpg', 'size': 172800, 'ratio': 1.3333333333333333}, 'standard': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/sddefault.jpg', 'size': 307200, 'ratio': 1.3333333333333333}, '720': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/hq720.jpg', 'size': 921600, 'ratio': 1.7777777777777777}, 'oar': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/oardefault.jpg', 'size': 0, 'ratio': 0}, 'maxres': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/maxresdefault.jpg', 'size': 2073600, 'ratio': 1.7777777777777777}}, 'subtitles': None}, 'headers': 'Accept=%2A/%2A&Accept-Language=en-US%2Cen%3Bq%3D0.5&Accept-Charset=ISO-8859-1%2Cutf-8%3Bq%3D0.7%2C%2A%3Bq%3D0.7&Referer=https%3A//www.youtube.com/watch%3Fv%3DvLxAQ4c9jY0&User-Agent=Mozilla/5.0%20%28Linux%3B%20Android%2010%3B%20SM-G981B%29%20AppleWebKit/537.36%20%28KHTML%2C%20like%20Gecko%29%20Chrome/80.0.3987.162%20Mobile%20Safari/537.36&Origin=https%3A//www.youtube.com&Accept-Encoding=gzip%2C%20deflate', 'playback_stats': {'playback_url': '', 'watchtime_url': ''}, 'container': 'webm', 'dash/audio': True, 'audio': {'bitrate': 160, 'codec': 'opus'}, 'sort': [0, -1, 160]}
2024-11-01 20:25:57.536 T:29536 debug <general>: [plugin.video.youtube] Stream 1:
{'title': '[B]aac-lc@128[/B] (mp4 / aac@128)', 'url': 'https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=<redacted>&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=140&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fmp4&rqh=1&gir=yes&clen=48634411&dur=3005.056&lmt=1689439391766908&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAKwY98BIgrWjizuAqNg2M7aM7mqrTf_BlJBs1ee_hA3yAiEA0mjkMdiEUI5cdpbrNv9WRGPeBRKdzjC4cWQoS0aVQn8%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-48634411', 'meta': {'id': 'vLxAQ4c9jY0', 'title': 'Ethereal Cinematic Space Ambient Music [ Cryostasis · A Unique Atmospheric Journey Through Space ]', 'status': {'unlisted': False, 'private': False, 'crawlable': True, 'family_safe': False, 'live': False}, 'channel': {'id': 'UCLGeMsRzG1nY4jOfEqnLICA', 'author': 'Cosmic Sounding'}, 'thumbnails': {'default': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/default.jpg', 'size': 10800, 'ratio': 1.3333333333333333}, 'medium': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/mqdefault.jpg', 'size': 57600, 'ratio': 1.7777777777777777}, 'high': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/hqdefault.jpg', 'size': 172800, 'ratio': 1.3333333333333333}, 'standard': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/sddefault.jpg', 'size': 307200, 'ratio': 1.3333333333333333}, '720': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/hq720.jpg', 'size': 921600, 'ratio': 1.7777777777777777}, 'oar': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/oardefault.jpg', 'size': 0, 'ratio': 0}, 'maxres': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/maxresdefault.jpg', 'size': 2073600, 'ratio': 1.7777777777777777}}, 'subtitles': None}, 'headers': 'Accept=%2A/%2A&Accept-Language=en-US%2Cen%3Bq%3D0.5&Accept-Charset=ISO-8859-1%2Cutf-8%3Bq%3D0.7%2C%2A%3Bq%3D0.7&Referer=https%3A//www.youtube.com/watch%3Fv%3DvLxAQ4c9jY0&User-Agent=Mozilla/5.0%20%28Linux%3B%20Android%2010%3B%20SM-G981B%29%20AppleWebKit/537.36%20%28KHTML%2C%20like%20Gecko%29%20Chrome/80.0.3987.162%20Mobile%20Safari/537.36&Origin=https%3A//www.youtube.com&Accept-Encoding=gzip%2C%20deflate', 'playback_stats': {'playback_url': '', 'watchtime_url': ''}, 'container': 'mp4', 'dash/audio': True, 'audio': {'bitrate': 128, 'codec': 'aac'}, 'sort': [0, -1, 115.2]}
2024-11-01 20:25:57.536 T:29536 debug <general>: [plugin.video.youtube] Stream 2:
{'title': '[B]he-aac@48[/B] (mp4 / aac@48)', 'url': 'https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=<redacted>&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=139&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fmp4&rqh=1&gir=yes&clen=18325593&dur=3005.126&lmt=1689439329626761&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIgVZks547vaQHzW60oLwZIXmnJ3DRRQhFbAyXtL0QHbc4CIQCXEBvmSFHxOI9DyLtYpTwTxIn_k8I5ABLlVNveP3hpFQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-18325593', 'meta': {'id': 'vLxAQ4c9jY0', 'title': 'Ethereal Cinematic Space Ambient Music [ Cryostasis · A Unique Atmospheric Journey Through Space ]', 'status': {'unlisted': False, 'private': False, 'crawlable': True, 'family_safe': False, 'live': False}, 'channel': {'id': 'UCLGeMsRzG1nY4jOfEqnLICA', 'author': 'Cosmic Sounding'}, 'thumbnails': {'default': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/default.jpg', 'size': 10800, 'ratio': 1.3333333333333333}, 'medium': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/mqdefault.jpg', 'size': 57600, 'ratio': 1.7777777777777777}, 'high': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/hqdefault.jpg', 'size': 172800, 'ratio': 1.3333333333333333}, 'standard': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/sddefault.jpg', 'size': 307200, 'ratio': 1.3333333333333333}, '720': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/hq720.jpg', 'size': 921600, 'ratio': 1.7777777777777777}, 'oar': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/oardefault.jpg', 'size': 0, 'ratio': 0}, 'maxres': {'url': 'https://i.ytimg.com/vi/vLxAQ4c9jY0/maxresdefault.jpg', 'size': 2073600, 'ratio': 1.7777777777777777}}, 'subtitles': None}, 'headers': 'Accept=%2A/%2A&Accept-Language=en-US%2Cen%3Bq%3D0.5&Accept-Charset=ISO-8859-1%2Cutf-8%3Bq%3D0.7%2C%2A%3Bq%3D0.7&Referer=https%3A//www.youtube.com/watch%3Fv%3DvLxAQ4c9jY0&User-Agent=Mozilla/5.0%20%28Linux%3B%20Android%2010%3B%20SM-G981B%29%20AppleWebKit/537.36%20%28KHTML%2C%20like%20Gecko%29%20Chrome/80.0.3987.162%20Mobile%20Safari/537.36&Origin=https%3A//www.youtube.com&Accept-Encoding=gzip%2C%20deflate', 'playback_stats': {'playback_url': '', 'watchtime_url': ''}, 'container': 'mp4', 'dash/audio': True, 'audio': {'bitrate': 48, 'codec': 'aac'}, 'sort': [0, -1, 43.2]}
2024-11-01 20:25:57.536 T:29536 debug <general>: [plugin.video.youtube] Selected stream: Stream 0
2024-11-01 20:25:57.536 T:29536 debug <general>: [plugin.video.youtube] Get |youtube.suggested_videos|: False (bool, success)
2024-11-01 20:25:57.539 T:29536 debug <general>: [plugin.video.youtube] Found cached data for videos:
|['vLxAQ4c9jY0']|
2024-11-01 20:25:57.551 T:29536 debug <general>: [plugin.video.youtube] Converting AudioItem |https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=<redacted>&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=50561231&dur=3005.021&lmt=1641340040503488&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJwL3hCt3NGN1dQ_MNRErtsAuLqRnV_R7x1GMWEMyU_4AiBGDXfXWx8CX1cTCjhET7L50MIMdXeflc52Gm8NjRMQxQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-50561231|
2024-11-01 20:25:57.552 T:29536 warning <general>: "totaltime" in ListItem.setProperty() is deprecated and might be removed in future Kodi versions. Please use InfoTagVideo.setResumePoint().
2024-11-01 20:25:57.554 T:29443 debug <general>: ------ Window Deinit (DialogBusy.xml) ------
2024-11-01 20:25:57.554 T:29542 debug <general>: Thread ScriptObs 545339997952 terminating
2024-11-01 20:25:57.562 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers(https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=89.64.108.147&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=50561231&dur=3005.021&lmt=1641340040503488&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJwL3hCt3NGN1dQ_MNRErtsAuLqRnV_R7x1GMWEMyU_4AiBGDXfXWx8CX1cTCjhET7L50MIMdXeflc52Gm8NjRMQxQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-50561231)
2024-11-01 20:25:57.562 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2024-11-01 20:25:57.562 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2024-11-01 20:25:57.562 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: matches rule: streams
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: mp2
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=false
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=true
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer)
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: for video=false, audio=true
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: for video=true, audio=true
2024-11-01 20:25:57.563 T:29443 debug <general>: CPlayerCoreFactory::GetPlayers: added 2 players
2024-11-01 20:25:57.569 T:29443 debug <general>: Radio UECP (RDS) Processor - new CDVDRadioRDSData
2024-11-01 20:25:57.569 T:29443 debug <general>: Audio ID3 tag processor - new CVideoPlayerAudioID3
2024-11-01 20:25:57.569 T:29443 info <general>: VideoPlayer::OpenFile: plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0
2024-11-01 20:25:57.569 T:29543 debug <general>: Thread VideoPlayer start, auto delete: false
2024-11-01 20:25:57.569 T:29443 debug <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2024-11-01 20:25:57.570 T:29544 debug <general>: Thread BackgroundLoader start, auto delete: false
2024-11-01 20:25:57.570 T:29443 debug <general>: [threads] name: 'BackgroundLoader' priority: '1'
2024-11-01 20:25:57.573 T:29543 info <general>: Creating InputStream
2024-11-01 20:25:57.575 T:29543 debug <general>: CFileCache::Open - <https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=89.64.108.147&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=50561231&dur=3005.021&lmt=1641340040503488&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJwL3hCt3NGN1dQ_MNRErtsAuLqRnV_R7x1GMWEMyU_4AiBGDXfXWx8CX1cTCjhET7L50MIMdXeflc52Gm8NjRMQxQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-50561231> opening
2024-11-01 20:25:57.577 T:29543 debug <general>: CurlFile::Open - <https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=89.64.108.147&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=50561231&dur=3005.021&lmt=1641340040503488&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJwL3hCt3NGN1dQ_MNRErtsAuLqRnV_R7x1GMWEMyU_4AiBGDXfXWx8CX1cTCjhET7L50MIMdXeflc52Gm8NjRMQxQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-50561231>
2024-11-01 20:25:57.577 T:29543 debug <general>: easy_acquire - Created session to https://rr1---sn-f5f7kn7z.googlevideo.com
2024-11-01 20:25:57.608 T:29497 debug <general>: Loading settings for plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0
2024-11-01 20:25:57.611 T:29544 debug <general>: Thread BackgroundLoader 545356906240 terminating
2024-11-01 20:25:57.622 T:29443 debug <general>: CVideoGUIInfo::InitCurrentItem(plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0)
2024-11-01 20:25:57.622 T:29443 debug <general>: CMusicGUIInfo::InitCurrentItem(plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0)
2024-11-01 20:25:57.622 T:29443 debug <general>: CPlayerGUIInfo::InitCurrentItem(plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0)
2024-11-01 20:25:57.638 T:29536 debug <general>: [plugin.video.youtube] Profiling stats: 48829 function calls (48544 primitive calls) in 0.911 seconds
Ordered by: cumulative time, internal time
List reduced from 931 to 20 due to restriction <20>
ncalls tottime percall cumtime percall filename:lineno(function)
1 0.000 0.000 0.910 0.910 xbmc_plugin.py:68(run)
1 0.000 0.000 0.908 0.908 abstract_provider.py:162(navigate)
1 0.000 0.000 0.908 0.908 yt_play.py:308(process)
1 0.000 0.000 0.567 0.567 yt_play.py:40(_play_stream)
1 0.001 0.001 0.547 0.547 youtube.py:191(get_streams)
1 0.001 0.001 0.545 0.545 stream_info.py:1337(load_stream_info)
2 0.000 0.000 0.498 0.249 requests.py:78(request)
2 0.000 0.000 0.494 0.247 sessions.py:502(request)
2 0.000 0.000 0.481 0.240 sessions.py:673(send)
2 0.000 0.000 0.459 0.230 adapters.py:434(send)
2 0.000 0.000 0.454 0.227 connectionpool.py:595(urlopen)
2 0.000 0.000 0.452 0.226 connectionpool.py:380(_make_request)
36 0.000 0.000 0.314 0.009 socket.py:692(readinto)
36 0.000 0.000 0.313 0.009 ssl.py:1299(recv_into)
36 0.000 0.000 0.313 0.009 ssl.py:1157(read)
36 0.313 0.009 0.313 0.009 {method 'read' of '_ssl._SSLSocket' objects}
2 0.000 0.000 0.302 0.151 connection.py:435(getresponse)
2 0.000 0.000 0.300 0.150 client.py:1351(getresponse)
2 0.000 0.000 0.300 0.150 client.py:318(begin)
63 0.000 0.000 0.300 0.005 {method 'readline' of '_io.BufferedReader' objects}
2024-11-01 20:25:57.638 T:29536 debug <general>: CPythonInvoker(4, /storage/.kodi/addons/plugin.video.youtube/resources/lib/plugin.py): script successfully run
2024-11-01 20:25:57.722 T:29443 debug <general>: ------ Window Init (DialogBusy.xml) ------
2024-11-01 20:25:58.083 T:29543 debug <general>: CFileCache::Open - <https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=89.64.108.147&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=50561231&dur=3005.021&lmt=1641340040503488&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJwL3hCt3NGN1dQ_MNRErtsAuLqRnV_R7x1GMWEMyU_4AiBGDXfXWx8CX1cTCjhET7L50MIMdXeflc52Gm8NjRMQxQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-50561231> source chunk size is 0, setting cache chunk size to 131072
2024-11-01 20:25:58.084 T:29543 debug <general>: CFileCache::Open - <https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=89.64.108.147&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=50561231&dur=3005.021&lmt=1641340040503488&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJwL3hCt3NGN1dQ_MNRErtsAuLqRnV_R7x1GMWEMyU_4AiBGDXfXWx8CX1cTCjhET7L50MIMdXeflc52Gm8NjRMQxQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-50561231> using single memory cache sized 20971520 bytes
2024-11-01 20:25:58.084 T:29546 debug <general>: Thread FileCache start, auto delete: false
2024-11-01 20:25:58.109 T:29543 info <general>: Creating Demuxer
2024-11-01 20:25:58.110 T:29543 debug <general>: Open - probing detected format [matroska,webm]
2024-11-01 20:25:58.110 T:29543 debug <general>: Open - avformat_find_stream_info starting
2024-11-01 20:25:58.111 T:29543 debug <general>: Open - av_find_stream_info finished
2024-11-01 20:25:58.111 T:29543 info <general>: ffmpeg[0xb3af910]: Input #0, matroska,webm, from 'https://rr1---sn-f5f7kn7z.googlevideo.com/videoplayback?expire=1730510757&ei=RSslZ56zCrqx6dsPzqSAgA0&ip=89.64.108.147&id=o-AN-NYc5BFkbSss-N8G6-xUVOvflK5zdd7azcWF7UmDCg&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1730489157%2C&mh=_p&mm=31%2C29&mn=sn-f5f7kn7z%2Csn-f5f7lnl6&ms=au%2Crdu&mv=m&mvi=1&pl=13&rms=au%2Cau&initcwndbps=2403750&siu=1&vprv=1&svpuc=1&mime=audio%2Fwebm&rqh=1&gir=yes&clen=50561231&dur=3005.021&lmt=1641340040503488&mt=1730488636&fvip=4&keepalive=yes&fexp=51312688%2C51326932&c=IOS&txp=6211224&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cvprv%2Csvpuc%2Cmime%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAJwL3hCt3NGN1dQ_MNRErtsAuLqRnV_R7x1GMWEMyU_4AiBGDXfXWx8CX1cTCjhET7L50MIMdXeflc52Gm8NjRMQxQ%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACJ0pHgwRAIgbweGZWX7XppPcIc0PQReJJ8dwnYSQsnI6wsuhujxl6cCIEmXyReTJSurf20JLfQzlduSx0yk6N7ErqUgDdKP3IL2&range=0-50561231':
2024-11-01 20:25:58.112 T:29543 info <general>: ffmpeg[0xb3af910]: Metadata:
2024-11-01 20:25:58.112 T:29543 info <general>: ffmpeg[0xb3af910]: encoder : google/video-file
2024-11-01 20:25:58.112 T:29543 info <general>: ffmpeg[0xb3af910]: Duration: 00:50:05.02, start: -0.007000, bitrate: 134 kb/s
2024-11-01 20:25:58.112 T:29543 info <general>: ffmpeg[0xb3af910]: Stream #0:0(eng): Audio: opus, 48000 Hz, stereo, fltp (default)
2024-11-01 20:25:58.112 T:29543 debug <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2024-11-01 20:25:58.112 T:29543 debug <general>: CFileCache::IoControl - setting maxRate to 0.14 Mbit/s with processWait of 100 ms
2024-11-01 20:25:58.112 T:29543 debug <general>: CDVDInputStreamFile::SetReadRate - set cache throttle rate to 18507 bytes per second
2024-11-01 20:25:58.112 T:29543 info <general>: Opening stream: 0 source: 256
2024-11-01 20:25:58.112 T:29543 info <general>: Finding audio codec for: 86076
2024-11-01 20:25:58.112 T:29543 info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder opus
2024-11-01 20:25:58.112 T:29543 info <general>: OpenStream: Allowing max Out-Of-Sync Value of 10 ms
2024-11-01 20:25:58.112 T:29543 info <general>: Creating audio thread
2024-11-01 20:25:58.113 T:29547 debug <general>: Thread VideoPlayerAudio start, auto delete: false
2024-11-01 20:25:58.113 T:29547 info <general>: running thread: CVideoPlayerAudio::Process()
2024-11-01 20:25:58.113 T:29543 debug <general>: CVideoPlayer::SetCaching - caching state 1
2024-11-01 20:25:58.113 T:29543 debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2024-11-01 20:25:58.113 T:29454 debug <general>: OnAVChange: CApplication::OnAVChange
2024-11-01 20:25:58.122 T:29443 debug <general>: FreeVisualisation() done
2024-11-01 20:25:58.122 T:29443 debug <general>: ADDON: Dll Initializing - Waveform
2024-11-01 20:25:58.122 T:29443 debug <general>: SECTION:LoadDLL(/storage/.kodi/addons/visualization.waveform/visualization.waveform.so.20.2.1)
2024-11-01 20:25:58.122 T:29443 debug <general>: Loading: /storage/.kodi/addons/visualization.waveform/visualization.waveform.so.20.2.1
2024-11-01 20:25:58.123 T:29547 debug <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false
2024-11-01 20:25:58.123 T:29547 debug <general>: CDVDAudio::Pause - pausing audio stream
2024-11-01 20:25:58.123 T:29547 debug <general>: ffmpeg[0xb3a88a0]: [opus] Could not update timestamps for skipped samples.
2024-11-01 20:25:58.123 T:29547 info <general>: Creating audio stream (codec id: 86076, channels: 2, sample rate: 48000, no pass-through)
2024-11-01 20:25:58.123 T:29547 debug <general>: CVideoPlayerAudio:: synctype set to 0: clock feedback
2024-11-01 20:25:58.124 T:29451 info <general>: CActiveAESink::OpenSink - initialize sink
2024-11-01 20:25:58.324 T:29451 debug <general>: CActiveAESink::OpenSink - trying to open device ALSA:@
2024-11-01 20:25:58.324 T:29451 info <general>: CAESinkALSA::Initialize - Attempting to open device "@"
2024-11-01 20:25:58.329 T:29451 info <general>: CAESinkALSA::Initialize - Opened device "sysdefault"
2024-11-01 20:25:58.329 T:29451 info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2024-11-01 20:25:58.330 T:29451 info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2024-11-01 20:25:58.330 T:29451 debug <general>: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
2024-11-01 20:25:58.330 T:29451 debug <general>: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
2024-11-01 20:25:58.330 T:29451 debug <general>: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2024-11-01 20:25:58.330 T:29451 debug <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2024-11-01 20:25:58.330 T:29451 debug <general>: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2024-11-01 20:25:58.330 T:29451 debug <general>: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2024-11-01 20:25:58.331 T:29451 debug <general>: CActiveAESink::OpenSink - ALSA Initialized:
2024-11-01 20:25:58.331 T:29451 debug <general>: Output Device : Default (vc4-hdmi-0 MAI PCM i2s-hifi-0) ()
2024-11-01 20:25:58.331 T:29451 debug <general>: Sample Rate : 48000
2024-11-01 20:25:58.331 T:29451 debug <general>: Sample Format : AE_FMT_S24NE3
2024-11-01 20:25:58.331 T:29451 debug <general>: Channel Count : 2
2024-11-01 20:25:58.331 T:29451 debug <general>: Channel Layout: FL, FR
2024-11-01 20:25:58.331 T:29451 debug <general>: Frames : 2400
2024-11-01 20:25:58.331 T:29451 debug <general>: Frame Size : 6
2024-11-01 20:25:58.338 T:29450 debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2024-11-01 20:25:58.706 T:29543 info <general>: Skipped 1 duplicate messages..
2024-11-01 20:25:58.706 T:29543 debug <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2024-11-01 20:25:58.706 T:29543 debug <general>: CVideoPlayer::HandleMessages - player started 1
2024-11-01 20:25:58.709 T:29497 debug <general>: OnAVChange: CApplication::OnAVChange
2024-11-01 20:25:58.709 T:29543 debug <general>: VideoPlayer::Sync - Audio - pts: 301000.000000, cache: 503135.868594, totalcache: 800000.011921
2024-11-01 20:25:58.709 T:29543 warning <general>: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
2024-11-01 20:25:58.709 T:29547 debug <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_RESYNC(-202135.868594), level: 5, cache: 485286.887928
2024-11-01 20:25:58.709 T:29547 debug <general>: CDVDAudio::Resume - resume audio stream
2024-11-01 20:25:58.709 T:29450 debug <general>: ActiveAE - start sync of audio stream
2024-11-01 20:25:58.709 T:29450 debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2024-11-01 20:25:58.710 T:29497 debug <general>: OnAVStarted: CApplication::OnAVStarted
2024-11-01 20:25:58.722 T:29443 debug <general>: Activating window ID: 12006
2024-11-01 20:25:58.754 T:29443 debug <general>: Updating visualization albumart: https://i.ytimg.com/vi/vLxAQ4c9jY0/hqdefault.jpg
2024-11-01 20:25:58.756 T:29465 debug <general>: [plugin.video.youtube] PlayerMonitor active threads: ||
2024-11-01 20:25:58.757 T:29548 debug <general>: [plugin.video.youtube] PlayerMonitorThread[vLxAQ4c9jY0]: Starting
2024-11-01 20:25:58.757 T:29548 debug <general>: [plugin.video.youtube] JSONStore.load - loading:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json|
2024-11-01 20:25:58.758 T:29548 debug <general>: [plugin.video.youtube] JSONStore.save - data unchanged:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json|
2024-11-01 20:25:58.758 T:29548 debug <general>: [plugin.video.youtube] JSONStore.load - loading:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/api_keys.json|
2024-11-01 20:25:58.758 T:29548 debug <general>: [plugin.video.youtube] JSONStore.save - data unchanged:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/api_keys.json|
2024-11-01 20:25:58.759 T:29548 debug <general>: [plugin.video.youtube] JSONStore.load - loading:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json|
2024-11-01 20:25:58.759 T:29548 debug <general>: [plugin.video.youtube] JSONStore.save - data unchanged:
|/storage/.kodi/userdata/addon_data/plugin.video.youtube/access_manager.json|
2024-11-01 20:25:58.759 T:29548 debug <general>: [plugin.video.youtube] Get |youtube.api.key|: "AIz...Usc" (str, success)
2024-11-01 20:25:58.759 T:29548 debug <general>: [plugin.video.youtube] Get |youtube.api.id|: "867...q4kvi" (str, success)
2024-11-01 20:25:58.759 T:29548 debug <general>: [plugin.video.youtube] Get |youtube.api.secret|: "GOC...uTl" (str, success)
2024-11-01 20:25:58.759 T:29548 debug <general>: [plugin.video.youtube] User: |0|, Using API key set: |own|
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Selecting YouTube config "All"
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Access token count: |2|, refresh token count: |2|
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Get |youtube.language|: "pl" (str, success)
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Get |youtube.region|: "PL" (str, success)
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Get |kodion.content.max_per_page|: 30 (int, success)
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Get |requests.ssl.verify|: True (bool, success)
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Get |requests.timeout.connect|: 9 (int, success)
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Get |requests.timeout.read|: 27 (int, success)
2024-11-01 20:25:58.760 T:29548 debug <general>: [plugin.video.youtube] Get |requests.proxy.source|: 1 (int, success)
2024-11-01 20:25:58.761 T:29548 debug <general>: [plugin.video.youtube] User is logged in
2024-11-01 20:25:58.832 T:29450 debug <general>: ActiveAE::SyncStream - average error of 20.677634, start adjusting
2024-11-01 20:25:58.832 T:29450 debug <general>: ActiveAE::SyncStream - average error 0.677634 below threshold of 30.000000
2024-11-01 20:25:59.023 T:29443 debug <general>: ------ Window Deinit (MyVideoNav.xml) ------
2024-11-01 20:25:59.023 T:29443 debug <general>: FreeVisualisation() started
2024-11-01 20:25:59.023 T:29443 debug <general>: SECTION:UnloadDll(/storage/.kodi/addons/visualization.waveform/visualization.waveform.so.20.2.1)
2024-11-01 20:25:59.023 T:29443 info <general>: ADDON: Dll Destroyed - Waveform
2024-11-01 20:25:59.023 T:29443 debug <general>: FreeVisualisation() done
2024-11-01 20:25:59.038 T:29443 debug <general>: ------ Window Init (MusicVisualisation.xml) ------
2024-11-01 20:25:59.038 T:29443 info <general>: Loading skin file: MusicVisualisation.xml, load type: KEEP_IN_MEMORY
2024-11-01 20:25:59.041 T:29443 debug <general>: FreeVisualisation() done
2024-11-01 20:25:59.041 T:29443 debug <general>: ADDON: Dll Initializing - Waveform
2024-11-01 20:25:59.041 T:29443 debug <general>: SECTION:LoadDLL(/storage/.kodi/addons/visualization.waveform/visualization.waveform.so.20.2.1)
2024-11-01 20:25:59.041 T:29443 debug <general>: Loading: /storage/.kodi/addons/visualization.waveform/visualization.waveform.so.20.2.1
2024-11-01 20:25:59.043 T:29443 debug <general>: ------ Window Init (DialogSeekBar.xml) ------
2024-11-01 20:25:59.054 T:29443 debug <general>: ------ Window Deinit (DialogBusy.xml) ------
2024-11-01 20:25:59.054 T:29443 debug <general>: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
2024-11-01 20:25:59.082 T:29450 debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2024-11-01 20:25:59.104 T:29443 debug <general>: Updating visualization albumart: https://i.ytimg.com/vi/vLxAQ4c9jY0/hqdefault.jpg
2024-11-01 20:26:08.996 T:29474 debug <general>: PushCecKeypress - received key e0 duration 1000
2024-11-01 20:26:09.004 T:29443 debug <general>: HandleKey: guide (0xe0) pressed, window 12006, action is Stop
2024-11-01 20:26:09.004 T:29443 info <general>: CVideoPlayer::CloseFile()
2024-11-01 20:26:09.004 T:29443 debug <general>: DeleteRenderer - deleting renderer
2024-11-01 20:26:09.004 T:29443 debug <general>: LinuxRendererGLES: Cleaning up GLES resources
2024-11-01 20:26:09.005 T:29443 debug <general>: SetHDR: setting connector colorspace to Default
2024-11-01 20:26:09.005 T:29443 info <general>: VideoPlayer: waiting for threads to exit
2024-11-01 20:26:09.008 T:29543 info <general>: CVideoPlayer::OnExit()
2024-11-01 20:26:09.009 T:29543 info <general>: Closing stream player 1
2024-11-01 20:26:09.009 T:29543 info <general>: Waiting for audio thread to exit
2024-11-01 20:26:09.033 T:29547 info <general>: thread end: CVideoPlayerAudio::OnExit()
2024-11-01 20:26:09.033 T:29547 debug <general>: Thread VideoPlayerAudio 545230085888 terminating
2024-11-01 20:26:09.034 T:29543 info <general>: Closing audio device
2024-11-01 20:26:09.083 T:29543 debug <general>: CDVDAudio::Flush - flush audio stream
2024-11-01 20:26:09.083 T:29450 debug <general>: CActiveAE::DiscardStream - audio stream deleted
2024-11-01 20:26:09.083 T:29450 debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2024-11-01 20:26:09.085 T:29543 info <general>: Skipped 2 duplicate messages..
2024-11-01 20:26:09.085 T:29543 info <general>: Deleting audio codec
2024-11-01 20:26:09.089 T:29451 info <general>: CActiveAESink::OpenSink - initialize sink
2024-11-01 20:26:09.097 T:29497 info <general>: Deleting settings information for files plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0
2024-11-01 20:26:09.100 T:29454 debug <general>: DoWork - Saving file state for video item plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0
2024-11-01 20:26:09.125 T:29454 debug <general>: DoWork - Saving file state for audio item plugin://plugin.video.youtube/play/?video_id=vLxAQ4c9jY0
2024-11-01 20:26:09.282 T:29451 debug <general>: CActiveAESink::OpenSink - trying to open device ALSA:@
2024-11-01 20:26:09.282 T:29451 info <general>: CAESinkALSA::Initialize - Attempting to open device "@"
2024-11-01 20:26:09.295 T:29451 info <general>: CAESinkALSA::Initialize - Opened device "sysdefault"
2024-11-01 20:26:09.296 T:29451 info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2024-11-01 20:26:09.296 T:29451 info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2024-11-01 20:26:09.297 T:29451 debug <general>: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
2024-11-01 20:26:09.298 T:29451 debug <general>: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
2024-11-01 20:26:09.298 T:29451 debug <general>: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2024-11-01 20:26:09.299 T:29451 debug <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2024-11-01 20:26:09.299 T:29451 debug <general>: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2024-11-01 20:26:09.299 T:29451 debug <general>: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2024-11-01 20:26:09.300 T:29451 debug <general>: CActiveAESink::OpenSink - ALSA Initialized:
2024-11-01 20:26:09.300 T:29451 debug <general>: Output Device : Default (vc4-hdmi-0 MAI PCM i2s-hifi-0) ()
2024-11-01 20:26:09.300 T:29451 debug <general>: Sample Rate : 44100
2024-11-01 20:26:09.300 T:29451 debug <general>: Sample Format : AE_FMT_S24NE3
2024-11-01 20:26:09.300 T:29451 debug <general>: Channel Count : 2
2024-11-01 20:26:09.300 T:29451 debug <general>: Channel Layout: FL, FR
2024-11-01 20:26:09.300 T:29451 debug <general>: Frames : 2205
2024-11-01 20:26:09.300 T:29451 debug <general>: Frame Size : 6
2024-11-01 20:26:09.303 T:29450 debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2024-11-01 20:26:09.367 T:29546 info <general>: Skipped 3 duplicate messages..
2024-11-01 20:26:09.367 T:29546 debug <general>: Thread FileCache 545259515648 terminating
2024-11-01 20:26:09.369 T:29456 debug <general>: OnPlayBackStopped: CApplication::OnPlayBackStopped
2024-11-01 20:26:09.369 T:29543 debug <general>: Thread VideoPlayer 545339997952 terminating
2024-11-01 20:26:09.369 T:29443 info <general>: VideoPlayer: finished waiting
2024-11-01 20:26:09.369 T:29443 info <general>: CVideoPlayer::CloseFile()
2024-11-01 20:26:09.369 T:29443 info <general>: VideoPlayer: waiting for threads to exit
2024-11-01 20:26:09.370 T:29443 info <general>: VideoPlayer: finished waiting
2024-11-01 20:26:09.374 T:29443 debug <general>: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
2024-11-01 20:26:09.374 T:29443 debug <general>: Audio ID3 tag processor - delete ~CVideoPlayerAudioID3
2024-11-01 20:26:09.374 T:29443 debug <general>: CGUIWindowManager::PreviousWindow: Deactivate
2024-11-01 20:26:09.375 T:29443 debug <general>: FreeVisualisation() started
2024-11-01 20:26:09.375 T:29443 debug <general>: SECTION:UnloadDll(/storage/.kodi/addons/visualization.waveform/visualization.waveform.so.20.2.1)
2024-11-01 20:26:09.375 T:29443 info <general>: ADDON: Dll Destroyed - Waveform
2024-11-01 20:26:09.376 T:29443 debug <general>: FreeVisualisation() done
2024-11-01 20:26:09.381 T:29443 debug <general>: ------ Window Deinit (DialogSeekBar.xml) ------
2024-11-01 20:26:09.391 T:29443 debug <general>: CDRMAtomic::FlipPage - Execute modeset at next commit
2024-11-01 20:26:09.405 T:29443 debug <general>: ------ Window Init (DialogSeekBar.xml) ------
2024-11-01 20:26:09.406 T:29443 debug <general>: ------ Window Deinit (DialogSeekBar.xml) ------
2024-11-01 20:26:09.415 T:29443 debug <general>: ------ Window Deinit (MusicVisualisation.xml) ------
2024-11-01 20:26:09.415 T:29443 debug <general>: FreeVisualisation() done
2024-11-01 20:26:09.437 T:29443 debug <general>: CGUIWindowManager::PreviousWindow: Activate new
2024-11-01 20:26:09.438 T:29443 debug <general>: ------ Window Init (MyVideoNav.xml) ------
2024-11-01 20:26:09.446 T:29443 error <general>: Control 55 in window 10025 has been asked to focus, but it can't
2024-11-01 20:26:09.605 T:29443 debug <general>: CGUIMediaWindow::GetDirectory (plugin://plugin.video.youtube/channel/mine/playlist/PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd/?category_label=muzyka%20do%20czytania)
2024-11-01 20:26:09.605 T:29443 debug <general>: ParentPath = [plugin://plugin.video.youtube/channel/mine/playlist/PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd/?category_label=muzyka%20do%20czytania]
2024-11-01 20:26:09.612 T:29443 debug <general>: Loading items: 11, directory: plugin://plugin.video.youtube/channel/mine/playlist/PL9lPEdHwdZi3xGcgw6AtdhXfl6pi-XWrd/?category_label=muzyka%20do%20czytania sort method: 0, ascending: false
2024-11-01 20:26:09.616 T:29497 debug <general>: CAddonDatabase::SetLastUsed[plugin.video.youtube] took 11 ms
2024-11-01 20:26:09.622 T:29549 debug <general>: Thread BackgroundLoader start, auto delete: false
2024-11-01 20:26:09.622 T:29443 debug <general>: [threads] name: 'BackgroundLoader' priority: '1'
2024-11-01 20:26:09.629 T:29549 debug <general>: Thread BackgroundLoader 545356906240 terminating
2024-11-01 20:26:09.666 T:29465 debug <general>: [plugin.video.youtube] PlayerMonitorThread[vLxAQ4c9jY0]: stopping
2024-11-01 20:26:09.666 T:29465 debug <general>: [plugin.video.youtube] PlayerMonitorThread[vLxAQ4c9jY0]: Stop event set
2024-11-01 20:26:09.708 T:29443 debug <general>: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
2024-11-01 20:26:09.764 T:29548 debug <general>: [plugin.video.youtube] Get |kodion.play_count.percent|: 85 (int, success)
2024-11-01 20:26:09.765 T:29548 debug <general>: [plugin.video.youtube] Playback stopped [vLxAQ4c9jY0]: 9.723 secs of 3005.021 @ 0%, played 0 time(s)
2024-11-01 20:26:09.765 T:29548 debug <general>: [plugin.video.youtube] Get |youtube.post.play.refresh|: False (bool, success)
2024-11-01 20:26:09.765 T:29548 debug <general>: [plugin.video.youtube] PlayerMonitorThread[vLxAQ4c9jY0]: End event set
2024-11-01 20:26:09.765 T:29465 debug <general>: [plugin.video.youtube] PlayerMonitorThread[vLxAQ4c9jY0]: clean up
2024-11-01 20:26:09.765 T:29465 debug <general>: [plugin.video.youtube] PlayerMonitor active threads: ||
2024-11-01 20:26:12.831 T:29474 debug <general>: PushCecKeypress - received key c8 duration 0
2024-11-01 20:26:12.837 T:29443 debug <general>: HandleKey: 200 (0xc8, obc55) pressed, window 10025, action is activatewindow(home)
2024-11-01 20:26:12.837 T:29443 debug <general>: Activating window ID: 10000
2024-11-01 20:26:13.059 T:29474 debug <general>: PushCecKeypress - received key c8 duration 228
2024-11-01 20:26:13.139 T:29443 debug <general>: ------ Window Deinit (MyVideoNav.xml) ------
2024-11-01 20:26:13.139 T:29443 debug <general>: FreeVisualisation() done
2024-11-01 20:26:13.154 T:29443 debug <general>: ------ Window Init (Home.xml) ------
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[addons://]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[addons://sources/video/]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[addons://sources/audio/]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[addons://sources/game/]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[addons://sources/executable/]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[addons://sources/image/]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[library://video/]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[sources://video/]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[special://videoplaylists/]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[favourites://]: refreshing..
2024-11-01 20:26:13.155 T:29443 debug <general>: CDirectoryProvider[addons://sources/game/]: refreshing..
2024-11-01 20:26:13.157 T:29497 debug <general>: CAddonDatabase: SELECT repo.id FROM repo .. took 0 ms
2024-11-01 20:26:13.171 T:29454 debug <general>: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
2024-11-01 20:26:13.171 T:29454 debug <general>: Getting Directory (special://profile/playlists/video)
2024-11-01 20:26:13.171 T:29454 debug <general>: Getting Directory (special://profile/playlists/mixed)
2024-11-01 20:26:13.171 T:29454 debug <general>: CMultiPathDirectory::MergeItems, items = 0
2024-11-01 20:26:13.176 T:29497 debug <general>: CAddonDatabase: query SELECT addons.*, repo.addonID AS repoID FROM addons JOIN addonlinkrepo ON addons.id=addonlinkrepo.idAddon JOIN repo ON repo.id=addonlinkrepo.idRepo WHERE addonlinkrepo.idRepo IN (1,2) ORDER BY repo.addonID, addons.addonID returned 1087 rows in 18 ms
2024-11-01 20:26:13.204 T:29456 warning <general>: CreateLoader - unsupported protocol(thumb) in thumb://None/
2024-11-01 20:26:13.204 T:29456 debug <general>: GetImageHash - unable to stat url thumb://None/
2024-11-01 20:26:13.260 T:29497 debug <general>: CAddonDatabase::GetAddons took 102 ms
2024-11-01 20:26:13.269 T:29497 debug <general>: CAddonMgr::GetAvailableUpdatesOrOutdatedAddons took 114 ms
2024-11-01 20:26:14.143 T:29474 debug <general>: PushCecKeypress - received key a6 duration 0
2024-11-01 20:26:14.148 T:29443 debug <general>: HandleKey: 166 (0xa6, obc89) pressed, window 10000, action is Up
Is that enough?
Not quite.
Everything in your log looks normal, and the video you were playing did not have a resume point. You have also disabled local history, so that is probably to be expected. Everything seems to be working the way you have set it up.
~I'm not sure how Kodi itself handles resuming for audio. If you get a log showing playback starting, skipping to >3minutes from the start, stopping, then trying to resume, but instead playing from the start then that may provide more information.~
Edit: Kodi doesn't support saving or using resume points for audio. This plugin does, but you have disabled that functionality.
Tanslator: This is a old problem and has been going on for several versions of the plugin. I won't provide logs because the problem is easy to reproduce.
YouTube v7.1.0.1, Kodi 21.0, RPi4