-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sun 2025-01-19 13:15:05 CST. -- Jan 19 13:14:09 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 13:14:09 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 19 13:14:09 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 19 13:14:09 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 19 13:14:09 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jan 19 13:14:09 volumio volumio[910]: info: Received Get System Version Jan 19 13:14:09 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 13:14:09 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Jan 19 13:14:19 volumio volumio-remote-updater[514]: No test mode Jan 19 13:14:19 volumio volumio-remote-updater[514]: No alpha test mode Jan 19 13:14:27 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent Jan 19 13:14:34 volumio volumio[910]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Jan 19 13:14:34 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Jan 19 13:14:34 volumio volumio[910]: info: Setting active UI to: {"uiPrettyName":"Manifest","uiName":"manifest","uiPath":"/volumio/http/www4"} Jan 19 13:14:35 volumio sudo[32595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/active_volumio_ui Jan 19 13:14:35 volumio sudo[32595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 13:14:35 volumio sudo[32595]: pam_unix(sudo:session): session closed for user root Jan 19 13:14:35 volumio volumio[910]: info: CoreCommandRouter::Reload Ui Jan 19 13:14:44 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 19 13:14:44 volumio volumio[910]: info: CURURI: music-library/USB/B03B-4531/校园民谣 Jan 19 13:14:44 volumio volumio[910]: info: Preload queue cleared Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 13:14:48 volumio volumio[910]: info: Retrieving Cloud Streaming UI Jan 19 13:14:48 volumio volumio[910]: info: Getting Tidal Cloud Configuration Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 13:14:48 volumio volumio[910]: info: Getting Qobuz Cloud Configuration Jan 19 13:14:48 volumio volumio[910]: info: Asking plugin for UI Config Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 13:14:48 volumio volumio[910]: info: Getting Spotify Cloud Configuration Jan 19 13:14:48 volumio volumio[910]: info: Asking plugin for UI Config Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 13:14:48 volumio volumio[910]: info: Saving Spotify Acccount Jan 19 13:14:48 volumio volumio[910]: info: Got Tidal Cloud Configuration Jan 19 13:14:48 volumio volumio[910]: info: Got it Jan 19 13:14:48 volumio volumio[910]: info: Got it Jan 19 13:14:48 volumio volumio[910]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 13:14:48 volumio volumio[910]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 13:14:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 19 13:14:51 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand rescan Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jan 19 13:14:51 volumio volumio[910]: info: Jan 19 13:14:51 volumio volumio[910]: ---------------------------- MPD announces state update: update Jan 19 13:14:51 volumio volumio[910]: info: sendMpdCommand rescan took 8 milliseconds Jan 19 13:14:51 volumio volumio[910]: info: ControllerMpd::getState Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jan 19 13:14:51 volumio volumio[910]: info: sendMpdCommand status took 9 milliseconds Jan 19 13:14:51 volumio volumio[910]: info: sendMpdCommand status took 4 milliseconds Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::parseState Jan 19 13:14:51 volumio volumio[910]: info: Command Router : Notfying DB Updatetrue Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::parseState Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 13:14:51 volumio volumio[910]: info: Jan 19 13:14:51 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jan 19 13:14:51 volumio volumio[910]: info: Ignoring MPD Status Update Jan 19 13:14:51 volumio volumio[910]: info: MPD Database updated - AlbumList cache refreshed Jan 19 13:14:51 volumio volumio[910]: info: Jan 19 13:14:51 volumio volumio[910]: ---------------------------- MPD announces state update: database Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jan 19 13:14:51 volumio volumio[910]: info: Jan 19 13:14:51 volumio volumio[910]: ---------------------------- MPD announces state update: update Jan 19 13:14:51 volumio volumio[910]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 19 13:14:51 volumio volumio[910]: info: ControllerMpd::getState Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jan 19 13:14:51 volumio volumio[910]: info: ControllerMpd::getState Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jan 19 13:14:51 volumio volumio[910]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 19 13:14:51 volumio volumio[910]: info: ------------------------------ 110ms Jan 19 13:14:51 volumio volumio[910]: error: updateQueue error: null Jan 19 13:14:51 volumio volumio[910]: info: sendMpdCommand status took 33 milliseconds Jan 19 13:14:51 volumio volumio[910]: info: ------------------------------ 40ms Jan 19 13:14:51 volumio volumio[910]: info: sendMpdCommand status took 31 milliseconds Jan 19 13:14:51 volumio volumio[910]: info: sendMpdCommand status took 35 milliseconds Jan 19 13:14:51 volumio volumio[910]: verbose: ControllerMpd::parseState Jan 19 13:14:52 volumio volumio[910]: info: Command Router : Notfying DB Updatefalse Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent Jan 19 13:14:52 volumio volumio[910]: verbose: ControllerMpd::parseState Jan 19 13:14:52 volumio volumio[910]: verbose: ControllerMpd::parseState Jan 19 13:14:52 volumio volumio[910]: info: ControllerMpd::pushState Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::pushState Jan 19 13:14:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jan 19 13:14:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jan 19 13:14:52 volumio volumio[910]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 19 13:14:52 volumio volumio[910]: verbose: CURRENT POSITION 0 Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::pushState Jan 19 13:14:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jan 19 13:14:52 volumio volumio[910]: info: No code Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::pushState Jan 19 13:14:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jan 19 13:14:52 volumio volumio[910]: info: ControllerMpd::pushState Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::pushState Jan 19 13:14:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jan 19 13:14:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jan 19 13:14:52 volumio volumio[910]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 19 13:14:52 volumio volumio[910]: verbose: CURRENT POSITION 0 Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::pushState Jan 19 13:14:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jan 19 13:14:52 volumio volumio[910]: info: No code Jan 19 13:14:52 volumio volumio[910]: info: CoreStateMachine::pushState Jan 19 13:14:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jan 19 13:14:52 volumio volumio[910]: info: ------------------------------ 174ms Jan 19 13:14:52 volumio volumio[910]: info: ------------------------------ 175ms Jan 19 13:14:52 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 13:14:56 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 13:15:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 19 13:15:04 volumio volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 13:15:04 volumio volumio[910]: Error: connect ETIMEDOUT 128.121.243.76:443 Jan 19 13:15:04 volumio volumio[910]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 19 13:15:04 volumio volumio[910]: errno: -110, Jan 19 13:15:04 volumio volumio[910]: code: 'ETIMEDOUT', Jan 19 13:15:04 volumio volumio[910]: syscall: 'connect', Jan 19 13:15:04 volumio volumio[910]: address: '128.121.243.76', Jan 19 13:15:04 volumio volumio[910]: port: 443 Jan 19 13:15:04 volumio volumio[910]: } Jan 19 13:15:04 volumio volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 13:15:05 volumio sudo[32702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 13:14 Jan 19 13:15:05 volumio sudo[32702]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"