00000000 0.00000000 [3960] FastMM has been installed. 00000001 0.05781112 [3960] Win7Tools - Windows 7 detected 00000002 0.05784828 [3960] Win7Tools - running on Windows Vista or later - initialize DWM 00000003 0.05791344 [3960] Win7Tools - Windows Vista DWM initialized 00000004 0.05795744 [3960] Win7Tools - Windows 7 DWM initialized 00000005 0.05800064 [3960] Win7Tools - Shell initialized 00000006 0.07259212 [3960] InstanceManager - registering class. 00000007 0.07267320 [3960] InstanceManager - creating mutex. 00000008 0.07273948 [3960] InstanceManager - creating window. 00000009 0.07430768 [3960] InstanceManager - releasing mutex. 00000010 0.07501660 [3960] Received command line: '"C:\Program Files (x86)\MediaMonkey\MediaMonkey.exe" ' 00000011 0.07507756 [3960] Going to read brand data. 00000012 0.07535448 [3960] Going to init Application. 00000013 0.13934308 [3960] Application was inicialized 00000014 0.13968788 [3960] Version: 3.2.0.1290 00000015 0.98490912 [3960] Forms: Received WM_ACTIVATEAPP 00000016 0.99522299 [3960] Splash screen was shown. 00000017 4.57152081 [3960] Going to read ini settigs. 00000018 4.58079052 [3960] DB path: C:\Users\Rusty\AppData\Local\MediaMonkey\MM.DB , default: , MyDir: C:\Program Files (x86)\MediaMonkey\ 00000019 4.58498287 [3960] User uses Gold version (registered user) 00000020 4.58505917 [3960] Going to set a language. 00000021 4.59282160 [3960] Setting titles. 00000022 4.59287024 [3960] Strings translation: started 00000023 4.59371758 [3960] Strings translation: finished 00000024 4.59460688 [3960] Going to init tree manager. 00000025 4.64378691 [3960] Going to insert main tree nodes. 00000026 4.64385366 [3960] Updated 0 nodes in MainTree list. 00000027 4.64414358 [3960] Updated 0 nodes in SongView list. 00000028 4.64419174 [3960] Updated 0 nodes in PodcastsView list. 00000029 4.64423180 [3960] Updated 0 nodes in VSTBrowseList1 list. 00000030 4.64426899 [3960] Updated 0 nodes in VSTBrowseList2 list. 00000031 4.64430666 [3960] Updated 0 nodes in VSTBrowseList3 list. 00000032 4.64445496 [3960] Updated 0 nodes in AlbumArtView list. 00000033 4.64765739 [3960] Going to init now playing window. 00000034 4.67069435 [3960] Now playing initialized. 00000035 4.67074251 [3960] Going to init tree history. 00000036 4.67079306 [3960] Going to init advanced error handlers. 00000037 4.67087889 [3960] Going to init covers window. 00000038 4.67336369 [3960] Going to read toolbar positions. 00000039 4.68246412 [3960] Going to load drag cursors. 00000040 4.68420267 [3960] Going to load player skin. 00000041 4.84530878 [3960] Setting PlayerSkin 00000042 4.84678364 [3960] Initializing Player Skin 00000043 4.84702253 [3960] Initializing Player Skin 00000044 4.84769773 [3960] Creating Panel Object: Visualization 00000045 4.84782410 [3960] Initializing Player Skin 00000046 4.84799433 [3960] Creating Panel Object: StatusPanel 00000047 4.84810877 [3960] Initializing Player Skin 00000048 4.84826088 [3960] Creating StateButton Object: PlaylistButton 00000049 4.84839153 [3960] Initializing Player Skin 00000050 4.84853411 [3960] Creating StateButton Object: EQButton 00000051 4.84864283 [3960] Initializing Player Skin 00000052 4.84878254 [3960] Creating StateButton Object: ShuffleButton 00000053 4.84888744 [3960] Initializing Player Skin 00000054 4.84902334 [3960] Creating StateButton Object: ContinousButton 00000055 4.84912777 [3960] Initializing Player Skin 00000056 4.84934139 [3960] Creating Text Object: Custom4 00000057 4.85696268 [3960] Initializing Player Skin 00000058 4.85714149 [3960] Creating Text Object: Custom3 00000059 4.85887432 [3960] Initializing Player Skin 00000060 4.85908461 [3960] Creating Text Object: Custom2 00000061 4.86066008 [3960] Initializing Player Skin 00000062 4.86087179 [3960] Creating Text Object: Custom1 00000063 4.86270475 [3960] Initializing Player Skin 00000064 4.86288786 [3960] Creating Text Object: SongTitle 00000065 4.86442327 [3960] Initializing Player Skin 00000066 4.86461687 [3960] Creating Text Object: KbpsDisplay 00000067 4.86649275 [3960] Initializing Player Skin 00000068 4.86666059 [3960] Creating Text Object: TimeDisplay 00000069 4.86838484 [3960] Initializing Player Skin 00000070 4.86854124 [3960] Creating Panel Object: Rating 00000071 4.88193655 [3960] Initializing Player Skin 00000072 4.88211250 [3960] Creating StateButton Object: MuteButton 00000073 4.88260555 [3960] Initializing Player Skin 00000074 4.88281822 [3960] Creating TrackBar Object: VolumeBar 00000075 4.88310194 [3960] Initializing Player Skin 00000076 4.88322639 [3960] Creating TrackBar Object: SeekBar 00000077 4.88347960 [3960] Initializing Player Skin 00000078 4.88360500 [3960] Creating Button Object: StopButton 00000079 4.88386869 [3960] Initializing Player Skin 00000080 4.88399696 [3960] Creating Button Object: NextButton 00000081 4.88426161 [3960] Initializing Player Skin 00000082 4.88438368 [3960] Creating Button Object: PrevButton 00000083 4.88464880 [3960] Initializing Player Skin 00000084 4.88478279 [3960] Creating StateButton Object: PlayPauseButton 00000085 4.88506746 [3960] Initializing Player Skin 00000086 4.97980595 [3960] Removing file: C:\Users\Rusty\AppData\Local\Temp\165723840.tmp 00000087 4.98052979 [3960] Removal complete 00000088 5.04512978 [3960] Going to check DB exists. 00000089 5.04539585 [3960] Going to init DB. 00000090 5.04543877 [3960] DB - Going to Open. 00000091 5.04559517 [3960] DB - Opened. 00000092 5.04564571 [3960] Going to check DB version. 00000093 5.04885387 [3960] Thread has started 2792 00000094 5.04895306 [3960] Thread registered, going to initialize 2792 00000095 5.04900074 [3960] Thread initialized 2792 00000096 5.04957962 [3960] MMModuleInit 00000097 5.10970545 [3960] 2792 DB open SQL: SELECT NULL FROM sqlite_master WHERE type='table' AND name='DBInfo' 00000098 5.11151600 [3960] 2792 DB lock finished, took 7945. 00000099 5.11524630 [3960] 2792 DB open SQL: SELECT * FROM DBInfo 00000100 5.11529064 [3960] 2792 DB lock finished, took 12808. 00000101 5.11586523 [3960] 2792 DB open SQL: SELECT * FROM Medias WHERE TurnedSN<>0 00000102 5.11639547 [3960] 2792 DB lock finished, took 10830. 00000103 5.11648846 [3960] Going to make final callback 2792 00000104 5.11656237 [3960] Going to do the first time actions. 00000105 5.11666679 [3960] Thread has finished 2792 00000106 5.11670208 [3960] 3496 DB open SQL: SELECT IdMedia FROM Medias WHERE DriveType = 12345 00000107 5.11711788 [3960] 3496 DB lock finished, took 7953. 00000108 5.11716413 [3960] 3496 DB open SQL: SELECT IdMedia FROM Medias WHERE DriveType = 12346 00000109 5.11760187 [3960] 3496 DB lock finished, took 9196. 00000110 5.11766815 [3960] Going to start drive update thread. 00000111 5.12003899 [3960] Going to read DB structures. 00000112 5.12057066 [3960] 3496 DB open SQL: SELECT * FROM Medias ORDER BY Medias.IDMedia 00000113 5.12065458 [3960] Thread has started 2540 00000114 5.12077427 [3960] Thread registered, going to initialize 2540 00000115 5.12081718 [3960] Thread initialized 2540 00000116 5.12116194 [3960] 3496 DB lock finished, took 12682. 00000117 5.12161684 [3960] 3496 DB open SQL: SELECT * FROM Lists WHERE IDListType = 1 ORDER BY SortOrder 00000118 5.12196922 [3960] 3496 DB lock finished, took 7247. 00000119 5.12247372 [3960] 3496 DB open SQL: SELECT * FROM Lists WHERE IDListType = 2 ORDER BY SortOrder 00000120 5.12277555 [3960] 3496 DB lock finished, took 6149. 00000121 5.12350988 [3960] 3496 DB open SQL: SELECT * FROM Lists WHERE IDListType = 3 ORDER BY SortOrder 00000122 5.12380648 [3960] 3496 DB lock finished, took 5989. 00000123 5.12447262 [3960] 3496 DB open SQL: SELECT * FROM Lists WHERE IDListType = 4 ORDER BY SortOrder 00000124 5.12476683 [3960] 3496 DB lock finished, took 5909. 00000125 5.12559748 [3960] Going to update background. 00000126 5.16163111 [3960] Going to init help file. 00000127 5.16168785 [3960] Going to init tray icon. 00000128 5.16715479 [3960] Going to init scripts. 00000129 5.16794825 [3960] Scripts: Read script - &Statistics 00000130 5.16804361 [3960] Scripts: Read script - Track List (&HTML) 00000131 5.16813040 [3960] Scripts: Read script - Track List (&XML) 00000132 5.16821909 [3960] Scripts: Read script - Track List (CS&V) 00000133 5.16830397 [3960] Scripts: Read script - Track List (&Excel) 00000134 5.16839504 [3960] Scripts: Read script - Auto-&increment Track #s... 00000135 5.16848326 [3960] Scripts: Read script - &Swap Artist and Title 00000136 5.16857004 [3960] Scripts: Read script - Case Checker... 00000137 5.16865778 [3960] Scripts: Read script - Export all Playlists... 00000138 5.16874743 [3960] Scripts: Read script - Export subscribed Podcasts... 00000139 5.16895342 [3960] Going to init hotkeys. 00000140 5.16899872 [3960] Going to init hotkeys. 00000141 5.17073965 [3960] 3496 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000142 5.17142200 [3960] 3496 DB lock finished, took 14552. 00000143 5.17447519 [3960] Hotkeys: Going to install hotkeys hook: 00000144 5.17456245 [3960] RIDEV_EXINPUTSINK registered successfuly 00000145 5.17472792 [3960] Going to set default hotkeys. 00000146 5.17481136 [3960] Going to set menu shortcuts. 00000147 5.17488813 [3960] Going to init popup menu handler. 00000148 5.21425295 [3960] 3496 DB prepare SQL: SELECT IDGenre, GenreName FROM Genres 00000149 5.21444416 [3960] 3496 DB query prepare finished, took 2344. 00000150 5.21449995 [3960] 3496 DB open SQL: SELECT IDGenre, GenreName FROM Genres 00000151 5.21485519 [3960] 3496 DB lock finished, took 7033. 00000152 5.22159910 [3960] 3496 DB open SQL: SELECT * FROM OrganizeRules ORDER BY Pos 00000153 5.22199488 [3960] 3496 DB lock finished, took 8209. 00000154 5.26445103 [3960] Main form initialized. 00000155 5.26463890 [3960] Player: Going to start plugin engine. 00000156 5.26534939 [3960] Winamp simulation window received messsage 36 00000157 5.26538944 [3960] Winamp sim - message left to the default processing 00000158 5.26593304 [3960] Winamp simulation window received messsage 131 00000159 5.26596928 [3960] Winamp sim - message left to the default processing 00000160 5.26607656 [3960] Winamp simulation window received messsage 1 00000161 5.26611280 [3960] Winamp sim - message left to the default processing 00000162 5.26704788 [3960] Winamp sim - message left to the default processing 00000163 5.26712465 [3960] Winamp sim - message left to the default processing 00000164 5.26776934 [3960] Winamp sim - message left to the default processing 00000165 5.26793623 [3960] Winamp sim - message left to the default processing 00000166 5.26855278 [3960] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_ape.dll 00000167 5.26982832 [3960] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_mpc.dll 00000168 5.27171469 [3960] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_vorbis.dll 00000169 5.27234793 [3960] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wav.dll 00000170 5.27315664 [3960] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wma.dll 00000171 5.27439451 [3960] Going to load input plugin C:\Program Files (x86)\MediaMonkey\Plugins\in_wmp3.dll 00000172 5.27860308 [3960] Going to load output plugin C:\Program Files (x86)\MediaMonkey\Plugins\out_MMDS.dll 00000173 5.28112316 [3960] FastMM has been installed. 00000174 5.32179403 [3960] Going to load output plugin C:\Program Files (x86)\MediaMonkey\Plugins\out_wave.dll 00000175 5.32282400 [3960] Going to load DSP plugin C:\Program Files (x86)\MediaMonkey\Plugins\dsp_DeFX.dll 00000176 5.32365417 [3960] Winamp sim - WM_USER message 603 00000177 5.32371187 [3960] Winamp sim - WM_USER message 603 00000178 5.32668877 [3960] Going to init playlist window. 00000179 5.32723665 [3960] DSP - Going to update DSP module list. 00000180 5.32738161 [3960] DSP - Module list updated. 00000181 5.33997297 [3960] Going to start auto-scanner. 00000182 5.34042454 [3960] Going to load format plug-ins. 00000183 5.38901186 [3960] Going to init encoding settings. 00000184 5.39577198 [3960] Player: Setting current track: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000185 5.39632750 [3960] Thread has started 3776 00000186 5.39650631 [3960] Thread registered, going to initialize 3776 00000187 5.39652872 [3960] Thread initialized 3776 00000188 5.41067505 [3960] Winamp simulation window received messsage 12 00000189 5.41071987 [3960] Winamp sim - message left to the default processing 00000190 5.41375160 [3960] 3496 DB open SQL: SELECT * FROM Devices WHERE PluginName='' 00000191 5.41439438 [3960] 3496 DB lock finished, took 13710. 00000192 5.41599274 [3960] FastMM has been installed. 00000193 5.41683435 [3960] 3496 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iPhone.dll' 00000194 5.41741800 [3960] 3496 DB lock finished, took 12294. 00000195 5.42014980 [3960] FastMM has been installed. 00000196 5.46572781 [3960] 3496 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iPod.dll' 00000197 5.46709299 [3960] 3496 DB lock finished, took 12926. 00000198 5.46713734 [3960] iPhone: Loading library: C:\Program Files (x86)\Common Files\Apple\Mobile Device Support\bin\iTunesMobileDevice.dll 00000199 5.65362740 [3960] 3496 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_iRiverH.dll' 00000200 5.65368557 [3960] 3496 DB lock finished, took 15012. 00000201 5.68170738 [3960] 3496 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_USBMass1.dll' 00000202 5.68254709 [3960] 3496 DB lock finished, took 15995. 00000203 5.68657351 [3960] FastMM has been installed. 00000204 5.74037504 [3960] 3496 DB open SQL: SELECT * FROM Devices WHERE PluginName='d_WMDM.dll' 00000205 5.74127722 [3960] 3496 DB lock finished, took 14309. 00000206 5.74158001 [3960] WMDM: Inializing WMDM 00000207 5.74241590 [3960] WMDM: Queueing action id:0 00000208 5.74330282 [3960] WMDM: Starting action id: 0 00000209 5.74334669 [3960] WMDM: Creating Dev Mgr 00000210 5.74503565 [3960] 3496 DB open SQL: SELECT * FROM Filters WHERE ID=0 00000211 5.74558926 [3960] 3496 DB lock finished, took 10475. 00000212 5.74669123 [3960] WMDM: Geting auth interface 00000213 5.74676991 [3960] WMDM: End of IU 00000214 5.74683571 [3960] WMDM: Authenticating 00000215 5.75067472 [3960] 3496 DB open SQL: SELECT * FROM Filters WHERE ID=0 00000216 5.75122118 [3960] 3496 DB lock finished, took 10295. 00000217 5.75500488 [3960] Player initialized. 00000218 5.75833416 [3960] Forms: Received WM_ACTIVATEAPP 00000219 5.75858641 [3960] Forms: Accepted WM_ACTIVATEAPP - 00000220 5.75862122 [3960] WMDM: Creating Notifications 00000221 5.75869322 [3960] Forms: NormalizeTopMosts: Level: 0 00000222 5.75917435 [3960] Forms: NormalizeTopMost: Starting 00000223 5.76035118 [3960] Winamp sim - ignoring. 00000224 5.76045704 [3960] Winamp simulation window received messsage 28 00000225 5.76050758 [3960] Winamp sim - ignoring. 00000226 5.76576567 [3960] Going to run application. 00000227 5.90853357 [3960] Win7Tools - Taskbar COM object initialized 00000228 5.98993731 [3960] 3496 DB prepare 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 00000229 5.99026489 [3960] 3496 DB query prepare finished, took 5373. 00000230 5.99032879 [3960] 3496 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 00000231 5.99191427 [3960] 3496 DB lock finished, took 35443. 00000232 6.02886677 [3960] Forms: Received WM_ACTIVATEAPP 00000233 6.02904177 [3960] Forms: Accepted WM_ACTIVATEAPP + 00000234 6.02929211 [3960] Forms: RestoreTopMost: Level: 1 00000235 6.02954245 [3960] Forms: RestoreTopMost: Starting 00000236 6.03211498 [3960] Winamp sim - ignoring. 00000237 6.03237057 [3960] Winamp simulation window received messsage 28 00000238 6.03250217 [3960] Winamp sim - ignoring. 00000239 6.37696838 [3960] Winamp simulation window received messsage 799 00000240 6.37706709 [3960] Winamp sim - message left to the default processing 00000241 6.37744379 [3960] Winamp sim - message left to the default processing 00000242 7.03200245 [3960] GetFileInfo: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000243 7.03216267 [3960] 3496 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000244 7.03276014 [3960] 3496 DB query prepare finished, took 12092. 00000245 7.03280687 [3960] 3496 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000246 7.03333378 [3960] 3496 DB lock finished, took 11201. 00000247 7.03371859 [3960] FH.ReadFileInfo: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000248 7.03376007 [3960] Locking file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000249 7.03380346 [3960] Locked file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000250 7.03392982 [3960] Unlocking file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000251 7.03397226 [3960] Unlocked file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000252 7.03424406 [3960] Locking file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000253 7.03428793 [3960] Locked file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000254 7.03440142 [3960] Unlocking file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000255 7.03444052 [3960] Unlocked file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000256 7.03448200 [3960] F_AAC: Opening file: OpenMode: 1 00000257 7.03884220 [3960] F_AAC: Entered AAC_ScanAtoms 00000258 7.03895760 [3960] F_AAC: Found FTYP atom, FileSize: 3580992 00000259 7.04036808 [3960] F_AAC: Found Data Atom: name, Length: 106, data payload: 000018C9 00001898 00007D68 000076E6 00019B10 0002DD55 00008000 00008000 000018AB 00000BE1 00000260 7.04040241 [3960] F_AAC: Found Data Atom: İnam, Length: 30, data payload: Into the Night 00000261 7.04046059 [3960] F_AAC: Found Data Atom: apID, Length: 40, data payload: russellsamuels@yahoo.com 00000262 7.04053259 [3960] F_AAC: Found Data Atom: purd, Length: 35, data payload: 2007-11-02 01:11:25 00000263 7.04057455 [3960] F_AAC: Found Data Atom: rate, Length: 18, data payload: -1 00000264 7.04062510 [3960] F_AAC: Found Data Atom: İART, Length: 23, data payload: Santana 00000265 7.04067135 [3960] F_AAC: Found Data Atom: aART, Length: 23, data payload: Santana 00000266 7.04071569 [3960] F_AAC: Found Data Atom: İalb, Length: 32, data payload: Ultimate Santana 00000267 7.04076099 [3960] F_AAC: Found Data Atom: İday, Length: 20, data payload: 2007 00000268 7.04262352 [3960] F_AAC: AAC_ExtractDetails: bitrate: 128000, channels: 2, lengthms: -916259690 00000269 7.04270315 [3960] FH.ReadFileInfo openned 00000270 7.06626892 [3960] Winamp simulation window received messsage 12 00000271 7.06634903 [3960] Winamp sim - message left to the default processing 00000272 7.08401966 [3960] Assoc: AssociateOnlyExts starting 00000273 7.08684349 [3960] Assoc: PrepareAssociation starting 00000274 7.08692265 [3960] Error during file associating: Failed to set data for 'AppID' 00000275 7.08787441 [3960] Assoc: PrepareAssociation autoplay handlers installed 00000276 7.08794546 [3960] Error during file associating: Failed to set data for '' 00000277 7.08871889 [3960] Assoc: PrepareAssociation media client installed 00000278 7.08878613 [3960] Error during file associating: Failed to set data for 'ApplicationName' 00000279 7.08883858 [3960] Assoc: PrepareAssociation default programs installed 00000280 7.09158802 [3960] Assoc: Associating: mmip 00000281 7.09166908 [3960] Error during file associating: Failed to set data for '' 00000282 7.09433651 [3960] Assoc: SetActionForFolders starting - action 1 00000283 7.12317562 [3960] Error during file associating: Failed to set data for '' 00000284 7.12333393 [3960] Assoc: SetActionForFolders finished - action 1 00000285 7.12464952 [3960] iPhone: LoadLibrary() finished 00000286 7.12542582 [3960] Assoc: SetActionForFolders starting - action 2 00000287 7.12656879 [3960] iPhone: iTunesMobileDeviceSupport loaded 00000288 7.12674618 [3960] Error during file associating: Failed to set data for '' 00000289 7.12680244 [3960] Assoc: SetActionForFolders finished - action 2 00000290 7.12750483 [3960] Assoc: SetActionForFolders starting - action 3 00000291 7.13054991 [3960] iPhone: AMSInitialize : 0 00000292 7.13073778 [3960] Error during file associating: Failed to set data for '' 00000293 7.13145065 [3960] Assoc: SetActionForFolders finished - action 3 00000294 7.13346481 [3960] Going to check for a new version. 00000295 7.13928556 [3960] iPhone: AMDeviceNotificationSubscribe : 0 00000296 7.23378325 [3960] 2540 DB prepare SQL: SELECT ID FROM Albums WHERE Tracks=0 00000297 7.23400259 [3960] 2540 DB query prepare finished, took 2902. 00000298 7.23406076 [3960] 2540 DB open SQL: SELECT ID FROM Albums WHERE Tracks=0 00000299 7.23909426 [3960] Thread has started 3492 00000300 7.23924732 [3960] 2540 DB lock finished, took 124486. 00000301 7.23933935 [3960] Thread registered, going to initialize 3492 00000302 7.23938751 [3960] 2540 DB prepare SQL: SELECT ID FROM Artists WHERE Tracks=0 AND Albums=0 AND Authors=0 AND Conducts=0 AND Lyrics=0 00000303 7.23960495 [3960] Thread initialized 3492 00000304 7.23991632 [3960] 2540 DB query prepare finished, took 3944. 00000305 7.23996592 [3960] GetFileInfo: K:\My Documents\My Music\Air\Moon Safari\1 - Air - Femme d'Argent.mp3 00000306 7.24002647 [3960] 2540 DB open SQL: SELECT ID FROM Artists WHERE Tracks=0 AND Albums=0 AND Authors=0 AND Conducts=0 AND Lyrics=0 00000307 7.24860096 [3960] 3776 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000308 7.24866199 [3960] 2540 DB lock finished, took 76939. 00000309 7.24898434 [3960] GetFileInfo: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000310 7.24905968 [3960] 2540 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000311 7.24962807 [3960] 2540 DB query prepare finished, took 12131. 00000312 7.24970245 [3960] 2540 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000313 7.25019789 [3960] 2540 DB lock finished, took 10108. 00000314 7.25045204 [3960] GetFileInfo: Found in DB 00000315 7.25088215 [3960] Going to make final callback 2540 00000316 7.25095415 [3960] DB: Deleting prepared query: SELECT ID FROM Albums WHERE Tracks=0 00000317 7.25103235 [3960] DB: Deleting prepared query: SELECT ID FROM Artists WHERE Tracks=0 AND Albums=0 AND Authors=0 AND Conducts=0 AND Lyrics=0 00000318 7.25110102 [3960] DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000319 7.25127363 [3960] Thread has finished 2540 00000320 7.25244474 [3960] 3776 DB query prepare finished, took 11342. 00000321 7.25253487 [3960] 3776 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000322 7.25302029 [3960] 3776 DB lock finished, took 9962. 00000323 7.25327730 [3960] GetFileInfo: Found in DB 00000324 7.25606346 [3960] 3496 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000325 7.25670958 [3960] 3496 DB lock finished, took 12965. 00000326 7.25786495 [3960] 3496 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 00000327 7.25804996 [3960] 3496 DB query prepare finished, took 2524. 00000328 7.25844097 [3960] 3496 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 00000329 7.25855303 [3960] 3496 DB query prepare finished, took 1273. 00000330 7.25891590 [3960] 3496 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=0 ) 00000331 7.25902462 [3960] 3496 DB query prepare finished, took 1206. 00000332 7.25940037 [3960] 3496 DB prepare SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( ( mediaaccessible( Songs.IdMedia) OR Songs.CacheStatus=1) ) 00000333 7.25954103 [3960] 3496 DB query prepare finished, took 1806. 00000334 7.25960684 [3960] 3496 DB open SQL: SELECT * FROM Filters WHERE ID=0 00000335 7.26019859 [3960] 3496 DB lock finished, took 12207. 00000336 7.26146030 [3960] GetFileInfo: K:\My Documents\My Music\Air\Moon Safari\3 - Air - All I Need.mp3 00000337 7.26150465 [3960] 3776 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000338 7.26238585 [3960] 3776 DB lock finished, took 10144. 00000339 7.26286697 [3960] GetFileInfo: Found in DB 00000340 7.27599621 [3960] GetFileInfo: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000341 7.27610493 [3960] GetFileInfo: readPictures=spAlways 00000342 7.27617359 [3960] GetFileInfo: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000343 7.27650309 [3960] Added Cover Lists: 1 00000344 7.27655697 [3960] FH.ReadFileInfo: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000345 7.27659988 [3960] Locking file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000346 7.27664042 [3960] Locked file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000347 7.27675915 [3960] Unlocking file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000348 7.27679920 [3960] Unlocked file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000349 7.27705812 [3960] Locking file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000350 7.27710056 [3960] Locked file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000351 7.27727938 [3960] Unlocking file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000352 7.27743959 [3960] Unlocked file: K:\My Documents\My Music\Santana\Ultimate Santana\65535 - Santana - Into the Night.m4p 00000353 7.27748585 [3960] F_AAC: Opening file: OpenMode: 1 00000354 7.28208065 [3960] F_AAC: Entered AAC_ScanAtoms 00000355 7.28233624 [3960] F_AAC: Found FTYP atom, FileSize: 3580992 00000356 7.28365946 [3960] F_AAC: Found Data Atom: name, Length: 106, data payload: 000018C9 00001898 00007D68 000076E6 00019B10 0002DD55 00008000 00008000 000018AB 00000BE1 00000357 7.28370667 [3960] F_AAC: Found Data Atom: İnam, Length: 30, data payload: Into the Night 00000358 7.28376436 [3960] F_AAC: Found Data Atom: apID, Length: 40, data payload: russellsamuels@yahoo.com 00000359 7.28383446 [3960] F_AAC: Found Data Atom: purd, Length: 35, data payload: 2007-11-02 01:11:25 00000360 7.28387690 [3960] F_AAC: Found Data Atom: rate, Length: 18, data payload: -1 00000361 7.28392649 [3960] F_AAC: Found Data Atom: İART, Length: 23, data payload: Santana 00000362 7.28397322 [3960] F_AAC: Found Data Atom: aART, Length: 23, data payload: Santana 00000363 7.28401661 [3960] F_AAC: Found Data Atom: İalb, Length: 32, data payload: Ultimate Santana 00000364 7.28406000 [3960] F_AAC: Found Data Atom: İday, Length: 20, data payload: 2007 00000365 7.28609943 [3960] F_AAC: AAC_ExtractDetails: bitrate: 128000, channels: 2, lengthms: -916259690 00000366 7.28619385 [3960] FH.ReadFileInfo openned 00000367 7.28717375 [3960] 3496 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000368 7.28760815 [3960] 3496 DB lock finished, took 8014. 00000369 7.28765583 [3960] Added Cover Lists: 2 00000370 7.28782845 [3960] Remaining Cover Lists: 1 00000371 7.28789520 [3960] GetFileInfo: Found in DB 00000372 7.28794956 [3960] 3496 DB open SQL: SELECT * FROM Covers WHERE IDSong=4118 ORDER BY CoverOrder 00000373 7.28850365 [3960] 3496 DB lock finished, took 11781. 00000374 7.29510355 [3960] Winamp simulation window received messsage 12 00000375 7.29513979 [3960] Winamp sim - message left to the default processing 00000376 7.29553986 [3960] GetFileInfo: K:\My Documents\My Music\Air\Moon Safari\5 - Air - Talisman.mp3 00000377 7.29619265 [3960] 3776 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000378 7.29623938 [3960] 3776 DB lock finished, took 13069. 00000379 7.29653692 [3960] GetFileInfo: Found in DB 00000380 7.29693174 [3960] GetFileInfo: K:\My Documents\My Music\Alicia Keys\No One - Single\1 - Alicia Keys - No One.m4p 00000381 7.29712248 [3960] 3776 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000382 7.30522108 [3960] 3776 DB lock finished, took 145639. 00000383 7.30705929 [3960] GetFileInfo: Found in DB 00000384 7.31057739 [3960] GetFileInfo: K:\My Documents\My Music\Chemical Brothers\Mix\12 - Chemical Brothers - Replacement Killers.mp3 00000385 7.31087303 [3960] 3776 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000386 7.31316805 [3960] 3776 DB lock finished, took 40580. 00000387 7.31355143 [3960] GetFileInfo: Found in DB 00000388 7.32246351 [3960] 3496 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 00000389 7.32286215 [3960] 3496 DB lock finished, took 8221. 00000390 7.32291603 [3960] 3496 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 00000391 7.32311869 [3960] 3496 DB lock finished, took 3915. 00000392 7.32316685 [3960] 3496 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=1 ) 00000393 7.32336378 [3960] 3496 DB lock finished, took 3754. 00000394 7.32340860 [3960] 3496 DB open SQL: SELECT Songs.ID FROM Songs WHERE Songs.ID=? AND ( Songs.TrackType=2 ) 00000395 7.32359982 [3960] 3496 DB lock finished, took 3633. 00000396 7.32421827 [3960] Going to make final callback 3776 00000397 7.33054638 [3960] DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000398 7.33119869 [3960] Thread has finished 3776 00000399 7.50394058 [3960] Node Now Playing starting. 00000400 7.50400114 [3960] Node Now Playing going to clear SongList. 00000401 7.52469587 [3960] GetFileInfo: K:\My Documents\My Music\Air\Moon Safari\1 - Air - Femme d'Argent.mp3 00000402 7.52477741 [3960] GetFileInfo: readPictures=spAlways 00000403 7.52482986 [3960] GetFileInfo: K:\My Documents\My Music\Air\Moon Safari\1 - Air - Femme d'Argent.mp3 00000404 7.52505922 [3960] Added Cover Lists: 2 00000405 7.52535105 [3960] MP3.ReadHeader K:\My Documents\My Music\Air\Moon Safari\1 - Air - Femme d'Argent.mp3 00000406 7.52542734 [3960] MP3.ReadHeader - File size 11831296 00000407 7.52553225 [3960] MP3.ReadHeader - Found ID3v2, length: 32268 00000408 7.52560520 [3960] MP3.ReadHeader framestarts: 32278, 32278 00000409 7.52567434 [3960] MP3.ReadHeader finished 00000410 7.52586222 [3960] MP3: Going to read tag from K:\My Documents\My Music\Air\Moon Safari\1 - Air - Femme d'Argent.mp3 00000411 7.53209925 [3960] Added Covers: 1 00000412 7.53225422 [3960] MP3: Tag read from K:\My Documents\My Music\Air\Moon Safari\1 - Air - Femme d'Argent.mp3 00000413 7.53236485 [3960] 3496 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000414 7.53298712 [3960] 3496 DB lock finished, took 12977. 00000415 7.53303289 [3960] Added Cover Lists: 3 00000416 7.53307390 [3960] Added Covers: 2 00000417 7.53323650 [3960] Remaining Covers: 1 00000418 7.53325939 [3960] Remaining Cover Lists: 2 00000419 7.53333807 [3960] GetFileInfo: Found in DB 00000420 7.53340149 [3960] 3496 DB open SQL: SELECT * FROM Covers WHERE IDSong=894 ORDER BY CoverOrder 00000421 7.53410816 [3960] 3496 DB lock finished, took 14870. 00000422 7.53415823 [3960] Added Covers: 2 00000423 7.53422976 [3960] Remaining Covers: 1 00000424 7.53437710 [3960] Added Covers: 2 00000425 7.53449392 [3960] Added Covers: 3 00000426 7.54801559 [3960] Going to make final callback 3492 00000427 7.54816723 [3960] Thread has finished 3492 00000428 10.81394958 [3960] WMDM: Notifications ok. 00000429 10.81399441 [3960] WMDM: Looking for device changes... 00000430 10.81406689 [3960] WMDM: Finished action id: 0 00000431 11.08018017 [3960] Remaining Covers: 2 00000432 11.08026218 [3960] Remaining Covers: 1 00000433 11.08029652 [3960] Remaining Covers: 0 00000434 11.08033371 [3960] Remaining Cover Lists: 1 00000435 11.08038521 [3960] GetFileInfo: K:\My Documents\My Music\Chemical Brothers\Mix\12 - Chemical Brothers - Replacement Killers.mp3 00000436 11.08041954 [3960] GetFileInfo: readPictures=spAlways 00000437 11.08045483 [3960] GetFileInfo: K:\My Documents\My Music\Chemical Brothers\Mix\12 - Chemical Brothers - Replacement Killers.mp3 00000438 11.08064747 [3960] Added Cover Lists: 2 00000439 11.08082962 [3960] MP3.ReadHeader K:\My Documents\My Music\Chemical Brothers\Mix\12 - Chemical Brothers - Replacement Killers.mp3 00000440 11.08087635 [3960] MP3.ReadHeader - File size 5964003 00000441 11.08096027 [3960] MP3.ReadHeader - Found ID3v2, length: 2038 00000442 11.08099842 [3960] MP3.ReadHeader framestarts: 2048, 2048 00000443 11.08103275 [3960] MP3.ReadHeader CheckMoreHeaders started 00000444 11.08106995 [3960] MP3.ReadHeader CheckMoreHeaders Endtest: 5120 00000445 11.08110428 [3960] MP3.ReadHeader CheckMoreHeaders finished 00000446 11.08115578 [3960] MP3.ReadHeader finished 00000447 11.08128548 [3960] MP3: Going to read tag from K:\My Documents\My Music\Chemical Brothers\Mix\12 - Chemical Brothers - Replacement Killers.mp3 00000448 11.08529282 [3960] MP3: Tag read from K:\My Documents\My Music\Chemical Brothers\Mix\12 - Chemical Brothers - Replacement Killers.mp3 00000449 11.08546543 [3960] 3496 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000450 11.08584023 [3960] 3496 DB lock finished, took 8119. 00000451 11.08588409 [3960] Added Cover Lists: 3 00000452 11.08599949 [3960] Remaining Cover Lists: 2 00000453 11.08606625 [3960] GetFileInfo: Found in DB 00000454 11.08611965 [3960] 3496 DB open SQL: SELECT * FROM Covers WHERE IDSong=1386 ORDER BY CoverOrder 00000455 11.08685017 [3960] 3496 DB lock finished, took 15294. 00000456 11.85107613 [3960] Burn strings translation: started 00000457 11.85136223 [3960] Burn strings translation: finished 00000458 11.92946911 [3960] Burning Wizard: UI adjusted 00000459 11.93839645 [3960] 3496 DB open SQL: SELECT WebCommercial, WebCopyright, WebFilepage, WebArtist, WebSource, WebRadio, WebPayment, WebPublisher, WebUser, PreviewStartTime, PreviewLength, CacheName, PreviewName, Copyright, Publisher, Encoder, ISRC, InitialKey, Language, OrigLyricist,PreGap, PostGap, TotalSamples, GaplessBytes, SignType, SignPart1, SignPart2, SignPart3, SignPart4 FROM Songs WHERE ID = 894 00000460 11.93900394 [3960] 3496 DB lock finished, took 12760. 00000461 11.93917179 [3960] 3496 DB open SQL: SELECT WebCommercial, WebCopyright, WebFilepage, WebArtist, WebSource, WebRadio, WebPayment, WebPublisher, WebUser, PreviewStartTime, PreviewLength, CacheName, PreviewName, Copyright, Publisher, Encoder, ISRC, InitialKey, Language, OrigLyricist,PreGap, PostGap, TotalSamples, GaplessBytes, SignType, SignPart1, SignPart2, SignPart3, SignPart4 FROM Songs WHERE ID = 897 00000462 11.93961239 [3960] 3496 DB lock finished, took 9332. 00000463 11.93975544 [3960] 3496 DB open SQL: SELECT WebCommercial, WebCopyright, WebFilepage, WebArtist, WebSource, WebRadio, WebPayment, WebPublisher, WebUser, PreviewStartTime, PreviewLength, CacheName, PreviewName, Copyright, Publisher, Encoder, ISRC, InitialKey, Language, OrigLyricist,PreGap, PostGap, TotalSamples, GaplessBytes, SignType, SignPart1, SignPart2, SignPart3, SignPart4 FROM Songs WHERE ID = 899 00000464 11.94015598 [3960] 3496 DB lock finished, took 8468. 00000465 11.94029808 [3960] 3496 DB open SQL: SELECT WebCommercial, WebCopyright, WebFilepage, WebArtist, WebSource, WebRadio, WebPayment, WebPublisher, WebUser, PreviewStartTime, PreviewLength, CacheName, PreviewName, Copyright, Publisher, Encoder, ISRC, InitialKey, Language, OrigLyricist,PreGap, PostGap, TotalSamples, GaplessBytes, SignType, SignPart1, SignPart2, SignPart3, SignPart4 FROM Songs WHERE ID = 958 00000466 11.94069290 [3960] 3496 DB lock finished, took 8314. 00000467 11.94083500 [3960] 3496 DB open SQL: SELECT WebCommercial, WebCopyright, WebFilepage, WebArtist, WebSource, WebRadio, WebPayment, WebPublisher, WebUser, PreviewStartTime, PreviewLength, CacheName, PreviewName, Copyright, Publisher, Encoder, ISRC, InitialKey, Language, OrigLyricist,PreGap, PostGap, TotalSamples, GaplessBytes, SignType, SignPart1, SignPart2, SignPart3, SignPart4 FROM Songs WHERE ID = 1386 00000468 11.94123936 [3960] 3496 DB lock finished, took 8430. 00000469 11.94135380 [3960] Burning Wizard: Memory structures initialized, number of pre-selected tracks: 6 00000470 11.95605850 [3960] Burning Wizard: Masks initialized 00000471 11.95618534 [3960] DriveChanged: entering 00000472 11.95643520 [3960] DriveChanged: NO WRITABLE DRIVE! 00000473 11.96206474 [3960] Burning Wizard: Checkboxes initialized 00000474 11.96329975 [3960] Burn Tree Loaded 00000475 11.96643448 [3960] Burner: InitBurner executed 00000476 11.97076988 [3960] hpCDEBurn: Engine created... 00000477 11.97080421 [3960] hpCDEBurn: Version obtained... 00000478 11.97084045 [3960] hpCDEBurn: Engine initialized successfuly... 00000479 11.97243881 [3960] hpCDEBurn: Device enumerator obtained... 00000480 12.05723667 [3960] hpCDEBurn: Device initialized: TSSTcorp, CDDVDW SH-S223B vSB01 (3:0:0) 00000481 12.05737209 [3960] Burner: StartUpBurner result =0 00000482 12.05748844 [3960] Burner: TBurner created 00000483 12.07270527 [3960] 3496 DB open SQL: SELECT * FROM Filters ORDER BY Pos 00000484 12.07345009 [3960] 3496 DB lock finished, took 14813. 00000485 12.07773685 [3960] Forms: NormalizeTopMosts: Level: 0 00000486 12.07792473 [3960] Forms: NormalizeTopMost: Starting 00000487 15.04385376 [3960] DriveChanged: entering 00000488 15.04393768 [3960] hpCDEBurn: UpdateDeviceMedia... 00000489 15.12940407 [3960] hpCDEBurn: Device refreshed 00000490 15.22963619 [3960] DriveChanged: UpdateDeviceMedia finished 00000491 15.22968292 [3960] hpCDEBurn: Getting device speeds... 00000492 15.31528473 [3960] hpCDEBurn: Device refreshed 00000493 15.31533337 [3960] hpCDEBurn: Max speed found - 8468 00000494 15.34634876 [3960] hpCDEBurn: We are using a list of speed given by device 00000495 15.34641552 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000496 15.34645748 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000497 15.34649849 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000498 15.34653664 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000499 15.34657669 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000500 15.34662151 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000501 15.34672260 [3960] DriveChanged: GetDeviceSpeeds finished 00000502 15.34683132 [3960] DriveChanged: Status bar updating finished 00000503 15.34692860 [3960] DriveChanged: Drive Info updated 00000504 15.34699345 [3960] Added speed to box: 48.0 x 00000505 15.34703827 [3960] Added speed to box: 40.0 x 00000506 15.34708214 [3960] Added speed to box: 32.0 x 00000507 15.34712601 [3960] Added speed to box: 24.0 x 00000508 15.34719276 [3960] Added speed to box: 16.0 x 00000509 15.34949780 [3960] DriveChanged: Disc info and speeds updated 00000510 15.35194111 [3960] DriveChanged: entering 00000511 15.35198879 [3960] hpCDEBurn: UpdateDeviceMedia... 00000512 15.43218708 [3960] hpCDEBurn: Device refreshed 00000513 15.53432941 [3960] DriveChanged: UpdateDeviceMedia finished 00000514 15.53437328 [3960] hpCDEBurn: Getting device speeds... 00000515 15.61550999 [3960] hpCDEBurn: Device refreshed 00000516 15.61555672 [3960] hpCDEBurn: Max speed found - 8468 00000517 15.64543629 [3960] hpCDEBurn: We are using a list of speed given by device 00000518 15.64549732 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000519 15.64553833 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000520 15.64557838 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000521 15.64561749 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000522 15.64565754 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000523 15.64569855 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000524 15.64578342 [3960] DriveChanged: GetDeviceSpeeds finished 00000525 15.64588451 [3960] DriveChanged: Status bar updating finished 00000526 15.64595699 [3960] DriveChanged: Drive Info updated 00000527 15.64601994 [3960] Added speed to box: 48.0 x 00000528 15.64606476 [3960] Added speed to box: 40.0 x 00000529 15.64610672 [3960] Added speed to box: 32.0 x 00000530 15.64615059 [3960] Added speed to box: 24.0 x 00000531 15.64636135 [3960] Added speed to box: 16.0 x 00000532 15.64638710 [3960] DriveChanged: Disc info and speeds updated 00000533 15.64889717 [3960] DriveChanged: entering 00000534 15.64894676 [3960] hpCDEBurn: UpdateDeviceMedia... 00000535 15.73485184 [3960] hpCDEBurn: Device refreshed 00000536 15.83165169 [3960] DriveChanged: UpdateDeviceMedia finished 00000537 15.83169556 [3960] hpCDEBurn: Getting device speeds... 00000538 15.91774464 [3960] hpCDEBurn: Device refreshed 00000539 15.91779709 [3960] hpCDEBurn: Max speed found - 8468 00000540 15.94709873 [3960] hpCDEBurn: We are using a list of speed given by device 00000541 15.94715309 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000542 15.94738293 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000543 15.94744110 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000544 15.94749451 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000545 15.94751263 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000546 15.94755840 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000547 15.94764900 [3960] DriveChanged: GetDeviceSpeeds finished 00000548 15.94775009 [3960] DriveChanged: Status bar updating finished 00000549 15.94782162 [3960] DriveChanged: Drive Info updated 00000550 15.94788265 [3960] Added speed to box: 48.0 x 00000551 15.94792557 [3960] Added speed to box: 40.0 x 00000552 15.94797039 [3960] Added speed to box: 32.0 x 00000553 15.94801426 [3960] Added speed to box: 24.0 x 00000554 15.94806004 [3960] Added speed to box: 16.0 x 00000555 15.94813538 [3960] DriveChanged: Disc info and speeds updated 00000556 15.94830608 [3960] DriveChanged: entering 00000557 15.94834423 [3960] hpCDEBurn: UpdateDeviceMedia... 00000558 16.03242302 [3960] hpCDEBurn: Device refreshed 00000559 16.13570404 [3960] DriveChanged: UpdateDeviceMedia finished 00000560 16.13582420 [3960] hpCDEBurn: Getting device speeds... 00000561 16.22368813 [3960] hpCDEBurn: Device refreshed 00000562 16.22434998 [3960] hpCDEBurn: Max speed found - 8468 00000563 16.25435638 [3960] hpCDEBurn: We are using a list of speed given by device 00000564 16.25441360 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000565 16.25445557 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000566 16.25449562 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000567 16.25453377 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000568 16.25457191 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000569 16.25461388 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000570 16.25469971 [3960] DriveChanged: GetDeviceSpeeds finished 00000571 16.25479889 [3960] DriveChanged: Status bar updating finished 00000572 16.25487518 [3960] DriveChanged: Drive Info updated 00000573 16.25493431 [3960] Added speed to box: 48.0 x 00000574 16.25498009 [3960] Added speed to box: 40.0 x 00000575 16.25502396 [3960] Added speed to box: 32.0 x 00000576 16.25506592 [3960] Added speed to box: 24.0 x 00000577 16.25510979 [3960] Added speed to box: 16.0 x 00000578 16.25530815 [3960] DriveChanged: Disc info and speeds updated 00000579 16.27817535 [3960] DriveChanged: entering 00000580 16.27822685 [3960] hpCDEBurn: UpdateDeviceMedia... 00000581 16.36236763 [3960] hpCDEBurn: Device refreshed 00000582 16.46245003 [3960] DriveChanged: UpdateDeviceMedia finished 00000583 16.46249390 [3960] hpCDEBurn: Getting device speeds... 00000584 16.54509735 [3960] hpCDEBurn: Device refreshed 00000585 16.54514885 [3960] hpCDEBurn: Max speed found - 8468 00000586 16.57537079 [3960] hpCDEBurn: We are using a list of speed given by device 00000587 16.57545662 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000588 16.57547569 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000589 16.57551575 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000590 16.57555580 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000591 16.57559395 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000592 16.57563591 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000593 16.57572174 [3960] DriveChanged: GetDeviceSpeeds finished 00000594 16.57582283 [3960] DriveChanged: Status bar updating finished 00000595 16.57589531 [3960] DriveChanged: Drive Info updated 00000596 16.57595634 [3960] Added speed to box: 48.0 x 00000597 16.57600212 [3960] Added speed to box: 40.0 x 00000598 16.57604408 [3960] Added speed to box: 32.0 x 00000599 16.57608795 [3960] Added speed to box: 24.0 x 00000600 16.57613182 [3960] Added speed to box: 16.0 x 00000601 16.57623672 [3960] DriveChanged: Disc info and speeds updated 00000602 16.57651138 [3960] DriveChanged: entering 00000603 16.57656479 [3960] hpCDEBurn: UpdateDeviceMedia... 00000604 16.66047096 [3960] hpCDEBurn: Device refreshed 00000605 16.75975990 [3960] DriveChanged: UpdateDeviceMedia finished 00000606 16.75980186 [3960] hpCDEBurn: Getting device speeds... 00000607 16.84368324 [3960] hpCDEBurn: Device refreshed 00000608 16.84373283 [3960] hpCDEBurn: Max speed found - 8468 00000609 16.87427902 [3960] hpCDEBurn: We are using a list of speed given by device 00000610 16.87434006 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000611 16.87438011 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000612 16.87441826 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000613 16.87445831 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000614 16.87449837 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000615 16.87454033 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000616 16.87462234 [3960] DriveChanged: GetDeviceSpeeds finished 00000617 16.87472343 [3960] DriveChanged: Status bar updating finished 00000618 16.87479591 [3960] DriveChanged: Drive Info updated 00000619 16.87485886 [3960] Added speed to box: 48.0 x 00000620 16.87490082 [3960] Added speed to box: 40.0 x 00000621 16.87494469 [3960] Added speed to box: 32.0 x 00000622 16.87498856 [3960] Added speed to box: 24.0 x 00000623 16.87503242 [3960] Added speed to box: 16.0 x 00000624 16.87510872 [3960] DriveChanged: Disc info and speeds updated 00000625 21.87145424 [3960] DriveChanged: entering 00000626 21.87154770 [3960] hpCDEBurn: UpdateDeviceMedia... 00000627 21.95466042 [3960] hpCDEBurn: Device refreshed 00000628 22.05584717 [3960] DriveChanged: UpdateDeviceMedia finished 00000629 22.05588913 [3960] hpCDEBurn: Getting device speeds... 00000630 22.13513947 [3960] hpCDEBurn: Device refreshed 00000631 22.13525009 [3960] hpCDEBurn: Max speed found - 8468 00000632 22.16516685 [3960] hpCDEBurn: We are using a list of speed given by device 00000633 22.16530609 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000634 22.16534805 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000635 22.16537666 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000636 22.16541481 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000637 22.16545486 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000638 22.16549683 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000639 22.16558075 [3960] DriveChanged: GetDeviceSpeeds finished 00000640 22.16568565 [3960] DriveChanged: Status bar updating finished 00000641 22.16576004 [3960] DriveChanged: Drive Info updated 00000642 22.16581917 [3960] Added speed to box: 48.0 x 00000643 22.16586494 [3960] Added speed to box: 40.0 x 00000644 22.16590881 [3960] Added speed to box: 32.0 x 00000645 22.16595268 [3960] Added speed to box: 24.0 x 00000646 22.16599464 [3960] Added speed to box: 16.0 x 00000647 22.16607285 [3960] DriveChanged: Disc info and speeds updated 00000648 27.16058350 [3960] DriveChanged: entering 00000649 27.16066551 [3960] hpCDEBurn: UpdateDeviceMedia... 00000650 27.24426270 [3960] hpCDEBurn: Device refreshed 00000651 27.34380341 [3960] DriveChanged: UpdateDeviceMedia finished 00000652 27.34385109 [3960] hpCDEBurn: Getting device speeds... 00000653 27.42947769 [3960] hpCDEBurn: Device refreshed 00000654 27.42952919 [3960] hpCDEBurn: Max speed found - 8468 00000655 27.45913887 [3960] hpCDEBurn: We are using a list of speed given by device 00000656 27.45920181 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000657 27.45924377 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000658 27.45933151 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000659 27.45936394 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000660 27.45950699 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000661 27.45955658 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000662 27.45964813 [3960] DriveChanged: GetDeviceSpeeds finished 00000663 27.45975113 [3960] DriveChanged: Status bar updating finished 00000664 27.45982552 [3960] DriveChanged: Drive Info updated 00000665 27.45988274 [3960] Added speed to box: 48.0 x 00000666 27.45992661 [3960] Added speed to box: 40.0 x 00000667 27.45997238 [3960] Added speed to box: 32.0 x 00000668 27.46001625 [3960] Added speed to box: 24.0 x 00000669 27.46006203 [3960] Added speed to box: 16.0 x 00000670 27.46013641 [3960] DriveChanged: Disc info and speeds updated 00000671 27.47329712 [3960] Forms: RestoreTopMost: Level: 1 00000672 27.47342110 [3960] Forms: RestoreTopMost: Starting 00000673 27.49376678 [3960] BurnAudioCD(): LastIndex = 0 ,TrackIndex = 5 00000674 27.49880409 [3960] Burn: On-the-fly audio burning. 00000675 27.50452805 [3960] Thread has started 3316 00000676 27.50657845 [3960] Thread registered, going to initialize 3316 00000677 27.50661659 [3960] Thread initialized 3316 00000678 27.51517677 [3960] New thread starting 1 00000679 27.52034569 [3960] Thread has started 3340 00000680 27.52041817 [3960] Thread registered, going to initialize 3340 00000681 27.52079010 [3960] Thread initialized 3340 00000682 27.57164383 [3960] Forms: NormalizeTopMosts: Level: 0 00000683 27.57181931 [3960] Forms: NormalizeTopMost: Starting 00000684 29.79235840 [3960] hpCDEBurn: Checking whether 8467 KB speed (set by user) can be used 00000685 29.79240990 [3960] hpCDEBurn: Getting device speeds... 00000686 29.87619400 [3960] hpCDEBurn: Device refreshed 00000687 29.87624168 [3960] hpCDEBurn: Max speed found - 8468 00000688 29.90858269 [3960] hpCDEBurn: We are using a list of speed given by device 00000689 29.90862083 [3960] hpCDEBurn: AddSpeed proc. - speed: 8467, multiplier: 176.400000 00000690 29.90876579 [3960] hpCDEBurn: AddSpeed proc. - speed: 7056, multiplier: 176.400000 00000691 29.90881538 [3960] hpCDEBurn: AddSpeed proc. - speed: 5644, multiplier: 176.400000 00000692 29.90885353 [3960] hpCDEBurn: AddSpeed proc. - speed: 4233, multiplier: 176.400000 00000693 29.90889549 [3960] hpCDEBurn: AddSpeed proc. - speed: 2822, multiplier: 176.400000 00000694 29.90894127 [3960] hpCDEBurn: AddSpeed proc. - speed: 8468, multiplier: 176.400000 00000695 29.90903854 [3960] hpCDEBurn: Selected speed was found in the list 00000696 29.92670631 [3960] Writing percentage: 00000697 29.92694473 [3960] hpCDEBurn: Going to create CD session. 00000698 32.40322495 [3960] Forms: RestoreTopMost: Level: 1 00000699 32.40328598 [3960] Forms: RestoreTopMost: Starting 00000700 32.42295837 [3960] Dialog: Burn operation failed! Some of the tracks are protected by Digital Rights Management, preventing the disk from being burnt. result: 1 00000701 32.42361069 [3960] Going to make final callback 3316 00000702 32.42372894 [3960] Thread has finished 3316 00000703 35.33753204 [3960] Thread has started 2084 00000704 35.33760834 [3960] Thread registered, going to initialize 2084 00000705 35.33764648 [3960] Thread initialized 2084 00000706 35.34856415 [3960] Going to make final callback 2084 00000707 35.34863663 [3960] Thread has finished 2084 00000708 42.59007645 [3960] Forms: Received WM_ACTIVATEAPP 00000709 42.59020233 [3960] Forms: Accepted WM_ACTIVATEAPP - 00000710 42.59077072 [3960] Forms: NormalizeTopMosts: Level: 0 00000711 42.59187698 [3960] Forms: NormalizeTopMost: Starting 00000712 42.59207535 [3960] Winamp sim - ignoring. 00000713 42.59213638 [3960] Winamp simulation window received messsage 28 00000714 42.59781647 [3960] Winamp sim - ignoring. 00000715 65.35288239 [3960] Thread has started 3236 00000716 65.35298157 [3960] Thread registered, going to initialize 3236 00000717 65.35305023 [3960] Thread initialized 3236 00000718 65.35764313 [3960] Going to make final callback 3236 00000719 65.35776520 [3960] Thread has finished 3236 00000720 66.27826691 [3960] Forms: Received WM_ACTIVATEAPP 00000721 66.27828979 [3960] Forms: Accepted WM_ACTIVATEAPP + 00000722 66.27833557 [3960] Forms: RestoreTopMost: Level: 1 00000723 66.27837372 [3960] Forms: RestoreTopMost: Starting 00000724 66.27902222 [3960] Winamp sim - ignoring. 00000725 66.27906799 [3960] Winamp simulation window received messsage 28 00000726 66.27910614 [3960] Winamp sim - ignoring. 00000727 66.45539093 [3960] We were asked to finish. 00000728 66.47698212 [3960] Forms: NormalizeTopMosts: Level: 0 00000729 66.47715759 [3960] Forms: NormalizeTopMost: Starting 00000730 71.24562073 [3960] Forms: RestoreTopMost: Level: 1 00000731 71.24568939 [3960] Forms: RestoreTopMost: Starting 00000732 71.25647736 [3960] Dialog: There are background processes running. Terminate them? result: 6 00000733 71.25963593 [3960] We are really going to finish. 00000734 71.27288818 [3960] Forms: Received WM_ACTIVATEAPP 00000735 71.27297974 [3960] Forms: Accepted WM_ACTIVATEAPP - 00000736 71.27335358 [3960] Forms: NormalizeTopMosts: Level: 0 00000737 71.27379608 [3960] Forms: NormalizeTopMost: Starting 00000738 71.27507782 [3960] Winamp sim - ignoring. 00000739 71.27515411 [3960] Winamp simulation window received messsage 28 00000740 71.27581024 [3960] Winamp sim - ignoring. 00000741 71.27683258 [3960] Close auto-tag. 00000742 71.27693176 [3960] _Stop: started 00000743 71.28480530 [3960] _Stop: finished 00000744 71.28668976 [3960] Playback stopped. 00000745 71.28677368 [3960] Termination of all threads started. 00000746 71.28683472 [3960] There is currently 1 threads running. 00000747 71.28729248 [3960] All threads were asked to terminate. 00000748 95.83237457 [3960] Thread has started 852 00000749 95.83345795 [3960] Thread is not allowed to start 852 00000750 125.42648315 [3960] Thread has started 3224 00000751 125.42654419 [3960] Thread is not allowed to start 3224 00000752 155.42974854 [3960] Thread has started 2648 00000753 155.42985535 [3960] Thread is not allowed to start 2648 00000754 185.59547424 [3960] Thread has started 1044 00000755 185.59649658 [3960] Thread is not allowed to start 1044