Dopamine 3.0 - Hung on Added 380 song (3%)

[Wayne-Mather]

Have had this error ever since v3 was started. Does not matter if run from source or the released binaries.

It always hangs on loading my library - version 2 loads without any issues.

I can get to the music via the Folder browser so it's not an access issue 🤷

OS Details Below

OS: Windows Pro 25H2
OS Build: 26200.7462
Processor: Intel Core Ultra 7 155H
Ram: 64Gb
Hardware: Dell XPS 14
Library: Exterrnal NVME Drive

[digimezzo]

@Wayne-Mather Could you attach the log file?

[Wayne-Mather]

Not much to say really... I deleted the AppData folder so there was no database and started again from scratch. And yes, I thought logging may have been from a --help 🤣

[2025-12-16 17:02:39.508] [info]  [Main] [Main] +++ Starting +++
[2025-12-16 17:02:41.491] [info]  [AppComponent] [ngOnInit] +++ Started Dopamine 3.0.0 +++
[2025-12-16 17:02:41.527] [warn]  [DiscordApi] [clearPresence] Discord client is not ready. Cannot clear presence.
[2025-12-16 17:02:41.533] [info]  [AppearanceService] [applyTheme] Applied theme name=Dopamine' and theme classes='default-theme-dark'
[2025-12-16 17:02:41.546] [info]  [QueuePersister] [restore] Restoring queue
[2025-12-16 17:02:41.568] [error] [QueuePersister] [restore]  Failed to restore queue. Error: no such table: QueuedTrack
[2025-12-16 17:02:41.570] [info]  [Queue] [restoreTracks] Restored '0' tracks
[2025-12-16 17:02:41.572] [info]  [DatabaseMigrator] [migrateAsync] Database is too old (v0). Applying migrations.
[2025-12-16 17:02:41.575] [info]  [DatabaseMigrator] [getMigrationsAsync] Found migrations: Migration1,Migration2,Migration3,Migration4,Migration5,Migration6,Migration7
[2025-12-16 17:02:41.576] [info]  [DatabaseMigrator] [migrateAsync] Applying migration Migration1
[2025-12-16 17:02:41.581] [info]  [DatabaseMigrator] [migrateAsync] Migration Migration1 success
[2025-12-16 17:02:41.583] [info]  [DatabaseMigrator] [migrateAsync] Applying migration Migration2
[2025-12-16 17:02:41.587] [info]  [DatabaseMigrator] [migrateAsync] Migration Migration2 success
[2025-12-16 17:02:41.588] [info]  [DatabaseMigrator] [migrateAsync] Applying migration Migration3
[2025-12-16 17:02:41.591] [info]  [DatabaseMigrator] [migrateAsync] Migration Migration3 success
[2025-12-16 17:02:41.592] [info]  [DatabaseMigrator] [migrateAsync] Applying migration Migration4
[2025-12-16 17:02:41.596] [info]  [DatabaseMigrator] [migrateAsync] Migration Migration4 success
[2025-12-16 17:02:41.598] [info]  [DatabaseMigrator] [migrateAsync] Applying migration Migration5
[2025-12-16 17:02:41.601] [info]  [DatabaseMigrator] [migrateAsync] Migration Migration5 success
[2025-12-16 17:02:41.603] [info]  [DatabaseMigrator] [migrateAsync] Applying migration Migration6
[2025-12-16 17:02:41.606] [info]  [DatabaseMigrator] [migrateAsync] Migration Migration6 success
[2025-12-16 17:02:41.608] [info]  [DatabaseMigrator] [migrateAsync] Applying migration Migration7
[2025-12-16 17:02:41.611] [info]  [DatabaseMigrator] [migrateAsync] Migration Migration7 success
[2025-12-16 17:02:55.311] [info]  [FolderService] [addNewFolderAsync] Added folder with path 'E:\Music'
[2025-12-16 17:03:02.827] [info]  [DatabaseMigrator] [migrateAsync] The database is up to date. No migrations to perform.
[2025-12-16 17:03:02.858] [info]  [FileService] [getAllParameters] Parameters: C:\Program Files\Dopamine\Dopamine.exe, --help
[2025-12-16 17:03:02.859] [info]  [FileService] [getAllParameters] File queue: 
[2025-12-16 17:03:02.860] [info]  [FileService] [hasPlayableFilesAsGivenParameters] Found parameters: C:\Program Files\Dopamine\Dopamine.exe, --help
[2025-12-16 17:03:02.914] [info]  [ArtistBrowserComponent] [orderArtists] Finished ordering artists. Time required: 0 ms
[2025-12-16 17:03:02.916] [info]  [AlbumBrowserComponent] [orderAlbums] Finished ordering albums. Time required: 0 ms
[2025-12-16 17:03:02.917] [info]  [TrackSorter] [sortByTitleAscending] Finished sorting tracks by title ascending. Time required: 0 ms
[2025-12-16 17:03:02.918] [info]  [TrackBrowserComponent] [orderTracks] Finished ordering tracks. Time required: 1 ms
[2025-12-16 17:03:02.931] [info]  [TrackSorter] [sortByAlbum] Finished sorting tracks by album. Time required: 0 ms
[2025-12-16 17:03:02.932] [info]  [TrackBrowserComponent] [orderTracks] Finished ordering tracks. Time required: 1 ms
[2025-12-16 17:03:03.470] [info]  [ArtistService] [getArtists] Finished getting artists. Time required: 2 ms
[2025-12-16 17:03:03.471] [info]  [ArtistService] [getArtists] Finished splitting artists. Time required: 0 ms
[2025-12-16 17:03:03.473] [info]  [ArtistBrowserComponent] [orderArtists] Finished ordering artists. Time required: 0 ms
[2025-12-16 17:03:03.527] [info]  [AlbumService] [getAllAlbums] Finished getting all albums. Time required: 3 ms
[2025-12-16 17:03:03.529] [info]  [AlbumBrowserComponent] [orderAlbums] Finished ordering albums. Time required: 0 ms
[2025-12-16 17:03:03.580] [info]  [TrackService] [getVisibleTracks] Finished getting visible tracks. Time required: 2 ms
[2025-12-16 17:03:03.581] [info]  [TrackSorter] [sortByAlbum] Finished sorting tracks by album. Time required: 0 ms
[2025-12-16 17:03:03.582] [info]  [TrackBrowserComponent] [orderTracks] Finished ordering tracks. Time required: 1 ms
[2025-12-16 17:03:04.901] [info]  [IndexingService] [indexCollection] Indexing collection.
[2025-12-16 17:03:04.905] [info]  [UpdateService] [checkForUpdatesAsync] Checking for updates
[2025-12-16 17:03:05.136] [info]  [Indexer] [indexCollectionIfOutdatedAsync] Indexing collection.
[2025-12-16 17:03:05.453] [info]  [UpdateService] [checkForUpdatesAsync] Current=3.0.0, Latest=3.0.0-preview.40
[2025-12-16 17:03:05.454] [info]  [UpdateService] [checkForUpdatesAsync] Latest (3.0.0-preview.40) <= Current (3.0.0). Nothing to do.
[2025-12-16 17:03:10.164] [info]  [CollectionChecker] [isCollectionOutdatedAsync] Collection is outdated: tracksNeedIndexing=false, numberOfTracksHasChanged=true, lastDateModifiedHasChanged=true
[2025-12-16 17:03:10.165] [info]  [Indexer] [indexCollectionIfOutdatedAsync] Collection is outdated.
[2025-12-16 17:03:10.165] [info]  [TrackIndexer] [indexTracksAsync] +++ STARTED INDEXING TRACKS +++
[2025-12-16 17:03:10.167] [info]  [TrackRemover] [removeTracksThatDoNoNotBelongToFoldersAsync] There are no tracks to remove. Time required: 1 ms
[2025-12-16 17:03:10.169] [info]  [TrackRemover] [removeTracksThatAreNotFoundOnDiskAsync] Found 0 tracks.
[2025-12-16 17:03:10.170] [info]  [TrackRemover] [removeTracksThatAreNotFoundOnDiskAsync] Removed 0 tracks. Time required: 2 ms
[2025-12-16 17:03:10.172] [info]  [TrackRemover] [removeFolderTracksForInexistingTracksAsync] There are no folder tracks to remove. Time required: 1 ms
[2025-12-16 17:03:10.173] [info]  [TrackUpdater] [updateTracksThatAreOutOfDateAsync] Updated tracks: 0. Time required: 1 ms
[2025-12-16 17:03:12.909] [info]  [TrackAdder] [addTracksThatAreNotInTheDatabaseAsync] Processed 1 tracks

