search

anxdpanic / plugin.video.youtube

Watch your favorite YouTube content on Kodi
https://ytaddon.panicked.xyz/forum
691 stars 114 forks source link

Kodi become unstable after X video.... #881

Closed DjDiabolik closed 2 months ago

DjDiabolik commented 2 months ago

Context

Please provide any relevant information about your setup

Current Behavior

After updating the youtube addons to the indicated version the same thing happened to me a couple of times already (but I can't reproduce it on demand): Once a video has finished playing, Kodi returns to the section list for example "My Subscription" It happens that kodi becomes practically unusable and everything stops loading to show the infinite spin blue whatever I try to do... if there is one thing I notice is that instead of the viewing status icons for all video, thumbnails appear. At this point nothigs more work and if i try to close and exit kodi freeze and i need to force close by terminal whit command "flatpak kill tv.Kodi.kodi"

Apparently on kodi.log there's no strange errors.... It seems that using youtube crashes kodi and it does it randomly...

if anyone has a similar problem let me know...

MoojMidge commented 2 months ago

Does it only happen when playing from My Subscriptions?

You need to post some logs otherwise there is no possibility of investigating further.

Post the kodi.log when it freezes, or kodi.old.log if you have already closed Kodi and relaunched it.

Additionally post a new debug log of opening, playing, stopping, the returning to the previous window, even if it doesn't freeze.

DjDiabolik commented 2 months ago

@MoojMidge Obviously the section "My Subscription" it's the most used and often and I have to say that it doesn't always happen. There are days that I can watch several videos without anything happening, others that instead it happens after like 3 or 4 finished videos.

Then as I say... Kodi it's not really freeze but just anything else I try to open (like another addons or simply another youtube section ) makes kodi load forever whit the blue spin on screen.

when this happens I notice it because I notice that if for example I am browsing the "My Subscription" instead of the normal icons of watched or not watched status (on the left of title) thumbnails of the same videos appear there and after that all stop to works.

the problem is that things still happen too randomly and suddenly... I can't make this happen on purpose and I don't think I'll be able to get the debug log this time. The last time it's append the last part of normal kodi.log it's that:

2024-08-18 01:22:46.689 T:7        info <general>: VideoPlayer::OpenFile: plugin://plugin.video.youtube/play/?video_id=DQ2c2mzje4o
2024-08-18 01:22:46.690 T:926842    info <general>: Creating InputStream
2024-08-18 01:22:46.692 T:926842    info <general>: AddOnLog: inputstream.adaptive: [Repr. chooser] Resolution set: 1920x1080, max allowed: 1920x1080, Adjust refresh rate: 0
2024-08-18 01:22:46.695 T:926842    info <general>: AddOnLog: inputstream.adaptive: Manifest successfully parsed (Periods: 1, Streams in first period: 7, Type: VOD)
2024-08-18 01:22:46.695 T:926842    info <general>: Creating Demuxer
2024-08-18 01:22:46.696 T:926842    info <general>: Opening stream: 1001 source: 256
2024-08-18 01:22:46.938 T:926842    info <general>: Creating video codec with codec id: 27
2024-08-18 01:22:46.938 T:926842    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2024-08-18 01:22:46.939 T:926842    info <general>: Creating video thread
2024-08-18 01:22:46.939 T:926847    info <general>: running thread: video_thread
2024-08-18 01:22:46.940 T:926842    info <general>: Opening stream: 1007 source: 256
2024-08-18 01:22:46.989 T:926842    info <general>: Finding audio codec for: 86018
2024-08-18 01:22:46.989 T:926842    info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2024-08-18 01:22:46.989 T:926842    info <general>: OpenStream: Allowing max Out-Of-Sync Value of 10 ms
2024-08-18 01:22:46.989 T:926842    info <general>: Creating audio thread
2024-08-18 01:22:46.990 T:926849    info <general>: running thread: CVideoPlayerAudio::Process()
2024-08-18 01:22:47.077 T:926892    info <general>: COutput::OnStartup: Output Thread created
2024-08-18 01:22:47.084 T:926849    info <general>: Creating audio stream (codec id: 86018, channels: 2, sample rate: 44100, no pass-through)
2024-08-18 01:22:47.085 T:21       info <general>: CActiveAESink::OpenSink - initialize sink
2024-08-18 01:22:47.134 T:21       info <general>: PulseAudio: Opened device alsa_output.pci-0000_00_0e.0.hdmi-surround in pcm mode with Buffersize 150 ms Periodsize 50 ms
2024-08-18 01:22:47.149 T:7        info <general>: GL: Selecting YUV 2 RGB shader
2024-08-18 01:22:47.149 T:7        info <general>: GL: Using GL_ARB_pixel_buffer_object
2024-08-18 01:22:47.149 T:7        info <general>: Using GL_TEXTURE_2D
2024-08-18 01:22:47.330 T:926847    info <general>: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 33333
2024-08-18 01:22:52.204 T:7        info <general>: GL: Selecting YUV 2 RGB shader
2024-08-18 01:22:52.206 T:7        info <general>: GL: Using GL_ARB_pixel_buffer_object
2024-08-18 01:22:52.206 T:7        info <general>: Using GL_TEXTURE_2D
2024-08-18 01:23:17.023 T:926849    info <general>: ProcessDecoderOutput: Changed max allowed Out-Of-Sync value to 52 ms due self-learning
2024-08-18 01:32:58.383 T:926849    info <general>: CVideoPlayerAudio::Process - stream stalled
2024-08-18 01:32:58.447 T:926842    info <general>: Process - eof reading from demuxer
2024-08-18 01:32:58.447 T:926842    info <general>: CVideoPlayer::OnExit()
2024-08-18 01:32:58.447 T:926842    info <general>: VideoPlayer: eof, waiting for queues to empty
2024-08-18 01:32:58.447 T:926842    info <general>: Closing stream player 1
2024-08-18 01:32:58.448 T:926842    info <general>: CDVDMessageQueue(audio)::WaitUntilEmpty
2024-08-18 01:32:58.448 T:926842    info <general>: Waiting for audio thread to exit
2024-08-18 01:32:58.448 T:926849    info <general>: thread end: CVideoPlayerAudio::OnExit()
2024-08-18 01:32:58.448 T:926842    info <general>: Closing audio device
2024-08-18 01:32:58.449 T:926842    info <general>: Deleting audio codec
2024-08-18 01:32:58.450 T:926842    info <general>: Closing stream player 2
2024-08-18 01:32:58.450 T:926842    info <general>: CDVDMessageQueue(video)::WaitUntilEmpty
2024-08-18 01:32:58.584 T:926842    info <general>: waiting for video thread to exit
2024-08-18 01:32:58.584 T:926847    info <general>: thread end: video_thread
2024-08-18 01:32:58.584 T:926842    info <general>: deleting video codec
2024-08-18 01:32:58.589 T:926842    info <general>: ADDON: Dll Destroyed - InputStream Adaptive
2024-08-18 01:32:58.602 T:7        info <general>: CVideoPlayer::CloseFile()
2024-08-18 01:32:58.603 T:7        info <general>: VAAPI::Close
2024-08-18 01:32:58.603 T:926892    info <general>: COutput::OnExit: Output Thread terminated
2024-08-18 01:32:58.603 T:7        info <general>: VAAPI::Close - closing decoder context
2024-08-18 01:32:58.606 T:7        info <general>: VideoPlayer: waiting for threads to exit
2024-08-18 01:32:58.606 T:7        info <general>: VideoPlayer: finished waiting
2024-08-18 01:32:58.606 T:7        info <general>: CVideoPlayer::CloseFile()
2024-08-18 01:32:58.606 T:7        info <general>: VideoPlayer: waiting for threads to exit
2024-08-18 01:32:58.606 T:7        info <general>: VideoPlayer: finished waiting
2024-08-18 01:32:58.615 T:7       error <general>: Control 55 in window 10025 has been asked to focus, but it can't
2024-08-18 01:32:59.302 T:971540    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.0) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-18 01:33:00.424 T:971540   error <general>: [plugin.video.youtube] Python strptime bug workaround - https://github.com/python/cpython/issues/71587
2024-08-18 01:33:01.258 T:971560    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.0) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-18 01:37:21.617 T:971575   error <general>: GetDirectory - Error getting plugin://plugin.video.youtube/kodion/search/list/?category_label=Ricerca
2024-08-18 01:37:21.618 T:7       error <general>: CGUIMediaWindow::GetDirectory(plugin://plugin.video.youtube/kodion/search/list/?category_label=Ricerca) failed
2024-08-18 01:37:58.784 T:7        info <general>: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY
2024-08-18 01:38:04.801 T:7        info <general>: Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY
2024-08-18 01:38:15.915 T:7        info <general>: Loading skin file: DialogSelect.xml, load type: KEEP_IN_MEMORY
2024-08-18 01:38:22.768 T:7        info <general>: Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY
2024-08-18 01:38:33.017 T:7        info <general>: Loading skin file: SettingsSystemInfo.xml, load type: KEEP_IN_MEMORY
2024-08-18 01:40:08.798 T:7        info <general>: Loading skin file: DialogButtonMenu.xml, load type: KEEP_IN_MEMORY
2024-08-18 01:40:11.179 T:7        info <general>: Stopping the application...
2024-08-18 01:40:11.179 T:7        info <general>: Stopping player
2024-08-18 01:40:11.179 T:7        info <general>: Storing total System Uptime
2024-08-18 01:40:11.180 T:7        info <general>: Saving settings
2024-08-18 01:40:11.182 T:7        info <general>: Saving skin settings
2024-08-18 01:40:11.183 T:7        info <general>: Stopping all

as you can see I finished watching the video... after that when kodi as back to show the "My Subscription" list I immediately saw the thumbnails appear to the left of the videos instead the "watched/not watched status" icons and from the log you can see that I tried to open the other sections of the addons but it fails and everything goes into error. You can also see that when I tried to exit kodi the procedure did not complete and I had to close its process from the linux terminal..

I'm using youtube when this happens. but in the end I don't want it to be my current skin that maybe goes into error and creates the generic stall....

DjDiabolik commented 2 months ago

In the meantime yesterday the version of Kodi itself was also updated and we moved to version 21.1..... I can do one thing next time: when it happens to me again i can go to enable the debug log level on the fly and try to open some section or other addons and i can try to see how it's appen in kodi.log after i try to exit from it.

Do you think this is useful?

DjDiabolik commented 2 months ago

WATCH THAT... it's just happened....... look that: immagine

The current kodi.log after try to look this last video:

2024-08-18 23:56:24.476 T:7        info <general>: VideoPlayer::OpenFile: plugin://plugin.video.youtube/play/?video_id=eyoKcVB1ZIA
2024-08-18 23:56:24.477 T:47218 warning <general>: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
2024-08-18 23:56:24.477 T:47218    info <general>: Creating InputStream
2024-08-18 23:56:24.479 T:47218    info <general>: AddOnLog: inputstream.adaptive: [Repr. chooser] Resolution set: 1920x1080, max allowed: 1920x1080, Adjust refresh rate: 0
2024-08-18 23:56:24.482 T:47218    info <general>: AddOnLog: inputstream.adaptive: Manifest successfully parsed (Periods: 1, Streams in first period: 7, Type: VOD)
2024-08-18 23:56:24.482 T:47218    info <general>: Creating Demuxer
2024-08-18 23:56:24.482 T:47218    info <general>: Opening stream: 1001 source: 256
2024-08-18 23:56:24.724 T:47218    info <general>: Creating video codec with codec id: 27
2024-08-18 23:56:24.724 T:47218    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2024-08-18 23:56:24.726 T:47218    info <general>: Creating video thread
2024-08-18 23:56:24.726 T:47223    info <general>: running thread: video_thread
2024-08-18 23:56:24.726 T:47218    info <general>: Opening stream: 1007 source: 256
2024-08-18 23:56:24.915 T:47218    info <general>: Finding audio codec for: 86018
2024-08-18 23:56:24.916 T:47218    info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2024-08-18 23:56:24.916 T:47218    info <general>: OpenStream: Allowing max Out-Of-Sync Value of 50 ms
2024-08-18 23:56:24.916 T:47218    info <general>: Creating audio thread
2024-08-18 23:56:24.917 T:47225    info <general>: running thread: CVideoPlayerAudio::Process()
2024-08-18 23:56:25.008 T:47225    info <general>: Creating audio stream (codec id: 86018, channels: 2, sample rate: 44100, no pass-through)
2024-08-18 23:56:25.015 T:47265    info <general>: COutput::OnStartup: Output Thread created
2024-08-18 23:56:25.046 T:19       info <general>: CActiveAESink::OpenSink - initialize sink
2024-08-18 23:56:25.098 T:7        info <general>: GL: Selecting YUV 2 RGB shader
2024-08-18 23:56:25.098 T:7        info <general>: GL: Using GL_ARB_pixel_buffer_object
2024-08-18 23:56:25.098 T:7        info <general>: Using GL_TEXTURE_2D
2024-08-18 23:56:25.236 T:19       info <general>: PulseAudio: Opened device alsa_output.pci-0000_00_0e.0.hdmi-surround in pcm mode with Buffersize 150 ms Periodsize 50 ms
2024-08-18 23:56:25.654 T:47223    info <general>: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 33333
2024-08-18 23:56:30.343 T:7        info <general>: GL: Selecting YUV 2 RGB shader
2024-08-18 23:56:30.344 T:7        info <general>: GL: Using GL_ARB_pixel_buffer_object
2024-08-18 23:56:30.344 T:7        info <general>: Using GL_TEXTURE_2D
2024-08-19 00:06:46.936 T:47225    info <general>: CVideoPlayerAudio::Process - stream stalled
2024-08-19 00:06:47.018 T:47218    info <general>: Process - eof reading from demuxer
2024-08-19 00:06:47.018 T:47218    info <general>: CVideoPlayer::OnExit()
2024-08-19 00:06:47.018 T:47218    info <general>: VideoPlayer: eof, waiting for queues to empty
2024-08-19 00:06:47.019 T:47218    info <general>: Closing stream player 1
2024-08-19 00:06:47.019 T:47218    info <general>: CDVDMessageQueue(audio)::WaitUntilEmpty
2024-08-19 00:06:47.019 T:47218    info <general>: Waiting for audio thread to exit
2024-08-19 00:06:47.019 T:47225    info <general>: thread end: CVideoPlayerAudio::OnExit()
2024-08-19 00:06:47.020 T:47218    info <general>: Closing audio device
2024-08-19 00:06:47.021 T:47218    info <general>: Deleting audio codec
2024-08-19 00:06:47.021 T:47218    info <general>: Closing stream player 2
2024-08-19 00:06:47.022 T:47218    info <general>: CDVDMessageQueue(video)::WaitUntilEmpty
2024-08-19 00:06:47.157 T:47218    info <general>: waiting for video thread to exit
2024-08-19 00:06:47.157 T:47223    info <general>: thread end: video_thread
2024-08-19 00:06:47.158 T:47218    info <general>: deleting video codec
2024-08-19 00:06:47.160 T:47218    info <general>: ADDON: Dll Destroyed - InputStream Adaptive
2024-08-19 00:06:47.177 T:7        info <general>: CVideoPlayer::CloseFile()
2024-08-19 00:06:47.177 T:7        info <general>: VAAPI::Close
2024-08-19 00:06:47.178 T:47265    info <general>: COutput::OnExit: Output Thread terminated
2024-08-19 00:06:47.178 T:7        info <general>: VAAPI::Close - closing decoder context
2024-08-19 00:06:47.180 T:7        info <general>: VideoPlayer: waiting for threads to exit
2024-08-19 00:06:47.180 T:7        info <general>: VideoPlayer: finished waiting
2024-08-19 00:06:47.180 T:7        info <general>: CVideoPlayer::CloseFile()
2024-08-19 00:06:47.180 T:7        info <general>: VideoPlayer: waiting for threads to exit
2024-08-19 00:06:47.180 T:7        info <general>: VideoPlayer: finished waiting
2024-08-19 00:06:47.188 T:7       error <general>: Control 55 in window 10025 has been asked to focus, but it can't
2024-08-19 00:06:47.925 T:92678    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-19 00:06:49.194 T:92678   error <general>: [plugin.video.youtube] Python strptime bug workaround - https://github.com/python/cpython/issues/71587
2024-08-19 00:06:49.693 T:92681    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-19 00:06:50.821 T:92681   error <general>: [plugin.video.youtube] Python strptime bug workaround - https://github.com/python/cpython/issues/71587

Now i try to enable the DEBUG LOG LEVEL on the fly and try to open some other section and after that try to exit from kodi and see whats append.... wait the next reply.

DjDiabolik commented 2 months ago

Immediately after the upper screenshot i'm enabled the debul log level and i re-open the youtube addons and i try to open some section. Apparently this time the issue reported here it's not anymore appears.... apparently this time kodi continue to be stable as ever infact from the rest of kodi.log you can see also the EXITING/CLOSIN Kodi procedure it's be completed whitout any issue.

I have copy/paste the rest of kodi.log whit DEBUG LOG LEVEL ENABLED here: https://pastebin.com/JQh4hzai

If there is anything that can be useful I am happy as always to be of service in some way. in this strange the strangeness is that: Why did the thumbnails appear after that video?

DjDiabolik commented 2 months ago

OMG... At right now KODI it's really unusable.... exactly after this last video... immediatelly after END of video kodi goes in LOOP and infinite spinning!! I can't stop and i can't do anythings.... apparently on kodi.log nothings strange but it's not the debug log:

2024-08-19 00:28:13.930 T:94234    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-19 00:28:25.480 T:94270    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /play/
                                                   Params: {'video_id': 'QIIfYahw-zA'}
2024-08-19 00:28:26.304 T:94113    info <general>: VideoPlayer::OpenFile: plugin://plugin.video.youtube/play/?video_id=QIIfYahw-zA
2024-08-19 00:28:26.305 T:94274    info <general>: Creating InputStream
2024-08-19 00:28:26.346 T:94274    info <general>: AddOnLog: inputstream.adaptive: [Repr. chooser] Resolution set: 1920x1080, max allowed: 1920x1080, Adjust refresh rate: 0
2024-08-19 00:28:26.349 T:94274    info <general>: AddOnLog: inputstream.adaptive: Manifest successfully parsed (Periods: 1, Streams in first period: 9, Type: VOD)
2024-08-19 00:28:26.349 T:94274    info <general>: Creating Demuxer
2024-08-19 00:28:26.349 T:94274    info <general>: Opening stream: 1001 source: 256
2024-08-19 00:28:26.411 T:94274    info <general>: Creating video codec with codec id: 167
2024-08-19 00:28:26.411 T:94274    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: Google VP9
2024-08-19 00:28:26.411 T:94274    info <general>: Creating video thread
2024-08-19 00:28:26.411 T:94278    info <general>: running thread: video_thread
2024-08-19 00:28:26.411 T:94274    info <general>: Opening stream: 1009 source: 256
2024-08-19 00:28:26.463 T:94274    info <general>: Finding audio codec for: 86018
2024-08-19 00:28:26.467 T:94274    info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2024-08-19 00:28:26.467 T:94274    info <general>: OpenStream: Allowing max Out-Of-Sync Value of 50 ms
2024-08-19 00:28:26.467 T:94274    info <general>: Creating audio thread
2024-08-19 00:28:26.468 T:94281    info <general>: running thread: CVideoPlayerAudio::Process()
2024-08-19 00:28:26.556 T:94326    info <general>: COutput::OnStartup: Output Thread created
2024-08-19 00:28:26.559 T:94281    info <general>: Creating audio stream (codec id: 86018, channels: 2, sample rate: 44100, no pass-through)
2024-08-19 00:28:26.597 T:94125    info <general>: CActiveAESink::OpenSink - initialize sink
2024-08-19 00:28:26.672 T:94113    info <general>: GL: Selecting YUV 2 RGB shader
2024-08-19 00:28:26.672 T:94113    info <general>: GL: Using GL_ARB_pixel_buffer_object
2024-08-19 00:28:26.672 T:94113    info <general>: Using GL_TEXTURE_2D
2024-08-19 00:28:26.674 T:94113    info <general>: GL: Selecting YUV 2 RGB shader
2024-08-19 00:28:26.674 T:94113    info <general>: GL: Using GL_ARB_pixel_buffer_object
2024-08-19 00:28:26.809 T:94125    info <general>: PulseAudio: Opened device alsa_output.pci-0000_00_0e.0.hdmi-surround in pcm mode with Buffersize 150 ms Periodsize 50 ms
2024-08-19 00:28:26.830 T:94113    info <general>: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
2024-08-19 00:28:27.201 T:94278    info <general>: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 16727
2024-08-19 00:38:28.624 T:94281    info <general>: CVideoPlayerAudio::Process - stream stalled
2024-08-19 00:38:28.769 T:94274    info <general>: Process - eof reading from demuxer
2024-08-19 00:38:28.769 T:94274    info <general>: CVideoPlayer::OnExit()
2024-08-19 00:38:28.769 T:94274    info <general>: VideoPlayer: eof, waiting for queues to empty
2024-08-19 00:38:28.770 T:94274    info <general>: Closing stream player 1
2024-08-19 00:38:28.770 T:94274    info <general>: CDVDMessageQueue(audio)::WaitUntilEmpty
2024-08-19 00:38:28.770 T:94274    info <general>: Waiting for audio thread to exit
2024-08-19 00:38:28.770 T:94281    info <general>: thread end: CVideoPlayerAudio::OnExit()
2024-08-19 00:38:28.770 T:94274    info <general>: Closing audio device
2024-08-19 00:38:28.771 T:94274    info <general>: Deleting audio codec
2024-08-19 00:38:28.771 T:94274    info <general>: Closing stream player 2
2024-08-19 00:38:28.771 T:94274    info <general>: CDVDMessageQueue(video)::WaitUntilEmpty
2024-08-19 00:38:28.882 T:94274    info <general>: waiting for video thread to exit
2024-08-19 00:38:28.882 T:94278    info <general>: thread end: video_thread
2024-08-19 00:38:28.883 T:94274    info <general>: deleting video codec
2024-08-19 00:38:28.887 T:156301    info <general>: Deleting settings information for files plugin://plugin.video.youtube/play/?video_id=QIIfYahw-zA
2024-08-19 00:38:28.889 T:94274    info <general>: ADDON: Dll Destroyed - InputStream Adaptive
2024-08-19 00:38:28.902 T:94113    info <general>: CVideoPlayer::CloseFile()
2024-08-19 00:38:28.902 T:94113    info <general>: VAAPI::Close
2024-08-19 00:38:28.902 T:94326    info <general>: COutput::OnExit: Output Thread terminated
2024-08-19 00:38:28.903 T:94113    info <general>: VAAPI::Close - closing decoder context
2024-08-19 00:38:28.908 T:94113    info <general>: VideoPlayer: waiting for threads to exit
2024-08-19 00:38:28.908 T:94113    info <general>: VideoPlayer: finished waiting
2024-08-19 00:38:28.908 T:94113    info <general>: CVideoPlayer::CloseFile()
2024-08-19 00:38:28.908 T:94113    info <general>: VideoPlayer: waiting for threads to exit
2024-08-19 00:38:28.908 T:94113    info <general>: VideoPlayer: finished waiting
2024-08-19 00:38:28.917 T:94113   error <general>: Control 55 in window 10025 has been asked to focus, but it can't
2024-08-19 00:38:29.769 T:156306    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-19 00:45:01.302 T:94125    info <general>: CActiveAESink::OpenSink - initialize sink
2024-08-19 00:45:01.346 T:94125    info <general>: PulseAudio: Opened device alsa_output.pci-0000_00_0e.0.hdmi-surround in pcm mode with Buffersize 150 ms Periodsize 50 ms

I have tryed to press ESC on keyboard.. or other keys... nothing append i can only force terminate it by linux terminal. At right now kodi and problably youtube addons it's pratically unusable

DjDiabolik commented 2 months ago

OMG... At right now KODI it's really unusable.... exactly after this last video... immediatelly after END of video kodi goes in LOOP and infinite spinning!! I can't stop and i can't do anythings.... apparently on kodi.log nothings strange but it's not the debug log:

2024-08-19 00:28:13.930 T:94234    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-19 00:28:25.480 T:94270    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /play/
                                                   Params: {'video_id': 'QIIfYahw-zA'}
2024-08-19 00:28:26.304 T:94113    info <general>: VideoPlayer::OpenFile: plugin://plugin.video.youtube/play/?video_id=QIIfYahw-zA
2024-08-19 00:28:26.305 T:94274    info <general>: Creating InputStream
2024-08-19 00:28:26.346 T:94274    info <general>: AddOnLog: inputstream.adaptive: [Repr. chooser] Resolution set: 1920x1080, max allowed: 1920x1080, Adjust refresh rate: 0
2024-08-19 00:28:26.349 T:94274    info <general>: AddOnLog: inputstream.adaptive: Manifest successfully parsed (Periods: 1, Streams in first period: 9, Type: VOD)
2024-08-19 00:28:26.349 T:94274    info <general>: Creating Demuxer
2024-08-19 00:28:26.349 T:94274    info <general>: Opening stream: 1001 source: 256
2024-08-19 00:28:26.411 T:94274    info <general>: Creating video codec with codec id: 167
2024-08-19 00:28:26.411 T:94274    info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: Google VP9
2024-08-19 00:28:26.411 T:94274    info <general>: Creating video thread
2024-08-19 00:28:26.411 T:94278    info <general>: running thread: video_thread
2024-08-19 00:28:26.411 T:94274    info <general>: Opening stream: 1009 source: 256
2024-08-19 00:28:26.463 T:94274    info <general>: Finding audio codec for: 86018
2024-08-19 00:28:26.467 T:94274    info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2024-08-19 00:28:26.467 T:94274    info <general>: OpenStream: Allowing max Out-Of-Sync Value of 50 ms
2024-08-19 00:28:26.467 T:94274    info <general>: Creating audio thread
2024-08-19 00:28:26.468 T:94281    info <general>: running thread: CVideoPlayerAudio::Process()
2024-08-19 00:28:26.556 T:94326    info <general>: COutput::OnStartup: Output Thread created
2024-08-19 00:28:26.559 T:94281    info <general>: Creating audio stream (codec id: 86018, channels: 2, sample rate: 44100, no pass-through)
2024-08-19 00:28:26.597 T:94125    info <general>: CActiveAESink::OpenSink - initialize sink
2024-08-19 00:28:26.672 T:94113    info <general>: GL: Selecting YUV 2 RGB shader
2024-08-19 00:28:26.672 T:94113    info <general>: GL: Using GL_ARB_pixel_buffer_object
2024-08-19 00:28:26.672 T:94113    info <general>: Using GL_TEXTURE_2D
2024-08-19 00:28:26.674 T:94113    info <general>: GL: Selecting YUV 2 RGB shader
2024-08-19 00:28:26.674 T:94113    info <general>: GL: Using GL_ARB_pixel_buffer_object
2024-08-19 00:28:26.809 T:94125    info <general>: PulseAudio: Opened device alsa_output.pci-0000_00_0e.0.hdmi-surround in pcm mode with Buffersize 150 ms Periodsize 50 ms
2024-08-19 00:28:26.830 T:94113    info <general>: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
2024-08-19 00:28:27.201 T:94278    info <general>: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 16727
2024-08-19 00:38:28.624 T:94281    info <general>: CVideoPlayerAudio::Process - stream stalled
2024-08-19 00:38:28.769 T:94274    info <general>: Process - eof reading from demuxer
2024-08-19 00:38:28.769 T:94274    info <general>: CVideoPlayer::OnExit()
2024-08-19 00:38:28.769 T:94274    info <general>: VideoPlayer: eof, waiting for queues to empty
2024-08-19 00:38:28.770 T:94274    info <general>: Closing stream player 1
2024-08-19 00:38:28.770 T:94274    info <general>: CDVDMessageQueue(audio)::WaitUntilEmpty
2024-08-19 00:38:28.770 T:94274    info <general>: Waiting for audio thread to exit
2024-08-19 00:38:28.770 T:94281    info <general>: thread end: CVideoPlayerAudio::OnExit()
2024-08-19 00:38:28.770 T:94274    info <general>: Closing audio device
2024-08-19 00:38:28.771 T:94274    info <general>: Deleting audio codec
2024-08-19 00:38:28.771 T:94274    info <general>: Closing stream player 2
2024-08-19 00:38:28.771 T:94274    info <general>: CDVDMessageQueue(video)::WaitUntilEmpty
2024-08-19 00:38:28.882 T:94274    info <general>: waiting for video thread to exit
2024-08-19 00:38:28.882 T:94278    info <general>: thread end: video_thread
2024-08-19 00:38:28.883 T:94274    info <general>: deleting video codec
2024-08-19 00:38:28.887 T:156301    info <general>: Deleting settings information for files plugin://plugin.video.youtube/play/?video_id=QIIfYahw-zA
2024-08-19 00:38:28.889 T:94274    info <general>: ADDON: Dll Destroyed - InputStream Adaptive
2024-08-19 00:38:28.902 T:94113    info <general>: CVideoPlayer::CloseFile()
2024-08-19 00:38:28.902 T:94113    info <general>: VAAPI::Close
2024-08-19 00:38:28.902 T:94326    info <general>: COutput::OnExit: Output Thread terminated
2024-08-19 00:38:28.903 T:94113    info <general>: VAAPI::Close - closing decoder context
2024-08-19 00:38:28.908 T:94113    info <general>: VideoPlayer: waiting for threads to exit
2024-08-19 00:38:28.908 T:94113    info <general>: VideoPlayer: finished waiting
2024-08-19 00:38:28.908 T:94113    info <general>: CVideoPlayer::CloseFile()
2024-08-19 00:38:28.908 T:94113    info <general>: VideoPlayer: waiting for threads to exit
2024-08-19 00:38:28.908 T:94113    info <general>: VideoPlayer: finished waiting
2024-08-19 00:38:28.917 T:94113   error <general>: Control 55 in window 10025 has been asked to focus, but it can't
2024-08-19 00:38:29.769 T:156306    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-19 00:45:01.302 T:94125    info <general>: CActiveAESink::OpenSink - initialize sink
2024-08-19 00:45:01.346 T:94125    info <general>: PulseAudio: Opened device alsa_output.pci-0000_00_0e.0.hdmi-surround in pcm mode with Buffersize 150 ms Periodsize 50 ms

I have tryed to press ESC on keyboard.. or other keys... nothing append i can only force terminate it by linux terminal. At right now kodi and problably youtube addons it's pratically unusable...

DjDiabolik commented 2 months ago

kodi.log

I think it's not useful at all.. i have tryed to: -Enable debug log -Restart Kodi -And try to reproduce one youtube video from "My Subscription" since as I was saying YouTube was practically unusable at that time.

When you need something to go wrong... lol all goes good and this time no issue at all in the meantime i can upload this complete debug log after i can also exit from kodi, and back to ubuntu dekstop, whitout any issue.

I'm more than sure that now I close the browser and reopen kodi and the problems return. If this really happens my @@ can start to roll I might definitely smash something tonight like throw the mini PC out the window...lol.

DjDiabolik commented 2 months ago

My friends, do you know what worries me? That the last time Kodi started giving me problems, even then I feared it was the addons' fault, but in the end I ended up "trashing" my old and glorious raspberry pi2 to move to new hardware (this current my Mini PC whit Ubuntu 22.04.4 LTS). Only the future will tell me more. For now i hope the previous portion and full kodi.log you come in handy to extract some information and maybe discover some bugs that can create problems with more intense uses.

For example, I just finished watching almost 50 minutes of content divided into almost 5 videos and everything went smoothly. Then maybe tomorrow I reopen Kodi and the problems appear after two videos.

MoojMidge commented 2 months ago

kodi.log

I think it's not useful at all.. i have tryed to: -Enable debug log -Restart Kodi -And try to reproduce one youtube video from "My Subscription" since as I was saying YouTube was practically unusable at that time.

It is actually very useful, because it shows the plugin settings that you are using, which is why I asked for this in the first place, even if the actual problem was not captured in the log.

The issue you have identified appears to be the same issue as #882, except for you it only occurs sometimes, after playback ends. The debug log indicates you have the following setting enabled:

Settings > Advanced > Mark as watched > Refresh after watching

Can you disable this and see if it improves things?

I'm going to close this issue in favour of the other one because the person who reported that can replicate the problem reliably, while for you it seems sporadic.

Please follow along in that other issue as I may need your help to investigate further or verify if any of the suggestions effect anything.

DjDiabolik commented 2 months ago

@MoojMidge ok.. right now I will try to disable that feature you indicate... I think that feature was active by default perhaps in some older version of the addon and I carried it with me active over time. I think it won't be a problem from time to time but I will have to launch some manual re-updates after watching some videos :)

DjDiabolik commented 2 months ago

https://github.com/anxdpanic/plugin.video.youtube/issues/882#issuecomment-2295930240 Do you think that reducing this timeout could also be useful for me? @MoojMidge

MoojMidge commented 2 months ago

right now I will try to disable that feature you indicate...

Has the problem occurred again after disabling this?

Do you think that reducing this timeout could also be useful for me?

Unlikely, but doesn't hurt to try.

DjDiabolik commented 2 months ago

@MoojMidge i have some news..... These days I have been using YouTube with the option "refresh after watched" disabled and I must say that the situation and the frequency of the problem's occurrence has reduced a lot.

Until this later afternoon before i went to dinner: I was watching a past "live stream" dated 18 Agosto and I added it to the playlist "Watch Later" After watching, the addon should have removed the video from the playlist and it did so correctly. Then the error appeared telling me to check the kodi.log....at that point kodi exited and returned to its home without me touching anything.

I have re-open the youtube addons and reopen the "My Subscription" section and baaaam.. surprise surprise. Instead the "watched/not watched" status icon the thumbnails to the left of the titles have reappeared.

At this point i have open my Kodi.Log and i can post the very last portion exactly after the "live stream" it's terminated.

2024-08-22 19:06:55.921 T:7        info <general>: VAAPI::Close
2024-08-22 19:06:55.921 T:144024    info <general>: COutput::OnExit: Output Thread terminated
2024-08-22 19:06:55.921 T:7        info <general>: VAAPI::Close - closing decoder context
2024-08-22 19:06:55.924 T:7        info <general>: VideoPlayer: waiting for threads to exit
2024-08-22 19:06:55.924 T:7        info <general>: VideoPlayer: finished waiting
2024-08-22 19:06:55.924 T:7        info <general>: CVideoPlayer::CloseFile()
2024-08-22 19:06:55.924 T:7        info <general>: VideoPlayer: waiting for threads to exit
2024-08-22 19:06:55.924 T:7        info <general>: VideoPlayer: finished waiting
2024-08-22 19:06:55.933 T:7       error <general>: Control 55 in window 10025 has been asked to focus, but it can't
2024-08-22 19:06:56.656 T:399100    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /channel/mine/playlist/PLD43ECEF06DF03B5C/
                                                   Params: {'category_label': 'Guarda più tardi'}
2024-08-22 19:06:57.798 T:144661 warning <general>: [plugin.video.youtube] v3 response: Items list is empty
2024-08-22 19:07:02.055 T:7        info <general>: Activate of window '10025' refused because there are active modal dialogs
2024-08-22 19:07:02.918 T:399100 warning <general>: [plugin.video.youtube] v3 response: Items list is empty
2024-08-22 19:07:16.372 T:399105    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /home
                                                   Params: {}
2024-08-22 19:07:18.779 T:399107   error <general>: Unable to find plugin 
2024-08-22 19:07:18.779 T:399107   error <general>: GetDirectory - Error getting plugin://
2024-08-22 19:07:18.780 T:7       error <general>: CGUIMediaWindow::GetDirectory(plugin://) failed
2024-08-22 19:07:20.344 T:399113    info <general>: set Property 'videolibrary.showunwatchedplots' to [0, 1, 2]
2024-08-22 19:07:21.195 T:7       error <general>: Control 55 in window 10025 has been asked to focus, but it can't
2024-08-22 19:07:21.969 T:399112    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /
                                                   Params: {}
2024-08-22 19:07:23.906 T:399122    info <general>: Skipped 3 duplicate messages..
2024-08-22 19:07:23.906 T:399122    info <general>: [plugin.video.youtube] Running: YouTube (7.0.9) on Omega (Kodi-21.1) with Python 3.11.9
                                                   Path: /special/my_subscriptions_filtered/
                                                   Params: {'category_label': 'Le mie iscrizioni (filtrate)'}
2024-08-22 19:07:27.591 T:399122   error <general>: [plugin.video.youtube] Python strptime bug workaround - https://github.com/python/cpython/issues/71587
2024-08-22 19:07:27.845 T:399114   error <general>: [plugin.video.youtube] SQLStorage._execute - database is locked:
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/plugin.py", line 16, in <module>
                                                       plugin_runner.run()
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/kodion/plugin_runner.py", line 63, in run
                                                       plugin.run(provider, context, focused=(current_uri == new_uri))
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/kodion/plugin/xbmc/xbmc_plugin.py", line 159, in run
                                                       result, options = provider.navigate(context)
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/kodion/abstract_provider.py", line 158, in navigate
                                                       result = handler(provider=self, context=context, re_match=re_match)
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/youtube/provider.py", line 1234, in on_root
                                                       playlists = resource_manager.get_related_playlists('mine')
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/youtube/helper/resource_manager.py", line 267, in get_related_playlists
                                                       result = self.get_channels((channel_id,), defer_cache=defer_cache)
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/youtube/helper/resource_manager.py", line 67, in get_channels
                                                       result = data_cache.get_items(ids, data_cache.ONE_MONTH)
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/kodion/sql_store/data_cache.py", line 32, in get_items
                                                       result = self._get_by_ids(content_ids,
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/kodion/sql_store/storage.py", line 483, in _get_by_ids
                                                       with self as (db, cursor), db:
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/kodion/sql_store/storage.py", line 214, in __enter__
                                                       self._open()
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/kodion/sql_store/storage.py", line 267, in _open
                                                       for result in self._execute(cursor, self._sql['has_old_table']):
                                                     File "/home/diabolik/.var/app/tv.kodi.Kodi/data/addons/plugin.video.youtube/resources/lib/youtube_plugin/kodion/sql_store/storage.py", line 316, in _execute
                                                       exc=exc, details=''.join(format_stack())

2024-08-22 20:24:39.250 T:18    warning <general>: Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail
2024-08-22 20:24:39.251 T:18       info <general>: Found 1 Lists of Devices
2024-08-22 20:24:39.251 T:18       info <general>: Enumerated PULSE devices:
2024-08-22 20:24:39.251 T:18       info <general>:     Device 1
2024-08-22 20:24:39.251 T:18       info <general>:         m_deviceName      : Default
2024-08-22 20:24:39.251 T:18       info <general>:         m_displayName     : Default
2024-08-22 20:24:39.251 T:18       info <general>:         m_displayNameExtra: Default Output Device (PULSEAUDIO)
2024-08-22 20:24:39.251 T:18       info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-08-22 20:24:39.251 T:18       info <general>:         m_channels        : FL, FR
2024-08-22 20:24:39.251 T:18       info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-08-22 20:24:39.251 T:18       info <general>:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2024-08-22 20:24:39.251 T:18       info <general>:         m_streamTypes     : No passthrough capabilities
2024-08-22 20:24:39.251 T:18       info <general>:     Device 2
2024-08-22 20:24:39.251 T:18       info <general>:         m_deviceName      : auto_null
2024-08-22 20:24:39.251 T:18       info <general>:         m_displayName     : Uscita dummy
2024-08-22 20:24:39.251 T:18       info <general>:         m_displayNameExtra: Uscita dummy (PULSEAUDIO)
2024-08-22 20:24:39.251 T:18       info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-08-22 20:24:39.251 T:18       info <general>:         m_channels        : FL, FR
2024-08-22 20:24:39.251 T:18       info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-08-22 20:24:39.251 T:18       info <general>:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2024-08-22 20:24:39.251 T:18       info <general>:         m_streamTypes     : No passthrough capabilities
2024-08-22 20:24:39.251 T:18    warning <general>: ValidateOutputDevices: audio output device setting has been updated from 'PULSE:alsa_output.pci-0000_00_0e.0.hdmi-surround|Audio interno Digital Surround 5.1 (HDMI)' to 'PULSE:Default|Default Output Device (PULSEAUDIO)'
2024-08-22 20:24:39.251 T:19       info <general>: CActiveAESink::OpenSink - initialize sink
2024-08-22 20:24:39.252 T:19       info <general>: PulseAudio: Opened device Default in pcm mode with Buffersize 300 ms Periodsize 100 ms
2024-08-22 20:24:39.254 T:18    warning <general>: Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail
2024-08-22 20:24:39.254 T:18       info <general>: Found 1 Lists of Devices
2024-08-22 20:24:39.254 T:18       info <general>: Enumerated PULSE devices:
2024-08-22 20:24:39.254 T:18       info <general>:     Device 1
2024-08-22 20:24:39.254 T:18       info <general>:         m_deviceName      : Default
2024-08-22 20:24:39.254 T:18       info <general>:         m_displayName     : Default
2024-08-22 20:24:39.254 T:18       info <general>:         m_displayNameExtra: Default Output Device (PULSEAUDIO)
2024-08-22 20:24:39.254 T:18       info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-08-22 20:24:39.254 T:18       info <general>:         m_channels        : FL, FR
2024-08-22 20:24:39.254 T:18       info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-08-22 20:24:39.254 T:18       info <general>:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2024-08-22 20:24:39.254 T:18       info <general>:         m_streamTypes     : No passthrough capabilities
2024-08-22 20:24:39.254 T:18       info <general>:     Device 2
2024-08-22 20:24:39.254 T:18       info <general>:         m_deviceName      : auto_null
2024-08-22 20:24:39.254 T:18       info <general>:         m_displayName     : Uscita dummy
2024-08-22 20:24:39.255 T:18       info <general>:         m_displayNameExtra: Uscita dummy (PULSEAUDIO)
2024-08-22 20:24:39.255 T:18       info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-08-22 20:24:39.255 T:18       info <general>:         m_channels        : FL, FR
2024-08-22 20:24:39.255 T:18       info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-08-22 20:24:39.255 T:18       info <general>:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2024-08-22 20:24:39.255 T:18       info <general>:         m_streamTypes     : No passthrough capabilities
2024-08-22 20:24:39.567 T:19      error <general>: Sink Timer expired for more than buffer time: 0.3s
2024-08-22 20:47:33.940 T:7        info <general>: Skipped 2 duplicate messages..
2024-08-22 20:47:33.940 T:7        info <general>: UpdateResolutions: User wanted output "GSM LG TV", we now have "GSM LG TV" size 1600x900 mm with 1 mode(s):
2024-08-22 20:47:33.940 T:7        info <general>: UpdateResolutions: - 1920x1080 @60.000 Hz pixel ratio 1.000 current
2024-08-22 20:47:34.032 T:18    warning <general>: Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail
2024-08-22 20:47:34.032 T:18       info <general>: Found 1 Lists of Devices
2024-08-22 20:47:34.032 T:18       info <general>: Enumerated PULSE devices:
2024-08-22 20:47:34.032 T:18       info <general>:     Device 1
2024-08-22 20:47:34.032 T:18       info <general>:         m_deviceName      : Default
2024-08-22 20:47:34.032 T:18       info <general>:         m_displayName     : Default
2024-08-22 20:47:34.032 T:18       info <general>:         m_displayNameExtra: Default Output Device (PULSEAUDIO)
2024-08-22 20:47:34.032 T:18       info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-08-22 20:47:34.032 T:18       info <general>:         m_channels        : FL, FR
2024-08-22 20:47:34.032 T:18       info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-08-22 20:47:34.032 T:18       info <general>:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2024-08-22 20:47:34.032 T:18       info <general>:         m_streamTypes     : No passthrough capabilities
2024-08-22 20:47:34.032 T:18       info <general>:     Device 2
2024-08-22 20:47:34.032 T:18       info <general>:         m_deviceName      : alsa_output.pci-0000_00_0e.0.hdmi-surround
2024-08-22 20:47:34.032 T:18       info <general>:         m_displayName     : Audio interno Digital Surround 5.1 (HDMI)
2024-08-22 20:47:34.032 T:18       info <general>:         m_displayNameExtra: HDMI / DisplayPort (PULSEAUDIO)
2024-08-22 20:47:34.032 T:18       info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-08-22 20:47:34.032 T:18       info <general>:         m_channels        : FL, FR, BL, BR, FC, LFE
2024-08-22 20:47:34.032 T:18       info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-08-22 20:47:34.032 T:18       info <general>:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2024-08-22 20:47:34.032 T:18       info <general>:         m_streamTypes     : No passthrough capabilities
2024-08-22 20:47:34.033 T:18    warning <general>: Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail
2024-08-22 20:47:34.034 T:18       info <general>: Found 1 Lists of Devices
2024-08-22 20:47:34.034 T:18       info <general>: Enumerated PULSE devices:
2024-08-22 20:47:34.034 T:18       info <general>:     Device 1
2024-08-22 20:47:34.034 T:18       info <general>:         m_deviceName      : Default
2024-08-22 20:47:34.034 T:18       info <general>:         m_displayName     : Default
2024-08-22 20:47:34.034 T:18       info <general>:         m_displayNameExtra: Default Output Device (PULSEAUDIO)
2024-08-22 20:47:34.034 T:18       info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-08-22 20:47:34.034 T:18       info <general>:         m_channels        : FL, FR
2024-08-22 20:47:34.034 T:18       info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-08-22 20:47:34.034 T:18       info <general>:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2024-08-22 20:47:34.034 T:18       info <general>:         m_streamTypes     : No passthrough capabilities
2024-08-22 20:47:34.034 T:18       info <general>:     Device 2
2024-08-22 20:47:34.034 T:18       info <general>:         m_deviceName      : alsa_output.pci-0000_00_0e.0.hdmi-surround
2024-08-22 20:47:34.034 T:18       info <general>:         m_displayName     : Audio interno Digital Surround 5.1 (HDMI)
2024-08-22 20:47:34.034 T:18       info <general>:         m_displayNameExtra: HDMI / DisplayPort (PULSEAUDIO)
2024-08-22 20:47:34.034 T:18       info <general>:         m_deviceType      : AE_DEVTYPE_PCM
2024-08-22 20:47:34.034 T:18       info <general>:         m_channels        : FL, FR, BL, BR, FC, LFE
2024-08-22 20:47:34.034 T:18       info <general>:         m_sampleRates     : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000
2024-08-22 20:47:34.034 T:18       info <general>:         m_dataFormats     : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT
2024-08-22 20:47:34.034 T:18       info <general>:         m_streamTypes     : No passthrough capabilities

There's present all errors related the youtube addons... then a few lines related to what? It seems like my Skin (Estuary Mod v2 on Kodinerds repo) did a forced refresh. Even on that occasion.. I tried to exit and close Kodi but the process never finished and, for back to ubuntu desktop, i have to kill the kodi process by terminal commands.

Could it be that the feature that removes videos from the "Watch Later" playlist after viewing will somehow trigger the same problem? To be a little ironic I could say : let posterity decide.. in italian we says "Ai posteri l'ardua sentenza" :)

DjDiabolik commented 2 months ago

Unfortunately it is not a debug log portion. I will try to add a new video to "Watch Later" and see what happens after watching. If Kodi still fails I will repeat the thing with the debug log active.

MoojMidge commented 2 months ago

That's OK debug log is not necessary at this point.

A database lock is what I suspected the issue may be in the first place, I just can't replicate it, or figure out why the lock is not being released.

DjDiabolik commented 2 months ago

That's OK debug log is not necessary at this point.

A database lock is what I suspected the issue may be in the first place, I just can't replicate it, or figure out why the lock is not being released.

I hypothesize one thing: Do the post-end/video-viewing routines fail to reload the previous playlist?

Now i'm also disable "Remove from "Watch later" after watching" it is then possible to do it manually from the context menu.... perhaps by "streamlining" that phase as much as possible, you can avoid the most common problems.

Disabling "Refresh after watching" was a godsend, however, absurdly... lol

MoojMidge commented 2 months ago

Give this test version a go: https://github.com/MoojMidge/plugin.video.youtube/archive/refs/heads/master.zip

Please report any feedback in #882 so I can keep the discussion in one place.