Double clicked .mmip at line ~996 (and once beforehand as well) --> The Server threw an exception. 00000000 0.00000000 [4256] Win7Tools - Windows 7 detected 00000001 0.00004544 [4256] Win7Tools - running on Windows Vista or later - initialize DWM 00000002 0.00011540 [4256] Win7Tools - Windows Vista DWM initialized 00000003 0.00015968 [4256] Win7Tools - Windows 7 DWM initialized 00000004 0.00020356 [4256] Win7Tools - Shell initialized 00000005 0.01333476 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000006 0.01583712 [4256] InstanceManager - registering class. 00000007 0.01593380 [4256] InstanceManager - creating mutex. 00000008 0.01600048 [4256] InstanceManager - creating window. 00000009 0.01659172 [4256] InstanceManager - releasing mutex. 00000010 0.01746656 [4256] Received command line: '"C:\Program Files (x86)\MediaMonkey\MediaMonkey.exe" ' 00000011 0.01773724 [4256] CheckDirectoryExistence: C:\ProgramData\MediaMonkey\Locale\ 00000012 0.01786964 [4256] Going to read brand data. 00000013 0.01799748 [4256] Going to init Application. 00000014 0.04721552 [4256] Application was inicialized 00000015 0.04747220 [4256] Version: 4.0.3.1471 00000016 0.04773380 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000017 0.06694336 [4256] Splash screen was shown. 00000018 0.19542859 [4256] Forms: Received WM_ACTIVATEAPP 00000019 0.19655472 [4256] MainForm: NC Activate + 00000020 0.45486763 [4256] User doesn't use Gold version. 00000021 0.45542413 [4256] Creating main form. 00000022 0.51749301 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000023 0.52159268 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000024 0.53161794 [4256] Going to read ini settings. 00000025 0.53221190 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\ 00000026 0.53982782 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000027 0.53996348 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000028 0.54239130 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\Temp\ 00000029 0.54244810 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\VirtualCD\ 00000030 0.54283315 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\Previews\ 00000031 0.54385501 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000032 0.54407543 [4256] DB path: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MM.DB , default: , MyDir: C:\Program Files (x86)\MediaMonkey\ 00000033 0.54868782 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\VirtualCD\ 00000034 0.54885983 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\Previews\ 00000035 0.54894811 [4256] CheckDirectoryExistence: C:\Program Files (x86)\MediaMonkey\Plugins\ 00000036 0.54903138 [4256] Plugins Folder: C:\Program Files (x86)\MediaMonkey\Plugins\ 00000037 0.54929441 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000038 0.54941308 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000039 0.54972053 [4256] Going to set a language. 00000040 0.57205689 [4256] Setting titles. 00000041 0.57223052 [4256] Strings translation: started 00000042 0.57263166 [4256] Strings translation: finished 00000043 0.57295811 [4256] Going to init tree manager. 00000044 0.57301158 [4256] TabsManager::AddTab - adding new tab 00000045 0.57308865 [4256] TTracks::Create - begin 00000046 0.57358003 [4256] TTracks::Create - AA classes prepared 00000047 0.57362682 [4256] TTracks::Create - managers prepared 00000048 0.57367635 [4256] TTracks::Create - done 00000049 0.57372898 [4256] TabsManager::AddTab - currently controller have 0 pages 00000050 0.57376617 [4256] TabsManager::AddTab - new tab position is -1 00000051 0.57380223 [4256] TabsManager::AddTab - going to add newly created tab to controller 00000052 0.57391244 [4256] TabsManager::AddTab - going to make presets to new tab 00000053 0.57395172 [4256] TabsManager::AddTab - done .. setting as current if needed 00000054 0.57401818 [4256] TabsManager::AddTab - adding new tab 00000055 0.57407385 [4256] TTracks::Create - begin 00000056 0.57459325 [4256] TTracks::Create - AA classes prepared 00000057 0.57463294 [4256] TTracks::Create - managers prepared 00000058 0.57467604 [4256] TTracks::Create - done 00000059 0.57471824 [4256] TabsManager::AddTab - it's 'New' tab 00000060 0.57476586 [4256] TabsManager::AddTab - currently controller have 1 pages 00000061 0.57480186 [4256] TabsManager::AddTab - new tab position is 0 00000062 0.57483834 [4256] TabsManager::AddTab - going to add newly created tab to controller 00000063 0.57495195 [4256] TabsManager::AddTab - going to make presets to new tab 00000064 0.57498950 [4256] TabsManager::AddTab - done .. setting as current if needed 00000065 0.58310270 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000066 0.58324379 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000067 0.59339976 [4256] Going to insert main tree nodes. 00000068 0.59413195 [4256] Updated 0 nodes in MainTree list. 00000069 0.59416133 [4256] Updated 0 nodes in SongView list. 00000070 0.59486365 [4256] Updated 0 nodes in PodcastsView list. 00000071 0.59489310 [4256] Updated 0 nodes in VSTBrowseList1 list. 00000072 0.59553832 [4256] Updated 0 nodes in VSTBrowseList2 list. 00000073 0.59572697 [4256] Updated 0 nodes in VSTBrowseList3 list. 00000074 0.59599638 [4256] Updated 0 nodes in VSTBrowseList4 list. 00000075 0.59660017 [4256] Updated 0 nodes in AlbumArtView list. 00000076 0.59803200 [4256] Going to init now playing window. 00000077 0.61641937 [4256] Now playing initialized. 00000078 0.61646402 [4256] Going to init tree history. 00000079 0.61650676 [4256] Going to init advanced error handlers. 00000080 0.61660719 [4256] Going to init covers window. 00000081 0.62206215 [4256] Going to read toolbar positions. 00000082 0.64737624 [4256] Going to load drag cursors. 00000083 0.65068781 [4256] Going to load player skin. 00000084 1.42091262 [4256] Setting PlayerSkin 00000085 1.42316842 [4256] Initializing Player Skin 00000086 1.42331934 [4256] Creating Panel Object: Rating 00000087 1.42868996 [4256] Initializing Player Skin 00000088 1.42884874 [4256] Creating StateButton Object: ContinousButton 00000089 1.42906785 [4256] Initializing Player Skin 00000090 1.42919743 [4256] Creating StateButton Object: PlaylistButton 00000091 1.42933202 [4256] Initializing Player Skin 00000092 1.42949617 [4256] Creating StateButton Object: EQButton 00000093 1.42960107 [4256] Initializing Player Skin 00000094 1.42972541 [4256] Creating StateButton Object: AutoDJButton 00000095 1.42985070 [4256] Initializing Player Skin 00000096 1.43007731 [4256] Creating StateButton Object: ShuffleButton 00000097 1.43012869 [4256] Initializing Player Skin 00000098 1.43036687 [4256] Creating Text Object: Custom2 00000099 1.43469787 [4256] Initializing Player Skin 00000100 1.43489051 [4256] Creating TrackBar Object: SeekBar 00000101 1.43568671 [4256] Initializing Player Skin 00000102 1.43589711 [4256] Creating Text Object: Custom1 00000103 1.43921888 [4256] Initializing Player Skin 00000104 1.43938565 [4256] Creating StateButton Object: StopAfterCurrent 00000105 1.44031107 [4256] Initializing Player Skin 00000106 1.44045186 [4256] Creating Button Object: NextButton 00000107 1.44125092 [4256] Initializing Player Skin 00000108 1.44137537 [4256] Creating StateButton Object: PlayPauseButton 00000109 1.44225109 [4256] Initializing Player Skin 00000110 1.44237638 [4256] Creating Button Object: PrevButton 00000111 1.44316900 [4256] Initializing Player Skin 00000112 1.44329631 [4256] Creating Button Object: StopButton 00000113 1.44426811 [4256] Initializing Player Skin 00000114 1.44646478 [4256] Creating TrackBar Object: VolumeBar 00000115 1.44792652 [4256] Initializing Player Skin 00000116 1.44808173 [4256] Creating StateButton Object: MuteButton 00000117 1.44901311 [4256] Initializing Player Skin 00000118 2.25721908 [4256] Removing file: C:\Users\Rusty\AppData\Local\Temp\44446166.tmp 00000119 2.25797796 [4256] Removal complete 00000120 2.30870438 [4256] Going to load format plug-ins. 00000121 2.30877924 [4256] TEventedAutoObject Created, Class: TSDBApplication 00000122 2.30929399 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000123 2.30976868 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Plugins\ 00000124 2.30989981 [4256] Plugins Folder: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Plugins\ 00000125 2.31002378 [4256] CheckDirectoryExistence: C:\Program Files (x86)\MediaMonkey\Plugins\ 00000126 2.31011558 [4256] Plugins Folder: C:\Program Files (x86)\MediaMonkey\Plugins\ 00000127 2.31487942 [4256] FastMM has been installed. 00000128 2.31834006 [4256] FLAC: FORMAT_Initialize(). 00000129 2.31931639 [4256] FastMM has been installed. 00000130 2.32633376 [4256] FastMM has been installed. 00000131 2.36083341 [4256] FastMM has been installed. 00000132 2.36801815 [4256] Going to check DB exists. 00000133 2.36816382 [4256] Going to check DB version. 00000134 2.38228154 [4256] TGenThread.Create: ThreadName: _DBVersionCheck , Running Threads: Internal: 0, All: 9 00000135 2.38693190 [4256] Thread '_DBVersionCheck' has started 2020 00000136 2.38702679 [4256] Thread registered, going to initialize 2020 00000137 2.38707638 [4256] Thread initialized 2020 00000138 2.38763976 [4256] Going to init DB:C:\Users\Rusty\AppData\Roaming\MediaMonkey\MM.DB 00000139 2.39513397 [4256] 2020 DB open SQL: SELECT NULL FROM sqlite_master WHERE type='table' AND name='DBInfo' 00000140 2.39537191 [4256] 2020 DB lock finished, took 4464. 00000141 2.39544415 [4256] 2020 DB open SQL: SELECT * FROM DBInfo 00000142 2.39589405 [4256] 2020 DB lock finished, took 7981. 00000143 2.39603829 [4256] 2020 DB open SQL: SELECT * FROM Medias WHERE TurnedSN<>0 00000144 2.39635944 [4256] 2020 DB lock finished, took 6516. 00000145 2.39642954 [4256] Going '_DBVersionCheck'to make final callback 2020 00000146 2.39654374 [4256] Going to do the first time actions. 00000147 2.39656544 [4256] 5520 DB open SQL: SELECT IdMedia FROM Medias WHERE DriveType = 12345 00000148 2.39683819 [4256] Thread '_DBVersionCheck'has finished 2020 00000149 2.39688897 [4256] 5520 DB lock finished, took 6255. 00000150 2.39694047 [4256] 5520 DB open SQL: SELECT IdMedia FROM Medias WHERE DriveType = 12346 00000151 2.39720917 [4256] 5520 DB lock finished, took 5367. 00000152 2.39725614 [4256] 5520 DB open SQL: SELECT IdMedia FROM Medias WHERE DriveType = 12347 00000153 2.39746761 [4256] 5520 DB lock finished, took 4097. 00000154 2.39753819 [4256] Going to start drive update thread. 00000155 2.41124821 [4256] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 9 00000156 2.41610622 [4256] Going to read DB structures. 00000157 2.41631055 [4256] 5520 DB open SQL: SELECT * FROM Medias ORDER BY Medias.IDMedia 00000158 2.41697288 [4256] 5520 DB lock finished, took 8996. 00000159 2.41706467 [4256] Thread 'UpdateDriveLetters_Var' has started 5908 00000160 2.41715097 [4256] 5520 DB open SQL: SELECT * FROM Lists WHERE IDListType = 1 ORDER BY SortOrder 00000161 2.41721058 [4256] Thread registered, going to initialize 5908 00000162 2.41732907 [4256] Thread initialized 5908 00000163 2.41738844 [4256] 5520 DB lock finished, took 5856. 00000164 2.41752315 [4256] 5520 DB open SQL: SELECT * FROM Lists WHERE IDListType = 2 ORDER BY SortOrder 00000165 2.41772842 [4256] 5520 DB lock finished, took 3908. 00000166 2.41784120 [4256] 5520 DB open SQL: SELECT * FROM Lists WHERE IDListType = 3 ORDER BY SortOrder 00000167 2.41802597 [4256] 5520 DB lock finished, took 3463. 00000168 2.41814184 [4256] 5520 DB open SQL: SELECT * FROM Lists WHERE IDListType = 4 ORDER BY SortOrder 00000169 2.41832280 [4256] 5520 DB lock finished, took 3389. 00000170 2.41863465 [4256] Going to update background. 00000171 2.44583893 [4256] Going to init help file. 00000172 2.44590306 [4256] Going to init tray icon. 00000173 2.44995928 [4256] Going to init scripts. 00000174 2.45021605 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000175 2.45031548 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Scripts\ 00000176 2.45068741 [4256] Current User Scripts Folder: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Scripts\ 00000177 2.45078015 [4256] Scripts Folder: C:\Program Files (x86)\MediaMonkey\Scripts\ 00000178 2.45113206 [4256] Scripts: Read script - &Statistics 00000179 2.45122385 [4256] Scripts: Read script - File List (&HTML) 00000180 2.45130324 [4256] Scripts: Read script - File List (&XML) 00000181 2.45138073 [4256] Scripts: Read script - File List (CS&V) 00000182 2.45145893 [4256] Scripts: Read script - File List (&Excel) 00000183 2.45155549 [4256] Scripts: Read script - Auto-&increment Track #s... 00000184 2.45171976 [4256] Scripts: Read script - &Swap Artist and Title 00000185 2.45184374 [4256] Scripts: Read script - Case Checker... 00000186 2.45193982 [4256] Scripts: Read script - Export all Playlists... 00000187 2.45196104 [4256] Scripts: Read script - Export subscribed Podcasts... 00000188 2.45218182 [4256] Going to init hotkeys. 00000189 2.45222807 [4256] Going to init hotkeys. 00000190 2.45298648 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000191 2.45309210 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Scripts\ 00000192 2.45317340 [4256] Current User Scripts Folder: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Scripts\ 00000193 2.45326114 [4256] Scripts Folder: C:\Program Files (x86)\MediaMonkey\Scripts\ 00000194 2.45659828 [4256] Hotkeys: Going to install hotkeys hook: 00000195 2.45670104 [4256] RIDEV_EXINPUTSINK registered successfuly 00000196 2.45677972 [4256] Going to set default hotkeys. 00000197 2.45688081 [4256] Going to set menu shortcuts. 00000198 2.45699263 [4256] Going to init popup menu handler. 00000199 2.45761395 [4256] 5520 DB open SQL: SELECT Source, Title, Album, Artist, Genre, Destination, BytesDownloaded, BytesTotal, StartDate, AddToLib FROM Downloads 00000200 2.45804119 [4256] 5520 DB lock finished, took 8718. 00000201 2.59391189 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\Temp\ 00000202 2.59872484 [4256] 5520 DB prepare SQL: SELECT IDGenre, GenreName FROM Genres 00000203 2.60094810 [4256] 5520 DB query prepare finished, took 2463. 00000204 2.60316539 [4256] 5520 DB open SQL: SELECT IDGenre, GenreName FROM Genres 00000205 2.60347080 [4256] 5520 DB lock finished, took 9445. 00000206 2.60617542 [4256] 5520 DB open SQL: SELECT * FROM OrganizeRules ORDER BY Pos 00000207 2.60887766 [4256] 5520 DB lock finished, took 8269. 00000208 2.61307597 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000209 2.61336422 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000210 2.64573812 [4256] 1 00000211 3.33334136 [4256] 5520 DB open SQL: SELECT Podcasts.* FROM Podcasts WHERE PodcastURL <> '' 00000212 3.33384728 [4256] 5520 DB lock finished, took 10693. 00000213 3.36687946 [4256] Main form initialized. 00000214 3.36703300 [4256] Player: Going to start plugin engine. 00000215 3.36737823 [4256] Winamp simulation window received messsage 36 00000216 3.36742091 [4256] Winamp sim - message left to the default processing 00000217 3.36745954 [4256] Winamp sim - message processing finished 00000218 3.36779904 [4256] Winamp simulation window received messsage 131 00000219 3.36783910 [4256] Winamp sim - message left to the default processing 00000220 3.36794782 [4256] Winamp sim - message processing finished 00000221 3.36801434 [4256] Winamp simulation window received messsage 1 00000222 3.36804938 [4256] Winamp sim - message left to the default processing 00000223 3.36808395 [4256] Winamp sim - message processing finished 00000224 3.36847353 [4256] Winamp Playlist sim - message 36 left to the default processing 00000225 3.36853313 [4256] Winamp Playlist sim - message 129 left to the default processing 00000226 3.36881018 [4256] Winamp Playlist sim - message 131 left to the default processing 00000227 3.36892128 [4256] Winamp Playlist sim - message 1 left to the default processing 00000228 3.36942911 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000229 3.36954665 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Plugins\ 00000230 3.36992121 [4256] Plugins Folder: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Plugins\ 00000231 3.37003541 [4256] CheckDirectoryExistence: C:\Program Files (x86)\MediaMonkey\Plugins\ 00000232 3.37012196 [4256] Plugins Folder: C:\Program Files (x86)\MediaMonkey\Plugins\ 00000233 3.37023687 [4256] Going to load DSP plugin C:\Program Files (x86)\MediaMonkey\Plugins\dsp_DeFX.dll 00000234 3.37080574 [4256] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_ape.dll 00000235 3.37198234 [4256] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_mfaudio.dll 00000236 3.37544513 [4256] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_mpc.dll 00000237 3.37730908 [4256] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_vorbis.dll 00000238 3.37805557 [4256] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wav.dll 00000239 3.38027906 [4256] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wma.dll 00000240 3.38167977 [4256] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wmp3.dll 00000241 3.38588524 [4256] Going to load output plugin C:\Program Files (x86)\MediaMonkey\Plugins\out_MMDS.dll 00000242 3.38897634 [4256] FastMM has been installed. 00000243 3.42394710 [4256] Going to load output plugin C:\Program Files (x86)\MediaMonkey\Plugins\out_WASAPI.dll 00000244 3.42624450 [4256] FastMM has been installed. 00000245 3.46027684 [4256] Going to load output plugin C:\Program Files (x86)\MediaMonkey\Plugins\out_wave.dll 00000246 3.46197343 [4256] Going to init playlist window. 00000247 3.46230912 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000248 3.46245909 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000249 3.46258068 [4256] DSP - Going to update DSP module list. 00000250 3.46262145 [4256] DSP - Module list updated. 00000251 3.48435068 [4256] Going to start auto-scanner. 00000252 3.48502493 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000253 3.48506498 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000254 3.48537803 [4256] Going to init encoding settings. 00000255 3.49287844 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000256 3.51094484 [4256] TGenThread.Create: ThreadName: PlayerEntriesUpdater_Var , Running Threads: Internal: 1, All: 14 00000257 3.51591158 [4256] Player: Setting current track: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma 00000258 3.51637983 [4256] PrepareDockedVideo: Begin 00000259 3.51680517 [4256] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false 00000260 3.51699686 [4256] PrepareDockedVideo: Reduced window or audio 00000261 3.51706243 [4256] Thread 'PlayerEntriesUpdater_Var' has started 4136 00000262 3.51714396 [4256] PrepareDockedVideo: Audio 00000263 3.51716518 [4256] Thread registered, going to initialize 4136 00000264 3.51723647 [4256] Thread initialized 4136 00000265 3.51729631 [4256] PrepareDockedVideo: End 00000266 3.52453923 [4256] Winamp simulation window received messsage 12 00000267 3.52458668 [4256] Winamp sim - message left to the default processing 00000268 3.52465844 [4256] Winamp sim - message processing finished 00000269 3.52470517 [4256] BQ: Added new task. Currently 1 tasks in queue. 00000270 3.52473950 [4256] BQ: Starting a new task. 00000271 3.52477503 [4256] BQ: Currently running 0 threads of 1 00000272 3.52480865 [4256] BQ: Starting a new thread. 00000273 3.56797528 [4256] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 2, All: 15 00000274 3.57184839 [4256] Thread 'BackgroundQueue' has started 5648 00000275 3.57413268 [4256] 5520 DB open SQL: SELECT * FROM Devices WHERE PluginName='' 00000276 3.57631898 [4256] Thread registered, going to initialize 5648 00000277 3.58073759 [4256] 5520 DB lock finished, took 18147. 00000278 3.58297920 [4256] Thread initialized 5648 00000279 3.58522582 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000280 3.58737803 [4256] Player: UpdatePlaylistEntry() starting. 00000281 3.58957338 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Plugins\ 00000282 3.59203267 [4256] Player: UpdatePlaylistEntry() Test1. 00000283 3.59647298 [4256] Plugins Folder: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Plugins\ 00000284 3.59904838 [4256] Player: UpdatePlaylistEntry() not in DB. 00000285 3.59939003 [4256] CheckDirectoryExistence: C:\Program Files (x86)\MediaMonkey\Plugins\ 00000286 3.60333252 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma 00000287 3.60383224 [4256] Plugins Folder: C:\Program Files (x86)\MediaMonkey\Plugins\ 00000288 3.60418391 [4256] 5648 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000289 3.60848737 [4256] 5648 DB query prepare finished, took 9458. 00000290 3.61210775 [4256] 5648 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000291 3.61477494 [4256] FastMM has been installed. 00000292 3.61523294 [4256] 5648 DB lock finished, took 11576. 00000293 3.61823082 [4256] 5520 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iPhone.dll' 00000294 3.62057257 [4256] FName2SongInfo - FName: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma, Mask:#%T - %A - %S, res = 11 00000295 3.62095189 [4256] 5520 DB lock finished, took 16460. 00000296 3.62113881 [4256] FName2SongInfo - FName: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma, Mask:#%T %A - %S, res = 9 00000297 3.62543273 [4256] FName2SongInfo - FName: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma, Mask:%A - %L-%T-%S, res = 7 00000298 3.62974596 [4256] FName2SongInfo - FName: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma, Mask:%X0's -%A-%S, res = 4 00000299 3.63362503 [4256] FastMM has been installed. 00000300 3.63399339 [4256] FName2SongInfo - FName: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma, Mask:%X0's%S, res = 1 00000301 3.63416696 [4256] FName2SongInfo - FName: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma, Mask:%A-%T-%S, res = 0 00000302 3.67022133 [4256] iPhone: Loading library: C:\Program Files (x86)\Common Files\Apple\Mobile Device Support\iTunesMobileDevice.dll 00000303 3.67037368 [4256] 5520 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iPod.dll' 00000304 3.67108583 [4256] 5520 DB lock finished, took 14811. 00000305 3.78170848 [4256] 5520 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iRiverH.dll' 00000306 3.78253579 [4256] 5520 DB lock finished, took 15618. 00000307 3.80653048 [4256] 5520 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_USBMass1.dll' 00000308 3.80713820 [4256] 5520 DB lock finished, took 12964. 00000309 3.81190109 [4256] FastMM has been installed. 00000310 3.86217022 [4256] 5520 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_WMDM.dll' 00000311 3.86308861 [4256] 5520 DB lock finished, took 19155. 00000312 3.86354327 [4256] WMDM: Inializing WMDM 00000313 3.86407232 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000314 3.86441493 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000315 3.86511993 [4256] WMDM: Queueing action id:0 00000316 3.86580825 [4256] WMDM: Starting action id: 0 00000317 3.86587977 [4256] WMDM: Creating Dev Mgr 00000318 3.86944795 [4256] 5520 DB prepare SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000319 3.86949754 [4256] 5520 DB query prepare finished, took 2100. 00000320 3.86961031 [4256] WMDM: Geting auth interface 00000321 3.86965346 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000322 3.86973095 [4256] WMDM: End of IU 00000323 3.87004662 [4256] WMDM: Authenticating 00000324 3.88050842 [4256] 5520 DB lock finished, took 192402. 00000325 3.88083339 [4256] WMDM: Creating Notifications 00000326 3.88192773 [4256] 5520 DB prepare SQL: SELECT * FROM Filters ORDER BY Pos 00000327 3.88212037 [4256] 5520 DB query prepare finished, took 3853. 00000328 3.88262296 [4256] 5520 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000329 3.88327670 [4256] 5520 DB lock finished, took 15762. 00000330 3.88420486 [4256] 5520 DB prepare SQL: SELECT * FROM Filters WHERE ID=? 00000331 3.88438272 [4256] 5520 DB query prepare finished, took 2390. 00000332 3.88499427 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000333 3.88557601 [4256] 5520 DB lock finished, took 6871. 00000334 3.88653445 [4256] 5520 DB prepare SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000335 3.88666058 [4256] 5520 DB query prepare finished, took 2491. 00000336 3.88672709 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000337 3.88686848 [4256] 5520 DB lock finished, took 1028. 00000338 3.88788867 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000339 3.88816381 [4256] 5520 DB lock finished, took 5270. 00000340 3.88827252 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000341 3.88833189 [4256] 5520 DB lock finished, took 460. 00000342 3.88933277 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000343 3.88956451 [4256] 5520 DB lock finished, took 4497. 00000344 3.88967609 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000345 3.88973284 [4256] 5520 DB lock finished, took 386. 00000346 3.89033890 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000347 3.89055371 [4256] 5520 DB lock finished, took 3815. 00000348 3.89066553 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000349 3.89071846 [4256] 5520 DB lock finished, took 360. 00000350 3.89127970 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000351 3.89146757 [4256] 5520 DB lock finished, took 3426. 00000352 3.89158106 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000353 3.89163852 [4256] 5520 DB lock finished, took 349. 00000354 3.89193535 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (3) ) LIMIT 1 00000355 3.89207363 [4256] 5520 DB query prepare finished, took 2183. 00000356 3.89211202 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000357 3.89225793 [4256] 5520 DB query prepare finished, took 952. 00000358 3.89227962 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (3) ) 00000359 3.89238262 [4256] 5520 DB query prepare finished, took 1030. 00000360 3.89242363 [4256] 5520 DB prepare SQL: SELECT ArtistsSongs.ID FROM ArtistsSongs, Songs WHERE ArtistsSongs.IDArtist=? AND ArtistsSongs.PersonType=1 AND ArtistsSongs.IDSong=Songs.ID AND ( Songs.TrackType in (3) ) 00000361 3.89275193 [4256] 5520 DB query prepare finished, took 1455. 00000362 3.89298081 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000363 3.89311481 [4256] 5520 DB lock finished, took 6161. 00000364 3.89677739 [4256] 5520 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000365 3.89783168 [4256] 5520 DB lock finished, took 8755. 00000366 3.89801216 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4,3) ) 00000367 3.89828420 [4256] 5520 DB query prepare finished, took 2526. 00000368 3.89834142 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000369 3.89854336 [4256] 5520 DB query prepare finished, took 1093. 00000370 3.89873528 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000371 3.89882946 [4256] 5520 DB query prepare finished, took 930. 00000372 3.89909697 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000373 3.89917469 [4256] 5520 DB query prepare finished, took 867. 00000374 3.89966655 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000375 3.89995575 [4256] 5520 DB query prepare finished, took 4779. 00000376 3.90023208 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000377 3.90031815 [4256] 5520 DB query prepare finished, took 1097. 00000378 3.90060067 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000379 3.90069699 [4256] 5520 DB query prepare finished, took 871. 00000380 3.90072250 [4256] 5520 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (3) ) LIMIT 1 00000381 3.90111732 [4256] 5520 DB lock finished, took 8005. 00000382 3.90118456 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000383 3.90153384 [4256] 5520 DB lock finished, took 6897. 00000384 3.90165854 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000385 3.90171957 [4256] 5520 DB lock finished, took 525. 00000386 3.90360951 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000387 3.90364695 [4256] 5520 DB lock finished, took 6739. 00000388 3.90387845 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000389 3.90448761 [4256] 5520 DB lock finished, took 1116. 00000390 3.90582728 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000391 3.90633154 [4256] 5520 DB lock finished, took 6149. 00000392 3.90636635 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000393 3.90654111 [4256] 5520 DB lock finished, took 1166. 00000394 3.90720201 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000395 3.90744901 [4256] 5520 DB lock finished, took 5152. 00000396 3.90761638 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000397 3.90767574 [4256] 5520 DB lock finished, took 451. 00000398 3.90826273 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000399 3.90847421 [4256] 5520 DB lock finished, took 3953. 00000400 3.90858293 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000401 3.90863752 [4256] 5520 DB lock finished, took 367. 00000402 3.90893412 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (2) ) LIMIT 1 00000403 3.90907145 [4256] 5520 DB query prepare finished, took 2232. 00000404 3.90911031 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000405 3.90919161 [4256] 5520 DB query prepare finished, took 927. 00000406 3.90922952 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (2) ) 00000407 3.90931201 [4256] 5520 DB query prepare finished, took 989. 00000408 3.90935040 [4256] 5520 DB prepare SQL: SELECT ArtistsSongs.ID FROM ArtistsSongs, Songs WHERE ArtistsSongs.IDArtist=? AND ArtistsSongs.PersonType=1 AND ArtistsSongs.IDSong=Songs.ID AND ( Songs.TrackType in (2) ) 00000409 3.90945220 [4256] 5520 DB query prepare finished, took 1451. 00000410 3.90956664 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000411 3.90982795 [4256] 5520 DB lock finished, took 4884. 00000412 3.91279030 [4256] 5520 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000413 3.91321921 [4256] 5520 DB lock finished, took 8251. 00000414 3.91425657 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4,3) ) 00000415 3.91445827 [4256] 5520 DB query prepare finished, took 2605. 00000416 3.91478348 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000417 3.91489315 [4256] 5520 DB query prepare finished, took 1129. 00000418 3.91519833 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000419 3.91530752 [4256] 5520 DB query prepare finished, took 1072. 00000420 3.91562629 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000421 3.91573191 [4256] 5520 DB query prepare finished, took 1055. 00000422 3.91603971 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000423 3.91614175 [4256] 5520 DB query prepare finished, took 1007. 00000424 3.91643906 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000425 3.91654491 [4256] 5520 DB query prepare finished, took 969. 00000426 3.91684294 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000427 3.91694522 [4256] 5520 DB query prepare finished, took 997. 00000428 3.91702175 [4256] 5520 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (2) ) LIMIT 1 00000429 3.91740203 [4256] 5520 DB lock finished, took 7225. 00000430 3.91754436 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000431 3.91773415 [4256] 5520 DB lock finished, took 3795. 00000432 3.91787386 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000433 3.91795707 [4256] 5520 DB lock finished, took 536. 00000434 3.91901755 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000435 3.91930580 [4256] 5520 DB lock finished, took 5012. 00000436 3.91944742 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000437 3.91973782 [4256] 5520 DB lock finished, took 453. 00000438 3.92075157 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000439 3.92108226 [4256] 5520 DB lock finished, took 5778. 00000440 3.92123461 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000441 3.92131972 [4256] 5520 DB lock finished, took 625. 00000442 3.92225099 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000443 3.92257142 [4256] 5520 DB lock finished, took 5595. 00000444 3.92273045 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000445 3.92281175 [4256] 5520 DB lock finished, took 533. 00000446 3.92314744 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (4) ) LIMIT 1 00000447 3.92330599 [4256] 5520 DB query prepare finished, took 2260. 00000448 3.92336011 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000449 3.92345786 [4256] 5520 DB query prepare finished, took 974. 00000450 3.92351294 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (4) ) 00000451 3.92360854 [4256] 5520 DB query prepare finished, took 942. 00000452 3.92366076 [4256] 5520 DB prepare SQL: SELECT ArtistsSongs.ID FROM ArtistsSongs, Songs WHERE ArtistsSongs.IDArtist=? AND ArtistsSongs.PersonType=1 AND ArtistsSongs.IDSong=Songs.ID AND ( Songs.TrackType in (4) ) 00000453 3.92378044 [4256] 5520 DB query prepare finished, took 1515. 00000454 3.92388034 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000455 3.92414856 [4256] 5520 DB lock finished, took 4805. 00000456 3.92683554 [4256] 5520 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000457 3.92740536 [4256] 5520 DB lock finished, took 10794. 00000458 3.92928791 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4,3) ) 00000459 3.92949319 [4256] 5520 DB query prepare finished, took 2706. 00000460 3.92982626 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000461 3.92995143 [4256] 5520 DB query prepare finished, took 1100. 00000462 3.93026090 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000463 3.93036485 [4256] 5520 DB query prepare finished, took 1070. 00000464 3.93066764 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000465 3.93077183 [4256] 5520 DB query prepare finished, took 1030. 00000466 3.93133187 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000467 3.93160033 [4256] 5520 DB query prepare finished, took 2813. 00000468 3.93216705 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000469 3.93262005 [4256] 5520 DB query prepare finished, took 2728. 00000470 3.93265033 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000471 3.93292356 [4256] 5520 DB query prepare finished, took 2887. 00000472 3.93297601 [4256] 5520 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (4) ) LIMIT 1 00000473 3.93394017 [4256] 5520 DB lock finished, took 11714. 00000474 3.93404341 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000475 3.93446755 [4256] 5520 DB lock finished, took 5561. 00000476 3.93463778 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000477 3.93466663 [4256] 5520 DB lock finished, took 555. 00000478 3.93568420 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000479 3.93594837 [4256] 5520 DB lock finished, took 5144. 00000480 3.93605852 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000481 3.93611455 [4256] 5520 DB lock finished, took 397. 00000482 3.93692160 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000483 3.93694520 [4256] 5520 DB lock finished, took 3540. 00000484 3.93706632 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000485 3.93712068 [4256] 5520 DB lock finished, took 378. 00000486 3.93799806 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000487 3.93802714 [4256] 5520 DB lock finished, took 4381. 00000488 3.93815613 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000489 3.93821383 [4256] 5520 DB lock finished, took 415. 00000490 3.93853664 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (5) ) LIMIT 1 00000491 3.93869257 [4256] 5520 DB query prepare finished, took 2430. 00000492 3.93872762 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000493 3.93881083 [4256] 5520 DB query prepare finished, took 961. 00000494 3.93886590 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (5) ) 00000495 3.93900585 [4256] 5520 DB query prepare finished, took 923. 00000496 3.93902493 [4256] 5520 DB prepare SQL: SELECT ArtistsSongs.ID FROM ArtistsSongs, Songs WHERE ArtistsSongs.IDArtist=? AND ArtistsSongs.PersonType=1 AND ArtistsSongs.IDSong=Songs.ID AND ( Songs.TrackType in (5) ) 00000497 3.93918681 [4256] 5520 DB query prepare finished, took 1646. 00000498 3.93924117 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000499 3.93980002 [4256] 5520 DB lock finished, took 10060. 00000500 3.94282174 [4256] 5520 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000501 3.94285655 [4256] 5520 DB lock finished, took 7275. 00000502 3.94391751 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4,3) ) 00000503 3.94401407 [4256] 5520 DB query prepare finished, took 2434. 00000504 3.94430947 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000505 3.94439650 [4256] 5520 DB query prepare finished, took 943. 00000506 3.94467783 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000507 3.94475651 [4256] 5520 DB query prepare finished, took 927. 00000508 3.94503927 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000509 3.94512820 [4256] 5520 DB query prepare finished, took 858. 00000510 3.94539928 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000511 3.94551945 [4256] 5520 DB query prepare finished, took 856. 00000512 3.94603062 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000513 3.94606328 [4256] 5520 DB query prepare finished, took 855. 00000514 3.94655704 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000515 3.94658375 [4256] 5520 DB query prepare finished, took 1820. 00000516 3.94663429 [4256] 5520 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (5) ) LIMIT 1 00000517 3.94713020 [4256] 5520 DB lock finished, took 7731. 00000518 3.94715261 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000519 3.94734144 [4256] 5520 DB lock finished, took 3281. 00000520 3.94747138 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000521 3.94756603 [4256] 5520 DB lock finished, took 457. 00000522 3.94857836 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000523 3.94881082 [4256] 5520 DB lock finished, took 4534. 00000524 3.94892859 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000525 3.94898725 [4256] 5520 DB lock finished, took 427. 00000526 3.94956779 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000527 3.94975519 [4256] 5520 DB lock finished, took 3508. 00000528 3.94986391 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000529 3.94991994 [4256] 5520 DB lock finished, took 332. 00000530 3.95048833 [4256] 5520 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000531 3.95100331 [4256] 5520 DB lock finished, took 10263. 00000532 3.95112967 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000533 3.95119071 [4256] 5520 DB lock finished, took 491. 00000534 3.95158792 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (6) ) LIMIT 1 00000535 3.95200133 [4256] 5520 DB query prepare finished, took 2525. 00000536 3.95202565 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000537 3.95222902 [4256] 5520 DB query prepare finished, took 1776. 00000538 3.95225573 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (6) ) 00000539 3.95271611 [4256] 5520 DB query prepare finished, took 5161. 00000540 3.95303798 [4256] 5520 DB prepare SQL: SELECT ArtistsSongs.ID FROM ArtistsSongs, Songs WHERE ArtistsSongs.IDArtist=? AND ArtistsSongs.PersonType=1 AND ArtistsSongs.IDSong=Songs.ID AND ( Songs.TrackType in (6) ) 00000541 3.95306849 [4256] 5520 DB query prepare finished, took 3559. 00000542 3.95327139 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000543 3.95364428 [4256] 5520 DB lock finished, took 6734. 00000544 3.95711422 [4256] 5520 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000545 3.95874810 [4256] 5520 DB lock finished, took 10976. 00000546 3.95929790 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4,3) ) 00000547 3.95935965 [4256] 5520 DB query prepare finished, took 2929. 00000548 3.96002102 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000549 3.96020961 [4256] 5520 DB query prepare finished, took 2544. 00000550 3.96054077 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000551 3.96064901 [4256] 5520 DB query prepare finished, took 1135. 00000552 3.96094966 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000553 3.96105027 [4256] 5520 DB query prepare finished, took 1036. 00000554 3.96134973 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000555 3.96144891 [4256] 5520 DB query prepare finished, took 1008. 00000556 3.96180892 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000557 3.96199298 [4256] 5520 DB query prepare finished, took 2198. 00000558 3.96232700 [4256] 5520 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000559 3.96255708 [4256] 5520 DB query prepare finished, took 1163. 00000560 3.96263766 [4256] 5520 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (6) ) LIMIT 1 00000561 3.96301794 [4256] 5520 DB lock finished, took 7787. 00000562 3.96395802 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000563 3.96421003 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Scripts\ 00000564 3.96476197 [4256] Current User Scripts Folder: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Scripts\ 00000565 3.96482682 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\Scripts\Auto\ 00000566 3.96514773 [4256] Scripts Folder: C:\Program Files (x86)\MediaMonkey\Scripts\ 00000567 3.96525311 [4256] CheckDirectoryExistence: C:\Program Files (x86)\MediaMonkey\Scripts\Auto\ 00000568 3.96599746 [4256] CheckDirectoryExistence: C:\ProgramData\MediaMonkey\Locale\ 00000569 3.96657372 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000570 3.96739340 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000571 3.96759295 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000572 4.01706028 [4256] WMDM: Notifications ok. 00000573 4.01722097 [4256] WMDM: Looking for device changes... 00000574 4.01725864 [4256] WMDM: Finished action id: 0 00000575 4.01907539 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000576 4.01923275 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000577 4.02057648 [4256] TabsManager::RestoreTabs - going to restore tabs 00000578 4.02138090 [4256] TabsManager::RestoreTabs - preparing tab Web 00000579 4.02161264 [4256] TabsManager::RestoreTabs - page index 0 00000580 4.02163363 [4256] TabsManager::RestoreTabs - going to load tab 0 presets 00000581 4.02655315 [4256] TabsManager::RestoreTabs - tab presets loaded 00000582 4.02658701 [4256] TabsManager::RestoreTabs - tabs are restored 00000583 4.02662325 [4256] TabsManager::RestoreTabs - ending 00000584 4.02845097 [4256] Node Web starting. 00000585 4.02849150 [4256] Node Web going to clear SongList. 00000586 4.02962732 [4256] 5520 DB prepare SQL: SELECT IDView FROM NodeViews WHERE NodeType = ? 00000587 4.02981234 [4256] 5520 DB query prepare finished, took 2516. 00000588 4.03021717 [4256] 5520 DB open SQL: SELECT IDView FROM NodeViews WHERE NodeType = ? 00000589 4.03049707 [4256] 5520 DB lock finished, took 8034. 00000590 4.03085899 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000591 4.03136492 [4256] 5520 DB lock finished, took 5969. 00000592 4.03194332 [4256] 5520 DB open SQL: SELECT IDView FROM NodeViews WHERE NodeType = ? 00000593 4.03222609 [4256] 5520 DB lock finished, took 5426. 00000594 4.03230238 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000595 4.03246021 [4256] 5520 DB lock finished, took 2820. 00000596 4.03327751 [4256] 5520 DB open SQL: SELECT IDView FROM NodeViews WHERE NodeType = ? 00000597 4.03360462 [4256] 5520 DB lock finished, took 4726. 00000598 4.03365803 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000599 4.03385067 [4256] 5520 DB lock finished, took 3030. 00000600 4.03508472 [4256] 5520 DB open SQL: SELECT IDView FROM NodeViews WHERE NodeType = ? 00000601 4.03527498 [4256] 5520 DB lock finished, took 6841. 00000602 4.03533697 [4256] 5520 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000603 4.03554869 [4256] 5520 DB lock finished, took 2982. 00000604 4.26689911 [4256] Going to fill tracklist, NodeType: 16 00000605 4.27143478 [4256] Player initialized. 00000606 4.27368116 [4256] Going to run application. 00000607 4.27617931 [4256] Forms: NormalizeTopMosts: Level: 0 00000608 4.28206444 [4256] Forms: NormalizeTopMost: Starting 00000609 4.28722477 [4256] MainForm: NC Activate - 00000610 4.31589937 [4256] Forms: Received WM_ACTIVATEAPP 00000611 4.31945276 [4256] Forms: Accepted WM_ACTIVATEAPP - 00000612 4.32332611 [4256] Forms: NormalizeTopMosts: Level: 1 00000613 4.32968855 [4256] Winamp sim - ignoring. 00000614 4.33276749 [4256] Winamp simulation window received messsage 28 00000615 4.33623981 [4256] Winamp sim - ignoring. 00000616 4.33874512 [4256] Forms: Received WM_ACTIVATEAPP 00000617 4.34104204 [4256] Forms: Accepted WM_ACTIVATEAPP + 00000618 4.34343052 [4256] Forms: RestoreTopMost: Level: 2 00000619 4.34853745 [4256] Winamp sim - ignoring. 00000620 4.35076237 [4256] Winamp simulation window received messsage 28 00000621 4.37146473 [4256] Winamp sim - ignoring. 00000622 4.37179089 [4256] Forms: RestoreTopMost: Level: 1 00000623 4.37236261 [4256] Forms: RestoreTopMost: Starting 00000624 4.37502813 [4256] MainForm: NC Activate + 00000625 4.39217901 [4256] Win7Tools - Taskbar COM object initialized 00000626 4.48578644 [4256] BQ: Added new task. Currently 1 tasks in queue. 00000627 4.48588514 [4256] BQ: Starting a new task. 00000628 4.48611355 [4256] BQ: There are threads waiting, resume one. 00000629 4.48750591 [4256] 5648 DB open SQL: SELECT Playlists.IDPlaylist, Playlists.PlaylistName FROM Playlists WHERE Playlists.IDPlaylist IN (SELECT PlaylistSongs.IDPlaylist FROM PlaylistSongs WHERE PlaylistSongs.IDSong IN (SELECT ID FROM Songs ORDER BY LastTimePlayed DESC LIMIT 200) GROUP BY PlaylistSongs.IDPlaylist) LIMIT 10 00000630 4.48755074 [4256] MainForm: NC Activate - 00000631 4.49232912 [4256] 5648 DB lock finished, took 26985. 00000632 4.49241829 [4256] MainForm: NC Activate + 00000633 4.80447340 [4256] Winamp simulation window received messsage 799 00000634 4.80640173 [4256] Winamp sim - message left to the default processing 00000635 4.80661201 [4256] Winamp sim - message processing finished 00000636 4.80782032 [4256] Winamp Playlist sim - message 799 left to the default processing 00000637 4.81188631 [4256] Winamp sim - WM_USER message 603 00000638 4.81194735 [4256] Winamp sim - WM_USER message 603 00000639 5.44103956 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000640 5.44152308 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\ 00000641 5.44163656 [4256] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00000642 5.54934645 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000643 5.54977655 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000644 5.63161230 [4256] iPhone: LoadLibrary() finished 00000645 5.63174152 [4256] iPhone: iTunesMobileDeviceSupport loaded 00000646 5.63278961 [4256] iPhone: AMSInitialize : 0 00000647 5.63549995 [4256] Assoc: RegisterIntoOS starting 00000648 5.64168978 [4256] ASL checking for logging parameters in environment variable "MediaMonkey.exe.log" 00000649 5.64172220 [4256] ASL checking for logging parameters in environment variable "asl.log" 00000650 5.64182806 [4256] ASL ignoring unknown/unsupported key/value pair OnFirstLog=command,environment 00000651 5.64216137 [4256] iPhone: AMDeviceNotificationSubscribe : 0 00000652 5.64482260 [4256] Assoc: RegisterIntoOS - autoplay handlers installed 00000653 5.64528894 [4256] Assoc: RegisterIntoOS - media client installed 00000654 5.64579630 [4256] Assoc: RegisterIntoOS - default programs installed 00000655 5.64583063 [4256] Assoc: Associating: mmip 00000656 5.64656210 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.mmip\UserChoice 00000657 5.64676523 [4256] Assoc: Associated: mmip 00000658 5.64680052 [4256] Assoc: Associating: mmdc 00000659 5.64806271 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.mmdc\UserChoice 00000660 5.64809465 [4256] Assoc: Associated: mmdc 00000661 5.64813519 [4256] Assoc: SetActionForFolders starting - action 1 00000662 5.64832258 [4256] Assoc: SetActionForFolders finished - action 1 00000663 5.64835882 [4256] Assoc: SetActionForFolders starting - action 2 00000664 5.64855242 [4256] Assoc: SetActionForFolders finished - action 2 00000665 5.64858818 [4256] Assoc: SetActionForFolders starting - action 3 00000666 5.64876842 [4256] Assoc: SetActionForFolders finished - action 3 00000667 5.64958382 [4256] Assoc: RegisterIntoOS - added ations to windows shell 00000668 5.64965773 [4256] Assoc: AssociateExts starting 00000669 5.65005302 [4256] Assoc: Associating: AAC 00000670 5.65133905 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.AAC\UserChoice 00000671 5.65136719 [4256] Assoc: Associated: AAC 00000672 5.65160561 [4256] Assoc: Associating: APE 00000673 5.65283728 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.APE\UserChoice 00000674 5.65286779 [4256] Assoc: Associated: APE 00000675 5.65290928 [4256] Assoc: Associating: APL 00000676 5.65410185 [4256] Assoc: Couldn't open: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.APL 00000677 5.65415049 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.APL\UserChoice 00000678 5.65418625 [4256] Assoc: Associated: APL 00000679 5.65435028 [4256] Assoc: Associating: ASX 00000680 5.65556240 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.ASX\UserChoice 00000681 5.65559483 [4256] Assoc: Associated: ASX 00000682 5.65563583 [4256] Assoc: Associating: AVI 00000683 5.65685320 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.AVI\UserChoice 00000684 5.65687943 [4256] Assoc: Associated: AVI 00000685 5.65692043 [4256] Assoc: Associating: CDA 00000686 5.65810537 [4256] Assoc: Couldn't open: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.CDA 00000687 5.65816307 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.CDA\UserChoice 00000688 5.65831995 [4256] Assoc: Associated: CDA 00000689 5.65836143 [4256] Assoc: Associating: DivX 00000690 5.66144943 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.DivX\UserChoice 00000691 5.66148090 [4256] Assoc: Associated: DivX 00000692 5.66156530 [4256] Assoc: Associating: F4V 00000693 5.66292858 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.F4V\UserChoice 00000694 5.66295958 [4256] Assoc: Associated: F4V 00000695 5.66300344 [4256] Assoc: Associating: FLA 00000696 5.66419792 [4256] Assoc: Couldn't open: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.FLA 00000697 5.66424942 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.FLA\UserChoice 00000698 5.66428471 [4256] Assoc: Associated: FLA 00000699 5.66432905 [4256] Assoc: Associating: FLAC 00000700 5.66554546 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.FLAC\UserChoice 00000701 5.66557550 [4256] Assoc: Associated: FLAC 00000702 5.66561985 [4256] Assoc: Associating: FLV 00000703 5.66685295 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.FLV\UserChoice 00000704 5.66688013 [4256] Assoc: Associated: FLV 00000705 5.66692209 [4256] Assoc: Associating: ifo 00000706 5.66813374 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.ifo\UserChoice 00000707 5.66815662 [4256] Assoc: Associated: ifo 00000708 5.66819859 [4256] Assoc: Associating: M1V 00000709 5.66940212 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.M1V\UserChoice 00000710 5.66943216 [4256] Assoc: Associated: M1V 00000711 5.66947126 [4256] Assoc: Associating: M2V 00000712 5.67067909 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.M2V\UserChoice 00000713 5.67070913 [4256] Assoc: Associated: M2V 00000714 5.67075062 [4256] Assoc: Associating: M3U 00000715 5.67199945 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.M3U\UserChoice 00000716 5.67203522 [4256] Assoc: Associated: M3U 00000717 5.67207956 [4256] Assoc: Associating: M3U8 00000718 5.67332363 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.M3U8\UserChoice 00000719 5.67334604 [4256] Assoc: Associated: M3U8 00000720 5.67338800 [4256] Assoc: Associating: M4A 00000721 5.67461443 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.M4A\UserChoice 00000722 5.67464018 [4256] Assoc: Associated: M4A 00000723 5.67468166 [4256] Assoc: Associating: M4B 00000724 5.67593145 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.M4B\UserChoice 00000725 5.67595577 [4256] Assoc: Associated: M4B 00000726 5.67600012 [4256] Assoc: Associating: M4P 00000727 5.67722702 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.M4P\UserChoice 00000728 5.67725134 [4256] Assoc: Associated: M4P 00000729 5.67742825 [4256] Assoc: Associating: MAC 00000730 5.67878675 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MAC\UserChoice 00000731 5.67881584 [4256] Assoc: Associated: MAC 00000732 5.67885971 [4256] Assoc: Associating: MKV 00000733 5.68013525 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MKV\UserChoice 00000734 5.68015957 [4256] Assoc: Associated: MKV 00000735 5.68033409 [4256] Assoc: Associating: MP+ 00000736 5.68157625 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MP+\UserChoice 00000737 5.68160200 [4256] Assoc: Associated: MP+ 00000738 5.68164158 [4256] Assoc: Associating: MP3 00000739 5.68287182 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MP3\UserChoice 00000740 5.68289614 [4256] Assoc: Associated: MP3 00000741 5.68293810 [4256] Assoc: Associating: MP4 00000742 5.68420410 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MP4\UserChoice 00000743 5.68434763 [4256] Assoc: Associated: MP4 00000744 5.68440437 [4256] Assoc: Associating: MPC 00000745 5.68569231 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MPC\UserChoice 00000746 5.68571472 [4256] Assoc: Associated: MPC 00000747 5.68575764 [4256] Assoc: Associating: MPE 00000748 5.68700266 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MPE\UserChoice 00000749 5.68702602 [4256] Assoc: Associated: MPE 00000750 5.68706894 [4256] Assoc: Associating: MPEG 00000751 5.68832827 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MPEG\UserChoice 00000752 5.68835258 [4256] Assoc: Associated: MPEG 00000753 5.68839693 [4256] Assoc: Associating: MPG 00000754 5.68964911 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MPG\UserChoice 00000755 5.68967485 [4256] Assoc: Associated: MPG 00000756 5.68971586 [4256] Assoc: Associating: MPP 00000757 5.69102287 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MPP\UserChoice 00000758 5.69104815 [4256] Assoc: Associated: MPP 00000759 5.69109011 [4256] Assoc: Associating: MPV 00000760 5.69232512 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.MPV\UserChoice 00000761 5.69234753 [4256] Assoc: Associated: MPV 00000762 5.69239235 [4256] Assoc: Associating: OGG 00000763 5.69362259 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.OGG\UserChoice 00000764 5.69364786 [4256] Assoc: Associated: OGG 00000765 5.69368982 [4256] Assoc: Associating: ogm 00000766 5.69490242 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.ogm\UserChoice 00000767 5.69492626 [4256] Assoc: Associated: ogm 00000768 5.69496775 [4256] Assoc: Associating: ogv 00000769 5.69634438 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.ogv\UserChoice 00000770 5.69637108 [4256] Assoc: Associated: ogv 00000771 5.69641781 [4256] Assoc: Associating: PLS 00000772 5.69766092 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.PLS\UserChoice 00000773 5.69768524 [4256] Assoc: Associated: PLS 00000774 5.69785404 [4256] Assoc: Associating: rm 00000775 5.69906998 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.rm\UserChoice 00000776 5.69909430 [4256] Assoc: Associated: rm 00000777 5.69913721 [4256] Assoc: Associating: rmvb 00000778 5.70037842 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.rmvb\UserChoice 00000779 5.70040131 [4256] Assoc: Associated: rmvb 00000780 5.70044422 [4256] Assoc: Associating: VOB 00000781 5.70165443 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.VOB\UserChoice 00000782 5.70168018 [4256] Assoc: Associated: VOB 00000783 5.70172215 [4256] Assoc: Associating: VQF 00000784 5.70291471 [4256] Assoc: Couldn't open: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.VQF 00000785 5.70296192 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.VQF\UserChoice 00000786 5.70299721 [4256] Assoc: Associated: VQF 00000787 5.70303822 [4256] Assoc: Associating: WAV 00000788 5.70427513 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.WAV\UserChoice 00000789 5.70430088 [4256] Assoc: Associated: WAV 00000790 5.70434237 [4256] Assoc: Associating: WAX 00000791 5.70678854 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.WAX\UserChoice 00000792 5.70684814 [4256] Assoc: Associated: WAX 00000793 5.70690489 [4256] Assoc: Associating: wm 00000794 5.70829821 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.wm\UserChoice 00000795 5.70832443 [4256] Assoc: Associated: wm 00000796 5.70836878 [4256] Assoc: Associating: WMA 00000797 5.70957565 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.WMA\UserChoice 00000798 5.70960522 [4256] Assoc: Associated: WMA 00000799 5.70964670 [4256] Assoc: Associating: wmv 00000800 5.71086550 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.wmv\UserChoice 00000801 5.71088457 [4256] Assoc: Associated: wmv 00000802 5.71092796 [4256] Assoc: Associating: x-divx 00000803 5.71225405 [4256] Assoc: Couldn't open: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.x-divx 00000804 5.71230078 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.x-divx\UserChoice 00000805 5.71233797 [4256] Assoc: Associated: x-divx 00000806 5.71238470 [4256] Assoc: Associating: XSPF 00000807 5.71359873 [4256] Assoc: Couldn't delete: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Explorer\FileExts\.XSPF\UserChoice 00000808 5.71362495 [4256] Assoc: Associated: XSPF 00000809 5.71379519 [4256] Assoc: AssociateExts finished 00000810 5.71445894 [4256] Going to check for a new version. 00000811 5.71460247 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000812 5.71479416 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000813 5.79410839 [4256] 5908 DB prepare SQL: SELECT ID FROM Albums WHERE Tracks=0 00000814 5.79432106 [4256] 5908 DB query prepare finished, took 2669. 00000815 5.79437780 [4256] 5908 DB open SQL: SELECT ID FROM Albums WHERE Tracks=0 00000816 5.80243826 [4256] 5908 DB lock finished, took 191484. 00000817 5.80271816 [4256] 5908 DB prepare SQL: SELECT ID FROM Artists WHERE Tracks=0 AND Albums=0 AND Authors=0 AND Conducts=0 AND Lyrics=0 AND Products=0 AND Roles=0 00000818 5.80312634 [4256] 5908 DB query prepare finished, took 7130. 00000819 5.80321026 [4256] 5908 DB open SQL: SELECT ID FROM Artists WHERE Tracks=0 AND Albums=0 AND Authors=0 AND Conducts=0 AND Lyrics=0 AND Products=0 AND Roles=0 00000820 5.80473804 [4256] 5908 DB lock finished, took 32474. 00000821 5.81799126 [4256] TGenThread.Create: ThreadName: IsSongAccessibleAsync , Running Threads: Internal: 3, All: 37 00000822 5.82552910 [4256] Thread 'IsSongAccessibleAsync' has started 5772 00000823 5.82558012 [4256] Thread registered, going to initialize 5772 00000824 5.82561922 [4256] Thread initialized 5772 00000825 5.82587385 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\30a0d1da-6908-474e-bbb5-2d33e24ae464.isma 00000826 5.82597780 [4256] 5908 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000827 5.82643318 [4256] 5908 DB query prepare finished, took 9346. 00000828 5.82651711 [4256] 5908 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000829 5.82702827 [4256] 5908 DB lock finished, took 10421. 00000830 5.82741499 [4256] GetFileInfo: Found in DB 00000831 5.82749557 [4256] Going 'UpdateDriveLetters_Var'to make final callback 5908 00000832 5.82766342 [4256] DB: Deleting prepared query: SELECT ID FROM Albums WHERE Tracks=0 00000833 5.82773590 [4256] DB: Deleting prepared query: SELECT ID FROM Artists WHERE Tracks=0 AND Albums=0 AND Authors=0 AND Conducts=0 AND Lyrics=0 AND Products=0 AND Roles=0 00000834 5.82780933 [4256] DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000835 5.82796049 [4256] Thread 'UpdateDriveLetters_Var'has finished 5908 00000836 5.82900238 [4256] Player: UpdatePlaylistEntry() starting. 00000837 5.83502531 [4256] Player: UpdatePlaylistEntry() Test1. 00000838 5.85334969 [4256] Player: UpdatePlaylistEntry() not in DB. 00000839 5.85343027 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\Linkin Park\Hybrid Theory\d1511b05-648a-4839-b69c-1fbb4f8686c5.isma 00000840 5.85348797 [4256] 4136 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000841 5.85570621 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000842 5.85573196 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00000843 5.85714769 [4256] TabsManager::RestoreTabs - going to restore tabs 00000844 5.85793877 [4256] TabsManager::RestoreTabs - preparing tab Web 00000845 5.85797691 [4256] TabsManager::RestoreTabs - page index 0 00000846 5.85801458 [4256] TabsManager::RestoreTabs - going to load tab 0 presets 00000847 5.86523724 [4256] TabsManager::RestoreTabs - tab presets loaded 00000848 5.86540556 [4256] TabsManager::RestoreTabs - preparing tab Music 00000849 5.86560440 [4256] 4136 DB query prepare finished, took 284718. 00000850 5.86573887 [4256] TabsManager::AddTab - adding new tab 00000851 5.86647606 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000852 5.86712885 [4256] TTracks::Create - begin 00000853 5.86744022 [4256] 4136 DB lock finished, took 15008. 00000854 5.86748266 [4256] TTracks::Create - AA classes prepared 00000855 5.86756992 [4256] TTracks::Create - managers prepared 00000856 5.86762762 [4256] TTracks::Create - done 00000857 5.86769390 [4256] TabsManager::AddTab - currently controller have 2 pages 00000858 5.86773252 [4256] TabsManager::AddTab - new tab position is 1 00000859 5.86778307 [4256] TabsManager::AddTab - going to add newly created tab to controller 00000860 5.87104559 [4256] TabsManager::AddTab - going to make presets to new tab 00000861 5.87116909 [4256] TabsManager::AddTab - done .. setting as current if needed 00000862 5.89817238 [4256] TabsManager::RestoreTabs - page index 1 00000863 5.90129805 [4256] TabsManager::RestoreTabs - going to load tab 1 presets 00000864 5.90714169 [4256] TabsManager::RestoreTabs - tab presets loaded 00000865 5.90717173 [4256] TabsManager::RestoreTabs - tabs are restored 00000866 5.91096687 [4256] TabsManager::RestoreTabs - ending 00000867 5.94284534 [4256] GetFileInfo: Found in DB 00000868 5.94330883 [4256] Thread 'PlayerEntriesUpdater_Var' is going to callback 00000869 5.98021936 [4256] Player: UpdatePlaylistEntry() starting. 00000870 5.98041677 [4256] Player: UpdatePlaylistEntry() Test1. 00000871 5.98050356 [4256] Player: UpdatePlaylistEntry() not in DB. 00000872 5.98063993 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\AK-69\THE CARTEL FROM STREETS\9dce9d30-af9d-430e-b746-1aa10124c90f.isma 00000873 5.98080111 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000874 5.98162317 [4256] 4136 DB lock finished, took 17230. 00000875 5.98187399 [4256] GetFileInfo: Found in DB 00000876 5.98210859 [4256] Player: UpdatePlaylistEntry() starting. 00000877 5.98215151 [4256] Player: UpdatePlaylistEntry() Test1. 00000878 5.98219538 [4256] Player: UpdatePlaylistEntry() not in DB. 00000879 5.98227978 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\Phil Collins\...Hits\53e73af0-4ba8-43d0-b823-287c4dbdace5.isma 00000880 5.98238325 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000881 5.98281717 [4256] 4136 DB lock finished, took 8863. 00000882 5.98303699 [4256] GetFileInfo: Found in DB 00000883 5.98321533 [4256] Player: UpdatePlaylistEntry() starting. 00000884 5.98325586 [4256] Player: UpdatePlaylistEntry() Test1. 00000885 5.98329592 [4256] Player: UpdatePlaylistEntry() not in DB. 00000886 5.98337078 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\Led Zeppelin\Coda\bd7c5b8a-6874-410e-b68b-858bb22e4bda.isma 00000887 5.98345613 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000888 5.98403835 [4256] 4136 DB lock finished, took 11522. 00000889 5.98425865 [4256] GetFileInfo: Found in DB 00000890 5.98443222 [4256] Player: UpdatePlaylistEntry() starting. 00000891 5.98447275 [4256] Player: UpdatePlaylistEntry() Test1. 00000892 5.98536634 [4256] Player: UpdatePlaylistEntry() not in DB. 00000893 5.98541498 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\Prince\The Hitsü^The B-Sides 3\1b1f734e-d550-465c-afce-096c0499df57.isma 00000894 5.98556232 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000895 5.98614979 [4256] 4136 DB lock finished, took 12055. 00000896 5.98640966 [4256] GetFileInfo: Found in DB 00000897 5.98662663 [4256] Player: UpdatePlaylistEntry() starting. 00000898 5.98667383 [4256] Player: UpdatePlaylistEntry() Test1. 00000899 5.98672533 [4256] Player: UpdatePlaylistEntry() not in DB. 00000900 5.98680687 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\Eric Clapton\Complete Clapton\f13dbd1c-4387-48cf-990d-5b2092391faf.isma 00000901 5.98690510 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000902 5.98737717 [4256] 4136 DB lock finished, took 9431. 00000903 5.98770809 [4256] GetFileInfo: Found in DB 00000904 5.98791742 [4256] Player: UpdatePlaylistEntry() starting. 00000905 5.98796177 [4256] Player: UpdatePlaylistEntry() Test1. 00000906 5.98800564 [4256] Player: UpdatePlaylistEntry() not in DB. 00000907 5.98809862 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\Chicago\Chicago Presents The Innovative Guitar Of Terry Kath\ebe059bf-f655-4096-8910-b678b5a95be1.isma 00000908 5.98820591 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000909 5.98872328 [4256] 4136 DB lock finished, took 10354. 00000910 5.98894978 [4256] GetFileInfo: Found in DB 00000911 5.98914289 [4256] Player: UpdatePlaylistEntry() starting. 00000912 5.98918533 [4256] Player: UpdatePlaylistEntry() Test1. 00000913 5.98923016 [4256] Player: UpdatePlaylistEntry() not in DB. 00000914 5.98931646 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\A-Ha\Stay On These Roads\aacbcbd8-1e30-4bb7-93a2-8f0780562a59.isma 00000915 5.98941135 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000916 5.98988152 [4256] 4136 DB lock finished, took 9201. 00000917 5.99010229 [4256] GetFileInfo: Found in DB 00000918 5.99028730 [4256] Player: UpdatePlaylistEntry() starting. 00000919 5.99033213 [4256] Player: UpdatePlaylistEntry() Test1. 00000920 5.99037504 [4256] Player: UpdatePlaylistEntry() not in DB. 00000921 5.99045563 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\test_file01.isma 00000922 5.99054766 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000923 5.99097013 [4256] 4136 DB lock finished, took 8260. 00000924 5.99119043 [4256] GetFileInfo: Found in DB 00000925 5.99136019 [4256] Player: UpdatePlaylistEntry() starting. 00000926 5.99140549 [4256] Player: UpdatePlaylistEntry() Test1. 00000927 5.99145031 [4256] Player: UpdatePlaylistEntry() not in DB. 00000928 5.99162817 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\test_file02.isma 00000929 5.99173880 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000930 5.99212503 [4256] 4136 DB lock finished, took 7734. 00000931 5.99231482 [4256] GetFileInfo: Found in DB 00000932 5.99245501 [4256] Player: UpdatePlaylistEntry() starting. 00000933 5.99249363 [4256] Player: UpdatePlaylistEntry() Test1. 00000934 5.99283409 [4256] Player: UpdatePlaylistEntry() not in DB. 00000935 5.99296951 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\PlayReadyMytraxSample\stg_check_audio.isma 00000936 5.99327850 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000937 5.99338579 [4256] 4136 DB lock finished, took 7926. 00000938 5.99359751 [4256] GetFileInfo: Found in DB 00000939 5.99375629 [4256] Player: UpdatePlaylistEntry() starting. 00000940 5.99379253 [4256] Player: UpdatePlaylistEntry() Test1. 00000941 5.99382973 [4256] Player: UpdatePlaylistEntry() not in DB. 00000942 5.99389267 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\É+ÿeûrìG\-\8fa8c50e-9b5e-4d4d-8b3e-86106e444d6a.isma 00000943 5.99395561 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000944 5.99423790 [4256] 4136 DB lock finished, took 5477. 00000945 5.99441385 [4256] GetFileInfo: Found in DB 00000946 5.99457026 [4256] Player: UpdatePlaylistEntry() starting. 00000947 5.99461174 [4256] Player: UpdatePlaylistEntry() Test1. 00000948 5.99465227 [4256] Player: UpdatePlaylistEntry() not in DB. 00000949 5.99472189 [4256] GetFileInfo: K:\My Documents\Temp\japandrm\for_microsoft\+-¼¦-¦Ñ-¦-¦üò-+¦Ñ¦¦¦+-+ü^+-¼¦-¦Ñ-¦-¦(Äwè÷)\¦¦+¦¦+¦üF è¦î+èyû+ï+ÅW\4faea39e-b795-4168-9d16-e93ac99f6ec6.isma 00000950 5.99478579 [4256] 4136 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000951 5.99514198 [4256] 4136 DB lock finished, took 6742. 00000952 5.99965572 [4256] GetFileInfo: Found in DB 00000953 6.01573420 [4256] Going 'PlayerEntriesUpdater_Var'to make final callback 4136 00000954 6.01588202 [4256] Thread 'PlayerEntriesUpdater_Var' is going to callback 00000955 6.01620626 [4256] DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000956 6.01656723 [4256] Thread 'PlayerEntriesUpdater_Var'has finished 4136 00000957 6.48224878 [4256] Going 'IsSongAccessibleAsync'to make final callback 5772 00000958 6.48234224 [4256] Thread 'IsSongAccessibleAsync'has finished 5772 00000959 7.56678343 [4256] Going 'BackgroundQueue'to make final callback 5648 00000960 7.56698608 [4256] DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000961 7.56703377 [4256] Thread 'BackgroundQueue'has finished 5648 00000962 10.92005634 [4256] MainForm: NC Activate - 00000963 10.92046165 [4256] Forms: Received WM_ACTIVATEAPP 00000964 10.92049313 [4256] Forms: Accepted WM_ACTIVATEAPP - 00000965 10.92053890 [4256] Forms: NormalizeTopMosts: Level: 0 00000966 10.92083931 [4256] Winamp sim - ignoring. 00000967 10.92088413 [4256] Winamp simulation window received messsage 28 00000968 10.92091751 [4256] Winamp sim - ignoring. 00000969 22.27011108 [4256] IDropTarget - Play. 00000970 33.48032379 [4256] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 34 00000971 33.48942184 [4256] Thread 'UpdateDriveLetters_Var' has started 5076 00000972 33.48947906 [4256] Thread registered, going to initialize 5076 00000973 33.48951721 [4256] Thread initialized 5076 00000974 33.49143982 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000975 33.49210739 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\ 00000976 33.49281693 [4256] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00000977 33.49310303 [4256] Going 'UpdateDriveLetters_Var'to make final callback 5076 00000978 33.49318314 [4256] Thread 'UpdateDriveLetters_Var'has finished 5076 00000979 63.49571609 [4256] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 34 00000980 63.50315094 [4256] Thread 'UpdateDriveLetters_Var' has started 4964 00000981 63.50326157 [4256] Thread registered, going to initialize 4964 00000982 63.50331879 [4256] Thread initialized 4964 00000983 63.50496674 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000984 63.50545502 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\ 00000985 63.50598526 [4256] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00000986 63.50629425 [4256] Going 'UpdateDriveLetters_Var'to make final callback 4964 00000987 63.50639725 [4256] Thread 'UpdateDriveLetters_Var'has finished 4964 00000988 93.51306152 [4256] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 31 00000989 93.52155304 [4256] Thread 'UpdateDriveLetters_Var' has started 3944 00000990 93.52163696 [4256] Thread registered, going to initialize 3944 00000991 93.52168274 [4256] Thread initialized 3944 00000992 93.52387238 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00000993 93.52796936 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\ 00000994 93.52990723 [4256] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00000995 93.53031921 [4256] Going 'UpdateDriveLetters_Var'to make final callback 3944 00000996 93.53038788 [4256] Thread 'UpdateDriveLetters_Var'has finished 3944 00000997 109.09682465 [4256] IDropTarget - Play. 00000998 117.99501038 [4256] IDropTarget - Play. 00000999 123.52342224 [4256] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 32 00001000 123.53109741 [4256] Thread 'UpdateDriveLetters_Var' has started 1964 00001001 123.53115845 [4256] Thread registered, going to initialize 1964 00001002 123.53120422 [4256] Thread initialized 1964 00001003 123.53305817 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00001004 123.53378296 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\ 00001005 123.53443909 [4256] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00001006 123.53475952 [4256] Going 'UpdateDriveLetters_Var'to make final callback 1964 00001007 123.53483582 [4256] Thread 'UpdateDriveLetters_Var'has finished 1964 00001008 153.54211426 [4256] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 32 00001009 153.55003357 [4256] Thread 'UpdateDriveLetters_Var' has started 6068 00001010 153.55014038 [4256] Thread registered, going to initialize 6068 00001011 153.55018616 [4256] Thread initialized 6068 00001012 153.55476379 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00001013 153.55528259 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\ 00001014 153.55581665 [4256] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00001015 153.55612183 [4256] Going 'UpdateDriveLetters_Var'to make final callback 6068 00001016 153.55621338 [4256] Thread 'UpdateDriveLetters_Var'has finished 6068 00001017 154.18354797 [4256] Forms: Received WM_ACTIVATEAPP 00001018 154.18359375 [4256] Forms: Accepted WM_ACTIVATEAPP + 00001019 154.18362427 [4256] Forms: RestoreTopMost: Level: 1 00001020 154.18365479 [4256] Forms: RestoreTopMost: Starting 00001021 154.18409729 [4256] Winamp sim - ignoring. 00001022 154.18414307 [4256] Winamp simulation window received messsage 28 00001023 154.18418884 [4256] Winamp sim - ignoring. 00001024 154.18865967 [4256] MainForm: NC Activate + 00001025 158.51786804 [4256] We were asked to finish. 00001026 158.51849365 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00001027 158.51869202 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00001028 158.52140808 [4256] We are really going to finish. 00001029 158.52786255 [4256] MainForm: NC Activate - 00001030 158.52839661 [4256] Close auto-tag. 00001031 158.52891541 [4256] _Stop: started 00001032 158.53648376 [4256] _Stop: finished 00001033 158.58886719 [4256] Playback stopped. 00001034 158.58894348 [4256] Termination of all threads started. 00001035 158.58903503 [4256] There is currently 0 threads running. 00001036 158.58908081 [4256] All threads were asked to terminate. 00001037 158.58912659 [4256] All threads really terminated. 00001038 158.58915710 [4256] Termination of all threads finished. 00001039 158.59495544 [4256] Terminating device handlers. 00001040 158.59503174 [4256] Devices: Terminating C:\Program Files (x86)\MediaMonkey\Plugins\d_iPhone.dll 00001041 158.59509277 [4256] iPod: UpdateDeviceState 00001042 158.59515381 [4256] iPod: Disconnected 00001043 158.59658813 [4256] Devices: fQuit called 00001044 158.59663391 [4256] Devices: Terminating C:\Program Files (x86)\MediaMonkey\Plugins\d_iPod.dll 00001045 158.59666443 [4256] iPod: UpdateDeviceState 00001046 158.59669495 [4256] iPod: Disconnected 00001047 158.59674072 [4256] Devices: fQuit called 00001048 158.59678650 [4256] Devices: Terminating C:\Program Files (x86)\MediaMonkey\Plugins\d_iRiverH.dll 00001049 158.59707642 [4256] Devices: fQuit called 00001050 158.59713745 [4256] Devices: Terminating C:\Program Files (x86)\MediaMonkey\Plugins\d_USBMass1.dll 00001051 158.59732056 [4256] Devices: fQuit called 00001052 158.59735107 [4256] Devices: Terminating C:\Program Files (x86)\MediaMonkey\Plugins\d_WMDM.dll 00001053 158.59741211 [4256] WMDM: Closing WMDM 00001054 158.59747314 [4256] WMDM: Queueing action id:0 00001055 158.59750366 [4256] WMDM: Waiting for queued actions to terminate 00001056 158.59762573 [4256] WMDM: Starting action id: 0 00001057 158.59770203 [4256] WMDM: Closing Notifications 00001058 158.59786987 [4256] WMDM: Closing Notifications 2 00001059 158.59794617 [4256] WMDM: Closing Notifications 3 00001060 158.59800720 [4256] WMDM: Waiting for queued actions to terminate 00001061 158.59803772 [4256] WMDM: Closing Dev manager 00001062 158.60064697 [4256] WMDM: All is terminated 00001063 158.60072327 [4256] WMDM: Finished action id: 0 00001064 159.60169983 [4256] WMDM: All is finished 00001065 159.60173035 [4256] Devices: fQuit called 00001066 159.60182190 [4256] Devices: Unloading C:\Program Files (x86)\MediaMonkey\Plugins\d_WMDM.dll 00001067 159.60188293 [4256] WMDM: Finalization started 00001068 159.60194397 [4256] WMDM: Finalization finished 00001069 159.60466003 [4256] FastMM has been uninstalled. 00001070 159.60580444 [4256] Devices: Unloading C:\Program Files (x86)\MediaMonkey\Plugins\d_USBMass1.dll 00001071 159.60612488 [4256] Devices: Unloading C:\Program Files (x86)\MediaMonkey\Plugins\d_iRiverH.dll 00001072 159.60668945 [4256] Devices: Unloading C:\Program Files (x86)\MediaMonkey\Plugins\d_iPod.dll 00001073 159.60894775 [4256] FastMM has been uninstalled. 00001074 159.60960388 [4256] Devices: Unloading C:\Program Files (x86)\MediaMonkey\Plugins\d_iPhone.dll 00001075 159.60986328 [4256] FastMM has been uninstalled. 00001076 159.61035156 [4256] Revoking global interface. 00001077 159.61039734 [4256] Events: Unregistering all events. 00001078 159.61044312 [4256] Events: Clearing Object store. 00001079 159.61100769 [4256] Terminate auto-scanning. 00001080 159.61106873 [4256] Clear songlist. 00001081 159.61114502 [4256] Going to close UPnP 00001082 159.61117554 [4256] Terminate player. 00001083 159.61122131 [4256] Going to save the main playlist. 00001084 159.61166382 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00001085 159.61184692 [4256] TM3UWriter.WriteToFile C:\Users\Rusty\AppData\Roaming\MediaMonkey\mediamonkey.m3u8 00001086 159.61392212 [4256] Going to close album browser. 00001087 159.61393738 [4256] Going to write settings. 00001088 159.61474609 [4256] Cleaning registry storage 00001089 159.63087463 [4256] Going to save tab #0 00001090 159.65315247 [4256] Saved tab #0 00001091 159.65318298 [4256] Going to save tab #1 00001092 159.75604248 [4256] Saved tab #1 00001093 159.75630188 [4256] Tabs saved. 00001094 159.76776123 [4256] Track browser closed. 00001095 159.76780701 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00001096 159.76824951 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00001097 159.83380127 [4256] CheckDirectoryExistence: C:\Program Files (x86)\MediaMonkey\Plugins\ 00001098 159.83401489 [4256] Plugins Folder: C:\Program Files (x86)\MediaMonkey\Plugins\ 00001099 159.83406067 [4256] CheckDirectoryExistence: C:\Program Files (x86)\MediaMonkey\Plugins\ 00001100 159.83413696 [4256] Plugins Folder: C:\Program Files (x86)\MediaMonkey\Plugins\ 00001101 159.83418274 [4256] CheckDirectoryExistence: C:\Program Files (x86)\MediaMonkey\Plugins\ 00001102 159.83425903 [4256] Plugins Folder: C:\Program Files (x86)\MediaMonkey\Plugins\ 00001103 159.97795105 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00001104 159.97814941 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00001105 159.97857666 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\VirtualCD\ 00001106 159.97924805 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Local\MediaMonkey\Previews\ 00001107 159.99229431 [4256] Going to hide equalizer. 00001108 159.99235535 [4256] Going to free playlist window. 00001109 160.04913330 [4256] Removing Cover manager. 00001110 160.04919434 [4256] 5520 DB exec SQL: DELETE FROM Downloads 00001111 160.04924011 [4256] 5520 DB lock successful. 00001112 160.15760803 [4256] 5520 DB lock finished, took 2700528. 00001113 160.15771484 [4256] Removing Error Handlers. 00001114 160.15808105 [4256] Going to free InfoPopup and InfoPopupPreview. 00001115 160.15856934 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\ 00001116 160.15867615 [4256] CheckDirectoryExistence: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MediaMonkey.ini 00001117 160.17277527 [4256] Removing shell hook. 00001118 160.17692566 [4256] Going to free player. 00001119 160.17759705 [4256] Player: Going to remove player plugins object. 00001120 160.17834473 [4256] _Stop: started 00001121 160.17893982 [4256] _Stop: finished 00001122 160.18699646 [4256] TWAPluginEngine.Stop - going to stop input plugin 00001123 160.18702698 [4256] SynchronizingAfterTagging finished 00001124 160.18705750 [4256] Going to stop. 00001125 160.18710327 [4256] TWAPluginEngine.Stop - input plugin stopped 00001126 160.18717957 [4256] Going to quit DSP plugin C:\Program Files (x86)\MediaMonkey\Plugins\dsp_DeFX.dll 00001127 160.18722534 [4256] Going to quit output plugin C:\Program Files (x86)\MediaMonkey\Plugins\out_wave.dll 00001128 160.18725586 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\out_wave.dll 00001129 160.18736267 [4256] Going to quit output plugin C:\Program Files (x86)\MediaMonkey\Plugins\out_WASAPI.dll 00001130 160.18739319 [4256] WASAPI: Terminating 00001131 160.18743896 [4256] WASAPI: All actions finished 00001132 160.18746948 [4256] WASAPI: All data destoyed 00001133 160.18750000 [4256] WASAPI: MMInterf disconnected 00001134 160.18757629 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\out_WASAPI.dll 00001135 160.18959045 [4256] FastMM has been uninstalled. 00001136 160.19032288 [4256] Going to quit output plugin C:\Program Files (x86)\MediaMonkey\Plugins\out_MMDS.dll 00001137 160.19035339 [4256] MMDS: Terminating 00001138 160.19039917 [4256] MMDS: All actions finished 00001139 160.19042969 [4256] MMDS: All data destoyed 00001140 160.19047546 [4256] TEventedAutoObject Destroying, Class: TSDBApplication 00001141 160.19052124 [4256] MMDS: MMInterf disconnected 00001142 160.19055176 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\out_MMDS.dll 00001143 160.19261169 [4256] FastMM has been uninstalled. 00001144 160.19319153 [4256] Going to quit input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wmp3.dll 00001145 160.19323730 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\in_wmp3.dll 00001146 160.19331360 [4256] INWMP3: Close() started 00001147 160.19334412 [4256] INWMP3: Close() 1 00001148 160.19337463 [4256] INWMP3: Close() 2 00001149 160.19340515 [4256] INWMP3: Close() 3 00001150 160.19345093 [4256] INWMP3: Close() finished 00001151 160.19404602 [4256] Going to quit input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wma.dll 00001152 160.19407654 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\in_wma.dll 00001153 160.19432068 [4256] Going to quit input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wav.dll 00001154 160.19435120 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\in_wav.dll 00001155 160.19465637 [4256] Going to quit input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_vorbis.dll 00001156 160.19470215 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\in_vorbis.dll 00001157 160.19479370 [4256] Going to quit input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_mpc.dll 00001158 160.19482422 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\in_mpc.dll 00001159 160.19491577 [4256] Going to quit input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_mfaudio.dll 00001160 160.19593811 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\in_mfaudio.dll 00001161 160.19703674 [4256] Going to quit input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_ape.dll 00001162 160.19708252 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\in_ape.dll 00001163 160.19883728 [4256] Player: Going to remove player scanning object. 00001164 160.19889832 [4256] Player: Going to remove player entries. 00001165 160.19923401 [4256] Going to close pop-up windows. 00001166 160.20103455 [4256] Going to destroy the main form. 00001167 160.20120239 [4256] Going to free format plugins. 00001168 160.20127869 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_WMV.dll 00001169 160.20179749 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_wave.dll 00001170 160.20216370 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_video.dll 00001171 160.20223999 [4256] f_video: DLL_PROCESS_DETACH 00001172 160.20249939 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_ogg.dll 00001173 160.20269775 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_MPG.dll 00001174 160.20288086 [4256] FastMM has been uninstalled. 00001175 160.20312500 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_mpc.dll 00001176 160.20581055 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_MP4.dll 00001177 160.20602417 [4256] FastMM has been uninstalled. 00001178 160.20629883 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_mkv.dll 00001179 160.20632935 [4256] 00001180 160.20704651 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_FLV.dll 00001181 160.21009827 [4256] FastMM has been uninstalled. 00001182 160.21041870 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_flac_codec.dll 00001183 160.21142578 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_flac.dll 00001184 160.21177673 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_AVI.dll 00001185 160.21197510 [4256] FastMM has been uninstalled. 00001186 160.21226501 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_ape.dll 00001187 160.21264648 [4256] Going to unload C:\Program Files (x86)\MediaMonkey\Plugins\f_aac.dll 00001188 160.21292114 [4256] Going to free playlist structure. 00001189 160.21296692 [4256] Going to free Destroyer. 00001190 160.21299744 [4256] Going to free all remaining structures. 00001191 160.21305847 [4256] Going to terminate SkinVisualization. 00001192 160.36555481 [4256] Closing database. 00001193 160.36560059 [4256] DB: Deleting prepared query: SELECT IDGenre, GenreName FROM Genres 00001194 160.36567688 [4256] DB: Deleting prepared query: SELECT ID, Name, View FROM Views ORDER BY ID 00001195 160.36573792 [4256] DB: Deleting prepared query: SELECT * FROM Filters ORDER BY Pos 00001196 160.36579895 [4256] DB: Deleting prepared query: SELECT * FROM Filters WHERE ID=? 00001197 160.36584473 [4256] DB: Deleting prepared query: SELECT ID, Name, View FROM Views WHERE ID = ? 00001198 160.36589050 [4256] DB: Deleting prepared query: SELECT IDView FROM NodeViews WHERE NodeType = ? 00001199 160.37229919 [4256] Going to finish application. 00001200 160.37843323 [4256] InstanceManager - window destroyed 00001201 160.37863159 [4256] TBackgroundQueue: Still waiting for finishing 0 threads. 00001202 160.38406372 [4256] TBackgroundQueue: Destroying...