Dec 13 09:33:19 volumio-wz go-librespot[5221]: time="2025-12-13T09:33:19+01:00" level=trace msg="sent dealer ping"
Dec 13 09:33:19 volumio-wz go-librespot[5221]: time="2025-12-13T09:33:19+01:00" level=trace msg="received dealer pong"
Dec 13 09:33:20 volumio-wz volumio[1243]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/gpio-buttons/volumio/bookworm/armhf
Dec 13 09:33:22 volumio-wz volumio[1243]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/gpio-buttons/volumio/bookworm/armhf
Dec 13 09:33:22 volumio-wz volumio[1243]: info: Folder /tmp/plugins removed
Dec 13 09:33:22 volumio-wz volumio[1243]: info: Check plugin dependencies
Dec 13 09:33:22 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 13 09:33:22 volumio-wz volumio[1243]: info: Checking if plugin already exists
Dec 13 09:33:22 volumio-wz volumio[1243]: info: Rename folder
Dec 13 09:33:22 volumio-wz volumio[1243]: info: Folder /tmp/downloaded_plugin.zip removed
Dec 13 09:33:22 volumio-wz volumio[1243]: info: Move to category
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Checking if install.sh is present
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Executing install.sh
Dec 13 09:33:23 volumio-wz sudo[7443]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/gpio-buttons/install.sh
Dec 13 09:33:23 volumio-wz sudo[7443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 13 09:33:23 volumio-wz sudo[7443]: pam_unix(sudo:session): session closed for user root
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Initializing config
Dec 13 09:33:23 volumio-wz volumio[1243]: Plugin install end detected on script
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Install script completed
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Adding reference to registry
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Done installing plugin.
Dec 13 09:33:23 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 13 09:33:23 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Folder /tmp/plugins removed
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Folder /tmp/downloaded_plugin.zip removed
Dec 13 09:33:23 volumio-wz volumio[1243]: info: Folder /data/temp removed
Dec 13 09:33:49 volumio-wz go-librespot[5221]: time="2025-12-13T09:33:49+01:00" level=trace msg="received accesspoint ping"
Dec 13 09:33:49 volumio-wz go-librespot[5221]: time="2025-12-13T09:33:49+01:00" level=trace msg="received accesspoint pong ack"
Dec 13 09:33:49 volumio-wz go-librespot[5221]: time="2025-12-13T09:33:49+01:00" level=trace msg="sent dealer ping"
Dec 13 09:33:49 volumio-wz go-librespot[5221]: time="2025-12-13T09:33:49+01:00" level=trace msg="received dealer pong"
Dec 13 09:33:52 volumio-wz volumio[1243]: info: CorePlayQueue::getTrack 12
Dec 13 09:33:52 volumio-wz volumio[1243]: info: CorePlayQueue::getTrack 13
Dec 13 09:33:52 volumio-wz volumio[1243]: info: Prefetching next song
Dec 13 09:33:52 volumio-wz volumio[1243]: info: [1765614832221] ControllerSpotify::prefetch
Dec 13 09:33:52 volumio-wz volumio[1243]: info: Sending Spotify command with payload to local API: /player/add_to_queue
Dec 13 09:33:52 volumio-wz go-librespot[5221]: time="2025-12-13T09:33:52+01:00" level=warning msg="cannot add to queue without a context"
Dec 13 09:33:56 volumio-wz volumio[1243]: info: CoreStateMachine::startPlaybackTimer
Dec 13 09:33:56 volumio-wz volumio[1243]: info: CorePlayQueue::getTrack 13
Dec 13 09:33:57 volumio-wz volumio[1243]: info: CoreStateMachine::pushState
Dec 13 09:33:57 volumio-wz volumio[1243]: info: CorePlayQueue::getTrack 13
Dec 13 09:33:57 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 13 09:33:57 volumio-wz volumio[1243]: info: CoreCommandRouter::volumioPushState
Dec 13 09:33:57 volumio-wz volumio[1243]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50
Dec 13 09:33:58 volumio-wz volumio[1243]: info: CoreCommandRouter::volumioGetState
Dec 13 09:33:58 volumio-wz volumio[1243]: info: CorePlayQueue::getTrack 13
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Retrieving Cloud Streaming UI
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Getting Tidal Cloud Configuration
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Getting Qobuz Cloud Configuration
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Asking plugin for UI Config
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Getting Spotify Cloud Configuration
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Asking plugin for UI Config
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Saving Spotify Acccount
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Got Tidal Cloud Configuration
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Got it
Dec 13 09:34:00 volumio-wz volumio[1243]: info: Got it
Dec 13 09:34:00 volumio-wz volumio[1243]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 13 09:34:00 volumio-wz volumio[1243]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave')
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 13 09:34:00 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Dec 13 09:34:04 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 13 09:34:08 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 13 09:34:12 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 13 09:34:16 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 13 09:34:18 volumio-wz volumio[1243]: info: Disabling MyMusic plugin upnp
Dec 13 09:34:18 volumio-wz sudo[7529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Dec 13 09:34:18 volumio-wz sudo[7529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 13 09:34:18 volumio-wz systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD...
Dec 13 09:34:18 volumio-wz volumio[1243]: info: Enabling MyMusic plugin upnp
Dec 13 09:34:18 volumio-wz volumio[1243]: info: Enabling plugin upnp
Dec 13 09:34:18 volumio-wz volumio[1243]: info: Loading plugin "upnp"...
Dec 13 09:34:18 volumio-wz volumio[1243]: info: [1765614858786] Starting Upmpd Daemon
Dec 13 09:34:18 volumio-wz volumio[1243]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 13 09:34:18 volumio-wz volumio[1243]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 13 09:34:18 volumio-wz volumio[1243]: Error: listen EADDRINUSE: address already in use :::6599
Dec 13 09:34:18 volumio-wz volumio[1243]: at Server.setupListenHandle [as _listen2] (node:net:1872:16)
Dec 13 09:34:18 volumio-wz volumio[1243]: at listenInCluster (node:net:1920:12)
Dec 13 09:34:18 volumio-wz volumio[1243]: at Server.listen (node:net:2008:7)
Dec 13 09:34:18 volumio-wz volumio[1243]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Dec 13 09:34:18 volumio-wz volumio[1243]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Dec 13 09:34:18 volumio-wz volumio[1243]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Dec 13 09:34:18 volumio-wz volumio[1243]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Dec 13 09:34:18 volumio-wz volumio[1243]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) {
Dec 13 09:34:18 volumio-wz volumio[1243]: code: 'EADDRINUSE',
Dec 13 09:34:18 volumio-wz volumio[1243]: errno: -98,
Dec 13 09:34:18 volumio-wz volumio[1243]: syscall: 'listen',
Dec 13 09:34:18 volumio-wz volumio[1243]: address: '::',
Dec 13 09:34:18 volumio-wz volumio[1243]: port: 6599
Dec 13 09:34:18 volumio-wz volumio[1243]: }
Dec 13 09:34:18 volumio-wz volumio[1243]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 13 09:34:19 volumio-wz sudo[7547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 09:33'
Dec 13 09:34:19 volumio-wz sudo[7547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
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="cfdc872d40b979cca36352a7333c940c38dc64b5"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025"
VOLUMIO_VERSION="4.073"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"