Addon provoques infinite "loading media informations from files"

[christophehenry]

Summary

After Kodi has starded, it seems to try to update music informations from files, even though the plugin is configured in extension mode and not native (files path) mode. This reproduce with a music library. I don"t know about films.

Steps to Reproduce

1. Install the addon
2. Connect to the server
3. Update library
4. Restart kodi
5. Kodi should display a "loading media informations from files" loader stuck at 0 on the top-lright corner of the screen

Operating System

OSMC 5.15.9

Jellyfin Server Version

10.10.7

Kodi Version

21.2

Addon Mode

Addon-mode

Logs

2025-05-07 18:48:08.012 T:736     error <general>: CCurlFile::Open - <https://<server>/Audio/00710a2cd733d465fbf11b5de59b979f/> Failed with code 404:
2025-05-07 18:48:08.013 T:736     error <general>: GetDirectory - Unable to get http directory (https://<server>/Audio/00710a2cd733d465fbf11b5de59b979f/)
2025-05-07 18:48:08.013 T:736     error <general>: GetDirectory - Error getting https://<server>/Audio/00710a2cd733d465fbf11b5de59b979f/
2025-05-07 18:48:08.016 T:730   warning <general>: Process directory 'https://<server>/Audio/00210a9b146f5808ee122d2a423752a5/' does not exist - skipping scan.

(This continues like this for 50k+ lines)

Server Logs

Click to see server logs

[2025-05-07 00:01:00.000 +00:00] [INF] [112] Jellyfin.Plugin.KodiSyncQueue.ScheduledTasks.RetentionTask: Retention deletion not possible if retention days is set to zero!
[2025-05-07 00:01:00.000 +00:00] [INF] [112] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Remove Old Sync Data" Completed after 0 minute(s) and 0 seconds
[2025-05-07 00:01:01.000 +00:00] [INF] [112] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for "Remove Old Sync Data" set to fire at 2025-05-08 00:01:00.000 +00:00, which is 23:59:58.9996016 from now.
[2025-05-07 02:00:00.034 +00:00] [INF] [89] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Extraire les images de chapitre" Completed after 0 minute(s) and 0 seconds
[2025-05-07 02:00:01.000 +00:00] [INF] [145] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for "Extraire les images de chapitre" set to fire at 2025-05-08 02:00:00.000 +00:00, which is 23:59:58.9997010 from now.
[2025-05-07 03:00:00.043 +00:00] [INF] [104] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Générer des images Trickplay" Completed after 0 minute(s) and 0 seconds
[2025-05-07 03:00:01.000 +00:00] [INF] [104] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for "Générer des images Trickplay" set to fire at 2025-05-08 03:00:00.000 +00:00, which is 23:59:58.9992558 from now.
[2025-05-07 09:38:01.561 +00:00] [INF] [143] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 09:38:02.781 +00:00] [INF] [146] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Plugin Settings Requested...
[2025-05-07 09:38:02.889 +00:00] [INF] [146] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Sync Requested for UserID: '"<user_id>"' with LastUpdateDT: '"2025-04-18T08:58:02Z"'
[2025-05-07 09:38:03.022 +00:00] [INF] [146] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Added: 0, Removed: 0, Updated: 3, Changed User Data: 107
[2025-05-07 09:38:03.022 +00:00] [INF] [146] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Request Finished Taking "00:00:00.1192911"
[2025-05-07 09:38:30.686 +00:00] [INF] [118] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 09:39:19.189 +00:00] [INF] [158] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 09:39:20.333 +00:00] [INF] [153] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Plugin Settings Requested...
[2025-05-07 09:39:20.426 +00:00] [INF] [153] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Sync Requested for UserID: '"<user_id>"' with LastUpdateDT: '"2025-05-07T09:36:05Z"'
[2025-05-07 09:39:20.472 +00:00] [INF] [153] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Added: 0, Removed: 0, Updated: 0, Changed User Data: 0
[2025-05-07 09:39:20.472 +00:00] [INF] [153] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Request Finished Taking "00:00:00.0459742"
[2025-05-07 09:40:03.328 +00:00] [INF] [158] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 09:42:02.994 +00:00] [INF] [141] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 09:42:04.282 +00:00] [INF] [141] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Plugin Settings Requested...
[2025-05-07 09:42:04.367 +00:00] [INF] [141] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Sync Requested for UserID: '"<user_id>"' with LastUpdateDT: '"2025-05-07T09:36:05Z"'
[2025-05-07 09:42:04.407 +00:00] [INF] [141] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Added: 0, Removed: 0, Updated: 0, Changed User Data: 0
[2025-05-07 09:42:04.407 +00:00] [INF] [141] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Request Finished Taking "00:00:00.0402507"
[2025-05-07 09:42:51.053 +00:00] [INF] [141] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2025-05-07 09:43:03.053 +00:00] [INF] [141] Emby.Server.Implementations.Session.SessionWebSocketListener: Lost 1 WebSockets.
[2025-05-07 09:43:10.877 +00:00] [INF] [141] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 09:44:00.615 +00:00] [INF] [154] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 09:44:01.781 +00:00] [INF] [36] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Plugin Settings Requested...
[2025-05-07 09:44:01.868 +00:00] [INF] [36] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Sync Requested for UserID: '"<user_id>"' with LastUpdateDT: '"2025-05-07T09:36:05Z"'
[2025-05-07 09:44:01.907 +00:00] [INF] [36] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Added: 0, Removed: 0, Updated: 0, Changed User Data: 0
[2025-05-07 09:44:01.907 +00:00] [INF] [36] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Request Finished Taking "00:00:00.0385718"
[2025-05-07 09:44:48.685 +00:00] [INF] [36] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2025-05-07 09:45:00.684 +00:00] [INF] [154] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2025-05-07 09:45:12.684 +00:00] [INF] [137] Emby.Server.Implementations.Session.SessionWebSocketListener: Lost 1 WebSockets.
[2025-05-07 09:49:15.783 +00:00] [INF] [11] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 09:50:29.440 +00:00] [INF] [8] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 09:50:30.621 +00:00] [INF] [147] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Plugin Settings Requested...
[2025-05-07 09:50:30.734 +00:00] [INF] [99] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Sync Requested for UserID: '"<user_id>"' with LastUpdateDT: '"2025-05-07T09:36:05Z"'
[2025-05-07 09:50:30.776 +00:00] [INF] [99] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Added: 0, Removed: 0, Updated: 0, Changed User Data: 0
[2025-05-07 09:50:30.776 +00:00] [INF] [99] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Request Finished Taking "00:00:00.0413508"
[2025-05-07 09:51:17.516 +00:00] [INF] [99] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2025-05-07 09:51:29.516 +00:00] [INF] [147] Emby.Server.Implementations.Session.SessionWebSocketListener: Lost 1 WebSockets.
[2025-05-07 10:26:09.734 +00:00] [WRN] [29] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "127.0.0.1" error receiving data: "The remote party closed the WebSocket connection without completing the close handshake."
[2025-05-07 10:26:09.795 +00:00] [INF] [29] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 10:35:01.931 +00:00] [INF] [67] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Télécharger les sous-titres manquants" Completed after 0 minute(s) and 0 seconds
[2025-05-07 10:35:02.002 +00:00] [INF] [67] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Nettoyer le répertoire des journaux" Completed after 0 minute(s) and 0 seconds
[2025-05-07 10:35:02.002 +00:00] [INF] [22] Emby.Server.Implementations.ScheduledTasks.TaskManager: "TasksRefreshChannels" Completed after 0 minute(s) and 0 seconds
[2025-05-07 10:35:02.100 +00:00] [INF] [123] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Vider le répertoire cache" Completed after 0 minute(s) and 0 seconds
[2025-05-07 10:35:02.131 +00:00] [INF] [123] Emby.Server.Implementations.ScheduledTasks.Tasks.OptimizeDatabaseTask: Optimizing and vacuuming jellyfin.db...
[2025-05-07 10:35:02.192 +00:00] [INF] [101] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Nettoyer le dossier des transcodages" Completed after 0 minute(s) and 0 seconds
[2025-05-07 10:35:02.269 +00:00] [INF] [123] Emby.Server.Implementations.ScheduledTasks.Tasks.OptimizeDatabaseTask: jellyfin.db optimized successfully!
[2025-05-07 10:35:02.270 +00:00] [INF] [123] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Optimiser la base de données" Completed after 0 minute(s) and 0 seconds
[2025-05-07 10:35:02.950 +00:00] [INF] [101] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Mettre à jour les extensions" Completed after 0 minute(s) and 0 seconds
[2025-05-07 10:35:05.132 +00:00] [INF] [123] Jellyfin.LiveTv.Guide.GuideManager: Refreshing guide with 7 days of guide data
[2025-05-07 10:35:05.133 +00:00] [INF] [123] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Refresh Guide" Completed after 0 minute(s) and 3 seconds
[2025-05-07 10:35:11.256 +00:00] [INF] [65] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Télécharger les paroles des chansons manquantes" Completed after 0 minute(s) and 9 seconds
[2025-05-07 10:35:14.010 +00:00] [INF] [74] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Normalisation audio" Completed after 0 minute(s) and 12 seconds
[2025-05-07 10:38:04.975 +00:00] [INF] [16] Emby.Server.Implementations.IO.LibraryMonitor: Stopping directory watching for path "/var/data/jellyfin"
[2025-05-07 10:38:04.979 +00:00] [INF] [16] Emby.Server.Implementations.Library.LibraryManager: Validating media library
[2025-05-07 10:38:04.988 +00:00] [WRN] [16] MediaBrowser.Controller.Entities.BaseItem: Library folder "/var/lib/jellyfin/data/playlists" is inaccessible or empty, skipping
[2025-05-07 10:38:04.998 +00:00] [WRN] [16] MediaBrowser.Controller.Entities.BaseItem: Library folder "/var/lib/jellyfin/data/playlists" is inaccessible or empty, skipping
[2025-05-07 10:38:07.857 +00:00] [INF] [39] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Analyse des segments de média" Completed after 0 minute(s) and 2 seconds
[2025-05-07 10:38:12.165 +00:00] [INF] [105] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Analyser la médiathèque" Completed after 0 minute(s) and 7 seconds
[2025-05-07 10:38:12.340 +00:00] [INF] [38] Emby.Server.Implementations.IO.LibraryMonitor: Watching directory "/var/data/jellyfin"
[2025-05-07 10:54:14.986 +00:00] [INF] [91] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 10:54:16.084 +00:00] [INF] [94] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Plugin Settings Requested...
[2025-05-07 10:54:16.174 +00:00] [INF] [94] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Sync Requested for UserID: '"<user_id>"' with LastUpdateDT: '"2025-05-07T09:36:05Z"'
[2025-05-07 10:54:16.220 +00:00] [INF] [94] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Added: 0, Removed: 0, Updated: 0, Changed User Data: 0
[2025-05-07 10:54:16.220 +00:00] [INF] [94] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Request Finished Taking "00:00:00.0462279"
[2025-05-07 10:55:03.055 +00:00] [INF] [94] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2025-05-07 10:55:15.056 +00:00] [INF] [94] Emby.Server.Implementations.Session.SessionWebSocketListener: Lost 1 WebSockets.
[2025-05-07 10:57:28.097 +00:00] [INF] [132] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Silicone Soul/A soul thing/09-Silicon Soul-I need an angel.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"95c481c2b3a3cbc15d92f5470ec598f8-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/95c481c2b3a3cbc15d92f5470ec598f8%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/95c481c2b3a3cbc15d92f5470ec598f8.m3u8\""
[2025-05-07 10:57:33.455 +00:00] [INF] [132] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 10:57:33.456 +00:00] [INF] [132] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 2 updates
[2025-05-07 10:57:33.456 +00:00] [INF] [132] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0018696"
[2025-05-07 10:57:48.467 +00:00] [INF] [161] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 10:57:48.659 +00:00] [INF] [161] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Télépopmusik/Angel milk/Télépopmusik-15 minutes.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"4f5d8480bd805cf7b686ddcdcca61748-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/4f5d8480bd805cf7b686ddcdcca61748%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/4f5d8480bd805cf7b686ddcdcca61748.m3u8\""
[2025-05-07 10:57:53.258 +00:00] [INF] [76] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 10:57:53.493 +00:00] [INF] [139] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Fritz Kalkbrenner/One of these days maxi/04-Fritz Kalkbrenner-One of these days (LCAW remix).flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"1763004b9eba97e050a67be470e9238d-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/1763004b9eba97e050a67be470e9238d%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/1763004b9eba97e050a67be470e9238d.m3u8\""
[2025-05-07 10:58:08.760 +00:00] [INF] [132] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:00:41.542 +00:00] [INF] [41] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "I need an angel". Stopped at "8" ms
[2025-05-07 11:00:42.128 +00:00] [INF] [161] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:00:42.155 +00:00] [INF] [161] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:00:42.155 +00:00] [INF] [161] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0268891"
[2025-05-07 11:00:42.955 +00:00] [INF] [161] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "15 minutes". Stopped at "16" ms
[2025-05-07 11:00:43.537 +00:00] [INF] [73] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:00:43.565 +00:00] [INF] [73] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:00:43.565 +00:00] [INF] [73] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0277670"
[2025-05-07 11:00:47.570 +00:00] [INF] [73] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Yuksek/Away from the sea/11-Yuksek-So down.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"99e7e3cdfc6476f3f0c91baa37dc28fa-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/99e7e3cdfc6476f3f0c91baa37dc28fa%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/99e7e3cdfc6476f3f0c91baa37dc28fa.m3u8\""
[2025-05-07 11:00:56.774 +00:00] [INF] [94] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:00:57.222 +00:00] [INF] [161] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Copyright/Voices & visions/13-Copyright & Song Williamson-He is (Ferrer & Sydenham's inc vox remix).flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"f799d3d2624830447e6a003cf5726256-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/f799d3d2624830447e6a003cf5726256%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/f799d3d2624830447e6a003cf5726256.m3u8\""
[2025-05-07 11:01:20.075 +00:00] [INF] [73] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:06:56.254 +00:00] [INF] [143] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "One of these days (LCAW remix)". Stopped at "372703" ms
[2025-05-07 11:06:56.853 +00:00] [INF] [143] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:06:56.988 +00:00] [INF] [143] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:06:56.988 +00:00] [INF] [143] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.1342995"
[2025-05-07 11:09:45.842 +00:00] [INF] [16] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Tensnake/Stimulate (deluxe)/15-Tensnake & Panama-Sunshine (Extended mix).flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"a26e7f548fd079f134877022a5d2055b-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/a26e7f548fd079f134877022a5d2055b%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/a26e7f548fd079f134877022a5d2055b.m3u8\""
[2025-05-07 11:09:57.105 +00:00] [INF] [13] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:10:40.279 +00:00] [INF] [13] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "So down". Stopped at "224040" ms
[2025-05-07 11:10:40.836 +00:00] [INF] [13] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:10:40.863 +00:00] [INF] [13] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:10:40.863 +00:00] [INF] [13] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0276672"
[2025-05-07 11:18:25.758 +00:00] [INF] [118] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Soviet Suprem/L'internationale (edition bonus)/07-Soviet Suprem-Slow slavic.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"48e8dd9c26ce9fb45eab03ab35153cc4-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/48e8dd9c26ce9fb45eab03ab35153cc4%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/48e8dd9c26ce9fb45eab03ab35153cc4.m3u8\""
[2025-05-07 11:18:34.214 +00:00] [INF] [62] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:18:34.333 +00:00] [INF] [118] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Aril Brikha/Art of vengeance maxi/01-Aril Brikha-Groove la' chord.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"c45b7dbab9e3a462807a399a74f2cc05-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/c45b7dbab9e3a462807a399a74f2cc05%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/c45b7dbab9e3a462807a399a74f2cc05.m3u8\""
[2025-05-07 11:18:43.880 +00:00] [INF] [40] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "He is (Ferrer & Sydenham's inc vox remix)". Stopped at "483573" ms
[2025-05-07 11:18:44.517 +00:00] [INF] [40] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:18:44.532 +00:00] [INF] [40] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:18:44.532 +00:00] [INF] [40] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0150392"
[2025-05-07 11:18:57.609 +00:00] [INF] [40] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:23:12.901 +00:00] [INF] [13] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "Sunshine (Extended mix)". Stopped at "268933" ms
[2025-05-07 11:23:13.521 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:23:13.552 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:23:13.552 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0303469"
[2025-05-07 11:23:25.394 +00:00] [INF] [13] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "Slow slavic". Stopped at "19" ms
[2025-05-07 11:23:25.956 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:23:25.984 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:23:25.984 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0282102"
[2025-05-07 11:24:18.801 +00:00] [INF] [146] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/IAM/Ombre est lumière (20ème anniversaire) CD3/12-IAM-La mort n'est pas une fin.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"78b2149a2e9ad07e9676c034136090b8-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/78b2149a2e9ad07e9676c034136090b8%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/78b2149a2e9ad07e9676c034136090b8.m3u8\""
[2025-05-07 11:24:32.824 +00:00] [INF] [37] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:31:24.824 +00:00] [INF] [94] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "Groove la' chord". Stopped at "476796" ms
[2025-05-07 11:31:25.390 +00:00] [INF] [94] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:31:25.416 +00:00] [INF] [94] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:31:25.416 +00:00] [INF] [94] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0263645"
[2025-05-07 11:33:02.829 +00:00] [INF] [94] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Bob Sinclar/Africanism vol. 4/02-House Rules & Africanism-Hyena.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"c31538d14bbeb4d878b5abd58f06c553-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/c31538d14bbeb4d878b5abd58f06c553%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/c31538d14bbeb4d878b5abd58f06c553.m3u8\""
[2025-05-07 11:33:20.277 +00:00] [INF] [90] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:33:20.470 +00:00] [INF] [41] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Juno Reactor/The golden sun of the great east/06-Juno Reactor-Tempest.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"dc819e4444f0c48675ec20b4bb621798-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/dc819e4444f0c48675ec20b4bb621798%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/dc819e4444f0c48675ec20b4bb621798.m3u8\""
[2025-05-07 11:33:42.284 +00:00] [INF] [38] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:36:55.294 +00:00] [INF] [38] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "La mort n'est pas une fin". Stopped at "330493" ms
[2025-05-07 11:36:55.890 +00:00] [INF] [47] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:36:55.919 +00:00] [INF] [47] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:36:55.919 +00:00] [INF] [47] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0286343"
[2025-05-07 11:42:00.675 +00:00] [INF] [36] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: "ffmpeg" "-analyzeduration 200M -probesize 1G  -i file:\"/var/data/jellyfin/Trans'Pact Productions/Prière au grand esprit/03-Perpetual Message-Ethno stack.flac\" -map_metadata -1 -map_chapters -1 -threads 0   -vn -acodec aac -ab 256000 -ac 2 -ar 44100 -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type fmp4 -hls_fmp4_init_filename \"6b79d11ba1dd6074dcb2d6ca2ca599ab-1.mp4\" -start_number 0 -hls_segment_filename \"/var/cache/jellyfin/transcodes/6b79d11ba1dd6074dcb2d6ca2ca599ab%d.mp4\" -hls_playlist_type vod -hls_list_size 0 -y \"/var/cache/jellyfin/transcodes/6b79d11ba1dd6074dcb2d6ca2ca599ab.m3u8\""
[2025-05-07 11:42:14.920 +00:00] [INF] [13] MediaBrowser.MediaEncoding.Transcoding.TranscodeManager: FFmpeg exited with code 0
[2025-05-07 11:42:25.278 +00:00] [INF] [158] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "Hyena". Stopped at "329938" ms
[2025-05-07 11:42:25.914 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:42:25.942 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 4 updates
[2025-05-07 11:42:25.942 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0273569"
[2025-05-07 11:42:27.670 +00:00] [INF] [39] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Finamp" "0.9.15" playing "Tempest". Stopped at "0" ms
[2025-05-07 11:42:28.172 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Started user data sync
[2025-05-07 11:42:28.198 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: User Data Sync: User "<username>" ("<user_id>") posted 2 updates
[2025-05-07 11:42:28.198 +00:00] [INF] [39] Jellyfin.Plugin.KodiSyncQueue.EntryPoints.UserSyncNotification: Finished user data sync, taking "00:00:00.0266616"
[2025-05-07 15:52:58.947 +00:00] [INF] [103] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 15:54:25.597 +00:00] [INF] [38] Jellyfin.Server.Implementations.Users.UserManager: Authentication request for "<username>" has succeeded.
[2025-05-07 15:54:25.597 +00:00] [INF] [38] Emby.Server.Implementations.Session.SessionManager: Current/Max sessions for user "<username>": 6/0
[2025-05-07 15:54:25.600 +00:00] [INF] [38] Emby.Server.Implementations.Session.SessionManager: Logging out access token "dd84e6fdddef49dfb826c96d55a187b6"
[2025-05-07 15:54:25.641 +00:00] [INF] [38] Emby.Server.Implementations.Session.SessionManager: Creating new access token for user 5fae5e50-8127-4219-afba-5346733c743a
[2025-05-07 15:54:27.502 +00:00] [INF] [52] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 15:55:15.502 +00:00] [INF] [103] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2025-05-07 15:55:27.503 +00:00] [INF] [103] Emby.Server.Implementations.Session.SessionWebSocketListener: Lost 1 WebSockets.
[2025-05-07 16:20:35.747 +00:00] [INF] [37] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 16:21:25.652 +00:00] [INF] [158] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 16:21:26.962 +00:00] [INF] [105] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Plugin Settings Requested...
[2025-05-07 16:21:27.056 +00:00] [INF] [118] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Sync Requested for UserID: '"<user_id>"' with LastUpdateDT: '"2025-05-07T16:10:48Z"'
[2025-05-07 16:21:27.100 +00:00] [INF] [118] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Added: 0, Removed: 0, Updated: 0, Changed User Data: 0
[2025-05-07 16:21:27.100 +00:00] [INF] [118] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Request Finished Taking "00:00:00.0440541"
[2025-05-07 16:22:13.713 +00:00] [INF] [105] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2025-05-07 16:22:25.714 +00:00] [INF] [118] Emby.Server.Implementations.Session.SessionWebSocketListener: Lost 1 WebSockets.
[2025-05-07 16:29:42.078 +00:00] [INF] [105] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 16:31:18.536 +00:00] [INF] [90] Jellyfin.Server.Implementations.Users.UserManager: Authentication request for "<username>" has succeeded.
[2025-05-07 16:31:18.536 +00:00] [INF] [90] Emby.Server.Implementations.Session.SessionManager: Current/Max sessions for user "<username>": 7/0
[2025-05-07 16:31:18.536 +00:00] [INF] [90] Emby.Server.Implementations.Session.SessionManager: Creating new access token for user 5fae5e50-8127-4219-afba-5346733c743a
[2025-05-07 16:31:19.013 +00:00] [INF] [160] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 16:32:32.323 +00:00] [INF] [13] Emby.Server.Implementations.Session.SessionManager: Logging out access token "dccfad0a44624ac089d3082877a61064"
[2025-05-07 16:32:32.335 +00:00] [INF] [160] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 16:33:16.320 +00:00] [INF] [145] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 16:33:17.675 +00:00] [INF] [145] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Plugin Settings Requested...
[2025-05-07 16:33:17.771 +00:00] [INF] [52] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Sync Requested for UserID: '"<user_id>"' with LastUpdateDT: '"2025-05-07T16:10:48Z"'
[2025-05-07 16:33:17.799 +00:00] [INF] [52] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Added: 0, Removed: 0, Updated: 0, Changed User Data: 0
[2025-05-07 16:33:17.799 +00:00] [INF] [52] Jellyfin.Plugin.KodiSyncQueue.API.KodiSyncQueueController: Request Finished Taking "00:00:00.0282351"
[2025-05-07 16:34:04.377 +00:00] [INF] [36] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2025-05-07 16:34:16.378 +00:00] [INF] [145] Emby.Server.Implementations.Session.SessionWebSocketListener: Lost 1 WebSockets.
[2025-05-07 16:43:21.992 +00:00] [INF] [145] Jellyfin.Server.Implementations.Users.UserManager: Authentication request for "<username>" has succeeded.
[2025-05-07 16:43:21.992 +00:00] [INF] [145] Emby.Server.Implementations.Session.SessionManager: Current/Max sessions for user "<username>": 8/0
[2025-05-07 16:43:21.992 +00:00] [INF] [145] Emby.Server.Implementations.Session.SessionManager: Creating new access token for user 5fae5e50-8127-4219-afba-5346733c743a
[2025-05-07 16:43:22.192 +00:00] [INF] [73] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request
[2025-05-07 16:44:05.968 +00:00] [INF] [90] Emby.Server.Implementations.Session.SessionManager: Logging out access token "f1d22210291b467ba96203b8180570a4"
[2025-05-07 16:44:06.016 +00:00] [INF] [73] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" closed
[2025-05-07 16:45:24.996 +00:00] [INF] [158] Jellyfin.Server.Implementations.Users.UserManager: Authentication request for "<username>" has succeeded.
[2025-05-07 16:45:24.996 +00:00] [INF] [158] Emby.Server.Implementations.Session.SessionManager: Current/Max sessions for user "<username>": 8/0
[2025-05-07 16:45:24.997 +00:00] [INF] [158] Emby.Server.Implementations.Session.SessionManager: Creating new access token for user 5fae5e50-8127-4219-afba-5346733c743a
[2025-05-07 16:45:25.174 +00:00] [INF] [90] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "127.0.0.1" request

Additional Information

None

[oddstr13]

That is unexpected behavior, there may be a new database flag we need to set to prevent it from trying to re-scan?

[christophehenry]

Don't hesitate to ping me if you want to test something.

[oddstr13]

I'm still on v20.2, as Kodi stopped updating the Ubuntu packages, so I don't currently have a v21 system to test on. If you can figure out why it is happening, I welcome PRs (or just the detailed info required for creating one).

[christophehenry]

Why not. Do you have a few hints as to where to start searching?

[oddstr13]

Other than looking at the database structure and comparing us vs natively scanned, and looking at Kodi source and PRs, unfortunately not.

Kinda sounds like Kodi is somehow deciding it doesn't have enough metadata, and starts re-scanning the files – which it can't, because the API isn't structured in such a way.

[christophehenry]

The DB for 21 is documented here. I don't see any flag to exclude path from library update. The video DB has noUpdate. Is it the flag that's present in 20?