00000001 1:22:27 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TFullPlayer.CheckPlayCounter 00000002 1:22:29 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TFullPlayer.CheckPlayCounter 00000003 1:22:31 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TFullPlayer.CheckPlayCounter 00000004 1:22:33 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TFullPlayer.CheckPlayCounter 00000005 1:22:35 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TFullPlayer.CheckPlayCounter 00000006 1:22:37 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TFullPlayer.CheckPlayCounter 00000007 1:22:39 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TFullPlayer.CheckPlayCounter 00000008 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVICEARRIVAL 00000009 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVTYP_VOLUME 00000010 1:22:40 PM [2404] MM5 [4276](R) TDevices.WMDeviceChangeToAll( wParam: 32768 , lParam: 648592) 00000011 1:22:40 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: UpdateDriveLetters_Thread 00000012 1:22:40 PM [2404] MM5 [4276](R) BQ: Currently running 1 threads of 10 00000013 1:22:40 PM [2404] MM5 [24392](R) >UpdateDriveLetters - start< 00000014 1:22:40 PM [2404] MM5 [24392](R) >UpdateDriveLetters - exists: C:\ [Windows], type: 3 00000015 1:22:40 PM [2404] MM5 [24392](R) >UpdateDriveLetters - exists: D:\ [Datas], type: 3 00000016 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVICEARRIVAL 00000017 1:22:40 PM [2404] MM5 [24392](R) >UpdateDriveLetters - exists: E:\ [RECOVERY], type: 3 00000018 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVTYP_VOLUME 00000019 1:22:40 PM [2404] MM5 [4276](R) TDevices.WMDeviceChangeToAll( wParam: 32768 , lParam: 648592) 00000020 1:22:40 PM [2404] MM5 [4276](R) BQ: Currently running 2 threads of 10 00000021 1:22:40 PM [2404] MM5 [7972](R) BQ: Thread[7972] is executing new task now: TDevices.WMDeviceChangeToAll 00000022 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVICEARRIVAL 00000023 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVTYP_VOLUME 00000024 1:22:40 PM [2404] MM5 [4276](R) TDevices.WMDeviceChangeToAll( wParam: 32768 , lParam: 648592) 00000025 1:22:40 PM [2404] MM5 [4276](R) BQ: Currently running 3 threads of 10 00000026 1:22:40 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: TDevices.WMDeviceChangeToAll 00000027 1:22:40 PM [2404] MM5 [16512](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000028 1:22:40 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: TDevices.WMDeviceChangeToAll 00000029 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVICEARRIVAL 00000030 1:22:40 PM [2404] MM5 [16512](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000031 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVTYP_VOLUME 00000032 1:22:40 PM [2404] MM5 [4276](R) TDevices.WMDeviceChangeToAll( wParam: 32768 , lParam: 648592) 00000033 1:22:40 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: TDevices.WMDeviceChangeToAll 00000034 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVICEARRIVAL 00000035 1:22:40 PM [2404] MM5 [16512](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000036 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVTYP_VOLUME 00000037 1:22:40 PM [2404] MM5 [4276](R) TDevices.WMDeviceChangeToAll( wParam: 32768 , lParam: 648592) 00000038 1:22:40 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TDevices.WMDeviceChangeToAll 00000039 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVICEARRIVAL 00000040 1:22:40 PM [2404] MM5 [6048](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000041 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVTYP_VOLUME 00000042 1:22:40 PM [2404] MM5 [4276](R) TDevices.WMDeviceChangeToAll( wParam: 32768 , lParam: 648592) 00000043 1:22:40 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: TDevices.WMDeviceChangeToAll 00000044 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVICEARRIVAL 00000045 1:22:40 PM [2404] MM5 [16512](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000046 1:22:40 PM [2404] MM5 [4276](R) Media: DBT_DEVTYP_VOLUME 00000047 1:22:40 PM [2404] MM5 [4276](R) TDevices.WMDeviceChangeToAll( wParam: 32768 , lParam: 648592) 00000048 1:22:40 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TDevices.WMDeviceChangeToAll 00000049 1:22:40 PM [2404] MM5 [6048](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000050 1:22:40 PM [2404] iPod: iPodConnected.ProcessEntries: CanonName: VID_05AC&PID_12 00000051 1:22:40 PM [2404] iPod: iPodConnected.ProcessEntries: CanonName: VID_05AC&PID_13 00000052 1:22:40 PM [2404] MM5 [24392](R) >UpdateDriveLetters - exists: F:\ [], type: 5 00000053 1:22:40 PM [2404] iPod: iPodConnected.ProcessEntries: CanonName: 1394\APPLE_COMPUTER__INC.&IPOD 00000054 1:22:40 PM [2404] iPod: iPodConnected.ProcessEntries: CanonName: VID_22B8&PID_4810 00000055 1:22:40 PM [2404] iPod: iPodConnected.ProcessEntries: CanonName: VID_05AC&PID_80 00000056 1:22:40 PM [2404] iPod: DeviceChange: wParam: 32768, devtype: 2 00000057 1:22:40 PM [2404] WMDM: Device change message 00000058 1:22:40 PM [2404] WMDM: Queueing action id:0 00000059 1:22:40 PM [2404] WMDM: Starting action id: 0 00000060 1:22:40 PM [2404] WMDM: Looking for device changes... 00000061 1:22:40 PM [2404] WMDM: Finished action id: 0 00000062 1:22:40 PM [2404] MM5 [24392](R) >UpdateDriveLetters - exists: G:\ [Justin Bieber - Changes], type: 5 00000063 1:22:40 PM [2404] MM5 [24392](R) CD: Procedure entered (GetInfoFromAudioCD), Drive letter: G 00000064 1:22:40 PM [2404] MM5 [24392](R) CD: AudioCDReadCS.Acquired, Drive letter: G 00000065 1:22:40 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: TSharedBase.RunAsPromiseNative 00000066 1:22:41 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TFullPlayer.CheckPlayCounter 00000067 1:22:42 PM [2404] hpCDEBurn: On the point of reading TOC 00000068 1:22:42 PM [2404] hpCDEBurn: Read TOC of 0. track - LBA value: 0 00000069 1:22:42 PM [2404] hpCDEBurn: Read TOC of 0. track - MSF format: 0, 0, 2, 0 00000070 1:22:42 PM [2404] hpCDEBurn: Read TOC of 1. track - LBA value: 10269 00000071 1:22:42 PM [2404] hpCDEBurn: Read TOC of 1. track - MSF format: 0, 2, 18, 69 00000072 1:22:42 PM [2404] hpCDEBurn: Read TOC of 2. track - LBA value: 22884 00000073 1:22:42 PM [2404] hpCDEBurn: Read TOC of 2. track - MSF format: 0, 5, 7, 9 00000074 1:22:42 PM [2404] hpCDEBurn: Read TOC of 3. track - LBA value: 37942 00000075 1:22:42 PM [2404] hpCDEBurn: Read TOC of 3. track - MSF format: 0, 8, 27, 67 00000076 1:22:42 PM [2404] hpCDEBurn: Read TOC of 4. track - LBA value: 53907 00000077 1:22:42 PM [2404] hpCDEBurn: Read TOC of 4. track - MSF format: 0, 12, 0, 57 00000078 1:22:42 PM [2404] hpCDEBurn: Read TOC of 5. track - LBA value: 69545 00000079 1:22:42 PM [2404] hpCDEBurn: Read TOC of 5. track - MSF format: 0, 15, 29, 20 00000080 1:22:42 PM [2404] hpCDEBurn: Read TOC of 6. track - LBA value: 84236 00000081 1:22:42 PM [2404] hpCDEBurn: Read TOC of 6. track - MSF format: 0, 18, 45, 11 00000082 1:22:42 PM [2404] hpCDEBurn: Read TOC of 7. track - LBA value: 100732 00000083 1:22:42 PM [2404] hpCDEBurn: Read TOC of 7. track - MSF format: 0, 22, 25, 7 00000084 1:22:42 PM [2404] hpCDEBurn: Read TOC of 8. track - LBA value: 114199 00000085 1:22:42 PM [2404] hpCDEBurn: Read TOC of 8. track - MSF format: 0, 25, 24, 49 00000086 1:22:42 PM [2404] hpCDEBurn: Read TOC of 9. track - LBA value: 127551 00000087 1:22:42 PM [2404] hpCDEBurn: Read TOC of 9. track - MSF format: 0, 28, 22, 51 00000088 1:22:42 PM [2404] hpCDEBurn: Read TOC of 10. track - LBA value: 142754 00000089 1:22:42 PM [2404] hpCDEBurn: Read TOC of 10. track - MSF format: 0, 31, 45, 29 00000090 1:22:42 PM [2404] hpCDEBurn: Read TOC of 11. track - LBA value: 156661 00000091 1:22:42 PM [2404] hpCDEBurn: Read TOC of 11. track - MSF format: 0, 34, 50, 61 00000092 1:22:42 PM [2404] hpCDEBurn: Read TOC of 12. track - LBA value: 169917 00000093 1:22:42 PM [2404] hpCDEBurn: Read TOC of 12. track - MSF format: 0, 37, 47, 42 00000094 1:22:42 PM [2404] hpCDEBurn: Read TOC of 13. track - LBA value: 180079 00000095 1:22:42 PM [2404] hpCDEBurn: Read TOC of 13. track - MSF format: 0, 40, 3, 4 00000096 1:22:42 PM [2404] hpCDEBurn: Read TOC of 14. track - LBA value: 192872 00000097 1:22:42 PM [2404] hpCDEBurn: Read TOC of 14. track - MSF format: 0, 42, 53, 47 00000098 1:22:42 PM [2404] hpCDEBurn: Read TOC of 15. track - LBA value: 205256 00000099 1:22:42 PM [2404] hpCDEBurn: Read TOC of 15. track - MSF format: 0, 45, 38, 56 00000100 1:22:42 PM [2404] hpCDEBurn: Read TOC of 16. track - LBA value: 216482 00000101 1:22:42 PM [2404] hpCDEBurn: Read TOC of 16. track - MSF format: 0, 48, 8, 32 00000102 1:22:42 PM [2404] MM5 [24392](R) CD: Going to read CD-Text. 00000103 1:22:42 PM [2404] hpCDEBurn: entering ReadCDText() 00000104 1:22:42 PM [2404] hpCDEBurn: On the point of calling pDevice->ReadCDText() 00000105 1:22:42 PM [2404] hpCDEBurn: pDevice->ReadCDText: res = 1 00000106 1:22:42 PM [2404] hpCDEBurn: - Count of CD-Text items: 1 00000107 1:22:42 PM [2404] hpCDEBurn: Reading of CD-Text for 0. track: Title: , Artist: 00000108 1:22:42 PM [2404] MM5 [24392](R) CD: CD-Text wasn't found. 00000109 1:22:42 PM [2404] MM5 [24392](R) CD: AudioCDReadCS.Released, Drive letter: G 00000110 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track01.cda 00000111 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000112 1:22:42 PM [2404] MM5 [24392](R) 24392 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000113 1:22:42 PM [2404] MM5 [24392](R) 24392 DB query prepare finished, took 0. 00000114 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track02.cda 00000115 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000116 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track03.cda 00000117 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000118 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track04.cda 00000119 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000120 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track05.cda 00000121 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000122 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track06.cda 00000123 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000124 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track07.cda 00000125 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000126 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track08.cda 00000127 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000128 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track09.cda 00000129 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000130 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track10.cda 00000131 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000132 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track11.cda 00000133 1:22:42 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000134 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track12.cda 00000135 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000136 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track13.cda 00000137 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000138 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track14.cda 00000139 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000140 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track15.cda 00000141 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000142 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: G:\Track16.cda 00000143 1:22:43 PM [2404] MM5 [24392](R) GetFileInfo: AudioCDTrack 00000144 1:22:43 PM [2404] MM5 [24392](R) CD: Procedure finished (GetInfoFromAudioCD) 00000145 1:22:43 PM [2404] MM5 [24392](R) New thread starting 1 00000146 1:22:43 PM [2404] MM5 [4276](R) registered task progress 102 00000147 1:22:43 PM [2404] MM5 [24392](R) >UpdateDriveLetters - drive changed: G:\ [Justin Bieber - Changes], type: 5 00000148 1:22:43 PM [2404] MM5 [7120](R) Prgrs: Creating a new level. 00000149 1:22:43 PM [2404] MM5 [24392](R) >UpdateDriveLetters - end< 00000150 1:22:43 PM [2404] MM5 [7120](R) MusicBrainz: querying discID: 7E1SieWul4WsUG3NnyNIcxMVpDQ- 00000151 1:22:43 PM [2404] MM5 [24392](R) BQ: Task took 2875 ms, stack: UpdateDriveLetters_Thread 00000152 1:22:43 PM [2404] MM5 [7120](R) TIndyHTTP.GetResponseContent started: URL: https://musicbrainz.org/ws/2/discid/7E1SieWul4WsUG3NnyNIcxMVpDQ-?fmt=json, RequestBody: 00000153 1:22:43 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: TFullPlayer.CheckPlayCounter 00000154 1:22:43 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 7443 ; Col: 29 00000155 1:22:43 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000156 1:22:43 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000157 1:22:43 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 7407 ; Col: 16 00000158 1:22:43 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000159 1:22:43 PM [2404] MM5 [6048](R) DB open SQL: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (4) LIMIT 1 00000160 1:22:43 PM [2404] MM5 [6048](R) DB open SQL: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (4) LIMIT 1 00000161 1:22:43 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 11 00000162 1:22:43 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000163 1:22:43 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 7466 ; Col: 42 00000164 1:22:43 PM [2404] MM5 [24392](R) DB open SQL: SELECT type, type_id, string_type_id FROM Pinned 00000165 1:22:43 PM [2404] MM5 [24392](R) DB open SQL: SELECT IDMedia, Folder, TrackCount, IDParentFolder FROM Folders WHERE ID = 86017 00000166 1:22:44 PM [2404] MM5 [7120](R) TIndyHTTP.GetResponseContent finished: URL: https://musicbrainz.org/ws/2/discid/7E1SieWul4WsUG3NnyNIcxMVpDQ-?fmt=json, ResponseContent.Size: 1164, ResponseCode: 200 00000167 1:22:44 PM [2404] MM5 [7120](R) Prgrs: Creating a new level. 00000168 1:22:44 PM [2404] MM5 [7120](R) TIndyHTTP.GetResponseContent started: URL: https://musicbrainz.org/ws/2/release/77aebad1-977c-4c92-97d6-17bc46690b06?inc=recordings+artist-credits+genres&fmt=json, RequestBody: 00000169 1:22:44 PM [2404] MM5 [7120](R) TIndyHTTP.GetResponseContent finished: URL: https://musicbrainz.org/ws/2/release/77aebad1-977c-4c92-97d6-17bc46690b06?inc=recordings+artist-credits+genres&fmt=json, ResponseContent.Size: 16752, ResponseCode: 200 00000170 1:22:45 PM [2404] MM5 [7120](R) Prgrs: Deleting a level. 00000171 1:22:45 PM [2404] MM5 [7120](R) Prgrs: Updating previous level from: 0 by 1 00000172 1:22:45 PM [2404] MM5 [7120](R) Prgrs: Deleting a level. 00000173 1:22:45 PM [2404] MM5 [7120](R) Prgrs: Creating a new level. 00000174 1:22:45 PM [2404] MM5 [7120](R) Prgrs: Creating a new level. 00000175 1:22:45 PM [2404] MM5 [7120](R) DB exec SQL: Update Medias SET ShowLabel='Justin Bieber - Changes' WHERE IDMedia=-1 00000176 1:22:45 PM [2404] MM5 [7120](R) DB lock took 0 ms : Update Medias SET ShowLabel='Justin Bieber - Changes' WHERE IDMedia=-1 00000177 1:22:45 PM [2404] MM5 [7120](R) DB open SQL: SELECT * FROM Medias ORDER BY Medias.IDMedia 00000178 1:22:45 PM [2404] MM5 [7120](R) DB exec SQL: UPDATE Medias SET CDDBQueryState=1, CDDBQueryResult='' WHERE IdMedia=-1 00000179 1:22:45 PM [2404] MM5 [7120](R) DB lock took 0 ms : UPDATE Medias SET CDDBQueryState=1, CDDBQueryResult='' WHERE IdMedia=-1 00000180 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000181 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 0 finished 00000182 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000183 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000184 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 7200 00000185 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 7200 finished 00000186 1:22:45 PM [2404] MM5 [24392](R) Task did not run within defined time. Is going to run after 766240312 ms 00000187 1:22:45 PM [2404] Stack : 00000188 1:22:45 PM [2404] MM5 [24392](R) BQ: Running tasks: 3, waiting tasks: 0, running low prior. tasks: -2 00000189 1:22:45 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: 00000190 1:22:45 PM [2404] MM5 [24392](R) Item instance thread from index 0 finished 00000191 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000192 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000193 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 0 finished 00000194 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000195 1:22:45 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TSharedList.sortAsync 00000196 1:22:45 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TSharedList.sortAsync 00000197 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000198 1:22:45 PM [2404] MM5 [24392](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: tracklist, id: -1) 00000199 1:22:45 PM [2404] MM5 [6048](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: tracklist, id: -1) 00000200 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 0 finished 00000201 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000202 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000203 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 0 finished 00000204 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000205 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000206 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 0 finished 00000207 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000208 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000209 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 0 finished 00000210 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000211 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000212 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 0 finished 00000213 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000214 1:22:45 PM [2404] MM5 [7120](R) Running item instance thread from index 0 00000215 1:22:45 PM [2404] MM5 [7120](R) Item instance thread from index 0 finished 00000216 1:22:45 PM [2404] MM5 [7120](R) All item instance threads were finished 00000217 1:22:45 PM [2404] MM5 [7120](R) Performance warning: TSongListData.doOnChange took 16 ms 00000218 1:22:45 PM [2404] MM5 [7120](R) Prgrs: Deleting a level. 00000219 1:22:45 PM [2404] MM5 [7120](R) Prgrs: Updating previous level from: 0 by 1 00000220 1:22:45 PM [2404] MM5 [7120](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: sharedlist, id: -1) 00000221 1:22:45 PM [2404] MM5 [7120](R) Prgrs: Deleting a level. 00000222 1:22:45 PM [2404] MM5 [7120](R) Thread 'CDDBQueryList_Var' is going to callback 00000223 1:22:45 PM [2404] MM5 [4276](R) unregistered task progress 102 00000224 1:22:45 PM [2404] MM5 [4276](R) unregistered task progress 102 00000225 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000226 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000227 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000228 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000229 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000230 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000231 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000232 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000233 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000234 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000235 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000236 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000237 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000238 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000239 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000240 1:22:45 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000241 1:22:45 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TSharedBase.RunAsPromiseNative 00000242 1:22:45 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: TFullPlayer.CheckPlayCounter 00000243 1:22:45 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 7443 ; Col: 29 00000244 1:22:45 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000245 1:22:45 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000246 1:22:45 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 7407 ; Col: 16 00000247 1:22:45 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000248 1:22:45 PM [2404] MM5 [24392](R) DB open SQL: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (4) LIMIT 1 00000249 1:22:45 PM [2404] MM5 [24392](R) DB open SQL: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (4) LIMIT 1 00000250 1:22:45 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 11 00000251 1:22:45 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000252 1:22:45 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 7466 ; Col: 42 00000253 1:22:45 PM [2404] MM5 [6048](R) DB open SQL: SELECT type, type_id, string_type_id FROM Pinned 00000254 1:22:45 PM [2404] MM5 [6048](R) DB open SQL: SELECT IDMedia, Folder, TrackCount, IDParentFolder FROM Folders WHERE ID = 86017 00000255 1:22:45 PM [2404] MM5 [4276](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: sharednodelist, id: -1) 00000256 1:22:46 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6927 ; Col: 47 00000257 1:22:46 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000258 1:22:46 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000259 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6926 ; Col: 16 00000260 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000261 1:22:46 PM [2404] MM5 [16512](R) 16512 DB prepare SQL: SELECT Count(*) FROM PathProcessing 00000262 1:22:46 PM [2404] MM5 [16512](R) 16512 DB query prepare finished, took 0. 00000263 1:22:46 PM [2404] MM5 [16512](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 0 00000264 1:22:46 PM [2404] MM5 [16512](R) DB open SQL: select * from folders Flds1 where IDParentFolder=0 GROUP BY Flds1.IDMedia 00000265 1:22:46 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6816 ; Col: 40 00000266 1:22:46 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000267 1:22:46 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000268 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6815 ; Col: 16 00000269 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000270 1:22:46 PM [2404] MM5 [24392](R) 24392 DB prepare SQL: SELECT Count(*) FROM PathProcessing 00000271 1:22:46 PM [2404] MM5 [24392](R) 24392 DB query prepare finished, took 0. 00000272 1:22:46 PM [2404] MM5 [24392](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 86015 00000273 1:22:46 PM [2404] MM5 [24392](R) DB open SQL: SELECT IDMedia FROM Folders WHERE IDParentFolder = 86015 00000274 1:22:46 PM [2404] MM5 [24392](R) DB open SQL: SELECT Flds1.Folder, Flds1.ID, COUNT(Flds2.TrackCount) as TrackCount, Flds1.IDMedia FROM Folders Flds1 LEFT OUTER JOIN Folders Flds2 ON Flds2.IDParentFolder=Flds1.ID AND Flds2.IDMedia=189 WHERE Flds1.IDMedia=189 AND Flds1.IDParentFolder=86015 GROUP BY Flds1.Folder 00000275 1:22:46 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6816 ; Col: 40 00000276 1:22:46 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000277 1:22:46 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000278 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6815 ; Col: 16 00000279 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000280 1:22:46 PM [2404] MM5 [6048](R) 6048 DB prepare SQL: SELECT Count(*) FROM PathProcessing 00000281 1:22:46 PM [2404] MM5 [6048](R) 6048 DB query prepare finished, took 0. 00000282 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 86016 00000283 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT IDMedia FROM Folders WHERE IDParentFolder = 86016 00000284 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT Flds1.Folder, Flds1.ID, COUNT(Flds2.TrackCount) as TrackCount, Flds1.IDMedia FROM Folders Flds1 LEFT OUTER JOIN Folders Flds2 ON Flds2.IDParentFolder=Flds1.ID AND Flds2.IDMedia=189 WHERE Flds1.IDMedia=189 AND Flds1.IDParentFolder=86016 GROUP BY Flds1.Folder 00000285 1:22:46 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6816 ; Col: 40 00000286 1:22:46 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000287 1:22:46 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000288 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6815 ; Col: 16 00000289 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000290 1:22:46 PM [2404] MM5 [16512](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 86017 00000291 1:22:46 PM [2404] MM5 [16512](R) DB open SQL: SELECT IDMedia FROM Folders WHERE IDParentFolder = 86017 00000292 1:22:46 PM [2404] MM5 [16512](R) DB open SQL: SELECT Flds1.Folder, Flds1.ID, COUNT(Flds2.TrackCount) as TrackCount, Flds1.IDMedia FROM Folders Flds1 LEFT OUTER JOIN Folders Flds2 ON Flds2.IDParentFolder=Flds1.ID AND Flds2.IDMedia=189 WHERE Flds1.IDMedia=189 AND Flds1.IDParentFolder=86017 GROUP BY Flds1.Folder 00000293 1:22:46 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6816 ; Col: 40 00000294 1:22:46 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000295 1:22:46 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000296 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6815 ; Col: 16 00000297 1:22:46 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000298 1:22:46 PM [2404] MM5 [24392](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 89023 00000299 1:22:46 PM [2404] MM5 [24392](R) DB open SQL: SELECT IDMedia FROM Folders WHERE IDParentFolder = 89023 00000300 1:22:46 PM [2404] MM5 [24392](R) DB open SQL: SELECT Flds1.Folder, Flds1.ID, COUNT(Flds2.TrackCount) as TrackCount, Flds1.IDMedia FROM Folders Flds1 LEFT OUTER JOIN Folders Flds2 ON Flds2.IDParentFolder=Flds1.ID AND Flds2.IDMedia=189 WHERE Flds1.IDMedia=189 AND Flds1.IDParentFolder=89023 GROUP BY Flds1.Folder 00000301 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 3 00000302 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000303 1:22:46 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6931 ; Col: 26 00000304 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86015 00000305 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=15794 00000306 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=84572 00000307 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 1 00000308 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000309 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 1 00000310 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000311 1:22:46 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6821 ; Col: 26 00000312 1:22:46 PM [2404] MM5 [16512](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86016 00000313 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 2 00000314 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000315 1:22:46 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6821 ; Col: 26 00000316 1:22:46 PM [2404] MM5 [24392](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86017 00000317 1:22:46 PM [2404] MM5 [24392](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86945 00000318 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 15 00000319 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000320 1:22:46 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6821 ; Col: 26 00000321 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89043 00000322 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89045 00000323 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89041 00000324 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 1 00000325 1:22:46 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000326 1:22:46 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6821 ; Col: 26 00000327 1:22:46 PM [2404] MM5 [16512](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89024 00000328 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89023 00000329 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89035 00000330 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89039 00000331 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=87754 00000332 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86840 00000333 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89014 00000334 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89031 00000335 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89026 00000336 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89029 00000337 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89033 00000338 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86048 00000339 1:22:46 PM [2404] MM5 [6048](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89037 00000340 1:22:46 PM [2404] MM5 [4276](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: sharednodelist, id: -1) 00000341 1:22:46 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TSharedBase.RunAsPromiseNative 00000342 1:22:47 PM [2404] MM5 [16512](R) BQ: Thread[16512] is executing new task now: TSharedUIList.processTableUpdate, list type: tracklist, count: 17, created ago: 10418562ms, stack: Callstack: Script: file:///viewHandlers.js ; Func: __refreshTracklist ; Row: 196 ; Col: 57 00000343 1:22:47 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 235 ; Col: 13 00000344 1:22:47 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 291 ; Col: 56 00000345 1:22:47 PM [2404] Script: file:///controls/multiview.js ; Func: _porcess ; Row: 1113 ; Col: 38 00000346 1:22:47 PM [2404] Script: file:///controls/multiview.js ; Func: _handler_call_onShow ; Row: 1118 ; Col: 9 00000347 1:22:47 PM [2404] MM5 [16512](R) FillSongListFolder for G:\ 00000348 1:22:47 PM [2404] MM5 [16512](R) CD: Procedure entered (GetInfoFromAudioCD), Drive letter: G 00000349 1:22:47 PM [2404] MM5 [16512](R) CD: AudioCDReadCS.Acquired, Drive letter: G 00000350 1:22:47 PM [2404] hpCDEBurn: On the point of reading TOC 00000351 1:22:47 PM [2404] hpCDEBurn: Read TOC of 0. track - LBA value: 0 00000352 1:22:47 PM [2404] hpCDEBurn: Read TOC of 0. track - MSF format: 0, 0, 2, 0 00000353 1:22:47 PM [2404] hpCDEBurn: Read TOC of 1. track - LBA value: 10269 00000354 1:22:47 PM [2404] hpCDEBurn: Read TOC of 1. track - MSF format: 0, 2, 18, 69 00000355 1:22:47 PM [2404] hpCDEBurn: Read TOC of 2. track - LBA value: 22884 00000356 1:22:47 PM [2404] hpCDEBurn: Read TOC of 2. track - MSF format: 0, 5, 7, 9 00000357 1:22:47 PM [2404] hpCDEBurn: Read TOC of 3. track - LBA value: 37942 00000358 1:22:47 PM [2404] hpCDEBurn: Read TOC of 3. track - MSF format: 0, 8, 27, 67 00000359 1:22:47 PM [2404] hpCDEBurn: Read TOC of 4. track - LBA value: 53907 00000360 1:22:47 PM [2404] hpCDEBurn: Read TOC of 4. track - MSF format: 0, 12, 0, 57 00000361 1:22:47 PM [2404] hpCDEBurn: Read TOC of 5. track - LBA value: 69545 00000362 1:22:47 PM [2404] hpCDEBurn: Read TOC of 5. track - MSF format: 0, 15, 29, 20 00000363 1:22:47 PM [2404] hpCDEBurn: Read TOC of 6. track - LBA value: 84236 00000364 1:22:47 PM [2404] hpCDEBurn: Read TOC of 6. track - MSF format: 0, 18, 45, 11 00000365 1:22:47 PM [2404] hpCDEBurn: Read TOC of 7. track - LBA value: 100732 00000366 1:22:47 PM [2404] hpCDEBurn: Read TOC of 7. track - MSF format: 0, 22, 25, 7 00000367 1:22:47 PM [2404] hpCDEBurn: Read TOC of 8. track - LBA value: 114199 00000368 1:22:47 PM [2404] hpCDEBurn: Read TOC of 8. track - MSF format: 0, 25, 24, 49 00000369 1:22:47 PM [2404] hpCDEBurn: Read TOC of 9. track - LBA value: 127551 00000370 1:22:47 PM [2404] hpCDEBurn: Read TOC of 9. track - MSF format: 0, 28, 22, 51 00000371 1:22:47 PM [2404] hpCDEBurn: Read TOC of 10. track - LBA value: 142754 00000372 1:22:47 PM [2404] hpCDEBurn: Read TOC of 10. track - MSF format: 0, 31, 45, 29 00000373 1:22:47 PM [2404] hpCDEBurn: Read TOC of 11. track - LBA value: 156661 00000374 1:22:47 PM [2404] hpCDEBurn: Read TOC of 11. track - MSF format: 0, 34, 50, 61 00000375 1:22:47 PM [2404] hpCDEBurn: Read TOC of 12. track - LBA value: 169917 00000376 1:22:47 PM [2404] hpCDEBurn: Read TOC of 12. track - MSF format: 0, 37, 47, 42 00000377 1:22:47 PM [2404] hpCDEBurn: Read TOC of 13. track - LBA value: 180079 00000378 1:22:47 PM [2404] hpCDEBurn: Read TOC of 13. track - MSF format: 0, 40, 3, 4 00000379 1:22:47 PM [2404] hpCDEBurn: Read TOC of 14. track - LBA value: 192872 00000380 1:22:47 PM [2404] hpCDEBurn: Read TOC of 14. track - MSF format: 0, 42, 53, 47 00000381 1:22:47 PM [2404] hpCDEBurn: Read TOC of 15. track - LBA value: 205256 00000382 1:22:47 PM [2404] hpCDEBurn: Read TOC of 15. track - MSF format: 0, 45, 38, 56 00000383 1:22:47 PM [2404] hpCDEBurn: Read TOC of 16. track - LBA value: 216482 00000384 1:22:47 PM [2404] hpCDEBurn: Read TOC of 16. track - MSF format: 0, 48, 8, 32 00000385 1:22:47 PM [2404] MM5 [16512](R) CD: Going to read CD-Text. 00000386 1:22:47 PM [2404] hpCDEBurn: entering ReadCDText() 00000387 1:22:47 PM [2404] hpCDEBurn: On the point of calling pDevice->ReadCDText() 00000388 1:22:47 PM [2404] hpCDEBurn: pDevice->ReadCDText: res = 1 00000389 1:22:47 PM [2404] hpCDEBurn: - Count of CD-Text items: 1 00000390 1:22:47 PM [2404] hpCDEBurn: Reading of CD-Text for 0. track: Title: , Artist: 00000391 1:22:47 PM [2404] MM5 [16512](R) CD: CD-Text wasn't found. 00000392 1:22:47 PM [2404] MM5 [16512](R) CD: AudioCDReadCS.Released, Drive letter: G 00000393 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track01.cda 00000394 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000395 1:22:47 PM [2404] MM5 [16512](R) 16512 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000396 1:22:47 PM [2404] MM5 [16512](R) 16512 DB query prepare finished, took 0. 00000397 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track02.cda 00000398 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000399 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track03.cda 00000400 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000401 1:22:47 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TFullPlayer.CheckPlayCounter 00000402 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track04.cda 00000403 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000404 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track05.cda 00000405 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000406 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track06.cda 00000407 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000408 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track07.cda 00000409 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000410 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track08.cda 00000411 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000412 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track09.cda 00000413 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000414 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track10.cda 00000415 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000416 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track11.cda 00000417 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000418 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track12.cda 00000419 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000420 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track13.cda 00000421 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000422 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track14.cda 00000423 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000424 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track15.cda 00000425 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000426 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: G:\Track16.cda 00000427 1:22:47 PM [2404] MM5 [16512](R) GetFileInfo: AudioCDTrack 00000428 1:22:47 PM [2404] MM5 [16512](R) CD: Procedure finished (GetInfoFromAudioCD) 00000429 1:22:47 PM [2404] MM5 [16512](R) Changed table - doing autoupdate TSongList and set previous focusedIndex 0 00000430 1:22:47 PM [2404] MM5 [16512](R) BQ: Task took 516 ms, stack: TSharedUIList.processTableUpdate, list type: tracklist, count: 17, created ago: 10418562ms, stack: Callstack: Script: file:///viewHandlers.js ; Func: __refreshTracklist ; Row: 196 ; Col: 57 00000431 1:22:47 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 235 ; Col: 13 00000432 1:22:47 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 291 ; Col: 56 00000433 1:22:47 PM [2404] Script: file:///controls/multiview.js ; Func: _porcess ; Row: 1113 ; Col: 38 00000434 1:22:47 PM [2404] Script: file:///controls/multiview.js ; Func: _handler_call_onShow ; Row: 1118 ; Col: 9 00000435 1:22:47 PM [2404] MM5 [24392](R) BQ: Thread[24392] is executing new task now: TSharedBase.RunAsPromiseNative 00000436 1:22:49 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TFullPlayer.CheckPlayCounter 00000437 1:22:50 PM [2404] MM5 [16512](R) DB: Deleting prepared query: SELECT Count(*) FROM PathProcessing 00000438 1:22:50 PM [2404] MM5 [16512](R) DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000439 1:22:50 PM [2404] MM5 [24392](R) DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000440 1:22:50 PM [2404] MM5 [24392](R) DB: Deleting prepared query: SELECT Count(*) FROM PathProcessing 00000441 1:22:51 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TFullPlayer.CheckPlayCounter 00000442 1:22:53 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TFullPlayer.CheckPlayCounter 00000443 1:22:53 PM [2404] MM5 [4276](R) Handling HID: 131145/0 00000444 1:22:53 PM [2404] MM5 [4276](R) Handling HID: 131145/0 00000445 1:22:53 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: Callstack: Script: file:///controls/listview.js ; Func: handleItemMouseDown ; Row: 2883 ; Col: 21 00000446 1:22:53 PM [2404] Script: file:///controls/listview.js ; Func: eval ; Row: 252 ; Col: 22 00000447 1:22:54 PM [2404] MM5 [4276](R) getNodeHandlerState(NODE_HANDLERS_STATE|optical_drive): {} 00000448 1:22:54 PM [2404] MM5 [4276](R) ControlCache: NowPlayingView | uniqueID_2904 added, controls.count = 1 00000449 1:22:54 PM [2404] MM5 [4276](R) getNodeHandlerState(NODE_HANDLERS_STATE|optical_drive): {} 00000450 1:22:54 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: __refreshTracklist ; Row: 196 ; Col: 57 00000451 1:22:54 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 235 ; Col: 13 00000452 1:22:54 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 291 ; Col: 56 00000453 1:22:54 PM [2404] Script: file:///controls/multiview.js ; Func: _porcess ; Row: 1113 ; Col: 38 00000454 1:22:54 PM [2404] Script: file:///controls/multiview.js ; Func: _handler_call_onShow ; Row: 1118 ; Col: 9 00000455 1:22:54 PM [2404] MM5 [6048](R) FillSongListFolder for G:\ 00000456 1:22:54 PM [2404] MM5 [6048](R) CD: Procedure entered (GetInfoFromAudioCD), Drive letter: G 00000457 1:22:54 PM [2404] MM5 [6048](R) CD: AudioCDReadCS.Acquired, Drive letter: G 00000458 1:22:54 PM [2404] MM5 [4276](R) BQ: Currently running 1 threads of 10 00000459 1:22:54 PM [2404] MM5 [21808](R) BQ: Thread[21808] is executing new task now: Callstack: Script: file:///controls/trackListView.js ; Func: handle_datasourcechanged ; Row: 2220 ; Col: 20 00000460 1:22:54 PM [2404] Script: file:///controls/listview.js ; Func: set ; Row: 3527 ; Col: 28 00000461 1:22:54 PM [2404] Script: file:///controls/listview.js ; Func: setDataSourceSameView ; Row: 3310 ; Col: 29 00000462 1:22:54 PM [2404] Script: file:///viewHandlers.js ; Func: _assign ; Row: 208 ; Col: 50 00000463 1:22:54 PM [2404] Script: file:///controls/mainTabContent.js ; Func: delayedAssign ; Row: 295 ; Col: 21 00000464 1:22:54 PM [2404] MM5 [4276](R) TreeView.setNodePath FROM root/optical_drive:G: TO root/optical_drive:G: 00000465 1:22:54 PM [2404] MM5 [4276](R) TreeView.setNodePath: path is same as the currently focused, no need for a job 00000466 1:22:54 PM [2404] hpCDEBurn: On the point of reading TOC 00000467 1:22:54 PM [2404] hpCDEBurn: Read TOC of 0. track - LBA value: 0 00000468 1:22:54 PM [2404] hpCDEBurn: Read TOC of 0. track - MSF format: 0, 0, 2, 0 00000469 1:22:54 PM [2404] hpCDEBurn: Read TOC of 1. track - LBA value: 10269 00000470 1:22:54 PM [2404] hpCDEBurn: Read TOC of 1. track - MSF format: 0, 2, 18, 69 00000471 1:22:54 PM [2404] hpCDEBurn: Read TOC of 2. track - LBA value: 22884 00000472 1:22:54 PM [2404] hpCDEBurn: Read TOC of 2. track - MSF format: 0, 5, 7, 9 00000473 1:22:54 PM [2404] hpCDEBurn: Read TOC of 3. track - LBA value: 37942 00000474 1:22:54 PM [2404] hpCDEBurn: Read TOC of 3. track - MSF format: 0, 8, 27, 67 00000475 1:22:54 PM [2404] hpCDEBurn: Read TOC of 4. track - LBA value: 53907 00000476 1:22:54 PM [2404] hpCDEBurn: Read TOC of 4. track - MSF format: 0, 12, 0, 57 00000477 1:22:54 PM [2404] hpCDEBurn: Read TOC of 5. track - LBA value: 69545 00000478 1:22:54 PM [2404] hpCDEBurn: Read TOC of 5. track - MSF format: 0, 15, 29, 20 00000479 1:22:54 PM [2404] hpCDEBurn: Read TOC of 6. track - LBA value: 84236 00000480 1:22:54 PM [2404] hpCDEBurn: Read TOC of 6. track - MSF format: 0, 18, 45, 11 00000481 1:22:54 PM [2404] hpCDEBurn: Read TOC of 7. track - LBA value: 100732 00000482 1:22:54 PM [2404] hpCDEBurn: Read TOC of 7. track - MSF format: 0, 22, 25, 7 00000483 1:22:54 PM [2404] hpCDEBurn: Read TOC of 8. track - LBA value: 114199 00000484 1:22:54 PM [2404] hpCDEBurn: Read TOC of 8. track - MSF format: 0, 25, 24, 49 00000485 1:22:54 PM [2404] hpCDEBurn: Read TOC of 9. track - LBA value: 127551 00000486 1:22:54 PM [2404] hpCDEBurn: Read TOC of 9. track - MSF format: 0, 28, 22, 51 00000487 1:22:54 PM [2404] hpCDEBurn: Read TOC of 10. track - LBA value: 142754 00000488 1:22:54 PM [2404] hpCDEBurn: Read TOC of 10. track - MSF format: 0, 31, 45, 29 00000489 1:22:54 PM [2404] hpCDEBurn: Read TOC of 11. track - LBA value: 156661 00000490 1:22:54 PM [2404] hpCDEBurn: Read TOC of 11. track - MSF format: 0, 34, 50, 61 00000491 1:22:54 PM [2404] hpCDEBurn: Read TOC of 12. track - LBA value: 169917 00000492 1:22:54 PM [2404] hpCDEBurn: Read TOC of 12. track - MSF format: 0, 37, 47, 42 00000493 1:22:54 PM [2404] hpCDEBurn: Read TOC of 13. track - LBA value: 180079 00000494 1:22:54 PM [2404] hpCDEBurn: Read TOC of 13. track - MSF format: 0, 40, 3, 4 00000495 1:22:54 PM [2404] hpCDEBurn: Read TOC of 14. track - LBA value: 192872 00000496 1:22:54 PM [2404] hpCDEBurn: Read TOC of 14. track - MSF format: 0, 42, 53, 47 00000497 1:22:54 PM [2404] hpCDEBurn: Read TOC of 15. track - LBA value: 205256 00000498 1:22:54 PM [2404] hpCDEBurn: Read TOC of 15. track - MSF format: 0, 45, 38, 56 00000499 1:22:54 PM [2404] hpCDEBurn: Read TOC of 16. track - LBA value: 216482 00000500 1:22:54 PM [2404] hpCDEBurn: Read TOC of 16. track - MSF format: 0, 48, 8, 32 00000501 1:22:54 PM [2404] MM5 [6048](R) CD: Going to read CD-Text. 00000502 1:22:54 PM [2404] hpCDEBurn: entering ReadCDText() 00000503 1:22:54 PM [2404] MM5 [21808](R) BQ: Thread[21808] is executing new task now: Callstack: Script: file:///actions.js ; Func: eval ; Row: 5843 ; Col: 29 00000504 1:22:54 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000505 1:22:54 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000506 1:22:54 PM [2404] Script: file:///actions.js ; Func: window.uitools.isPinnedAsync ; Row: 5841 ; Col: 16 00000507 1:22:54 PM [2404] Script: file:///actions.js ; Func: visible ; Row: 558 ; Col: 36 00000508 1:22:54 PM [2404] MM5 [21808](R) 21808 DB prepare SQL: SELECT NULL FROM Pinned WHERE type=? AND string_type_id=? 00000509 1:22:54 PM [2404] MM5 [21808](R) 21808 DB query prepare finished, took 0. 00000510 1:22:54 PM [2404] hpCDEBurn: On the point of calling pDevice->ReadCDText() 00000511 1:22:54 PM [2404] MM5 [4276](R) menu coords 3039x1273 00000512 1:22:54 PM [2404] MM5 [21808](R) BQ: Thread[21808] is executing new task now: Callstack: Script: file:///controls/control.js ; Func: eval ; Row: 1165 ; Col: 29 00000513 1:22:54 PM [2404] Script: file:///controls/control.js ; Func: eval ; Row: 263 ; Col: 13 00000514 1:22:54 PM [2404] hpCDEBurn: pDevice->ReadCDText: res = 1 00000515 1:22:54 PM [2404] hpCDEBurn: - Count of CD-Text items: 1 00000516 1:22:54 PM [2404] hpCDEBurn: Reading of CD-Text for 0. track: Title: , Artist: 00000517 1:22:54 PM [2404] MM5 [6048](R) CD: CD-Text wasn't found. 00000518 1:22:54 PM [2404] MM5 [6048](R) CD: AudioCDReadCS.Released, Drive letter: G 00000519 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track01.cda 00000520 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000521 1:22:54 PM [2404] MM5 [6048](R) 6048 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000522 1:22:54 PM [2404] MM5 [6048](R) 6048 DB query prepare finished, took 0. 00000523 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track02.cda 00000524 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000525 1:22:54 PM [2404] MM5 [4276](R) menu coords 3039x1273 00000526 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track03.cda 00000527 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000528 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track04.cda 00000529 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000530 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track05.cda 00000531 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000532 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track06.cda 00000533 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000534 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track07.cda 00000535 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000536 1:22:54 PM [2404] MM5 [4276](R) menu coords 3039x1273 00000537 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track08.cda 00000538 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000539 1:22:54 PM [2404] MM5 [4276](R) Main window is not minimized and not hidden 00000540 1:22:54 PM [23280] MM5 [7260](B) Activating window file:///dialogs/empty.html?wndID=2_menu 00000541 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track09.cda 00000542 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000543 1:22:54 PM [2404] MM5 [4276](R) Main window is not minimized and not hidden 00000544 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track10.cda 00000545 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000546 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track11.cda 00000547 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000548 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track12.cda 00000549 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000550 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track13.cda 00000551 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000552 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track14.cda 00000553 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000554 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track15.cda 00000555 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000556 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: G:\Track16.cda 00000557 1:22:54 PM [2404] MM5 [6048](R) GetFileInfo: AudioCDTrack 00000558 1:22:54 PM [2404] MM5 [4276](R) registered task progress 103 00000559 1:22:54 PM [2404] MM5 [6048](R) CD: Procedure finished (GetInfoFromAudioCD) 00000560 1:22:54 PM [2404] MM5 [21808](R) BQ: Thread[21808] is executing new task now: TSharedList.sortAsync 00000561 1:22:54 PM [2404] MM5 [21808](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: tracklist, id: -1) 00000562 1:22:54 PM [2404] MM5 [6048](R) BQ: Task took 563 ms, stack: Callstack: Script: file:///viewHandlers.js ; Func: __refreshTracklist ; Row: 196 ; Col: 57 00000563 1:22:54 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 235 ; Col: 13 00000564 1:22:54 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 291 ; Col: 56 00000565 1:22:54 PM [2404] Script: file:///controls/multiview.js ; Func: _porcess ; Row: 1113 ; Col: 38 00000566 1:22:54 PM [2404] Script: file:///controls/multiview.js ; Func: _handler_call_onShow ; Row: 1118 ; Col: 9 00000567 1:22:54 PM [2404] MM5 [4276](R) unregistered task progress 103 00000568 1:22:54 PM [2404] MM5 [21808](R) BQ: Thread[21808] is executing new task now: TSharedBase.RunAsPromiseNative 00000569 1:22:55 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TFullPlayer.CheckPlayCounter 00000570 1:22:57 PM [2404] MM5 [21808](R) BQ: Thread[21808] is executing new task now: TFullPlayer.CheckPlayCounter 00000571 1:22:58 PM [2404] MM5 [6048](R) DB: Deleting prepared query: SELECT Count(*) FROM PathProcessing 00000572 1:22:58 PM [2404] MM5 [6048](R) DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000573 1:22:59 PM [2404] MM5 [4276](R) BQ: Currently running 1 threads of 10 00000574 1:22:59 PM [2404] MM5 [21808](R) BQ: Thread[21808] is executing new task now: Callstack: Script: file:///controls/multiview.js ; Func: eval ; Row: 649 ; Col: 21 00000575 1:22:59 PM [2404] Script: file:///controls/control.js ; Func: eval ; Row: 312 ; Col: 13 00000576 1:22:59 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: Callstack: Script: file:///controls/multiview.js ; Func: eval ; Row: 649 ; Col: 21 00000577 1:22:59 PM [2404] Script: file:///controls/control.js ; Func: eval ; Row: 312 ; Col: 13 00000578 1:22:59 PM [2404] MM5 [21576](R) WriteIniSettings 00000579 1:22:59 PM [2404] MM5 [21576](R) Plugins Folder: D:\Software\MediaMonkey 5\Plugins\ 00000580 1:22:59 PM [2404] MM5 [21576](R) Plugins Folder: D:\Software\MediaMonkey 5\Plugins\ 00000581 1:22:59 PM [2404] MM5 [21808](R) BQ: Thread[21808] is executing new task now: TFullPlayer.CheckPlayCounter 00000582 1:23:01 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000583 1:23:02 PM [2404] MM5 [21808](R) DB: Deleting prepared query: SELECT NULL FROM Pinned WHERE type=? AND string_type_id=? 00000584 1:23:03 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000585 1:23:03 PM [2404] MM5 [4276](R) Handling HID: 131145/0 00000586 1:23:04 PM [2404] UPnP Server: OnAction: GetSystemUpdateID 00000587 1:23:04 PM [2404] UPnP GetSystemUpdateID: 0 00000588 1:23:04 PM [2404] UPnP Server: OnAction: GetSortCapabilities 00000589 1:23:04 PM [2404] UPnP Server: OnAction: GetSearchCapabilities 00000590 1:23:04 PM [2404] UPnP Server: OnAction: GetProtocolInfo 00000591 1:23:04 PM [2404] UPnP Server: OnAction: GetSystemUpdateID 00000592 1:23:04 PM [2404] UPnP GetSystemUpdateID: 0 00000593 1:23:04 PM [2404] UPnP Server: OnAction: GetSortCapabilities 00000594 1:23:04 PM [2404] UPnP Server: OnAction: GetSearchCapabilities 00000595 1:23:04 PM [2404] UPnP Server: OnAction: GetProtocolInfo 00000596 1:23:05 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000597 1:23:07 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000598 1:23:09 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000599 1:23:10 PM [2404] MM5 [4276](R) Handling HID: 131145/0 00000600 1:23:10 PM [2404] MM5 [4276](R) Handling HID: 131145/0 00000601 1:23:11 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000602 1:23:12 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: Callstack: Script: file:///actions.js ; Func: execute ; Row: 1872 ; Col: 27 00000603 1:23:12 PM [2404] MM5 [21576](R) CD: Procedure entered (GetInfoFromAudioCD), Drive letter: G 00000604 1:23:12 PM [2404] MM5 [21576](R) CD: AudioCDReadCS.Acquired, Drive letter: G 00000605 1:23:12 PM [2404] MM5 [4276](R) Main window is not minimized and hidden 00000606 1:23:12 PM [2404] MM5 [4276](R) Main window is not minimized and hidden 00000607 1:23:12 PM [2404] hpCDEBurn: On the point of reading TOC 00000608 1:23:12 PM [2404] hpCDEBurn: Read TOC of 0. track - LBA value: 0 00000609 1:23:12 PM [2404] hpCDEBurn: Read TOC of 0. track - MSF format: 0, 0, 2, 0 00000610 1:23:12 PM [2404] hpCDEBurn: Read TOC of 1. track - LBA value: 10269 00000611 1:23:12 PM [2404] hpCDEBurn: Read TOC of 1. track - MSF format: 0, 2, 18, 69 00000612 1:23:12 PM [2404] hpCDEBurn: Read TOC of 2. track - LBA value: 22884 00000613 1:23:12 PM [2404] hpCDEBurn: Read TOC of 2. track - MSF format: 0, 5, 7, 9 00000614 1:23:12 PM [2404] hpCDEBurn: Read TOC of 3. track - LBA value: 37942 00000615 1:23:12 PM [2404] hpCDEBurn: Read TOC of 3. track - MSF format: 0, 8, 27, 67 00000616 1:23:12 PM [2404] hpCDEBurn: Read TOC of 4. track - LBA value: 53907 00000617 1:23:12 PM [2404] hpCDEBurn: Read TOC of 4. track - MSF format: 0, 12, 0, 57 00000618 1:23:12 PM [2404] hpCDEBurn: Read TOC of 5. track - LBA value: 69545 00000619 1:23:12 PM [2404] hpCDEBurn: Read TOC of 5. track - MSF format: 0, 15, 29, 20 00000620 1:23:12 PM [2404] hpCDEBurn: Read TOC of 6. track - LBA value: 84236 00000621 1:23:12 PM [2404] hpCDEBurn: Read TOC of 6. track - MSF format: 0, 18, 45, 11 00000622 1:23:12 PM [2404] hpCDEBurn: Read TOC of 7. track - LBA value: 100732 00000623 1:23:12 PM [2404] hpCDEBurn: Read TOC of 7. track - MSF format: 0, 22, 25, 7 00000624 1:23:12 PM [2404] hpCDEBurn: Read TOC of 8. track - LBA value: 114199 00000625 1:23:12 PM [2404] hpCDEBurn: Read TOC of 8. track - MSF format: 0, 25, 24, 49 00000626 1:23:12 PM [2404] hpCDEBurn: Read TOC of 9. track - LBA value: 127551 00000627 1:23:12 PM [2404] hpCDEBurn: Read TOC of 9. track - MSF format: 0, 28, 22, 51 00000628 1:23:12 PM [2404] hpCDEBurn: Read TOC of 10. track - LBA value: 142754 00000629 1:23:12 PM [2404] hpCDEBurn: Read TOC of 10. track - MSF format: 0, 31, 45, 29 00000630 1:23:12 PM [2404] hpCDEBurn: Read TOC of 11. track - LBA value: 156661 00000631 1:23:12 PM [2404] hpCDEBurn: Read TOC of 11. track - MSF format: 0, 34, 50, 61 00000632 1:23:12 PM [2404] hpCDEBurn: Read TOC of 12. track - LBA value: 169917 00000633 1:23:12 PM [2404] hpCDEBurn: Read TOC of 12. track - MSF format: 0, 37, 47, 42 00000634 1:23:12 PM [2404] hpCDEBurn: Read TOC of 13. track - LBA value: 180079 00000635 1:23:12 PM [2404] hpCDEBurn: Read TOC of 13. track - MSF format: 0, 40, 3, 4 00000636 1:23:12 PM [2404] hpCDEBurn: Read TOC of 14. track - LBA value: 192872 00000637 1:23:12 PM [2404] hpCDEBurn: Read TOC of 14. track - MSF format: 0, 42, 53, 47 00000638 1:23:12 PM [2404] hpCDEBurn: Read TOC of 15. track - LBA value: 205256 00000639 1:23:12 PM [2404] hpCDEBurn: Read TOC of 15. track - MSF format: 0, 45, 38, 56 00000640 1:23:12 PM [2404] hpCDEBurn: Read TOC of 16. track - LBA value: 216482 00000641 1:23:12 PM [2404] hpCDEBurn: Read TOC of 16. track - MSF format: 0, 48, 8, 32 00000642 1:23:12 PM [2404] MM5 [21576](R) CD: Going to read CD-Text. 00000643 1:23:12 PM [2404] hpCDEBurn: entering ReadCDText() 00000644 1:23:12 PM [2404] hpCDEBurn: On the point of calling pDevice->ReadCDText() 00000645 1:23:12 PM [2404] hpCDEBurn: pDevice->ReadCDText: res = 1 00000646 1:23:12 PM [2404] hpCDEBurn: - Count of CD-Text items: 1 00000647 1:23:12 PM [2404] hpCDEBurn: Reading of CD-Text for 0. track: Title: , Artist: 00000648 1:23:12 PM [2404] MM5 [21576](R) CD: CD-Text wasn't found. 00000649 1:23:12 PM [2404] MM5 [21576](R) CD: AudioCDReadCS.Released, Drive letter: G 00000650 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track01.cda 00000651 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000652 1:23:12 PM [2404] MM5 [21576](R) 21576 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000653 1:23:12 PM [2404] MM5 [21576](R) 21576 DB query prepare finished, took 0. 00000654 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track02.cda 00000655 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000656 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track03.cda 00000657 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000658 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track04.cda 00000659 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000660 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track05.cda 00000661 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000662 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track06.cda 00000663 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000664 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track07.cda 00000665 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000666 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track08.cda 00000667 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000668 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track09.cda 00000669 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000670 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track10.cda 00000671 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000672 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track11.cda 00000673 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000674 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track12.cda 00000675 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000676 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track13.cda 00000677 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000678 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track14.cda 00000679 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000680 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track15.cda 00000681 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000682 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: G:\Track16.cda 00000683 1:23:12 PM [2404] MM5 [21576](R) GetFileInfo: AudioCDTrack 00000684 1:23:12 PM [2404] MM5 [21576](R) CD: Procedure finished (GetInfoFromAudioCD) 00000685 1:23:12 PM [2404] MM5 [21576](R) New thread starting 1 00000686 1:23:12 PM [2404] MM5 [21576](R) BQ: Task took 625 ms, stack: Callstack: Script: file:///actions.js ; Func: execute ; Row: 1872 ; Col: 27 00000687 1:23:12 PM [2404] MM5 [23136](R) Prgrs: Creating a new level. 00000688 1:23:12 PM [2404] MM5 [23136](R) MusicBrainz: querying discID: 7E1SieWul4WsUG3NnyNIcxMVpDQ- 00000689 1:23:12 PM [2404] MM5 [23136](R) TIndyHTTP.GetResponseContent started: URL: https://musicbrainz.org/ws/2/discid/7E1SieWul4WsUG3NnyNIcxMVpDQ-?fmt=json, RequestBody: 00000690 1:23:12 PM [2404] MM5 [4276](R) registered task progress 104 00000691 1:23:13 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000692 1:23:13 PM [2404] MM5 [23136](R) TIndyHTTP.GetResponseContent finished: URL: https://musicbrainz.org/ws/2/discid/7E1SieWul4WsUG3NnyNIcxMVpDQ-?fmt=json, ResponseContent.Size: 1164, ResponseCode: 200 00000693 1:23:13 PM [2404] MM5 [23136](R) Prgrs: Creating a new level. 00000694 1:23:13 PM [2404] MM5 [23136](R) TIndyHTTP.GetResponseContent started: URL: https://musicbrainz.org/ws/2/release/77aebad1-977c-4c92-97d6-17bc46690b06?inc=recordings+artist-credits+genres&fmt=json, RequestBody: 00000695 1:23:14 PM [2404] MM5 [23136](R) TIndyHTTP.GetResponseContent finished: URL: https://musicbrainz.org/ws/2/release/77aebad1-977c-4c92-97d6-17bc46690b06?inc=recordings+artist-credits+genres&fmt=json, ResponseContent.Size: 16752, ResponseCode: 200 00000696 1:23:14 PM [2404] MM5 [23136](R) Prgrs: Deleting a level. 00000697 1:23:14 PM [2404] MM5 [23136](R) Prgrs: Updating previous level from: 0 by 1 00000698 1:23:14 PM [2404] MM5 [23136](R) Prgrs: Deleting a level. 00000699 1:23:14 PM [2404] MM5 [23136](R) Prgrs: Creating a new level. 00000700 1:23:14 PM [2404] MM5 [23136](R) Prgrs: Creating a new level. 00000701 1:23:14 PM [2404] MM5 [23136](R) DB exec SQL: Update Medias SET ShowLabel='Justin Bieber - Changes' WHERE IDMedia=-1 00000702 1:23:14 PM [2404] MM5 [23136](R) DB lock took 0 ms : Update Medias SET ShowLabel='Justin Bieber - Changes' WHERE IDMedia=-1 00000703 1:23:14 PM [2404] MM5 [23136](R) DB open SQL: SELECT * FROM Medias ORDER BY Medias.IDMedia 00000704 1:23:14 PM [2404] MM5 [23136](R) DB exec SQL: UPDATE Medias SET CDDBQueryState=1, CDDBQueryResult='' WHERE IdMedia=-1 00000705 1:23:14 PM [2404] MM5 [23136](R) DB lock took 0 ms : UPDATE Medias SET CDDBQueryState=1, CDDBQueryResult='' WHERE IdMedia=-1 00000706 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000707 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000708 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000709 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000710 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 7200 00000711 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 7200 finished 00000712 1:23:14 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: 00000713 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000714 1:23:14 PM [2404] MM5 [21576](R) Item instance thread from index 0 finished 00000715 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000716 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000717 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000718 1:23:14 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TSharedList.sortAsync 00000719 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000720 1:23:14 PM [2404] MM5 [21576](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: tracklist, id: -1) 00000721 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000722 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000723 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000724 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000725 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000726 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000727 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000728 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000729 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000730 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000731 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000732 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000733 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000734 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000735 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000736 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000737 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000738 1:23:14 PM [2404] MM5 [23136](R) Running item instance thread from index 0 00000739 1:23:14 PM [2404] MM5 [23136](R) Item instance thread from index 0 finished 00000740 1:23:14 PM [2404] MM5 [23136](R) All item instance threads were finished 00000741 1:23:14 PM [2404] MM5 [23136](R) BQ: Currently running 1 threads of 10 00000742 1:23:14 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TSharedList.sortAsync 00000743 1:23:14 PM [2404] MM5 [21576](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: tracklist, id: -1) 00000744 1:23:14 PM [2404] MM5 [23136](R) Performance warning: TSongListData.doOnChange took 16 ms 00000745 1:23:14 PM [2404] MM5 [23136](R) Performance warning: TSongListData.doOnChange took 16 ms 00000746 1:23:14 PM [2404] MM5 [23136](R) Prgrs: Deleting a level. 00000747 1:23:14 PM [2404] MM5 [23136](R) Prgrs: Updating previous level from: 0 by 1 00000748 1:23:14 PM [2404] MM5 [23136](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: sharedlist, id: -1) 00000749 1:23:14 PM [2404] MM5 [23136](R) Prgrs: Deleting a level. 00000750 1:23:14 PM [2404] MM5 [23136](R) Thread 'CDDBQueryList_Var' is going to callback 00000751 1:23:14 PM [2404] MM5 [4276](R) unregistered task progress 104 00000752 1:23:14 PM [2404] MM5 [4276](R) unregistered task progress 104 00000753 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000754 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000755 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000756 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000757 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000758 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000759 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000760 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000761 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000762 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000763 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000764 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000765 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000766 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000767 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000768 1:23:14 PM [2404] MM5 [4276](R) COM - calling event TSDBApplication.DoTrackPropertiesEvent 00000769 1:23:14 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TSharedBase.RunAsPromiseNative 00000770 1:23:14 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: TSharedBase.RunAsPromiseNative 00000771 1:23:15 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 7443 ; Col: 29 00000772 1:23:15 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000773 1:23:15 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000774 1:23:15 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 7407 ; Col: 16 00000775 1:23:15 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000776 1:23:15 PM [2404] MM5 [21576](R) DB open SQL: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (4) LIMIT 1 00000777 1:23:15 PM [2404] MM5 [21576](R) DB open SQL: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (4) LIMIT 1 00000778 1:23:15 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 11 00000779 1:23:15 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000780 1:23:15 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 7466 ; Col: 42 00000781 1:23:15 PM [2404] MM5 [8440](R) DB open SQL: SELECT type, type_id, string_type_id FROM Pinned 00000782 1:23:15 PM [2404] MM5 [8440](R) DB open SQL: SELECT IDMedia, Folder, TrackCount, IDParentFolder FROM Folders WHERE ID = 86017 00000783 1:23:15 PM [2404] MM5 [4276](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: sharednodelist, id: -1) 00000784 1:23:15 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000785 1:23:16 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6927 ; Col: 47 00000786 1:23:16 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000787 1:23:16 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000788 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6926 ; Col: 16 00000789 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000790 1:23:16 PM [2404] MM5 [8440](R) 8440 DB prepare SQL: SELECT Count(*) FROM PathProcessing 00000791 1:23:16 PM [2404] MM5 [8440](R) 8440 DB query prepare finished, took 0. 00000792 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 0 00000793 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: select * from folders Flds1 where IDParentFolder=0 GROUP BY Flds1.IDMedia 00000794 1:23:16 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6816 ; Col: 40 00000795 1:23:16 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000796 1:23:16 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000797 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6815 ; Col: 16 00000798 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000799 1:23:16 PM [2404] MM5 [21576](R) 21576 DB prepare SQL: SELECT Count(*) FROM PathProcessing 00000800 1:23:16 PM [2404] MM5 [21576](R) 21576 DB query prepare finished, took 0. 00000801 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 86015 00000802 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT IDMedia FROM Folders WHERE IDParentFolder = 86015 00000803 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT Flds1.Folder, Flds1.ID, COUNT(Flds2.TrackCount) as TrackCount, Flds1.IDMedia FROM Folders Flds1 LEFT OUTER JOIN Folders Flds2 ON Flds2.IDParentFolder=Flds1.ID AND Flds2.IDMedia=189 WHERE Flds1.IDMedia=189 AND Flds1.IDParentFolder=86015 GROUP BY Flds1.Folder 00000804 1:23:16 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6816 ; Col: 40 00000805 1:23:16 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000806 1:23:16 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000807 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6815 ; Col: 16 00000808 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000809 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 86016 00000810 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT IDMedia FROM Folders WHERE IDParentFolder = 86016 00000811 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT Flds1.Folder, Flds1.ID, COUNT(Flds2.TrackCount) as TrackCount, Flds1.IDMedia FROM Folders Flds1 LEFT OUTER JOIN Folders Flds2 ON Flds2.IDParentFolder=Flds1.ID AND Flds2.IDMedia=189 WHERE Flds1.IDMedia=189 AND Flds1.IDParentFolder=86016 GROUP BY Flds1.Folder 00000812 1:23:16 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6816 ; Col: 40 00000813 1:23:16 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000814 1:23:16 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000815 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6815 ; Col: 16 00000816 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000817 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 86017 00000818 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT IDMedia FROM Folders WHERE IDParentFolder = 86017 00000819 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT Flds1.Folder, Flds1.ID, COUNT(Flds2.TrackCount) as TrackCount, Flds1.IDMedia FROM Folders Flds1 LEFT OUTER JOIN Folders Flds2 ON Flds2.IDParentFolder=Flds1.ID AND Flds2.IDMedia=189 WHERE Flds1.IDMedia=189 AND Flds1.IDParentFolder=86017 GROUP BY Flds1.Folder 00000820 1:23:16 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6816 ; Col: 40 00000821 1:23:16 PM [2404] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000822 1:23:16 PM [2404] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000823 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: getChildren ; Row: 6815 ; Col: 16 00000824 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: refreshNodeChildren ; Row: 2478 ; Col: 17 00000825 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT Count(*) FROM Folders WHERE IDParentFolder = 89023 00000826 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT IDMedia FROM Folders WHERE IDParentFolder = 89023 00000827 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT Flds1.Folder, Flds1.ID, COUNT(Flds2.TrackCount) as TrackCount, Flds1.IDMedia FROM Folders Flds1 LEFT OUTER JOIN Folders Flds2 ON Flds2.IDParentFolder=Flds1.ID AND Flds2.IDMedia=189 WHERE Flds1.IDMedia=189 AND Flds1.IDParentFolder=89023 GROUP BY Flds1.Folder 00000828 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 15 00000829 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000830 1:23:16 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6821 ; Col: 26 00000831 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89043 00000832 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89045 00000833 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89041 00000834 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 3 00000835 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89023 00000836 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89035 00000837 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000838 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89039 00000839 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=87754 00000840 1:23:16 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6931 ; Col: 26 00000841 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86015 00000842 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86840 00000843 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=15794 00000844 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89014 00000845 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89031 00000846 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=84572 00000847 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89026 00000848 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89029 00000849 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89033 00000850 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86048 00000851 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89037 00000852 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 1 00000853 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000854 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 1 00000855 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000856 1:23:16 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6821 ; Col: 26 00000857 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86016 00000858 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 2 00000859 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000860 1:23:16 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6821 ; Col: 26 00000861 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86017 00000862 1:23:16 PM [2404] MM5 [21576](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=86945 00000863 1:23:16 PM [2404] MM5 [21576](R) DB lock took 16 ms : SELECT COUNT() FROM Folders WHERE IDParentFolder=86945 00000864 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren, count 1 00000865 1:23:16 PM [2404] MM5 [4276](R) TSharedNode.addChildren: finished 00000866 1:23:16 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: Callstack: Script: file:///viewHandlers.js ; Func: eval ; Row: 6821 ; Col: 26 00000867 1:23:16 PM [2404] MM5 [8440](R) DB open SQL: SELECT COUNT() FROM Folders WHERE IDParentFolder=89024 00000868 1:23:16 PM [2404] MM5 [4276](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: sharednodelist, id: -1) 00000869 1:23:16 PM [2404] MM5 [4276](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: sharednodelist, id: -1) 00000870 1:23:16 PM [2404] MM5 [4276](R) Performance warning: doOnChange is called too often, consider using beginUpdate/endUpdate (OT: sharednodelist, id: -1) 00000871 1:23:16 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TSharedBase.RunAsPromiseNative 00000872 1:23:16 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: TSharedUIList.processTableUpdate, list type: tracklist, count: 17, created ago: 10448062ms, stack: Callstack: Script: file:///viewHandlers.js ; Func: __refreshTracklist ; Row: 196 ; Col: 57 00000873 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 235 ; Col: 13 00000874 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 291 ; Col: 56 00000875 1:23:16 PM [2404] Script: file:///controls/multiview.js ; Func: _porcess ; Row: 1113 ; Col: 38 00000876 1:23:16 PM [2404] Script: file:///controls/multiview.js ; Func: _handler_call_onShow ; Row: 1118 ; Col: 9 00000877 1:23:16 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TSharedUIList.processTableUpdate, list type: tracklist, count: 17, created ago: 22547ms, stack: Callstack: Script: file:///viewHandlers.js ; Func: __refreshTracklist ; Row: 196 ; Col: 57 00000878 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 235 ; Col: 13 00000879 1:23:16 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 291 ; Col: 56 00000880 1:23:16 PM [2404] Script: file:///controls/multiview.js ; Func: _porcess ; Row: 1113 ; Col: 38 00000881 1:23:16 PM [2404] Script: file:///controls/multiview.js ; Func: _handler_call_onShow ; Row: 1118 ; Col: 9 00000882 1:23:16 PM [2404] MM5 [8440](R) FillSongListFolder for G:\ 00000883 1:23:16 PM [2404] MM5 [21576](R) FillSongListFolder for G:\ 00000884 1:23:16 PM [2404] MM5 [8440](R) CD: Procedure entered (GetInfoFromAudioCD), Drive letter: G 00000885 1:23:16 PM [2404] MM5 [8440](R) CD: AudioCDReadCS.Acquired, Drive letter: G 00000886 1:23:16 PM [2404] MM5 [21576](R) CD: Procedure entered (GetInfoFromAudioCD), Drive letter: G 00000887 1:23:16 PM [2404] hpCDEBurn: On the point of reading TOC 00000888 1:23:16 PM [2404] hpCDEBurn: Read TOC of 0. track - LBA value: 0 00000889 1:23:16 PM [2404] hpCDEBurn: Read TOC of 0. track - MSF format: 0, 0, 2, 0 00000890 1:23:16 PM [2404] hpCDEBurn: Read TOC of 1. track - LBA value: 10269 00000891 1:23:16 PM [2404] hpCDEBurn: Read TOC of 1. track - MSF format: 0, 2, 18, 69 00000892 1:23:16 PM [2404] hpCDEBurn: Read TOC of 2. track - LBA value: 22884 00000893 1:23:16 PM [2404] hpCDEBurn: Read TOC of 2. track - MSF format: 0, 5, 7, 9 00000894 1:23:16 PM [2404] hpCDEBurn: Read TOC of 3. track - LBA value: 37942 00000895 1:23:16 PM [2404] hpCDEBurn: Read TOC of 3. track - MSF format: 0, 8, 27, 67 00000896 1:23:16 PM [2404] hpCDEBurn: Read TOC of 4. track - LBA value: 53907 00000897 1:23:16 PM [2404] hpCDEBurn: Read TOC of 4. track - MSF format: 0, 12, 0, 57 00000898 1:23:16 PM [2404] hpCDEBurn: Read TOC of 5. track - LBA value: 69545 00000899 1:23:16 PM [2404] hpCDEBurn: Read TOC of 5. track - MSF format: 0, 15, 29, 20 00000900 1:23:16 PM [2404] hpCDEBurn: Read TOC of 6. track - LBA value: 84236 00000901 1:23:16 PM [2404] hpCDEBurn: Read TOC of 6. track - MSF format: 0, 18, 45, 11 00000902 1:23:16 PM [2404] hpCDEBurn: Read TOC of 7. track - LBA value: 100732 00000903 1:23:16 PM [2404] hpCDEBurn: Read TOC of 7. track - MSF format: 0, 22, 25, 7 00000904 1:23:16 PM [2404] hpCDEBurn: Read TOC of 8. track - LBA value: 114199 00000905 1:23:16 PM [2404] hpCDEBurn: Read TOC of 8. track - MSF format: 0, 25, 24, 49 00000906 1:23:16 PM [2404] hpCDEBurn: Read TOC of 9. track - LBA value: 127551 00000907 1:23:16 PM [2404] hpCDEBurn: Read TOC of 9. track - MSF format: 0, 28, 22, 51 00000908 1:23:16 PM [2404] hpCDEBurn: Read TOC of 10. track - LBA value: 142754 00000909 1:23:16 PM [2404] hpCDEBurn: Read TOC of 10. track - MSF format: 0, 31, 45, 29 00000910 1:23:16 PM [2404] hpCDEBurn: Read TOC of 11. track - LBA value: 156661 00000911 1:23:16 PM [2404] hpCDEBurn: Read TOC of 11. track - MSF format: 0, 34, 50, 61 00000912 1:23:16 PM [2404] hpCDEBurn: Read TOC of 12. track - LBA value: 169917 00000913 1:23:16 PM [2404] hpCDEBurn: Read TOC of 12. track - MSF format: 0, 37, 47, 42 00000914 1:23:16 PM [2404] hpCDEBurn: Read TOC of 13. track - LBA value: 180079 00000915 1:23:16 PM [2404] hpCDEBurn: Read TOC of 13. track - MSF format: 0, 40, 3, 4 00000916 1:23:16 PM [2404] hpCDEBurn: Read TOC of 14. track - LBA value: 192872 00000917 1:23:16 PM [2404] hpCDEBurn: Read TOC of 14. track - MSF format: 0, 42, 53, 47 00000918 1:23:16 PM [2404] hpCDEBurn: Read TOC of 15. track - LBA value: 205256 00000919 1:23:16 PM [2404] hpCDEBurn: Read TOC of 15. track - MSF format: 0, 45, 38, 56 00000920 1:23:16 PM [2404] hpCDEBurn: Read TOC of 16. track - LBA value: 216482 00000921 1:23:16 PM [2404] hpCDEBurn: Read TOC of 16. track - MSF format: 0, 48, 8, 32 00000922 1:23:16 PM [2404] MM5 [8440](R) CD: Going to read CD-Text. 00000923 1:23:16 PM [2404] hpCDEBurn: entering ReadCDText() 00000924 1:23:16 PM [2404] hpCDEBurn: On the point of calling pDevice->ReadCDText() 00000925 1:23:17 PM [2404] hpCDEBurn: pDevice->ReadCDText: res = 1 00000926 1:23:17 PM [2404] hpCDEBurn: - Count of CD-Text items: 1 00000927 1:23:17 PM [2404] hpCDEBurn: Reading of CD-Text for 0. track: Title: , Artist: 00000928 1:23:17 PM [2404] MM5 [8440](R) CD: CD-Text wasn't found. 00000929 1:23:17 PM [2404] MM5 [8440](R) CD: AudioCDReadCS.Released, Drive letter: G 00000930 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track01.cda 00000931 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000932 1:23:17 PM [2404] MM5 [8440](R) 8440 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000933 1:23:17 PM [2404] MM5 [8440](R) 8440 DB query prepare finished, took 0. 00000934 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track02.cda 00000935 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000936 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track03.cda 00000937 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000938 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track04.cda 00000939 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000940 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track05.cda 00000941 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000942 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track06.cda 00000943 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000944 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track07.cda 00000945 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000946 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track08.cda 00000947 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000948 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track09.cda 00000949 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000950 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track10.cda 00000951 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000952 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track11.cda 00000953 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000954 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track12.cda 00000955 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000956 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track13.cda 00000957 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000958 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track14.cda 00000959 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000960 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track15.cda 00000961 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000962 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: G:\Track16.cda 00000963 1:23:17 PM [2404] MM5 [8440](R) GetFileInfo: AudioCDTrack 00000964 1:23:17 PM [2404] MM5 [8440](R) CD: Procedure finished (GetInfoFromAudioCD) 00000965 1:23:17 PM [2404] MM5 [8440](R) Changed table - doing autoupdate TSongList and set previous focusedIndex 0 00000966 1:23:17 PM [2404] MM5 [8440](R) BQ: Task took 688 ms, stack: TSharedUIList.processTableUpdate, list type: tracklist, count: 17, created ago: 10448062ms, stack: Callstack: Script: file:///viewHandlers.js ; Func: __refreshTracklist ; Row: 196 ; Col: 57 00000967 1:23:17 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 235 ; Col: 13 00000968 1:23:17 PM [2404] Script: file:///viewHandlers.js ; Func: onShow ; Row: 291 ; Col: 56 00000969 1:23:17 PM [2404] Script: file:///controls/multiview.js ; Func: _porcess ; Row: 1113 ; Col: 38 00000970 1:23:17 PM [2404] Script: file:///controls/multiview.js ; Func: _handler_call_onShow ; Row: 1118 ; Col: 9 00000971 1:23:17 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TSharedBase.RunAsPromiseNative 00000972 1:23:17 PM [2404] MM5 [8440](R) BQ: Thread[8440] is executing new task now: TFullPlayer.CheckPlayCounter 00000973 1:23:19 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000974 1:23:20 PM [2404] MM5 [8440](R) DB: Deleting prepared query: SELECT Count(*) FROM PathProcessing 00000975 1:23:20 PM [2404] MM5 [8440](R) DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 00000976 1:23:21 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000977 1:23:23 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000978 1:23:26 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000979 1:23:27 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000980 1:23:29 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000981 1:23:31 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000982 1:23:33 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000983 1:23:33 PM [2404] MM5 [4276](R) Handling HID: 131145/0 00000984 1:23:35 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000985 1:23:37 PM [2404] MM5 [21576](R) BQ: Thread[21576] is executing new task now: TFullPlayer.CheckPlayCounter 00000986 1:23:37 PM [2404] MM5 [4276](R) Ignoring HID: 131145/0 - already handled by WMInput/AppCommand