In this log, files were downloading from a podcast, and at around line 1750, searches / node switches were initiated, and the 'hourglass' appeared immediately. After a bit more time, the log stopped as well. When MM was closed (using the 'x'), Win7 gave an error message about it experiencing a problem, and no further logging occurred. 00000000 0.00000000 [3056] Win7Tools - Windows 7 detected 00000001 0.00063628 [3056] Win7Tools - running on Windows Vista or later - initialize DWM 00000002 0.00082456 [3056] Win7Tools - Windows Vista DWM initialized 00000003 0.00089352 [3056] Win7Tools - Windows 7 DWM initialized 00000004 0.00095544 [3056] Win7Tools - Shell initialized 00000005 0.02158332 [3056] InstanceManager - registering class. 00000006 0.02168100 [3056] InstanceManager - creating mutex. 00000007 0.02175428 [3056] InstanceManager - creating window. 00000008 0.02300172 [3056] InstanceManager - releasing mutex. 00000009 0.02309028 [3056] Received command line: '"C:\Program Files (x86)\MediaMonkey4\MediaMonkey.exe" ' 00000010 0.02327976 [3056] Going to read brand data. 00000011 0.02330868 [3056] Going to init Application. 00000012 0.05840792 [3056] Application was inicialized 00000013 0.05877992 [3056] Version: 4.0.0.1351 00000014 0.08221884 [3056] Splash screen was shown. 00000015 0.28284365 [3056] Forms: Received WM_ACTIVATEAPP 00000016 0.29065284 [3056] MainForm: NC Activate + 00000017 3.12682462 [3056] Creating main form. 00000018 3.22890949 [3056] Going to read ini settings. 00000019 3.24274421 [3056] DB path: C:\Users\Rusty\AppData\Roaming\MediaMonkey\MM.DB , default: , MyDir: C:\Program Files (x86)\MediaMonkey4\ 00000020 3.24342537 [3056] User uses Gold version (registered user) 00000021 3.25510120 [3056] Going to set a language. 00000022 3.26561761 [3056] Setting titles. 00000023 3.26564574 [3056] Strings translation: started 00000024 3.28092599 [3056] Strings translation: finished 00000025 3.29617739 [3056] Going to init tree manager. 00000026 3.35684180 [3056] Going to insert main tree nodes. 00000027 3.36580443 [3056] Updated 0 nodes in MainTree list. 00000028 3.36673450 [3056] Updated 0 nodes in SongView list. 00000029 3.36676764 [3056] Updated 0 nodes in PodcastsView list. 00000030 3.36739707 [3056] Updated 0 nodes in VSTBrowseList1 list. 00000031 3.36761761 [3056] Updated 0 nodes in VSTBrowseList2 list. 00000032 3.36781216 [3056] Updated 0 nodes in VSTBrowseList3 list. 00000033 3.36882854 [3056] Updated 0 nodes in VSTBrowseList4 list. 00000034 3.36911225 [3056] Updated 0 nodes in AlbumArtView list. 00000035 3.37098408 [3056] Going to init now playing window. 00000036 3.38966775 [3056] Now playing initialized. 00000037 3.38982534 [3056] Going to init tree history. 00000038 3.38996124 [3056] Going to init advanced error handlers. 00000039 3.39010882 [3056] Going to init covers window. 00000040 3.39490294 [3056] Going to read toolbar positions. 00000041 3.41654325 [3056] Going to load drag cursors. 00000042 3.42327070 [3056] Going to load player skin. 00000043 4.30150652 [3056] Setting PlayerSkin 00000044 4.30327415 [3056] Initializing Player Skin 00000045 4.30376148 [3056] Creating Panel Object: Rating 00000046 4.31125307 [3056] Initializing Player Skin 00000047 4.31183958 [3056] Creating StateButton Object: ContinousButton 00000048 4.31202126 [3056] Initializing Player Skin 00000049 4.31221914 [3056] Creating StateButton Object: PlaylistButton 00000050 4.31233692 [3056] Initializing Player Skin 00000051 4.31247663 [3056] Creating StateButton Object: EQButton 00000052 4.31250715 [3056] Initializing Player Skin 00000053 4.31261158 [3056] Creating StateButton Object: AutoDJButton 00000054 4.31274128 [3056] Initializing Player Skin 00000055 4.31285620 [3056] Creating StateButton Object: ShuffleButton 00000056 4.31297731 [3056] Initializing Player Skin 00000057 4.31309271 [3056] Creating Text Object: Custom2 00000058 4.31801891 [3056] Initializing Player Skin 00000059 4.31811285 [3056] Creating TrackBar Object: SeekBar 00000060 4.31908417 [3056] Initializing Player Skin 00000061 4.31951761 [3056] Creating Text Object: Custom1 00000062 4.32408381 [3056] Initializing Player Skin 00000063 4.32464123 [3056] Creating Button Object: StopAfterCurrent 00000064 4.32528591 [3056] Initializing Player Skin 00000065 4.32555199 [3056] Creating Button Object: NextButton 00000066 4.32766151 [3056] Initializing Player Skin 00000067 4.32877254 [3056] Creating StateButton Object: PlayPauseButton 00000068 4.32959652 [3056] Initializing Player Skin 00000069 4.32978582 [3056] Creating Button Object: PrevButton 00000070 4.33097935 [3056] Initializing Player Skin 00000071 4.33104849 [3056] Creating Button Object: StopButton 00000072 4.33192492 [3056] Initializing Player Skin 00000073 4.33251333 [3056] Creating TrackBar Object: VolumeBar 00000074 4.33434916 [3056] Initializing Player Skin 00000075 4.33477688 [3056] Creating StateButton Object: MuteButton 00000076 4.33530569 [3056] Initializing Player Skin 00000077 5.23827982 [3056] Removing file: C:\Users\Rusty\AppData\Local\Temp\134773007.tmp 00000078 5.24338436 [3056] Removal complete 00000079 5.30461168 [3056] Going to load format plug-ins. 00000080 5.33026123 [3056] FastMM has been installed. 00000081 5.36391211 [3056] FastMM has been installed. 00000082 5.38137960 [3056] FastMM has been installed. 00000083 5.40343952 [3056] Going to check DB exists. 00000084 5.40363789 [3056] Going to init DB. 00000085 5.40379763 [3056] DB - Going to Open. 00000086 5.40388918 [3056] DB - Opened. 00000087 5.40625668 [3056] Going to check DB version. 00000088 5.40923214 [3056] Thread has started 5416 00000089 5.40959024 [3056] Thread registered, going to initialize 5416 00000090 5.41100216 [3056] Thread initialized 5416 00000091 5.41509056 [3056] MMModuleInit 00000092 5.43905926 [3056] 5416 DB open SQL: SELECT NULL FROM sqlite_master WHERE type='table' AND name='DBInfo' 00000093 5.43954706 [3056] 5416 DB lock finished, took 7553. 00000094 5.43965673 [3056] 5416 DB open SQL: SELECT * FROM DBInfo 00000095 5.44007826 [3056] 5416 DB lock finished, took 7772. 00000096 5.44050312 [3056] 5416 DB open SQL: SELECT * FROM Medias WHERE TurnedSN<>0 00000097 5.44128132 [3056] 5416 DB lock finished, took 17715. 00000098 5.44142723 [3056] Going to make final callback 5416 00000099 5.44149971 [3056] Going to do the first time actions. 00000100 5.44172430 [3056] Thread has finished 5416 00000101 5.44220734 [3056] 6684 DB open SQL: SELECT IdMedia FROM Medias WHERE DriveType = 12345 00000102 5.44229364 [3056] 6684 DB lock finished, took 9262. 00000103 5.44266129 [3056] 6684 DB open SQL: SELECT IdMedia FROM Medias WHERE DriveType = 12346 00000104 5.44270229 [3056] 6684 DB lock finished, took 7138. 00000105 5.44274712 [3056] 6684 DB open SQL: SELECT IdMedia FROM Medias WHERE DriveType = 12347 00000106 5.44326735 [3056] 6684 DB lock finished, took 9834. 00000107 5.44335747 [3056] Going to start drive update thread. 00000108 5.44822073 [3056] Going to read DB structures. 00000109 5.44824982 [3056] 6684 DB open SQL: SELECT * FROM Medias ORDER BY Medias.IDMedia 00000110 5.44899082 [3056] Thread has started 6324 00000111 5.44970369 [3056] 6684 DB lock finished, took 12037. 00000112 5.45099497 [3056] Thread registered, going to initialize 6324 00000113 5.45169353 [3056] 6684 DB open SQL: SELECT * FROM Lists WHERE IDListType = 1 ORDER BY SortOrder 00000114 5.45188046 [3056] Thread initialized 6324 00000115 5.45285654 [3056] 6684 DB lock finished, took 12235. 00000116 5.45288181 [3056] 6684 DB open SQL: SELECT * FROM Lists WHERE IDListType = 2 ORDER BY SortOrder 00000117 5.45329809 [3056] 6684 DB lock finished, took 8282. 00000118 5.45374870 [3056] 6684 DB open SQL: SELECT * FROM Lists WHERE IDListType = 3 ORDER BY SortOrder 00000119 5.45405817 [3056] 6684 DB lock finished, took 6167. 00000120 5.45445681 [3056] 6684 DB open SQL: SELECT * FROM Lists WHERE IDListType = 4 ORDER BY SortOrder 00000121 5.45475721 [3056] 6684 DB lock finished, took 5930. 00000122 5.45566034 [3056] Going to update background. 00000123 5.48678350 [3056] Going to init help file. 00000124 5.48861170 [3056] Going to init tray icon. 00000125 5.50303888 [3056] Going to init scripts. 00000126 5.50393867 [3056] Scripts: Read script - &Statistics 00000127 5.50403500 [3056] Scripts: Read script - File List (&HTML) 00000128 5.50411081 [3056] Scripts: Read script - File List (&XML) 00000129 5.50418377 [3056] Scripts: Read script - File List (CS&V) 00000130 5.50425911 [3056] Scripts: Read script - File List (&Excel) 00000131 5.50433874 [3056] Scripts: Read script - Auto-&increment Track #s... 00000132 5.50441170 [3056] Scripts: Read script - &Swap Artist and Title 00000133 5.50448704 [3056] Scripts: Read script - Case Checker... 00000134 5.50456238 [3056] Scripts: Read script - Export all Playlists... 00000135 5.50479603 [3056] Scripts: Read script - Export subscribed Podcasts... 00000136 5.50483465 [3056] Going to init hotkeys. 00000137 5.50487995 [3056] Going to init hotkeys. 00000138 5.50972557 [3056] Hotkeys: Going to install hotkeys hook: 00000139 5.50989866 [3056] RIDEV_EXINPUTSINK registered successfuly 00000140 5.51030350 [3056] Going to set default hotkeys. 00000141 5.51107216 [3056] Going to set menu shortcuts. 00000142 5.51217937 [3056] Going to init popup menu handler. 00000143 5.51290798 [3056] 6684 DB open SQL: SELECT Source, Title, Album, Artist, Genre, Destination, BytesDownloaded, BytesTotal, StartDate, AddToLib FROM Downloads 00000144 5.51373672 [3056] 6684 DB lock finished, took 25805. 00000145 5.63270378 [3056] 6684 DB prepare SQL: SELECT IDGenre, GenreName FROM Genres 00000146 5.63303995 [3056] 6684 DB query prepare finished, took 2282. 00000147 5.63305998 [3056] 6684 DB open SQL: SELECT IDGenre, GenreName FROM Genres 00000148 5.63344812 [3056] 6684 DB lock finished, took 8043. 00000149 5.63735914 [3056] 6684 DB open SQL: SELECT * FROM OrganizeRules ORDER BY Pos 00000150 5.63776684 [3056] 6684 DB lock finished, took 8524. 00000151 5.67214346 [3056] 1 00000152 5.70896912 [3056] Main form initialized. 00000153 5.71303034 [3056] Player: Going to start plugin engine. 00000154 5.71353483 [3056] Winamp simulation window received messsage 36 00000155 5.71358061 [3056] Winamp sim - message left to the default processing 00000156 5.71392012 [3056] Winamp simulation window received messsage 131 00000157 5.71394062 [3056] Winamp sim - message left to the default processing 00000158 5.71406317 [3056] Winamp simulation window received messsage 1 00000159 5.71410561 [3056] Winamp sim - message left to the default processing 00000160 5.71462917 [3056] Winamp sim - message left to the default processing 00000161 5.71467543 [3056] Winamp sim - message left to the default processing 00000162 5.71526194 [3056] Winamp sim - message left to the default processing 00000163 5.71607304 [3056] Winamp sim - message left to the default processing 00000164 5.71659517 [3056] Going to load input plugin C:\Program Files (x86)\MediaMonkey4\Plugins\in_ape.dll 00000165 5.71738577 [3056] Going to load input plugin C:\Program Files (x86)\MediaMonkey4\Plugins\in_mfaudio.dll 00000166 5.71978092 [3056] Going to load input plugin C:\Program Files (x86)\MediaMonkey4\Plugins\in_mpc.dll 00000167 5.72168684 [3056] Going to load input plugin C:\Program Files (x86)\MediaMonkey4\Plugins\in_vorbis.dll 00000168 5.72279119 [3056] Going to load input plugin C:\Program Files (x86)\MediaMonkey4\Plugins\in_wav.dll 00000169 5.72332621 [3056] Going to load input plugin C:\Program Files (x86)\MediaMonkey4\Plugins\in_wma.dll 00000170 5.72513533 [3056] Going to load input plugin C:\Program Files (x86)\MediaMonkey4\Plugins\in_wmp3.dll 00000171 5.72648239 [3056] Going to load output plugin C:\Program Files (x86)\MediaMonkey4\Plugins\out_MMDS.dll 00000172 5.72922802 [3056] FastMM has been installed. 00000173 5.77481985 [3056] Going to load output plugin C:\Program Files (x86)\MediaMonkey4\Plugins\out_WASAPI.dll 00000174 5.77710056 [3056] FastMM has been installed. 00000175 5.89411592 [3056] Going to load output plugin C:\Program Files (x86)\MediaMonkey4\Plugins\out_wave.dll 00000176 5.89988232 [3056] Going to load DSP plugin C:\Program Files (x86)\MediaMonkey4\Plugins\dsp_DeFX.dll 00000177 5.92991495 [3056] Winamp sim - WM_USER message 603 00000178 5.93686962 [3056] Winamp sim - WM_USER message 603 00000179 5.93690109 [3056] Going to init playlist window. 00000180 5.94528818 [3056] DSP - Going to update DSP module list. 00000181 5.97270346 [3056] DSP - Module list updated. 00000182 5.97344780 [3056] Going to start auto-scanner. 00000183 6.00198412 [3056] Going to init encoding settings. 00000184 6.03434706 [3056] Player: Setting current track: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000185 6.03457832 [3056] Thread has started 6440 00000186 6.03464460 [3056] UpdateSongPictures: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000187 6.03470039 [3056] Thread registered, going to initialize 6440 00000188 6.03631926 [3056] UpdateSongPictures: isFirstMediaScan, K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000189 6.03639984 [3056] Thread initialized 6440 00000190 6.03646088 [3056] UpdateSongPictures: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000191 6.03802824 [3056] UpdateSongPictures: isFirstMediaScan, K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000192 6.05309010 [3056] Winamp simulation window received messsage 12 00000193 6.08025551 [3056] Winamp sim - message left to the default processing 00000194 6.08629227 [3056] 6684 DB open SQL: SELECT * FROM Devices WHERE PluginName='' 00000195 6.09419155 [3056] 6684 DB lock finished, took 15779. 00000196 6.10146761 [3056] FastMM has been installed. 00000197 6.11191797 [3056] 6684 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iPhone.dll' 00000198 6.11887980 [3056] 6684 DB lock finished, took 14487. 00000199 6.11891794 [3056] FastMM has been installed. 00000200 6.17607498 [3056] 6684 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iPod.dll' 00000201 6.17637491 [3056] iPhone: Loading library: C:\Program Files (x86)\Common Files\Apple\Mobile Device Support\iTunesMobileDevice.dll 00000202 6.17694664 [3056] 6684 DB lock finished, took 15173. 00000203 6.43830633 [3056] 6684 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iRiverH.dll' 00000204 6.43898249 [3056] 6684 DB lock finished, took 12115. 00000205 6.48783827 [3056] 6684 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_USBMass1.dll' 00000206 6.49054289 [3056] 6684 DB lock finished, took 39604. 00000207 6.49470377 [3056] FastMM has been installed. 00000208 6.56195450 [3056] 6684 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_WMDM.dll' 00000209 6.56460476 [3056] 6684 DB lock finished, took 13446. 00000210 6.56530809 [3056] WMDM: Inializing WMDM 00000211 6.56649971 [3056] WMDM: Queueing action id:0 00000212 6.56671762 [3056] 6684 DB prepare SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000213 6.56677628 [3056] 6684 DB query prepare finished, took 2490. 00000214 6.56724501 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000215 6.56885004 [3056] 6684 DB lock finished, took 9504. 00000216 6.56906319 [3056] 6684 DB prepare SQL: SELECT * FROM Filters ORDER BY Pos 00000217 6.56912136 [3056] 6684 DB query prepare finished, took 2719. 00000218 6.57067633 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000219 6.57282162 [3056] 6684 DB lock finished, took 28514. 00000220 6.57317591 [3056] 6684 DB prepare SQL: SELECT * FROM Filters WHERE ID=? 00000221 6.57324028 [3056] 6684 DB query prepare finished, took 5145. 00000222 6.57361937 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000223 6.57377338 [3056] 6684 DB lock finished, took 7515. 00000224 6.57394648 [3056] 6684 DB prepare SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000225 6.57400274 [3056] 6684 DB query prepare finished, took 2598. 00000226 6.57420731 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000227 6.57878160 [3056] 6684 DB lock finished, took 3483. 00000228 6.57930136 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000229 6.57973957 [3056] 6684 DB lock finished, took 8439. 00000230 6.57995653 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000231 6.58066368 [3056] 6684 DB lock finished, took 3694. 00000232 6.58109999 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000233 6.58125830 [3056] 6684 DB lock finished, took 7798. 00000234 6.58147335 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000235 6.58170223 [3056] 6684 DB lock finished, took 3703. 00000236 6.58189011 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (3) ) LIMIT 1 00000237 6.58195019 [3056] 6684 DB query prepare finished, took 2706. 00000238 6.58206081 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000239 6.58211470 [3056] 6684 DB query prepare finished, took 1254. 00000240 6.58221769 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (3) ) 00000241 6.58227205 [3056] 6684 DB query prepare finished, took 1133. 00000242 6.58242035 [3056] 6684 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) ) 00000243 6.58247995 [3056] 6684 DB query prepare finished, took 2234. 00000244 6.58283520 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000245 6.58414507 [3056] 6684 DB lock finished, took 6667. 00000246 6.58468866 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000247 6.58595657 [3056] 6684 DB lock finished, took 10629. 00000248 6.58616590 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4) ) 00000249 6.58642292 [3056] 6684 DB query prepare finished, took 3078. 00000250 6.58655739 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000251 6.58676243 [3056] 6684 DB query prepare finished, took 1373. 00000252 6.58706570 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000253 6.58747959 [3056] 6684 DB query prepare finished, took 5779. 00000254 6.58751678 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ( mediaaccessible( Songs.IdMedia) OR Songs.CacheStatus=1) ) 00000255 6.58780527 [3056] 6684 DB query prepare finished, took 3221. 00000256 6.58870125 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000257 6.58902979 [3056] 6684 DB query prepare finished, took 1364. 00000258 6.58911991 [3056] WMDM: Starting action id: 0 00000259 6.58923626 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000260 6.59125614 [3056] WMDM: Creating Dev Mgr 00000261 6.59226513 [3056] 6684 DB query prepare finished, took 7058. 00000262 6.59252882 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000263 6.59279823 [3056] 6684 DB query prepare finished, took 2954. 00000264 6.59293842 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000265 6.59300661 [3056] 6684 DB query prepare finished, took 1474. 00000266 6.59354067 [3056] 6684 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (3) ) LIMIT 1 00000267 6.59366608 [3056] WMDM: Geting auth interface 00000268 6.59448910 [3056] 6684 DB lock finished, took 11230. 00000269 6.59454918 [3056] WMDM: End of IU 00000270 6.59477520 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000271 6.59570742 [3056] WMDM: Authenticating 00000272 6.59595919 [3056] 6684 DB lock finished, took 8070. 00000273 6.59622192 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000274 6.59764433 [3056] 6684 DB lock finished, took 4830. 00000275 6.59809971 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000276 6.59827089 [3056] 6684 DB lock finished, took 7567. 00000277 6.59847164 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000278 6.59983635 [3056] 6684 DB lock finished, took 2985. 00000279 6.60031223 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000280 6.60047436 [3056] 6684 DB lock finished, took 7874. 00000281 6.60206270 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000282 6.60681343 [3056] 6684 DB lock finished, took 13038. 00000283 6.60715628 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (2) ) LIMIT 1 00000284 6.60720778 [3056] 6684 DB query prepare finished, took 3098. 00000285 6.60771704 [3056] WMDM: Creating Notifications 00000286 6.60781813 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000287 6.61151600 [3056] 6684 DB query prepare finished, took 3132. 00000288 6.61249733 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (2) ) 00000289 6.61291265 [3056] 6684 DB query prepare finished, took 4329. 00000290 6.61316729 [3056] 6684 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) ) 00000291 6.61323500 [3056] 6684 DB query prepare finished, took 4245. 00000292 6.61337423 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000293 6.61378479 [3056] 6684 DB lock finished, took 8424. 00000294 6.61509848 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000295 6.61557627 [3056] 6684 DB lock finished, took 10222. 00000296 6.61811829 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4) ) 00000297 6.61828375 [3056] 6684 DB query prepare finished, took 2657. 00000298 6.61848211 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000299 6.61866808 [3056] 6684 DB query prepare finished, took 1067. 00000300 6.61886740 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000301 6.61915445 [3056] 6684 DB query prepare finished, took 1286. 00000302 6.61917496 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ( mediaaccessible( Songs.IdMedia) OR Songs.CacheStatus=1) ) 00000303 6.61928272 [3056] 6684 DB query prepare finished, took 1386. 00000304 6.61979198 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000305 6.61994934 [3056] 6684 DB query prepare finished, took 2594. 00000306 6.62014341 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000307 6.62023115 [3056] 6684 DB query prepare finished, took 1074. 00000308 6.62090731 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000309 6.62100744 [3056] 6684 DB query prepare finished, took 1499. 00000310 6.62120533 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000311 6.62135983 [3056] 6684 DB query prepare finished, took 1977. 00000312 6.62141418 [3056] 6684 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (2) ) LIMIT 1 00000313 6.62185240 [3056] 6684 DB lock finished, took 9084. 00000314 6.62230921 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000315 6.62266111 [3056] 6684 DB lock finished, took 7079. 00000316 6.62278652 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000317 6.62296915 [3056] 6684 DB lock finished, took 3338. 00000318 6.62349463 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000319 6.62380362 [3056] 6684 DB lock finished, took 6235. 00000320 6.62399721 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000321 6.62410736 [3056] 6684 DB lock finished, took 2506. 00000322 6.62448263 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000323 6.62475824 [3056] 6684 DB lock finished, took 5401. 00000324 6.62488127 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000325 6.62502766 [3056] 6684 DB lock finished, took 2385. 00000326 6.62523413 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (5) ) LIMIT 1 00000327 6.62536335 [3056] 6684 DB query prepare finished, took 2429. 00000328 6.62540340 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000329 6.62549591 [3056] 6684 DB query prepare finished, took 1137. 00000330 6.62554455 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (5) ) 00000331 6.62562227 [3056] 6684 DB query prepare finished, took 1049. 00000332 6.62567759 [3056] 6684 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) ) 00000333 6.62577915 [3056] 6684 DB query prepare finished, took 1719. 00000334 6.62628794 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000335 6.62631416 [3056] 6684 DB lock finished, took 6870. 00000336 6.62759542 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000337 6.62807941 [3056] 6684 DB lock finished, took 10067. 00000338 6.62964201 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4) ) 00000339 6.62984085 [3056] 6684 DB query prepare finished, took 3070. 00000340 6.63006973 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000341 6.63016081 [3056] 6684 DB query prepare finished, took 1119. 00000342 6.63098621 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000343 6.63108158 [3056] 6684 DB query prepare finished, took 1236. 00000344 6.63137293 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ( mediaaccessible( Songs.IdMedia) OR Songs.CacheStatus=1) ) 00000345 6.63153934 [3056] 6684 DB query prepare finished, took 2889. 00000346 6.63173628 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000347 6.63331318 [3056] 6684 DB query prepare finished, took 1128. 00000348 6.63359547 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000349 6.63400173 [3056] 6684 DB query prepare finished, took 3200. 00000350 6.63500023 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000351 6.63535690 [3056] 6684 DB query prepare finished, took 3674. 00000352 6.63566160 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000353 6.63571596 [3056] 6684 DB query prepare finished, took 1382. 00000354 6.63576078 [3056] 6684 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (5) ) LIMIT 1 00000355 6.63619566 [3056] 6684 DB lock finished, took 9046. 00000356 6.63675690 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000357 6.63743210 [3056] 6684 DB lock finished, took 10722. 00000358 6.63746500 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000359 6.63796663 [3056] 6684 DB lock finished, took 5292. 00000360 6.63826895 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000361 6.63856697 [3056] 6684 DB lock finished, took 5968. 00000362 6.63868618 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000363 6.63881969 [3056] 6684 DB lock finished, took 2223. 00000364 6.63924408 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000365 6.64033985 [3056] 6684 DB lock finished, took 21698. 00000366 6.64118338 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000367 6.64124918 [3056] 6684 DB lock finished, took 3756. 00000368 6.64212179 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (6) ) LIMIT 1 00000369 6.64216375 [3056] 6684 DB query prepare finished, took 2930. 00000370 6.64224529 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000371 6.64250135 [3056] 6684 DB query prepare finished, took 2210. 00000372 6.64264059 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (6) ) 00000373 6.64269972 [3056] 6684 DB query prepare finished, took 1381. 00000374 6.64290619 [3056] 6684 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) ) 00000375 6.64305067 [3056] 6684 DB query prepare finished, took 1895. 00000376 6.64345551 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000377 6.64471436 [3056] 6684 DB lock finished, took 7880. 00000378 6.64533329 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000379 6.64658689 [3056] 6684 DB lock finished, took 11885. 00000380 6.64678383 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4) ) 00000381 6.64727688 [3056] 6684 DB query prepare finished, took 2748. 00000382 6.64747572 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000383 6.64771843 [3056] 6684 DB query prepare finished, took 2450. 00000384 6.64783382 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000385 6.64803839 [3056] 6684 DB query prepare finished, took 1311. 00000386 6.64815187 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ( mediaaccessible( Songs.IdMedia) OR Songs.CacheStatus=1) ) 00000387 6.64835215 [3056] 6684 DB query prepare finished, took 1352. 00000388 6.64845753 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000389 6.64865685 [3056] 6684 DB query prepare finished, took 1068. 00000390 6.64875793 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000391 6.64895630 [3056] 6684 DB query prepare finished, took 1059. 00000392 6.64905643 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000393 6.64925194 [3056] 6684 DB query prepare finished, took 1045. 00000394 6.64964342 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000395 6.64972210 [3056] 6684 DB query prepare finished, took 1069. 00000396 6.65015888 [3056] 6684 DB open SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (6) ) LIMIT 1 00000397 6.65032387 [3056] 6684 DB lock finished, took 10810. 00000398 6.65242958 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000399 6.65279436 [3056] 6684 DB lock finished, took 9034. 00000400 6.74838591 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000401 6.74842119 [3056] 6684 DB lock finished, took 8232. 00000402 6.75429678 [3056] Player initialized. 00000403 6.75432777 [3056] Going to run application. 00000404 6.81871510 [3056] Win7Tools - Taskbar COM object initialized 00000405 7.12231350 [3056] MainForm: NC Activate - 00000406 7.15239620 [3056] Thread has started 6432 00000407 7.15254402 [3056] Thread registered, going to initialize 6432 00000408 7.15257359 [3056] MainForm: NC Activate + 00000409 7.15272856 [3056] Thread initialized 6432 00000410 7.16240597 [3056] 6432 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 00000411 7.16717958 [3056] 6432 DB lock finished, took 50271. 00000412 7.31021881 [3056] Going to make final callback 6432 00000413 7.31040382 [3056] Thread has finished 6432 00000414 7.31245613 [3056] WMDM: Notifications ok. 00000415 7.31260681 [3056] WMDM: Looking for device changes... 00000416 7.31268787 [3056] WMDM: Finished action id: 0 00000417 7.43899393 [3056] Winamp simulation window received messsage 799 00000418 7.43906832 [3056] Winamp sim - message left to the default processing 00000419 7.43915701 [3056] Winamp sim - message left to the default processing 00000420 7.52835846 [3056] Thread has started 2608 00000421 7.52877331 [3056] Thread registered, going to initialize 2608 00000422 7.52892208 [3056] Thread initialized 2608 00000423 7.53022671 [3056] 2608 DB open SQL: SELECT PodcastEpisodes.* FROM PodcastEpisodes WHERE NetSource = 'http://feeds.nos.nl/~r/journaal/~5/HaNN0kDOhoc/NOS_Journaal_VODcast_2011-03-02_16_59_53.mp4' AND idTrack < 0 00000424 7.53246450 [3056] 2608 DB lock finished, took 27372. 00000425 7.79219627 [3056] GetFileInfo: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000426 7.79282618 [3056] 6684 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000427 7.79291058 [3056] 6684 DB query prepare finished, took 11197. 00000428 7.79349995 [3056] 6684 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000429 7.79363346 [3056] 6684 DB lock finished, took 12112. 00000430 7.79370546 [3056] FName2SongInfo - FName: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3, Mask:#%T - %A - %S, res = 11 00000431 7.79388142 [3056] FName2SongInfo - FName: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3, Mask:#%T %A - %S, res = 9 00000432 7.79398537 [3056] FName2SongInfo - FName: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3, Mask:%T-%A - %L - %S, res = 1 00000433 7.79435349 [3056] FName2SongInfo - FName: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3, Mask:%T-%A - %S, res = 0 00000434 7.79443073 [3056] MP3.ReadHeader K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000435 7.79453611 [3056] MP3.ReadHeader - File size 4113874 00000436 7.79463816 [3056] MP3.ReadHeader - Found ID3v2, length: 40672 00000437 7.79468870 [3056] MP3.ReadHeader framestarts: 41152, 41152 00000438 7.79496431 [3056] MP3.ReadHeader CheckMoreHeaders started 00000439 7.79530811 [3056] MP3.ReadHeader CheckMoreHeaders Endtest: 262144 00000440 7.79539061 [3056] MP3.ReadHeader CheckMoreHeaders finished 00000441 7.79561234 [3056] MP3.ReadHeader finished 00000442 7.80511808 [3056] MP3: Going to read tag from K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000443 7.80532551 [3056] MP3: Tag read from K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000444 7.80612373 [3056] Thread has started 5668 00000445 7.80625391 [3056] UpdateSongPictures: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000446 7.80633545 [3056] Thread registered, going to initialize 5668 00000447 7.80638075 [3056] UpdateSongPictures: isFirstMediaScan, K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000448 7.80652714 [3056] Thread initialized 5668 00000449 7.80730867 [3056] 5668 DB open SQL: SELECT PodcastEpisodes.* FROM PodcastEpisodes WHERE NetSource = 'http://feeds.nos.nl/~r/journaal/~5/OYbl6gIYdro/NOS_Journaal_VODcast_2011-03-02_11_59_56.mp4' AND idTrack < 0 00000450 7.81039047 [3056] 5668 DB lock finished, took 15349. 00000451 7.81123209 [3056] UpdateSongPictures: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000452 7.81127024 [3056] UpdateSongPictures: isFirstMediaScan, K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000453 7.82926083 [3056] Winamp simulation window received messsage 12 00000454 7.82929802 [3056] Winamp sim - message left to the default processing 00000455 7.94772100 [3056] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00000456 7.97291756 [3056] 6324 DB prepare SQL: SELECT ID FROM Albums WHERE Tracks=0 00000457 7.97299147 [3056] 6324 DB query prepare finished, took 2992. 00000458 7.97945976 [3056] 6324 DB open SQL: SELECT ID FROM Albums WHERE Tracks=0 00000459 7.98339844 [3056] 6324 DB lock finished, took 247391. 00000460 7.98395443 [3056] 6324 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 00000461 7.98435211 [3056] 6324 DB query prepare finished, took 3147. 00000462 7.99007082 [3056] 6324 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 00000463 7.99122000 [3056] 6324 DB lock finished, took 138798. 00000464 7.99128342 [3056] GetFileInfo: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000465 7.99197626 [3056] 6324 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000466 7.99206543 [3056] 6324 DB query prepare finished, took 11244. 00000467 7.99275398 [3056] 6324 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000468 7.99311447 [3056] 6324 DB lock finished, took 14402. 00000469 7.99319506 [3056] GetFileInfo: Found in DB 00000470 7.99325132 [3056] Going to make final callback 6324 00000471 7.99331856 [3056] DB: Deleting prepared query: SELECT ID FROM Albums WHERE Tracks=0 00000472 7.99338388 [3056] 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 00000473 7.99354362 [3056] DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000474 7.99409103 [3056] Thread has finished 6324 00000475 8.00887680 [3056] Thread has started 6948 00000476 8.00894356 [3056] Thread registered, going to initialize 6948 00000477 8.00898266 [3056] Thread initialized 6948 00000478 8.27197933 [3056] GetFileInfo: K:\My Documents\My Music\Beethoven\Unknown\00 - Beethoven - 5th Symphony.mp3 00000479 8.27202892 [3056] 6440 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000480 8.27255726 [3056] 6440 DB query prepare finished, took 11442. 00000481 8.27261353 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000482 8.27322006 [3056] 6440 DB lock finished, took 13034. 00000483 8.27354717 [3056] GetFileInfo: Found in DB 00000484 8.43687344 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000485 8.43691158 [3056] 6684 DB lock finished, took 8326. 00000486 8.44787312 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000487 8.45391941 [3056] 6684 DB lock finished, took 5195. 00000488 8.47209167 [3056] Node Classical Music starting. 00000489 8.47213936 [3056] Node Classical Music going to clear SongList. 00000490 8.47314072 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000491 8.47360420 [3056] 6684 DB lock finished, took 8736. 00000492 8.47370338 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000493 8.47384644 [3056] 6684 DB lock finished, took 2402. 00000494 8.47403812 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000495 8.47426128 [3056] 6684 DB lock finished, took 4318. 00000496 8.47450352 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000497 8.47753429 [3056] 6684 DB lock finished, took 2581. 00000498 8.47762585 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000499 8.47885609 [3056] 6684 DB lock finished, took 15294. 00000500 8.47901344 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000501 8.48035622 [3056] 6684 DB lock finished, took 6176. 00000502 8.48127460 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000503 8.48160934 [3056] 6684 DB lock finished, took 10126. 00000504 8.48173714 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000505 8.48196697 [3056] 6684 DB lock finished, took 3813. 00000506 8.48214817 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (3) ) LIMIT 1 00000507 8.48229980 [3056] 6684 DB query prepare finished, took 2497. 00000508 8.48237133 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000509 8.48247051 [3056] 6684 DB query prepare finished, took 1253. 00000510 8.48250961 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (3) ) 00000511 8.48269844 [3056] 6684 DB query prepare finished, took 1006. 00000512 8.48271656 [3056] 6684 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) ) 00000513 8.48285007 [3056] 6684 DB query prepare finished, took 1909. 00000514 8.48296165 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000515 8.48331070 [3056] 6684 DB lock finished, took 7120. 00000516 8.48464966 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000517 8.48517323 [3056] 6684 DB lock finished, took 10802. 00000518 8.48640156 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4) ) 00000519 8.48656940 [3056] 6684 DB query prepare finished, took 2765. 00000520 8.48676586 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000521 8.48692131 [3056] 6684 DB query prepare finished, took 1018. 00000522 8.49348640 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000523 8.49366760 [3056] 6684 DB query prepare finished, took 3096. 00000524 8.49388313 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ( mediaaccessible( Songs.IdMedia) OR Songs.CacheStatus=1) ) 00000525 8.49397850 [3056] 6684 DB query prepare finished, took 1241. 00000526 8.49416161 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000527 8.49426270 [3056] 6684 DB query prepare finished, took 964. 00000528 8.49446392 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000529 8.49455261 [3056] 6684 DB query prepare finished, took 1069. 00000530 8.49482727 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000531 8.49491119 [3056] 6684 DB query prepare finished, took 969. 00000532 8.49505138 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000533 8.49514389 [3056] 6684 DB query prepare finished, took 1150. 00000534 8.49519444 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000535 8.49562359 [3056] 6684 DB lock finished, took 8724. 00000536 8.49575424 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000537 8.49647522 [3056] 6684 DB lock finished, took 15054. 00000538 8.53799534 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000539 8.53840542 [3056] 6684 DB lock finished, took 8359. 00000540 8.53853703 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000541 8.53868198 [3056] 6684 DB lock finished, took 2512. 00000542 8.56446552 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\01 Baba Yetu (feat. Soweto Gospel Choir).mp3 00000543 8.56450081 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000544 8.56710625 [3056] 6440 DB lock finished, took 22802. 00000545 8.56714439 [3056] GetFileInfo: Found in DB 00000546 8.60544395 [3056] Thread has started 6528 00000547 8.60548592 [3056] Thread registered, going to initialize 6528 00000548 8.60569000 [3056] Thread initialized 6528 00000549 8.61330414 [3056] 6528 DB open SQL: SELECT Songs.* 00000550 8.61330414 [3056] FROM Songs 00000551 8.61330414 [3056] WHERE ( Songs.TrackType in (3) ) 00000552 8.61723328 [3056] 6528 DB lock finished, took 30423. 00000553 8.62122440 [3056] Going to make final callback 6528 00000554 8.63628101 [3056] Thread has finished 6528 00000555 8.72760868 [3056] Assoc: AssociateOnlyExts starting 00000556 8.72767830 [3056] Assoc: PrepareAssociation starting 00000557 8.73221207 [3056] Error during file associating: Failed to set data for 'AppID' 00000558 8.73225307 [3056] Assoc: PrepareAssociation autoplay handlers installed 00000559 8.73321152 [3056] Error during file associating: Failed to set data for '' 00000560 8.73325634 [3056] Assoc: PrepareAssociation media client installed 00000561 8.73404980 [3056] Error during file associating: Failed to set data for 'ApplicationName' 00000562 8.73409462 [3056] Assoc: PrepareAssociation default programs installed 00000563 8.73413277 [3056] Assoc: Associating: mmip 00000564 8.73699570 [3056] Error during file associating: Failed to set data for '' 00000565 8.73704624 [3056] Assoc: Associating: mmdc 00000566 8.74384785 [3056] Error during file associating: Failed to set data for '' 00000567 8.74391270 [3056] Assoc: SetActionForFolders starting - action 1 00000568 8.74727440 [3056] Error during file associating: Failed to set data for '' 00000569 8.74734879 [3056] Assoc: SetActionForFolders finished - action 1 00000570 8.74739742 [3056] Assoc: SetActionForFolders starting - action 2 00000571 8.74822140 [3056] Error during file associating: Failed to set data for '' 00000572 8.74832439 [3056] Assoc: SetActionForFolders finished - action 2 00000573 8.74834919 [3056] Assoc: SetActionForFolders starting - action 3 00000574 8.74912357 [3056] Error during file associating: Failed to set data for '' 00000575 8.74917507 [3056] Assoc: SetActionForFolders finished - action 3 00000576 8.75006580 [3056] Going to check for a new version. 00000577 8.76157188 [3056] Thread has started 6504 00000578 8.76173210 [3056] Thread registered, going to initialize 6504 00000579 8.76201439 [3056] Thread initialized 6504 00000580 8.76220512 [3056] 6504 DB open SQL: SELECT PodcastEpisodes.* FROM PodcastEpisodes WHERE NetSource = 'http://feeds.nos.nl/~r/journaal/~5/BS3Wa7oXdak/NOS_Journaal_VODcast_2011-03-02_06_59_56.mp4' AND idTrack < 0 00000581 8.76392365 [3056] 6504 DB lock finished, took 36114. 00000582 8.85503769 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\02 Mado Kara Mieru (feat. Lia, Aoi Tada, Kaori Omura).mp3 00000583 8.85512352 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000584 8.85567284 [3056] 6440 DB lock finished, took 11750. 00000585 8.85591316 [3056] GetFileInfo: Found in DB 00000586 8.86036015 [3056] Thread has started 5524 00000587 8.86123562 [3056] Thread registered, going to initialize 5524 00000588 8.86129951 [3056] Thread initialized 5524 00000589 8.86140633 [3056] 5524 DB open SQL: SELECT PodcastEpisodes.* FROM PodcastEpisodes WHERE NetSource = 'http://feeds.nos.nl/~r/journaal/~5/x10ru9iYTaA/NOS_Journaal_VODcast_2011-03-01_19_59_57.mp4' AND idTrack < 0 00000590 8.86215401 [3056] 5524 DB lock finished, took 15462. 00000591 9.00132179 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\03 Dao Zai Fan Ye (feat. Jia Ruhan).mp3 00000592 9.00141144 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000593 9.00721455 [3056] 6440 DB lock finished, took 11734. 00000594 9.01616859 [3056] GetFileInfo: Found in DB 00000595 9.13756180 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\04 Se E¦? Pra Vir Que Venha (feat. Dulce Pontes).mp3 00000596 9.13764095 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000597 9.13810730 [3056] 6440 DB lock finished, took 9728. 00000598 9.13832378 [3056] GetFileInfo: Found in DB 00000599 9.21339226 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\05 Rassemblons-Nous.mp3 00000600 9.21609879 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000601 9.22736168 [3056] 6440 DB lock finished, took 25796. 00000602 9.22787666 [3056] GetFileInfo: Found in DB 00000603 9.25659180 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\06 Lux Aeterna.mp3 00000604 9.28949738 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000605 9.32477379 [3056] 6440 DB lock finished, took 15181. 00000606 9.32983017 [3056] GetFileInfo: Found in DB 00000607 9.61621857 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\07 Caoineadh (feat. Anonymous 4).mp3 00000608 9.61734295 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000609 9.62317944 [3056] 6440 DB lock finished, took 21784. 00000610 9.62529087 [3056] GetFileInfo: Found in DB 00000611 9.63720417 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\08 Hymn Do Trojcy Swietej (feat. Frederica von Stade).mp3 00000612 9.64148998 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000613 9.64379120 [3056] 6440 DB lock finished, took 11080. 00000614 9.64850330 [3056] GetFileInfo: Found in DB 00000615 9.71024799 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\09 Hayom Kadosh.mp3 00000616 9.71129799 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000617 9.71179008 [3056] 6440 DB lock finished, took 17443. 00000618 9.71270657 [3056] GetFileInfo: Found in DB 00000619 9.83879185 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\10 Hamsafar (feat. Sussan Deyhim).mp3 00000620 9.84380913 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000621 9.84460449 [3056] 6440 DB lock finished, took 8577. 00000622 9.84509087 [3056] GetFileInfo: Found in DB 00000623 9.86674786 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\11 Sukla-Krsne.mp3 00000624 9.86682796 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000625 9.86740780 [3056] 6440 DB lock finished, took 11373. 00000626 9.86762810 [3056] GetFileInfo: Found in DB 00000627 10.06147957 [3056] GetFileInfo: \\192.168.0.147\Qdownload\Calling All Dawns\12 Kia Hora Te Marino.mp3 00000628 10.06161118 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000629 10.06208229 [3056] 6440 DB lock finished, took 9841. 00000630 10.06229496 [3056] GetFileInfo: Found in DB 00000631 10.08376217 [3056] GetFileInfo: K:\My Documents\My Music\Ludwig van Beethoven, composer. Seattle Symphony. Gerard Schwarz, director\Unknown\1 - Ludwig van Beethoven, composer. Seattle Symphony. Gerard Schwarz, director - Symphony No. 9 (Scherzo).wma 00000632 10.08388329 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000633 10.08452415 [3056] 6440 DB lock finished, took 13726. 00000634 10.08471012 [3056] GetFileInfo: Found in DB 00000635 10.08482742 [3056] GetFileInfo: K:\My Documents\My Music\Ludwig van Beethoven, composer. Seattle Symphony. Gerard Schwarz, director1\1\1 - Ludwig van Beethoven, composer. Seattle Symphony. Gerard Schwarz, director1 - Symphony No. 9 (Scherzo)1.wma 00000636 10.08491993 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000637 10.08528042 [3056] 6440 DB lock finished, took 8138. 00000638 10.08553505 [3056] GetFileInfo: Found in DB 00000639 10.08563995 [3056] GetFileInfo: K:\My Documents\My Music\Mozart\Unknown\00 - Mozart - The Magic Flute.mp3 00000640 10.08571243 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000641 10.08610535 [3056] 6440 DB lock finished, took 8002. 00000642 10.08629513 [3056] GetFileInfo: Found in DB 00000643 10.08639336 [3056] GetFileInfo: K:\My Documents\My Music\Various\Top 10 of Classical Music\00 - Mozart - Rondo Alla Turca.mp3 00000644 10.08649063 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000645 10.08683300 [3056] 6440 DB lock finished, took 7768. 00000646 10.08710861 [3056] GetFileInfo: Found in DB 00000647 10.08712482 [3056] GetFileInfo: K:\My Documents\My Music\Various\Top 10 of Classical Music\00 - Mozart - Symphony No. 40, 1st movement.mp3 00000648 10.08718491 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000649 10.08762932 [3056] 6440 DB lock finished, took 9021. 00000650 10.08783150 [3056] GetFileInfo: Found in DB 00000651 10.08794212 [3056] GetFileInfo: K:\My Documents\My Music\Various\Top 10 of Classical Music\2 - Orchestra - Moonlight Sonata.mp3 00000652 10.08806705 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000653 10.08832264 [3056] 6440 DB lock finished, took 6337. 00000654 10.08858013 [3056] GetFileInfo: Found in DB 00000655 10.08864212 [3056] GetFileInfo: K:\My Documents\My Music\Various\Top 10 of Classical Music\00 - Philharmonic2 - Fur Elise.mp3 00000656 10.08870316 [3056] 6440 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000657 10.08900928 [3056] 6440 DB lock finished, took 6155. 00000658 10.08919334 [3056] GetFileInfo: Found in DB 00000659 10.08947182 [3056] UpdateSongPictures: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000660 10.08977890 [3056] GetFileInfo: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000661 10.08979893 [3056] GetFileInfo: readPictures=spAlways 00000662 10.08984280 [3056] GetFileInfo: K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000663 10.09003544 [3056] Added Cover Lists: 1 00000664 10.09021664 [3056] MP3.ReadHeader K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000665 10.09026623 [3056] MP3.ReadHeader - File size 4113874 00000666 10.09035778 [3056] MP3.ReadHeader - Found ID3v2, length: 40672 00000667 10.09044266 [3056] MP3.ReadHeader framestarts: 41152, 41152 00000668 10.09048080 [3056] MP3.ReadHeader CheckMoreHeaders started 00000669 10.09103203 [3056] MP3.ReadHeader CheckMoreHeaders Endtest: 262144 00000670 10.09132004 [3056] MP3.ReadHeader CheckMoreHeaders finished 00000671 10.09142113 [3056] MP3.ReadHeader finished 00000672 10.09153748 [3056] MP3: Going to read tag from K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000673 10.09586430 [3056] Added Covers: 1 00000674 10.09597588 [3056] MP3: Tag read from K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000675 10.09605408 [3056] 6684 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000676 10.09647942 [3056] 6684 DB lock finished, took 8808. 00000677 10.09652901 [3056] Added Cover Lists: 2 00000678 10.09656906 [3056] Added Covers: 2 00000679 10.09680653 [3056] Remaining Covers: 1 00000680 10.09689236 [3056] Remaining Cover Lists: 1 00000681 10.09692860 [3056] GetFileInfo: Found in DB 00000682 10.09697628 [3056] UpdateSongPictures: CoverList.LoadForSongId, K:\My Documents\My Music\Richard Stoltzman\Fine Music, Vol. 1\2 - Richard Stoltzman; Slovak Radio Symphony Orchestra - Maid with the Flaxen Hair.mp3 00000683 10.09702492 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=5225 ORDER BY CoverOrder 00000684 10.09768295 [3056] 6684 DB lock finished, took 13955. 00000685 10.09773731 [3056] Added Covers: 2 00000686 10.09780216 [3056] Remaining Covers: 1 00000687 10.11844635 [3056] Winamp simulation window received messsage 12 00000688 10.11849117 [3056] Winamp sim - message left to the default processing 00000689 10.12516689 [3056] Going to make final callback 6440 00000690 10.12550354 [3056] DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000691 10.12566757 [3056] Thread has finished 6440 00000692 11.17318439 [3056] iPhone: LoadLibrary() finished 00000693 11.17416286 [3056] iPhone: iTunesMobileDeviceSupport loaded 00000694 11.17583656 [3056] iPhone: AMSInitialize : 0 00000695 11.34274006 [3056] ASL checking for logging parameters in environment variable "MediaMonkey.exe.log" 00000696 11.34285545 [3056] ASL checking for logging parameters in environment variable "asl.log" 00000697 11.34291744 [3056] ASL ignoring unknown/unsupported key/value pair OnFirstLog=command,environment 00000698 11.34634399 [3056] iPhone: AMDeviceNotificationSubscribe : 0 00000699 15.85410690 [3056] Going to make final callback 6948 00000700 15.85484409 [3056] Thread has finished 6948 00000701 24.91463470 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000702 24.91467476 [3056] 6684 DB lock finished, took 9562. 00000703 24.91486931 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000704 24.91515923 [3056] 6684 DB lock finished, took 5799. 00000705 24.91571617 [3056] Node Music starting. 00000706 24.92001534 [3056] Node Music going to clear SongList. 00000707 24.94931221 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000708 24.94959068 [3056] 6684 DB lock finished, took 7969. 00000709 24.94985771 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000710 24.95018196 [3056] 6684 DB lock finished, took 4820. 00000711 24.95072746 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000712 24.95454025 [3056] 6684 DB lock finished, took 11272. 00000713 24.95485497 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000714 24.95548630 [3056] 6684 DB lock finished, took 5253. 00000715 24.95592880 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000716 24.95608521 [3056] 6684 DB lock finished, took 8307. 00000717 24.95636177 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000718 24.95702362 [3056] 6684 DB lock finished, took 4797. 00000719 24.95758629 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000720 24.95773697 [3056] 6684 DB lock finished, took 10721. 00000721 24.95812607 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000722 24.95842552 [3056] 6684 DB lock finished, took 5160. 00000723 24.95859909 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (0,4) ) LIMIT 1 00000724 24.95865631 [3056] 6684 DB query prepare finished, took 2615. 00000725 24.95876694 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4) ) 00000726 24.95883942 [3056] 6684 DB query prepare finished, took 1300. 00000727 24.95894814 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (0,4) ) 00000728 24.95900345 [3056] 6684 DB query prepare finished, took 1237. 00000729 24.95913506 [3056] 6684 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 (0,4) ) 00000730 24.95926285 [3056] 6684 DB query prepare finished, took 1837. 00000731 24.95964241 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000732 24.96142769 [3056] 6684 DB lock finished, took 7294. 00000733 24.96219826 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000734 24.96360397 [3056] 6684 DB lock finished, took 12772. 00000735 24.96380615 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4) ) 00000736 24.96420097 [3056] 6684 DB query prepare finished, took 2875. 00000737 24.96437073 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000738 24.96460533 [3056] 6684 DB query prepare finished, took 1869. 00000739 24.96472549 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000740 24.96494293 [3056] 6684 DB query prepare finished, took 1357. 00000741 24.96505928 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ( mediaaccessible( Songs.IdMedia) OR Songs.CacheStatus=1) ) 00000742 24.96526337 [3056] 6684 DB query prepare finished, took 1358. 00000743 24.96536827 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000744 24.96556854 [3056] 6684 DB query prepare finished, took 1117. 00000745 24.96566963 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000746 24.96600151 [3056] 6684 DB query prepare finished, took 1077. 00000747 24.96614456 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000748 24.96637154 [3056] 6684 DB query prepare finished, took 1571. 00000749 24.96648407 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000750 24.96655273 [3056] 6684 DB query prepare finished, took 1240. 00000751 24.96694946 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000752 24.96709824 [3056] 6684 DB lock finished, took 7698. 00000753 24.96726608 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000754 24.96848297 [3056] 6684 DB lock finished, took 2660. 00000755 24.97601509 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000756 24.97688293 [3056] 6684 DB lock finished, took 7882. 00000757 24.97721291 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000758 24.97728348 [3056] 6684 DB lock finished, took 4499. 00000759 24.99709702 [3056] Thread has started 2668 00000760 24.99729729 [3056] Thread registered, going to initialize 2668 00000761 24.99742508 [3056] Thread initialized 2668 00000762 25.00927734 [3056] 2668 DB open SQL: SELECT Songs.* 00000763 25.00927734 [3056] FROM Songs 00000764 25.00927734 [3056] WHERE ( Songs.TrackType in (0,4) ) 00000765 25.06243896 [3056] 2668 DB lock finished, took 80879. 00000766 26.37364388 [3056] Going to make final callback 2668 00000767 26.51527977 [3056] Thread has finished 2668 00000768 34.81023788 [3056] Thread has started 6380 00000769 34.81035614 [3056] Thread registered, going to initialize 6380 00000770 34.81040955 [3056] Thread initialized 6380 00000771 34.81047440 [3056] ComboHelper::FillThread - lastPart = "h"; len = 1 00000772 34.81737900 [3056] Winamp sim - message left to the default processing 00000773 34.81877136 [3056] Winamp simulation window received messsage 0 00000774 34.81989288 [3056] Winamp sim - message left to the default processing 00000775 34.82916641 [3056] Going to make final callback 6380 00000776 34.82960892 [3056] Thread has finished 6380 00000777 34.88699722 [3056] Thread has started 6936 00000778 34.88706589 [3056] Thread registered, going to initialize 6936 00000779 34.88710403 [3056] Thread initialized 6936 00000780 34.88716125 [3056] ComboHelper::FillThread - lastPart = "he"; len = 2 00000781 34.89398193 [3056] Winamp sim - message left to the default processing 00000782 34.89472580 [3056] Winamp simulation window received messsage 0 00000783 34.89517212 [3056] Winamp sim - message left to the default processing 00000784 34.90146637 [3056] Going to make final callback 6936 00000785 34.93371582 [3056] Winamp sim - message left to the default processing 00000786 34.93566895 [3056] Winamp simulation window received messsage 0 00000787 34.93698120 [3056] Winamp sim - message left to the default processing 00000788 34.94657898 [3056] Thread has finished 6936 00000789 34.99140549 [3056] Thread has started 4592 00000790 34.99151993 [3056] Thread registered, going to initialize 4592 00000791 34.99157333 [3056] Thread initialized 4592 00000792 34.99164581 [3056] ComboHelper::FillThread - lastPart = "hel"; len = 3 00000793 34.99867630 [3056] Winamp sim - message left to the default processing 00000794 35.00361633 [3056] Winamp simulation window received messsage 0 00000795 35.00365448 [3056] Winamp sim - message left to the default processing 00000796 35.00434875 [3056] Going to make final callback 4592 00000797 35.00448608 [3056] Thread has finished 4592 00000798 35.10406494 [3056] Thread has started 5452 00000799 35.10449219 [3056] Thread registered, going to initialize 5452 00000800 35.10530472 [3056] Thread initialized 5452 00000801 35.10547638 [3056] ComboHelper::FillThread - lastPart = "help"; len = 4 00000802 35.11148071 [3056] Winamp sim - message left to the default processing 00000803 35.11248016 [3056] Winamp simulation window received messsage 0 00000804 35.11257553 [3056] Winamp sim - message left to the default processing 00000805 35.11855316 [3056] Going to make final callback 5452 00000806 35.12515259 [3056] Winamp sim - message left to the default processing 00000807 35.12784195 [3056] Winamp simulation window received messsage 0 00000808 35.12830353 [3056] Winamp sim - message left to the default processing 00000809 35.12927628 [3056] Thread has finished 5452 00000810 35.61069107 [3056] Thread has started 6576 00000811 35.61099625 [3056] Thread registered, going to initialize 6576 00000812 35.61101151 [3056] Thread initialized 6576 00000813 35.62368774 [3056] Node Music going to clear SongList. 00000814 35.97235489 [3056] Thread has started 6992 00000815 35.97258759 [3056] Thread registered, going to initialize 6992 00000816 35.97260666 [3056] Thread initialized 6992 00000817 35.97504044 [3056] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00000818 35.97547913 [3056] Going to make final callback 6992 00000819 35.97554779 [3056] Thread has finished 6992 00000820 36.06750488 [3056] Going to make final callback 6576 00000821 36.07601929 [3056] Thread has finished 6576 00000822 39.93875885 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000823 39.93909073 [3056] 6684 DB lock finished, took 9917. 00000824 39.93959045 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000825 39.93965912 [3056] 6684 DB lock finished, took 10077. 00000826 39.94159317 [3056] Node Video starting. 00000827 39.94514084 [3056] Node Video going to clear SongList. 00000828 39.98700714 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000829 39.98767471 [3056] 6684 DB lock finished, took 15985. 00000830 39.98774719 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000831 39.98799896 [3056] 6684 DB lock finished, took 2888. 00000832 39.98811340 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000833 39.98864746 [3056] 6684 DB lock finished, took 4601. 00000834 39.98992157 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000835 39.99054718 [3056] 6684 DB lock finished, took 5439. 00000836 39.99220657 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000837 39.99255753 [3056] 6684 DB lock finished, took 11617. 00000838 39.99288940 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000839 39.99341202 [3056] 6684 DB lock finished, took 6322. 00000840 39.99375153 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000841 39.99390030 [3056] 6684 DB lock finished, took 6398. 00000842 39.99406433 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000843 39.99430466 [3056] 6684 DB lock finished, took 2616. 00000844 39.99447632 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE ( Songs.TrackType in (5) ) LIMIT 1 00000845 39.99453354 [3056] 6684 DB query prepare finished, took 2553. 00000846 39.99464035 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000847 39.99469757 [3056] 6684 DB query prepare finished, took 1257. 00000848 39.99479675 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.IDAlbum=? AND ( Songs.TrackType in (5) ) 00000849 39.99486923 [3056] 6684 DB query prepare finished, took 1100. 00000850 39.99500275 [3056] 6684 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) ) 00000851 39.99513245 [3056] 6684 DB query prepare finished, took 1826. 00000852 39.99555206 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00000853 39.99706268 [3056] 6684 DB lock finished, took 8122. 00000854 39.99801254 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000855 39.99974060 [3056] 6684 DB lock finished, took 17015. 00000856 40.00007248 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (0,4) ) 00000857 40.00035477 [3056] 6684 DB query prepare finished, took 4007. 00000858 40.00047684 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (3) ) 00000859 40.00068283 [3056] 6684 DB query prepare finished, took 1464. 00000860 40.00120163 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (1,7) ) 00000861 40.00149918 [3056] 6684 DB query prepare finished, took 1695. 00000862 40.00166321 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ( mediaaccessible( Songs.IdMedia) OR Songs.CacheStatus=1) ) 00000863 40.00226974 [3056] 6684 DB query prepare finished, took 2445. 00000864 40.00254822 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (2) ) 00000865 40.00287628 [3056] 6684 DB query prepare finished, took 3315. 00000866 40.00301743 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (4) ) 00000867 40.00342560 [3056] 6684 DB query prepare finished, took 1591. 00000868 40.00358963 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (5) ) 00000869 40.00382614 [3056] 6684 DB query prepare finished, took 1821. 00000870 40.00394058 [3056] 6684 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType in (6) ) 00000871 40.00401306 [3056] 6684 DB query prepare finished, took 1303. 00000872 40.00447083 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000873 40.00461960 [3056] 6684 DB lock finished, took 9065. 00000874 40.00481415 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000875 40.00800323 [3056] 6684 DB lock finished, took 3197. 00000876 40.00816345 [3056] TFMainWindow::PrepareAlbumArtList - begin 00000877 40.00864410 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000878 40.00880814 [3056] 6684 DB lock finished, took 7896. 00000879 40.00899887 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000880 40.00963593 [3056] 6684 DB lock finished, took 3193. 00000881 40.01008606 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000882 40.01023865 [3056] 6684 DB lock finished, took 7940. 00000883 40.01041031 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000884 40.01150894 [3056] 6684 DB lock finished, took 2753. 00000885 40.03004837 [3056] TFMainWindow::PrepareAlbumArtList - end 00000886 40.11153030 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000887 40.11177063 [3056] 6684 DB lock finished, took 8668. 00000888 40.11190033 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000889 40.11449051 [3056] 6684 DB lock finished, took 9289. 00000890 40.12709427 [3056] Thread has started 5712 00000891 40.12716675 [3056] Thread registered, going to initialize 5712 00000892 40.12728500 [3056] Thread initialized 5712 00000893 40.13009262 [3056] 5712 DB open SQL: SELECT Songs.* 00000894 40.13009262 [3056] FROM Songs 00000895 40.13009262 [3056] WHERE ( Songs.TrackType in (5) ) 00000896 40.13031769 [3056] 5712 DB lock finished, took 21401. 00000897 40.48186111 [3056] Going to make final callback 5712 00000898 40.55120850 [3056] TFMainWindow::PrepareAlbumArtList - begin 00000899 40.79098511 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000900 40.79155350 [3056] 6684 DB lock finished, took 15467. 00000901 40.79169464 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000902 40.79192734 [3056] 6684 DB lock finished, took 4643. 00000903 40.79269028 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000904 40.79299164 [3056] 6684 DB lock finished, took 7502. 00000905 40.79312134 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000906 40.79326248 [3056] 6684 DB lock finished, took 2419. 00000907 40.79438782 [3056] TFMainWindow::PrepareAlbumArtList - end 00000908 40.79674530 [3056] Added Covers: 2 00000909 40.79958725 [3056] Remaining Covers: 1 00000910 40.80949783 [3056] Added Covers: 2 00000911 40.80957413 [3056] Remaining Covers: 1 00000912 40.81684113 [3056] Added Covers: 2 00000913 40.81711960 [3056] Remaining Covers: 1 00000914 40.82455826 [3056] Added Covers: 2 00000915 40.82465363 [3056] Remaining Covers: 1 00000916 40.85519409 [3056] Added Covers: 2 00000917 40.86494446 [3056] Remaining Covers: 1 00000918 40.87563705 [3056] Added Covers: 2 00000919 40.88368988 [3056] Remaining Covers: 1 00000920 40.89515686 [3056] Added Covers: 2 00000921 40.90935135 [3056] Remaining Covers: 1 00000922 40.91680145 [3056] Added Covers: 2 00000923 40.91685867 [3056] Remaining Covers: 1 00000924 40.94296265 [3056] Added Covers: 2 00000925 40.94329834 [3056] Remaining Covers: 1 00000926 40.95074081 [3056] Added Covers: 2 00000927 40.95145798 [3056] Remaining Covers: 1 00000928 40.96347809 [3056] Added Covers: 2 00000929 40.96385193 [3056] Remaining Covers: 1 00000930 40.97275543 [3056] Added Covers: 2 00000931 40.97316742 [3056] Remaining Covers: 1 00000932 41.00293732 [3056] Thread has finished 5712 00000933 45.09406281 [3056] Thread has started 2788 00000934 45.09412384 [3056] Thread registered, going to initialize 2788 00000935 45.09415817 [3056] Thread initialized 2788 00000936 45.09421921 [3056] ComboHelper::FillThread - lastPart = "m"; len = 1 00000937 45.10078812 [3056] Going to make final callback 2788 00000938 45.10089874 [3056] Thread has finished 2788 00000939 45.51955795 [3056] Thread has started 6712 00000940 45.51963043 [3056] Thread registered, going to initialize 6712 00000941 45.51967239 [3056] Thread initialized 6712 00000942 45.51973343 [3056] ComboHelper::FillThread - lastPart = "mk"; len = 2 00000943 45.53200150 [3056] Winamp sim - message left to the default processing 00000944 45.53226089 [3056] Winamp simulation window received messsage 0 00000945 45.53292465 [3056] Winamp sim - message left to the default processing 00000946 45.53451157 [3056] Going to make final callback 6712 00000947 45.53476715 [3056] Thread has finished 6712 00000948 45.61477661 [3056] Thread has started 3052 00000949 45.61495590 [3056] Thread registered, going to initialize 3052 00000950 45.61594009 [3056] Thread initialized 3052 00000951 45.61598969 [3056] ComboHelper::FillThread - lastPart = "mk"; len = 2 00000952 45.64824677 [3056] Winamp sim - message left to the default processing 00000953 45.64886475 [3056] Winamp simulation window received messsage 0 00000954 45.64896774 [3056] Winamp sim - message left to the default processing 00000955 45.65004730 [3056] Going to make final callback 3052 00000956 45.65025711 [3056] Thread has finished 3052 00000957 45.71683502 [3056] Thread has started 5600 00000958 45.71702957 [3056] Thread registered, going to initialize 5600 00000959 45.71709824 [3056] Thread initialized 5600 00000960 45.71719742 [3056] ComboHelper::FillThread - lastPart = "mkv"; len = 3 00000961 45.73011398 [3056] Winamp sim - message left to the default processing 00000962 45.73223877 [3056] Winamp simulation window received messsage 0 00000963 45.73289490 [3056] Winamp sim - message left to the default processing 00000964 45.73382187 [3056] Going to make final callback 5600 00000965 45.73387146 [3056] Thread has finished 5600 00000966 46.27196884 [3056] Thread has started 5836 00000967 46.27199936 [3056] Thread registered, going to initialize 5836 00000968 46.27219009 [3056] Thread initialized 5836 00000969 46.31013489 [3056] Node Video going to clear SongList. 00000970 46.32869720 [3056] TFMainWindow::PrepareAlbumArtList - begin 00000971 46.32890320 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000972 46.32966995 [3056] 6684 DB lock finished, took 16093. 00000973 46.33000565 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000974 46.33026886 [3056] 6684 DB lock finished, took 4823. 00000975 46.33103943 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000976 46.33203888 [3056] 6684 DB lock finished, took 15658. 00000977 46.33219528 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000978 46.33277893 [3056] 6684 DB lock finished, took 10045. 00000979 46.33330154 [3056] TFMainWindow::PrepareAlbumArtList - end 00000980 46.47389221 [3056] Going to make final callback 5836 00000981 46.52524567 [3056] TFMainWindow::PrepareAlbumArtList - begin 00000982 46.52988434 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000983 46.52999115 [3056] 6684 DB lock finished, took 8355. 00000984 46.53024292 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000985 46.53120041 [3056] 6684 DB lock finished, took 9570. 00000986 46.53157806 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00000987 46.53173065 [3056] 6684 DB lock finished, took 6855. 00000988 46.53208923 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00000989 46.53341293 [3056] 6684 DB lock finished, took 6899. 00000990 46.53511047 [3056] TFMainWindow::PrepareAlbumArtList - end 00000991 46.53667831 [3056] 6684 DB prepare SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00000992 46.53672028 [3056] 6684 DB query prepare finished, took 3569. 00000993 46.53689957 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00000994 46.53763962 [3056] 6684 DB lock finished, took 11919. 00000995 46.54955673 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00000996 46.55010986 [3056] 6684 DB lock finished, took 11475. 00000997 46.55486298 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00000998 46.55529404 [3056] 6684 DB lock finished, took 9017. 00000999 46.56288910 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001000 46.56326675 [3056] 6684 DB lock finished, took 9146. 00001001 46.58514023 [3056] Thread has finished 5836 00001002 46.59484100 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001003 46.59545517 [3056] 6684 DB lock finished, took 15542. 00001004 46.60043335 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001005 46.60087967 [3056] 6684 DB lock finished, took 8875. 00001006 46.60517502 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001007 46.60564804 [3056] 6684 DB lock finished, took 9853. 00001008 46.60996628 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001009 46.61042023 [3056] 6684 DB lock finished, took 9411. 00001010 46.69542313 [3056] Thread has started 5224 00001011 46.69549179 [3056] Thread registered, going to initialize 5224 00001012 46.69552612 [3056] Thread initialized 5224 00001013 46.69561005 [3056] Added Cover Lists: 2 00001014 46.69564819 [3056] 5224 DB prepare SQL: SELECT Covers.* FROM Covers, Songs WHERE songs.idalbum=? AND covers.idsong=songs.id ORDER BY Covers.idsong 00001015 46.69591904 [3056] 5224 DB query prepare finished, took 4277. 00001016 46.69602966 [3056] 5224 DB open SQL: SELECT Covers.* FROM Covers, Songs WHERE songs.idalbum=? AND covers.idsong=songs.id ORDER BY Covers.idsong 00001017 46.69708252 [3056] 5224 DB lock finished, took 22240. 00001018 46.69770813 [3056] Added Covers: 2 00001019 46.69810104 [3056] Added Covers: 3 00001020 46.69818497 [3056] Remaining Covers: 2 00001021 46.69823456 [3056] Added Cover Lists: 3 00001022 46.69828796 [3056] Added Covers: 3 00001023 46.69834137 [3056] Remaining Covers: 2 00001024 46.69844437 [3056] Remaining Cover Lists: 2 00001025 46.69909668 [3056] 5224 DB prepare SQL: SELECT ID,Album FROM Albums WHERE Album=? AND Artist=? 00001026 46.69916153 [3056] 5224 DB query prepare finished, took 3067. 00001027 46.69984436 [3056] 5224 DB open SQL: SELECT ID,Album FROM Albums WHERE Album=? AND Artist=? 00001028 46.69995117 [3056] 5224 DB lock finished, took 14253. 00001029 46.70000458 [3056] 5224 DB exec SQL: INSERT INTO Albums (Artist, Album) VALUES ('Unknown','Unknown') 00001030 46.70175934 [3056] 5224 DB lock successful. 00001031 46.86989975 [3056] 5224 DB lock finished, took 4241523. 00001032 46.86998367 [3056] Returned ID value: 2556 00001033 46.87015915 [3056] 5224 DB prepare SQL: SELECT ID,Artist FROM Artists WHERE Artist=? 00001034 46.87021637 [3056] 5224 DB query prepare finished, took 2664. 00001035 46.87063980 [3056] 5224 DB open SQL: SELECT ID,Artist FROM Artists WHERE Artist=? 00001036 46.87077713 [3056] 5224 DB lock finished, took 8520. 00001037 46.87083435 [3056] 5224 DB exec SQL: INSERT INTO ArtistsAlbums (IDArtist,IDAlbum) VALUES (295,2556) 00001038 46.87361908 [3056] 5224 DB lock successful. 00001039 47.04329681 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001040 47.04711533 [3056] 6684 DB lock finished, took 15708. 00001041 47.04766846 [3056] 5224 DB lock finished, took 4304630. 00001042 47.04774094 [3056] Added Cover Lists: 3 00001043 47.04778290 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001044 47.04784393 [3056] 5224 DB open SQL: SELECT Covers.* FROM Covers, Songs WHERE songs.idalbum=? AND covers.idsong=songs.id ORDER BY Covers.idsong 00001045 47.04860306 [3056] 6684 DB lock finished, took 9304. 00001046 47.04864120 [3056] 5224 DB lock finished, took 17170. 00001047 47.05008316 [3056] 5224 DB open SQL: SELECT ID,Album FROM Albums WHERE Album=? AND Artist=? 00001048 47.05023956 [3056] 5224 DB lock finished, took 29533. 00001049 47.05030060 [3056] Added Cover Lists: 4 00001050 47.05063248 [3056] 5224 DB open SQL: SELECT Covers.* FROM Covers, Songs WHERE songs.idalbum=? AND covers.idsong=songs.id ORDER BY Covers.idsong 00001051 47.05075073 [3056] 5224 DB lock finished, took 6422. 00001052 47.05341339 [3056] 5224 DB open SQL: SELECT ID,Album FROM Albums WHERE Album=? AND Artist=? 00001053 47.05364609 [3056] 5224 DB lock finished, took 67603. 00001054 47.05378723 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001055 47.05384064 [3056] Added Cover Lists: 5 00001056 47.05422211 [3056] 5224 DB open SQL: SELECT Covers.* FROM Covers, Songs WHERE songs.idalbum=? AND covers.idsong=songs.id ORDER BY Covers.idsong 00001057 47.05429077 [3056] 6684 DB lock finished, took 9530. 00001058 47.05578995 [3056] 5224 DB lock finished, took 33189. 00001059 47.05670166 [3056] Going to make final callback 5224 00001060 47.06972122 [3056] DB: Deleting prepared query: SELECT Covers.* FROM Covers, Songs WHERE songs.idalbum=? AND covers.idsong=songs.id ORDER BY Covers.idsong 00001061 47.06980896 [3056] DB: Deleting prepared query: SELECT ID,Album FROM Albums WHERE Album=? AND Artist=? 00001062 47.06991577 [3056] DB: Deleting prepared query: SELECT ID,Artist FROM Artists WHERE Artist=? 00001063 47.07227707 [3056] Thread has finished 5224 00001064 47.26328278 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001065 47.26374054 [3056] 6684 DB lock finished, took 9478. 00001066 47.28020477 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001067 47.30420685 [3056] 6684 DB lock finished, took 8851. 00001068 47.30447006 [3056] Thread has started 6740 00001069 47.30462646 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001070 47.31702423 [3056] 6684 DB lock finished, took 20537. 00001071 47.31812286 [3056] Thread registered, going to initialize 6740 00001072 47.31837463 [3056] Thread initialized 6740 00001073 47.31845093 [3056] Going to make final callback 6740 00001074 47.31885529 [3056] Thread has finished 6740 00001075 47.51658630 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001076 47.51702118 [3056] 6684 DB lock finished, took 8959. 00001077 47.52782440 [3056] Thread has started 5664 00001078 47.52785492 [3056] Thread registered, going to initialize 5664 00001079 47.52791214 [3056] Thread initialized 5664 00001080 47.52798080 [3056] Going to make final callback 5664 00001081 47.53057098 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001082 47.53100204 [3056] 6684 DB lock finished, took 8939. 00001083 47.54116058 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001084 47.54205322 [3056] 6684 DB lock finished, took 16229. 00001085 47.54234695 [3056] Thread has started 6412 00001086 47.54246521 [3056] Thread registered, going to initialize 6412 00001087 47.54251862 [3056] Thread initialized 6412 00001088 47.54295731 [3056] Going to make final callback 6412 00001089 47.55350494 [3056] Thread has finished 6412 00001090 47.55833817 [3056] Thread has finished 5664 00001091 47.75030899 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001092 47.75069046 [3056] 6684 DB lock finished, took 9127. 00001093 47.75988388 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001094 47.76158905 [3056] 6684 DB lock finished, took 9146. 00001095 47.76316452 [3056] Thread has started 5348 00001096 47.76327133 [3056] Thread registered, going to initialize 5348 00001097 47.76425934 [3056] Thread initialized 5348 00001098 47.76434708 [3056] Going to make final callback 5348 00001099 47.76637268 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001100 47.76679611 [3056] 6684 DB lock finished, took 8568. 00001101 47.77593231 [3056] Thread has started 6252 00001102 47.77603149 [3056] Thread registered, going to initialize 6252 00001103 47.77608871 [3056] Thread initialized 6252 00001104 47.77619553 [3056] Going to make final callback 6252 00001105 47.78693771 [3056] Thread has finished 5348 00001106 47.78874207 [3056] Thread has finished 6252 00001107 47.98195648 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001108 47.98297119 [3056] 6684 DB lock finished, took 8909. 00001109 47.99578094 [3056] Thread has started 6424 00001110 47.99596024 [3056] Thread registered, going to initialize 6424 00001111 47.99601746 [3056] Thread initialized 6424 00001112 47.99608231 [3056] Going to make final callback 6424 00001113 47.99728775 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001114 47.99773788 [3056] 6684 DB lock finished, took 9902. 00001115 48.00804520 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001116 48.00874329 [3056] Thread has started 6864 00001117 48.00889969 [3056] Thread registered, going to initialize 6864 00001118 48.00952911 [3056] 6684 DB lock finished, took 10714. 00001119 48.00963974 [3056] Thread initialized 6864 00001120 48.01022339 [3056] Going to make final callback 6864 00001121 48.02163315 [3056] Thread has finished 6424 00001122 48.02545166 [3056] Thread has finished 6864 00001123 48.24861526 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001124 48.25780869 [3056] 6684 DB lock finished, took 9034. 00001125 48.26906204 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001126 48.27693939 [3056] 6684 DB lock finished, took 18544. 00001127 48.27802658 [3056] Thread has started 5628 00001128 48.28079605 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001129 48.28092957 [3056] Thread registered, going to initialize 5628 00001130 48.29003906 [3056] 6684 DB lock finished, took 9748. 00001131 48.29027176 [3056] Thread initialized 5628 00001132 48.29036713 [3056] Going to make final callback 5628 00001133 48.29620743 [3056] Thread has finished 5628 00001134 48.50569153 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001135 48.51334763 [3056] 6684 DB lock finished, took 90279. 00001136 48.51934052 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001137 48.51978302 [3056] 6684 DB lock finished, took 9094. 00001138 48.52412033 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001139 48.52478409 [3056] 6684 DB lock finished, took 11431. 00001140 48.54388046 [3056] Thread has started 7132 00001141 48.54397583 [3056] Thread registered, going to initialize 7132 00001142 48.54404449 [3056] Thread initialized 7132 00001143 48.54441452 [3056] Going to make final callback 7132 00001144 48.54572678 [3056] Thread has finished 7132 00001145 48.78451538 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001146 48.78844452 [3056] 6684 DB lock finished, took 8832. 00001147 48.80868530 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001148 48.80949783 [3056] 6684 DB lock finished, took 8966. 00001149 48.81053543 [3056] Thread has started 6976 00001150 48.81060410 [3056] Thread registered, going to initialize 6976 00001151 48.81067657 [3056] Thread initialized 6976 00001152 48.81090164 [3056] Going to make final callback 6976 00001153 48.81431961 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001154 48.81473923 [3056] 6684 DB lock finished, took 8664. 00001155 48.83349609 [3056] Thread has finished 6976 00001156 48.83415222 [3056] Thread has started 3884 00001157 48.83553314 [3056] Thread registered, going to initialize 3884 00001158 48.83668137 [3056] Thread initialized 3884 00001159 48.83684921 [3056] Going to make final callback 3884 00001160 48.84735489 [3056] Thread has finished 3884 00001161 49.02885437 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001162 49.02927780 [3056] 6684 DB lock finished, took 8752. 00001163 49.03797913 [3056] Thread has started 5196 00001164 49.03804398 [3056] Thread registered, going to initialize 5196 00001165 49.03810883 [3056] Thread initialized 5196 00001166 49.03819656 [3056] Going to make final callback 5196 00001167 49.03987122 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001168 49.04031372 [3056] 6684 DB lock finished, took 9010. 00001169 49.04980087 [3056] Thread has started 152 00001170 49.04986954 [3056] Thread registered, going to initialize 152 00001171 49.04995346 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001172 49.05027008 [3056] Thread initialized 152 00001173 49.05037308 [3056] Going to make final callback 152 00001174 49.05044937 [3056] 6684 DB lock finished, took 9887. 00001175 49.06158447 [3056] Thread has started 5420 00001176 49.06165695 [3056] Thread registered, going to initialize 5420 00001177 49.06172943 [3056] Thread initialized 5420 00001178 49.06183243 [3056] Going to make final callback 5420 00001179 49.07362747 [3056] Thread has finished 5196 00001180 49.07455063 [3056] Thread has finished 5420 00001181 49.11676788 [3056] Thread has finished 152 00001182 49.27700806 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001183 49.27732849 [3056] 6684 DB lock finished, took 9874. 00001184 49.28629303 [3056] Thread has started 696 00001185 49.28945160 [3056] Thread registered, going to initialize 696 00001186 49.28948593 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001187 49.28964996 [3056] Thread initialized 696 00001188 49.28975296 [3056] Going to make final callback 696 00001189 49.29011536 [3056] 6684 DB lock finished, took 10759. 00001190 49.30002594 [3056] Thread has started 4748 00001191 49.30010605 [3056] Thread registered, going to initialize 4748 00001192 49.30017090 [3056] Thread initialized 4748 00001193 49.30028915 [3056] Going to make final callback 4748 00001194 49.30112839 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001195 49.30399323 [3056] 6684 DB lock finished, took 9091. 00001196 49.31040192 [3056] Thread has started 3068 00001197 49.31055450 [3056] Thread registered, going to initialize 3068 00001198 49.31064224 [3056] Thread initialized 3068 00001199 49.31076050 [3056] Going to make final callback 3068 00001200 49.32156372 [3056] Thread has finished 4748 00001201 49.32460403 [3056] Thread has finished 696 00001202 49.32489395 [3056] Thread has finished 3068 00001203 49.53392792 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001204 49.53414536 [3056] 6684 DB lock finished, took 9766. 00001205 49.54652023 [3056] Thread has started 6436 00001206 49.54663467 [3056] Thread registered, going to initialize 6436 00001207 49.54668427 [3056] Thread initialized 6436 00001208 49.54675293 [3056] Going to make final callback 6436 00001209 49.54878616 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001210 49.54931641 [3056] 6684 DB lock finished, took 11110. 00001211 49.55848312 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001212 49.55911255 [3056] 6684 DB lock finished, took 13072. 00001213 49.57323837 [3056] Thread has finished 6436 00001214 49.57929993 [3056] Thread has started 1860 00001215 49.57947922 [3056] Thread registered, going to initialize 1860 00001216 49.57976913 [3056] Thread initialized 1860 00001217 49.57985306 [3056] Going to make final callback 1860 00001218 49.58040237 [3056] Thread has finished 1860 00001219 49.79740524 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001220 49.79747772 [3056] 6684 DB lock finished, took 10791. 00001221 49.80928421 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001222 49.81446838 [3056] 6684 DB lock finished, took 8923. 00001223 49.81507492 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001224 49.82036972 [3056] 6684 DB lock finished, took 10264. 00001225 49.82050323 [3056] Thread has started 196 00001226 49.82055283 [3056] Thread registered, going to initialize 196 00001227 49.82062149 [3056] Thread initialized 196 00001228 49.82070923 [3056] Going to make final callback 196 00001229 49.83247375 [3056] Thread has finished 196 00001230 50.02639771 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001231 50.02680206 [3056] 6684 DB lock finished, took 9118. 00001232 50.03925323 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001233 50.03947449 [3056] 6684 DB lock finished, took 9610. 00001234 50.03964233 [3056] Thread has started 2532 00001235 50.03970718 [3056] Thread registered, going to initialize 2532 00001236 50.03978348 [3056] Thread initialized 2532 00001237 50.03995132 [3056] Going to make final callback 2532 00001238 50.04390335 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001239 50.04502106 [3056] 6684 DB lock finished, took 8939. 00001240 50.05222321 [3056] Thread has started 6656 00001241 50.05225372 [3056] Thread registered, going to initialize 6656 00001242 50.05253220 [3056] Thread initialized 6656 00001243 50.05260849 [3056] Going to make final callback 6656 00001244 50.07963181 [3056] Thread has finished 2532 00001245 50.08078003 [3056] Thread has finished 6656 00001246 50.11168671 [3056] Going to make final callback 2608 00001247 50.11180878 [3056] Thread has finished 2608 00001248 50.25808716 [3056] Thread has started 5324 00001249 50.25821686 [3056] Thread registered, going to initialize 5324 00001250 50.25827789 [3056] Thread initialized 5324 00001251 50.25839996 [3056] 5324 DB open SQL: SELECT PodcastEpisodes.* FROM PodcastEpisodes WHERE NetSource = 'http://feeds.nos.nl/~r/journaal/~5/Mwy0OrUKqp8/NOS_Journaal_VODcast_2011-03-01_17_00_00.mp4' AND idTrack < 0 00001252 50.25919342 [3056] 5324 DB lock finished, took 16494. 00001253 50.26414108 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001254 50.26704025 [3056] 6684 DB lock finished, took 9169. 00001255 50.27414703 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001256 50.27808762 [3056] 6684 DB lock finished, took 8910. 00001257 50.27855682 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001258 50.28034973 [3056] 6684 DB lock finished, took 8758. 00001259 50.30043793 [3056] Thread has started 4244 00001260 50.30053329 [3056] Thread registered, going to initialize 4244 00001261 50.30056000 [3056] Thread initialized 4244 00001262 50.30065155 [3056] Going to make final callback 4244 00001263 50.30711365 [3056] Thread has finished 4244 00001264 50.54609299 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001265 50.54620743 [3056] 6684 DB lock finished, took 13515. 00001266 50.55626297 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001267 50.55659866 [3056] 6684 DB lock finished, took 9062. 00001268 50.56052399 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001269 50.56091309 [3056] 6684 DB lock finished, took 8856. 00001270 50.57929611 [3056] Thread has started 7060 00001271 50.57963181 [3056] Thread registered, going to initialize 7060 00001272 50.57974243 [3056] Thread initialized 7060 00001273 50.57996368 [3056] Going to make final callback 7060 00001274 50.58036804 [3056] Thread has finished 7060 00001275 50.80585861 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001276 50.85233688 [3056] 6684 DB lock finished, took 9815. 00001277 50.85325241 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001278 50.85703278 [3056] 6684 DB lock finished, took 16545. 00001279 50.85752487 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001280 50.86188126 [3056] 6684 DB lock finished, took 9140. 00001281 50.88908768 [3056] Thread has started 3376 00001282 50.88919067 [3056] Thread registered, going to initialize 3376 00001283 50.88924026 [3056] Thread initialized 3376 00001284 50.88930893 [3056] Going to make final callback 3376 00001285 50.89044952 [3056] Thread has finished 3376 00001286 51.08910751 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001287 51.08954620 [3056] 6684 DB lock finished, took 9138. 00001288 51.09866333 [3056] Thread has started 7164 00001289 51.09878922 [3056] Thread registered, going to initialize 7164 00001290 51.09992599 [3056] Thread initialized 7164 00001291 51.09998703 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001292 51.10013580 [3056] Going to make final callback 7164 00001293 51.10066223 [3056] 6684 DB lock finished, took 10370. 00001294 51.10959625 [3056] Thread has started 6236 00001295 51.10966873 [3056] Thread registered, going to initialize 6236 00001296 51.10973358 [3056] Thread initialized 6236 00001297 51.11172104 [3056] Going to make final callback 6236 00001298 51.11477280 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001299 51.11489868 [3056] 6684 DB lock finished, took 10035. 00001300 51.12245941 [3056] Thread has started 4448 00001301 51.12345123 [3056] Thread registered, going to initialize 4448 00001302 51.12354279 [3056] Thread initialized 4448 00001303 51.12363434 [3056] Going to make final callback 4448 00001304 51.13515472 [3056] Thread has finished 6236 00001305 51.13630676 [3056] Thread has finished 7164 00001306 51.13704300 [3056] Thread has finished 4448 00001307 51.33963776 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001308 51.34075928 [3056] 6684 DB lock finished, took 10617. 00001309 51.35010910 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001310 51.35014725 [3056] 6684 DB lock finished, took 8857. 00001311 51.35174561 [3056] Thread has started 5768 00001312 51.35186768 [3056] Thread registered, going to initialize 5768 00001313 51.35193253 [3056] Thread initialized 5768 00001314 51.35199738 [3056] Going to make final callback 5768 00001315 51.35638428 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001316 51.35643005 [3056] 6684 DB lock finished, took 10410. 00001317 51.37408066 [3056] Thread has started 6860 00001318 51.37416077 [3056] Thread registered, going to initialize 6860 00001319 51.37424469 [3056] Thread initialized 6860 00001320 51.37444687 [3056] Going to make final callback 6860 00001321 51.38074493 [3056] Thread has finished 6860 00001322 51.38986588 [3056] Thread has finished 5768 00001323 51.56433868 [3056] Thread has started 5800 00001324 51.57250977 [3056] Thread registered, going to initialize 5800 00001325 51.58838654 [3056] Thread initialized 5800 00001326 51.59934998 [3056] ComboHelper::FillThread - lastPart = "mk"; len = 2 00001327 51.60499573 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001328 51.62164688 [3056] 6684 DB lock finished, took 10520. 00001329 51.63141632 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001330 51.63510132 [3056] 6684 DB lock finished, took 17927. 00001331 51.64050293 [3056] Thread has started 3656 00001332 51.65098953 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001333 51.65805054 [3056] Thread registered, going to initialize 3656 00001334 51.69071960 [3056] 6684 DB lock finished, took 10797. 00001335 51.71738052 [3056] Thread initialized 3656 00001336 51.72408295 [3056] Winamp sim - message left to the default processing 00001337 51.72999573 [3056] Going to make final callback 3656 00001338 51.73580933 [3056] Winamp simulation window received messsage 0 00001339 51.77474594 [3056] Winamp sim - message left to the default processing 00001340 51.78501129 [3056] Thread has finished 3656 00001341 51.78589249 [3056] Going to make final callback 5800 00001342 51.78605652 [3056] Thread has started 6892 00001343 51.80561829 [3056] Thread registered, going to initialize 6892 00001344 51.80730438 [3056] Thread initialized 6892 00001345 51.80739594 [3056] Thread has finished 5800 00001346 51.80743027 [3056] Winamp sim - message left to the default processing 00001347 51.80757523 [3056] ComboHelper::FillThread - lastPart = "m"; len = 1 00001348 51.80760956 [3056] Winamp simulation window received messsage 0 00001349 51.80764771 [3056] Winamp sim - message left to the default processing 00001350 51.84453964 [3056] Going to make final callback 6892 00001351 51.84476089 [3056] Thread has finished 6892 00001352 51.90770340 [3056] Thread has started 6256 00001353 51.90980530 [3056] Thread registered, going to initialize 6256 00001354 51.91361618 [3056] Thread initialized 6256 00001355 51.91381073 [3056] Remaining Cover Lists: 4 00001356 51.91392899 [3056] Thread has started 5188 00001357 51.91398239 [3056] Remaining Cover Lists: 3 00001358 51.91409302 [3056] Thread registered, going to initialize 5188 00001359 51.91542816 [3056] Remaining Cover Lists: 2 00001360 51.91553116 [3056] Thread initialized 5188 00001361 51.91555405 [3056] Remaining Covers: 1 00001362 51.91569901 [3056] ComboHelper::FillThread - lastPart = ""; len = 0 00001363 51.91602707 [3056] Remaining Cover Lists: 1 00001364 51.92069244 [3056] Node Video going to clear SongList. 00001365 51.95957947 [3056] TFMainWindow::PrepareAlbumArtList - begin 00001366 51.96010590 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001367 51.96029282 [3056] 6684 DB lock finished, took 8789. 00001368 51.96047974 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001369 51.96106339 [3056] 6684 DB lock finished, took 3052. 00001370 51.96146774 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001371 51.96205521 [3056] 6684 DB lock finished, took 7510. 00001372 51.96244049 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001373 51.96450806 [3056] 6684 DB lock finished, took 6881. 00001374 51.99903107 [3056] TFMainWindow::PrepareAlbumArtList - end 00001375 51.99921799 [3056] Going to make final callback 6256 00001376 52.08760452 [3056] TFMainWindow::PrepareAlbumArtList - begin 00001377 52.29512787 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001378 52.29556274 [3056] 6684 DB lock finished, took 8868. 00001379 52.29568863 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001380 52.29589462 [3056] 6684 DB lock finished, took 2768. 00001381 52.29624176 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001382 52.29650879 [3056] 6684 DB lock finished, took 5231. 00001383 52.29662323 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001384 52.29675674 [3056] 6684 DB lock finished, took 2233. 00001385 52.30121994 [3056] TFMainWindow::PrepareAlbumArtList - end 00001386 52.30429840 [3056] Added Covers: 2 00001387 52.30468750 [3056] Remaining Covers: 1 00001388 52.31201172 [3056] Added Covers: 2 00001389 52.31249237 [3056] Remaining Covers: 1 00001390 52.31927109 [3056] Added Covers: 2 00001391 52.31986237 [3056] Remaining Covers: 1 00001392 52.32704163 [3056] Added Covers: 2 00001393 52.33088684 [3056] Remaining Covers: 1 00001394 52.33961868 [3056] Added Covers: 2 00001395 52.34001923 [3056] Remaining Covers: 1 00001396 52.34814835 [3056] Added Covers: 2 00001397 52.34859085 [3056] Remaining Covers: 1 00001398 52.35591125 [3056] Added Covers: 2 00001399 52.35630035 [3056] Remaining Covers: 1 00001400 52.36747360 [3056] Added Covers: 2 00001401 52.36786652 [3056] Remaining Covers: 1 00001402 52.49796677 [3056] Added Covers: 2 00001403 52.49838257 [3056] Remaining Covers: 1 00001404 52.50590897 [3056] Added Covers: 2 00001405 52.50631714 [3056] Remaining Covers: 1 00001406 52.51490784 [3056] Added Covers: 2 00001407 52.51530075 [3056] Remaining Covers: 1 00001408 52.52692032 [3056] Added Covers: 2 00001409 52.52743530 [3056] Remaining Covers: 1 00001410 52.59107971 [3056] Thread has finished 6256 00001411 52.87419510 [3056] Going to make final callback 5188 00001412 53.00813675 [3056] Thread has finished 5188 00001413 54.01056290 [3056] Thread has started 5220 00001414 54.01072311 [3056] Thread registered, going to initialize 5220 00001415 54.01079941 [3056] Thread initialized 5220 00001416 54.01206970 [3056] ComboHelper::FillThread - lastPart = "m"; len = 1 00001417 54.01744461 [3056] Going to make final callback 5220 00001418 54.01913834 [3056] Thread has finished 5220 00001419 54.18483353 [3056] Thread has started 6248 00001420 54.18493271 [3056] Thread registered, going to initialize 6248 00001421 54.18498611 [3056] Thread initialized 6248 00001422 54.18505096 [3056] ComboHelper::FillThread - lastPart = "mp"; len = 2 00001423 54.19551849 [3056] Winamp sim - message left to the default processing 00001424 54.19557953 [3056] Winamp simulation window received messsage 0 00001425 54.19562149 [3056] Winamp sim - message left to the default processing 00001426 54.19923019 [3056] Going to make final callback 6248 00001427 54.19957352 [3056] Thread has finished 6248 00001428 54.27936935 [3056] Thread has started 5888 00001429 54.28065872 [3056] Thread registered, going to initialize 5888 00001430 54.28079987 [3056] Thread initialized 5888 00001431 54.28097153 [3056] ComboHelper::FillThread - lastPart = "mp"; len = 2 00001432 54.28856277 [3056] Winamp sim - message left to the default processing 00001433 54.28950119 [3056] Winamp simulation window received messsage 0 00001434 54.28967285 [3056] Winamp sim - message left to the default processing 00001435 54.29074097 [3056] Going to make final callback 5888 00001436 54.29082108 [3056] Thread has finished 5888 00001437 54.52184677 [3056] Thread has started 5896 00001438 54.52201843 [3056] Thread registered, going to initialize 5896 00001439 54.52206039 [3056] Thread initialized 5896 00001440 54.52212906 [3056] ComboHelper::FillThread - lastPart = "mp4"; len = 3 00001441 54.52803040 [3056] Winamp sim - message left to the default processing 00001442 54.52845001 [3056] Winamp simulation window received messsage 0 00001443 54.52852249 [3056] Winamp sim - message left to the default processing 00001444 54.52937698 [3056] Going to make final callback 5896 00001445 54.53488541 [3056] Thread has finished 5896 00001446 55.01644897 [3056] Thread has started 3204 00001447 55.01651382 [3056] Thread registered, going to initialize 3204 00001448 55.02048874 [3056] Thread initialized 3204 00001449 55.03595352 [3056] Node Video going to clear SongList. 00001450 55.09285355 [3056] TFMainWindow::PrepareAlbumArtList - begin 00001451 55.10027695 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001452 55.10033417 [3056] 6684 DB lock finished, took 21720. 00001453 55.10731125 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001454 55.10740662 [3056] 6684 DB lock finished, took 5683. 00001455 55.11317062 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001456 55.11320496 [3056] 6684 DB lock finished, took 8064. 00001457 55.11534500 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001458 55.11542130 [3056] 6684 DB lock finished, took 5316. 00001459 55.13563538 [3056] TFMainWindow::PrepareAlbumArtList - end 00001460 55.23881912 [3056] Going to make final callback 3204 00001461 55.27263641 [3056] TFMainWindow::PrepareAlbumArtList - begin 00001462 55.27437973 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001463 55.27468491 [3056] 6684 DB lock finished, took 17243. 00001464 55.27515793 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001465 55.27899551 [3056] 6684 DB lock finished, took 9567. 00001466 55.27941132 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001467 55.27956390 [3056] 6684 DB lock finished, took 7583. 00001468 55.27973557 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001469 55.28062057 [3056] 6684 DB lock finished, took 2682. 00001470 55.28580093 [3056] TFMainWindow::PrepareAlbumArtList - end 00001471 55.28697205 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001472 55.29700470 [3056] 6684 DB lock finished, took 18105. 00001473 55.29765701 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001474 55.30146408 [3056] 6684 DB lock finished, took 11836. 00001475 55.30214310 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001476 55.32123566 [3056] 6684 DB lock finished, took 359722. 00001477 55.32953262 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001478 55.33993912 [3056] 6684 DB lock finished, took 12737. 00001479 55.35066986 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001480 55.35588837 [3056] Thread has started 3636 00001481 55.36170959 [3056] 6684 DB lock finished, took 35145. 00001482 55.37073135 [3056] Thread registered, going to initialize 3636 00001483 55.37768936 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001484 55.38657379 [3056] Thread initialized 3636 00001485 55.38661194 [3056] 6684 DB lock finished, took 11574. 00001486 55.38686371 [3056] Added Cover Lists: 2 00001487 55.38714981 [3056] 3636 DB prepare SQL: SELECT Covers.* FROM Covers, Songs WHERE songs.idalbum=? AND covers.idsong=songs.id ORDER BY Covers.idsong 00001488 55.38721848 [3056] 3636 DB query prepare finished, took 5149. 00001489 55.38776016 [3056] 3636 DB open SQL: SELECT Covers.* FROM Covers, Songs WHERE songs.idalbum=? AND covers.idsong=songs.id ORDER BY Covers.idsong 00001490 55.38787460 [3056] 3636 DB lock finished, took 11001. 00001491 55.38844299 [3056] Added Covers: 2 00001492 55.39113998 [3056] Added Covers: 3 00001493 55.41664124 [3056] Remaining Covers: 2 00001494 55.42529678 [3056] Added Covers: 3 00001495 55.44367981 [3056] Remaining Covers: 2 00001496 55.44428635 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001497 55.46118164 [3056] 6684 DB lock finished, took 10662. 00001498 55.46129990 [3056] Thread has finished 3204 00001499 55.46229935 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001500 55.46945572 [3056] 6684 DB lock finished, took 14997. 00001501 55.47249603 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001502 55.47330093 [3056] 6684 DB lock finished, took 16110. 00001503 55.47708893 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001504 55.47757721 [3056] 6684 DB lock finished, took 9933. 00001505 55.48156357 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001506 55.48222351 [3056] 6684 DB lock finished, took 14201. 00001507 55.48824692 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001508 55.48860931 [3056] 6684 DB lock finished, took 9858. 00001509 55.49263382 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001510 55.49337006 [3056] 6684 DB lock finished, took 15695. 00001511 55.51265335 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001512 55.51801300 [3056] 6684 DB lock finished, took 12053. 00001513 56.03754044 [3056] UpdateSongPictures: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001514 56.03759766 [3056] GetFileInfo: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001515 56.03763199 [3056] GetFileInfo: readPictures=spAlways 00001516 56.03773880 [3056] GetFileInfo: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001517 56.15432358 [3056] Added Cover Lists: 3 00001518 56.15439224 [3056] FH.ReadFileInfo: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001519 56.15445328 [3056] Locking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001520 56.15487671 [3056] Locked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001521 56.34344864 [3056] Unlocking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001522 56.34373093 [3056] Unlocked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001523 58.73745346 [3056] Locking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001524 58.73751068 [3056] Locked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001525 59.12230301 [3056] Unlocking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001526 59.12266922 [3056] Unlocked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001527 59.12340927 [3056] F_AAC: Opening file: OpenMode: 1 00001528 59.12733078 [3056] F_AAC: Entered AAC_ScanAtoms 00001529 59.59608459 [3056] F_AAC: Found FTYP atom, FileSize: 174449446664 00001530 61.91264343 [3056] Thread has started 6532 00001531 61.91267776 [3056] Thread registered, going to initialize 6532 00001532 61.91276932 [3056] Thread initialized 6532 00001533 61.91283035 [3056] ComboHelper::FillThread - lastPart = "mp"; len = 2 00001534 61.92141342 [3056] Winamp sim - message left to the default processing 00001535 61.92152405 [3056] Winamp simulation window received messsage 0 00001536 61.92168045 [3056] Winamp sim - message left to the default processing 00001537 61.92293930 [3056] Going to make final callback 6532 00001538 61.92324448 [3056] Thread has finished 6532 00001539 62.08921051 [3056] Thread has started 3940 00001540 62.08930969 [3056] Thread registered, going to initialize 3940 00001541 62.08940125 [3056] Thread initialized 3940 00001542 62.08950806 [3056] ComboHelper::FillThread - lastPart = "m"; len = 1 00001543 62.09260941 [3056] Going to make final callback 3940 00001544 62.09704971 [3056] Thread has finished 3940 00001545 62.23808670 [3056] Thread has started 6276 00001546 62.23813248 [3056] Thread registered, going to initialize 6276 00001547 62.26148987 [3056] Thread initialized 6276 00001548 62.27437973 [3056] Thread has started 6444 00001549 62.27448273 [3056] Thread registered, going to initialize 6444 00001550 62.27453995 [3056] Thread initialized 6444 00001551 62.27460480 [3056] ComboHelper::FillThread - lastPart = ""; len = 0 00001552 62.27845764 [3056] Going to make final callback 6444 00001553 62.27909470 [3056] Thread has finished 6444 00001554 65.98616028 [3056] Thread has started 3288 00001555 65.98628235 [3056] Thread registered, going to initialize 3288 00001556 65.98634338 [3056] Thread initialized 3288 00001557 65.98833466 [3056] CDPlayer.ini location: C:\Users\Rusty\AppData\Roaming\MediaMonkey\CDPlayer.ini 00001558 65.98866272 [3056] Going to make final callback 3288 00001559 65.98867798 [3056] Thread has finished 3288 00001560 69.71922302 [3056] Going to make final callback 6504 00001561 69.71935272 [3056] Thread has finished 6504 00001562 69.78012848 [3056] Thread has started 5624 00001563 69.78022766 [3056] Thread registered, going to initialize 5624 00001564 69.78028107 [3056] Thread initialized 5624 00001565 69.78038025 [3056] 5624 DB open SQL: SELECT PodcastEpisodes.* FROM PodcastEpisodes WHERE NetSource = 'http://feeds.nos.nl/~r/journaal/~5/bZDx98YsLSs/NOS_Journaal_VODcast_2011-03-03_20_00_08.mp4' AND idTrack < 0 00001566 69.78102875 [3056] 5624 DB lock finished, took 13407. 00001567 69.78123474 [3056] 5624 DB open SQL: Select Podcasts.* from Podcasts where id = 31 00001568 69.78169250 [3056] 5624 DB lock finished, took 9174. 00001569 74.24304962 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001570 74.24818420 [3056] 6684 DB lock finished, took 9276. 00001571 74.25179291 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001572 74.25788116 [3056] 6684 DB lock finished, took 9483. 00001573 74.25844574 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001574 74.26228333 [3056] 6684 DB lock finished, took 9383. 00001575 74.26409149 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001576 74.26701355 [3056] 6684 DB lock finished, took 28132. 00001577 74.27233887 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001578 74.27368927 [3056] 6684 DB lock finished, took 9503. 00001579 74.27692413 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001580 74.27732849 [3056] 6684 DB lock finished, took 9065. 00001581 74.28778839 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001582 74.28810120 [3056] 6684 DB lock finished, took 10231. 00001583 74.29178619 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001584 74.29219055 [3056] 6684 DB lock finished, took 8487. 00001585 74.30065918 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001586 74.30107880 [3056] 6684 DB lock finished, took 8802. 00001587 74.30558777 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001588 74.30599213 [3056] 6684 DB lock finished, took 8348. 00001589 74.31203461 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001590 74.31609344 [3056] 6684 DB lock finished, took 9085. 00001591 74.31873322 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001592 74.32058716 [3056] 6684 DB lock finished, took 18867. 00001593 74.32765961 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001594 74.32781982 [3056] 6684 DB lock finished, took 9697. 00001595 74.33331299 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001596 74.33423615 [3056] 6684 DB lock finished, took 18545. 00001597 74.33903503 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001598 74.33911133 [3056] 6684 DB lock finished, took 8597. 00001599 74.34592438 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001600 74.34762573 [3056] 6684 DB lock finished, took 9569. 00001601 74.35557556 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001602 74.35599518 [3056] 6684 DB lock finished, took 8930. 00001603 74.36064911 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001604 74.36098480 [3056] 6684 DB lock finished, took 8644. 00001605 74.36604309 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001606 74.36644745 [3056] 6684 DB lock finished, took 8575. 00001607 74.37025452 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001608 74.37065887 [3056] 6684 DB lock finished, took 8407. 00001609 76.37370300 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001610 76.37483215 [3056] 6684 DB lock finished, took 9926. 00001611 76.37894440 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001612 76.38195038 [3056] 6684 DB lock finished, took 9137. 00001613 76.38454437 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001614 76.38576508 [3056] 6684 DB lock finished, took 11553. 00001615 76.38920593 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001616 76.39109802 [3056] 6684 DB lock finished, took 10323. 00001617 76.40904236 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001618 76.41327667 [3056] 6684 DB lock finished, took 10275. 00001619 76.41407776 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001620 76.41876221 [3056] 6684 DB lock finished, took 8522. 00001621 76.41924286 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001622 76.42362213 [3056] 6684 DB lock finished, took 8606. 00001623 76.42446136 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001624 76.42491150 [3056] 6684 DB lock finished, took 15555. 00001625 76.43781281 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001626 76.45774841 [3056] 6684 DB lock finished, took 8853. 00001627 76.46826172 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001628 76.47853088 [3056] 6684 DB lock finished, took 11765. 00001629 76.48630524 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001630 76.49306488 [3056] 6684 DB lock finished, took 13979. 00001631 76.50887299 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001632 76.52144623 [3056] 6684 DB lock finished, took 10347. 00001633 76.53079224 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001634 76.54352570 [3056] 6684 DB lock finished, took 9948. 00001635 76.55268097 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001636 76.56985474 [3056] 6684 DB lock finished, took 15543. 00001637 76.57981873 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001638 76.58030701 [3056] 6684 DB lock finished, took 8895. 00001639 76.58563232 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001640 76.58604431 [3056] 6684 DB lock finished, took 8665. 00001641 80.21540833 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views ORDER BY ID 00001642 80.21672821 [3056] 6684 DB lock finished, took 8927. 00001643 80.21797180 [3056] 6684 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00001644 80.21862793 [3056] 6684 DB lock finished, took 16716. 00001645 80.21965790 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001646 80.21990967 [3056] 6684 DB lock finished, took 4714. 00001647 80.23735809 [3056] Forms: NormalizeTopMosts: Level: 0 00001648 80.24517822 [3056] Forms: NormalizeTopMost: Starting 00001649 80.38161469 [3056] MainForm: NC Activate - 00001650 80.43381500 [3056] Winamp sim - message left to the default processing 00001651 80.43406677 [3056] Winamp simulation window received messsage 0 00001652 80.43410492 [3056] Winamp sim - message left to the default processing 00001653 82.85787964 [3056] Forms: RestoreTopMost: Level: 1 00001654 82.85868073 [3056] Forms: RestoreTopMost: Starting 00001655 82.86543274 [3056] MainForm: NC Activate + 00001656 83.05829620 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001657 83.05835724 [3056] 6684 DB lock finished, took 22904. 00001658 83.06646729 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001659 83.07089996 [3056] 6684 DB lock finished, took 21873. 00001660 83.07180786 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001661 83.07387543 [3056] 6684 DB lock finished, took 18156. 00001662 83.09065247 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001663 83.09917450 [3056] 6684 DB lock finished, took 9741. 00001664 83.09981537 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001665 83.10007477 [3056] 6684 DB lock finished, took 10854. 00001666 83.10697937 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001667 83.10881042 [3056] 6684 DB lock finished, took 22694. 00001668 83.11277771 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001669 83.11669159 [3056] 6684 DB lock finished, took 9724. 00001670 83.11904907 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001671 83.11968231 [3056] 6684 DB lock finished, took 8506. 00001672 83.12856293 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001673 83.12902832 [3056] 6684 DB lock finished, took 9236. 00001674 83.13337708 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001675 83.13364410 [3056] 6684 DB lock finished, took 8982. 00001676 83.14042664 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001677 83.14090729 [3056] 6684 DB lock finished, took 9126. 00001678 83.14649200 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001679 83.14690399 [3056] 6684 DB lock finished, took 8667. 00001680 83.15652466 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001681 83.15762329 [3056] 6684 DB lock finished, took 11134. 00001682 83.16158295 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001683 83.16198730 [3056] 6684 DB lock finished, took 8425. 00001684 83.16587830 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001685 83.16652679 [3056] 6684 DB lock finished, took 13803. 00001686 83.17115021 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001687 83.17485046 [3056] 6684 DB lock finished, took 86201. 00001688 83.18267059 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001689 83.18325043 [3056] 6684 DB lock finished, took 8828. 00001690 83.18874359 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001691 83.18972778 [3056] 6684 DB lock finished, took 22873. 00001692 83.19400787 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001693 83.19484711 [3056] 6684 DB lock finished, took 13924. 00001694 83.20017242 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001695 83.20059204 [3056] 6684 DB lock finished, took 8639. 00001696 83.21195221 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001697 83.21241760 [3056] 6684 DB lock finished, took 8773. 00001698 83.21674347 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001699 83.21762085 [3056] 6684 DB lock finished, took 15284. 00001700 83.22208405 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001701 83.22304535 [3056] 6684 DB lock finished, took 28373. 00001702 83.22807312 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001703 83.22854614 [3056] 6684 DB lock finished, took 9899. 00001704 83.24191284 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001705 83.24234009 [3056] 6684 DB lock finished, took 8782. 00001706 83.24641418 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001707 83.24693298 [3056] 6684 DB lock finished, took 8404. 00001708 83.25202179 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001709 83.25267792 [3056] 6684 DB lock finished, took 8495. 00001710 83.25703430 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001711 83.25780487 [3056] 6684 DB lock finished, took 9170. 00001712 83.26597595 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001713 83.26888275 [3056] 6684 DB lock finished, took 16673. 00001714 83.27426147 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001715 83.27542114 [3056] 6684 DB lock finished, took 11288. 00001716 83.28097534 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001717 83.28123474 [3056] 6684 DB lock finished, took 9275. 00001718 83.28570557 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001719 83.28709412 [3056] 6684 DB lock finished, took 8590. 00001720 87.87209320 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001721 87.87751007 [3056] 6684 DB lock finished, took 9317. 00001722 87.87854004 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001723 87.88406372 [3056] 6684 DB lock finished, took 19291. 00001724 87.88462830 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001725 87.88932800 [3056] 6684 DB lock finished, took 10156. 00001726 87.88983154 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001727 87.89653778 [3056] 6684 DB lock finished, took 8621. 00001728 87.89823151 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001729 87.89871979 [3056] 6684 DB lock finished, took 9906. 00001730 87.90650177 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001731 87.90674591 [3056] 6684 DB lock finished, took 16085. 00001732 87.91288757 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001733 87.91374969 [3056] 6684 DB lock finished, took 13621. 00001734 87.91805267 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001735 87.91845703 [3056] 6684 DB lock finished, took 8591. 00001736 87.93291473 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001737 87.93305969 [3056] 6684 DB lock finished, took 8997. 00001738 87.93733978 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001739 87.93775177 [3056] 6684 DB lock finished, took 8419. 00001740 87.94487762 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001741 87.94528961 [3056] 6684 DB lock finished, took 8660. 00001742 87.94942474 [3056] 6684 DB open SQL: SELECT * FROM Covers WHERE IDSong=? ORDER BY CoverOrder 00001743 87.94984436 [3056] 6684 DB lock finished, took 8596. 00001744 90.85580444 [3056] 6684 DB open SQL: SELECT * FROM Filters WHERE ID=? 00001745 90.85686493 [3056] 6684 DB lock finished, took 8067. 00001746 90.85712433 [3056] 6684 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 00001747 90.85888672 [3056] 6684 DB lock finished, took 4569. 00001748 90.86717224 [3056] Node Audiobook starting. 00001749 94.17700195 [3056] F_AAC: AAC_ExtractDetails: bitrate: 2196566, channels: 2, lengthms: 1488921996 00001750 94.18851471 [3056] F_AAC: GetTrackDetails() 00001751 94.19174957 [3056] F_AAC: parse_atoms() 00001752 101.81091309 [3056] F_AAC: GetAACTrack() 00001753 101.81233978 [3056] F_AAC: GetAACTrack(), SampleRate = 48000 00001754 101.83207703 [3056] FH.ReadFileInfo openned 00001755 101.83979034 [3056] Locking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001756 101.84936523 [3056] Locked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001757 101.86293030 [3056] Unlocking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001758 101.86361694 [3056] Unlocked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001759 101.87882233 [3056] Locking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001760 101.88046265 [3056] Locked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001761 101.88919830 [3056] Unlocking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001762 101.88946533 [3056] Unlocked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001763 101.95809937 [3056] Locking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001764 101.95823669 [3056] Locked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001765 101.96356964 [3056] Unlocking file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001766 101.96361542 [3056] Unlocked file: \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001767 101.96826172 [3056] F_AAC: Entered AAC_ScanAtoms 00001768 101.98122406 [3056] F_AAC: Found FTYP atom, FileSize: 174449446664 00001769 105.67622375 [3056] F_AAC: AAC_ExtractDetails: bitrate: 2196566, channels: 2, lengthms: 1488921996 00001770 105.68782806 [3056] F_AAC: GetTrackDetails() 00001771 105.68788147 [3056] F_AAC: parse_atoms() 00001772 112.87845612 [3056] F_AAC: GetAACTrack() 00001773 112.87869263 [3056] F_AAC: GetAACTrack(), SampleRate = 48000 00001774 116.37898254 [3056] 3636 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00001775 116.37979126 [3056] 3636 DB query prepare finished, took 14142. 00001776 116.38006592 [3056] 3636 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00001777 116.38088989 [3056] 3636 DB lock finished, took 17413. 00001778 116.38111877 [3056] Added Cover Lists: 4 00001779 116.38146210 [3056] Remaining Cover Lists: 3 00001780 116.38162231 [3056] GetFileInfo: Found in DB 00001781 116.38169861 [3056] UpdateSongPictures: CoverList.LoadForSongId, \\192.168.0.147\Qdownload\A Serious Man 2009 BRRip H264 AAC-GreatMagician\A Serious Man.mp4 00001782 116.38179016 [3056] 3636 DB open SQL: SELECT * FROM Covers WHERE IDSong=10786 ORDER BY CoverOrder 00001783 116.38266754 [3056] 3636 DB lock finished, took 17903. 00001784 116.38275146 [3056] Added Covers: 3 00001785 116.38298798 [3056] Remaining Covers: 2 00001786 116.38305664 [3056] Remaining Covers: 1 00001787 116.38311768 [3056] Remaining Cover Lists: 2 00001788 116.38317108 [3056] Added Cover Lists: 3 00001789 116.38322449 [3056] Remaining Cover Lists: 2 00001790 116.38328552 [3056] Remaining Cover Lists: 1 00001791 116.38344574 [3056] Going to make final callback 3636