Metadata missing from files

adrianmarsh · Post by **adrianmarsh** » Wed Sep 29, 2021 2:24 pm

Version: 5.0.2.2506

I've reinstalled v5, and noticed an issue tonight. As Discogs isn't there yet my plan was to rip some CDs using V5, then update the files using V4+Discogs

The RIP for 3 CDs all seemed to go well. The metadata in the V5 library looks fine on screen - the data is there for ALL files, I think pulled down from MusicBrainz as part of the rip. So within the V5 library it looks good, nothing missing.
However when I read the FLAC files into V4, some of the files are missing tag data. I've attached a screenshot and you can see its not all of the files, just some, quite randomly it seems.

I used MP3TAG to verify the data is missing from the files.
And I've tried cleaning the TAGs in MM5 and Update Tags, nothing updated.
The files seem to play okay, so audio data is there.

I'll install the debug V5 version and see if I can replicate the rip and tag-rewrite with the logs.
I see some previous forum posts about iTunes sync issues, but I'm not using iTunes anywhere.

Post by **Lowlander** » Wed Sep 29, 2021 2:30 pm

Can you run Tools > Edit Tags > Update tags in MM5? Does that fix the problem of missing tags?

For me MM4 shows the tags for FLAC files ripped as well as edited by MM5.

adrianmarsh · Post by **adrianmarsh** » Wed Sep 29, 2021 2:45 pm

mmmm.. debugview is showing me that MM isn't able to write to the file when I try to sync. Something must be blocking it I guess. My first two suspicions are QNAP Qsync, and maybe Microsoft A/V.. I'll see if I can figure that out.

adrianmarsh · Post by **adrianmarsh** » Wed Sep 29, 2021 2:59 pm

So the debug log below seems to suggest somethings stopping the write, but then it confirms that the files been updated.
Checking the file afterwards, it hasn't.

Code: Select all

