-- Logs begin at Sun 2025-05-11 00:16:49 HKT, end at Sun 2025-05-11 00:23:59 HKT. -- May 11 00:22:13 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioGetState May 11 00:22:13 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:22:24 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioGetState May 11 00:22:24 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:22:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 11 00:22:33 volumio-home1 volumio[1264]: info: Preload queue cleared May 11 00:22:33 volumio-home1 wpa_supplicant[1062]: wlan0: CTRL-EVENT-BEACON-LOSS May 11 00:22:39 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 00:22:39 volumio-home1 volumio[1264]: info: CURURI: music-library May 11 00:22:39 volumio-home1 volumio[1264]: info: Preload queue cleared May 11 00:22:40 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 00:22:40 volumio-home1 volumio[1264]: info: CURURI: music-library/USB May 11 00:22:40 volumio-home1 volumio[1264]: info: Preload queue cleared May 11 00:22:41 volumio-home1 ntpd[1253]: Soliciting pool server 116.203.151.74 May 11 00:22:41 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 00:22:41 volumio-home1 volumio[1264]: info: CURURI: music-library/USB/系统 May 11 00:22:41 volumio-home1 volumio[1264]: info: Preload queue cleared May 11 00:22:45 volumio-home1 kernel: perf: interrupt took too long (3161 > 3131), lowering kernel.perf_event_max_sample_rate to 63250 May 11 00:22:48 volumio-home1 ntpd[1253]: Soliciting pool server 45.76.221.157 May 11 00:22:54 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 00:22:54 volumio-home1 volumio[1264]: info: CURURI: music-library/NAS May 11 00:22:54 volumio-home1 volumio[1264]: info: Preload queue cleared May 11 00:22:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 00:22:55 volumio-home1 volumio[1264]: info: CURURI: music-library/NAS/Music May 11 00:22:55 volumio-home1 volumio[1264]: error: Failed LSINFO: null May 11 00:22:55 volumio-home1 volumio[1264]: info: Preload queue cleared May 11 00:23:00 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioGetState May 11 00:23:00 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 00:23:05 volumio-home1 volumio[1264]: info: Retrieving Cloud Streaming UI May 11 00:23:05 volumio-home1 volumio[1264]: info: Getting Tidal Cloud Configuration May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 00:23:05 volumio-home1 volumio[1264]: info: Getting Qobuz Cloud Configuration May 11 00:23:05 volumio-home1 volumio[1264]: info: Asking plugin for UI Config May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 00:23:05 volumio-home1 volumio[1264]: info: Getting Spotify Cloud Configuration May 11 00:23:05 volumio-home1 volumio[1264]: info: Asking plugin for UI Config May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 00:23:05 volumio-home1 volumio[1264]: info: Saving Spotify Acccount May 11 00:23:05 volumio-home1 volumio[1264]: info: Got Tidal Cloud Configuration May 11 00:23:05 volumio-home1 volumio[1264]: info: Got it May 11 00:23:05 volumio-home1 volumio[1264]: info: Got it May 11 00:23:05 volumio-home1 volumio[1264]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 00:23:05 volumio-home1 volumio[1264]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioGetBrowseSources May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioGetBrowseSources May 11 00:23:05 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioGetBrowseSources May 11 00:23:06 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:06 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 11 00:23:10 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:13 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 11 00:23:13 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand rescan May 11 00:23:13 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:13 volumio-home1 volumio[1264]: info: May 11 00:23:13 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:13 volumio-home1 volumio[1264]: info: sendMpdCommand rescan took 3 milliseconds May 11 00:23:13 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:13 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:13 volumio-home1 volumio[1264]: info: May 11 00:23:13 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:13 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:13 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:13 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:13 volumio-home1 volumio[1264]: info: May 11 00:23:13 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:13 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:13 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:13 volumio-home1 volumio[1264]: info: sendMpdCommand status took 5 milliseconds May 11 00:23:13 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:17 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand status took 3847 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand status took 3847 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand status took 3847 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand status took 3847 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand status took 3846 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:17 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:17 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand update May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 184 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 63 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 37 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: info: sendMpdCommand update took 14 milliseconds May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:17 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:17 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141994,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:17 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:17 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:17 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141994,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:17 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:17 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:17 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141994,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:17 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:17 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:17 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:17 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:17 volumio-home1 volumio[1264]: info: ------------------------------ 4089ms May 11 00:23:17 volumio-home1 volumio[1264]: info: ------------------------------ 4088ms May 11 00:23:17 volumio-home1 volumio[1264]: info: ------------------------------ 4087ms May 11 00:23:17 volumio-home1 volumio[1264]: Upnp client error: Error: This socket has been ended by the other party May 11 00:23:18 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:19 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 11 00:23:19 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand update May 11 00:23:19 volumio-home1 volumio[1264]: info: sendMpdCommand update took 5 milliseconds May 11 00:23:20 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 11 00:23:20 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand rescan May 11 00:23:20 volumio-home1 volumio[1264]: info: sendMpdCommand rescan took 5 milliseconds May 11 00:23:22 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:26 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:30 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:30 volumio-home1 volumio[1264]: info: MPD Database updated - AlbumList cache refreshed May 11 00:23:30 volumio-home1 volumio[1264]: info: May 11 00:23:30 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: database May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: May 11 00:23:30 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:30 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: MPD Database updated - AlbumList cache refreshed May 11 00:23:30 volumio-home1 volumio[1264]: info: May 11 00:23:30 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: database May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: May 11 00:23:30 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:30 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: MPD Database updated - AlbumList cache refreshed May 11 00:23:30 volumio-home1 volumio[1264]: info: May 11 00:23:30 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: database May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: May 11 00:23:30 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:30 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:30 volumio-home1 volumio[1264]: info: sendMpdCommand status took 632 milliseconds May 11 00:23:30 volumio-home1 volumio[1264]: info: sendMpdCommand status took 632 milliseconds May 11 00:23:30 volumio-home1 volumio[1264]: info: sendMpdCommand status took 632 milliseconds May 11 00:23:30 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:32 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:32 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:32 volumio-home1 volumio[1264]: info: sendMpdCommand status took 2762 milliseconds May 11 00:23:32 volumio-home1 volumio[1264]: info: sendMpdCommand status took 2762 milliseconds May 11 00:23:32 volumio-home1 volumio[1264]: info: sendMpdCommand status took 2763 milliseconds May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:32 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:32 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:32 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:32 volumio-home1 volumio[1264]: Upnp client error: Error: This socket has been ended by the other party May 11 00:23:33 volumio-home1 volumio[1264]: info: sendMpdCommand status took 2968 milliseconds May 11 00:23:33 volumio-home1 volumio[1264]: info: sendMpdCommand status took 2967 milliseconds May 11 00:23:33 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:33 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:33 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:33 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:33 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:33 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:33 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 460 milliseconds May 11 00:23:33 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 462 milliseconds May 11 00:23:33 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 221 milliseconds May 11 00:23:33 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 221 milliseconds May 11 00:23:33 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 15 milliseconds May 11 00:23:33 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 14 milliseconds May 11 00:23:33 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:33 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:33 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158750,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:33 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:33 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158750,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:33 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:33 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158875,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:33 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:33 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158875,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:33 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:33 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158875,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:33 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:33 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158875,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:33 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:33 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:33 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:33 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:33 volumio-home1 volumio[1264]: info: ------------------------------ 3079ms May 11 00:23:33 volumio-home1 volumio[1264]: info: ------------------------------ 3079ms May 11 00:23:33 volumio-home1 volumio[1264]: info: ------------------------------ 3079ms May 11 00:23:33 volumio-home1 volumio[1264]: info: ------------------------------ 3078ms May 11 00:23:33 volumio-home1 volumio[1264]: info: ------------------------------ 3078ms May 11 00:23:33 volumio-home1 volumio[1264]: info: ------------------------------ 3078ms May 11 00:23:34 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:38 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:41 volumio-home1 volumio[1264]: info: May 11 00:23:41 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:41 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:41 volumio-home1 volumio[1264]: info: May 11 00:23:41 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:41 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:41 volumio-home1 volumio[1264]: info: May 11 00:23:41 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:41 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:41 volumio-home1 volumio[1264]: info: sendMpdCommand status took 4 milliseconds May 11 00:23:41 volumio-home1 volumio[1264]: info: sendMpdCommand status took 3 milliseconds May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:41 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:41 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:41 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:41 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:41 volumio-home1 volumio[1264]: info: sendMpdCommand status took 954 milliseconds May 11 00:23:41 volumio-home1 volumio[1264]: info: sendMpdCommand status took 955 milliseconds May 11 00:23:41 volumio-home1 volumio[1264]: info: sendMpdCommand status took 954 milliseconds May 11 00:23:41 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 72 milliseconds May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:41 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:42 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:42 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:42 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:42 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:42 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":169621,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:42 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:42 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:42 volumio-home1 volumio[1264]: info: ------------------------------ 1099ms May 11 00:23:42 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 172 milliseconds May 11 00:23:42 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 115 milliseconds May 11 00:23:42 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:42 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":169621,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:42 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:42 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:42 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":169621,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:42 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:42 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:42 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:42 volumio-home1 volumio[1264]: info: ------------------------------ 1258ms May 11 00:23:42 volumio-home1 volumio[1264]: info: ------------------------------ 1258ms May 11 00:23:42 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 00:23:46 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioGetState May 11 00:23:46 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:47 volumio-home1 ntpd[1253]: Soliciting pool server 2606:4700:f1::1 May 11 00:23:52 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:52 volumio-home1 volumio[1264]: info: May 11 00:23:52 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:52 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:52 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:52 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:52 volumio-home1 volumio[1264]: info: May 11 00:23:52 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:52 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:52 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:52 volumio-home1 volumio[1264]: info: May 11 00:23:52 volumio-home1 volumio[1264]: ---------------------------- MPD announces state update: update May 11 00:23:52 volumio-home1 volumio[1264]: info: ControllerMpd::getState May 11 00:23:52 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand status May 11 00:23:52 volumio-home1 volumio[1264]: info: sendMpdCommand status took 10 milliseconds May 11 00:23:52 volumio-home1 volumio[1264]: info: sendMpdCommand status took 9 milliseconds May 11 00:23:52 volumio-home1 volumio[1264]: info: sendMpdCommand status took 3 milliseconds May 11 00:23:52 volumio-home1 volumio[1264]: info: sendMpdCommand status took 2 milliseconds May 11 00:23:52 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:53 volumio-home1 ntpd[1253]: Soliciting pool server 45.76.221.157 May 11 00:23:55 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:55 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:55 volumio-home1 volumio[1264]: info: Command Router : Notfying DB Updatetrue May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseState May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 00:23:55 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 299 milliseconds May 11 00:23:55 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 156 milliseconds May 11 00:23:55 volumio-home1 volumio[1264]: info: sendMpdCommand playlistinfo took 28 milliseconds May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:55 volumio-home1 volumio[1264]: verbose: ControllerMpd::parseTrackInfo May 11 00:23:55 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":181494,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:55 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:55 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:55 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":181494,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:55 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:55 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:55 volumio-home1 volumio[1264]: info: ControllerMpd::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::servicePushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: verbose: STATE SERVICE {"status":"play","position":0,"seek":181494,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"长城 Beyond.mp3","artist":"Beyond","album":null,"uri":"USB/系统/长城 Beyond.mp3","trackType":"mp3"} May 11 00:23:55 volumio-home1 volumio[1264]: verbose: CURRENT POSITION 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState stateService play May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::syncState currentStatus play May 11 00:23:55 volumio-home1 volumio[1264]: info: Received an update from plugin. extracting info from payload May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreStateMachine::pushState May 11 00:23:55 volumio-home1 volumio[1264]: info: CorePlayQueue::getTrack 3 May 11 00:23:55 volumio-home1 volumio[1264]: info: CoreCommandRouter::volumioPushState May 11 00:23:55 volumio-home1 volumio[1264]: info: ------------------------------ 2765ms May 11 00:23:55 volumio-home1 volumio[1264]: info: ------------------------------ 2765ms May 11 00:23:55 volumio-home1 volumio[1264]: info: ------------------------------ 2757ms May 11 00:23:55 volumio-home1 volumio[1264]: Upnp client error: Error: This socket has been ended by the other party May 11 00:23:58 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 00:23:58 volumio-home1 volumio[1264]: info: CURURI: music-library May 11 00:23:58 volumio-home1 volumio[1264]: info: Preload queue cleared May 11 00:23:58 volumio-home1 volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 00:23:58 volumio-home1 volumio[1264]: info: CURURI: music-library/NAS May 11 00:23:58 volumio-home1 volumio[1264]: info: Preload queue cleared May 11 00:23:59 volumio-home1 volumio[1264]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 00:23:59 volumio-home1 volumio[1264]: Error: connect ETIMEDOUT 199.59.149.202:80 May 11 00:23:59 volumio-home1 volumio[1264]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 11 00:23:59 volumio-home1 volumio[1264]: errno: -110, May 11 00:23:59 volumio-home1 volumio[1264]: code: 'ETIMEDOUT', May 11 00:23:59 volumio-home1 volumio[1264]: syscall: 'connect', May 11 00:23:59 volumio-home1 volumio[1264]: address: '199.59.149.202', May 11 00:23:59 volumio-home1 volumio[1264]: port: 80 May 11 00:23:59 volumio-home1 volumio[1264]: } May 11 00:23:59 volumio-home1 volumio[1264]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 00:23:59 volumio-home1 sudo[3616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 00:22 May 11 00:23:59 volumio-home1 sudo[3616]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"