00000001 0.00000000 [23156] MM5 [7828](R) DB: Deleting prepared query: SELECT IDGenre, GenreName FROM Genres 00000002 0.01641490 [23156] MM5 [11788](R) Going 'Worker'to make final callback 11788 00000003 0.17739710 [23156] SAStream: seeked to 589824 00000004 0.17744200 [23156] SAStream: seeked to 655360 00000005 0.17748660 [23156] SAStream: seeked to 720896 00000006 0.26048771 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 4555/107180 ms, tolerance hit: false 00000007 0.26467410 [23156] MM5 [5760](R) Still waiting for event (callstacks) : 00000008 0.26474631 [23156] MM5 [5760](R) Wait for event ID :1, delay :20000, callstack : 00000009 0.26481640 [23156] MM5 [5760](R) Wait for event ID :1, delay :60000, callstack : 00000010 0.49369529 [23692] 19/02 22:58:38.085 {INFO} [DML:HMDNormal] HMD Reporting state change: {'State':'NotDetected','IsDetected':0,'IsReady':0,'Mode':'Unknown','IsWirelessReady':0,'IsRebooting':1,'IsFirmwareOutOfDate':0} 00000011 0.51292962 [23156] MM5 [18096](R) Going 'Worker'to make final callback 18096 00000012 0.51642388 [23156] MM5 [4632](R) Going 'Worker'to make final callback 4632 00000013 1.78962398 [23156] SAStream: Seek(655360, 0) 00000014 1.78969419 [23156] SAStream: Seek(720896, 0) 00000015 1.78975201 [23156] SAStream: Seek(786432, 0) 00000016 2.26357985 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 6546/107180 ms, tolerance hit: false 00000017 2.87403321 [23156] MM5 [17188](R) Check scheduled scan/sync for: MediaMonkey Server, Last scan: 1676865468976, Interval: 30000, now: 1676865520465 00000018 2.87511230 [23156] MM5 [18684](R) BQ: Thread[18684] is executing new task now: Callstack: Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 2860 ; Col: 20 00000019 2.87511230 [23156] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000020 2.87511230 [23156] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000021 2.87511230 [23156] Script: file:///helpers/mediaSync.js ; Func: setCustomSettings ; Row: 2858 ; Col: 16 00000022 2.87511230 [23156] Script: file:///helpers/mediaSync.js ; Func: checkLibraryScan ; Row: 3113 ; Col: 22 00000023 2.87526011 [23156] MM5 [17188](R) BQ: Currently running 2 threads of 30 00000024 2.87572789 [23156] MM5 [8496](R) TSharing.refreshRemoteServers starting 00000025 2.87600470 [23156] UPnP: Get servers 00000026 2.87607646 [23156] UPnP: Servers count: 0 00000027 2.87636971 [23156] MM5 [17188](R) MediaMonkey Server is not syncable now 00000028 2.92342329 [23156] MM5 [5760](R) CheckDownloadsQueue: Max: 4, in progress: 0, queued: 0, resume enabled: true 00000029 3.48295069 [23156] SAStream: Seek(720896, 0) 00000030 3.48301840 [23156] SAStream: Seek(786432, 0) 00000031 3.48307490 [23156] SAStream: Seek(851968, 0) 00000032 3.72122192 [23156] MM5 [8496](R) BQ: Thread[8496] is executing new task now: TCollections.doRefreshCollections 00000033 3.72131991 [23156] MM5 [8496](R) TCollection.getIsVisible: Classical Music, FVisibleInMainTree: 2 00000034 3.72164011 [23156] MM5 [8496](R) TCollection.checkCollectionContainsData:Classical Music, query: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (3) LIMIT 1, res: true 00000035 3.72172999 [23156] MM5 [8496](R) TCollection.getIsVisible: Audiobooks, FVisibleInMainTree: 2 00000036 3.72194171 [23156] MM5 [8496](R) TCollection.checkCollectionContainsData:Audiobooks, query: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (2) LIMIT 1, res: true 00000037 3.72203588 [23156] MM5 [8496](R) DB open SQL: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (4) LIMIT 1 00000038 3.72219324 [23156] MM5 [8496](R) TCollection.getIsVisible: Video, FVisibleInMainTree: 2 00000039 3.72235513 [23156] MM5 [8496](R) TCollection.checkCollectionContainsData:Video, query: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (5) LIMIT 1, res: true 00000040 3.72243834 [23156] MM5 [8496](R) DB open SQL: SELECT Songs.Id AS SongID FROM Songs WHERE Songs.TrackType in (6) LIMIT 1 00000041 4.25033140 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 8531/107180 ms, tolerance hit: false 00000042 5.13689184 [23156] SAStream: Seek(786432, 0) 00000043 5.13694334 [23156] SAStream: Seek(851968, 0) 00000044 5.13698578 [23156] SAStream: Seek(917504, 0) 00000045 5.89521265 [23156] MM5 [18684](R) Going 'Worker'to make final callback 18684 00000046 6.25241661 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 10535/107180 ms, tolerance hit: false 00000047 6.72240162 [23156] MM5 [8496](R) Going 'Worker'to make final callback 8496 00000048 7.01905441 [23156] SAStream: Seek(851968, 0) 00000049 7.01913977 [23156] SAStream: Seek(917504, 0) 00000050 7.01918554 [23156] SAStream: Seek(983040, 0) 00000051 8.25417709 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 12537/107180 ms, tolerance hit: false 00000052 8.63011456 [23156] SAStream: Seek(917504, 0) 00000053 8.63017654 [23156] SAStream: Seek(983040, 0) 00000054 8.63023090 [23156] SAStream: Seek(1048576, 0) 00000055 10.25756454 [23156] MM5 [24760](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 14539/107180 ms, tolerance hit: false 00000056 10.28221512 [23156] MM5 [5760](R) Still waiting for event (callstacks) : 00000057 10.28228188 [23156] MM5 [5760](R) Wait for event ID :1, delay :60000, callstack : 00000058 10.68119526 [23156] SAStream: Seek(983040, 0) 00000059 10.68125916 [23156] SAStream: Seek(1048576, 0) 00000060 10.68131638 [23156] SAStream: Seek(1114112, 0) 00000061 12.26075077 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 16544/107180 ms, tolerance hit: false 00000062 12.62597084 [23156] SAStream: Seek(1048576, 0) 00000063 12.62603664 [23156] SAStream: Seek(1114112, 0) 00000064 12.62609482 [23156] SAStream: Seek(1179648, 0) 00000065 14.26461411 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 18546/107180 ms, tolerance hit: false 00000066 14.57155037 [23156] SAStream: Seek(1114112, 0) 00000067 14.57161427 [23156] SAStream: Seek(1179648, 0) 00000068 14.57166767 [23156] SAStream: Seek(1245184, 0) 00000069 15.50546360 [23692] 19/02 22:58:53.096 {INFO} [DML:HMDNormal] HMD Reporting state change: {'State':'NotDetected','IsDetected':0,'IsReady':0,'Mode':'Unknown','IsWirelessReady':0,'IsRebooting':1,'IsFirmwareOutOfDate':0} 00000070 16.20388794 [23156] SAStream: Seek(1179648, 0) 00000071 16.20394325 [23156] SAStream: Seek(1245184, 0) 00000072 16.20399094 [23156] SAStream: Seek(1310720, 0) 00000073 16.25138092 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 20533/107180 ms, tolerance hit: false 00000074 18.17044067 [23156] SAStream: Seek(1245184, 0) 00000075 18.17050552 [23156] SAStream: Seek(1310720, 0) 00000076 18.17056084 [23156] SAStream: Seek(1376256, 0) 00000077 18.25234795 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 22535/107180 ms, tolerance hit: false 00000078 19.88653946 [23156] SAStream: Seek(1310720, 0) 00000079 19.88660812 [23156] SAStream: Seek(1376256, 0) 00000080 19.88666534 [23156] SAStream: Seek(1441792, 0) 00000081 20.25226974 [23156] MM5 [24760](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 24534/107180 ms, tolerance hit: false 00000082 20.29621124 [23156] MM5 [5760](R) Still waiting for event (callstacks) : 00000083 20.29627991 [23156] MM5 [5760](R) Wait for event ID :1, delay :60000, callstack : 00000084 21.57940102 [23156] SAStream: Seek(1376256, 0) 00000085 21.57946205 [23156] SAStream: Seek(1441792, 0) 00000086 21.57951736 [23156] SAStream: Seek(1507328, 0) 00000087 22.25495720 [23156] MM5 [24760](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 26538/107180 ms, tolerance hit: false 00000088 23.40008354 [23156] SAStream: Seek(1441792, 0) 00000089 23.40014648 [23156] SAStream: Seek(1507328, 0) 00000090 23.40020370 [23156] SAStream: Seek(1572864, 0) 00000091 24.25889206 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 28542/107180 ms, tolerance hit: false 00000092 25.38675880 [23156] SAStream: Seek(1507328, 0) 00000093 25.38682175 [23156] SAStream: Seek(1572864, 0) 00000094 25.38687706 [23156] SAStream: Seek(1638400, 0) 00000095 26.26133537 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 30543/107180 ms, tolerance hit: false 00000096 27.41571426 [23156] SAStream: Seek(1572864, 0) 00000097 27.41576576 [23156] SAStream: Seek(1638400, 0) 00000098 27.41581154 [23156] SAStream: Seek(1703936, 0) 00000099 28.25890160 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 32542/107180 ms, tolerance hit: false 00000100 29.00756836 [23156] SAStream: Seek(1638400, 0) 00000101 29.00762939 [23156] SAStream: Seek(1703936, 0) 00000102 29.00768280 [23156] SAStream: Seek(1769472, 0) 00000103 30.26009941 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 34543/107180 ms, tolerance hit: false 00000104 30.31162834 [23156] MM5 [5760](R) Still waiting for event (callstacks) : 00000105 30.31169510 [23156] MM5 [5760](R) Wait for event ID :1, delay :60000, callstack : 00000106 30.50865364 [23692] 19/02 22:59:08.100 {INFO} [DML:HMDNormal] HMD Reporting state change: {'State':'NotDetected','IsDetected':0,'IsReady':0,'Mode':'Unknown','IsWirelessReady':0,'IsRebooting':1,'IsFirmwareOutOfDate':0} 00000107 31.05862427 [23156] SAStream: Seek(1703936, 0) 00000108 31.05868721 [23156] SAStream: Seek(1769472, 0) 00000109 31.05874252 [23156] SAStream: Seek(1835008, 0) 00000110 32.24968338 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 36532/107180 ms, tolerance hit: false 00000111 32.71251297 [23156] SAStream: Seek(1769472, 0) 00000112 32.71257782 [23156] SAStream: Seek(1835008, 0) 00000113 32.71263504 [23156] SAStream: Seek(1900544, 0) 00000114 32.89892578 [23156] MM5 [17188](R) Check scheduled scan/sync for: MediaMonkey Server, Last scan: 1676865520465, Interval: 30000, now: 1676865550489 00000115 32.89996338 [23156] MM5 [23680](R) BQ: Thread[23680] is executing new task now: Callstack: Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 2860 ; Col: 20 00000116 32.89996338 [23156] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000117 32.89996338 [23156] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000118 32.89996338 [23156] Script: file:///helpers/mediaSync.js ; Func: setCustomSettings ; Row: 2858 ; Col: 16 00000119 32.89996338 [23156] Script: file:///helpers/mediaSync.js ; Func: checkLibraryScan ; Row: 3113 ; Col: 22 00000120 32.90011978 [23156] MM5 [17188](R) BQ: Currently running 2 threads of 30 00000121 32.90064240 [23156] MM5 [22240](R) TSharing.refreshRemoteServers starting 00000122 32.90091705 [23156] UPnP: Get servers 00000123 32.90098572 [23156] UPnP: Servers count: 0 00000124 32.91185379 [23156] MM5 [17188](R) MediaMonkey Server is not syncable now 00000125 34.25168610 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 38535/107180 ms, tolerance hit: false 00000126 34.67953110 [23156] SAStream: Seek(1835008, 0) 00000127 34.67960358 [23156] SAStream: Seek(1900544, 0) 00000128 34.67966080 [23156] SAStream: Seek(1966080, 0) 00000129 35.90160751 [23156] MM5 [22240](R) Going 'Worker'to make final callback 22240 00000130 35.90609741 [23156] MM5 [23680](R) Going 'Worker'to make final callback 23680 00000131 36.26263809 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 40545/107180 ms, tolerance hit: false 00000132 36.39859772 [23156] SAStream: Seek(1900544, 0) 00000133 36.39866257 [23156] SAStream: Seek(1966080, 0) 00000134 36.39871979 [23156] SAStream: Seek(2031616, 0) 00000135 38.26432419 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 42547/107180 ms, tolerance hit: false 00000136 38.40613937 [23156] SAStream: Seek(1966080, 0) 00000137 38.40618896 [23156] SAStream: Seek(2031616, 0) 00000138 38.40623474 [23156] SAStream: Seek(2097152, 0) 00000139 40.25112915 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 44534/107180 ms, tolerance hit: false 00000140 40.25121689 [23156] SAStream: seeked to 2031616 00000141 40.25128174 [23156] SAStream: seeked to 2097152 00000142 40.25134277 [23156] SAStream: seeked to 2162688 00000143 40.32785034 [23156] MM5 [5760](R) Still waiting for event (callstacks) : 00000144 40.32791519 [23156] MM5 [5760](R) Wait for event ID :1, delay :60000, callstack : 00000145 41.75637054 [23156] SAStream: Seek(2097152, 0) 00000146 41.75643539 [23156] SAStream: Seek(2162688, 0) 00000147 41.75648880 [23156] SAStream: Seek(2228224, 0) 00000148 42.25507355 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 46537/107180 ms, tolerance hit: false 00000149 43.68202972 [23156] SAStream: Seek(2162688, 0) 00000150 43.68209076 [23156] SAStream: Seek(2228224, 0) 00000151 43.68214417 [23156] SAStream: Seek(2293760, 0) 00000152 44.25821304 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 48541/107180 ms, tolerance hit: false 00000153 45.49742889 [23156] SAStream: Seek(2228224, 0) 00000154 45.49752808 [23156] SAStream: seeked to 2293760 00000155 45.49760056 [23156] SAStream: seeked to 2359296 00000156 45.51279068 [23692] 19/02 22:59:23.104 {INFO} [DML:HMDNormal] HMD Reporting state change: {'State':'NotDetected','IsDetected':0,'IsReady':0,'Mode':'Unknown','IsWirelessReady':0,'IsRebooting':1,'IsFirmwareOutOfDate':0} 00000157 46.25837708 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 50540/107180 ms, tolerance hit: false 00000158 47.48351669 [23156] SAStream: Seek(2293760, 0) 00000159 47.48357391 [23156] SAStream: Seek(2359296, 0) 00000160 47.48361969 [23156] SAStream: Seek(2424832, 0) 00000161 48.25814438 [23156] MM5 [24760](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 52554/107180 ms, tolerance hit: false 00000162 49.28312683 [23156] SAStream: Seek(2359296, 0) 00000163 49.28318787 [23156] SAStream: Seek(2424832, 0) 00000164 49.28324127 [23156] SAStream: Seek(2490368, 0) 00000165 50.25780869 [23156] MM5 [24760](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 54541/107180 ms, tolerance hit: false 00000166 50.34449387 [23156] MM5 [5760](R) Still waiting for event (callstacks) : 00000167 51.20835495 [23156] SAStream: Seek(2424832, 0) 00000168 52.26071548 [23156] MM5 [24760](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 56543/107180 ms, tolerance hit: false 00000169 52.90677643 [23156] MM5 [5760](R) BQ: Currently running 1 threads of 30 00000170 52.92087936 [23156] MM5 [5760](R) New thread starting 1 00000171 52.92211151 [23156] MM5 [25232](R) Going 'AutoCalcNormalization_Var'to make final callback 25232 00000172 52.92224884 [23156] MM5 [5760](R) BQ: Task took 62672 ms, stack: TCallBacks.ProcessEventDoneCallbacks 00000173 52.92777634 [23156] MM5 [17188](R) registered task progress 1 00000174 53.10785294 [23156] SAStream: Seek(2490368, 0) 00000175 53.10791397 [23156] SAStream: Seek(2555904, 0) 00000176 53.10797119 [23156] SAStream: Seek(2621440, 0) 00000177 54.26199722 [23156] MM5 [13384](R) MemRAM: 242.1 MB, MemPrivate: 209.2 MB, MemVirtual: 701.9 MB 00000178 54.26219559 [23156] MM5 [13384](R) Allocated TSharedObject: 8146, delta: 7378 00000179 54.26225281 [23156] MM5 [13384](R) Allocated TSharedList: 252, delta: 79 00000180 54.26231766 [23156] MM5 [13384](R) Allocated TSongListData: 6470, delta: 6470 00000181 54.26237488 [23156] MM5 [13384](R) Allocated TQueryPlus: 0, delta: -3 00000182 54.26243973 [23156] MM5 [13384](R) Allocated TArtist: 0, delta: 0 00000183 54.26249695 [23156] MM5 [13384](R) Allocated TPodcast: 0, delta: 0 00000184 54.26256180 [23156] MM5 [13384](R) Allocated TPromise: 0, delta: -4 00000185 54.27170563 [23156] MM5 [24760](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 58555/107180 ms, tolerance hit: false 00000186 54.65466690 [23156] SAStream: Seek(2555904, 0) 00000187 54.65473175 [23156] SAStream: Seek(2621440, 0) 00000188 54.65478897 [23156] SAStream: Seek(2686976, 0) 00000189 55.90740585 [23156] MM5 [18864](R) Going 'Worker'to make final callback 18864 00000190 55.92242050 [23156] MM5 [5760](R) Going 'Worker'to make final callback 5760 00000191 56.02689362 [23692] 19/02 22:59:33.618 {INFO} [HW:Enumeration] Connected devices: 0 Camera(s) 00000192 56.25790787 [23156] MM5 [13384](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 60541/107180 ms, tolerance hit: false 00000193 56.53684616 [23156] SAStream: Seek(2621440, 0) 00000194 56.53690720 [23156] SAStream: Seek(2686976, 0) 00000195 56.53696823 [23156] SAStream: Seek(2752512, 0) 00000196 57.17117310 [23692] 19/02 22:59:34.762 {DEBUG} [Gamepad:Utils] Device change detected: Scanning for gamepads... 00000197 57.17309570 [23156] MM5 [17188](R) BQ: Currently running 0 threads of 10 00000198 57.17386246 [23156] MM5 [19264](R) BQ: Thread[19264] is executing new task now: TDevices.WMDeviceChangeToAll 00000199 57.17406845 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000200 57.17413712 [23156] MM5 [15216](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000201 57.17419434 [23156] MM5 [15216](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000202 57.17427063 [23156] MM5 [15216](R) BQ: Thread[15216] is executing new task now: TDevices.WMDeviceChangeToAll 00000203 57.17432785 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000204 57.17441177 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000205 57.17448425 [23156] MM5 [15216](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000206 57.17455292 [23156] MM5 [15216](R) BQ: Thread[15216] is executing new task now: TDevices.WMDeviceChangeToAll 00000207 57.18322372 [23156] WASAPI: DeviceStateChanged({0.0.0.00000000}.{bfcf50e0-9e23-42ac-bbbf-7f957f783ec5}, notpresent) 00000208 57.19028473 [23156] WASAPI: OnPropertyValueChanged({0.0.0.00000000}.{bfcf50e0-9e23-42ac-bbbf-7f957f783ec5}, {9C119480-DDC2-4954-A150-5BD240D454AD}, 1) 00000209 57.19050980 [23156] WASAPI: OnPropertyValueChanged({0.0.0.00000000}.{bfcf50e0-9e23-42ac-bbbf-7f957f783ec5}, {9C119480-DDC2-4954-A150-5BD240D454AD}, 2) 00000210 57.20326996 [23156] iPod (MM5): [19264] DeviceChange: wParam: 7, devtype: -1 00000211 57.20332718 [23156] WMDM: Queueing action id:0 00000212 57.20341873 [23156] WMDM: Looking for device changes... 00000213 58.25743866 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 62540/107180 ms, tolerance hit: false 00000214 58.39636993 [23156] SAStream: Seek(2686976, 0) 00000215 58.39644241 [23156] SAStream: Seek(2752512, 0) 00000216 58.39649963 [23156] SAStream: Seek(2818048, 0) 00000217 60.17470169 [23156] MM5 [15216](R) Going 'Worker'to make final callback 15216 00000218 60.20320511 [23156] MM5 [19264](R) Going 'Worker'to make final callback 19264 00000219 60.25600815 [23156] MM5 [9976](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 64539/107180 ms, tolerance hit: false 00000220 60.30339050 [23156] SAStream: Seek(2752512, 0) 00000221 60.30346298 [23156] SAStream: Seek(2818048, 0) 00000222 60.30352020 [23156] SAStream: Seek(2883584, 0) 00000223 60.51843262 [23692] 19/02 22:59:38.109 {INFO} [DML:HMDNormal] HMD Reporting state change: {'State':'NotDetected','IsDetected':0,'IsReady':0,'Mode':'Unknown','IsWirelessReady':0,'IsRebooting':1,'IsFirmwareOutOfDate':0} 00000224 62.02810287 [23156] SAStream: Seek(2818048, 0) 00000225 62.02817154 [23156] SAStream: Seek(2883584, 0) 00000226 62.02822876 [23156] SAStream: Seek(2949120, 0) 00000227 63.11532211 [23156] MM5 [17188](R) Check scheduled scan/sync for: MediaMonkey Server, Last scan: 1676865550490, Interval: 30000, now: 1676865580706 00000228 63.11634064 [23156] MM5 [12784](R) BQ: Thread[12784] is executing new task now: Callstack: Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 2860 ; Col: 20 00000229 63.11634064 [23156] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000230 63.11634064 [23156] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000231 63.11634064 [23156] Script: file:///helpers/mediaSync.js ; Func: setCustomSettings ; Row: 2858 ; Col: 16 00000232 63.11634064 [23156] Script: file:///helpers/mediaSync.js ; Func: checkLibraryScan ; Row: 3113 ; Col: 22 00000233 63.11645889 [23156] MM5 [17188](R) BQ: Currently running 1 threads of 10 00000234 63.11697388 [23156] MM5 [6908](R) TSharing.refreshRemoteServers starting 00000235 63.11724091 [23156] UPnP: Get servers 00000236 63.11730576 [23156] UPnP: Servers count: 0 00000237 63.14012909 [23156] MM5 [17188](R) MediaMonkey Server is not syncable now 00000238 64.18615723 [23156] SAStream: Seek(2883584, 0) 00000239 64.18621826 [23156] SAStream: Seek(2949120, 0) 00000240 64.18627930 [23156] SAStream: Seek(3014656, 0) 00000241 64.26245880 [23156] MM5 [24760](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 68545/107180 ms, tolerance hit: false 00000242 66.12606812 [23156] MM5 [12784](R) Going 'Worker'to make final callback 12784 00000243 66.24963379 [23156] MM5 [14460](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 70532/107180 ms, tolerance hit: false 00000244 66.24999237 [23156] SAStream: seeked to 2949120 00000245 66.25005341 [23156] SAStream: seeked to 3014656 00000246 66.25015259 [23156] SAStream: seeked to 3080192 00000247 68.24975586 [23156] MM5 [14460](R) TFullPlayer.CheckIncreasePlayed: Percentage: 0.9, track position: 71934/107180 ms, tolerance hit: false 00000248 70.12885284 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000249 70.12953949 [23156] MM5 [10116](R) BQ: Thread[10116] is executing new task now: TDevices.WMDeviceChangeToAll 00000250 70.12968445 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000251 70.12979126 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000252 70.12985992 [23156] MM5 [26320](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000253 70.12995148 [23156] MM5 [26320](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000254 70.13001251 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000255 70.13008118 [23156] MM5 [26320](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000256 70.13014984 [23156] MM5 [26320](R) BQ: Thread[26320] is executing new task now: TDevices.WMDeviceChangeToAll 00000257 70.13191986 [23692] 19/02 22:59:47.723 {DEBUG} [Gamepad:Utils] Device change detected: Scanning for gamepads... 00000258 70.14553070 [23156] WASAPI: OnDefaultDeviceChanged(, eRender, eConsole), CurrentDeviceIDConfig={0.0.0.00000000}.{21aed06f-34d2-4dda-878e-660d042fde94}, DefaultDeviceID={0.0.0.00000000}.{21aed06f-34d2-4dda-878e-660d042fde94} 00000259 70.14587402 [23156] WASAPI: OnDefaultDeviceChanged(, eRender, eCommunications), CurrentDeviceIDConfig={0.0.0.00000000}.{21aed06f-34d2-4dda-878e-660d042fde94}, DefaultDeviceID={0.0.0.00000000}.{21aed06f-34d2-4dda-878e-660d042fde94} 00000260 70.14733887 [24720] 2023-02-19 22:59:47.738 W cue.libs.sys_vol_control: Cannot get default audio endpoint device 00000261 70.16209412 [23156] WASAPI: OnSessionDisconnected(DisconnectReasonDeviceRemoval) 00000262 70.16226959 [23156] WASAPI: OnPropertyValueChanged({0.0.0.00000000}.{21aed06f-34d2-4dda-878e-660d042fde94}, {9C119480-DDC2-4954-A150-5BD240D454AD}, 2) 00000263 70.16461945 [23156] iPod (MM5): [10116] DeviceChange: wParam: 7, devtype: -1 00000264 70.16468811 [23156] WMDM: Queueing action id:0 00000265 70.16476440 [23156] WMDM: Looking for device changes... 00000266 70.21157074 [23692] 19/02 22:59:47.802 {WARNING} [AudioDeviceSource] Unable to obtain Windows default audio output device: Element not found. (-2147023728) 00000267 70.21276855 [23692] 19/02 22:59:47.803 {WARNING} [DefaultAudioDevicePolicy] Could not find any suitable audio output device 00000268 70.22456360 [23156] WASAPI: Unplugged, resetting 00000269 70.22710419 [23156] WASAPI: No output device available. 00000270 70.22726440 [23156] WASAPI: Output thread is terminated 00000271 70.22773743 [23156] MM5 [26320](R) _Stop: started 00000272 70.22786713 [23156] MM5 [26320](R) SynchronizingAfterTagging finished 00000273 70.25543976 [23156] in_mfaudio: DecodeThread: calling Flush 2 00000274 70.25565338 [23156] in_mfaudio: DecodeThread: exit 00000275 70.25600433 [23156] in_mfaudio: Stop - calling free(pReadBuffer) 00000276 70.25610352 [23156] WASAPI: Close starts 00000277 70.25616455 [23156] MM5 [26320](R) IntOut_Close - freeing resampler 00000278 70.25630188 [23156] in_mfaudio: Stop - releasing pSource 00000279 70.25659180 [23156] in_mfaudio: Stop - pReader released 00000280 70.25666046 [23156] SAStream: calling CloseStreamAccess 00000281 70.25671387 [23156] in_mfaudio: Stop - calling SAVSADeInit 00000282 70.25677490 [23156] MM5 [26320](R) TWAPluginEngine.Stop - input plugin stopped 00000283 70.25689697 [23156] MM5 [26320](R) TInternalPlayer.Stop - finished 00000284 70.25698090 [16616] MM5 [22572](B) Received message 1024(2, 603) 00000285 70.25702667 [16616] MM5 [22572](B) Redirecting winamp message 1024(2, 603) 00000286 70.25709534 [23156] MM5 [17188](R) Player - new state: stop 00000287 70.25846100 [23156] MM5 [17188](R) last.fm: onPlaybackState stop 00000288 70.25873566 [23156] MM5 [17188](R) Player - new state: stop 00000289 70.26747131 [16616] MM5 [22572](B) WARNING: Another command in progress, inProgressCommands: 1 00000290 70.26770782 [16616] MM5 [22572](B) WARNING: Another command in progress, inProgressCommands: 2 00000291 70.37924957 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000292 70.37940216 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000293 70.37951660 [23156] MM5 [26320](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000294 70.37968445 [23156] MM5 [10116](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000295 70.39435577 [16616] MM5 [22572](B) WARNING: Another command in progress, inProgressCommands: 1 00000296 70.40986633 [16616] MM5 [22572](B) WARNING: Another command in progress, inProgressCommands: 1 00000297 70.42514038 [16616] MM5 [22572](B) WARNING: Another command in progress, inProgressCommands: 1 00000298 70.44097137 [23156] MM5 [17188](R) handleCOMr_com_player_is_playing 00000299 70.45606995 [23156] MM5 [17188](R) handleCOMr_com_player_get_is_shuffle 00000300 70.47148895 [23156] MM5 [17188](R) handleCOMr_com_player_get_is_repeat 00000301 70.48731995 [23156] MM5 [17188](R) handleCOMr_com_player_get_volume 00000302 70.50208282 [23156] MM5 [17188](R) handleCOMr_com_player_get_playback_time 00000303 73.38147736 [23156] MM5 [26320](R) Going 'Worker'to make final callback 26320 00000304 75.52543640 [23692] 19/02 22:59:47.803 {INFO} [AudioPipeline] output -> NONE 00000305 79.07540894 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000306 79.07588196 [23156] MM5 [17188](R) BQ: Currently running 1 threads of 10 00000307 79.07614136 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000308 79.07631683 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000309 79.07637024 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000310 79.28483582 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000311 79.28654480 [23156] MM5 [3612](R) BQ: Thread[3612] is executing new task now: TDevices.WMDeviceChangeToAll 00000312 79.28662109 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000313 79.28670502 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000314 79.28686523 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000315 79.58541870 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000316 79.58616638 [23156] MM5 [3612](R) BQ: Thread[3612] is executing new task now: TDevices.WMDeviceChangeToAll 00000317 79.58623505 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000318 79.58630371 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000319 79.58639526 [23156] MM5 [3612](R) BQ: Thread[3612] is executing new task now: TDevices.WMDeviceChangeToAll 00000320 79.91860199 [23692] 19/02 22:59:57.509 {DEBUG} [Gamepad:Utils] Device change detected: Scanning for gamepads... 00000321 79.93389130 [23156] MM5 [3612](R) BQ: Thread[3612] is executing new task now: TDevices.WMDeviceChangeToAll 00000322 79.93396759 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000323 79.98251343 [24720] 2023-02-19 22:59:57.573 W cue.libs.sys_vol_control: Nothing to release 00000324 79.98720551 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000325 79.98730469 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000326 79.98780060 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000327 79.98786926 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000328 79.98796844 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000329 79.98802948 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000330 79.99600220 [23692] 19/02 22:59:57.587 {DEBUG} [Gamepad:Utils] Device change detected: Scanning for gamepads... 00000331 80.04611206 [23692] 19/02 22:59:57.637 {WARNING} [DefaultAudioDevicePolicy] Could not find Rift device for input falling back to Windows default 00000332 80.13810730 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000333 80.13819885 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000334 80.13829803 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000335 80.13883209 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000336 80.13895416 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000337 80.13903046 [23156] MM5 [3612](R) BQ: Thread[3612] is executing new task now: TDevices.WMDeviceChangeToAll 00000338 80.13909149 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000339 80.13916016 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000340 80.13923645 [23156] MM5 [3612](R) BQ: Thread[3612] is executing new task now: TDevices.WMDeviceChangeToAll 00000341 80.13928223 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000342 80.13935089 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000343 80.43952942 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000344 80.44011688 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000345 80.44017792 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000346 80.44027710 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000347 80.44036102 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000348 80.49346161 [24720] 2023-02-19 22:59:58.084 I cue.devices: Manifest for device "(vid=21b4, pid=81)" not found. 00000349 80.50566864 [23692] 19/02 22:59:57.637 {INFO} [AudioPipeline] input -> Microphone (Realtek High Definition Audio) 00000350 80.50585938 [23692] 19/02 22:59:58.096 {DEBUG} [Gamepad:Utils] Device change detected: Scanning for gamepads... 00000351 80.68231964 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000352 80.68251801 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000353 80.68330383 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000354 80.68338776 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000355 80.68344116 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000356 80.68350983 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000357 80.70715332 [23692] 19/02 22:59:58.298 {DEBUG} [Gamepad:Utils] Device change detected: Scanning for gamepads... 00000358 80.77101898 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000359 80.77163696 [23156] MM5 [3612](R) BQ: Thread[3612] is executing new task now: TDevices.WMDeviceChangeToAll 00000360 80.77173615 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000361 80.77181244 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000362 80.78464508 [23692] 19/02 22:59:58.376 {DEBUG} [Gamepad:Utils] Device change detected: Scanning for gamepads... 00000363 80.81930542 [23692] 19/02 22:59:58.410 {WARNING} [DefaultAudioDevicePolicy] Could not find Rift device for input falling back to Windows default 00000364 80.88487244 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000365 80.88539124 [23156] MM5 [20312](R) BQ: Thread[20312] is executing new task now: TDevices.WMDeviceChangeToAll 00000366 80.88545227 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000367 80.88553619 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000368 80.88561249 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000369 80.99595642 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000370 80.99607086 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000371 80.99652863 [23156] MM5 [3612](R) BQ: Thread[3612] is executing new task now: TDevices.WMDeviceChangeToAll 00000372 80.99659729 [23156] MM5 [3612](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000373 80.99666595 [23156] MM5 [17188](R) TDevices.WMDeviceChangeToAll( wParam: 7 , lParam: 0) 00000374 80.99677277 [23156] MM5 [20312](R) TDevices.WMDeviceChangeToAll: already handled as last message 00000375 82.41538239 [23692] 19/02 22:59:58.410 {INFO} [AudioPipeline] input -> Microphone (Realtek High Definition Audio) 00000376 82.41544342 [23692] 19/02 23:00:00.006 {INFO} [RiftDML:ReportingManager] Reporting event 'oculus_etw_usb_event' 00000377 83.99813080 [23156] MM5 [3612](R) Going 'Worker'to make final callback 3612 00000378 85.60082245 [23692] 19/02 23:00:00.006 {INFO} [RiftDML:EtwReportEventInfo] oculus_etw_usb_event 00000379 85.60082245 [23692] provider_guid : ETW_PROVIDER_USB3HUB_GUID 00000380 85.60082245 [23692] process_id : 0 00000381 85.60082245 [23692] event_id : USBHUB3_ETW_EVENT_DESCRIPTOR_VALIDATION_FAILURE 00000382 85.60082245 [23692] version : 0 00000383 85.60082245 [23692] channel : 16 00000384 85.60082245 [23692] task : 9 00000385 85.60082245 [23692] opcode : 11 00000386 85.60082245 [23692] activity_guid : {9B06CAFC-415E-000C-8DAE-D49B5E41D901} 00000387 85.60082245 [23692] keyword : 4000000000004401 00000388 85.60082245 [23692] event_specific_payload : fid_HubDevice = 00005876f2a6cc88, fid_UsbDevice = 00005876f5412ab8, fid_PortNumber = 00000001, fid_NtStatus = 00000084, 00000389 85.60082245 [23692] 00000390 85.60089874 [23692] 19/02 23:00:00.006 {INFO} [RiftDML:EtwReportEventInfo] oculus_etw_usb_event 00000391 85.60089874 [23692] provider_guid : ETW_PROVIDER_USB3HUB_GUID 00000392 85.60089874 [23692] process_id : 0 00000393 85.60089874 [23692] event_id : USBHUB3_ETW_EVENT_INVALID_SERIAL_NUMBER_STRING_DESCRIPTOR 00000394 85.60089874 [23692] version : 0 00000395 85.60089874 [23692] channel : 16 00000396 85.60089874 [23692] task : 10 00000397 85.60089874 [23692] opcode : 11 00000398 85.60089874 [23692] activity_guid : {9B06CAFC-415E-000C-8DAE-D49B5E41D901} 00000399 85.60089874 [23692] keyword : 4000000000004401 00000400 85.60089874 [23692] event_specific_payload : fid_UsbDevice = 00005876f5412ab8, 00000401 85.60089874 [23692] 00000402 90.53323364 [23692] 19/02 23:00:08.124 {INFO} [DML:HMDNormal] HMD Reporting state change: {'State':'NotDetected','IsDetected':0,'IsReady':0,'Mode':'Unknown','IsWirelessReady':0,'IsRebooting':1,'IsFirmwareOutOfDate':0} 00000403 94.14021301 [23156] MM5 [17188](R) BQ: Currently running 0 threads of 10 00000404 94.14091492 [23156] MM5 [17188](R) sharing.getRemoteServers trace: Callstack: Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 1949 ; Col: 47 00000405 94.14091492 [23156] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000406 94.14091492 [23156] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000407 94.14091492 [23156] Script: file:///helpers/mediaSync.js ; Func: _getServer ; Row: 1926 ; Col: 44 00000408 94.14091492 [23156] Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 1987 ; Col: 18 00000409 94.14091492 [23156] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000410 94.14091492 [23156] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000411 94.14091492 [23156] Script: file:///helpers/mediaSync.js ; Func: _checkServerIsRunning ; Row: 1986 ; Col: 14 00000412 94.14091492 [23156] Script: file:///helpers/mediaSync.js ; Func: syncEnabled ; Row: 1083 ; Col: 14 00000413 94.14091492 [23156] Script: file:///helpers/mediaSync.js ; Func: isSyncable ; Row: 2839 ; Col: 28 00000414 94.14091492 [23156] Script: file:///helpers/mediaSync.js ; Func: checkLibraryScan ; Row: 3115 ; Col: 27 00000415 94.14091492 [23156] Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 3163 ; Col: 34 00000416 94.14091492 [23156] Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 3161 ; Col: 30 00000417 94.14091492 [23156] Script: file:///promise.js ; Func: invokeCallback ; Row: 399 ; Col: 19 00000418 94.14091492 [23156] Script: file:///promise.js ; Func: publish ; Row: 457 ; Col: 9 00000419 94.14091492 [23156] Script: file:///promise.js ; Func: publishFulfillment ; Row: 633 ; Col: 7 00000420 94.14091492 [23156] Script: file:///promise.js ; Func: flush ; Row: 193 ; Col: 9 00000421 94.14136505 [23156] MM5 [3636](R) BQ: Thread[3636] is executing new task now: Callstack: Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 1949 ; Col: 47 00000422 94.14136505 [23156] Script: file:///promise.js ; Func: invokeResolver ; Row: 387 ; Col: 18 00000423 94.14136505 [23156] Script: file:///promise.js ; Func: Promise ; Row: 374 ; Col: 7 00000424 94.14136505 [23156] Script: file:///helpers/mediaSync.js ; Func: _getServer ; Row: 1926 ; Col: 44 00000425 94.14136505 [23156] Script: file:///helpers/mediaSync.js ; Func: eval ; Row: 1987 ; Col: 18 00000426 94.16417694 [23156] MM5 [3636](R) DB open SQL: SELECT * FROM RemoteServers 00000427 94.16437531 [23156] UPnP: Get servers 00000428 94.16454315 [23156] MM5 [3636](R) TSharing.refreshRemoteServers finished