-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Mon 2025-09-15 14:23:21 UTC. -- Sep 15 14:22:02 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Sep 15 14:22:02 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:04 volumio volumio[1121]: info: Executing endpoint metavolumio Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 15 14:22:04 volumio volumio[1121]: info: Executing endpoint metavolumio Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 15 14:22:04 volumio volumio[1121]: info: Executing endpoint metavolumio Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::volumioPause Sep 15 14:22:04 volumio volumio[1121]: info: CoreStateMachine::pause Sep 15 14:22:04 volumio volumio[1121]: info: CoreStateMachine::stPlaybackTimer Sep 15 14:22:04 volumio volumio[1121]: info: CoreStateMachine::servicePause Sep 15 14:22:04 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::servicePause Sep 15 14:22:04 volumio volumio[1121]: info: ControllerMpd::pause Sep 15 14:22:04 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand pause Sep 15 14:22:04 volumio volumio[1121]: info: Sep 15 14:22:04 volumio volumio[1121]: ---------------------------- MPD announces state update: player Sep 15 14:22:04 volumio volumio[1121]: info: sendMpdCommand pause took 1 milliseconds Sep 15 14:22:04 volumio volumio[1121]: info: ControllerMpd::getState Sep 15 14:22:04 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Sep 15 14:22:04 volumio volumio[1121]: info: sendMpdCommand status took 1 milliseconds Sep 15 14:22:04 volumio volumio[1121]: verbose: ControllerMpd::parseState Sep 15 14:22:04 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 14:22:04 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 15 14:22:04 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Sep 15 14:22:04 volumio volumio[1121]: info: ControllerMpd::pushState Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Sep 15 14:22:04 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:04 volumio volumio[1121]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":67782,"duration":159,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Hollywood","artist":"Angus & Julia Stone","album":"Hollywood - EP","uri":"USB/Primo-Music/Angus & Julia Stone/Hollywood - EP/01 Hollywood.m4a","trackType":"m4a"} Sep 15 14:22:04 volumio volumio[1121]: verbose: CURRENT POSITION 0 Sep 15 14:22:04 volumio volumio[1121]: info: CoreStateMachine::syncState stateService pause Sep 15 14:22:04 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus pause Sep 15 14:22:04 volumio volumio[1121]: info: CoreStateMachine::pushState Sep 15 14:22:04 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Sep 15 14:22:04 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Sep 15 14:22:04 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:04 volumio volumio[1121]: info: CoreStateMachine::stPlaybackTimer Sep 15 14:22:04 volumio volumio[1121]: info: ------------------------------ 24ms Sep 15 14:22:04 volumio volumio[1121]: error: Failed request for metavolumio API Sep 15 14:22:05 volumio volumio[1121]: info: CoreCommandRouter::volumioPlay Sep 15 14:22:05 volumio volumio[1121]: info: CoreStateMachine::play index undefined Sep 15 14:22:05 volumio volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 14:22:05 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:05 volumio volumio[1121]: info: CoreStateMachine::startPlaybackTimer Sep 15 14:22:05 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:05 volumio volumio[1121]: info: ControllerMpd::resume Sep 15 14:22:05 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand play Sep 15 14:22:05 volumio volumio[1121]: info: sendMpdCommand play took 4 milliseconds Sep 15 14:22:05 volumio volumio[1121]: info: Sep 15 14:22:05 volumio volumio[1121]: ---------------------------- MPD announces state update: player Sep 15 14:22:05 volumio volumio[1121]: info: ControllerMpd::getState Sep 15 14:22:05 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Sep 15 14:22:05 volumio volumio[1121]: info: sendMpdCommand status took 0 milliseconds Sep 15 14:22:05 volumio volumio[1121]: verbose: ControllerMpd::parseState Sep 15 14:22:05 volumio volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 14:22:05 volumio volumio[1121]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 15 14:22:05 volumio volumio[1121]: verbose: ControllerMpd::parseTrackInfo Sep 15 14:22:05 volumio volumio[1121]: info: ControllerMpd::pushState Sep 15 14:22:05 volumio volumio[1121]: info: CoreCommandRouter::servicePushState Sep 15 14:22:05 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:05 volumio volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68674,"duration":159,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Hollywood","artist":"Angus & Julia Stone","album":"Hollywood - EP","uri":"USB/Primo-Music/Angus & Julia Stone/Hollywood - EP/01 Hollywood.m4a","trackType":"m4a"} Sep 15 14:22:05 volumio volumio[1121]: verbose: CURRENT POSITION 0 Sep 15 14:22:05 volumio volumio[1121]: info: CoreStateMachine::syncState stateService play Sep 15 14:22:05 volumio volumio[1121]: info: CoreStateMachine::syncState currentStatus pause Sep 15 14:22:05 volumio volumio[1121]: info: CoreStateMachine::pushState Sep 15 14:22:05 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 14:22:05 volumio volumio[1121]: info: CoreCommandRouter::volumioPushState Sep 15 14:22:05 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Sep 15 14:22:05 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:22:05 volumio volumio[1121]: info: ------------------------------ 18ms Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:22:15 volumio volumio[1121]: info: Retrieving Cloud Streaming UI Sep 15 14:22:15 volumio volumio[1121]: info: Getting Tidal Cloud Configuration Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:22:15 volumio volumio[1121]: info: Getting Qobuz Cloud Configuration Sep 15 14:22:15 volumio volumio[1121]: info: Asking plugin for UI Config Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:22:15 volumio volumio[1121]: info: Getting Spotify Cloud Configuration Sep 15 14:22:15 volumio volumio[1121]: info: Asking plugin for UI Config Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:22:15 volumio volumio[1121]: info: Saving Spotify Acccount Sep 15 14:22:15 volumio volumio[1121]: info: Got it Sep 15 14:22:15 volumio volumio[1121]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 15 14:22:15 volumio volumio[1121]: info: Got Tidal Cloud Configuration Sep 15 14:22:15 volumio volumio[1121]: info: Got it Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:15 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 15 14:22:19 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:23 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:27 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:31 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:35 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:39 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:43 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:47 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:51 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:55 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:22:59 volumio volumio[1121]: info: CoreCommandRouter::volumioGetState Sep 15 14:22:59 volumio volumio[1121]: info: CorePlayQueue::getTrack 0 Sep 15 14:23:01 volumio volumio[1121]: info: Executing endpoint metavolumio Sep 15 14:23:01 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 15 14:23:01 volumio volumio[1121]: info: Executing endpoint metavolumio Sep 15 14:23:01 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 15 14:23:01 volumio volumio[1121]: info: Executing endpoint metavolumio Sep 15 14:23:01 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 15 14:23:03 volumio volumio[1121]: error: Failed request for metavolumio API Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:23:05 volumio volumio[1121]: info: Retrieving Cloud Streaming UI Sep 15 14:23:05 volumio volumio[1121]: info: Getting Tidal Cloud Configuration Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:23:05 volumio volumio[1121]: info: Getting Qobuz Cloud Configuration Sep 15 14:23:05 volumio volumio[1121]: info: Asking plugin for UI Config Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:23:05 volumio volumio[1121]: info: Getting Spotify Cloud Configuration Sep 15 14:23:05 volumio volumio[1121]: info: Asking plugin for UI Config Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:23:05 volumio volumio[1121]: info: Saving Spotify Acccount Sep 15 14:23:05 volumio volumio[1121]: info: Got it Sep 15 14:23:05 volumio volumio[1121]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 15 14:23:05 volumio volumio[1121]: info: Got Tidal Cloud Configuration Sep 15 14:23:05 volumio volumio[1121]: info: Got it Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:23:05 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 15 14:23:09 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:23:13 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:23:17 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 14:23:20 volumio volumio[1121]: info: Disabling MyMusic plugin upnp Sep 15 14:23:20 volumio sudo[7491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 15 14:23:20 volumio sudo[7491]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 14:23:20 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 15 14:23:20 volumio volumio[1121]: error: Upnp client error: Error: This socket has been ended by the other party Sep 15 14:23:20 volumio volumio[1121]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 15 14:23:21 volumio volumio[1121]: info: Enabling MyMusic plugin upnp Sep 15 14:23:21 volumio volumio[1121]: info: Enabling plugin upnp Sep 15 14:23:21 volumio volumio[1121]: info: Loading plugin "upnp"... Sep 15 14:23:21 volumio volumio[1121]: info: [1757946201567] Starting Upmpd Daemon Sep 15 14:23:21 volumio volumio[1121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 14:23:21 volumio volumio[1121]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 14:23:21 volumio volumio[1121]: Error: listen EADDRINUSE: address already in use :::6599 Sep 15 14:23:21 volumio volumio[1121]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Sep 15 14:23:21 volumio volumio[1121]: at listenInCluster (net.js:1379:12) Sep 15 14:23:21 volumio volumio[1121]: at Server.listen (net.js:1465:7) Sep 15 14:23:21 volumio volumio[1121]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 15 14:23:21 volumio volumio[1121]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 15 14:23:21 volumio volumio[1121]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 15 14:23:21 volumio volumio[1121]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 15 14:23:21 volumio volumio[1121]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Sep 15 14:23:21 volumio volumio[1121]: code: 'EADDRINUSE', Sep 15 14:23:21 volumio volumio[1121]: errno: -98, Sep 15 14:23:21 volumio volumio[1121]: syscall: 'listen', Sep 15 14:23:21 volumio volumio[1121]: address: '::', Sep 15 14:23:21 volumio volumio[1121]: port: 6599 Sep 15 14:23:21 volumio volumio[1121]: } Sep 15 14:23:21 volumio volumio[1121]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 14:23:21 volumio sudo[7507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 14:22 Sep 15 14:23:21 volumio sudo[7507]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:55:15 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="2f3686ade78abf3b92c180a0c5b20c6b"