-- Logs begin at Mon 2025-02-10 15:51:25 CET, end at Mon 2025-02-24 14:25:10 CET. -- Feb 24 14:24:13 volumio volumio[679]: info: Clearing queue after UPNP request Feb 24 14:24:13 volumio volumio[679]: info: CoreStateMachine::ClearQueue Feb 24 14:24:13 volumio volumio[679]: info: CoreStateMachine::stop Feb 24 14:24:13 volumio volumio[679]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:24:13 volumio volumio[679]: info: CorePlayQueue::clearPlayQueue Feb 24 14:24:13 volumio volumio[679]: info: CorePlayQueue::saveQueue Feb 24 14:24:13 volumio volumio[679]: info: CoreCommandRouter::volumioPushState Feb 24 14:24:13 volumio volumio[679]: info: CoreCommandRouter::volumioPushQueue Feb 24 14:24:13 volumio volumio[679]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Feb 24 14:24:13 volumio volumio[679]: info: Starting UPNP Playback Feb 24 14:24:13 volumio volumio[679]: info: Preparing playback through UPNP Feb 24 14:24:13 volumio volumio[679]: info: CoreCommandRouter::volumioGetState Feb 24 14:24:13 volumio volumio[679]: info: CorePlayQueue::getTrack 0 Feb 24 14:24:13 volumio volumio[679]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 14:24:43 volumio volumio[1009]: 1 Feb 24 14:24:43 volumio volumio[1009]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 14:24:43 volumio volumio[1009]: Dload Upload Total Spent Left Speed Feb 24 14:24:43 volumio volumio[679]: info: Feb 24 14:24:43 volumio volumio[679]: ---------------------------- Client requests Volume 1 Feb 24 14:24:43 volumio volumio[679]: info: VolumeController::SetAlsaVolume1 Feb 24 14:24:43 volumio volumio[679]: info: CoreStateMachine::pushState Feb 24 14:24:43 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:24:43 volumio volumio[679]: info: CoreCommandRouter::volumioPushState Feb 24 14:24:43 volumio volumio[1009]: [158B blob data] Feb 24 14:24:43 volumio volumio[1009]: {"time":1740403483914,"response":"volume Success"}1 Feb 24 14:24:43 volumio volumio[679]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Feb 24 14:24:43 volumio volumio[679]: SPOTIFY: SPOTIFY VOLUME 96 Feb 24 14:24:43 volumio volumio[679]: SPOTIFY: VOLUMIO VOLUME 1 Feb 24 14:24:43 volumio volumio[679]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 24 14:24:43 volumio volumio[679]: info: Setting Spotify Volume from Volumio: 1 Feb 24 14:24:44 volumio volumio[1009]: 11 Feb 24 14:24:44 volumio volumio[1009]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 24 14:24:44 volumio volumio[1009]: Dload Upload Total Spent Left Speed Feb 24 14:24:44 volumio volumio[679]: info: Feb 24 14:24:44 volumio volumio[679]: ---------------------------- Client requests Volume 11 Feb 24 14:24:44 volumio volumio[679]: info: VolumeController::SetAlsaVolume11 Feb 24 14:24:44 volumio volumio[679]: info: CoreStateMachine::pushState Feb 24 14:24:44 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 14:24:44 volumio volumio[679]: info: CoreCommandRouter::volumioPushState Feb 24 14:24:44 volumio volumio[1009]: [158B blob data] Feb 24 14:24:44 volumio volumio[1009]: {"time":1740403484110,"response":"volume Success"}11 Feb 24 14:24:44 volumio volumio[679]: SPOTIFY: RECEIVED VOLUMIO VOLUME 11 Feb 24 14:24:44 volumio volumio[679]: SPOTIFY: SPOTIFY VOLUME 1 Feb 24 14:24:44 volumio volumio[679]: SPOTIFY: VOLUMIO VOLUME 11 Feb 24 14:24:44 volumio volumio[679]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 24 14:24:44 volumio volumio[679]: info: Setting Spotify Volume from Volumio: 11 Feb 24 14:24:44 volumio volumio[679]: info: Clearing queue after UPNP request Feb 24 14:24:44 volumio volumio[679]: info: CoreStateMachine::ClearQueue Feb 24 14:24:44 volumio volumio[679]: info: CoreStateMachine::stop Feb 24 14:24:44 volumio volumio[679]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 14:24:44 volumio volumio[679]: info: CorePlayQueue::clearPlayQueue Feb 24 14:24:44 volumio volumio[679]: info: CorePlayQueue::saveQueue Feb 24 14:24:44 volumio volumio[679]: info: CoreCommandRouter::volumioPushState Feb 24 14:24:44 volumio volumio[679]: info: CoreCommandRouter::volumioPushQueue Feb 24 14:24:44 volumio volumio[679]: SPOTIFY: RECEIVED VOLUMIO VOLUME 11 Feb 24 14:24:44 volumio volumio[679]: info: Starting UPNP Playback Feb 24 14:24:44 volumio volumio[679]: info: Preparing playback through UPNP Feb 24 14:24:44 volumio volumio[679]: info: CoreCommandRouter::volumioGetState Feb 24 14:24:44 volumio volumio[679]: info: CorePlayQueue::getTrack 0 Feb 24 14:24:44 volumio volumio[679]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 14:24:45 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 24 14:24:45 volumio volumio[679]: SPOTIFY: SETTING SPOTIFY VOLUME 11 Feb 24 14:24:45 volumio volumio[679]: info: Sending Spotify command with payload to local API: /player/volume Feb 24 14:24:45 volumio go-librespot[18445]: time="2025-02-24T14:24:45+01:00" level=debug msg="update volume to 7208/65535" Feb 24 14:24:45 volumio go-librespot[18445]: time="2025-02-24T14:24:45+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 24 14:24:45 volumio go-librespot[18445]: time="2025-02-24T14:24:45+01:00" level=trace msg="emitting websocket event: volume" Feb 24 14:24:45 volumio volumio[679]: SPOTIFY: received: {"type":"volume","data":{"value":11,"max":100}} Feb 24 14:24:45 volumio volumio[679]: SPOTIFY: RECEIVED SPOTIFY VOLUME 11 Feb 24 14:24:45 volumio volumio[679]: SPOTIFY: received: {"type":"volume","data":{"value":11,"max":100}} Feb 24 14:24:45 volumio volumio[679]: SPOTIFY: RECEIVED SPOTIFY VOLUME 11 Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 14:24:57 volumio volumio[679]: info: Retrieving Cloud Streaming UI Feb 24 14:24:57 volumio volumio[679]: info: Getting Tidal Cloud Configuration Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 14:24:57 volumio volumio[679]: info: Getting Qobuz Cloud Configuration Feb 24 14:24:57 volumio volumio[679]: info: Asking plugin for UI Config Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 14:24:57 volumio volumio[679]: info: Getting Spotify Cloud Configuration Feb 24 14:24:57 volumio volumio[679]: info: Asking plugin for UI Config Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 14:24:57 volumio volumio[679]: info: Saving Spotify Acccount Feb 24 14:24:57 volumio volumio[679]: info: Got Tidal Cloud Configuration Feb 24 14:24:57 volumio volumio[679]: info: Got it Feb 24 14:24:57 volumio volumio[679]: info: Got it Feb 24 14:24:57 volumio volumio[679]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 14:24:57 volumio volumio[679]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 14:24:57 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 14:24:57 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:24:57 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:24:57 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:24:57 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:24:57 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:24:57 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:24:58 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 24 14:25:02 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 14:25:02 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:25:02 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:25:02 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:25:02 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:25:02 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:25:02 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:25:05 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 14:25:05 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:25:05 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:25:05 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:25:05 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:25:05 volumio volumio[679]: error: MPD error: Error: This socket has been ended by the other party Feb 24 14:25:05 volumio volumio[679]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 24 14:25:06 volumio volumio[679]: info: Disabling MyMusic plugin upnp Feb 24 14:25:06 volumio sudo[15044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 24 14:25:06 volumio sudo[15044]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 14:25:06 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 24 14:25:06 volumio volumio[679]: error: Upnp client error: Error: This socket has been ended by the other party Feb 24 14:25:09 volumio volumio[679]: info: Enabling MyMusic plugin upnp Feb 24 14:25:09 volumio volumio[679]: info: Enabling plugin upnp Feb 24 14:25:09 volumio volumio[679]: info: Loading plugin "upnp"... Feb 24 14:25:09 volumio volumio[679]: info: [1740403509096] Starting Upmpd Daemon Feb 24 14:25:09 volumio volumio[679]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 14:25:09 volumio volumio[679]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 14:25:09 volumio volumio[679]: Error: listen EADDRINUSE: address already in use :::6599 Feb 24 14:25:09 volumio volumio[679]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Feb 24 14:25:09 volumio volumio[679]: at listenInCluster (net.js:1366:12) Feb 24 14:25:09 volumio volumio[679]: at Server.listen (net.js:1452:7) Feb 24 14:25:09 volumio volumio[679]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 24 14:25:09 volumio volumio[679]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 24 14:25:09 volumio volumio[679]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 24 14:25:09 volumio volumio[679]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 24 14:25:09 volumio volumio[679]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Feb 24 14:25:09 volumio volumio[679]: code: 'EADDRINUSE', Feb 24 14:25:09 volumio volumio[679]: errno: -98, Feb 24 14:25:09 volumio volumio[679]: syscall: 'listen', Feb 24 14:25:09 volumio volumio[679]: address: '::', Feb 24 14:25:09 volumio volumio[679]: port: 6599 Feb 24 14:25:09 volumio volumio[679]: } Feb 24 14:25:09 volumio volumio[679]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 14:25:10 volumio sudo[15060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 14:24 Feb 24 14:25:10 volumio sudo[15060]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"