stuck again on 380 songs added (3%). Closed the app, started Dopamine v2 and already up to 30% of the song library

version 2 log after library rebuilt

2025-12-16 17:10:20.069|Info|App.xaml.InitializeShell|119|### STARTING Dopamine, version 2.0.10.4000, IsPortable = False, Windows version = Microsoft Windows 11 Pro (Microsoft Windows NT 10.0.26200.0), IsWindows10 = True ###
2025-12-16 17:10:20.085|Info|App.xaml.InitializeWcfServices|176|CommandService was started successfully
2025-12-16 17:10:20.087|Info|App.xaml.InitializeWcfServices|191|FileService was started successfully
2025-12-16 17:10:20.087|Info|App.xaml.InitializeShell|156|Showing Main screen
2025-12-16 17:10:20.151|Info|IndexingService.CheckCollectionAsync|143|+++ STARTED CHECKING COLLECTION +++
2025-12-16 17:10:20.381|Info|PlaybackService.GetSavedQueuedTracks|1347|Getting saved queued tracks
2025-12-16 17:10:20.482|Info|UpdateService.Reset|150|Resetting update check
2025-12-16 17:10:20.482|Info|UpdateService.DisablePeriodicCheck|143|Disabling periodic update check
2025-12-16 17:10:20.483|Info|UpdateService.CheckNow|92|Checking for updates
2025-12-16 17:10:20.483|Info|UpdateService.CheckNow|97|Update check: current version = 2.0.10.4000
2025-12-16 17:10:21.070|Info|UpdateService.CheckNow|106|Update check: new online version = 2.0.10.4000...
2025-12-16 17:10:21.070|Info|UpdateService.CheckNow|123|No new version was found
2025-12-16 17:10:21.749|Info|IndexingService.IndexTracksAsync|274|+++ STARTED INDEXING COLLECTION +++
2025-12-16 17:10:21.765|Info|IndexingService.IndexTracksAsync|290|Tracks removed: 0. Time required: 17 ms +++
2025-12-16 17:10:21.841|Info|IndexingService.IndexTracksAsync|300|Tracks updated: 0. Time required: 17 ms +++
2025-12-16 17:10:36.242|Error|QueuedTrackRepository.SaveQueuedTracksAsync|65|Could not save queued tracks. Exception: database is locked
2025-12-16 17:10:36.242|Info|PlaybackService.SaveQueuedTracksAsync|507|Saved 0 queued tracks
2025-12-16 17:13:06.743|Info|IndexingService.IndexTracksAsync|307|Tracks added: 16121. Time required: 164902 ms +++
2025-12-16 17:13:06.743|Info|IndexingService.IndexTracksAsync|314|+++ FINISHED INDEXING COLLECTION: Tracks removed: 0. Tracks updated: 0. Tracks added: 16121. Time required: 164995 ms +++
2025-12-16 17:13:06.751|Info|IndexingService.CleanupArtworkAsync|651|+++ STARTED CLEANING UP ARTWORK +++
2025-12-16 17:13:06.758|Info|IndexingService.CleanupArtworkAsync|672|+++ FINISHED CLEANING UP ARTWORK: Covers deleted from database: 0. Covers deleted from disk: 0. Time required:  ms +++
2025-12-16 17:13:06.758|Info|IndexingService.IndexCollectionAsync|227|Sending event to refresh the lists because: isTracksChanged = True, isArtworkCleanedUp = False
2025-12-16 17:13:06.763|Info|IndexingService.AddArtworkInBackgroundAsync|707|+++ STARTED ADDING ARTWORK IN THE BACKGROUND +++
2025-12-16 17:13:34.818|Error|CacheService.CacheArtworkAsync|95|Could not cache byte[] artwork. Exception: Parameter is not valid.
2025-12-16 17:14:17.678|Error|CacheService.CacheArtworkAsync|95|Could not cache byte[] artwork. Exception: Parameter is not valid.
2025-12-16 17:14:40.777|Error|CacheService.CacheArtworkAsync|95|Could not cache byte[] artwork. Exception: Parameter is not valid.
2025-12-16 17:14:54.193|Error|CacheService.CacheArtworkAsync|95|Could not cache byte[] artwork. Exception: Parameter is not valid.

[digimezzo]

@Wayne-Mather That log is not helpful indeed. WOuld you mind trying out this nightly? https://github.com/digimezzo/dopamine/actions/runs/20281153639

I've added logging so that we can see on which file it hangs. Could you, after trying the nightly, attach the new logfile here? Thank you.

[Wayne-Mather]

Last line is:

[2025-12-17 10:19:26.835] [info]  [TrackAdder] [addTracksThatAreNotInTheDatabaseAsync] Processing track 'E:\Music\!--= Compilations =--\Dance\Groove Armada - I See You Baby Shakin That Ass.mp3'

And that file can open and play just fine in VLC. Full log can be posted to something private so no door knocks 😏

[digimezzo]

@Wayne-Mather Yes could you share the log and that file somewhere? Thank you.

[Wayne-Mather]

@digimezzo email sent