00000001	0.00000000	[23296] MM5 [7204](R) **** focusControl: ColumnTrackList	
00000002	1.53827107	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///controls/listview.js ; Func: handleItemMouseDown ; Row: 2995 ; Col: 21 	
00000003	1.53827107	[23296] Script: file:///controls/listview.js ; Func: eval ; Row: 264 ; Col: 22 	
00000004	1.62120247	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///controls/artWindow.js ; Func: eval ; Row: 1830 ; Col: 55 	
00000005	1.62120247	[23296] Script: file:///controls/control.js ; Func: eval ; Row: 320 ; Col: 13 	
00000006	1.65909088	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///actions.js ; Func: getSelectedTracklist ; Row: 6334 ; Col: 25 	
00000007	1.65909088	[23296] Script: file:///actions.js ; Func: eval ; Row: 1808 ; Col: 39 	
00000008	1.65909088	[23296] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 	
00000009	1.65909088	[23296] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 	
00000010	1.65909088	[23296] Script: file:///actions.js ; Func: visible ; Row: 1805 ; Col: 24 	
00000011	1.66650605	[23296] MM5 [7204](R) TUtils.getDataFromClipboard: JSON: , data <> nil	
00000012	1.66677725	[23296] MM5 [7204](R) Paste disabled, canPasteClipboard returned false	
00000013	1.67916560	[23296] MM5 [7204](R) menu coords 730x492	
00000014	1.68953776	[23296] MM5 [7204](R) menu coords 730x492	
00000015	1.69020081	[23296] MM5 [7204](R) setBounds 730x368 (248x711) with ratio 1	
00000016	1.70807838	[23296] MM5 [7204](R) menu coords 730x492	
00000017	1.71253514	[23296] MM5 [7204](R) Main window is not minimized and not hidden	
00000018	1.73367238	[23296] MM5 [7204](R) menu coords 976x491	
00000019	1.74024796	[23296] MM5 [7204](R) Main window is not minimized and not hidden	
00000020	2.30677843	[23296] MM5 [7204](R) Main window is not minimized and hidden	
00000021	2.31495190	[23296] MM5 [7204](R) setBounds 976x524 (134x36) with ratio 1	
00000022	2.34187603	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///actions.js ; Func: getSelectedTracklist ; Row: 6334 ; Col: 25 	
00000023	2.34187603	[23296] Script: file:///actions.js ; Func: eval ; Row: 3715 ; Col: 47 	
00000024	2.34187603	[23296] Script: file:///promise.js ; Func: invokeCallback ; Row: 399 ; Col: 19 	
00000025	2.34187603	[23296] Script: file:///promise.js ; Func: publish ; Row: 457 ; Col: 9 	
00000026	2.34187603	[23296] Script: file:///promise.js ; Func: publishFulfillment ; Row: 633 ; Col: 7 	
00000027	2.35193038	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///actions.js ; Func: eval ; Row: 9386 ; Col: 30 	
00000028	2.35193038	[23296] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 	
00000029	2.35193038	[23296] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 	
00000030	2.35193038	[23296] Script: file:///actions.js ; Func: isFocusedPinnedAsync ; Row: 9374 ; Col: 16 	
00000031	2.35193038	[23296] Script: file:///actions.js ; Func: visible ; Row: 977 ; Col: 24 	
00000032	2.35230470	[23296] MM5 [4960](R) 4960 DB query prepare finished, took 0.	
00000033	2.36011720	[23296] MM5 [7204](R) menu coords 976x524	
00000034	2.36373711	[23296] MM5 [7204](R) menu coords 976x524	
00000035	2.36661530	[23296] MM5 [7204](R) Main window is not minimized and hidden	
00000036	2.37512136	[23296] MM5 [7204](R) menu coords 976x554	
00000037	2.38968730	[23296] MM5 [7204](R) Main window is not minimized and not hidden	
00000038	2.39206290	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: getTrackCollectionAsync ; Row: 8183 ; Col: 32 	
00000039	2.39206290	[23296] Script: file:///actions.js ; Func: eval ; Row: 9042 ; Col: 30 	
00000040	2.39325047	[23296] MM5 [4960](R) 4960 DB query prepare finished, took 0.	
00000041	2.39423800	[23296] MM5 [4960](R) 4960 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) )	
00000042	2.39509249	[23296] MM5 [4960](R) 4960 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4,5,7) )	
00000043	2.39628530	[23296] MM5 [4960](R) 4960 DB query prepare finished, took 0.	
00000044	2.39674783	[23296] MM5 [4960](R) TCollection.getIsVisible: Included Music, FVisibleInMainTree: 1	
00000045	2.39812756	[23296] MM5 [7204](R) Main window is not minimized and not hidden	
00000046	2.39846087	[23296] MM5 [7204](R) Main window is not minimized and hidden	
00000047	2.39943266	[23296] MM5 [4960](R) 4960 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( (Songs.SongPath like '%Artist Album%') AND Songs.TrackType in (0) )	
00000048	2.39977169	[23296] MM5 [4960](R) TCollection.getIsVisible: Organised Music, FVisibleInMainTree: 1	
00000049	2.40032911	[23296] MM5 [4960](R) 4960 DB query prepare finished, took 0.	
00000050	2.40086865	[23296] MM5 [4960](R) 4960 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( (Songs.SongPath like '%Music Collection\Various Artists%') )	
00000051	2.40113235	[23296] MM5 [4960](R) TCollection.getIsVisible: Misc Audio / Downloads, FVisibleInMainTree: 1	
00000052	2.40169001	[23296] MM5 [4960](R) 4960 DB query prepare finished, took 0.	
00000053	2.40221119	[23296] MM5 [4960](R) 4960 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,3) AND (Songs.SongPath like '%Unorganised%') )	
00000054	2.69334602	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///actions.js ; Func: getSelectedTracklist ; Row: 6334 ; Col: 25 	
00000055	2.69334602	[23296] Script: file:///actions.js ; Func: eval ; Row: 9920 ; Col: 55 	
00000056	2.69334602	[23296] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 	
00000057	2.69334602	[23296] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 	
00000058	2.69334602	[23296] Script: file:///actions.js ; Func: checked ; Row: 9919 ; Col: 28 	
00000059	2.73113489	[23296] MM5 [7204](R) menu coords 976x740	
00000060	3.30867195	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///actions.js ; Func: getSelectedTracklist ; Row: 6334 ; Col: 25 	
00000061	3.30867195	[23296] Script: file:///actions.js ; Func: eval ; Row: 9307 ; Col: 43 	
00000062	3.30867195	[23296] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 	
00000063	3.30867195	[23296] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 	
00000064	3.30867195	[23296] Script: file:///actions.js ; Func: notLocalMediaListSelected ; Row: 9306 ; Col: 19 	
00000065	3.31759644	[23296] MM5 [7204](R) menu coords 976x860	
00000066	3.33185077	[23296] MM5 [7204](R) menu coords 976x860	
00000067	3.33496308	[23296] MM5 [7204](R) Main window is not minimized and not hidden	
00000068	5.12802649	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///actions.js ; Func: getSelectedTracklist ; Row: 6334 ; Col: 25 	
00000069	5.12802649	[23296] Script: file:///actions.js ; Func: advTagOperation ; Row: 9347 ; Col: 32 	
00000070	5.12802649	[23296] Script: file:///actions.js ; Func: execute ; Row: 1157 ; Col: 17 	
00000071	5.12882233	[23296] MM5 [7204](R) Updated devicePixelRatio for file:///dialogs/dlgMessage.html to 1	
00000072	5.13106441	[13072] MM5 [26204](B) Main browser created	
00000073	5.13627768	[23296] MM5 [7204](R) Main window is not minimized and hidden	
00000074	5.14024973	[13072] MM5 [26204](B) BeforeBrowse called file:///dialogs/dlgMessage.html	
00000075	5.14131355	[13072] MM5 [26476](B) GetResourceRequestHandler called	
00000076	5.14155579	[13072] MM5 [26476](B) Requesting URL file:///dialogs/dlgMessage.html	
00000077	5.14206791	[13072] MM5 [20964](B) Retrieving file file:///dialogs/dlgMessage.html	
00000078	5.14291525	[13072] MM5 [20964](B) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\dialogs\dlgMessage.html", took: 0	
00000079	5.15956450	[23296] MM5 [7204](R) ********** OnBrowserCreated 11	
00000080	5.28451443	[23296] MM5 [7204](R) ********** OnContextCreated file:///dialogs/dlgMessage.html (11)	
00000081	5.31361771	[13072] MM5 [26476](B) GetResourceRequestHandler called	
00000082	5.31384182	[13072] MM5 [26476](B) GetResourceHandler called	
00000083	5.31440878	[13072] MM5 [26476](B) GetResourceRequestHandler called	
00000084	5.31456757	[13072] MM5 [26476](B) GetResourceHandler called	
00000085	5.31525898	[13072] MM5 [26476](B) GetResourceRequestHandler called	
00000086	5.31536484	[13072] MM5 [26476](B) GetResourceHandler called	
00000087	5.31572723	[13072] MM5 [20964](B) Retrieving file file:///dialogs/dlgMessage.css	
00000088	5.31759691	[13072] MM5 [20964](B) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\dialogs\dlgMessage.css", took: 0	
00000089	5.31915331	[13072] MM5 [20964](B) Retrieving file file:///dialogs/dlgMessage.css, took: 0	
00000090	5.31937599	[13072] MM5 [20964](B) buildPathFromUrl from file:///mminit.js to mminit.js	
00000091	5.32247257	[13072] MM5 [20964](B) buildPathFromUrl from file:///mminit_add.js to mminit_add.js	
00000092	5.32381821	[13072] MM5 [20964](B) Retrieving file file:///mminit.js, took: 0	
00000093	5.32432175	[13072] MM5 [20964](B) buildPathFromUrl from file:///dialogs/dlgMessage.js to dialogs\dlgMessage.js	
00000094	5.32653046	[13072] MM5 [20964](B) buildPathFromUrl from file:///dialogs/dlgMessage_add.js to dialogs\dlgMessage_add.js	
00000095	5.34549713	[23296] MM5 [7204](R) Retrieving file file:///promise.js	
00000096	5.34658051	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\promise.js", took: 0	
00000097	5.34724092	[23296] MM5 [7204](R) Retrieving file file:///promise.js, took: 0	
00000098	5.35376740	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\\skins\desktop\info.json", took: 0	
00000099	5.35767984	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///mminit.js ; Func: window.loadFile ; Row: 499 ; Col: 38 	
00000100	5.35767984	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 1782 ; Col: 13 	
00000101	5.35767984	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 1807 ; Col: 3 	
00000102	5.35786438	[23296] MM5 [4960](R) buildPathFromUrl from file:///temp/precompiledLess_Material Design (dark)_Desktop.css to C:\Windows\Temp\precompiledLess_Material Design (dark)_Desktop.css	
00000103	5.35882378	[23296] MM5 [4960](R) Retrieving file file:///temp/precompiledLess_Material Design (dark)_Desktop.css, took: 0	
00000104	5.35982180	[23296] MM5 [7204](R) Retrieving file file:///binding.js	
00000105	5.36006308	[13072] MM5 [26476](B) GetResourceHandler called	
00000106	5.36017656	[23296] MM5 [7204](R) buildPathFromUrl from file:///binding.js to binding.js	
00000107	5.36154222	[23296] MM5 [7204](R) buildPathFromUrl from file:///binding_add.js to binding_add.js	
00000108	5.36558962	[23296] MM5 [7204](R) Plugins Folder: C:\Program Files (x86)\MediaMonkey 5 beta\Plugins\	
00000109	5.36586142	[23296] MM5 [7204](R) Plugins Folder: C:\Program Files (x86)\MediaMonkey 5 beta\Plugins\	
00000110	5.36735201	[23296] MM5 [7204](R) Retrieving file file:///helpers/observableObject.js	
00000111	5.36822891	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\helpers\observableObject.js", took: 0	
00000112	5.36882782	[23296] MM5 [7204](R) Retrieving file file:///helpers/observableObject.js, took: 0	
00000113	5.36981344	[23296] MM5 [7204](R) buildPathFromUrl from file:///utils.js to utils.js	
00000114	5.37063599	[23296] MM5 [7204](R) buildPathFromUrl from file:///utils_add.js to utils_add.js	
00000115	5.37137365	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\youtube\layouts\Desktop\utils_add.js", took: 0	
00000116	5.37150955	[23296] MM5 [7204](R) Retrieving file file:///utils.js, took: 0	
00000117	5.37288237	[23296] MM5 [7204](R) buildPathFromUrl from file:///animationTools.js to animationTools.js	
00000118	5.37371111	[23296] MM5 [7204](R) buildPathFromUrl from file:///animationTools_add.js to animationTools_add.js	
00000119	5.37564421	[23296] MM5 [7204](R) Retrieving file file:///velocity.js	
00000120	5.37652540	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\velocity.js", took: 0	
00000121	5.37718153	[23296] MM5 [7204](R) Retrieving file file:///velocity.js, took: 0	
00000122	5.38355637	[23296] MM5 [7204](R) Retrieving file file:///controls/tooltipController.js	
00000123	5.38449430	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\controls\tooltipController.js", took: 0	
00000124	5.38510466	[23296] MM5 [7204](R) Retrieving file file:///controls/tooltipController.js, took: 0	
00000125	5.38606739	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/control.js to controls\control.js	
00000126	5.38694096	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/control_add.js to controls\control_add.js	
00000127	5.39341259	[23296] MM5 [7204](R) Retrieving file file:///dndutils.js	
00000128	5.39465475	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\dndutils.js", took: 0	
00000129	5.39555502	[23296] MM5 [7204](R) Retrieving file file:///dndutils.js, took: 0	
00000130	5.39686441	[23296] MM5 [7204](R) buildPathFromUrl from file:///consts.js to consts.js	
00000131	5.39766502	[23296] MM5 [7204](R) buildPathFromUrl from file:///consts_add.js to consts_add.js	
00000132	5.39898491	[23296] MM5 [7204](R) Retrieving file file:///controls/popupmenu.js	
00000133	5.39980078	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\controls\popupmenu.js", took: 0	
00000134	5.40036774	[23296] MM5 [7204](R) Retrieving file file:///controls/popupmenu.js, took: 0	
00000135	5.40435171	[23296] MM5 [7204](R) Retrieving file file:///actions.js	
00000136	5.40539360	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\actions.js", took: 0	
00000137	5.40603542	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\swapartisttitle\actions_add.js", took: 0	
00000138	5.40643597	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\statistics\actions_add.js", took: 0	
00000139	5.40681744	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\redonavbarbutton\actions_add.js", took: 0	
00000140	5.40723324	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\exportpodcasts\actions_add.js", took: 0	
00000141	5.40768242	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\export\actions_add.js", took: 0	
00000142	5.40806532	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\cleanmultivaluefields\actions_add.js", took: 0	
00000143	5.40842819	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\casechecker\actions_add.js", took: 0	
00000144	5.40879059	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\exportplaylists\actions_add.js", took: 0	
00000145	5.40915823	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\featuredartiststoartists\actions_add.js", took: 0	
00000146	5.40956450	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\scripts\autoinctrack\actions_add.js", took: 0	
00000147	5.40982246	[23296] MM5 [7204](R) Retrieving file file:///actions.js, took: 0	
00000148	5.43735456	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///mminit.js ; Func: window.loadFile ; Row: 499 ; Col: 38 	
00000149	5.43735456	[23296] Script: file:///mminit.js ; Func: window.loadIcon ; Row: 835 ; Col: 13 	
00000150	5.43735456	[23296] Script: file:///actions.js ; Func: eval ; Row: 9875 ; Col: 5 	
00000151	5.43735456	[23296] Script: file:///actions.js ; Func: eval ; Row: 10023 ; Col: 3 	
00000152	5.43735456	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 625 ; Col: 28 	
00000153	5.43746758	[23296] MM5 [4960](R) buildPathFromUrl from file:///skin/icon/star.svg to skin\icon\star.svg	
00000154	5.46384382	[23296] MM5 [7204](R) Retrieving file file:///controls/checkbox.js	
00000155	5.46490049	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\controls\checkbox.js", took: 0	
00000156	5.46555185	[23296] MM5 [7204](R) Retrieving file file:///controls/checkbox.js, took: 0	
00000157	5.46718788	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/innerWindow.js to controls\innerWindow.js	
00000158	5.46815109	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/innerWindow_add.js to controls\innerWindow_add.js	
00000159	5.47575521	[23296] MM5 [7204](R) ******** currentMD5=997651D759DCD375B0EC9E9CEB80801E, precompiledLessMD5=997651D759DCD375B0EC9E9CEB80801E, currentTimestamp=0, precompiledLessTimestamp=0	
00000160	5.48288536	[23296] MM5 [7204](R) load event call 11	
00000161	5.48330021	[23296] MM5 [7204](R) Main window is not minimized and hidden	
00000162	5.48750401	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/windowtitle.js to controls\windowtitle.js	
00000163	5.48836946	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\controls\windowtitle.js", took: 0	
00000164	5.48895979	[23296] MM5 [7204](R) Retrieving file file:///controls/windowtitle.js, took: 16	
00000165	5.49320555	[23296] MM5 [7204](R) Retrieving file file:///controls/windowtitle.html	
00000166	5.49405527	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\controls\windowtitle.html", took: 0	
00000167	5.49801350	[13072] MM5 [26476](B) GetResourceRequestHandler called	
00000168	5.49811697	[13072] MM5 [26476](B) GetResourceHandler called	
00000169	5.49843264	[13072] MM5 [20964](B) Retrieving file file:///skin/fonts/Roboto-Regular.ttf	
00000170	5.49900579	[13072] MM5 [20964](B) Retrieving file file:///skin/fonts/Roboto-Regular.ttf, took: 0	
00000171	5.50218964	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/buttons.js to controls\buttons.js	
00000172	5.50308800	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/buttons_add.js to controls\buttons_add.js	
00000173	5.50497293	[23296] MM5 [7204](R) Retrieving file file:///controls/button.js	
00000174	5.50579977	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\controls\button.js", took: 0	
00000175	5.50636148	[23296] MM5 [7204](R) Retrieving file file:///controls/button.js, took: 0	
00000176	5.50713730	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/menuButton.js to controls\menuButton.js	
00000177	5.50793505	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/menuButton_add.js to controls\menuButton_add.js	
00000178	5.50915051	[23296] MM5 [7204](R) Retrieving file file:///controls/iconbutton.js	
00000179	5.50994253	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\controls\iconbutton.js", took: 0	
00000180	5.51049662	[23296] MM5 [7204](R) Retrieving file file:///controls/iconbutton.js, took: 0	
00000181	5.51116419	[23296] MM5 [7204](R) buildPathFromUrl from file:///controls/toolbutton.js to controls\toolbutton.js	
00000182	5.51195097	[23296] MM5 [7204](R) TFilesHandlerFile.getFileContent - opening file "c:\program files (x86)\mediamonkey 5 beta\controls\toolbutton.js", took: 0	
00000183	5.51258183	[23296] MM5 [7204](R) Retrieving file file:///controls/toolbutton.js, took: 0	
00000184	5.51566982	[23296] MM5 [4960](R) Retrieving file file:///skin/icon/mediamonkey.svg	
00000185	5.51618481	[23296] MM5 [4960](R) Retrieving file file:///skin/icon/mediamonkey.svg, took: 0	
00000186	5.51669693	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///mminit.js ; Func: window.loadFile ; Row: 499 ; Col: 38 	
00000187	5.51669693	[23296] Script: file:///mminit.js ; Func: window.loadIcon ; Row: 835 ; Col: 13 	
00000188	5.51669693	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 1910 ; Col: 13 	
00000189	5.51669693	[23296] Script: file:///mminit.js ; Func: window.forEach ; Row: 1023 ; Col: 9 	
00000190	5.51669693	[23296] Script: file:///mminit.js ; Func: window.initializeControls ; Row: 1907 ; Col: 5 	
00000191	5.51681995	[23296] MM5 [4960](R) buildPathFromUrl from file:///skin/icon/mainMenu.svg to skin\icon\mainMenu.svg	
00000192	5.51740217	[23296] MM5 [21520](R) BQ: Thread[21520] is executing new task now: Callstack: Script: file:///mminit.js ; Func: window.loadFile ; Row: 499 ; Col: 38 	
00000193	5.51740217	[23296] Script: file:///mminit.js ; Func: window.loadIcon ; Row: 835 ; Col: 13 	
00000194	5.51740217	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 1910 ; Col: 13 	
00000195	5.51740217	[23296] Script: file:///mminit.js ; Func: window.forEach ; Row: 1023 ; Col: 9 	
00000196	5.51740217	[23296] Script: file:///mminit.js ; Func: window.initializeControls ; Row: 1907 ; Col: 5 	
00000197	5.51750183	[23296] MM5 [21520](R) buildPathFromUrl from file:///skin/icon/help.svg to skin\icon\help.svg	
00000198	5.51805496	[23296] MM5 [21520](R) BQ: Thread[21520] is executing new task now: Callstack: Script: file:///mminit.js ; Func: window.loadFile ; Row: 499 ; Col: 38 	
00000199	5.51805496	[23296] Script: file:///mminit.js ; Func: window.loadIcon ; Row: 835 ; Col: 13 	
00000200	5.51805496	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 1910 ; Col: 13 	
00000201	5.51805496	[23296] Script: file:///mminit.js ; Func: window.forEach ; Row: 1023 ; Col: 9 	
00000202	5.51805496	[23296] Script: file:///mminit.js ; Func: window.initializeControls ; Row: 1907 ; Col: 5 	
00000203	5.51814365	[23296] MM5 [21520](R) buildPathFromUrl from file:///skin/icon/minimize.svg to skin\icon\minimize.svg	
00000204	5.51870823	[23296] MM5 [21520](R) BQ: Thread[21520] is executing new task now: Callstack: Script: file:///mminit.js ; Func: window.loadFile ; Row: 499 ; Col: 38 	
00000205	5.51870823	[23296] Script: file:///mminit.js ; Func: window.loadIcon ; Row: 835 ; Col: 13 	
00000206	5.51870823	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 1910 ; Col: 13 	
00000207	5.51870823	[23296] Script: file:///mminit.js ; Func: window.forEach ; Row: 1023 ; Col: 9 	
00000208	5.51870823	[23296] Script: file:///mminit.js ; Func: window.initializeControls ; Row: 1907 ; Col: 5 	
00000209	5.51880169	[23296] MM5 [21520](R) buildPathFromUrl from file:///skin/icon/maximize.svg to skin\icon\maximize.svg	
00000210	5.51935339	[23296] MM5 [21520](R) BQ: Thread[21520] is executing new task now: Callstack: Script: file:///mminit.js ; Func: window.loadFile ; Row: 499 ; Col: 38 	
00000211	5.51935339	[23296] Script: file:///mminit.js ; Func: window.loadIcon ; Row: 835 ; Col: 13 	
00000212	5.51935339	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 1910 ; Col: 13 	
00000213	5.51935339	[23296] Script: file:///mminit.js ; Func: window.forEach ; Row: 1023 ; Col: 9 	
00000214	5.51935339	[23296] Script: file:///mminit.js ; Func: window.initializeControls ; Row: 1907 ; Col: 5 	
00000215	5.51944304	[23296] MM5 [21520](R) buildPathFromUrl from file:///skin/icon/restore.svg to skin\icon\restore.svg	
00000216	5.51995516	[23296] MM5 [21520](R) Retrieving file file:///skin/icon/restore.svg, took: 16	
00000217	5.52008963	[23296] MM5 [21520](R) Retrieving file file:///skin/icon/windowclose.svg	
00000218	5.52058887	[23296] MM5 [21520](R) Retrieving file file:///skin/icon/windowclose.svg, took: 0	
00000219	5.52330256	[23296] MM5 [7204](R) Display 0, X: 0, Y: 0, width: 1920, height: 1080 	
00000220	5.52377272	[23296] MM5 [7204](R) Going to place window X: 627, Y: 442, width: 604, height: 196 	
00000221	5.52394533	[23296] MM5 [7204](R) Placed to display	
00000222	5.52615929	[13072] MM5 [26204](B) TBrowserWindow.prepareResizedShape: width/height: 604/196	
00000223	5.55135441	[23296] MM5 [7204](R) ready event call 11	
00000224	5.55605507	[23296] MM5 [4960](R) BQ: Thread[4960] is executing new task now: Callstack: Script: file:///mminit.js ; Func: window.loadFile ; Row: 499 ; Col: 38 	
00000225	5.55605507	[23296] Script: file:///mminit.js ; Func: window.loadIcon ; Row: 835 ; Col: 13 	
00000226	5.55605507	[23296] Script: file:///dialogs/dlgMessage.js ; Func: init ; Row: 55 ; Col: 5 	
00000227	5.55605507	[23296] Script: file:///mminit.js ; Func: undefined ; Row: 2715 ; Col: 25 	
00000228	5.55621433	[23296] MM5 [4960](R) buildPathFromUrl from file:///skin/icon/confirmation.svg to skin\icon\confirmation.svg	
00000229	5.56019449	[23296] MM5 [7204](R) **** focusControl: Button	
00000230	5.56090212	[23296] MM5 [7204](R) Main window is not minimized and not hidden	
00000231	5.56301832	[13072] MM5 [26204](B) enableWindows	
00000232	5.56648684	[13072] notifyReady called	
00000233	5.56994915	[13072] MM5 [26204](B) Activating window file:///dialogs/dlgMessage.html	
00000234	5.57237864	[23296] MM5 [7204](R) Main window is not minimized and not hidden	
00000235	5.57872200	[13072] MM5 [18120](B) TBrowserWindow.prepareResizedShape: width/height: 604/196	
00000236	5.66701698	[23296] MM5 [7204](R) window.setComputedSize: 604/196	
00000237	5.68982315	[13072] MM5 [26204](B) TBrowserWindow.prepareResizedShape: width/height: 604/196	
00000238	6.65090322	[23296] MM5 [7204](R) window.setComputedSize: 604/196	
00000239	6.80257511	[23296] MM5 [7204](R) window.setComputedSize: 604/196	
00000240	7.15506458	[23296] MM5 [7204](R) **** focusControl: Button	
00000241	7.27598858	[23296] MM5 [7204](R) TBackgroundTagger.UpdateTags: Got BTLockCS	
00000242	7.27624655	[23296] MM5 [7204](R) Tagging: Queuing finished	
00000243	7.27742100	[23296] MM5 [7204](R) registered task progress  8	
00000244	7.27806139	[23296] MM5 [7204](R) closewindow start	
00000245	7.27851200	[23296] MM5 [7692](R) Tagging: Thread started	
00000246	7.27877855	[23296] MM5 [7692](R) Tagging: Track will be tagged now: E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC, AlbumArtCount=0	
00000247	7.27915525	[23296] MM5 [7692](R) FH.WriteFileInfo: E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC	
00000248	7.27958059	[23296] MM5 [7692](R) FH.WriteFileInfo: openned	
00000249	7.27978706	[23296] f_flac: FORMAT_SetInteger	
00000250	7.27999687	[23296] f_flac: FORMAT_SetInteger	
00000251	7.28024101	[23296] f_flac: FORMAT_SetInteger	
00000252	7.28041840	[23296] f_flac: FORMAT_SetString	
00000253	7.28062391	[23296] f_flac: FORMAT_SetString	
00000254	7.28079462	[23296] f_flac: FORMAT_SetString	
00000255	7.28088713	[13072] MM5 [26204](B) Activating window file:///dialogs/dlgMessage.html	
00000256	7.28099918	[23296] f_flac: FORMAT_SetString	
00000257	7.28115702	[23296] f_flac: FORMAT_SetString	
00000258	7.28131533	[23296] f_flac: FORMAT_SetString	
00000259	7.28141451	[23296] f_flac: FORMAT_SetString	
00000260	7.28153658	[23296] f_flac: FORMAT_SetString	
00000261	7.28163338	[23296] f_flac: FORMAT_SetString	
00000262	7.28171825	[23296] f_flac: FORMAT_SetString	
00000263	7.28184938	[23296] f_flac: FORMAT_SetString	
00000264	7.28192663	[23296] f_flac: FORMAT_SetString	
00000265	7.28202868	[23296] f_flac: FORMAT_SetString	
00000266	7.28212166	[23296] f_flac: FORMAT_SetString	
00000267	7.28219604	[23296] f_flac: FORMAT_SetString	
00000268	7.28226089	[23296] f_flac: FORMAT_SetString	
00000269	7.28232718	[23296] f_flac: FORMAT_SetString	
00000270	7.28239059	[23296] f_flac: FORMAT_SetDouble	
00000271	7.28245258	[23296] f_flac: FORMAT_SetDouble	
00000272	7.28254175	[23296] f_flac: FORMAT_SetInteger	
00000273	7.28262091	[23296] flacParser: UpdateTag	
00000274	7.28295374	[23296] flacParser: ConvertMetadataToBlock END	
00000275	7.28302860	[23296] flacParser: ConvertAAToBlocks END	
00000276	7.28480005	[23296] MM5 [7204](R) closewindow end	
00000277	7.29220819	[23296] flacParser: RenameFileAccess failed	
00000278	7.29241848	[23296] f_flac: FORMAT_CloseFile	
00000279	7.29306936	[13072] MM5 [26204](B) enableWindows	
00000280	7.29666424	[13072] MM5 [26204](B) Activating window file:///mainwindow.html	
00000281	7.29992390	[13072] MM5 [26204](B) WM_CLOSE begin	
00000282	7.30009127	[13072] MM5 [26204](B) ****** CLOSE beforeClose for identifier 11	
00000283	7.30204725	[13072] MM5 [26204](B) Activating window file:///mainwindow.html	
00000284	7.30370092	[13072] MM5 [26204](B) ****** CLOSE closeBrowserCall for identifier 11	
00000285	7.30693293	[23296] MM5 [7204](R) TSharedWindow.notifyOnClose started	
00000286	7.31126070	[23296] MM5 [7204](R) TSharedWindow.notifyOnClose finished	
00000287	7.31650400	[23296] MM5 [7204](R) **** focusControl: ColumnTrackList	
00000288	7.32707024	[13072] MM5 [26204](B) WM_CLOSE begin	
00000289	7.32721663	[13072] MM5 [26204](B) ****** CLOSE canClose for identifier 11	
00000290	7.32760239	[13072] MM5 [26204](B) WM_DESTROY end	
00000291	7.33257914	[13072] MM5 [26204](B) WM_DESTROY end	
00000292	7.33401775	[23296] MM5 [7204](R) OnContextReleased start	
00000293	7.33456182	[23296] MM5 [7204](R) TSharedWindow destroy called - worker id: 0, addr: 102999824	
00000294	7.33773518	[13072] MM5 [26204](B) WM_CLOSE end	
00000295	7.33998394	[23296] MM5 [7204](R) OnBrowserDestroyed end.	
00000296	8.07043552	[13072] Win7Tools - Thumbnail called	
00000297	8.52042294	[23296] MM5 [21520](R) Going 'Worker'to make final callback 21520	
00000298	8.55741405	[23296] MM5 [4960](R) Going 'Worker'to make final callback 4960	
00000299	8.64836884	[23296] MM5 [7204](R) BQ: Currently running 0 threads of 10	
00000300	9.49440479	[23296] MM5 [4960](R) DB: Deleting prepared query: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( (Songs.TrackType in (3) OR (Songs.SongPath like '%Music Collection\Classical%')) )	
00000301	9.49454784	[23296] MM5 [7956](R) DB open SQL: SELECT PictureDataHash FROM Covers WHERE IDSong=99322 ORDER BY CoverOrder	
00000302	9.49497318	[23296] MM5 [4960](R) DB: Deleting prepared query: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4,5,7) )	
00000303	9.49509239	[23296] MM5 [4960](R) DB: Deleting prepared query: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( (Songs.SongPath like '%iTunes Media\Music%') AND Songs.TrackType in (0) )	
00000304	9.49520683	[23296] MM5 [4960](R) DB: Deleting prepared query: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ((Songs.SongPath like '%Music Collection\Artist Albums%') OR (Songs.SongPath like '%Music Collection\Various Artists%') OR (Songs.SongPath like '%Music Collection\Classical%')) )	
00000305	9.49580097	[23296] MM5 [4960](R) DB: Deleting prepared query: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ((Songs.SongPath like '%Music Collection\Artist Album%') OR (Songs.SongPath like '%Music Collection\Various Artists%') OR (Songs.SongPath like '%Music Collection\Classical%')) )	
00000306	9.49612427	[23296] MM5 [4960](R) DB: Deleting prepared query: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,1,2) AND NOT (Songs.SongPath like '%Artist Albums%') AND NOT (Songs.SongPath like '%Various Artists%') AND NOT (Songs.SongPath like '%Unorganised%') )	
00000307	9.49930286	[23296] MM5 [7956](R) TStretcher.PerformStretch: Threads[3], source[105, 104], target[93,92]	
00000308	9.50004292	[23296] MM5 [7956](R) BQ: Currently running 2 threads of 10	
00000309	9.50114822	[23296] MM5 [19312](R) BQ: Thread[19312] is executing new task now: TStretcher.PerformStretch	
00000310	9.50409317	[23296] MM5 [7956](R) BQ: Task took 844 ms, stack: 	
00000311	9.69223690	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000312	9.91470337	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000313	10.03587246	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000314	10.07495880	[6908] 6400 0000000000000000 ENTER: DllCanUnloadNow	
00000315	10.13904381	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000316	10.24309444	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000317	10.34707546	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000318	10.45164680	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000319	10.55585194	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000320	10.65920544	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000321	10.88326263	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000322	10.99505329	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000323	11.10705757	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000324	11.21936131	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000325	11.33097649	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000326	11.44295406	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000327	11.55515671	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000328	11.65889740	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000329	11.76325035	[23296] MM5 [7692](R) WaitUntilClosed: Cannot open file "E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC". The process cannot access the file because it is being used by another process	
00000330	11.87474918	[23296] MM5 [7692](R) RestoreFromTagging starting.	
00000331	11.87505245	[23296] MM5 [7692](R) Tagging: Track was tagged: E:\Music Collection\Unorganised\from CD\Queen\Live At The Rainbow - March '74\01 # Procession # Queen.FLAC	
00000332	11.87527275	[23296] MM5 [7692](R) Going 'DoTag_Var'to make final callback 7692	
00000333	11.87553120	[23296] MM5 [7692](R) ********* Progress FinishIt 0	
00000334	12.50485039	[23296] MM5 [12668](R) Going 'Worker'to make final callback 12668	
00000335	12.50494671	[23296] MM5 [19312](R) Going 'Worker'to make final callback 19312

Is line 331 a confirmation that the tags were all successfully written, or if its a partial update ?

adrianmarsh · Post by **adrianmarsh** » Wed Sep 29, 2021 3:07 pm

Never mind.... I tracked it down. I used handle.exe from Sysinternals, and its turned out to be Spotify !! (Spotify.exe).
I've no idea why so I'll be reporting that as a bug up to Spotify. Why it has a lock on files is a mystery.

Once I closed Spotify, and re-ran a Update Tags I can see they've updated.

adrianmarsh · Post by **adrianmarsh** » Wed Sep 29, 2021 3:44 pm

I found a similar looking bug reported here in Spotifys forums.

Post by **Peke** » Wed Sep 29, 2021 5:16 pm

Looks like serious issue.

For such cases I use https://lockhunter.com/ as free handy application.

Metadata missing from files

Metadata missing from files

Re: Metadata missing from files

Re: Metadata missing from files

Re: Metadata missing from files

Re: Metadata missing from files

Re: Metadata missing from files

Re: Metadata missing from files