Apr 21 20:35:01 bassfly volumio[1033]: info: Loading i18n strings for locale en Apr 21 20:35:01 bassfly volumio[1033]: Updating browse sources language Apr 21 20:35:01 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::initPlayerControls Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 20:35:02 bassfly volumio[1033]: Express server listening on port 3000 Apr 21 20:35:02 bassfly volumio[1033]: [Metrics] WebUI: 47s 392.51ms Apr 21 20:35:02 bassfly volumio[1033]: info: CoreStateMachine::resetVolumioState Apr 21 20:35:02 bassfly volumio[1033]: info: CoreStateMachine::getcurrentVolume Apr 21 20:35:02 bassfly volumio[1033]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 20:35:02 bassfly volumio[1033]: info: Volumio Network Manager: Network status updated: 0 Apr 21 20:35:02 bassfly sudo[1471]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 20:35:02 bassfly sudo[1471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:02 bassfly sudo[1473]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 20:35:02 bassfly sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:02 bassfly sudo[1473]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:03 bassfly sudo[1471]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:03 bassfly volumio-remote-updater[598]: [2026-04-21 20:35:03] [connect] Successful connection Apr 21 20:35:03 bassfly volumio[1033]: info: CoreStateMachine::pushState Apr 21 20:35:03 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:03 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 20:35:03 bassfly volumio[1033]: info: CoreCommandRouter::volumioPushState Apr 21 20:35:03 bassfly volumio[1033]: info: CoreStateMachine::updateTrackBlock Apr 21 20:35:03 bassfly volumio[1033]: info: CorePlayQueue::getTrackBlock Apr 21 20:35:03 bassfly volumio[1033]: info: CoreCommandRouter::volumioRetrievevolume Apr 21 20:35:03 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:03 bassfly volumio[1033]: info: Executing start script for DAC BassFly-uHAT Apr 21 20:35:03 bassfly volumio[1033]: info: Reloading queue from file Apr 21 20:35:03 bassfly sudo[1494]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x00 0x2000 w Apr 21 20:35:03 bassfly volumio[1033]: info: CoreStateMachine::pushState Apr 21 20:35:03 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:03 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 20:35:03 bassfly volumio[1033]: info: CoreCommandRouter::volumioPushState Apr 21 20:35:03 bassfly sudo[1494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:04 bassfly volumio[1033]: info: CoreStateMachine::setRepeat null single undefined Apr 21 20:35:04 bassfly volumio[1033]: info: CoreStateMachine::pushState Apr 21 20:35:04 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:04 bassfly sudo[1494]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:04 bassfly volumio[1033]: info: CoreCommandRouter::volumioPushState Apr 21 20:35:04 bassfly volumio[1033]: info: CoreStateMachine::setRandom null Apr 21 20:35:04 bassfly volumio[1033]: info: CoreStateMachine::pushState Apr 21 20:35:04 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:04 bassfly volumio[1033]: info: CoreCommandRouter::volumioPushState Apr 21 20:35:04 bassfly sudo[1498]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x13 0x3016 w Apr 21 20:35:04 bassfly sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:04 bassfly sudo[1498]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:04 bassfly volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Apr 21 20:35:04 bassfly volumio[1033]: info: Setting Device type: Raspberry PI Apr 21 20:35:04 bassfly volumio[1033]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 21 20:35:04 bassfly volumio[1033]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Apr 21 20:35:04 bassfly volumio[1033]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 21 20:35:04 bassfly sudo[1500]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x01 0xD805 w Apr 21 20:35:04 bassfly sudo[1500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:04 bassfly sudo[1500]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:04 bassfly volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 21 20:35:04 bassfly sudo[1502]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x00 0x0900 w Apr 21 20:35:04 bassfly sudo[1502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:04 bassfly volumio-remote-updater[598]: [2026-04-21 20:35:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1776796503 101 Apr 21 20:35:04 bassfly sudo[1504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 21 20:35:04 bassfly sudo[1502]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:04 bassfly sudo[1504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:04 bassfly volumio[1033]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Apr 21 20:35:04 bassfly sudo[1515]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x00 0x2000 w Apr 21 20:35:04 bassfly sudo[1515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:04 bassfly sudo[1515]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:05 bassfly sudo[1522]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x13 0x3016 w Apr 21 20:35:05 bassfly sudo[1522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:05 bassfly volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 21 20:35:05 bassfly sudo[1522]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:05 bassfly volumio[1033]: info: Volumio Network Manager: Network status updated: 2 Apr 21 20:35:05 bassfly sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x01 0xD801 w Apr 21 20:35:05 bassfly sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:05 bassfly sudo[1527]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:05 bassfly sudo[1529]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x00 0x0900 w Apr 21 20:35:05 bassfly sudo[1529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:05 bassfly sudo[1529]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:05 bassfly sudo[1532]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x05 0xDD59 w Apr 21 20:35:05 bassfly sudo[1532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:05 bassfly sudo[1532]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:05 bassfly sudo[1535]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x06 0x24CC w Apr 21 20:35:05 bassfly sudo[1535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:05 bassfly sudo[1535]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:06 bassfly sudo[1537]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x07 0x1A65 w Apr 21 20:35:06 bassfly sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:06 bassfly sudo[1537]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:06 bassfly sudo[1539]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x08 0x2CDB w Apr 21 20:35:06 bassfly sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:06 bassfly sudo[1539]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:06 bassfly sudo[1541]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x09 0x1646 w Apr 21 20:35:06 bassfly sudo[1541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:06 bassfly sudo[1541]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:06 bassfly sudo[1543]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0A 0x3AEA w Apr 21 20:35:06 bassfly sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:06 bassfly sudo[1543]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:06 bassfly sudo[1545]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0B 0xF34D w Apr 21 20:35:06 bassfly sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:06 bassfly sudo[1545]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:06 bassfly sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0C 0x3EF9 w Apr 21 20:35:06 bassfly sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:07 bassfly sudo[1547]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:07 bassfly sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0D 0xE05E w Apr 21 20:35:07 bassfly sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:07 bassfly sudo[1549]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:07 bassfly sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0E 0x34F8 w Apr 21 20:35:07 bassfly sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:07 bassfly sudo[1551]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:07 bassfly sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x0F 0x9A00 w Apr 21 20:35:07 bassfly sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:07 bassfly sudo[1553]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:07 bassfly sudo[1555]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6c 0x11 0x2E13 w Apr 21 20:35:07 bassfly sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:07 bassfly sudo[1555]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:07 bassfly sudo[1557]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x05 0xDD59 w Apr 21 20:35:07 bassfly sudo[1557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:07 bassfly sudo[1557]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:08 bassfly sudo[1559]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x06 0x24CC w Apr 21 20:35:08 bassfly sudo[1559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:08 bassfly sudo[1559]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:08 bassfly sudo[1561]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x07 0x1A65 w Apr 21 20:35:08 bassfly sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:08 bassfly sudo[1561]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:08 bassfly sudo[1563]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x08 0x2CDB w Apr 21 20:35:08 bassfly sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:08 bassfly sudo[1563]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:08 bassfly sudo[1565]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x09 0x1646 w Apr 21 20:35:08 bassfly sudo[1565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:08 bassfly sudo[1565]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:08 bassfly sudo[1567]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0A 0x3AEA w Apr 21 20:35:08 bassfly sudo[1567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:08 bassfly sudo[1567]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:09 bassfly sudo[1569]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0B 0xF34D w Apr 21 20:35:09 bassfly sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:09 bassfly sudo[1569]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:09 bassfly sudo[1571]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0C 0x3EF9 w Apr 21 20:35:09 bassfly sudo[1571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:09 bassfly sudo[1571]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:09 bassfly sudo[1573]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0D 0xE05E w Apr 21 20:35:09 bassfly sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:09 bassfly sudo[1573]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:09 bassfly sudo[1575]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0E 0x34F8 w Apr 21 20:35:09 bassfly sudo[1575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:09 bassfly sudo[1575]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:09 bassfly sudo[1577]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x0F 0x9A00 w Apr 21 20:35:09 bassfly sudo[1577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:09 bassfly sudo[1577]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:09 bassfly sudo[1579]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x6d 0x11 0x2E13 w Apr 21 20:35:09 bassfly sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:09 bassfly sudo[1579]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:09 bassfly volumio[1033]: info: DAC script executed Apr 21 20:35:09 bassfly volumio[1033]: info: Completed loading Core Plugins Apr 21 20:35:09 bassfly volumio[1033]: info: Preparing to generate the ALSA configuration file Apr 21 20:35:10 bassfly volumio[1033]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 21 20:35:10 bassfly volumio[1033]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 21 20:35:10 bassfly volumio[1033]: info: Reading ALSA contributions from plugins. Apr 21 20:35:10 bassfly volumio[1033]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 21 20:35:10 bassfly volumio[1033]: info: Discovery: Restarting Browsing Apr 21 20:35:10 bassfly volumio[1033]: info: Asound.conf file unchanged, so no further update is needed Apr 21 20:35:10 bassfly volumio[1033]: info: Output device has changed, restarting MPD Apr 21 20:35:10 bassfly sudo[1583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 20:35:10 bassfly volumio[1033]: info: Output device has changed, restarting Shairport Sync Apr 21 20:35:10 bassfly sudo[1583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:10 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:10 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:10 bassfly sudo[1583]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:10 bassfly volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 20:35:10 bassfly sudo[1585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 20:35:10 bassfly sudo[1585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:10 bassfly volumio[1033]: info: ___________ START PLUGINS ___________ Apr 21 20:35:10 bassfly volumio[1033]: info: ControllerMpd::onStart: Initializing MPD Apr 21 20:35:10 bassfly volumio[1033]: info: Creating MPD Configuration file Apr 21 20:35:11 bassfly sudo[1593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 21 20:35:11 bassfly sudo[1593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 20:35:11 bassfly systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 20:35:11 bassfly volumio[1033]: info: [1776796511055] CoreMusicLibrary::Adding element Media Servers Apr 21 20:35:11 bassfly systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:11 bassfly sudo[1595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 20:35:11 bassfly sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 20:35:11 bassfly volumio[1033]: info: UPNP Browser: Client initialized successfully Apr 21 20:35:11 bassfly sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:11 bassfly systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:35:11 bassfly sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:11 bassfly sudo[1593]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:11 bassfly sudo[1595]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:11 bassfly systemd[1]: mpd.service: Deactivated successfully. Apr 21 20:35:11 bassfly systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 20:35:11 bassfly systemd[1]: mpd.socket: Deactivated successfully. Apr 21 20:35:11 bassfly systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 21 20:35:11 bassfly systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:11 bassfly systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 21 20:35:11 bassfly systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 20:35:11 bassfly volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:11 bassfly volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 20:35:11 bassfly volumio[1033]: info: [1776796511730] CoreMusicLibrary::Adding element Last_100 Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 20:35:11 bassfly volumio[1033]: info: [1776796511822] CoreMusicLibrary::Adding element Webradio Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:11 bassfly sudo[1606]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 21 20:35:11 bassfly sudo[1606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 21 20:35:11 bassfly sudo[1620]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 21 20:35:11 bassfly sudo[1606]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:11 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 20:35:11 bassfly volumio[1033]: info: Initializing BBC Radios Apr 21 20:35:12 bassfly volumio5-onboarding[1601]: time=2026-04-21T20:35:12.206+02:00 level=INFO msg="running volumio5-device-gateway" version=83ee4468+CHANGES buildDate=2026-04-15T10:25:32Z Apr 21 20:35:12 bassfly volumio[1033]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Apr 21 20:35:12 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:12 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 20:35:12 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:12 bassfly volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 20:35:12 bassfly volumio[1033]: info: [1776796512908] CoreMusicLibrary::Adding element Bandcamp Discover Apr 21 20:35:12 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:12 bassfly volumio[1033]: Cannot find translation for source Bandcamp Discover Apr 21 20:35:13 bassfly systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Apr 21 20:35:13 bassfly systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 21 20:35:13 bassfly volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 20:35:13 bassfly volumio[1033]: info: [1776796513089] CoreMusicLibrary::Adding element Mixcloud Apr 21 20:35:13 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:13 bassfly volumio[1033]: Cannot find translation for source Bandcamp Discover Apr 21 20:35:13 bassfly volumio[1033]: Cannot find translation for source Mixcloud Apr 21 20:35:13 bassfly systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 21 20:35:13 bassfly sudo[1504]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:13 bassfly volumio[1033]: info: Creating Spotify config file Apr 21 20:35:13 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:16 bassfly volumio[1033]: info: gpio-buttons - Initializing GPIO triggers... Apr 21 20:35:16 bassfly volumio[1033]: info: gpio-buttons - ✅ GPIO6 configured for playPause Apr 21 20:35:16 bassfly volumio[1033]: info: gpio-buttons - ✅ GPIO7 configured for volumeUp Apr 21 20:35:16 bassfly volumio[1033]: info: gpio-buttons - ✅ GPIO8 configured for volumeDown Apr 21 20:35:16 bassfly volumio[1033]: info: gpio-buttons - ✅ GPIO5 configured for next Apr 21 20:35:16 bassfly volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 20:35:16 bassfly volumio[1033]: info: [1776796516480] CoreMusicLibrary::Adding element 80s80s Radio Apr 21 20:35:16 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Bandcamp Discover Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Mixcloud Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source 80s80s Radio Apr 21 20:35:16 bassfly volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 20:35:16 bassfly volumio[1033]: info: [1776796516571] CoreMusicLibrary::Adding element Mother Earth Radio Apr 21 20:35:16 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Bandcamp Discover Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Mixcloud Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source 80s80s Radio Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Mother Earth Radio Apr 21 20:35:16 bassfly volumio[1033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 20:35:16 bassfly volumio[1033]: info: [1776796516663] CoreMusicLibrary::Adding element Radio Paradise Apr 21 20:35:16 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Bandcamp Discover Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Mixcloud Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source 80s80s Radio Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Mother Earth Radio Apr 21 20:35:16 bassfly volumio[1033]: Cannot find translation for source Radio Paradise Apr 21 20:35:17 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 21 20:35:17 bassfly volumio[1033]: info: Volumio Calling Home Apr 21 20:35:18 bassfly volumio[1033]: info: gpio-buttons - GPIO-Buttons started Apr 21 20:35:18 bassfly volumio[1033]: info: Preparing to generate the ALSA configuration file Apr 21 20:35:18 bassfly ntpd[746]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Apr 21 20:35:18 bassfly ntpd[746]: DNS: dns_check: DNS error: -11, System error Apr 21 20:35:18 bassfly ntpd[746]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Apr 21 20:35:18 bassfly sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Apr 21 20:35:18 bassfly sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:18 bassfly sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Apr 21 20:35:18 bassfly sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:18 bassfly sudo[1671]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:19 bassfly ntpd[746]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 21 20:35:22 bassfly volumio5-onboarding[1601]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:48166->127.0.0.1:3000: i/o timeout Apr 21 20:35:22 bassfly systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 21 20:35:22 bassfly systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 21 20:35:22 bassfly systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Apr 21 20:35:22 bassfly systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:35:22 bassfly systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:35:23 bassfly volumio5-onboarding[1680]: time=2026-04-21T20:35:23.112+02:00 level=INFO msg="running volumio5-device-gateway" version=83ee4468+CHANGES buildDate=2026-04-15T10:25:32Z Apr 21 20:35:26 bassfly ntpd[746]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Apr 21 20:35:26 bassfly ntpd[746]: DNS: Pool taking: 195.32.70.195 Apr 21 20:35:26 bassfly ntpd[746]: DNS: Pool taking: 172.232.208.229 Apr 21 20:35:26 bassfly ntpd[746]: DNS: Pool taking: 151.22.209.90 Apr 21 20:35:26 bassfly ntpd[746]: DNS: Pool taking: 162.159.200.1 Apr 21 20:35:26 bassfly ntpd[746]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Apr 21 20:35:26 bassfly ntpd[746]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 21 20:35:26 bassfly ntpd[746]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Apr 21 20:35:26 bassfly ntpd[746]: DNS: Pool taking: 204.216.214.76 Apr 21 20:35:26 bassfly ntpd[746]: DNS: Pool taking: 217.61.62.224 Apr 21 20:35:26 bassfly ntpd[746]: DNS: Pool taking: 185.19.184.35 Apr 21 20:35:26 bassfly ntpd[746]: DNS: Pool taking: 185.157.229.254 Apr 21 20:35:26 bassfly ntpd[746]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Apr 21 20:35:30 bassfly mpd[1621]: 2026-04-21T20:35:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 20:35:31 bassfly systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 20:35:32 bassfly sudo[1585]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:32 bassfly sudo[1597]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:32 bassfly systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Apr 21 20:35:32 bassfly sudo[1673]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:32 bassfly (lugin.sh)[1705]: mpd_oled_plugin.service: Failed to execute /tmp/mpd_oled_plugin.sh: Text file busy Apr 21 20:35:32 bassfly (lugin.sh)[1705]: mpd_oled_plugin.service: Failed at step EXEC spawning /tmp/mpd_oled_plugin.sh: Text file busy Apr 21 20:35:32 bassfly systemd[1]: mpd_oled_plugin.service: Main process exited, code=exited, status=203/EXEC Apr 21 20:35:32 bassfly systemd[1]: mpd_oled_plugin.service: Failed with result 'exit-code'. Apr 21 20:35:32 bassfly volumio[1033]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 21 20:35:32 bassfly volumio[1033]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 21 20:35:32 bassfly volumio[1033]: info: Reading ALSA contributions from plugins. Apr 21 20:35:32 bassfly volumio[1033]: info: Discovery: adding 9328465f-6b88-47d1-a458-cbe97bd08b18 Apr 21 20:35:32 bassfly volumio[1033]: info: Discovery: Found device BassFly Apr 21 20:35:32 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:35:32 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:32 bassfly nmbd[891]: [2026/04/21 20:35:32.915796, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Apr 21 20:35:32 bassfly nmbd[891]: Packet send failed to 192.168.0.255(138) ERRNO=Network is unreachable Apr 21 20:35:33 bassfly volumio5-onboarding[1680]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:39004->127.0.0.1:3000: i/o timeout Apr 21 20:35:33 bassfly systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 21 20:35:33 bassfly systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 21 20:35:33 bassfly systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Apr 21 20:35:33 bassfly systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:35:33 bassfly systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:35:33 bassfly volumio5-onboarding[1708]: time=2026-04-21T20:35:33.528+02:00 level=INFO msg="running volumio5-device-gateway" version=83ee4468+CHANGES buildDate=2026-04-15T10:25:32Z Apr 21 20:35:33 bassfly volumio[1033]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Apr 21 20:35:34 bassfly volumio[1033]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Apr 21 20:35:34 bassfly volumio[1033]: info: MPD Permissions set Apr 21 20:35:34 bassfly volumio[1033]: info: Completed starting Core Plugins Apr 21 20:35:34 bassfly volumio[1033]: info: ------------------------------------------- Apr 21 20:35:34 bassfly volumio[1033]: info: ----- MyVolumio plugins startup ---- Apr 21 20:35:34 bassfly volumio[1033]: info: ------------------------------------------- Apr 21 20:35:34 bassfly volumio[1033]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 21 20:35:34 bassfly volumio[1033]: info: MPD Permissions set Apr 21 20:35:34 bassfly volumio[1033]: info: Upmpdcli Daemon Started Apr 21 20:35:34 bassfly volumio[1033]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 21 20:35:34 bassfly volumio[1033]: info: Discovery: Restarting Browsing Apr 21 20:35:34 bassfly volumio[1033]: info: Discovery: this is already registered, 9328465f-6b88-47d1-a458-cbe97bd08b18 Apr 21 20:35:34 bassfly volumio[1033]: info: Discovery: Found device BassFly Apr 21 20:35:34 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:35:34 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:34 bassfly volumio[1033]: info: Discovery: adding 1eee4f22-7aa6-4f68-b6dd-bc45755add4e Apr 21 20:35:34 bassfly volumio[1033]: info: Discovery: Found device Integro-Livingroom Apr 21 20:35:34 bassfly volumio[1033]: info: Discovery: Connecting to remote: 192.168.1.105 Apr 21 20:35:35 bassfly volumio[1033]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Apr 21 20:35:35 bassfly volumio[1033]: info: Spotify config file written Apr 21 20:35:36 bassfly sudo[1721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 21 20:35:36 bassfly sudo[1721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:36 bassfly systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 21 20:35:36 bassfly systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 21 20:35:36 bassfly systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 21 20:35:36 bassfly sudo[1721]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:36 bassfly go-librespot[1725]: go-librespot daemon starting... Apr 21 20:35:37 bassfly volumio[1033]: error: MPD error: The expression evaluated to a falsy value: Apr 21 20:35:37 bassfly volumio[1033]: assert.ok(self.idling) Apr 21 20:35:37 bassfly volumio[1033]: error: The expression evaluated to a falsy value: Apr 21 20:35:37 bassfly volumio[1033]: assert.ok(self.idling) Apr 21 20:35:37 bassfly volumio[1033]: info: MPD running with PID1621 Apr 21 20:35:37 bassfly volumio[1033]: ,establishing connection Apr 21 20:35:37 bassfly volumio[1033]: info: Volumio called home Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly go-librespot[1726]: time="2026-04-21T20:35:37+02:00" level=info msg="running go-librespot 0.7.1" Apr 21 20:35:37 bassfly go-librespot[1726]: time="2026-04-21T20:35:37+02:00" level=debug msg="app state loaded" Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly go-librespot[1726]: time="2026-04-21T20:35:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:37 bassfly volumio[1033]: info: No need to fix Spotify hosts Apr 21 20:35:38 bassfly volumio[1033]: error: updateQueue error: null Apr 21 20:35:38 bassfly volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Apr 21 20:35:39 bassfly volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Apr 21 20:35:40 bassfly volumio[1033]: info: Received Get System Info Apr 21 20:35:40 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 20:35:40 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 20:35:40 bassfly volumio[1033]: info: Discovery: Getting this device information Apr 21 20:35:40 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:35:40 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:40 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 20:35:40 bassfly go-librespot[1726]: time="2026-04-21T20:35:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 21 20:35:40 bassfly go-librespot[1726]: time="2026-04-21T20:35:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 21 20:35:40 bassfly go-librespot[1726]: time="2026-04-21T20:35:40+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 21 20:35:40 bassfly volumio5-onboarding[1708]: time=2026-04-21T20:35:40.299+02:00 level=INFO msg="system info for 96cd7cb2a620f113d0a6229df13a116e" deviceName=BassFly deviceVariant=volumio deviceModel= softwareVersion=4.143 Apr 21 20:35:40 bassfly go-librespot[1726]: time="2026-04-21T20:35:40+02:00" level=info msg="zeroconf server listening on port 34659" Apr 21 20:35:40 bassfly go-librespot[1726]: time="2026-04-21T20:35:40+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 21 20:35:40 bassfly volumio[1033]: error: error Apr 21 20:35:40 bassfly volumio[1033]: error: updateQueue error: null Apr 21 20:35:41 bassfly volumio[1033]: info: Starting Shairport Sync Apr 21 20:35:41 bassfly volumio[1033]: info: Starting Shairport Sync Apr 21 20:35:41 bassfly volumio[1033]: info: Starting Shairport Sync Apr 21 20:35:41 bassfly volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 21 20:35:41 bassfly sudo[1770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 20:35:41 bassfly sudo[1772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 20:35:41 bassfly sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 20:35:41 bassfly sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:41 bassfly sudo[1772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:41 bassfly sudo[1770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:41 bassfly volumio5-onboarding[1708]: time=2026-04-21T20:35:41.521+02:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 21 20:35:41 bassfly volumio[1033]: info: Received Get System Info Apr 21 20:35:41 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 20:35:41 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 20:35:41 bassfly volumio[1033]: info: Discovery: Getting this device information Apr 21 20:35:41 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:35:41 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:41 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 20:35:42 bassfly volumio[1033]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 21 20:35:42 bassfly systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 21 20:35:42 bassfly systemd[1]: shairport-sync.service: Deactivated successfully. Apr 21 20:35:42 bassfly systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 21 20:35:42 bassfly systemd[1]: shairport-sync.service: Consumed 2.422s CPU time. Apr 21 20:35:42 bassfly systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 21 20:35:42 bassfly sudo[1768]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:42 bassfly sudo[1772]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:42 bassfly sudo[1770]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:42 bassfly volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 21 20:35:42 bassfly volumio[1033]: info: go-librespot daemon successfully initialized Apr 21 20:35:42 bassfly volumio[1033]: info: Asound.conf file unchanged, so no further update is needed Apr 21 20:35:42 bassfly volumio[1033]: info: Output device has changed, restarting MPD Apr 21 20:35:42 bassfly volumio[1033]: info: Output device has changed, restarting Shairport Sync Apr 21 20:35:42 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:42 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:42 bassfly sudo[1782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 20:35:42 bassfly sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:42 bassfly sudo[1780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 20:35:42 bassfly sudo[1780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:42 bassfly volumio[1033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 21 20:35:42 bassfly systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 21 20:35:43 bassfly sudo[1780]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:43 bassfly volumio-remote-updater[598]: Test mode disabled Apr 21 20:35:43 bassfly volumio-remote-updater[598]: Alpha mode enabled Apr 21 20:35:43 bassfly volumio-remote-updater[598]: Alpha legacy test mode disabled Apr 21 20:35:47 bassfly volumio[1033]: info: Discovery: this is already registered, 9328465f-6b88-47d1-a458-cbe97bd08b18 Apr 21 20:35:47 bassfly volumio[1033]: info: Discovery: Found device BassFly Apr 21 20:35:47 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:35:47 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:48 bassfly volumio[1033]: info: Shairport-Sync Started Apr 21 20:35:50 bassfly volumio[1033]: Error adding Membership: Error: addMembership EINVAL Apr 21 20:35:50 bassfly volumio[1033]: info: Shairport-Sync Started Apr 21 20:35:50 bassfly volumio[1033]: info: Shairport-Sync Started Apr 21 20:35:50 bassfly volumio[1033]: info: Initializing connection to go-librespot Websocket Apr 21 20:35:50 bassfly systemd[1]: mpd.service: Deactivated successfully. Apr 21 20:35:50 bassfly systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 21 20:35:50 bassfly systemd[1]: mpd.service: Consumed 8.322s CPU time. Apr 21 20:35:50 bassfly systemd[1]: mpd.socket: Deactivated successfully. Apr 21 20:35:50 bassfly systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 21 20:35:50 bassfly systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 21 20:35:50 bassfly systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 21 20:35:50 bassfly volumio[1033]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Apr 21 20:35:50 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:50 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 20:35:50 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:50 bassfly systemd[1]: Starting mpd.service - Music Player Daemon... Apr 21 20:35:51 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 20:35:51 bassfly sudo[1823]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 21 20:35:51 bassfly sudo[1823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 21 20:35:51 bassfly sudo[1823]: pam_unix(sudo:session): session closed for user root Apr 21 20:35:52 bassfly volumio[1033]: info: MPD Permissions set Apr 21 20:35:52 bassfly go-librespot[1726]: time="2026-04-21T20:35:52+02:00" level=debug msg="new websocket client" Apr 21 20:35:52 bassfly volumio[1033]: verbose: New Socket.io Connection to 192.168.1.106:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 21 20:35:53 bassfly volumio[1033]: info: Connection to go-librespot Websocket established Apr 21 20:35:53 bassfly volumio[1033]: verbose: New Socket.io Connection to 192.168.1.106:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 21 20:35:53 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:53 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:53 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:53 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:53 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:53 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:53 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:53 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:54 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:54 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:54 bassfly volumio[1033]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 21 20:35:54 bassfly volumio[1033]: info: Discovery: Restarting Browsing Apr 21 20:35:54 bassfly volumio[1033]: error: MyVolumio Plugin failed to start in a timely fashion Apr 21 20:35:55 bassfly volumio[1033]: [Metrics] CommandRouter: 98s 11.88ms Apr 21 20:35:55 bassfly volumio[1033]: info: CoreCommandRouter::volumiosetStartupVolume Apr 21 20:35:55 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 20:35:55 bassfly volumio[1033]: info: VolumeController:: Setting startup Volume 60 Apr 21 20:35:55 bassfly volumio[1033]: info: VolumeController::SetAlsaVolume60 Apr 21 20:35:55 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 20:35:55 bassfly volumio[1033]: info: CoreCommandRouter::Close All Modals sent Apr 21 20:35:57 bassfly volumio[1033]: info: CoreCommandRouter::Close All Modals sent Apr 21 20:35:57 bassfly volumio[1033]: info: CoreStateMachine::pushState Apr 21 20:35:57 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:57 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 20:35:57 bassfly volumio[1033]: info: CoreCommandRouter::volumioPushState Apr 21 20:35:58 bassfly volumio[1033]: info: Getting Spotify volume Apr 21 20:35:59 bassfly volumio[1033]: info: Starting Shairport Sync Apr 21 20:35:59 bassfly volumio[1033]: info: Discovery: Connected to remote: 192.168.1.105 Apr 21 20:35:59 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 21 20:35:59 bassfly sudo[1838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 21 20:35:59 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 20:35:59 bassfly sudo[1838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:35:59 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 21 20:35:59 bassfly systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 21 20:35:59 bassfly systemd[1]: shairport-sync.service: Deactivated successfully. Apr 21 20:35:59 bassfly systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 21 20:35:59 bassfly systemd[1]: shairport-sync.service: Consumed 1.463s CPU time. Apr 21 20:35:59 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 21 20:35:59 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:35:59 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:35:59 bassfly systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 21 20:35:59 bassfly sudo[1838]: pam_unix(sudo:session): session closed for user root Apr 21 20:36:00 bassfly volumio[1033]: info: Shairport-Sync Started Apr 21 20:36:00 bassfly volumio[1033]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 21 20:36:00 bassfly volumio[1033]: info: Discovery: Restarting Browsing Apr 21 20:36:00 bassfly volumio[1033]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 21 20:36:00 bassfly volumio[1033]: info: Discovery: Restarting Browsing Apr 21 20:36:00 bassfly volumio[1033]: info: Discovery: Restart already pending, ignoring duplicate call Apr 21 20:36:00 bassfly volumio[1033]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 21 20:36:00 bassfly volumio[1033]: info: Discovery: Restarting Browsing Apr 21 20:36:00 bassfly volumio[1033]: info: Discovery: Restart already pending, ignoring duplicate call Apr 21 20:36:03 bassfly volumio[1033]: info: Discovery: this is already registered, 1eee4f22-7aa6-4f68-b6dd-bc45755add4e Apr 21 20:36:03 bassfly volumio[1033]: info: Discovery: Found device Integro-Livingroom Apr 21 20:36:03 bassfly mpd[1826]: 2026-04-21T20:36:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 20:36:04 bassfly volumio[1033]: info: Discovery: this is already registered, 9328465f-6b88-47d1-a458-cbe97bd08b18 Apr 21 20:36:04 bassfly volumio[1033]: info: Discovery: Found device BassFly Apr 21 20:36:04 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:36:04 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:36:04 bassfly volumio[1033]: info: Discovery: this is already registered, 1eee4f22-7aa6-4f68-b6dd-bc45755add4e Apr 21 20:36:04 bassfly volumio[1033]: info: Discovery: Found device Integro-Livingroom Apr 21 20:36:04 bassfly volumio[1033]: info: Discovery: this is already registered, 9328465f-6b88-47d1-a458-cbe97bd08b18 Apr 21 20:36:04 bassfly volumio[1033]: info: Discovery: Found device BassFly Apr 21 20:36:04 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:36:04 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:36:04 bassfly volumio[1033]: info: Discovery: this is already registered, 9328465f-6b88-47d1-a458-cbe97bd08b18 Apr 21 20:36:04 bassfly systemd[1]: Started mpd.service - Music Player Daemon. Apr 21 20:36:04 bassfly volumio[1033]: info: Discovery: Found device BassFly Apr 21 20:36:04 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:36:04 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:36:04 bassfly sudo[1782]: pam_unix(sudo:session): session closed for user root Apr 21 20:36:04 bassfly volumio[1033]: info: BOOT COMPLETED Apr 21 20:36:05 bassfly volumio[1033]: info: New Spotify access tokenBQD3uZleNE... Apr 21 20:36:05 bassfly volumio[1033]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 21 20:36:05 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:36:05 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: this is already registered, 9328465f-6b88-47d1-a458-cbe97bd08b18 Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: Found device BassFly Apr 21 20:36:06 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:36:06 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: this is already registered, 9328465f-6b88-47d1-a458-cbe97bd08b18 Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: Found device BassFly Apr 21 20:36:06 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:36:06 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:36:06 bassfly volumio[1033]: error: updateQueue error: null Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: Restarting Browsing Apr 21 20:36:06 bassfly volumio[1033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 21 20:36:06 bassfly volumio[1033]: verbose: New Socket.io Connection to 192.168.1.106:3000 from 192.168.1.200 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: Browse raised the following error Error: dns service error: unknown Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: Restarting Browsing Apr 21 20:36:06 bassfly volumio[1033]: info: Discovery: Restart already pending, ignoring duplicate call Apr 21 20:36:07 bassfly volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 21 20:36:07 bassfly volumio[1033]: info: CoreCommandRouter::volumioGetState Apr 21 20:36:07 bassfly volumio[1033]: info: CorePlayQueue::getTrack 0 Apr 21 20:36:08 bassfly go-librespot[1726]: time="2026-04-21T20:36:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Apr 21 20:36:08 bassfly systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 21 20:36:08 bassfly systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 21 20:36:08 bassfly volumio[1033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 20:36:08 bassfly volumio[1033]: Error: socket hang up Apr 21 20:36:08 bassfly volumio[1033]: at connResetException (node:internal/errors:720:14) Apr 21 20:36:08 bassfly volumio[1033]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 21 20:36:08 bassfly volumio[1033]: at Socket.emit (node:events:526:35) Apr 21 20:36:08 bassfly volumio[1033]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 21 20:36:08 bassfly volumio[1033]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 21 20:36:08 bassfly volumio[1033]: code: 'ECONNRESET', Apr 21 20:36:08 bassfly volumio[1033]: response: undefined Apr 21 20:36:08 bassfly volumio[1033]: } Apr 21 20:36:08 bassfly volumio[1033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 20:36:10 bassfly volumio5-onboarding[1708]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Apr 21 20:36:10 bassfly systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 21 20:36:10 bassfly systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 21 20:36:10 bassfly systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Apr 21 20:36:10 bassfly systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:36:10 bassfly systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:36:11 bassfly systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 21 20:36:11 bassfly systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 21 20:36:11 bassfly systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 21 20:36:11 bassfly go-librespot[1907]: go-librespot daemon starting... Apr 21 20:36:12 bassfly go-librespot[1908]: time="2026-04-21T20:36:12+02:00" level=info msg="running go-librespot 0.7.1" Apr 21 20:36:12 bassfly go-librespot[1908]: time="2026-04-21T20:36:12+02:00" level=debug msg="app state loaded" Apr 21 20:36:12 bassfly go-librespot[1908]: time="2026-04-21T20:36:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 21 20:36:12 bassfly volumio5-onboarding[1890]: time=2026-04-21T20:36:12.274+02:00 level=INFO msg="running volumio5-device-gateway" version=83ee4468+CHANGES buildDate=2026-04-15T10:25:32Z Apr 21 20:36:12 bassfly go-librespot[1908]: time="2026-04-21T20:36:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 21 20:36:12 bassfly go-librespot[1908]: time="2026-04-21T20:36:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 21 20:36:12 bassfly go-librespot[1908]: time="2026-04-21T20:36:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 21 20:36:12 bassfly go-librespot[1908]: time="2026-04-21T20:36:12+02:00" level=info msg="zeroconf server listening on port 33543" Apr 21 20:36:12 bassfly go-librespot[1908]: time="2026-04-21T20:36:12+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 21 20:36:14 bassfly go-librespot[1908]: time="2026-04-21T20:36:14+02:00" level=debug msg="obtained new client token: AAAI078GkWHDCxS0qPc0i5lCrd2nfNG/2FW/HeZFCGwZitg97O4R9D8wWsqMGvu5z64HBdtFqxA29CscPtLn+FzZzJePjAnq4t3wtoUH8Fr/UvdpZd3SKvyVD8OjFmJ3vVCvqRfeJjwwG5S9RUGx7Z7ECTxOTDHXfeF+NLoHYUTPI9/dmgNsBnkW5mTwEatcolyrFDyHVj0ACxTCK8vjY7cPocDHhWA4M7w4roJcoqjg5/wYhJECYpqA" Apr 21 20:36:14 bassfly go-librespot[1908]: time="2026-04-21T20:36:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 21 20:36:14 bassfly go-librespot[1908]: time="2026-04-21T20:36:14+02:00" level=debug msg="completed keyexchange" Apr 21 20:36:14 bassfly go-librespot[1908]: time="2026-04-21T20:36:14+02:00" level=debug msg="completed challenge" Apr 21 20:36:14 bassfly go-librespot[1908]: time="2026-04-21T20:36:14+02:00" level=info msg="authenticated AP" username="ms*********************q9" Apr 21 20:36:14 bassfly go-librespot[1908]: time="2026-04-21T20:36:14+02:00" level=info msg="authenticated Login5" username="ms*********************q9" Apr 21 20:36:14 bassfly go-librespot[1908]: time="2026-04-21T20:36:14+02:00" level=debug msg="initializing zeroconf session" username="ms*********************q9" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=debug msg="dealer connection opened" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=trace msg="starting accesspoint recv loop" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=trace msg="starting dealer recv loop" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=trace msg="received accesspoint ping" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=debug msg="received connection id: NTkzZjVlYjUtZjU5...OTU5N0I2QjAyMQ==" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=trace msg="received accesspoint pong ack" Apr 21 20:36:15 bassfly go-librespot[1908]: time="2026-04-21T20:36:15+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 21 20:36:22 bassfly sudo[1935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-21 20:35' Apr 21 20:36:22 bassfly sudo[1935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 21 20:36:22 bassfly volumio5-onboarding[1890]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:48304->127.0.0.1:3000: i/o timeout Apr 21 20:36:22 bassfly systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 21 20:36:22 bassfly systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 21 20:36:22 bassfly systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Apr 21 20:36:22 bassfly systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:36:22 bassfly systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 21 20:36:23 bassfly volumio5-onboarding[1947]: time=2026-04-21T20:36:23.239+02:00 level=INFO msg="running volumio5-device-gateway" version=83ee4468+CHANGES buildDate=2026-04-15T10:25:32Z 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="f107261a9bd5157ff5c5bbfacc2d3aef88ae641e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="7f1bd9e83b67dc80eb7e0d85089b6e1994df1be5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Apr 21 10:21:42 UTC 2026" VOLUMIO_VERSION="4.143" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="bd8666f76951314267ed39a1c3f3c8d0"