-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-04-19 22:20:17 CEST. -- Apr 19 22:19:00 volumio volumio[968]: ------------------------------------ BT MESSAGE: BT STATUS: pending Apr 19 22:19:00 volumio volumio[968]: ------------------------------------ BT MESSAGE: BT STATUS: active Apr 19 22:19:00 volumio volumio[968]: BT PLUGIN MESSAGE: Playing: true Apr 19 22:19:00 volumio volumio[968]: BT PLUGIN MESSAGE: VOLATILE: bluetooth Apr 19 22:19:00 volumio volumio[968]: BT PLUGIN MESSAGE: STATE:undefined Apr 19 22:19:00 volumio volumio[968]: info: CoreCommandRouter::servicePushState Apr 19 22:19:00 volumio volumio[968]: info: CoreStateMachine::pushState Apr 19 22:19:00 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 22:19:00 volumio pulseaudio[18231]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Apr 19 22:19:00 volumio pulseaudio[18231]: Cannot set requested sink latency of 337.51 ms, adjusting to 600.00 ms Apr 19 22:19:00 volumio volumio[968]: info: CoreCommandRouter::volumioPushState Apr 19 22:19:00 volumio pulseaudio[18231]: Cannot set requested source latency of 105.16 ms, adjusting to 135.29 ms Apr 19 22:19:00 volumio volumio[968]: info: MRS: Pushing multiroomSync output update for this device Apr 19 22:19:00 volumio volumio[968]: info: MRS: Pushing multiroomSync output Apr 19 22:19:10 volumio dbus-daemon[656]: [system] Rejected send message, 1 matched rules; type="method_return", sender=":1.45" (uid=1000 pid=18231 comm="/usr/bin/pulseaudio --high-priority=true ") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.5" (uid=0 pid=812 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 19 22:19:38 volumio volumio[968]: verbose: New Socket.io Connection to 192.168.100.10 from 192.168.100.2 UA: Mozilla/5.0 (Linux; Android 13; M2103K19G Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.38 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 19 22:19:38 volumio volumio[968]: verbose: New Socket.io Connection to 192.168.100.10 from 192.168.100.2 UA: Mozilla/5.0 (Linux; Android 13; M2103K19G Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.38 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 19 22:19:38 volumio volumio[968]: verbose: New Socket.io Connection to 192.168.100.10 from 192.168.100.2 UA: Mozilla/5.0 (Linux; Android 13; M2103K19G Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.38 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 22:19:38 volumio volumio[968]: info: Discovery: Getting this device information Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 22:19:38 volumio volumio[968]: info: Discovery: Getting this device information Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 22:19:38 volumio volumio[968]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 19 22:19:38 volumio volumio[968]: info: Received Get System Info Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 22:19:38 volumio volumio[968]: info: Discovery: Getting this device information Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Apr 19 22:19:38 volumio volumio[968]: info: Listing playlists Apr 19 22:19:38 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 22:19:38 volumio volumio[968]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Apr 19 22:19:38 volumio volumio[968]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Apr 19 22:19:38 volumio volumio[968]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Apr 19 22:19:38 volumio volumio[968]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Apr 19 22:19:38 volumio volumio[968]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Apr 19 22:19:38 volumio volumio[968]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:449:13) Apr 19 22:19:38 volumio volumio[968]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 22:19:38 volumio volumio[968]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 19 22:19:39 volumio volumio[968]: verbose: New Socket.io Connection to 192.168.100.10:3000 from 192.168.100.2 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 22:19:41 volumio volumio[968]: info: Retrieving Cloud Streaming UI Apr 19 22:19:41 volumio volumio[968]: info: Getting Tidal Cloud Configuration Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 22:19:41 volumio volumio[968]: info: Getting Qobuz Cloud Configuration Apr 19 22:19:41 volumio volumio[968]: info: Asking plugin for UI Config Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 22:19:41 volumio volumio[968]: info: Getting Spotify Cloud Configuration Apr 19 22:19:41 volumio volumio[968]: info: Asking plugin for UI Config Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 22:19:41 volumio volumio[968]: info: Saving Spotify Acccount Apr 19 22:19:41 volumio volumio[968]: info: Got it Apr 19 22:19:41 volumio volumio[968]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 19 22:19:41 volumio volumio[968]: info: Got Tidal Cloud Configuration Apr 19 22:19:41 volumio volumio[968]: info: Got it Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:19:41 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 19 22:19:45 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 22:19:49 volumio volumio[968]: info: Discovery: Getting this device information Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 22:19:49 volumio volumio[968]: info: Discovery: Getting this device information Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::volumioGetState Apr 19 22:19:49 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 22:19:53 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:19:57 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:19:57 volumio volumio[968]: info: Enabling MyMusic plugin smart_inputs Apr 19 22:19:57 volumio volumio[968]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service smart_inputs Apr 19 22:19:57 volumio volumio[968]: info: Enabling plugin smart_inputs Apr 19 22:19:57 volumio volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 19 22:19:57 volumio volumio[968]: info: Adding inputs REST Endpoints Apr 19 22:19:57 volumio volumio[968]: info: Updating scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 19 22:19:57 volumio volumio[968]: info: Scanning Audio Inputs Apr 19 22:19:57 volumio volumio[968]: xcb_connection_has_error() returned true Apr 19 22:19:57 volumio volumio[968]: info: Checking against Known Cards name Apr 19 22:19:57 volumio volumio[968]: info: Checking against Known Cards name Apr 19 22:19:57 volumio volumio[968]: info: Adding Server instance for streaming Apr 19 22:19:57 volumio volumio[968]: info: Done. Apr 19 22:20:01 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:20:05 volumio volumio[968]: info: Disabling MyMusic plugin upnp_browser Apr 19 22:20:05 volumio volumio[968]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Apr 19 22:20:05 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 22:20:05 volumio volumio[968]: Cannot find translation for source TIDAL Apr 19 22:20:05 volumio volumio[968]: info: Disabling plugin upnp_browser Apr 19 22:20:05 volumio volumio[968]: info: Done. Apr 19 22:20:05 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:20:07 volumio volumio[968]: info: Disabling MyMusic plugin airplay_emulation Apr 19 22:20:07 volumio volumio[968]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Apr 19 22:20:07 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 22:20:07 volumio volumio[968]: Cannot find translation for source TIDAL Apr 19 22:20:07 volumio volumio[968]: info: Disabling plugin airplay_emulation Apr 19 22:20:07 volumio volumio[968]: info: Done. Apr 19 22:20:07 volumio sudo[19975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Apr 19 22:20:07 volumio sudo[19975]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 22:20:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 22:20:07 volumio volumio[968]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 19 22:20:07 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 19 22:20:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 22:20:07 volumio sudo[19975]: pam_unix(sudo:session): session closed for user root Apr 19 22:20:07 volumio volumio[968]: info: Shairport-Sync Stopped Apr 19 22:20:08 volumio volumio[968]: info: Disabling MyMusic plugin upnp Apr 19 22:20:08 volumio sudo[19978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 19 22:20:08 volumio sudo[19978]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 22:20:08 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 19 22:20:08 volumio volumio[968]: error: Upnp client error: Error: This socket has been ended by the other party Apr 19 22:20:08 volumio volumio[968]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 19 22:20:09 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:20:13 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 22:20:17 volumio volumio[968]: info: Enabling MyMusic plugin upnp Apr 19 22:20:17 volumio volumio[968]: info: Enabling plugin upnp Apr 19 22:20:17 volumio volumio[968]: info: Loading plugin "upnp"... Apr 19 22:20:17 volumio volumio[968]: info: [1745094017568] Starting Upmpd Daemon Apr 19 22:20:17 volumio volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 22:20:17 volumio volumio[968]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 22:20:17 volumio volumio[968]: Error: listen EADDRINUSE: address already in use :::6599 Apr 19 22:20:17 volumio volumio[968]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Apr 19 22:20:17 volumio volumio[968]: at listenInCluster (net.js:1366:12) Apr 19 22:20:17 volumio volumio[968]: at Server.listen (net.js:1452:7) Apr 19 22:20:17 volumio volumio[968]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 19 22:20:17 volumio volumio[968]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 19 22:20:17 volumio volumio[968]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 19 22:20:17 volumio volumio[968]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 22:20:17 volumio volumio[968]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Apr 19 22:20:17 volumio volumio[968]: code: 'EADDRINUSE', Apr 19 22:20:17 volumio volumio[968]: errno: -98, Apr 19 22:20:17 volumio volumio[968]: syscall: 'listen', Apr 19 22:20:17 volumio volumio[968]: address: '::', Apr 19 22:20:17 volumio volumio[968]: port: 6599 Apr 19 22:20:17 volumio volumio[968]: } Apr 19 22:20:17 volumio volumio[968]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 22:20:17 volumio sudo[20003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 22:19 Apr 19 22:20:17 volumio sudo[20003]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"