-- Logs begin at Thu 2025-07-17 22:17:01 UTC, end at Thu 2025-07-17 22:19:59 UTC. -- Jul 17 22:18:01 volumio sudo[1581]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:01 volumio volumio[1469]: info: MPD running with PID1511 Jul 17 22:18:01 volumio volumio[1469]: ,establishing connection Jul 17 22:18:01 volumio volumio[1469]: error: MPD error: The expression evaluated to a falsy value: Jul 17 22:18:01 volumio volumio[1469]: assert.ok(self.idling) Jul 17 22:18:01 volumio volumio[1469]: error: MPD error: The expression evaluated to a falsy value: Jul 17 22:18:01 volumio volumio[1469]: assert.ok(self.idling) Jul 17 22:18:01 volumio volumio[1469]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jul 17 22:18:01 volumio volumio[1469]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:01 volumio volumio[1469]: info: Reloading queue from file Jul 17 22:18:01 volumio volumio[1469]: info: CoreStateMachine::setRepeat true single undefined Jul 17 22:18:01 volumio volumio[1469]: info: CoreStateMachine::pushState Jul 17 22:18:01 volumio volumio[1469]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:01 volumio volumio[1469]: info: CoreStateMachine::setRandom false Jul 17 22:18:01 volumio volumio[1469]: info: CoreStateMachine::pushState Jul 17 22:18:01 volumio volumio[1469]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:01 volumio volumio[1469]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:01 volumio volumio[1469]: info: Setting Device type: Raspberry PI Jul 17 22:18:01 volumio volumio[1469]: error: updateQueue error: null Jul 17 22:18:01 volumio volumio[1469]: info: Completed loading Core Plugins Jul 17 22:18:01 volumio volumio[1469]: info: Preparing to generate the ALSA configuration file Jul 17 22:18:02 volumio volumio[1469]: info: Asound.conf file unchanged, so no further update is needed Jul 17 22:18:02 volumio volumio[1469]: info: Output device has changed, restarting MPD Jul 17 22:18:02 volumio sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:02 volumio sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:02 volumio volumio[1469]: info: Output device has changed, restarting Shairport Sync Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:02 volumio sudo[1592]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:02 volumio sudo[1594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:02 volumio sudo[1594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:02 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:02 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:02 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:02 volumio volumio[1469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:02 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:02 volumio volumio[1469]: info: ___________ START PLUGINS ___________ Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:02 volumio volumio[1469]: info: [1752790682116] CoreMusicLibrary::Adding element Media Servers Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:02 volumio volumio[1469]: Cannot find translation for sourceMedia Servers Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:02 volumio volumio[1469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:02 volumio volumio[1469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:02 volumio volumio[1469]: info: [1752790682206] CoreMusicLibrary::Adding element Last_100 Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:02 volumio volumio[1469]: Cannot find translation for sourceMedia Servers Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:02 volumio volumio[1469]: info: [1752790682209] CoreMusicLibrary::Adding element Webradio Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:02 volumio volumio[1469]: Cannot find translation for sourceMedia Servers Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:02 volumio volumio[1469]: info: [1752790682218] CoreMusicLibrary::Adding element Radio Paradise Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:02 volumio volumio[1469]: Cannot find translation for sourceMedia Servers Jul 17 22:18:02 volumio volumio[1469]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:02 volumio volumio[1469]: info: [1752790682221] CoreMusicLibrary::Adding element LastFM Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:02 volumio volumio[1469]: Cannot find translation for sourceMedia Servers Jul 17 22:18:02 volumio volumio[1469]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:02 volumio volumio[1469]: Cannot find translation for sourceLastFM Jul 17 22:18:02 volumio volumio[1469]: info: [LastFM] scrobbler initiated! Jul 17 22:18:02 volumio volumio[1469]: info: [LastFM] extended logging: false Jul 17 22:18:02 volumio volumio[1469]: info: [LastFM] try scrobble stream/radio plays: false Jul 17 22:18:02 volumio volumio[1469]: info: [LastFM] Left init routine Jul 17 22:18:02 volumio volumio[1469]: info: [LastFM] Socket already connected: true Jul 17 22:18:02 volumio volumio[1469]: info: Loading i18n strings for locale en Jul 17 22:18:02 volumio volumio[1469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:02 volumio volumio[1469]: info: Volumio Calling Home Jul 17 22:18:02 volumio sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 17 22:18:02 volumio sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:02 volumio systemd[1]: Reloading. Jul 17 22:18:02 volumio volumio[1469]: Unhandled rejection Error: No sockets available, cannot start. Jul 17 22:18:02 volumio volumio[1469]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 17 22:18:02 volumio volumio[1469]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 17 22:18:02 volumio volumio[1469]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 17 22:18:02 volumio volumio[1469]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 17 22:18:02 volumio volumio[1469]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 17 22:18:02 volumio volumio[1469]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 17 22:18:02 volumio volumio[1469]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 17 22:18:02 volumio volumio[1469]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 17 22:18:02 volumio volumio[1469]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 17 22:18:02 volumio volumio[1469]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 17 22:18:02 volumio volumio[1469]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 17 22:18:02 volumio volumio[1469]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 17 22:18:02 volumio volumio[1469]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 17 22:18:02 volumio volumio[1469]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 17 22:18:02 volumio volumio[1469]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 17 22:18:02 volumio volumio[1469]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 17 22:18:02 volumio volumio[1469]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:02 volumio volumio[1469]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jul 17 22:18:02 volumio volumio[1469]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 17 22:18:02 volumio volumio[1469]: errno: -16, Jul 17 22:18:02 volumio volumio[1469]: code: 'EBUSY', Jul 17 22:18:02 volumio volumio[1469]: syscall: 'getaddrinfo', Jul 17 22:18:02 volumio volumio[1469]: hostname: 'ws.audioscrobbler.com' Jul 17 22:18:02 volumio volumio[1469]: } Jul 17 22:18:02 volumio volumio[1469]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:02 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 17 22:18:02 volumio sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 22:17 Jul 17 22:18:02 volumio sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:02 volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:02 volumio mpd[1609]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:02 volumio mpd[1609]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:02 volumio mpd[1609]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:03 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 17 22:18:03 volumio sudo[1594]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:03 volumio sudo[1613]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 17 22:18:03 volumio systemd[1]: Started dynamicswap service. Jul 17 22:18:03 volumio systemd[1]: Starting Daily apt download activities... Jul 17 22:18:03 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:18:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 17 22:18:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jul 17 22:18:03 volumio systemd[1]: Stopped Volumio Backend Module. Jul 17 22:18:03 volumio systemd[1]: Started Volumio Backend Module. Jul 17 22:18:03 volumio systemd[1]: Started dynamicswap service. Jul 17 22:18:03 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:18:04 volumio wpa_supplicant[976]: wlan0: Failed to initiate sched scan Jul 17 22:18:04 volumio volumio[1658]: info: ------------------------------------------- Jul 17 22:18:04 volumio volumio[1658]: info: ----- Volumio3 ---- Jul 17 22:18:04 volumio volumio[1658]: info: ------------------------------------------- Jul 17 22:18:04 volumio volumio[1658]: info: ----- System startup ---- Jul 17 22:18:04 volumio volumio[1658]: info: ------------------------------------------- Jul 17 22:18:04 volumio volumio[1658]: info: MYVOLUMIO Environment detected Jul 17 22:18:04 volumio volumio[1658]: info: Plugin folders cleanup Jul 17 22:18:04 volumio volumio[1658]: info: Scanning into folder /volumio/app/plugins/ Jul 17 22:18:04 volumio volumio[1658]: info: Scanning category audio_interface Jul 17 22:18:04 volumio volumio[1658]: info: Scanning category miscellanea Jul 17 22:18:04 volumio volumio[1658]: info: Scanning category music_service Jul 17 22:18:04 volumio volumio[1658]: info: Scanning category plugins.json Jul 17 22:18:04 volumio volumio[1658]: info: Scanning category system_controller Jul 17 22:18:04 volumio volumio[1658]: info: Scanning category user_interface Jul 17 22:18:04 volumio volumio[1658]: info: Scanning into folder /data/plugins/ Jul 17 22:18:04 volumio volumio[1658]: info: Scanning category music_service Jul 17 22:18:04 volumio volumio[1658]: info: Scanning category user_interface Jul 17 22:18:04 volumio volumio[1658]: info: Plugin folders cleanup completed Jul 17 22:18:04 volumio volumio[1658]: info: ------------------------------------------- Jul 17 22:18:04 volumio volumio[1658]: info: ----- Core plugins startup ---- Jul 17 22:18:04 volumio volumio[1658]: info: ------------------------------------------- Jul 17 22:18:04 volumio volumio[1658]: info: Loading plugins from folder /volumio/app/plugins/ Jul 17 22:18:04 volumio volumio[1658]: info: Adding plugin upnp to MyMusic Plugins Jul 17 22:18:04 volumio volumio[1658]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 17 22:18:04 volumio volumio[1658]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 17 22:18:04 volumio volumio[1658]: info: Loading plugins from folder /data/plugins/ Jul 17 22:18:04 volumio volumio[1658]: info: Loading plugin "system"... Jul 17 22:18:04 volumio volumio[1658]: info: Loading plugin "appearance"... Jul 17 22:18:05 volumio systemd[1]: apt-daily.service: Succeeded. Jul 17 22:18:05 volumio systemd[1]: Started Daily apt download activities. Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "network"... Jul 17 22:18:05 volumio volumio[1658]: info: Refreshing Cached IP Addresses Jul 17 22:18:05 volumio sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 22:18:05 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:05 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "services"... Jul 17 22:18:05 volumio sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 22:18:05 volumio sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "alsa_controller"... Jul 17 22:18:05 volumio sudo[1730]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:05 volumio sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 17 22:18:05 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:05 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "wizard"... Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "volumio_command_line_client"... Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "upnp"... Jul 17 22:18:05 volumio volumio[1658]: info: [1752790685607] Starting Upmpd Daemon Jul 17 22:18:05 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "my_music"... Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "mpd"... Jul 17 22:18:05 volumio volumio[1658]: info: Creating MPD Configuration file Jul 17 22:18:05 volumio sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:05 volumio volumio[1658]: info: Loading plugin "upnp_browser"... Jul 17 22:18:05 volumio sudo[1739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:05 volumio sudo[1739]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:05 volumio sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:05 volumio sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:05 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:05 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:05 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:05 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "networkfs"... Jul 17 22:18:06 volumio volumio[1658]: info: Starting Udev Watcher for removable devices Jul 17 22:18:06 volumio volumio[1658]: info: Ignoring mount for partition: boot Jul 17 22:18:06 volumio volumio[1658]: info: Ignoring mount for partition: volumio Jul 17 22:18:06 volumio volumio[1658]: info: Ignoring mount for partition: volumio_data Jul 17 22:18:06 volumio volumio[1658]: info: Mounting Device ADATA_32GB Jul 17 22:18:06 volumio sudo[1752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ADATA_32GB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:18:06 volumio sudo[1752]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:06 volumio sudo[1752]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:06 volumio volumio[1658]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:18:06 volumio volumio[1658]: error: Failed to mount ADATA_32GB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/ADATA_32GB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:18:06 volumio volumio[1658]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:18:06 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "alarm-clock"... Jul 17 22:18:06 volumio mpd[1746]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "airplay_emulation"... Jul 17 22:18:06 volumio volumio[1658]: info: Starting Shairport Sync Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "last_100"... Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "webradio"... Jul 17 22:18:06 volumio mpd[1746]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:06 volumio mpd[1746]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:06 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:06 volumio sudo[1741]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "i2s_dacs"... Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "volumiodiscovery"... Jul 17 22:18:06 volumio volumio[1658]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:06 volumio volumio[1658]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:06 volumio volumio[1658]: *** WARNING *** For more information see Jul 17 22:18:06 volumio node[1658]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:06 volumio volumio[1658]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:06 volumio volumio[1658]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:06 volumio volumio[1658]: *** WARNING *** For more information see Jul 17 22:18:06 volumio node[1658]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:06 volumio node[1658]: *** WARNING *** For more information see Jul 17 22:18:06 volumio node[1658]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:06 volumio node[1658]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:06 volumio node[1658]: *** WARNING *** For more information see Jul 17 22:18:06 volumio volumio[1658]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 17 22:18:06 volumio volumio[1658]: info: Discovery: Started advertising with name: Volumio Jul 17 22:18:06 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "outputs"... Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "albumart"... Jul 17 22:18:06 volumio volumio[1658]: info: Plugin example_plugin is not enabled Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "inputs"... Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "updater_comm"... Jul 17 22:18:06 volumio volumio[1658]: info: Plugin mpdemulation is not enabled Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "rest_api"... Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "websocket"... Jul 17 22:18:06 volumio volumio[1658]: info: Loading plugin "radio_paradise"... Jul 17 22:18:06 volumio volumio[1658]: Forking 3 albumart workers Jul 17 22:18:07 volumio volumio[1658]: info: Applying required configuration parameters for plugin radio_paradise Jul 17 22:18:07 volumio volumio[1658]: info: [1752790687096] [RadioParadise] API delay: 5 Jul 17 22:18:07 volumio volumio[1658]: info: Loading plugin "Systeminfo"... Jul 17 22:18:07 volumio volumio[1658]: info: Loading plugin "lastfm"... Jul 17 22:18:07 volumio volumio[1658]: Starting albumart workers Jul 17 22:18:07 volumio volumio[1658]: Starting albumart workers Jul 17 22:18:07 volumio volumio[1658]: Starting albumart workers Jul 17 22:18:07 volumio volumio[1658]: info: Loading plugin "touch_display"... Jul 17 22:18:08 volumio volumio[1658]: info: Applying required configuration parameters for plugin touch_display Jul 17 22:18:08 volumio volumio[1658]: info: Loading i18n strings for locale en Jul 17 22:18:08 volumio volumio[1658]: Updating browse sources language Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::initPlayerControls Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: Express server listening on port 3000 Jul 17 22:18:08 volumio volumio[1658]: [Metrics] WebUI: 4s 177.27ms Jul 17 22:18:08 volumio sudo[1733]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:08 volumio volumio[1658]: info: CoreStateMachine::resetVolumioState Jul 17 22:18:08 volumio volumio[1658]: info: CoreStateMachine::getcurrentVolume Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioRetrievevolume Jul 17 22:18:08 volumio volumio[1658]: info: CoreStateMachine::pushState Jul 17 22:18:08 volumio volumio[1658]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:08 volumio volumio[1658]: info: MPD Permissions set Jul 17 22:18:08 volumio volumio[1658]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jul 17 22:18:08 volumio volumio[1658]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 17 22:18:08 volumio volumio[1658]: info: MPD running with PID1746 Jul 17 22:18:08 volumio volumio[1658]: ,establishing connection Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: Reloading queue from file Jul 17 22:18:08 volumio volumio[1658]: error: updateQueue error: null Jul 17 22:18:08 volumio volumio[1658]: info: CoreStateMachine::setRepeat true single undefined Jul 17 22:18:08 volumio volumio[1658]: info: CoreStateMachine::pushState Jul 17 22:18:08 volumio volumio[1658]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:08 volumio volumio[1658]: info: CoreStateMachine::setRandom false Jul 17 22:18:08 volumio volumio[1658]: info: CoreStateMachine::pushState Jul 17 22:18:08 volumio volumio[1658]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:08 volumio volumio[1658]: info: Setting Device type: Raspberry PI Jul 17 22:18:08 volumio volumio[1658]: info: Completed loading Core Plugins Jul 17 22:18:08 volumio volumio[1658]: info: Preparing to generate the ALSA configuration file Jul 17 22:18:08 volumio volumio[1658]: error: updateQueue error: null Jul 17 22:18:08 volumio volumio[1658]: info: Asound.conf file unchanged, so no further update is needed Jul 17 22:18:08 volumio volumio[1658]: info: Output device has changed, restarting MPD Jul 17 22:18:08 volumio sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:08 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:08 volumio volumio[1658]: info: Output device has changed, restarting Shairport Sync Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:08 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:08 volumio sudo[1827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:08 volumio sudo[1827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:08 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:08 volumio volumio[1658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:08 volumio volumio[1658]: info: ___________ START PLUGINS ___________ Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:08 volumio volumio[1658]: info: [1752790688711] CoreMusicLibrary::Adding element Media Servers Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:08 volumio volumio[1658]: Cannot find translation for sourceMedia Servers Jul 17 22:18:08 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:08 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:08 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:08 volumio volumio[1658]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:08 volumio volumio[1658]: info: [1752790688806] CoreMusicLibrary::Adding element Last_100 Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:08 volumio volumio[1658]: Cannot find translation for sourceMedia Servers Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:08 volumio volumio[1658]: info: [1752790688809] CoreMusicLibrary::Adding element Webradio Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:08 volumio volumio[1658]: Cannot find translation for sourceMedia Servers Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:08 volumio volumio[1658]: info: [1752790688818] CoreMusicLibrary::Adding element Radio Paradise Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:08 volumio volumio[1658]: Cannot find translation for sourceMedia Servers Jul 17 22:18:08 volumio volumio[1658]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:08 volumio volumio[1658]: info: [1752790688821] CoreMusicLibrary::Adding element LastFM Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:08 volumio volumio[1658]: Cannot find translation for sourceMedia Servers Jul 17 22:18:08 volumio volumio[1658]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:08 volumio volumio[1658]: Cannot find translation for sourceLastFM Jul 17 22:18:08 volumio volumio[1658]: info: [LastFM] scrobbler initiated! Jul 17 22:18:08 volumio volumio[1658]: info: [LastFM] extended logging: false Jul 17 22:18:08 volumio volumio[1658]: info: [LastFM] try scrobble stream/radio plays: false Jul 17 22:18:08 volumio volumio[1658]: info: [LastFM] Left init routine Jul 17 22:18:08 volumio volumio[1658]: info: [LastFM] Socket already connected: true Jul 17 22:18:08 volumio volumio[1658]: info: Loading i18n strings for locale en Jul 17 22:18:08 volumio volumio[1658]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:08 volumio volumio[1658]: info: Volumio Calling Home Jul 17 22:18:08 volumio sudo[1845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 17 22:18:08 volumio sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:08 volumio systemd[1]: Reloading. Jul 17 22:18:08 volumio volumio[1658]: Unhandled rejection Error: No sockets available, cannot start. Jul 17 22:18:08 volumio volumio[1658]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 17 22:18:08 volumio volumio[1658]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 17 22:18:08 volumio volumio[1658]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 17 22:18:08 volumio volumio[1658]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 17 22:18:08 volumio volumio[1658]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 17 22:18:08 volumio volumio[1658]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 17 22:18:08 volumio volumio[1658]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 17 22:18:08 volumio volumio[1658]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 17 22:18:08 volumio volumio[1658]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 17 22:18:08 volumio volumio[1658]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 17 22:18:08 volumio volumio[1658]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 17 22:18:08 volumio volumio[1658]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 17 22:18:08 volumio volumio[1658]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 17 22:18:08 volumio volumio[1658]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 17 22:18:08 volumio volumio[1658]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 17 22:18:08 volumio volumio[1658]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 17 22:18:09 volumio volumio[1658]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:09 volumio volumio[1658]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jul 17 22:18:09 volumio volumio[1658]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 17 22:18:09 volumio volumio[1658]: errno: -16, Jul 17 22:18:09 volumio volumio[1658]: code: 'EBUSY', Jul 17 22:18:09 volumio volumio[1658]: syscall: 'getaddrinfo', Jul 17 22:18:09 volumio volumio[1658]: hostname: 'ws.audioscrobbler.com' Jul 17 22:18:09 volumio volumio[1658]: } Jul 17 22:18:09 volumio volumio[1658]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:09 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 17 22:18:09 volumio sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 22:17 Jul 17 22:18:09 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:09 volumio mpd[1841]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:09 volumio mpd[1841]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:09 volumio mpd[1841]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:09 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:09 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:09 volumio sudo[1845]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:09 volumio sudo[1827]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 17 22:18:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 17 22:18:09 volumio systemd[1]: Started dynamicswap service. Jul 17 22:18:09 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:18:10 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 17 22:18:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jul 17 22:18:10 volumio systemd[1]: Stopped Volumio Backend Module. Jul 17 22:18:10 volumio systemd[1]: Started Volumio Backend Module. Jul 17 22:18:10 volumio systemd[1]: Started dynamicswap service. Jul 17 22:18:10 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:18:10 volumio volumio-remote-updater[477]: [2025-07-17 22:18:10] [connect] Successful connection Jul 17 22:18:11 volumio volumio[1888]: info: ------------------------------------------- Jul 17 22:18:11 volumio volumio[1888]: info: ----- Volumio3 ---- Jul 17 22:18:11 volumio volumio[1888]: info: ------------------------------------------- Jul 17 22:18:11 volumio volumio[1888]: info: ----- System startup ---- Jul 17 22:18:11 volumio volumio[1888]: info: ------------------------------------------- Jul 17 22:18:11 volumio volumio[1888]: info: MYVOLUMIO Environment detected Jul 17 22:18:11 volumio volumio[1888]: info: Plugin folders cleanup Jul 17 22:18:11 volumio volumio[1888]: info: Scanning into folder /volumio/app/plugins/ Jul 17 22:18:11 volumio volumio[1888]: info: Scanning category audio_interface Jul 17 22:18:11 volumio volumio[1888]: info: Scanning category miscellanea Jul 17 22:18:11 volumio volumio[1888]: info: Scanning category music_service Jul 17 22:18:11 volumio volumio[1888]: info: Scanning category plugins.json Jul 17 22:18:11 volumio volumio[1888]: info: Scanning category system_controller Jul 17 22:18:11 volumio volumio[1888]: info: Scanning category user_interface Jul 17 22:18:11 volumio volumio[1888]: info: Scanning into folder /data/plugins/ Jul 17 22:18:11 volumio volumio[1888]: info: Scanning category music_service Jul 17 22:18:11 volumio volumio[1888]: info: Scanning category user_interface Jul 17 22:18:11 volumio volumio[1888]: info: Plugin folders cleanup completed Jul 17 22:18:11 volumio volumio[1888]: info: ------------------------------------------- Jul 17 22:18:11 volumio volumio[1888]: info: ----- Core plugins startup ---- Jul 17 22:18:11 volumio volumio[1888]: info: ------------------------------------------- Jul 17 22:18:11 volumio volumio[1888]: info: Loading plugins from folder /volumio/app/plugins/ Jul 17 22:18:11 volumio volumio[1888]: info: Adding plugin upnp to MyMusic Plugins Jul 17 22:18:11 volumio volumio[1888]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 17 22:18:11 volumio volumio[1888]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 17 22:18:11 volumio volumio[1888]: info: Loading plugins from folder /data/plugins/ Jul 17 22:18:11 volumio volumio[1888]: info: Loading plugin "system"... Jul 17 22:18:11 volumio volumio[1888]: info: Loading plugin "appearance"... Jul 17 22:18:11 volumio wpa_supplicant[976]: wlan0: Failed to initiate sched scan Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "network"... Jul 17 22:18:12 volumio volumio[1888]: info: Refreshing Cached IP Addresses Jul 17 22:18:12 volumio sudo[1912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 22:18:12 volumio sudo[1912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:12 volumio sudo[1912]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:12 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 22:18:12 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "services"... Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "alsa_controller"... Jul 17 22:18:12 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:12 volumio sudo[1917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 17 22:18:12 volumio sudo[1917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:12 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "wizard"... Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "volumio_command_line_client"... Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "upnp"... Jul 17 22:18:12 volumio volumio[1888]: info: [1752790692209] Starting Upmpd Daemon Jul 17 22:18:12 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "my_music"... Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "mpd"... Jul 17 22:18:12 volumio volumio[1888]: info: Creating MPD Configuration file Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "upnp_browser"... Jul 17 22:18:12 volumio sudo[1923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:12 volumio sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:12 volumio sudo[1923]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:12 volumio sudo[1925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:12 volumio sudo[1925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:12 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:12 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:12 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:12 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:12 volumio volumio[1888]: info: Loading plugin "networkfs"... Jul 17 22:18:12 volumio volumio[1888]: info: Starting Udev Watcher for removable devices Jul 17 22:18:13 volumio volumio[1888]: info: Ignoring mount for partition: boot Jul 17 22:18:13 volumio volumio[1888]: info: Ignoring mount for partition: volumio Jul 17 22:18:13 volumio volumio[1888]: info: Ignoring mount for partition: volumio_data Jul 17 22:18:13 volumio volumio[1888]: info: Mounting Device ADATA_32GB Jul 17 22:18:13 volumio sudo[1936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ADATA_32GB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:18:13 volumio sudo[1936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:13 volumio sudo[1936]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:13 volumio volumio[1888]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:18:13 volumio volumio[1888]: error: Failed to mount ADATA_32GB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/ADATA_32GB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:18:13 volumio volumio[1888]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:18:13 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "alarm-clock"... Jul 17 22:18:13 volumio mpd[1930]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "airplay_emulation"... Jul 17 22:18:13 volumio volumio[1888]: info: Starting Shairport Sync Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "last_100"... Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "webradio"... Jul 17 22:18:13 volumio mpd[1930]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:13 volumio mpd[1930]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:13 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:13 volumio sudo[1925]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "i2s_dacs"... Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "volumiodiscovery"... Jul 17 22:18:13 volumio volumio[1888]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:13 volumio volumio[1888]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:13 volumio volumio[1888]: *** WARNING *** For more information see Jul 17 22:18:13 volumio node[1888]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:13 volumio volumio[1888]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:13 volumio volumio[1888]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:13 volumio volumio[1888]: *** WARNING *** For more information see Jul 17 22:18:13 volumio node[1888]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:13 volumio node[1888]: *** WARNING *** For more information see Jul 17 22:18:13 volumio node[1888]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:13 volumio node[1888]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:13 volumio node[1888]: *** WARNING *** For more information see Jul 17 22:18:13 volumio volumio[1888]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 17 22:18:13 volumio volumio[1888]: info: Discovery: Started advertising with name: Volumio Jul 17 22:18:13 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "outputs"... Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "albumart"... Jul 17 22:18:13 volumio volumio[1888]: info: Plugin example_plugin is not enabled Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "inputs"... Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "updater_comm"... Jul 17 22:18:13 volumio volumio[1888]: info: Plugin mpdemulation is not enabled Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "rest_api"... Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "websocket"... Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "radio_paradise"... Jul 17 22:18:13 volumio volumio[1888]: Forking 3 albumart workers Jul 17 22:18:13 volumio volumio[1888]: info: Applying required configuration parameters for plugin radio_paradise Jul 17 22:18:13 volumio volumio[1888]: info: [1752790693698] [RadioParadise] API delay: 5 Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "Systeminfo"... Jul 17 22:18:13 volumio volumio[1888]: info: Loading plugin "lastfm"... Jul 17 22:18:14 volumio volumio[1888]: Starting albumart workers Jul 17 22:18:14 volumio volumio[1888]: Starting albumart workers Jul 17 22:18:14 volumio volumio[1888]: Starting albumart workers Jul 17 22:18:14 volumio volumio[1888]: info: Loading plugin "touch_display"... Jul 17 22:18:14 volumio volumio[1888]: info: Applying required configuration parameters for plugin touch_display Jul 17 22:18:14 volumio volumio[1888]: info: Loading i18n strings for locale en Jul 17 22:18:14 volumio volumio[1888]: Updating browse sources language Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::initPlayerControls Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: Express server listening on port 3000 Jul 17 22:18:14 volumio volumio[1888]: [Metrics] WebUI: 3s 881.59ms Jul 17 22:18:14 volumio volumio[1888]: info: CoreStateMachine::resetVolumioState Jul 17 22:18:14 volumio volumio[1888]: info: CoreStateMachine::getcurrentVolume Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::volumioRetrievevolume Jul 17 22:18:14 volumio volumio[1888]: info: CoreStateMachine::pushState Jul 17 22:18:14 volumio volumio[1888]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:14 volumio sudo[1917]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:14 volumio volumio[1888]: info: MPD Permissions set Jul 17 22:18:14 volumio volumio-remote-updater[477]: [2025-07-17 22:18:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752790690 101 Jul 17 22:18:14 volumio volumio[1888]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 17 22:18:14 volumio volumio[1888]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 17 22:18:14 volumio volumio[1888]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 17 22:18:14 volumio volumio[1888]: info: MPD running with PID1930 Jul 17 22:18:14 volumio volumio[1888]: ,establishing connection Jul 17 22:18:14 volumio ntpd[680]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:14 volumio volumio[1888]: info: Reloading queue from file Jul 17 22:18:14 volumio volumio[1888]: error: updateQueue error: null Jul 17 22:18:15 volumio volumio[1888]: info: CoreStateMachine::setRepeat true single undefined Jul 17 22:18:15 volumio volumio[1888]: info: CoreStateMachine::pushState Jul 17 22:18:15 volumio volumio[1888]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:15 volumio volumio[1888]: info: CoreStateMachine::setRandom false Jul 17 22:18:15 volumio volumio[1888]: info: CoreStateMachine::pushState Jul 17 22:18:15 volumio volumio[1888]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:15 volumio volumio[1888]: info: Setting Device type: Raspberry PI Jul 17 22:18:15 volumio volumio[1888]: info: Completed loading Core Plugins Jul 17 22:18:15 volumio volumio[1888]: info: Preparing to generate the ALSA configuration file Jul 17 22:18:15 volumio volumio[1888]: error: updateQueue error: null Jul 17 22:18:15 volumio volumio[1888]: info: Asound.conf file unchanged, so no further update is needed Jul 17 22:18:15 volumio volumio[1888]: info: Output device has changed, restarting MPD Jul 17 22:18:15 volumio volumio[1888]: info: Output device has changed, restarting Shairport Sync Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:15 volumio sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:15 volumio sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:15 volumio sudo[2008]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:15 volumio sudo[2010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:15 volumio sudo[2010]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:15 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:15 volumio volumio[1888]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:15 volumio volumio[1888]: info: ___________ START PLUGINS ___________ Jul 17 22:18:15 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:15 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:15 volumio volumio[1888]: info: [1752790695278] CoreMusicLibrary::Adding element Media Servers Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:15 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:15 volumio volumio[1888]: Cannot find translation for sourceMedia Servers Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:15 volumio volumio[1888]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:15 volumio volumio[1888]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:15 volumio volumio[1888]: info: [1752790695376] CoreMusicLibrary::Adding element Last_100 Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:15 volumio volumio[1888]: Cannot find translation for sourceMedia Servers Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:15 volumio volumio[1888]: info: [1752790695379] CoreMusicLibrary::Adding element Webradio Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:15 volumio volumio[1888]: Cannot find translation for sourceMedia Servers Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:15 volumio volumio[1888]: info: [1752790695388] CoreMusicLibrary::Adding element Radio Paradise Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:15 volumio volumio[1888]: Cannot find translation for sourceMedia Servers Jul 17 22:18:15 volumio volumio[1888]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:15 volumio volumio[1888]: info: [1752790695391] CoreMusicLibrary::Adding element LastFM Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:15 volumio volumio[1888]: Cannot find translation for sourceMedia Servers Jul 17 22:18:15 volumio volumio[1888]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:15 volumio volumio[1888]: Cannot find translation for sourceLastFM Jul 17 22:18:15 volumio volumio[1888]: info: [LastFM] scrobbler initiated! Jul 17 22:18:15 volumio volumio[1888]: info: [LastFM] extended logging: false Jul 17 22:18:15 volumio volumio[1888]: info: [LastFM] try scrobble stream/radio plays: false Jul 17 22:18:15 volumio volumio[1888]: info: [LastFM] Left init routine Jul 17 22:18:15 volumio volumio[1888]: info: [LastFM] Socket already connected: true Jul 17 22:18:15 volumio volumio[1888]: info: Loading i18n strings for locale en Jul 17 22:18:15 volumio volumio[1888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:15 volumio volumio[1888]: info: Volumio Calling Home Jul 17 22:18:15 volumio sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 17 22:18:15 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:15 volumio systemd[1]: Reloading. Jul 17 22:18:15 volumio volumio[1888]: Unhandled rejection Error: No sockets available, cannot start. Jul 17 22:18:15 volumio volumio[1888]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 17 22:18:15 volumio volumio[1888]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 17 22:18:15 volumio volumio[1888]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 17 22:18:15 volumio volumio[1888]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 17 22:18:15 volumio volumio[1888]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 17 22:18:15 volumio volumio[1888]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 17 22:18:15 volumio volumio[1888]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 17 22:18:15 volumio volumio[1888]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 17 22:18:15 volumio volumio[1888]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 17 22:18:15 volumio volumio[1888]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 17 22:18:15 volumio volumio[1888]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 17 22:18:15 volumio volumio[1888]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 17 22:18:15 volumio volumio[1888]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 17 22:18:15 volumio volumio[1888]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 17 22:18:15 volumio volumio[1888]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 17 22:18:15 volumio volumio[1888]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 17 22:18:15 volumio volumio[1888]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:15 volumio volumio[1888]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jul 17 22:18:15 volumio volumio[1888]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 17 22:18:15 volumio volumio[1888]: errno: -16, Jul 17 22:18:15 volumio volumio[1888]: code: 'EBUSY', Jul 17 22:18:15 volumio volumio[1888]: syscall: 'getaddrinfo', Jul 17 22:18:15 volumio volumio[1888]: hostname: 'ws.audioscrobbler.com' Jul 17 22:18:15 volumio volumio[1888]: } Jul 17 22:18:15 volumio volumio[1888]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:15 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 17 22:18:15 volumio ntpd[680]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 17 22:18:16 volumio sudo[2057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 22:17 Jul 17 22:18:16 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:16 volumio mpd[2025]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:16 volumio mpd[2025]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:16 volumio mpd[2025]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:16 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:16 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:16 volumio sudo[2010]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:16 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:16 volumio volumio-remote-updater[477]: [2025-07-17 22:18:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 17 22:18:16 volumio volumio-remote-updater[477]: [2025-07-17 22:18:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 17 22:18:16 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 17 22:18:16 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 17 22:18:16 volumio systemd[1]: Started dynamicswap service. Jul 17 22:18:16 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:18:16 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 17 22:18:16 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jul 17 22:18:16 volumio systemd[1]: Stopped Volumio Backend Module. Jul 17 22:18:16 volumio systemd[1]: Started Volumio Backend Module. Jul 17 22:18:16 volumio systemd[1]: Started dynamicswap service. Jul 17 22:18:16 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:18:17 volumio volumio[2072]: info: ------------------------------------------- Jul 17 22:18:17 volumio volumio[2072]: info: ----- Volumio3 ---- Jul 17 22:18:17 volumio volumio[2072]: info: ------------------------------------------- Jul 17 22:18:17 volumio volumio[2072]: info: ----- System startup ---- Jul 17 22:18:17 volumio volumio[2072]: info: ------------------------------------------- Jul 17 22:18:17 volumio volumio[2072]: info: MYVOLUMIO Environment detected Jul 17 22:18:17 volumio volumio[2072]: info: Plugin folders cleanup Jul 17 22:18:17 volumio volumio[2072]: info: Scanning into folder /volumio/app/plugins/ Jul 17 22:18:17 volumio volumio[2072]: info: Scanning category audio_interface Jul 17 22:18:17 volumio volumio[2072]: info: Scanning category miscellanea Jul 17 22:18:17 volumio volumio[2072]: info: Scanning category music_service Jul 17 22:18:17 volumio volumio[2072]: info: Scanning category plugins.json Jul 17 22:18:17 volumio volumio[2072]: info: Scanning category system_controller Jul 17 22:18:17 volumio volumio[2072]: info: Scanning category user_interface Jul 17 22:18:17 volumio volumio[2072]: info: Scanning into folder /data/plugins/ Jul 17 22:18:17 volumio volumio[2072]: info: Scanning category music_service Jul 17 22:18:17 volumio volumio[2072]: info: Scanning category user_interface Jul 17 22:18:17 volumio volumio[2072]: info: Plugin folders cleanup completed Jul 17 22:18:17 volumio volumio[2072]: info: ------------------------------------------- Jul 17 22:18:17 volumio volumio[2072]: info: ----- Core plugins startup ---- Jul 17 22:18:17 volumio volumio[2072]: info: ------------------------------------------- Jul 17 22:18:17 volumio volumio[2072]: info: Loading plugins from folder /volumio/app/plugins/ Jul 17 22:18:17 volumio volumio[2072]: info: Adding plugin upnp to MyMusic Plugins Jul 17 22:18:17 volumio volumio[2072]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 17 22:18:17 volumio volumio[2072]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 17 22:18:17 volumio volumio[2072]: info: Loading plugins from folder /data/plugins/ Jul 17 22:18:17 volumio volumio[2072]: info: Loading plugin "system"... Jul 17 22:18:17 volumio volumio[2072]: info: Loading plugin "appearance"... Jul 17 22:18:17 volumio ntpd[680]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "network"... Jul 17 22:18:18 volumio volumio[2072]: info: Refreshing Cached IP Addresses Jul 17 22:18:18 volumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 22:18:18 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:18 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:18 volumio sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 22:18:18 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "services"... Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "alsa_controller"... Jul 17 22:18:18 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:18 volumio sudo[2101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 17 22:18:18 volumio sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:18 volumio sudo[2101]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:18 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "wizard"... Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "volumio_command_line_client"... Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "upnp"... Jul 17 22:18:18 volumio volumio[2072]: info: [1752790698535] Starting Upmpd Daemon Jul 17 22:18:18 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "my_music"... Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "mpd"... Jul 17 22:18:18 volumio volumio[2072]: info: Creating MPD Configuration file Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "upnp_browser"... Jul 17 22:18:18 volumio sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:18 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:18 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:18 volumio sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:18 volumio sudo[2109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:18 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:18 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:18 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:18 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:18 volumio ntpd[680]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 17 22:18:18 volumio volumio[2072]: info: Loading plugin "networkfs"... Jul 17 22:18:19 volumio volumio[2072]: info: Starting Udev Watcher for removable devices Jul 17 22:18:19 volumio volumio[2072]: info: Ignoring mount for partition: boot Jul 17 22:18:19 volumio volumio[2072]: info: Ignoring mount for partition: volumio Jul 17 22:18:19 volumio volumio[2072]: info: Ignoring mount for partition: volumio_data Jul 17 22:18:19 volumio volumio[2072]: info: Mounting Device ADATA_32GB Jul 17 22:18:19 volumio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ADATA_32GB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:18:19 volumio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:19 volumio sudo[2120]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:19 volumio volumio[2072]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:18:19 volumio volumio[2072]: error: Failed to mount ADATA_32GB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/ADATA_32GB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:18:19 volumio volumio[2072]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:18:19 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "alarm-clock"... Jul 17 22:18:19 volumio wpa_supplicant[976]: wlan0: Trying to associate with SSID '#Kocouri.NET.Fifejdy#' Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "airplay_emulation"... Jul 17 22:18:19 volumio mpd[2114]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:19 volumio volumio[2072]: info: Starting Shairport Sync Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "last_100"... Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "webradio"... Jul 17 22:18:19 volumio mpd[2114]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:19 volumio mpd[2114]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:19 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:19 volumio sudo[2109]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "i2s_dacs"... Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "volumiodiscovery"... Jul 17 22:18:19 volumio volumio[2072]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:19 volumio volumio[2072]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:19 volumio volumio[2072]: *** WARNING *** For more information see Jul 17 22:18:19 volumio node[2072]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:19 volumio volumio[2072]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:19 volumio volumio[2072]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:19 volumio volumio[2072]: *** WARNING *** For more information see Jul 17 22:18:19 volumio node[2072]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:19 volumio node[2072]: *** WARNING *** For more information see Jul 17 22:18:19 volumio node[2072]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:19 volumio node[2072]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:19 volumio node[2072]: *** WARNING *** For more information see Jul 17 22:18:19 volumio volumio[2072]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 17 22:18:19 volumio volumio[2072]: info: Discovery: Started advertising with name: Volumio Jul 17 22:18:19 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "outputs"... Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "albumart"... Jul 17 22:18:19 volumio volumio[2072]: info: Plugin example_plugin is not enabled Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "inputs"... Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "updater_comm"... Jul 17 22:18:19 volumio volumio[2072]: info: Plugin mpdemulation is not enabled Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "rest_api"... Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "websocket"... Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "radio_paradise"... Jul 17 22:18:19 volumio volumio[2072]: Forking 3 albumart workers Jul 17 22:18:19 volumio volumio[2072]: info: Applying required configuration parameters for plugin radio_paradise Jul 17 22:18:19 volumio volumio[2072]: info: [1752790699989] [RadioParadise] API delay: 5 Jul 17 22:18:19 volumio volumio[2072]: info: Loading plugin "Systeminfo"... Jul 17 22:18:20 volumio volumio[2072]: info: Loading plugin "lastfm"... Jul 17 22:18:20 volumio volumio[2072]: Starting albumart workers Jul 17 22:18:20 volumio volumio[2072]: Starting albumart workers Jul 17 22:18:20 volumio volumio[2072]: Starting albumart workers Jul 17 22:18:20 volumio volumio[2072]: info: Loading plugin "touch_display"... Jul 17 22:18:20 volumio volumio[2072]: info: Applying required configuration parameters for plugin touch_display Jul 17 22:18:20 volumio volumio[2072]: info: Loading i18n strings for locale en Jul 17 22:18:20 volumio volumio[2072]: Updating browse sources language Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::initPlayerControls Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:20 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: Express server listening on port 3000 Jul 17 22:18:21 volumio volumio[2072]: [Metrics] WebUI: 3s 942.92ms Jul 17 22:18:21 volumio volumio[2072]: info: CoreStateMachine::resetVolumioState Jul 17 22:18:21 volumio volumio[2072]: info: CoreStateMachine::getcurrentVolume Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioRetrievevolume Jul 17 22:18:21 volumio volumio[2072]: info: CoreStateMachine::pushState Jul 17 22:18:21 volumio volumio[2072]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:21 volumio volumio[2072]: info: MPD Permissions set Jul 17 22:18:21 volumio volumio[2072]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 17 22:18:21 volumio volumio[2072]: wlan0 Interface doesn't support scanning : Device or resource busy Jul 17 22:18:21 volumio volumio[2072]: info: Cannot use regular scanning, forcing with ap-force Jul 17 22:18:21 volumio sudo[2184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 17 22:18:21 volumio sudo[2184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:21 volumio sudo[2184]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:21 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Jul 17 22:18:21 volumio volumio[2072]: command failed: Resource temporarily unavailable (-11) Jul 17 22:18:21 volumio volumio[2072]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jul 17 22:18:21 volumio volumio[2072]: command failed: Resource temporarily unavailable (-11) Jul 17 22:18:21 volumio volumio[2072]: info: MPD running with PID2114 Jul 17 22:18:21 volumio volumio[2072]: ,establishing connection Jul 17 22:18:21 volumio volumio[2072]: error: MPD error: The expression evaluated to a falsy value: Jul 17 22:18:21 volumio volumio[2072]: assert.ok(self.idling) Jul 17 22:18:21 volumio volumio[2072]: error: MPD error: The expression evaluated to a falsy value: Jul 17 22:18:21 volumio volumio[2072]: assert.ok(self.idling) Jul 17 22:18:21 volumio volumio[2072]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Jul 17 22:18:21 volumio volumio[2072]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 17 22:18:21 volumio volumio-remote-updater[477]: [2025-07-17 22:18:21] [connect] Successful connection Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: Reloading queue from file Jul 17 22:18:21 volumio volumio[2072]: info: CoreStateMachine::setRepeat true single undefined Jul 17 22:18:21 volumio volumio[2072]: info: CoreStateMachine::pushState Jul 17 22:18:21 volumio volumio[2072]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:21 volumio volumio[2072]: info: CoreStateMachine::setRandom false Jul 17 22:18:21 volumio volumio[2072]: info: CoreStateMachine::pushState Jul 17 22:18:21 volumio volumio[2072]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:21 volumio volumio[2072]: info: Setting Device type: Raspberry PI Jul 17 22:18:21 volumio volumio[2072]: error: updateQueue error: null Jul 17 22:18:21 volumio volumio[2072]: info: Completed loading Core Plugins Jul 17 22:18:21 volumio volumio[2072]: info: Preparing to generate the ALSA configuration file Jul 17 22:18:21 volumio volumio-remote-updater[477]: [2025-07-17 22:18:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752790701 101 Jul 17 22:18:21 volumio volumio[2072]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jul 17 22:18:21 volumio volumio[2072]: info: Asound.conf file unchanged, so no further update is needed Jul 17 22:18:21 volumio volumio[2072]: info: Output device has changed, restarting MPD Jul 17 22:18:21 volumio sudo[2195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:21 volumio sudo[2195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:21 volumio volumio[2072]: info: Output device has changed, restarting Shairport Sync Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:21 volumio sudo[2195]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:21 volumio sudo[2198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:21 volumio sudo[2198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:21 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:21 volumio volumio[2072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:21 volumio volumio[2072]: info: ___________ START PLUGINS ___________ Jul 17 22:18:21 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:21 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:21 volumio volumio[2072]: info: [1752790701480] CoreMusicLibrary::Adding element Media Servers Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:21 volumio volumio[2072]: Cannot find translation for sourceMedia Servers Jul 17 22:18:21 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:21 volumio volumio[2072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:21 volumio volumio[2072]: info: [1752790701577] CoreMusicLibrary::Adding element Last_100 Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:21 volumio volumio[2072]: Cannot find translation for sourceMedia Servers Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:21 volumio volumio[2072]: info: [1752790701580] CoreMusicLibrary::Adding element Webradio Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:21 volumio volumio[2072]: Cannot find translation for sourceMedia Servers Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:21 volumio volumio[2072]: info: [1752790701591] CoreMusicLibrary::Adding element Radio Paradise Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:21 volumio volumio[2072]: Cannot find translation for sourceMedia Servers Jul 17 22:18:21 volumio volumio[2072]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:21 volumio volumio[2072]: info: [1752790701595] CoreMusicLibrary::Adding element LastFM Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:21 volumio volumio[2072]: Cannot find translation for sourceMedia Servers Jul 17 22:18:21 volumio volumio[2072]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:21 volumio volumio[2072]: Cannot find translation for sourceLastFM Jul 17 22:18:21 volumio volumio[2072]: info: [LastFM] scrobbler initiated! Jul 17 22:18:21 volumio volumio[2072]: info: [LastFM] extended logging: false Jul 17 22:18:21 volumio volumio[2072]: info: [LastFM] try scrobble stream/radio plays: false Jul 17 22:18:21 volumio volumio[2072]: info: [LastFM] Left init routine Jul 17 22:18:21 volumio volumio[2072]: info: [LastFM] Socket already connected: true Jul 17 22:18:21 volumio volumio[2072]: info: Loading i18n strings for locale en Jul 17 22:18:21 volumio volumio[2072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:21 volumio volumio[2072]: info: Volumio Calling Home Jul 17 22:18:21 volumio sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 17 22:18:21 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:21 volumio systemd[1]: Reloading. Jul 17 22:18:21 volumio volumio[2072]: Unhandled rejection Error: No sockets available, cannot start. Jul 17 22:18:21 volumio volumio[2072]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 17 22:18:21 volumio volumio[2072]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 17 22:18:21 volumio volumio[2072]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 17 22:18:21 volumio volumio[2072]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 17 22:18:21 volumio volumio[2072]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 17 22:18:21 volumio volumio[2072]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 17 22:18:21 volumio volumio[2072]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 17 22:18:21 volumio volumio[2072]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 17 22:18:21 volumio volumio[2072]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 17 22:18:21 volumio volumio[2072]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 17 22:18:21 volumio volumio[2072]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 17 22:18:21 volumio volumio[2072]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 17 22:18:21 volumio volumio[2072]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 17 22:18:21 volumio volumio[2072]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 17 22:18:21 volumio volumio[2072]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 17 22:18:21 volumio volumio[2072]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 17 22:18:21 volumio volumio[2072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:21 volumio volumio[2072]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jul 17 22:18:21 volumio volumio[2072]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 17 22:18:21 volumio volumio[2072]: errno: -16, Jul 17 22:18:21 volumio volumio[2072]: code: 'EBUSY', Jul 17 22:18:21 volumio volumio[2072]: syscall: 'getaddrinfo', Jul 17 22:18:21 volumio volumio[2072]: hostname: 'ws.audioscrobbler.com' Jul 17 22:18:21 volumio volumio[2072]: } Jul 17 22:18:21 volumio volumio[2072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:22 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 17 22:18:22 volumio sudo[2244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 22:17 Jul 17 22:18:22 volumio sudo[2244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:22 volumio mpd[2211]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:22 volumio mpd[2211]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:22 volumio mpd[2211]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:22 volumio sudo[2244]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:22 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:22 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:22 volumio sudo[2198]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:22 volumio volumio-remote-updater[477]: [2025-07-17 22:18:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 17 22:18:22 volumio volumio-remote-updater[477]: [2025-07-17 22:18:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 17 22:18:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 17 22:18:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 17 22:18:22 volumio systemd[1]: Started dynamicswap service. Jul 17 22:18:22 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: carrier acquired Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: connected to Access Point `#Kocouri.NET.Fifejdy#' Jul 17 22:18:22 volumio wpa_supplicant[976]: wlan0: Associated with 90:5c:44:fb:eb:a6 Jul 17 22:18:22 volumio wpa_supplicant[976]: wlan0: CTRL-EVENT-CONNECTED - Connection to 90:5c:44:fb:eb:a6 completed [id=0 id_str=] Jul 17 22:18:22 volumio wpa_supplicant[976]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 17 22:18:22 volumio systemd-udevd[2259]: Process '/sbin/crda' failed with exit code 255. Jul 17 22:18:22 volumio dhcpcd[988]: DUID 00:01:00:01:2a:c9:69:f4:e4:5f:01:04:ef:55 Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: IAID 01:04:ef:56 Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: adding address fe80::49ac:9e7a:bbb9:6b09 Jul 17 22:18:22 volumio dhcpcd[988]: ipv6_addaddr1: Permission denied Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: probing address 192.168.0.25/24 Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: carrier lost Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: carrier acquired Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: IAID 01:04:ef:56 Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: probing address 192.168.0.25/24 Jul 17 22:18:22 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 17 22:18:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Jul 17 22:18:22 volumio systemd[1]: Started dynamicswap service. Jul 17 22:18:22 volumio systemd[1]: Stopped Volumio Backend Module. Jul 17 22:18:22 volumio dhcpcd[988]: wlan0: soliciting an IPv6 router Jul 17 22:18:22 volumio systemd[1]: Started Volumio Backend Module. Jul 17 22:18:22 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:18:23 volumio volumio[2283]: info: ------------------------------------------- Jul 17 22:18:23 volumio volumio[2283]: info: ----- Volumio3 ---- Jul 17 22:18:23 volumio volumio[2283]: info: ------------------------------------------- Jul 17 22:18:23 volumio volumio[2283]: info: ----- System startup ---- Jul 17 22:18:23 volumio volumio[2283]: info: ------------------------------------------- Jul 17 22:18:24 volumio volumio[2283]: info: MYVOLUMIO Environment detected Jul 17 22:18:24 volumio volumio[2283]: info: Plugin folders cleanup Jul 17 22:18:24 volumio volumio[2283]: info: Scanning into folder /volumio/app/plugins/ Jul 17 22:18:24 volumio volumio[2283]: info: Scanning category audio_interface Jul 17 22:18:24 volumio volumio[2283]: info: Scanning category miscellanea Jul 17 22:18:24 volumio volumio[2283]: info: Scanning category music_service Jul 17 22:18:24 volumio volumio[2283]: info: Scanning category plugins.json Jul 17 22:18:24 volumio volumio[2283]: info: Scanning category system_controller Jul 17 22:18:24 volumio volumio[2283]: info: Scanning category user_interface Jul 17 22:18:24 volumio volumio[2283]: info: Scanning into folder /data/plugins/ Jul 17 22:18:24 volumio volumio[2283]: info: Scanning category music_service Jul 17 22:18:24 volumio volumio[2283]: info: Scanning category user_interface Jul 17 22:18:24 volumio volumio[2283]: info: Plugin folders cleanup completed Jul 17 22:18:24 volumio volumio[2283]: info: ------------------------------------------- Jul 17 22:18:24 volumio volumio[2283]: info: ----- Core plugins startup ---- Jul 17 22:18:24 volumio volumio[2283]: info: ------------------------------------------- Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugins from folder /volumio/app/plugins/ Jul 17 22:18:24 volumio volumio[2283]: info: Adding plugin upnp to MyMusic Plugins Jul 17 22:18:24 volumio volumio[2283]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 17 22:18:24 volumio volumio[2283]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugins from folder /data/plugins/ Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "system"... Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "appearance"... Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "network"... Jul 17 22:18:24 volumio volumio[2283]: info: Refreshing Cached IP Addresses Jul 17 22:18:24 volumio sudo[2306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 22:18:24 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:24 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:24 volumio sudo[2308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 22:18:24 volumio sudo[2308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:24 volumio sudo[2308]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "services"... Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "alsa_controller"... Jul 17 22:18:24 volumio sudo[2312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 17 22:18:24 volumio sudo[2312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:24 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "wizard"... Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "volumio_command_line_client"... Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "upnp"... Jul 17 22:18:24 volumio volumio[2283]: info: [1752790704992] Starting Upmpd Daemon Jul 17 22:18:24 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "my_music"... Jul 17 22:18:24 volumio volumio[2283]: info: Loading plugin "mpd"... Jul 17 22:18:25 volumio volumio[2283]: info: Creating MPD Configuration file Jul 17 22:18:25 volumio sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:25 volumio volumio[2283]: info: Loading plugin "upnp_browser"... Jul 17 22:18:25 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:25 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:25 volumio sudo[2319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:25 volumio sudo[2319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:25 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:25 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:25 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:25 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:25 volumio volumio[2283]: info: Loading plugin "networkfs"... Jul 17 22:18:25 volumio volumio[2283]: info: Starting Udev Watcher for removable devices Jul 17 22:18:25 volumio wpa_supplicant[976]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jul 17 22:18:25 volumio volumio[2283]: info: Ignoring mount for partition: boot Jul 17 22:18:25 volumio volumio[2283]: info: Ignoring mount for partition: volumio Jul 17 22:18:25 volumio volumio[2283]: info: Ignoring mount for partition: volumio_data Jul 17 22:18:25 volumio volumio[2283]: info: Mounting Device ADATA_32GB Jul 17 22:18:25 volumio sudo[2330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ADATA_32GB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:18:25 volumio sudo[2330]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:25 volumio sudo[2330]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:25 volumio volumio[2283]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:18:25 volumio volumio[2283]: error: Failed to mount ADATA_32GB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/ADATA_32GB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:18:25 volumio volumio[2283]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:18:25 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:25 volumio volumio[2283]: info: Loading plugin "alarm-clock"... Jul 17 22:18:25 volumio mpd[2324]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "airplay_emulation"... Jul 17 22:18:26 volumio mpd[2324]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:26 volumio mpd[2324]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:26 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:26 volumio volumio[2283]: info: Starting Shairport Sync Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "last_100"... Jul 17 22:18:26 volumio sudo[2319]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "webradio"... Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "i2s_dacs"... Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "volumiodiscovery"... Jul 17 22:18:26 volumio volumio[2283]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:26 volumio volumio[2283]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:26 volumio node[2283]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:26 volumio volumio[2283]: *** WARNING *** For more information see Jul 17 22:18:26 volumio volumio[2283]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:26 volumio volumio[2283]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:26 volumio volumio[2283]: *** WARNING *** For more information see Jul 17 22:18:26 volumio node[2283]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:26 volumio node[2283]: *** WARNING *** For more information see Jul 17 22:18:26 volumio node[2283]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:18:26 volumio node[2283]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:18:26 volumio node[2283]: *** WARNING *** For more information see Jul 17 22:18:26 volumio volumio[2283]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 17 22:18:26 volumio volumio[2283]: info: Discovery: Started advertising with name: Volumio Jul 17 22:18:26 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "outputs"... Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "albumart"... Jul 17 22:18:26 volumio volumio[2283]: info: Plugin example_plugin is not enabled Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "inputs"... Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "updater_comm"... Jul 17 22:18:26 volumio volumio[2283]: info: Plugin mpdemulation is not enabled Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "rest_api"... Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "websocket"... Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "radio_paradise"... Jul 17 22:18:26 volumio volumio[2283]: Forking 3 albumart workers Jul 17 22:18:26 volumio volumio[2283]: info: Applying required configuration parameters for plugin radio_paradise Jul 17 22:18:26 volumio volumio[2283]: info: [1752790706473] [RadioParadise] API delay: 5 Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "Systeminfo"... Jul 17 22:18:26 volumio volumio[2283]: info: Loading plugin "lastfm"... Jul 17 22:18:26 volumio volumio[2283]: Starting albumart workers Jul 17 22:18:27 volumio volumio[2283]: Starting albumart workers Jul 17 22:18:27 volumio volumio[2283]: Starting albumart workers Jul 17 22:18:27 volumio volumio[2283]: info: Loading plugin "touch_display"... Jul 17 22:18:27 volumio volumio[2283]: info: Applying required configuration parameters for plugin touch_display Jul 17 22:18:27 volumio volumio[2283]: info: Loading i18n strings for locale en Jul 17 22:18:27 volumio volumio[2283]: Updating browse sources language Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::initPlayerControls Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: Express server listening on port 3000 Jul 17 22:18:27 volumio volumio[2283]: [Metrics] WebUI: 3s 948.84ms Jul 17 22:18:27 volumio volumio-remote-updater[477]: [2025-07-17 22:18:27] [connect] Successful connection Jul 17 22:18:27 volumio volumio[2283]: info: CoreStateMachine::resetVolumioState Jul 17 22:18:27 volumio volumio[2283]: info: CoreStateMachine::getcurrentVolume Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::volumioRetrievevolume Jul 17 22:18:27 volumio volumio[2283]: info: CoreStateMachine::pushState Jul 17 22:18:27 volumio volumio[2283]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:27 volumio volumio[2283]: info: MPD Permissions set Jul 17 22:18:27 volumio volumio-remote-updater[477]: [2025-07-17 22:18:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752790707 101 Jul 17 22:18:27 volumio volumio[2283]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 17 22:18:27 volumio volumio[2283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 17 22:18:27 volumio volumio[2283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 17 22:18:27 volumio volumio[2283]: info: MPD running with PID2324 Jul 17 22:18:27 volumio volumio[2283]: ,establishing connection Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:27 volumio volumio[2283]: info: Reloading queue from file Jul 17 22:18:27 volumio volumio[2283]: error: updateQueue error: null Jul 17 22:18:27 volumio volumio[2283]: info: CoreStateMachine::setRepeat true single undefined Jul 17 22:18:27 volumio volumio[2283]: info: CoreStateMachine::pushState Jul 17 22:18:27 volumio volumio[2283]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:27 volumio volumio[2283]: info: CoreStateMachine::setRandom false Jul 17 22:18:27 volumio volumio[2283]: info: CoreStateMachine::pushState Jul 17 22:18:27 volumio volumio[2283]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:18:27 volumio volumio[2283]: info: CoreCommandRouter::volumioPushState Jul 17 22:18:27 volumio volumio[2283]: info: Setting Device type: Raspberry PI Jul 17 22:18:27 volumio dhcpcd[988]: wlan0: using static address 192.168.0.25/24 Jul 17 22:18:27 volumio avahi-daemon[473]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.25. Jul 17 22:18:27 volumio dhcpcd[988]: wlan0: adding route to 192.168.0.0/24 Jul 17 22:18:27 volumio dhcpcd[988]: wlan0: adding default route via 192.168.0.1 Jul 17 22:18:27 volumio avahi-daemon[473]: New relevant interface wlan0.IPv4 for mDNS. Jul 17 22:18:27 volumio avahi-daemon[473]: Registering new address record for 192.168.0.25 on wlan0.IPv4. Jul 17 22:18:27 volumio volumio[2283]: info: Completed loading Core Plugins Jul 17 22:18:27 volumio volumio[2283]: info: Preparing to generate the ALSA configuration file Jul 17 22:18:27 volumio volumio[2283]: error: updateQueue error: null Jul 17 22:18:27 volumio volumio[2283]: info: Asound.conf file unchanged, so no further update is needed Jul 17 22:18:27 volumio volumio[2283]: info: Output device has changed, restarting MPD Jul 17 22:18:28 volumio sudo[2423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:18:28 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:28 volumio volumio[2283]: info: Output device has changed, restarting Shairport Sync Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:28 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:28 volumio sudo[2426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:18:28 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:28 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:18:28 volumio volumio[2283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:28 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:18:28 volumio volumio[2283]: info: ___________ START PLUGINS ___________ Jul 17 22:18:28 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:28 volumio volumio[2283]: info: [1752790708079] CoreMusicLibrary::Adding element Media Servers Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:28 volumio volumio[2283]: Cannot find translation for sourceMedia Servers Jul 17 22:18:28 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:28 volumio volumio[2283]: info: [1752790708175] CoreMusicLibrary::Adding element Last_100 Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:28 volumio volumio[2283]: Cannot find translation for sourceMedia Servers Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:28 volumio volumio[2283]: info: [1752790708178] CoreMusicLibrary::Adding element Webradio Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:28 volumio volumio[2283]: Cannot find translation for sourceMedia Servers Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:28 volumio volumio[2283]: info: [1752790708187] CoreMusicLibrary::Adding element Radio Paradise Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:28 volumio volumio[2283]: Cannot find translation for sourceMedia Servers Jul 17 22:18:28 volumio volumio[2283]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:18:28 volumio volumio[2283]: info: [1752790708191] CoreMusicLibrary::Adding element LastFM Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:28 volumio volumio[2283]: Cannot find translation for sourceMedia Servers Jul 17 22:18:28 volumio volumio[2283]: Cannot find translation for sourceRadio Paradise Jul 17 22:18:28 volumio volumio[2283]: Cannot find translation for sourceLastFM Jul 17 22:18:28 volumio volumio[2283]: info: [LastFM] scrobbler initiated! Jul 17 22:18:28 volumio volumio[2283]: info: [LastFM] extended logging: false Jul 17 22:18:28 volumio volumio[2283]: info: [LastFM] try scrobble stream/radio plays: false Jul 17 22:18:28 volumio volumio[2283]: info: [LastFM] Left init routine Jul 17 22:18:28 volumio volumio[2283]: info: [LastFM] Socket already connected: true Jul 17 22:18:28 volumio volumio[2283]: info: Loading i18n strings for locale en Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:18:28 volumio volumio[2283]: info: Volumio Calling Home Jul 17 22:18:28 volumio sudo[2444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 17 22:18:28 volumio sudo[2444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:28 volumio systemd[1]: Reloading. Jul 17 22:18:28 volumio volumio[2283]: info: MPD Permissions set Jul 17 22:18:28 volumio volumio[2283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:28 volumio volumio[2283]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 17 22:18:28 volumio volumio[2283]: info: Starting Shairport Sync Jul 17 22:18:28 volumio volumio[2283]: info: Starting Shairport Sync Jul 17 22:18:28 volumio sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 22:18:28 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:28 volumio volumio[2283]: info: Starting Shairport Sync Jul 17 22:18:28 volumio sudo[2473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 22:18:28 volumio sudo[2312]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:28 volumio sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:28 volumio sudo[2474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 22:18:28 volumio sudo[2474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:28 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 17 22:18:28 volumio mpd[2440]: Jul 17 22:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:18:28 volumio mpd[2440]: Jul 17 22:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:18:28 volumio mpd[2440]: Jul 17 22:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:18:29 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:18:29 volumio sudo[2444]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:29 volumio sudo[2426]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:29 volumio volumio[2283]: info: touch_display: systemctl daemon-reload succeeded. Jul 17 22:18:29 volumio volumio[2283]: error: updateQueue error: null Jul 17 22:18:29 volumio sudo[2480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 17 22:18:29 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 22:18:29 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 22:18:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 22:18:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 22:18:29 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:29 volumio sudo[2473]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:29 volumio volumio[2283]: info: Shairport-Sync Started Jul 17 22:18:29 volumio volumio[2283]: Error adding Membership: Error: addMembership EINVAL Jul 17 22:18:29 volumio volumio[2283]: info: Shairport-Sync Started Jul 17 22:18:29 volumio sudo[2474]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:29 volumio volumio[2283]: info: Shairport-Sync Started Jul 17 22:18:29 volumio systemd[1]: Started Volumio Kiosk. Jul 17 22:18:29 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:29 volumio volumio[2283]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 17 22:18:29 volumio volumio[2283]: info: touch_display: Volumio Kiosk started Jul 17 22:18:29 volumio volumio[2283]: info: CoreCommandRouter::volumioGetState Jul 17 22:18:29 volumio volumio[2283]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:29 volumio volumio[2283]: info: Completed starting Core Plugins Jul 17 22:18:29 volumio volumio[2283]: info: ------------------------------------------- Jul 17 22:18:29 volumio volumio[2283]: info: ----- MyVolumio plugins startup ---- Jul 17 22:18:29 volumio volumio[2283]: info: ------------------------------------------- Jul 17 22:18:29 volumio volumio[2283]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 17 22:18:29 volumio volumio[2283]: info: CoreCommandRouter::volumioGetState Jul 17 22:18:29 volumio volumio[2283]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:29 volumio volumio[2283]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Jul 17 22:18:29 volumio startx[2483]: X.Org X Server 1.20.4 Jul 17 22:18:29 volumio startx[2483]: X Protocol Version 11, Revision 0 Jul 17 22:18:29 volumio startx[2483]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 17 22:18:29 volumio startx[2483]: Current Operating System: Linux volumio 5.10.92-v7l+ #1514 SMP Mon Jan 17 17:38:03 GMT 2022 armv7l Jul 17 22:18:29 volumio startx[2483]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=600 bcm2708_fb.fbswap=1 smsc95xx.macaddr=E4:5F:01:04:EF:55 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Jul 17 22:18:29 volumio startx[2483]: Build Date: 09 November 2022 03:13:10PM Jul 17 22:18:29 volumio startx[2483]: xorg-server 2:1.20.4-1+rpt4+deb10u6 (https://www.debian.org/support) Jul 17 22:18:29 volumio startx[2483]: Current version of pixman: 0.36.0 Jul 17 22:18:29 volumio startx[2483]: Before reporting problems, check http://wiki.x.org Jul 17 22:18:29 volumio startx[2483]: to make sure that you have the latest version. Jul 17 22:18:29 volumio startx[2483]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 17 22:18:29 volumio startx[2483]: (++) from command line, (!!) notice, (II) informational, Jul 17 22:18:29 volumio startx[2483]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 17 22:18:29 volumio startx[2483]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jul 17 22:18:29 2025 Jul 17 22:18:29 volumio volumio[2283]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 17 22:18:29 volumio startx[2483]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 17 22:18:29 volumio startx[2483]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 17 22:18:29 volumio volumio[2283]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 17 22:18:29 volumio ntpd[680]: Listen normally on 3 wlan0 192.168.0.25:123 Jul 17 22:18:29 volumio ntpd[680]: new interface(s) found: waking up resolver Jul 17 22:18:30 volumio nmbd[654]: [2025/07/17 22:18:30.413489, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 17 22:18:30 volumio nmbd[654]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 17 22:18:30 volumio systemd[1]: Started Samba NMB Daemon. Jul 17 22:18:30 volumio systemd[1]: Starting Samba Winbind Daemon... Jul 17 22:18:30 volumio volumio[2283]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 17 22:18:30 volumio winbindd[2526]: [2025/07/17 22:18:30.625834, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 17 22:18:30 volumio winbindd[2526]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 17 22:18:30 volumio winbindd[2526]: [2025/07/17 22:18:30.648755, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 17 22:18:30 volumio winbindd[2526]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 17 22:18:30 volumio systemd[1]: Started Samba Winbind Daemon. Jul 17 22:18:30 volumio systemd[1]: Starting Samba SMB Daemon... Jul 17 22:18:31 volumio systemd[1]: Started Samba SMB Daemon. Jul 17 22:18:31 volumio systemd[1]: Reached target Multi-User System. Jul 17 22:18:31 volumio systemd[1]: Reached target Graphical Interface. Jul 17 22:18:31 volumio smbd[2549]: [2025/07/17 22:18:31.105301, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 17 22:18:31 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 17 22:18:31 volumio smbd[2549]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 17 22:18:31 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 17 22:18:31 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 17 22:18:31 volumio systemd[1]: Startup finished in 9.668s (kernel) + 1min 32.140s (userspace) = 1min 41.809s. Jul 17 22:18:35 volumio sudo[2641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 22:18:35 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:35 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:35 volumio sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 22:18:35 volumio sudo[2643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:35 volumio sudo[2643]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:35 volumio sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 17 22:18:35 volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:35 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 17 22:18:35 volumio sudo[2647]: pam_unix(sudo:session): session closed for user root Jul 17 22:18:35 volumio volumio[2283]: info: Upmpdcli Daemon Started Jul 17 22:18:35 volumio volumio[2649]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 17 22:18:36 volumio volumio[2649]: ............++++ Jul 17 22:18:39 volumio volumio[2283]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 5 Jul 17 22:18:40 volumio volumio[2283]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 6 Jul 17 22:18:41 volumio volumio[2283]: info: CoreCommandRouter::volumioGetState Jul 17 22:18:41 volumio volumio[2283]: info: CorePlayQueue::getTrack 0 Jul 17 22:18:41 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 17 22:18:41 volumio volumio[2283]: info: CoreCommandRouter::volumioGetVisibleSources Jul 17 22:18:41 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:18:41 volumio volumio[2283]: info: Listing playlists Jul 17 22:18:41 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 17 22:18:41 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 17 22:18:41 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 17 22:18:41 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 17 22:18:44 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 17 22:18:49 volumio volumio[2283]: error: MyVolumio Plugin failed to start in a timely fashion Jul 17 22:18:49 volumio volumio[2283]: info: BOOT COMPLETED Jul 17 22:18:49 volumio volumio[2283]: [Metrics] CommandRouter: 25s 376.35ms Jul 17 22:18:49 volumio volumio[2283]: info: CoreCommandRouter::volumiosetStartupVolume Jul 17 22:18:49 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:18:49 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:18:49 volumio volumio[2283]: info: CoreCommandRouter::Close All Modals sent Jul 17 22:18:49 volumio volumio[2283]: info: CoreCommandRouter::Close All Modals sent Jul 17 22:18:50 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 17 22:18:50 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 17 22:18:50 volumio volumio[2283]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 17 22:18:51 volumio volumio[2649]: ..........................................................................................................................................................................++++ Jul 17 22:18:51 volumio volumio[2649]: e is 65537 (0x010001) Jul 17 22:18:51 volumio volumio[2649]: writing RSA key Jul 17 22:18:56 volumio volumio[2283]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:56 volumio volumio[2283]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Jul 17 22:18:56 volumio volumio[2283]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 17 22:18:56 volumio volumio[2283]: errno: -3007, Jul 17 22:18:56 volumio volumio[2283]: code: 'ENOTFOUND', Jul 17 22:18:56 volumio volumio[2283]: syscall: 'getaddrinfo', Jul 17 22:18:56 volumio volumio[2283]: hostname: 'ws.audioscrobbler.com' Jul 17 22:18:56 volumio volumio[2283]: } Jul 17 22:18:56 volumio volumio[2283]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:18:56 volumio sudo[2792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 22:17 Jul 17 22:18:56 volumio sudo[2792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:18:56 volumio sudo[2792]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:34 volumio volumio-remote-updater[477]: [2025-07-17 22:19:34] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Jul 17 22:19:34 volumio volumio-remote-updater[477]: [2025-07-17 22:19:34] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Jul 17 22:19:34 volumio volumio-remote-updater[477]: [2025-07-17 22:19:34] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Jul 17 22:19:34 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 17 22:19:34 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 17 22:19:34 volumio systemd[1]: Started dynamicswap service. Jul 17 22:19:34 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:19:35 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 17 22:19:35 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Jul 17 22:19:35 volumio systemd[1]: Stopped Volumio Backend Module. Jul 17 22:19:35 volumio systemd[1]: Started Volumio Backend Module. Jul 17 22:19:35 volumio systemd[1]: Started dynamicswap service. Jul 17 22:19:35 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 17 22:19:36 volumio volumio[3022]: info: ------------------------------------------- Jul 17 22:19:36 volumio volumio[3022]: info: ----- Volumio3 ---- Jul 17 22:19:36 volumio volumio[3022]: info: ------------------------------------------- Jul 17 22:19:36 volumio volumio[3022]: info: ----- System startup ---- Jul 17 22:19:36 volumio volumio[3022]: info: ------------------------------------------- Jul 17 22:19:36 volumio volumio[3022]: info: MYVOLUMIO Environment detected Jul 17 22:19:36 volumio volumio[3022]: info: Plugin folders cleanup Jul 17 22:19:36 volumio volumio[3022]: info: Scanning into folder /volumio/app/plugins/ Jul 17 22:19:36 volumio volumio[3022]: info: Scanning category audio_interface Jul 17 22:19:36 volumio volumio[3022]: info: Scanning category miscellanea Jul 17 22:19:36 volumio volumio[3022]: info: Scanning category music_service Jul 17 22:19:36 volumio volumio[3022]: info: Scanning category plugins.json Jul 17 22:19:36 volumio volumio[3022]: info: Scanning category system_controller Jul 17 22:19:36 volumio volumio[3022]: info: Scanning category user_interface Jul 17 22:19:36 volumio volumio[3022]: info: Scanning into folder /data/plugins/ Jul 17 22:19:36 volumio volumio[3022]: info: Scanning category music_service Jul 17 22:19:36 volumio volumio[3022]: info: Scanning category user_interface Jul 17 22:19:36 volumio volumio[3022]: info: Plugin folders cleanup completed Jul 17 22:19:36 volumio volumio[3022]: info: ------------------------------------------- Jul 17 22:19:36 volumio volumio[3022]: info: ----- Core plugins startup ---- Jul 17 22:19:36 volumio volumio[3022]: info: ------------------------------------------- Jul 17 22:19:36 volumio volumio[3022]: info: Loading plugins from folder /volumio/app/plugins/ Jul 17 22:19:36 volumio volumio[3022]: info: Adding plugin upnp to MyMusic Plugins Jul 17 22:19:36 volumio volumio[3022]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 17 22:19:36 volumio volumio[3022]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 17 22:19:36 volumio volumio[3022]: info: Loading plugins from folder /data/plugins/ Jul 17 22:19:36 volumio volumio[3022]: info: Loading plugin "system"... Jul 17 22:19:36 volumio volumio[3022]: info: Loading plugin "appearance"... Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "network"... Jul 17 22:19:37 volumio volumio[3022]: info: Refreshing Cached IP Addresses Jul 17 22:19:37 volumio sudo[3046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 22:19:37 volumio sudo[3046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:37 volumio sudo[3046]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "services"... Jul 17 22:19:37 volumio sudo[3048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 22:19:37 volumio sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "alsa_controller"... Jul 17 22:19:37 volumio sudo[3048]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:37 volumio sudo[3051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 17 22:19:37 volumio sudo[3051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:37 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "wizard"... Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "volumio_command_line_client"... Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "upnp"... Jul 17 22:19:37 volumio volumio[3022]: info: [1752790777289] Starting Upmpd Daemon Jul 17 22:19:37 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "my_music"... Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "mpd"... Jul 17 22:19:37 volumio volumio[3022]: info: Creating MPD Configuration file Jul 17 22:19:37 volumio sudo[3057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "upnp_browser"... Jul 17 22:19:37 volumio sudo[3057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:37 volumio sudo[3057]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:37 volumio sudo[3059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:19:37 volumio sudo[3059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:37 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:19:37 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:19:37 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:19:37 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:19:37 volumio volumio[3022]: info: Loading plugin "networkfs"... Jul 17 22:19:37 volumio volumio[3022]: info: Starting Udev Watcher for removable devices Jul 17 22:19:38 volumio volumio[3022]: info: Ignoring mount for partition: boot Jul 17 22:19:38 volumio volumio[3022]: info: Ignoring mount for partition: volumio Jul 17 22:19:38 volumio volumio[3022]: info: Ignoring mount for partition: volumio_data Jul 17 22:19:38 volumio volumio[3022]: info: Mounting Device ADATA_32GB Jul 17 22:19:38 volumio sudo[3070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/ADATA_32GB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:19:38 volumio sudo[3070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:38 volumio sudo[3070]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:38 volumio volumio[3022]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:19:38 volumio volumio[3022]: error: Failed to mount ADATA_32GB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/ADATA_32GB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 17 22:19:38 volumio volumio[3022]: mount: /media/ADATA_32GB: /dev/sda1 already mounted on /media/ADATA_32GB. Jul 17 22:19:38 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "alarm-clock"... Jul 17 22:19:38 volumio mpd[3064]: Jul 17 22:19 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:19:38 volumio mpd[3064]: Jul 17 22:19 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:19:38 volumio mpd[3064]: Jul 17 22:19 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:19:38 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:19:38 volumio sudo[3059]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "airplay_emulation"... Jul 17 22:19:38 volumio volumio[3022]: info: Starting Shairport Sync Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "last_100"... Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "webradio"... Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "i2s_dacs"... Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "volumiodiscovery"... Jul 17 22:19:38 volumio volumio[3022]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:19:38 volumio volumio[3022]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:19:38 volumio volumio[3022]: *** WARNING *** For more information see Jul 17 22:19:38 volumio node[3022]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 17 22:19:38 volumio volumio[3022]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:19:38 volumio volumio[3022]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:19:38 volumio volumio[3022]: *** WARNING *** For more information see Jul 17 22:19:38 volumio node[3022]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:19:38 volumio node[3022]: *** WARNING *** For more information see Jul 17 22:19:38 volumio node[3022]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 17 22:19:38 volumio node[3022]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 17 22:19:38 volumio node[3022]: *** WARNING *** For more information see Jul 17 22:19:38 volumio volumio[3022]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 17 22:19:38 volumio volumio[3022]: info: Discovery: Started advertising with name: Volumio Jul 17 22:19:38 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "outputs"... Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "albumart"... Jul 17 22:19:38 volumio volumio[3022]: info: Plugin example_plugin is not enabled Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "inputs"... Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "updater_comm"... Jul 17 22:19:38 volumio volumio[3022]: info: Plugin mpdemulation is not enabled Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "rest_api"... Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "websocket"... Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "radio_paradise"... Jul 17 22:19:38 volumio volumio[3022]: Forking 3 albumart workers Jul 17 22:19:38 volumio volumio[3022]: info: Applying required configuration parameters for plugin radio_paradise Jul 17 22:19:38 volumio volumio[3022]: info: [1752790778838] [RadioParadise] API delay: 5 Jul 17 22:19:38 volumio volumio[3022]: info: Loading plugin "Systeminfo"... Jul 17 22:19:39 volumio volumio[3022]: info: Loading plugin "lastfm"... Jul 17 22:19:39 volumio volumio[3022]: Starting albumart workers Jul 17 22:19:39 volumio volumio[3022]: Starting albumart workers Jul 17 22:19:39 volumio volumio[3022]: Starting albumart workers Jul 17 22:19:39 volumio volumio[3022]: info: Loading plugin "touch_display"... Jul 17 22:19:39 volumio volumio[3022]: info: Applying required configuration parameters for plugin touch_display Jul 17 22:19:39 volumio volumio[3022]: info: Loading i18n strings for locale en Jul 17 22:19:39 volumio volumio[3022]: Updating browse sources language Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::initPlayerControls Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:19:39 volumio volumio[3022]: Express server listening on port 3000 Jul 17 22:19:39 volumio volumio[3022]: [Metrics] WebUI: 4s 81.13ms Jul 17 22:19:39 volumio volumio-remote-updater[477]: [2025-07-17 22:19:39] [connect] Successful connection Jul 17 22:19:39 volumio volumio[3022]: info: CoreStateMachine::resetVolumioState Jul 17 22:19:39 volumio volumio[3022]: info: CoreStateMachine::getcurrentVolume Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::volumioRetrievevolume Jul 17 22:19:39 volumio volumio[3022]: info: CoreStateMachine::pushState Jul 17 22:19:39 volumio volumio[3022]: info: CorePlayQueue::getTrack 0 Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:19:39 volumio volumio[3022]: info: CoreCommandRouter::volumioPushState Jul 17 22:19:39 volumio volumio[3022]: info: MPD Permissions set Jul 17 22:19:39 volumio volumio[3022]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 1 Jul 17 22:19:40 volumio volumio-remote-updater[477]: [2025-07-17 22:19:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752790779 101 Jul 17 22:19:40 volumio volumio[3022]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Jul 17 22:19:40 volumio volumio[3022]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jul 17 22:19:40 volumio volumio[3022]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jul 17 22:19:40 volumio volumio[3022]: info: MPD running with PID3064 Jul 17 22:19:40 volumio volumio[3022]: ,establishing connection Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: Reloading queue from file Jul 17 22:19:40 volumio volumio[3022]: error: updateQueue error: null Jul 17 22:19:40 volumio volumio[3022]: info: CoreStateMachine::setRepeat true single undefined Jul 17 22:19:40 volumio volumio[3022]: info: CoreStateMachine::pushState Jul 17 22:19:40 volumio volumio[3022]: info: CorePlayQueue::getTrack 0 Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::volumioPushState Jul 17 22:19:40 volumio volumio[3022]: info: CoreStateMachine::setRandom false Jul 17 22:19:40 volumio volumio[3022]: info: CoreStateMachine::pushState Jul 17 22:19:40 volumio volumio[3022]: info: CorePlayQueue::getTrack 0 Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::volumioPushState Jul 17 22:19:40 volumio volumio[3022]: info: Setting Device type: Raspberry PI Jul 17 22:19:40 volumio volumio[3022]: info: Completed loading Core Plugins Jul 17 22:19:40 volumio volumio[3022]: info: Preparing to generate the ALSA configuration file Jul 17 22:19:40 volumio volumio[3022]: error: updateQueue error: null Jul 17 22:19:40 volumio volumio[3022]: info: Asound.conf file unchanged, so no further update is needed Jul 17 22:19:40 volumio volumio[3022]: info: Output device has changed, restarting MPD Jul 17 22:19:40 volumio sudo[3143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 22:19:40 volumio sudo[3143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:40 volumio volumio[3022]: info: Output device has changed, restarting Shairport Sync Jul 17 22:19:40 volumio sudo[3143]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:19:40 volumio sudo[3146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 22:19:40 volumio sudo[3146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:40 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 22:19:40 volumio volumio[3022]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:19:40 volumio volumio[3022]: info: ___________ START PLUGINS ___________ Jul 17 22:19:40 volumio systemd[1]: mpd.service: Succeeded. Jul 17 22:19:40 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:19:40 volumio volumio[3022]: info: [1752790780522] CoreMusicLibrary::Adding element Media Servers Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:19:40 volumio volumio[3022]: Cannot find translation for sourceMedia Servers Jul 17 22:19:40 volumio systemd[1]: Starting Music Player Daemon... Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:19:40 volumio volumio[3022]: info: [1752790780622] CoreMusicLibrary::Adding element Last_100 Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:19:40 volumio volumio[3022]: Cannot find translation for sourceMedia Servers Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:19:40 volumio volumio[3022]: info: [1752790780624] CoreMusicLibrary::Adding element Webradio Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:19:40 volumio volumio[3022]: Cannot find translation for sourceMedia Servers Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:19:40 volumio volumio[3022]: info: [1752790780635] CoreMusicLibrary::Adding element Radio Paradise Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:19:40 volumio volumio[3022]: Cannot find translation for sourceMedia Servers Jul 17 22:19:40 volumio volumio[3022]: Cannot find translation for sourceRadio Paradise Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 17 22:19:40 volumio volumio[3022]: info: [1752790780638] CoreMusicLibrary::Adding element LastFM Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:19:40 volumio volumio[3022]: Cannot find translation for sourceMedia Servers Jul 17 22:19:40 volumio volumio[3022]: Cannot find translation for sourceRadio Paradise Jul 17 22:19:40 volumio volumio[3022]: Cannot find translation for sourceLastFM Jul 17 22:19:40 volumio volumio[3022]: info: [LastFM] scrobbler initiated! Jul 17 22:19:40 volumio volumio[3022]: info: [LastFM] extended logging: false Jul 17 22:19:40 volumio volumio[3022]: info: [LastFM] try scrobble stream/radio plays: false Jul 17 22:19:40 volumio volumio[3022]: info: [LastFM] Left init routine Jul 17 22:19:40 volumio volumio[3022]: info: [LastFM] Socket already connected: true Jul 17 22:19:40 volumio volumio[3022]: info: Loading i18n strings for locale en Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 22:19:40 volumio volumio[3022]: info: Volumio Calling Home Jul 17 22:19:40 volumio sudo[3164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 17 22:19:40 volumio sudo[3164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:40 volumio systemd[1]: Reloading. Jul 17 22:19:40 volumio volumio[3022]: info: MPD Permissions set Jul 17 22:19:40 volumio sudo[3051]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 22:19:40 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 22:19:41 volumio volumio[3022]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 17 22:19:41 volumio volumio[3022]: info: Starting Shairport Sync Jul 17 22:19:41 volumio volumio[3022]: info: Starting Shairport Sync Jul 17 22:19:41 volumio sudo[3188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 22:19:41 volumio sudo[3188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:41 volumio volumio[3022]: info: Starting Shairport Sync Jul 17 22:19:41 volumio sudo[3192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 22:19:41 volumio sudo[3193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 22:19:41 volumio sudo[3193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:41 volumio sudo[3192]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:41 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 17 22:19:41 volumio mpd[3159]: Jul 17 22:19 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 22:19:41 volumio mpd[3159]: Jul 17 22:19 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 17 22:19:41 volumio mpd[3159]: Jul 17 22:19 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 17 22:19:41 volumio systemd[1]: Started Music Player Daemon. Jul 17 22:19:41 volumio sudo[3164]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:41 volumio sudo[3146]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:41 volumio volumio[3022]: info: touch_display: systemctl daemon-reload succeeded. Jul 17 22:19:41 volumio volumio[3022]: error: updateQueue error: null Jul 17 22:19:41 volumio sudo[3199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 17 22:19:41 volumio sudo[3199]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 22:19:41 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 22:19:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 22:19:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 22:19:41 volumio sudo[3193]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:41 volumio sudo[3192]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:41 volumio volumio[3022]: info: Shairport-Sync Started Jul 17 22:19:41 volumio volumio[3022]: Error adding Membership: Error: addMembership EINVAL Jul 17 22:19:41 volumio sudo[3188]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:41 volumio volumio[3022]: info: Shairport-Sync Started Jul 17 22:19:41 volumio volumio[3022]: info: Shairport-Sync Started Jul 17 22:19:41 volumio sudo[3199]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:41 volumio volumio[3022]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 17 22:19:41 volumio volumio[3022]: info: touch_display: Volumio Kiosk started Jul 17 22:19:41 volumio volumio[3022]: info: CoreCommandRouter::volumioGetState Jul 17 22:19:41 volumio volumio[3022]: info: CorePlayQueue::getTrack 0 Jul 17 22:19:41 volumio volumio[3022]: info: Completed starting Core Plugins Jul 17 22:19:41 volumio volumio[3022]: info: ------------------------------------------- Jul 17 22:19:41 volumio volumio[3022]: info: ----- MyVolumio plugins startup ---- Jul 17 22:19:41 volumio volumio[3022]: info: ------------------------------------------- Jul 17 22:19:41 volumio volumio[3022]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 17 22:19:41 volumio volumio[3022]: info: CoreCommandRouter::volumioGetState Jul 17 22:19:41 volumio volumio[3022]: info: CorePlayQueue::getTrack 0 Jul 17 22:19:41 volumio volumio[3022]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 17 22:19:41 volumio volumio[3022]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 17 22:19:41 volumio volumio[3022]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 17 22:19:43 volumio volumio[3022]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 5 Jul 17 22:19:43 volumio volumio[3022]: info: CoreCommandRouter::volumioGetState Jul 17 22:19:43 volumio volumio[3022]: info: CorePlayQueue::getTrack 0 Jul 17 22:19:43 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 17 22:19:43 volumio volumio[3022]: info: CoreCommandRouter::volumioGetVisibleSources Jul 17 22:19:43 volumio volumio[3022]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 22:19:43 volumio volumio[3022]: info: Listing playlists Jul 17 22:19:43 volumio ntpd[680]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 17 22:19:47 volumio sudo[3211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 22:19:47 volumio sudo[3211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:47 volumio sudo[3211]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:47 volumio sudo[3214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 22:19:47 volumio sudo[3214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:47 volumio sudo[3214]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:47 volumio sudo[3217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 17 22:19:47 volumio sudo[3217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 22:19:47 volumio sudo[3217]: pam_unix(sudo:session): session closed for user root Jul 17 22:19:47 volumio volumio[3022]: info: Upmpdcli Daemon Started Jul 17 22:19:58 volumio volumio[3022]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:19:58 volumio volumio[3022]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Jul 17 22:19:58 volumio volumio[3022]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 17 22:19:58 volumio volumio[3022]: errno: -3007, Jul 17 22:19:58 volumio volumio[3022]: code: 'ENOTFOUND', Jul 17 22:19:58 volumio volumio[3022]: syscall: 'getaddrinfo', Jul 17 22:19:58 volumio volumio[3022]: hostname: 'ws.audioscrobbler.com' Jul 17 22:19:58 volumio volumio[3022]: } Jul 17 22:19:58 volumio volumio[3022]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 22:19:59 volumio sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 22:18 Jul 17 22:19:59 volumio sudo[3229]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"