-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Wed 2024-08-28 22:52:36 CST. -- Aug 28 22:51:10 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 22:51:10 volumio volumio[5790]: info: CURURI: music-library Aug 28 22:51:10 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:11 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 22:51:11 volumio volumio[5790]: info: CURURI: music-library/INTERNAL Aug 28 22:51:11 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:12 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 22:51:12 volumio volumio[5790]: info: CURURI: music-library/INTERNAL/music Aug 28 22:51:12 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:15 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 22:51:15 volumio volumio[5790]: info: CURURI: artists:// Aug 28 22:51:15 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:17 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 22:51:17 volumio volumio[5790]: info: CURURI: albums:// Aug 28 22:51:17 volumio volumio[5790]: info: listAlbums - loading Albums from cache Aug 28 22:51:17 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:19 volumio nmbd[904]: [2024/08/28 22:51:19.692631, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 28 22:51:19 volumio nmbd[904]: query_name_response: Multiple (2) responses received for a query on subnet 10.62.0.25 for name WORKGROUP<1d>. Aug 28 22:51:19 volumio nmbd[904]: This response was from IP 10.62.0.251, reporting an IP address of 10.62.0.251. Aug 28 22:51:21 volumio volumio[5790]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3CUndefined%3E/0c26e961-708a-48d8-b437-3493b6a78755.jpg' Aug 28 22:51:21 volumio volumio[5790]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/artist/42503de6-2cfd-4457-a327-09f59067d3f6.jpg' Aug 28 22:51:22 volumio volumio[5790]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beyond/d7bee4ee-7d71-4ba8-b9d5-18ed031be14e.jpg' Aug 28 22:51:23 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 22:51:23 volumio volumio[5790]: info: CURURI: artists:// Aug 28 22:51:23 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:24 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 22:51:24 volumio volumio[5790]: info: CURURI: music-library Aug 28 22:51:24 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:27 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 22:51:27 volumio volumio[5790]: info: CURURI: music-library/INTERNAL Aug 28 22:51:27 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:34 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 28 22:51:34 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:35 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Aug 28 22:51:35 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:35 volumio volumio[5790]: info: Preloading song: mnt/INTERNAL/music/刀郎/01-2002年的第一场雪.mp3 Aug 28 22:51:35 volumio volumio[5790]: info: Preloading song: mnt/INTERNAL/music/小城故事.mp3 Aug 28 22:51:35 volumio volumio[5790]: info: Exploding uri mnt/INTERNAL/music/刀郎/01-2002年的第一场雪.mp3 in service mpd Aug 28 22:51:35 volumio volumio[5790]: info: ALBUMART /albumart?cacheid=371&web=%C2%B5%C2%B6%C3%80%C3%89/2002%C3%84%C3%AA%C2%B5%C3%84%C2%B5%C3%9A%C3%92%C2%BB%C2%B3%C2%A1%C3%91%C2%A9/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusic%2F%E5%88%80%E9%83%8E%2F01-2002%E5%B9%B4%E7%9A%84%E7%AC%AC%E4%B8%80%E5%9C%BA%E9%9B%AA.mp3&metadata=false Aug 28 22:51:35 volumio volumio[5790]: info: URI /mnt/INTERNAL/music/刀郎/01-2002年的第一场雪.mp3 Aug 28 22:51:36 volumio volumio[5790]: info: Exploding uri mnt/INTERNAL/music/小城故事.mp3 in service mpd Aug 28 22:51:36 volumio volumio[5790]: info: ALBUMART /albumart?cacheid=371&web=%C2%B5%C3%8B%C3%80%C3%B6%C2%BE%C3%BD/%C2%B5%C3%8B%C3%80%C3%B6%C2%BE%C3%BD%C2%BE%C2%AB%C3%91%C2%A1/extralarge&path=%2Fmnt%2FINTERNAL%2Fmusic%2F%E5%B0%8F%E5%9F%8E%E6%95%85%E4%BA%8B.mp3&metadata=false Aug 28 22:51:36 volumio volumio[5790]: info: URI /mnt/INTERNAL/music/小城故事.mp3 Aug 28 22:51:38 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Aug 28 22:51:38 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 22:51:41 volumio volumio[5790]: info: Retrieving Cloud Streaming UI Aug 28 22:51:41 volumio volumio[5790]: info: Getting Tidal Cloud Configuration Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 22:51:41 volumio volumio[5790]: info: Getting Qobuz Cloud Configuration Aug 28 22:51:41 volumio volumio[5790]: info: Asking plugin for UI Config Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 22:51:41 volumio volumio[5790]: info: Getting Spotify Cloud Configuration Aug 28 22:51:41 volumio volumio[5790]: info: Asking plugin for UI Config Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 22:51:41 volumio volumio[5790]: info: Saving Spotify Acccount Aug 28 22:51:41 volumio volumio[5790]: info: Got Tidal Cloud Configuration Aug 28 22:51:41 volumio volumio[5790]: info: Got it Aug 28 22:51:41 volumio volumio[5790]: info: Got it Aug 28 22:51:41 volumio volumio[5790]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 22:51:41 volumio volumio[5790]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:51:41 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 28 22:51:41 volumio volumio[5790]: info: Preload queue cleared Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand update Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: update Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand update took 6 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: update Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: update Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 13 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 11 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 10 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 8 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 7 milliseconds Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: info: Command Router : Notfying DB Updatetrue Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: info: Command Router : Notfying DB Updatetrue Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::Close All Modals sent Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: info: Command Router : Notfying DB Updatetrue Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::Close All Modals sent Aug 28 22:51:44 volumio volumio[5790]: info: MPD Database updated - AlbumList cache refreshed Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: database Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: update Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 124 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand playlistinfo took 78 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117121,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117121,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 166ms Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 162ms Aug 28 22:51:44 volumio volumio[5790]: info: MPD Database updated - AlbumList cache refreshed Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: database Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: update Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: MPD Database updated - AlbumList cache refreshed Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: database Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: Aug 28 22:51:44 volumio volumio[5790]: ---------------------------- MPD announces state update: update Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::getState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand status Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 65 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 64 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 64 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand playlistinfo took 62 milliseconds Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: info: Command Router : Notfying DB Updatefalse Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::Close All Modals sent Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117121,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 263ms Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 127 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 127 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 126 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 124 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 124 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand status took 121 milliseconds Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: info: Command Router : Notfying DB Updatefalse Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::Close All Modals sent Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: info: Command Router : Notfying DB Updatefalse Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::Close All Modals sent Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseState Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand playlistinfo took 130 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand playlistinfo took 129 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand playlistinfo took 38 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand playlistinfo took 37 milliseconds Aug 28 22:51:44 volumio volumio[5790]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: verbose: ControllerMpd::parseTrackInfo Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117247,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117247,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117247,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117247,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117247,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ControllerMpd::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::servicePushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117247,"duration":261,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"2002ÄêµÄµÚÒ»³¡Ñ©","artist":"µ¶ÀÉ","album":"2002ÄêµÄµÚÒ»³¡Ñ©","uri":"INTERNAL/music/刀郎/01-2002年的第一场雪.mp3","trackType":"mp3"} Aug 28 22:51:44 volumio volumio[5790]: verbose: CURRENT POSITION 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState stateService play Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::syncState currentStatus play Aug 28 22:51:44 volumio volumio[5790]: info: Received an update from plugin. extracting info from payload Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: CoreStateMachine::pushState Aug 28 22:51:44 volumio volumio[5790]: info: CorePlayQueue::getTrack 2 Aug 28 22:51:44 volumio volumio[5790]: info: CoreCommandRouter::volumioPushState Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 335ms Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 335ms Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 301ms Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 302ms Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 300ms Aug 28 22:51:44 volumio volumio[5790]: info: ------------------------------ 299ms Aug 28 22:51:45 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:51:49 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:51:53 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:51:57 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:01 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:05 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:09 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:13 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:17 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:21 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:25 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:29 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:33 volumio volumio[5790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 22:52:36 volumio volumio[5790]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 22:52:36 volumio volumio[5790]: Error: connect ETIMEDOUT 202.160.128.203:80 Aug 28 22:52:36 volumio volumio[5790]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 28 22:52:36 volumio volumio[5790]: errno: -110, Aug 28 22:52:36 volumio volumio[5790]: code: 'ETIMEDOUT', Aug 28 22:52:36 volumio volumio[5790]: syscall: 'connect', Aug 28 22:52:36 volumio volumio[5790]: address: '202.160.128.203', Aug 28 22:52:36 volumio volumio[5790]: port: 80 Aug 28 22:52:36 volumio volumio[5790]: } Aug 28 22:52:36 volumio volumio[5790]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 22:52:36 volumio sudo[6315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 22:51 Aug 28 22:52:36 volumio sudo[6315]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"