View Issue Details
| ID | Project | Category | View Status | Date Submitted | Last Update |
|---|---|---|---|---|---|
| 0016732 | MMW 5 | Burning / Disc Handling | public | 2020-06-18 19:22 | 2020-07-05 22:45 |
| Reporter | lowlander | Assigned To | |||
| Priority | urgent | Severity | major | Reproducibility | always |
| Status | closed | Resolution | fixed | ||
| Product Version | 5.0 | ||||
| Target Version | 5.0.1 | Fixed in Version | 5.0 | ||
| Summary | 0016732: CD Lookup doesn't retrieve featured Artists | ||||
| Description | Noticed with Justin Bieber - Changes: https://musicbrainz.org/release-group/7f673bef-5e95-46a7-baa2-8f29df0624cf Result (not sure which MediaMonkey used): https://musicbrainz.org/release/77aebad1-977c-4c92-97d6-17bc46690b06 shows Justin Bieber feat. Quavo as Artist for Track 4 (Intentions), but MediaMonkey shows only Justin Bieber as Artist value. It may a nice additional feature if MediaMonkey could interpret and tag accordingly multi-values for Artist. | ||||
| Tags | No tags attached. | ||||
| Attached Files | Lookup Log.LOG (95,991 bytes)
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<T>.sortAsync
00000196 1:22:45 PM [2404] MM5 [6048](R) BQ: Thread[6048] is executing new task now: TSharedList<T>.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<T>.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<T>.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<T>.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<T>.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<T>.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<T>.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<T>.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<T>.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
| ||||
| Fixed in build | 2259 | ||||
|
|
Used release by MM5 according to the debug log: https://musicbrainz.org/ws/2/release/77aebad1-977c-4c92-97d6-17bc46690b06?inc=recordings+artist-credits+genres&fmt=json |
|
|
Already fixed in 2259 by the same fix as for 0016745 (SVN revision 36089) |
