-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-11-27 02:43:04 UTC. -- Nov 27 02:42:04 rbvolumio volumio[812]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 02:42:04 rbvolumio volumio[812]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 02:42:04 rbvolumio volumio[812]: info: Retrieving Cloud Streaming UI Nov 27 02:42:04 rbvolumio volumio[812]: info: Getting Tidal Cloud Configuration Nov 27 02:42:04 rbvolumio volumio[812]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 02:42:04 rbvolumio volumio[812]: info: Getting Qobuz Cloud Configuration Nov 27 02:42:04 rbvolumio volumio[812]: info: Asking plugin for UI Config Nov 27 02:42:04 rbvolumio volumio[812]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 02:42:04 rbvolumio volumio[812]: info: Getting Spotify Cloud Configuration Nov 27 02:42:04 rbvolumio volumio[812]: info: Asking plugin for UI Config Nov 27 02:42:04 rbvolumio volumio[812]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 02:42:04 rbvolumio volumio[812]: info: Saving Spotify Acccount Nov 27 02:42:04 rbvolumio volumio[812]: info: Got it Nov 27 02:42:04 rbvolumio volumio[812]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 27 02:42:04 rbvolumio volumio[812]: info: Got Tidal Cloud Configuration Nov 27 02:42:04 rbvolumio volumio[812]: info: Got it Nov 27 02:42:04 rbvolumio volumio[812]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::volumioGetBrowseSources Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:05 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:05 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:05 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 27 02:42:07 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 27 02:42:07 rbvolumio volumio[812]: verbose: ControllerMpd::sendMpdCommand update Nov 27 02:42:09 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:09 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:09 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:13 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:13 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:13 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:17 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:17 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:17 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:21 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:21 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:21 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:25 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 02:42:25 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 02:42:25 rbvolumio volumio[812]: info: Discovery: Getting this device information Nov 27 02:42:25 rbvolumio volumio[812]: info: CoreCommandRouter::volumioGetState Nov 27 02:42:25 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 12 Nov 27 02:42:25 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 02:42:25 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:25 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:25 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:29 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:29 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:29 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:33 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:33 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:33 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:34 rbvolumio volumio[812]: info: CoreCommandRouter::volumioNext Nov 27 02:42:34 rbvolumio volumio[812]: info: CoreStateMachine::next Nov 27 02:42:34 rbvolumio volumio[812]: info: CoreStateMachine::stop Nov 27 02:42:34 rbvolumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 02:42:34 rbvolumio volumio[812]: info: CoreStateMachine::play index undefined Nov 27 02:42:34 rbvolumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 02:42:34 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 13 Nov 27 02:42:34 rbvolumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Nov 27 02:42:34 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 13 Nov 27 02:42:34 rbvolumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio_Fl/Isaiah J. Thompson/The Power of the Spirit/04 - For Phineas (Intro).mp3 Nov 27 02:42:34 rbvolumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Nov 27 02:42:34 rbvolumio volumio[812]: info: CoreStateMachine::updateTrackBlock Nov 27 02:42:34 rbvolumio volumio[812]: info: CorePlayQueue::getTrackBlock Nov 27 02:42:35 rbvolumio volumio[812]: info: CoreCommandRouter::volumioPlay Nov 27 02:42:35 rbvolumio volumio[812]: info: CoreStateMachine::play index undefined Nov 27 02:42:35 rbvolumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 02:42:35 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 13 Nov 27 02:42:35 rbvolumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Nov 27 02:42:35 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 13 Nov 27 02:42:35 rbvolumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio_Fl/Isaiah J. Thompson/The Power of the Spirit/04 - For Phineas (Intro).mp3 Nov 27 02:42:35 rbvolumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Nov 27 02:42:36 rbvolumio volumio[812]: info: CoreCommandRouter::volumioPlay Nov 27 02:42:36 rbvolumio volumio[812]: info: CoreStateMachine::play index undefined Nov 27 02:42:36 rbvolumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 02:42:36 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 13 Nov 27 02:42:36 rbvolumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Nov 27 02:42:36 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 13 Nov 27 02:42:36 rbvolumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio_Fl/Isaiah J. Thompson/The Power of the Spirit/04 - For Phineas (Intro).mp3 Nov 27 02:42:36 rbvolumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:37 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:37 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreCommandRouter::volumioNext Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreStateMachine::next Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreStateMachine::stop Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreStateMachine::play index undefined Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 02:42:37 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 14 Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreStateMachine::startPlaybackTimer Nov 27 02:42:37 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 14 Nov 27 02:42:37 rbvolumio volumio[812]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio_Fl/Isaiah J. Thompson/The Power of the Spirit/05 - For Phineas.mp3 Nov 27 02:42:37 rbvolumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Nov 27 02:42:37 rbvolumio volumio[812]: info: CoreStateMachine::updateTrackBlock Nov 27 02:42:37 rbvolumio volumio[812]: info: CorePlayQueue::getTrackBlock Nov 27 02:42:41 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:41 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:41 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:45 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:45 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:45 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:49 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:49 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:49 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:51 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Nov 27 02:42:51 rbvolumio volumio[812]: verbose: ControllerMpd::sendMpdCommand rescan Nov 27 02:42:53 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:53 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:53 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:55 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 27 02:42:55 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 27 02:42:55 rbvolumio volumio[812]: info: Discovery: Getting this device information Nov 27 02:42:55 rbvolumio volumio[812]: info: CoreCommandRouter::volumioGetState Nov 27 02:42:55 rbvolumio volumio[812]: info: CorePlayQueue::getTrack 14 Nov 27 02:42:55 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 27 02:42:57 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:42:57 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:42:57 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:42:58 rbvolumio volumio[812]: info: Disabling MyMusic plugin upnp Nov 27 02:42:58 rbvolumio sudo[2180]: volumio : unable to resolve host rbvolumio Nov 27 02:42:58 rbvolumio sudo[2180]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 27 02:42:58 rbvolumio sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 27 02:42:58 rbvolumio sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 02:42:58 rbvolumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 27 02:42:58 rbvolumio volumio[812]: error: Upnp client error: Error: This socket has been ended by the other party Nov 27 02:42:58 rbvolumio volumio[812]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 02:43:01 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 02:43:01 rbvolumio volumio[812]: error: MPD error: The expression evaluated to a falsy value: Nov 27 02:43:01 rbvolumio volumio[812]: assert.ok(self.idling) Nov 27 02:43:04 rbvolumio volumio[812]: info: Enabling MyMusic plugin upnp Nov 27 02:43:04 rbvolumio volumio[812]: info: Enabling plugin upnp Nov 27 02:43:04 rbvolumio volumio[812]: info: Loading plugin "upnp"... Nov 27 02:43:04 rbvolumio volumio[812]: info: [1732675384066] Starting Upmpd Daemon Nov 27 02:43:04 rbvolumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 27 02:43:04 rbvolumio volumio[812]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 02:43:04 rbvolumio volumio[812]: Error: listen EADDRINUSE: address already in use :::6599 Nov 27 02:43:04 rbvolumio volumio[812]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 27 02:43:04 rbvolumio volumio[812]: at listenInCluster (net.js:1366:12) Nov 27 02:43:04 rbvolumio volumio[812]: at Server.listen (net.js:1452:7) Nov 27 02:43:04 rbvolumio volumio[812]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 27 02:43:04 rbvolumio volumio[812]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 27 02:43:04 rbvolumio volumio[812]: at Promise._successFn (/volumio/app/pluginmanager.js:1841:19) Nov 27 02:43:04 rbvolumio volumio[812]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 27 02:43:04 rbvolumio volumio[812]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 27 02:43:04 rbvolumio volumio[812]: code: 'EADDRINUSE', Nov 27 02:43:04 rbvolumio volumio[812]: errno: -98, Nov 27 02:43:04 rbvolumio volumio[812]: syscall: 'listen', Nov 27 02:43:04 rbvolumio volumio[812]: address: '::', Nov 27 02:43:04 rbvolumio volumio[812]: port: 6599 Nov 27 02:43:04 rbvolumio volumio[812]: } Nov 27 02:43:04 rbvolumio volumio[812]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 02:43:04 rbvolumio sudo[2191]: volumio : unable to resolve host rbvolumio Nov 27 02:43:04 rbvolumio sudo[2191]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 27 02:43:04 rbvolumio sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 02:42 Nov 27 02:43:04 rbvolumio sudo[2191]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"