Oct 17 12:53:00 volumio volumio[29676]: Forking 3 albumart workers Oct 17 12:53:00 volumio volumio[29614]: info: Loading i18n strings for locale en Oct 17 12:53:00 volumio volumio[29614]: Updating browse sources language Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: Express server listening on port 3000 Oct 17 12:53:00 volumio volumio[29614]: [Metrics] WebUI: 3s 307.95ms Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:00 volumio volumio[29614]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:00 volumio volumio[29614]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::pushState Oct 17 12:53:00 volumio volumio[29614]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:00 volumio volumio[29614]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:00 volumio volumio[29614]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: Reloading queue from file Oct 17 12:53:00 volumio volumio[29614]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::pushState Oct 17 12:53:00 volumio volumio[29614]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::pushState Oct 17 12:53:00 volumio volumio[29614]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::setRandom null Oct 17 12:53:00 volumio volumio[29614]: info: CoreStateMachine::pushState Oct 17 12:53:00 volumio volumio[29614]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:00 volumio volumio[29614]: info: Setting Device type: Raspberry PI Oct 17 12:53:00 volumio volumio[29614]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:00 volumio volumio[29614]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:00 volumio volumio[29614]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:00 volumio volumio[29614]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:00 volumio volumio[29614]: info: Completed loading Core Plugins Oct 17 12:53:00 volumio volumio[29614]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:00 volumio volumio[29614]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:00 volumio volumio[29614]: info: Reading ALSA contributions from plugins. Oct 17 12:53:00 volumio volumio[29614]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:00 volumio volumio[29614]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:00 volumio volumio[29614]: info: Discovery: Found device Volumioffice Oct 17 12:53:00 volumio volumio[29614]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:00 volumio volumio[29614]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:00 volumio volumio[29686]: Starting albumart workers Oct 17 12:53:00 volumio volumio-remote-updater[703]: [2026-10-17 12:53:00] [connect] Successful connection Oct 17 12:53:00 volumio sudo[29651]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:00 volumio volumio[29687]: Starting albumart workers Oct 17 12:53:00 volumio volumio[29614]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:00 volumio volumio[29688]: Starting albumart workers Oct 17 12:53:00 volumio volumio[29614]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:00 volumio volumio[29614]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:00 volumio volumio[29614]: info: Output device has changed, restarting MPD Oct 17 12:53:00 volumio volumio[29614]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:00 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:00 volumio sudo[29740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:00 volumio sudo[29740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:01 volumio sudo[29740]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:01 volumio sudo[29743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:01 volumio sudo[29743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:01 volumio volumio[29614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:01 volumio volumio[29614]: info: ___________ START PLUGINS ___________ Oct 17 12:53:01 volumio volumio[29614]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:01 volumio volumio[29614]: info: Creating MPD Configuration file Oct 17 12:53:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:01 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:01 volumio volumio[29614]: info: [1792201981094] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:01 volumio sudo[29751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:01 volumio sudo[29751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:01 volumio volumio[29614]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:01 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:01 volumio systemd[1]: mpd.service: Consumed 1.637s CPU time. Oct 17 12:53:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:01 volumio sudo[29754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:01 volumio sudo[29754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:01 volumio volumio[29614]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:01 volumio sudo[29752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:01 volumio sudo[29752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:01 volumio sudo[29752]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:01 volumio volumio[29614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:01 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:01 volumio volumio[29614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:01 volumio volumio[29614]: info: [1792201981266] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:01 volumio volumio[29614]: info: [1792201981271] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:01 volumio sudo[29751]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:01 volumio volumio[29614]: info: Initializing BBC Radios Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:01 volumio volumio[29614]: info: [1792201981418] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:01 volumio sudo[29771]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:01 volumio volumio[29614]: Cannot find translation for source LastFM Oct 17 12:53:01 volumio sudo[29771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:01 volumio volumio[29614]: info: [LastFM] scrobbler initiated! Oct 17 12:53:01 volumio volumio[29614]: info: [LastFM] extended logging: false Oct 17 12:53:01 volumio volumio[29614]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:01 volumio sudo[29771]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:01 volumio volumio[29614]: info: [LastFM] Left init routine Oct 17 12:53:01 volumio volumio[29614]: info: [LastFM] Socket already connected: true Oct 17 12:53:01 volumio volumio[29614]: info: Volumio Calling Home Oct 17 12:53:01 volumio volumio[29614]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:01 volumio volumio[29614]: info: Discovery: Found device Volumio Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:01 volumio volumio[29614]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:01 volumio volumio-remote-updater[703]: [2026-10-17 12:53:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792201980 101 Oct 17 12:53:01 volumio volumio[29614]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:01 volumio volumio[29614]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:01 volumio volumio[29614]: info: Discovery: Found device Volumio Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:01 volumio volumio[29614]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:01 volumio volumio[29614]: info: Received Get System Info Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:01 volumio volumio[29614]: info: Discovery: Getting this device information Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:01 volumio volumio[29614]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:01 volumio volumio[29614]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:01 volumio volumio[29614]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:01 volumio volumio[29614]: Error: connect ECONNREFUSED 34.49.30.2:80 Oct 17 12:53:01 volumio volumio[29614]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 12:53:01 volumio volumio[29614]: errno: -111, Oct 17 12:53:01 volumio volumio[29614]: code: 'ECONNREFUSED', Oct 17 12:53:01 volumio volumio[29614]: syscall: 'connect', Oct 17 12:53:01 volumio volumio[29614]: address: '34.49.30.2', Oct 17 12:53:01 volumio volumio[29614]: port: 80 Oct 17 12:53:01 volumio volumio[29614]: } Oct 17 12:53:01 volumio volumio[29614]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:02 volumio sudo[29822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:52' Oct 17 12:53:02 volumio sudo[29822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:02 volumio sudo[29822]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:02 volumio volumio-remote-updater[703]: [2026-10-17 12:53:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 17 12:53:02 volumio volumio-remote-updater[703]: [2026-10-17 12:53:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 17 12:53:02 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:02.603+11:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:43272->127.0.0.1:3000: read: connection reset by peer" Oct 17 12:53:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 12:53:02 volumio sudo[29754]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:02 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:02.605+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:02 volumio sudo[29743]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 12:53:02 volumio systemd[1]: volumio.service: Consumed 11.821s CPU time. Oct 17 12:53:02 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:02 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 109. Oct 17 12:53:02 volumio mpd[29787]: 2026-10-17T12:53:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:02 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:02 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 12:53:02 volumio systemd[1]: volumio.service: Consumed 11.821s CPU time. Oct 17 12:53:02 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 12:53:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:02 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:03 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:03 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:03.607+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:03 volumio volumio[29839]: info: ------------------------------------------- Oct 17 12:53:03 volumio volumio[29839]: info: ----- Volumio3 ---- Oct 17 12:53:03 volumio volumio[29839]: info: ------------------------------------------- Oct 17 12:53:03 volumio volumio[29839]: info: ----- System startup ---- Oct 17 12:53:03 volumio volumio[29839]: info: ------------------------------------------- Oct 17 12:53:04 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:04.237+11:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to check if setup wizard should be shown: could not get wizard status: context deadline exceeded" Oct 17 12:53:04 volumio volumio[29839]: info: MYVOLUMIO Environment detected Oct 17 12:53:04 volumio volumio[29839]: info: Plugin folders cleanup Oct 17 12:53:04 volumio volumio[29839]: info: Scanning into folder /volumio/app/plugins/ Oct 17 12:53:04 volumio volumio[29839]: info: Scanning category audio_interface Oct 17 12:53:04 volumio volumio[29839]: info: Scanning category miscellanea Oct 17 12:53:04 volumio volumio[29839]: info: Scanning category music_service Oct 17 12:53:04 volumio volumio[29839]: info: Scanning category plugins.json Oct 17 12:53:04 volumio volumio[29839]: info: Scanning category system_controller Oct 17 12:53:04 volumio volumio[29839]: info: Scanning category user_interface Oct 17 12:53:04 volumio volumio[29839]: info: Scanning into folder /data/plugins/ Oct 17 12:53:04 volumio volumio[29839]: info: Scanning category user_interface Oct 17 12:53:04 volumio volumio[29839]: info: Plugin folders cleanup completed Oct 17 12:53:04 volumio volumio[29839]: info: ------------------------------------------- Oct 17 12:53:04 volumio volumio[29839]: info: ----- Core plugins startup ---- Oct 17 12:53:04 volumio volumio[29839]: info: ------------------------------------------- Oct 17 12:53:04 volumio volumio[29839]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 12:53:04 volumio volumio[29839]: info: Adding plugin upnp to MyMusic Plugins Oct 17 12:53:04 volumio volumio[29839]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 12:53:04 volumio volumio[29839]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 12:53:04 volumio volumio[29839]: info: Loading plugins from folder /data/plugins/ Oct 17 12:53:04 volumio volumio[29839]: info: Loading plugin "system"... Oct 17 12:53:04 volumio volumio[29839]: info: Loading plugin "appearance"... Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "network"... Oct 17 12:53:05 volumio volumio[29839]: info: Refreshing Cached IP Addresses Oct 17 12:53:05 volumio sudo[29867]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:05 volumio sudo[29867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:05 volumio sudo[29869]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:05 volumio sudo[29867]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:05 volumio sudo[29869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "services"... Oct 17 12:53:05 volumio sudo[29869]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "volumio5onboarding"... Oct 17 12:53:05 volumio sudo[29877]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 12:53:05 volumio sudo[29877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "alsa_controller"... Oct 17 12:53:05 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "wizard"... Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "networkfs"... Oct 17 12:53:05 volumio volumio[29839]: info: Starting Udev Watcher for removable devices Oct 17 12:53:05 volumio volumio[29839]: info: Ignoring mount for partition: boot Oct 17 12:53:05 volumio volumio[29839]: info: Ignoring mount for partition: volumio Oct 17 12:53:05 volumio volumio[29839]: info: Ignoring mount for partition: volumio_data Oct 17 12:53:05 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "volumio_command_line_client"... Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "upnp"... Oct 17 12:53:05 volumio volumio[29839]: info: [1792201985182] Starting Upmpd Daemon Oct 17 12:53:05 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "my_music"... Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "mpd"... Oct 17 12:53:05 volumio volumio[29839]: info: Loading plugin "upnp_browser"... Oct 17 12:53:06 volumio volumio[29839]: info: Starting UPNP Browser Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "alarm-clock"... Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "novaui"... Oct 17 12:53:06 volumio volumio[29839]: info: Applying required configuration parameters for plugin novaui Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "airplay_emulation"... Oct 17 12:53:06 volumio volumio[29839]: info: Starting Shairport Sync Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "last_100"... Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "webradio"... Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "i2s_dacs"... Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "volumiodiscovery"... Oct 17 12:53:06 volumio volumio[29839]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:06 volumio node[29839]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:06 volumio volumio[29839]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:06 volumio node[29839]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:06 volumio volumio[29839]: *** WARNING *** For more information see Oct 17 12:53:06 volumio node[29839]: *** WARNING *** For more information see Oct 17 12:53:06 volumio volumio[29839]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:06 volumio node[29839]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:06 volumio volumio[29839]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:06 volumio node[29839]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:06 volumio volumio[29839]: *** WARNING *** For more information see Oct 17 12:53:06 volumio node[29839]: *** WARNING *** For more information see Oct 17 12:53:06 volumio volumio[29839]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 12:53:06 volumio volumio[29839]: info: Discovery: Started advertising with name: Volumio Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "outputs"... Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "albumart"... Oct 17 12:53:06 volumio volumio[29839]: info: Plugin example_plugin is not enabled Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "inputs"... Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "updater_comm"... Oct 17 12:53:06 volumio volumio[29839]: info: Plugin mpdemulation is not enabled Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "rest_api"... Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "websocket"... Oct 17 12:53:06 volumio volumio[29839]: info: Starting Socket.io Server version 1.7.4 Oct 17 12:53:06 volumio volumio[29839]: info: Loading plugin "lastfm"... Oct 17 12:53:06 volumio volumio[29900]: Forking 3 albumart workers Oct 17 12:53:06 volumio volumio[29839]: info: Loading i18n strings for locale en Oct 17 12:53:06 volumio volumio[29839]: Updating browse sources language Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:06 volumio volumio[29839]: Express server listening on port 3000 Oct 17 12:53:06 volumio volumio[29839]: [Metrics] WebUI: 3s 303.76ms Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:07 volumio volumio[29839]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:07 volumio volumio[29839]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::pushState Oct 17 12:53:07 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:07 volumio volumio[29839]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:07 volumio volumio[29839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:07 volumio volumio[29839]: info: Reloading queue from file Oct 17 12:53:07 volumio volumio[29839]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::pushState Oct 17 12:53:07 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::pushState Oct 17 12:53:07 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::setRandom null Oct 17 12:53:07 volumio volumio[29839]: info: CoreStateMachine::pushState Oct 17 12:53:07 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:07 volumio volumio[29839]: info: Setting Device type: Raspberry PI Oct 17 12:53:07 volumio volumio[29839]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:07 volumio volumio[29839]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:07 volumio volumio[29839]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:07 volumio volumio[29839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:07 volumio volumio[29839]: info: Completed loading Core Plugins Oct 17 12:53:07 volumio volumio[29839]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:07 volumio volumio[29839]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:07 volumio volumio[29839]: info: Reading ALSA contributions from plugins. Oct 17 12:53:07 volumio volumio[29839]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:07 volumio volumio[29839]: info: Discovery: Found device Volumioffice Oct 17 12:53:07 volumio volumio[29839]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:07 volumio volumio[29839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:07 volumio volumio[29839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:07 volumio volumio[29839]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:07 volumio volumio[29912]: Starting albumart workers Oct 17 12:53:07 volumio volumio[29839]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:07 volumio volumio[29911]: Starting albumart workers Oct 17 12:53:07 volumio volumio[29839]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:07 volumio volumio[29839]: info: Output device has changed, restarting MPD Oct 17 12:53:07 volumio sudo[29877]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:07 volumio volumio[29839]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:07 volumio volumio-remote-updater[703]: [2026-10-17 12:53:07] [connect] Successful connection Oct 17 12:53:07 volumio sudo[29964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:07 volumio sudo[29964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:07 volumio sudo[29966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:07 volumio sudo[29964]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:07 volumio sudo[29966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:07 volumio volumio[29839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:07 volumio volumio[29839]: info: ___________ START PLUGINS ___________ Oct 17 12:53:07 volumio volumio[29839]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:07 volumio volumio[29839]: info: Creating MPD Configuration file Oct 17 12:53:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:07 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:07 volumio volumio[29839]: info: [1792201987697] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:07 volumio sudo[29975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:07 volumio sudo[29975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:07 volumio sudo[29976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:07 volumio volumio[29839]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:07 volumio sudo[29976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:07 volumio sudo[29976]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:07 volumio sudo[29978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:07 volumio sudo[29978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:07 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:07 volumio systemd[1]: mpd.service: Consumed 1.528s CPU time. Oct 17 12:53:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:07 volumio volumio[29839]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:07 volumio volumio[29839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:07 volumio volumio[29839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:07 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:07 volumio volumio[29839]: info: [1792201987860] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:07 volumio volumio[29839]: info: [1792201987862] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:07 volumio volumio[29910]: Starting albumart workers Oct 17 12:53:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:07 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:07 volumio volumio[29839]: info: Initializing BBC Radios Oct 17 12:53:07 volumio sudo[29975]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:08 volumio volumio[29839]: info: [1792201988012] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:08 volumio volumio[29839]: Cannot find translation for source LastFM Oct 17 12:53:08 volumio volumio[29839]: info: [LastFM] scrobbler initiated! Oct 17 12:53:08 volumio volumio[29839]: info: [LastFM] extended logging: false Oct 17 12:53:08 volumio volumio[29839]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:08 volumio sudo[29995]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:08 volumio sudo[29995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:08 volumio sudo[29995]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:08 volumio volumio[29839]: info: [LastFM] Left init routine Oct 17 12:53:08 volumio volumio[29839]: info: [LastFM] Socket already connected: false Oct 17 12:53:08 volumio volumio[29839]: info: Volumio Calling Home Oct 17 12:53:08 volumio volumio[29839]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:08 volumio volumio[29839]: info: Discovery: Found device Volumio Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:08 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:08 volumio volumio[29839]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:08 volumio volumio[29839]: info: Discovery: Found device Volumio Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:08 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:08 volumio volumio[29839]: info: Received Get System Info Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:08 volumio volumio[29839]: info: Discovery: Getting this device information Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:08 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:08 volumio volumio[29839]: info: Received Get System Version Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:08 volumio volumio[29839]: info: Received Get System Info Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:08 volumio volumio[29839]: info: Discovery: Getting this device information Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:08 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 17 12:53:08 volumio sudo[30034]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 17 12:53:08 volumio sudo[30034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:08 volumio sudo[30034]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:08 volumio sudo[30043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:08 volumio volumio[29839]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:08 volumio volumio[29839]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:08 volumio sudo[30043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:08 volumio sudo[30040]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:08 volumio volumio[29839]: info: CoreCommandRouter::volumioGetQueue Oct 17 12:53:08 volumio volumio[29839]: info: CoreStateMachine::getQueue Oct 17 12:53:08 volumio volumio[29839]: info: CorePlayQueue::getQueue Oct 17 12:53:08 volumio sudo[30043]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:08 volumio sudo[30050]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:08 volumio sudo[30050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:08 volumio sudo[30040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:08 volumio sudo[30057]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:08 volumio sudo[30057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:08 volumio sudo[30050]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:08 volumio sudo[30040]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:08 volumio sudo[30057]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:08 volumio sudo[30058]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:08 volumio sudo[30058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:08 volumio sudo[30058]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:08 volumio volumio[29839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:08 volumio volumio[29839]: Error: connect ECONNREFUSED 34.49.30.2:80 Oct 17 12:53:08 volumio volumio[29839]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 12:53:08 volumio volumio[29839]: errno: -111, Oct 17 12:53:08 volumio volumio[29839]: code: 'ECONNREFUSED', Oct 17 12:53:08 volumio volumio[29839]: syscall: 'connect', Oct 17 12:53:08 volumio volumio[29839]: address: '34.49.30.2', Oct 17 12:53:08 volumio volumio[29839]: port: 80 Oct 17 12:53:08 volumio volumio[29839]: } Oct 17 12:53:08 volumio volumio[29839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:09 volumio sudo[30079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:52' Oct 17 12:53:09 volumio sudo[30079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:09 volumio sudo[30079]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:09 volumio volumio-remote-updater[703]: [2026-10-17 12:53:09] [error] handle_read_http_response error: asio.system:104 (Connection reset by peer) Oct 17 12:53:09 volumio volumio-remote-updater[703]: [2026-10-17 12:53:09] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 17 12:53:09 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:09.432+11:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:43374->127.0.0.1:3000: read: connection reset by peer" Oct 17 12:53:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 12:53:09 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:09.437+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:09 volumio sudo[29966]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:09 volumio sudo[29978]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 12:53:09 volumio systemd[1]: volumio.service: Consumed 12.324s CPU time. Oct 17 12:53:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 110. Oct 17 12:53:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:09 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 12:53:09 volumio systemd[1]: volumio.service: Consumed 12.324s CPU time. Oct 17 12:53:09 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 12:53:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:09 volumio mpd[30016]: 2026-10-17T12:53:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:09 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:09 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:10 volumio volumio[30095]: info: ------------------------------------------- Oct 17 12:53:10 volumio volumio[30095]: info: ----- Volumio3 ---- Oct 17 12:53:10 volumio volumio[30095]: info: ------------------------------------------- Oct 17 12:53:10 volumio volumio[30095]: info: ----- System startup ---- Oct 17 12:53:10 volumio volumio[30095]: info: ------------------------------------------- Oct 17 12:53:11 volumio volumio[30095]: info: MYVOLUMIO Environment detected Oct 17 12:53:11 volumio volumio[30095]: info: Plugin folders cleanup Oct 17 12:53:11 volumio volumio[30095]: info: Scanning into folder /volumio/app/plugins/ Oct 17 12:53:11 volumio volumio[30095]: info: Scanning category audio_interface Oct 17 12:53:11 volumio volumio[30095]: info: Scanning category miscellanea Oct 17 12:53:11 volumio volumio[30095]: info: Scanning category music_service Oct 17 12:53:11 volumio volumio[30095]: info: Scanning category plugins.json Oct 17 12:53:11 volumio volumio[30095]: info: Scanning category system_controller Oct 17 12:53:11 volumio volumio[30095]: info: Scanning category user_interface Oct 17 12:53:11 volumio volumio[30095]: info: Scanning into folder /data/plugins/ Oct 17 12:53:11 volumio volumio[30095]: info: Scanning category user_interface Oct 17 12:53:11 volumio volumio[30095]: info: Plugin folders cleanup completed Oct 17 12:53:11 volumio volumio[30095]: info: ------------------------------------------- Oct 17 12:53:11 volumio volumio[30095]: info: ----- Core plugins startup ---- Oct 17 12:53:11 volumio volumio[30095]: info: ------------------------------------------- Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 12:53:11 volumio volumio[30095]: info: Adding plugin upnp to MyMusic Plugins Oct 17 12:53:11 volumio volumio[30095]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 12:53:11 volumio volumio[30095]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugins from folder /data/plugins/ Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "system"... Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "appearance"... Oct 17 12:53:11 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:11.334+11:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to check if setup wizard should be shown: could not get wizard status: context deadline exceeded" Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "network"... Oct 17 12:53:11 volumio volumio[30095]: info: Refreshing Cached IP Addresses Oct 17 12:53:11 volumio sudo[30125]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:11 volumio sudo[30125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:11 volumio sudo[30127]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "services"... Oct 17 12:53:11 volumio sudo[30127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:11 volumio sudo[30125]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "volumio5onboarding"... Oct 17 12:53:11 volumio sudo[30127]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "alsa_controller"... Oct 17 12:53:11 volumio sudo[30139]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 12:53:11 volumio sudo[30139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:11 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "wizard"... Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "networkfs"... Oct 17 12:53:11 volumio volumio[30095]: info: Starting Udev Watcher for removable devices Oct 17 12:53:11 volumio volumio[30095]: info: Ignoring mount for partition: boot Oct 17 12:53:11 volumio volumio[30095]: info: Ignoring mount for partition: volumio Oct 17 12:53:11 volumio volumio[30095]: info: Ignoring mount for partition: volumio_data Oct 17 12:53:11 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "volumio_command_line_client"... Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "upnp"... Oct 17 12:53:11 volumio volumio[30095]: info: [1792201991848] Starting Upmpd Daemon Oct 17 12:53:11 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "my_music"... Oct 17 12:53:11 volumio volumio[30095]: info: Loading plugin "mpd"... Oct 17 12:53:12 volumio volumio[30095]: info: Loading plugin "upnp_browser"... Oct 17 12:53:12 volumio volumio[30095]: info: Starting UPNP Browser Oct 17 12:53:12 volumio volumio[30095]: info: Loading plugin "alarm-clock"... Oct 17 12:53:12 volumio volumio[30095]: info: Loading plugin "novaui"... Oct 17 12:53:12 volumio volumio[30095]: info: Applying required configuration parameters for plugin novaui Oct 17 12:53:12 volumio volumio[30095]: info: Loading plugin "airplay_emulation"... Oct 17 12:53:12 volumio volumio[30095]: info: Starting Shairport Sync Oct 17 12:53:12 volumio volumio[30095]: info: Loading plugin "last_100"... Oct 17 12:53:12 volumio volumio[30095]: info: Loading plugin "webradio"... Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "i2s_dacs"... Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "volumiodiscovery"... Oct 17 12:53:13 volumio volumio[30095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:13 volumio volumio[30095]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:13 volumio node[30095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:13 volumio volumio[30095]: *** WARNING *** For more information see Oct 17 12:53:13 volumio volumio[30095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:13 volumio volumio[30095]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:13 volumio volumio[30095]: *** WARNING *** For more information see Oct 17 12:53:13 volumio node[30095]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:13 volumio node[30095]: *** WARNING *** For more information see Oct 17 12:53:13 volumio node[30095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:13 volumio node[30095]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:13 volumio node[30095]: *** WARNING *** For more information see Oct 17 12:53:13 volumio volumio[30095]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 12:53:13 volumio volumio[30095]: info: Discovery: Started advertising with name: Volumio Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "outputs"... Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "albumart"... Oct 17 12:53:13 volumio volumio[30095]: info: Plugin example_plugin is not enabled Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "inputs"... Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "updater_comm"... Oct 17 12:53:13 volumio volumio[30095]: info: Plugin mpdemulation is not enabled Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "rest_api"... Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "websocket"... Oct 17 12:53:13 volumio volumio[30095]: info: Starting Socket.io Server version 1.7.4 Oct 17 12:53:13 volumio volumio[30095]: info: Loading plugin "lastfm"... Oct 17 12:53:13 volumio volumio[30157]: Forking 3 albumart workers Oct 17 12:53:13 volumio volumio[30095]: info: Loading i18n strings for locale en Oct 17 12:53:13 volumio volumio[30095]: Updating browse sources language Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: Express server listening on port 3000 Oct 17 12:53:13 volumio volumio[30095]: [Metrics] WebUI: 3s 297.36ms Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:13 volumio volumio[30095]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:13 volumio volumio[30095]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::pushState Oct 17 12:53:13 volumio volumio[30095]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:13 volumio volumio[30095]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:13 volumio volumio[30095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:13 volumio volumio[30095]: info: Reloading queue from file Oct 17 12:53:13 volumio volumio[30095]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::pushState Oct 17 12:53:13 volumio volumio[30095]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::pushState Oct 17 12:53:13 volumio volumio[30095]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::setRandom null Oct 17 12:53:13 volumio volumio[30095]: info: CoreStateMachine::pushState Oct 17 12:53:13 volumio volumio[30095]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:13 volumio volumio[30095]: info: Setting Device type: Raspberry PI Oct 17 12:53:13 volumio volumio[30095]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:13 volumio volumio[30095]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:13 volumio volumio[30095]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:13 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:13 volumio volumio[30095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:13 volumio volumio[30095]: info: Completed loading Core Plugins Oct 17 12:53:13 volumio volumio[30095]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:13 volumio volumio[30095]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:13 volumio volumio[30095]: info: Reading ALSA contributions from plugins. Oct 17 12:53:14 volumio volumio[30095]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:14 volumio volumio[30095]: info: Discovery: Found device Volumioffice Oct 17 12:53:14 volumio volumio[30095]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:14 volumio volumio[30095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:14 volumio volumio[30095]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:14 volumio volumio[30095]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:14 volumio volumio[30095]: info: Discovery: Found device Volumio Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:14 volumio volumio[30095]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:14 volumio volumio[30095]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:14 volumio volumio[30095]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:14 volumio volumio[30167]: Starting albumart workers Oct 17 12:53:14 volumio volumio[30095]: info: Output device has changed, restarting MPD Oct 17 12:53:14 volumio volumio[30095]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:14 volumio sudo[30221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:14 volumio sudo[30221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:14 volumio sudo[30221]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:14 volumio sudo[30139]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:14 volumio sudo[30224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:14 volumio sudo[30224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:14 volumio volumio[30095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:14 volumio volumio[30095]: info: ___________ START PLUGINS ___________ Oct 17 12:53:14 volumio volumio[30095]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:14 volumio volumio[30095]: info: Creating MPD Configuration file Oct 17 12:53:14 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:14 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:14 volumio volumio[30095]: info: [1792201994328] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:14 volumio sudo[30232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:14 volumio sudo[30232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:14 volumio sudo[30232]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:14 volumio sudo[30234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:14 volumio sudo[30234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:14 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:14 volumio sudo[30230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:14 volumio sudo[30230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:14 volumio systemd[1]: mpd.service: Consumed 1.692s CPU time. Oct 17 12:53:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:14 volumio volumio[30095]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:14 volumio volumio[30095]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:14 volumio volumio[30168]: Starting albumart workers Oct 17 12:53:14 volumio volumio[30095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:14 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:14 volumio volumio[30095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:14 volumio volumio[30095]: info: [1792201994465] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:14 volumio volumio[30095]: info: [1792201994468] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:14 volumio sudo[30230]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:14 volumio volumio[30095]: info: Initializing BBC Radios Oct 17 12:53:14 volumio volumio[30169]: Starting albumart workers Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:14 volumio volumio[30095]: info: [1792201994574] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:14 volumio volumio[30095]: Cannot find translation for source LastFM Oct 17 12:53:14 volumio volumio[30095]: info: [LastFM] scrobbler initiated! Oct 17 12:53:14 volumio volumio[30095]: info: [LastFM] extended logging: false Oct 17 12:53:14 volumio volumio[30095]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:14 volumio volumio[30095]: info: [LastFM] Left init routine Oct 17 12:53:14 volumio volumio[30095]: info: [LastFM] Socket already connected: false Oct 17 12:53:14 volumio volumio[30095]: info: Volumio Calling Home Oct 17 12:53:14 volumio sudo[30251]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:14 volumio sudo[30251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:14 volumio sudo[30251]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:14 volumio volumio[30095]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:14 volumio volumio[30095]: info: Discovery: Found device Volumio Oct 17 12:53:14 volumio volumio[30095]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:14 volumio volumio[30095]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:15 volumio volumio[30095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:15 volumio volumio[30095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:15 volumio volumio[30095]: info: Received Get System Info Oct 17 12:53:15 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:15 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:15 volumio volumio[30095]: info: Discovery: Getting this device information Oct 17 12:53:15 volumio volumio[30095]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:15 volumio volumio[30095]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:15 volumio volumio[30095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:15 volumio volumio[30095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:15 volumio volumio[30095]: Error: connect ECONNREFUSED 34.49.30.2:80 Oct 17 12:53:15 volumio volumio[30095]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 12:53:15 volumio volumio[30095]: errno: -111, Oct 17 12:53:15 volumio volumio[30095]: code: 'ECONNREFUSED', Oct 17 12:53:15 volumio volumio[30095]: syscall: 'connect', Oct 17 12:53:15 volumio volumio[30095]: address: '34.49.30.2', Oct 17 12:53:15 volumio volumio[30095]: port: 80 Oct 17 12:53:15 volumio volumio[30095]: } Oct 17 12:53:15 volumio volumio[30095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:15 volumio sudo[30303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:52' Oct 17 12:53:15 volumio sudo[30303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:15 volumio sudo[30303]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 12:53:15 volumio sudo[30224]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:15 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:15.931+11:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:60196->127.0.0.1:3000: read: connection reset by peer" Oct 17 12:53:15 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:15.934+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:15 volumio sudo[30234]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 12:53:15 volumio systemd[1]: volumio.service: Consumed 12.039s CPU time. Oct 17 12:53:16 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:16 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:16 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 111. Oct 17 12:53:16 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:16 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 12:53:16 volumio systemd[1]: volumio.service: Consumed 12.039s CPU time. Oct 17 12:53:16 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 12:53:16 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:16 volumio mpd[30268]: 2026-10-17T12:53:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:16 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:16 volumio volumio-remote-updater[703]: [2026-10-17 12:53:16] [connect] Successful connection Oct 17 12:53:17 volumio volumio[30318]: info: ------------------------------------------- Oct 17 12:53:17 volumio volumio[30318]: info: ----- Volumio3 ---- Oct 17 12:53:17 volumio volumio[30318]: info: ------------------------------------------- Oct 17 12:53:17 volumio volumio[30318]: info: ----- System startup ---- Oct 17 12:53:17 volumio volumio[30318]: info: ------------------------------------------- Oct 17 12:53:17 volumio volumio[30318]: info: MYVOLUMIO Environment detected Oct 17 12:53:17 volumio volumio[30318]: info: Plugin folders cleanup Oct 17 12:53:17 volumio volumio[30318]: info: Scanning into folder /volumio/app/plugins/ Oct 17 12:53:17 volumio volumio[30318]: info: Scanning category audio_interface Oct 17 12:53:17 volumio volumio[30318]: info: Scanning category miscellanea Oct 17 12:53:17 volumio volumio[30318]: info: Scanning category music_service Oct 17 12:53:17 volumio volumio[30318]: info: Scanning category plugins.json Oct 17 12:53:17 volumio volumio[30318]: info: Scanning category system_controller Oct 17 12:53:17 volumio volumio[30318]: info: Scanning category user_interface Oct 17 12:53:17 volumio volumio[30318]: info: Scanning into folder /data/plugins/ Oct 17 12:53:17 volumio volumio[30318]: info: Scanning category user_interface Oct 17 12:53:17 volumio volumio[30318]: info: Plugin folders cleanup completed Oct 17 12:53:17 volumio volumio[30318]: info: ------------------------------------------- Oct 17 12:53:17 volumio volumio[30318]: info: ----- Core plugins startup ---- Oct 17 12:53:17 volumio volumio[30318]: info: ------------------------------------------- Oct 17 12:53:17 volumio volumio[30318]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 12:53:17 volumio volumio[30318]: info: Adding plugin upnp to MyMusic Plugins Oct 17 12:53:17 volumio volumio[30318]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 12:53:17 volumio volumio[30318]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 12:53:17 volumio volumio[30318]: info: Loading plugins from folder /data/plugins/ Oct 17 12:53:17 volumio volumio[30318]: info: Loading plugin "system"... Oct 17 12:53:17 volumio volumio[30318]: info: Loading plugin "appearance"... Oct 17 12:53:18 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:18.031+11:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to check if setup wizard should be shown: could not get wizard status: context deadline exceeded" Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "network"... Oct 17 12:53:18 volumio volumio[30318]: info: Refreshing Cached IP Addresses Oct 17 12:53:18 volumio sudo[30348]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:18 volumio sudo[30348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:18 volumio sudo[30350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:18 volumio sudo[30350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:18 volumio sudo[30348]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:18 volumio sudo[30350]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "services"... Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "volumio5onboarding"... Oct 17 12:53:18 volumio sudo[30359]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 12:53:18 volumio sudo[30359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "alsa_controller"... Oct 17 12:53:18 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "wizard"... Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "networkfs"... Oct 17 12:53:18 volumio volumio[30318]: info: Starting Udev Watcher for removable devices Oct 17 12:53:18 volumio volumio[30318]: info: Ignoring mount for partition: boot Oct 17 12:53:18 volumio volumio[30318]: info: Ignoring mount for partition: volumio Oct 17 12:53:18 volumio volumio[30318]: info: Ignoring mount for partition: volumio_data Oct 17 12:53:18 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "volumio_command_line_client"... Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "upnp"... Oct 17 12:53:18 volumio volumio[30318]: info: [1792201998377] Starting Upmpd Daemon Oct 17 12:53:18 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "my_music"... Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "mpd"... Oct 17 12:53:18 volumio volumio[30318]: info: Loading plugin "upnp_browser"... Oct 17 12:53:19 volumio volumio[30318]: info: Starting UPNP Browser Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "alarm-clock"... Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "novaui"... Oct 17 12:53:19 volumio volumio[30318]: info: Applying required configuration parameters for plugin novaui Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "airplay_emulation"... Oct 17 12:53:19 volumio volumio[30318]: info: Starting Shairport Sync Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "last_100"... Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "webradio"... Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "i2s_dacs"... Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "volumiodiscovery"... Oct 17 12:53:19 volumio volumio[30318]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:19 volumio volumio[30318]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:19 volumio volumio[30318]: *** WARNING *** For more information see Oct 17 12:53:19 volumio volumio[30318]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:19 volumio volumio[30318]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:19 volumio node[30318]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:19 volumio volumio[30318]: *** WARNING *** For more information see Oct 17 12:53:19 volumio node[30318]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:19 volumio node[30318]: *** WARNING *** For more information see Oct 17 12:53:19 volumio node[30318]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:19 volumio node[30318]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:19 volumio node[30318]: *** WARNING *** For more information see Oct 17 12:53:19 volumio volumio[30318]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 12:53:19 volumio volumio[30318]: info: Discovery: Started advertising with name: Volumio Oct 17 12:53:19 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "outputs"... Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "albumart"... Oct 17 12:53:19 volumio volumio[30318]: info: Plugin example_plugin is not enabled Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "inputs"... Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "updater_comm"... Oct 17 12:53:19 volumio volumio[30318]: info: Plugin mpdemulation is not enabled Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "rest_api"... Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "websocket"... Oct 17 12:53:19 volumio volumio[30318]: info: Starting Socket.io Server version 1.7.4 Oct 17 12:53:19 volumio volumio[30318]: info: Loading plugin "lastfm"... Oct 17 12:53:19 volumio volumio[30380]: Forking 3 albumart workers Oct 17 12:53:20 volumio volumio[30318]: info: Loading i18n strings for locale en Oct 17 12:53:20 volumio volumio[30318]: Updating browse sources language Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: Express server listening on port 3000 Oct 17 12:53:20 volumio volumio[30318]: [Metrics] WebUI: 3s 274.99ms Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:20 volumio volumio[30318]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:20 volumio volumio-remote-updater[703]: [2026-10-17 12:53:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792201996 101 Oct 17 12:53:20 volumio volumio[30318]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::pushState Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:20 volumio volumio[30318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: Reloading queue from file Oct 17 12:53:20 volumio volumio[30318]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::pushState Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::pushState Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::setRandom null Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::pushState Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:20 volumio volumio[30318]: info: Setting Device type: Raspberry PI Oct 17 12:53:20 volumio volumio[30318]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:20 volumio volumio[30318]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:20 volumio volumio[30318]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:20 volumio volumio[30318]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 3 Oct 17 12:53:20 volumio volumio[30318]: info: Completed loading Core Plugins Oct 17 12:53:20 volumio volumio[30318]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:20 volumio volumio[30318]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:20 volumio volumio[30318]: info: Reading ALSA contributions from plugins. Oct 17 12:53:20 volumio volumio[30318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: Found device Volumioffice Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:20 volumio volumio[30318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:20 volumio volumio[30318]: info: Received Get System Version Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:20 volumio volumio[30318]: info: Received Get System Info Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: Getting this device information Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 17 12:53:20 volumio sudo[30445]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 17 12:53:20 volumio volumio[30390]: Starting albumart workers Oct 17 12:53:20 volumio volumio[30318]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 17 12:53:20 volumio sudo[30449]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 17 12:53:20 volumio sudo[30449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 12:53:20 volumio sudo[30445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioGetQueue Oct 17 12:53:20 volumio volumio[30318]: info: CoreStateMachine::getQueue Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getQueue Oct 17 12:53:20 volumio sudo[30455]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:20 volumio sudo[30459]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:20 volumio sudo[30455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:20 volumio sudo[30459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:20 volumio sudo[30464]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:20 volumio sudo[30464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:20 volumio sudo[30445]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:20 volumio sudo[30455]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:20 volumio sudo[30464]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:20 volumio sudo[30449]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:20 volumio sudo[30459]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:20 volumio sudo[30359]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:20 volumio sudo[30466]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:20 volumio sudo[30466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:20 volumio volumio[30391]: Starting albumart workers Oct 17 12:53:20 volumio sudo[30466]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:20 volumio volumio[30318]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: Found device Volumio Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: Found device Volumio Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:20 volumio volumio[30318]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:20 volumio volumio[30318]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:20 volumio volumio[30318]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:20 volumio volumio[30318]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:20 volumio volumio[30318]: info: Output device has changed, restarting MPD Oct 17 12:53:20 volumio volumio[30318]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:20 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:21 volumio sudo[30478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:21 volumio sudo[30476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:21 volumio sudo[30478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:21 volumio sudo[30476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:21 volumio sudo[30476]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:21 volumio volumio[30392]: Starting albumart workers Oct 17 12:53:21 volumio volumio[30318]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:21 volumio volumio[30318]: info: ___________ START PLUGINS ___________ Oct 17 12:53:21 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:21 volumio volumio[30318]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:21 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:21 volumio volumio[30318]: info: Creating MPD Configuration file Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:21 volumio volumio[30318]: info: [1792202001102] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:21 volumio sudo[30486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:21 volumio sudo[30486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:21 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:21 volumio systemd[1]: mpd.service: Consumed 1.687s CPU time. Oct 17 12:53:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:21 volumio volumio[30318]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:21 volumio sudo[30490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:21 volumio sudo[30489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:21 volumio sudo[30490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:21 volumio volumio[30318]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:21 volumio sudo[30489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:21 volumio sudo[30489]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:21 volumio volumio[30318]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:21 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:21 volumio sudo[30486]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:21 volumio volumio[30318]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:21 volumio volumio[30318]: info: [1792202001296] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:21 volumio volumio[30318]: info: [1792202001299] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:21 volumio volumio[30318]: info: Initializing BBC Radios Oct 17 12:53:21 volumio sudo[30513]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:21 volumio sudo[30513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:21 volumio sudo[30513]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:21 volumio volumio[30318]: info: [1792202001420] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:21 volumio volumio[30318]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:21 volumio volumio[30318]: Cannot find translation for source LastFM Oct 17 12:53:21 volumio volumio[30318]: info: [LastFM] scrobbler initiated! Oct 17 12:53:21 volumio volumio[30318]: info: [LastFM] extended logging: false Oct 17 12:53:21 volumio volumio[30318]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:21 volumio volumio[30318]: info: [LastFM] Left init routine Oct 17 12:53:21 volumio volumio[30318]: info: [LastFM] Socket already connected: false Oct 17 12:53:21 volumio volumio[30318]: info: Volumio Calling Home Oct 17 12:53:21 volumio volumio[30318]: info: MPD Permissions set Oct 17 12:53:21 volumio volumio[30318]: info: MPD Permissions set Oct 17 12:53:21 volumio volumio[30318]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:21 volumio volumio[30318]: Error: connect ECONNREFUSED 34.49.30.2:80 Oct 17 12:53:21 volumio volumio[30318]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 12:53:21 volumio volumio[30318]: errno: -111, Oct 17 12:53:21 volumio volumio[30318]: code: 'ECONNREFUSED', Oct 17 12:53:21 volumio volumio[30318]: syscall: 'connect', Oct 17 12:53:21 volumio volumio[30318]: address: '34.49.30.2', Oct 17 12:53:21 volumio volumio[30318]: port: 80 Oct 17 12:53:21 volumio volumio[30318]: } Oct 17 12:53:21 volumio volumio[30318]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:22 volumio sudo[30560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:52' Oct 17 12:53:22 volumio sudo[30560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:22 volumio sudo[30560]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:22 volumio volumio-remote-updater[703]: [2026-10-17 12:53:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 17 12:53:22 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:22.662+11:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:53148->127.0.0.1:3000: read: connection reset by peer" Oct 17 12:53:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 12:53:22 volumio volumio-remote-updater[703]: [2026-10-17 12:53:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 17 12:53:22 volumio sudo[30490]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:22 volumio sudo[30478]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 12:53:22 volumio systemd[1]: volumio.service: Consumed 12.336s CPU time. Oct 17 12:53:22 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:22 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:22 volumio mpd[30534]: 2026-10-17T12:53:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 112. Oct 17 12:53:22 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:22 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 12:53:22 volumio systemd[1]: volumio.service: Consumed 12.336s CPU time. Oct 17 12:53:22 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 12:53:22 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:22 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:23 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:23 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:23.664+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:24 volumio volumio[30577]: info: ------------------------------------------- Oct 17 12:53:24 volumio volumio[30577]: info: ----- Volumio3 ---- Oct 17 12:53:24 volumio volumio[30577]: info: ------------------------------------------- Oct 17 12:53:24 volumio volumio[30577]: info: ----- System startup ---- Oct 17 12:53:24 volumio volumio[30577]: info: ------------------------------------------- Oct 17 12:53:24 volumio volumio[30577]: info: MYVOLUMIO Environment detected Oct 17 12:53:24 volumio volumio[30577]: info: Plugin folders cleanup Oct 17 12:53:24 volumio volumio[30577]: info: Scanning into folder /volumio/app/plugins/ Oct 17 12:53:24 volumio volumio[30577]: info: Scanning category audio_interface Oct 17 12:53:24 volumio volumio[30577]: info: Scanning category miscellanea Oct 17 12:53:24 volumio volumio[30577]: info: Scanning category music_service Oct 17 12:53:24 volumio volumio[30577]: info: Scanning category plugins.json Oct 17 12:53:24 volumio volumio[30577]: info: Scanning category system_controller Oct 17 12:53:24 volumio volumio[30577]: info: Scanning category user_interface Oct 17 12:53:24 volumio volumio[30577]: info: Scanning into folder /data/plugins/ Oct 17 12:53:24 volumio volumio[30577]: info: Scanning category user_interface Oct 17 12:53:24 volumio volumio[30577]: info: Plugin folders cleanup completed Oct 17 12:53:24 volumio volumio[30577]: info: ------------------------------------------- Oct 17 12:53:24 volumio volumio[30577]: info: ----- Core plugins startup ---- Oct 17 12:53:24 volumio volumio[30577]: info: ------------------------------------------- Oct 17 12:53:24 volumio volumio[30577]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 12:53:24 volumio volumio[30577]: info: Adding plugin upnp to MyMusic Plugins Oct 17 12:53:24 volumio volumio[30577]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 12:53:24 volumio volumio[30577]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 12:53:24 volumio volumio[30577]: info: Loading plugins from folder /data/plugins/ Oct 17 12:53:24 volumio volumio[30577]: info: Loading plugin "system"... Oct 17 12:53:24 volumio volumio[30577]: info: Loading plugin "appearance"... Oct 17 12:53:24 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:24.715+11:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to check if setup wizard should be shown: could not get wizard status: context deadline exceeded" Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "network"... Oct 17 12:53:25 volumio volumio[30577]: info: Refreshing Cached IP Addresses Oct 17 12:53:25 volumio sudo[30605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:25 volumio sudo[30605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:25 volumio sudo[30607]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:25 volumio sudo[30607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "services"... Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "volumio5onboarding"... Oct 17 12:53:25 volumio sudo[30607]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:25 volumio sudo[30605]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:25 volumio sudo[30614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 12:53:25 volumio sudo[30614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "alsa_controller"... Oct 17 12:53:25 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "wizard"... Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "networkfs"... Oct 17 12:53:25 volumio volumio[30577]: info: Starting Udev Watcher for removable devices Oct 17 12:53:25 volumio volumio[30577]: info: Ignoring mount for partition: boot Oct 17 12:53:25 volumio volumio[30577]: info: Ignoring mount for partition: volumio Oct 17 12:53:25 volumio volumio[30577]: info: Ignoring mount for partition: volumio_data Oct 17 12:53:25 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "volumio_command_line_client"... Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "upnp"... Oct 17 12:53:25 volumio volumio[30577]: info: [1792202005209] Starting Upmpd Daemon Oct 17 12:53:25 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "my_music"... Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "mpd"... Oct 17 12:53:25 volumio volumio[30577]: info: Loading plugin "upnp_browser"... Oct 17 12:53:26 volumio volumio[30577]: info: Starting UPNP Browser Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "alarm-clock"... Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "novaui"... Oct 17 12:53:26 volumio volumio[30577]: info: Applying required configuration parameters for plugin novaui Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "airplay_emulation"... Oct 17 12:53:26 volumio volumio[30577]: info: Starting Shairport Sync Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "last_100"... Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "webradio"... Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "i2s_dacs"... Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "volumiodiscovery"... Oct 17 12:53:26 volumio volumio[30577]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:26 volumio volumio[30577]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:26 volumio volumio[30577]: *** WARNING *** For more information see Oct 17 12:53:26 volumio volumio[30577]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:26 volumio volumio[30577]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:26 volumio volumio[30577]: *** WARNING *** For more information see Oct 17 12:53:26 volumio node[30577]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:26 volumio node[30577]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:26 volumio node[30577]: *** WARNING *** For more information see Oct 17 12:53:26 volumio node[30577]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:26 volumio node[30577]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:26 volumio node[30577]: *** WARNING *** For more information see Oct 17 12:53:26 volumio volumio[30577]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 12:53:26 volumio volumio[30577]: info: Discovery: Started advertising with name: Volumio Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "outputs"... Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "albumart"... Oct 17 12:53:26 volumio volumio[30577]: info: Plugin example_plugin is not enabled Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "inputs"... Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "updater_comm"... Oct 17 12:53:26 volumio volumio[30577]: info: Plugin mpdemulation is not enabled Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "rest_api"... Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "websocket"... Oct 17 12:53:26 volumio volumio[30577]: info: Starting Socket.io Server version 1.7.4 Oct 17 12:53:26 volumio volumio[30577]: info: Loading plugin "lastfm"... Oct 17 12:53:26 volumio volumio[30637]: Forking 3 albumart workers Oct 17 12:53:26 volumio volumio[30577]: info: Loading i18n strings for locale en Oct 17 12:53:26 volumio volumio[30577]: Updating browse sources language Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:26 volumio volumio[30577]: Express server listening on port 3000 Oct 17 12:53:26 volumio volumio[30577]: [Metrics] WebUI: 3s 267.16ms Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:27 volumio volumio[30577]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:27 volumio volumio[30577]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::pushState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:27 volumio volumio[30577]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:27 volumio volumio[30577]: info: Reloading queue from file Oct 17 12:53:27 volumio volumio[30577]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:27 volumio volumio[30577]: info: Received Get System Info Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: Getting this device information Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:27 volumio volumio[30577]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::pushState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::pushState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::setRandom null Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::pushState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:27 volumio volumio[30577]: info: Setting Device type: Raspberry PI Oct 17 12:53:27 volumio volumio[30577]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:27 volumio volumio[30577]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:27 volumio volumio[30577]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 17 12:53:27 volumio volumio[30577]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:27 volumio volumio[30577]: info: Completed loading Core Plugins Oct 17 12:53:27 volumio volumio[30577]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:27 volumio volumio[30577]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:27 volumio volumio[30577]: info: Reading ALSA contributions from plugins. Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: Found device Volumioffice Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:27 volumio volumio[30647]: Starting albumart workers Oct 17 12:53:27 volumio volumio[30577]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:27 volumio sudo[30614]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:27 volumio volumio[30648]: Starting albumart workers Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: Found device Volumio Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30649]: Starting albumart workers Oct 17 12:53:27 volumio volumio-remote-updater[703]: [2026-10-17 12:53:27] [connect] Successful connection Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: Found device Volumio Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:27 volumio volumio[30577]: info: Received Get System Version Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:27 volumio volumio[30577]: info: Received Get System Info Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:27 volumio volumio[30577]: info: Discovery: Getting this device information Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 17 12:53:27 volumio sudo[30700]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 17 12:53:27 volumio sudo[30700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:27 volumio volumio[30577]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 17 12:53:27 volumio sudo[30703]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:27 volumio sudo[30700]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:27 volumio sudo[30703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::volumioGetQueue Oct 17 12:53:27 volumio volumio[30577]: info: CoreStateMachine::getQueue Oct 17 12:53:27 volumio volumio[30577]: info: CorePlayQueue::getQueue Oct 17 12:53:27 volumio sudo[30703]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:27 volumio sudo[30711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:27 volumio sudo[30711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:27 volumio sudo[30720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:27 volumio sudo[30714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:27 volumio sudo[30723]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:27 volumio sudo[30714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:27 volumio sudo[30723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:27 volumio sudo[30711]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:27 volumio sudo[30720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:27 volumio sudo[30714]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:27 volumio sudo[30720]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:27 volumio sudo[30723]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:27 volumio volumio[30577]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:27 volumio volumio[30577]: info: Output device has changed, restarting MPD Oct 17 12:53:27 volumio volumio[30577]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:27 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:27 volumio sudo[30732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:27 volumio sudo[30732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:27 volumio sudo[30732]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:27 volumio volumio[30577]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:28 volumio volumio[30577]: info: ___________ START PLUGINS ___________ Oct 17 12:53:28 volumio sudo[30736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:28 volumio sudo[30736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:28 volumio volumio[30577]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:28 volumio volumio[30577]: info: Creating MPD Configuration file Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:28 volumio volumio[30577]: info: [1792202008047] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:28 volumio sudo[30742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:28 volumio sudo[30742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:28 volumio sudo[30744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:28 volumio sudo[30744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:28 volumio volumio[30577]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:28 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:28 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:28 volumio volumio[30577]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:28 volumio sudo[30746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:28 volumio sudo[30744]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:28 volumio sudo[30746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:28 volumio sudo[30742]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:28 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:28 volumio systemd[1]: mpd.service: Consumed 1.566s CPU time. Oct 17 12:53:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:28 volumio volumio[30577]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:28 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:28 volumio volumio[30577]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:28 volumio volumio[30577]: info: [1792202008245] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:28 volumio volumio[30577]: info: [1792202008248] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:28 volumio volumio[30577]: info: Initializing BBC Radios Oct 17 12:53:28 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:28.285+11:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:28 volumio volumio[30577]: info: [1792202008372] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:28 volumio volumio[30577]: Cannot find translation for source LastFM Oct 17 12:53:28 volumio volumio[30577]: info: [LastFM] scrobbler initiated! Oct 17 12:53:28 volumio volumio[30577]: info: [LastFM] extended logging: false Oct 17 12:53:28 volumio volumio[30577]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:28 volumio volumio[30577]: info: [LastFM] Left init routine Oct 17 12:53:28 volumio volumio[30577]: info: [LastFM] Socket already connected: true Oct 17 12:53:28 volumio volumio[30577]: info: Volumio Calling Home Oct 17 12:53:28 volumio sudo[30773]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:28 volumio sudo[30773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:28 volumio sudo[30773]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:28 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:28 volumio volumio[30577]: info: Received Get System Info Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:28 volumio volumio[30577]: info: Discovery: Getting this device information Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:28 volumio volumio[30577]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:28 volumio volumio[30577]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:28 volumio volumio-remote-updater[703]: [2026-10-17 12:53:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792202007 101 Oct 17 12:53:28 volumio volumio[30577]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:28 volumio volumio[30577]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:28 volumio volumio[30577]: Error: connect ECONNREFUSED 34.49.30.2:80 Oct 17 12:53:28 volumio volumio[30577]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 12:53:28 volumio volumio[30577]: errno: -111, Oct 17 12:53:28 volumio volumio[30577]: code: 'ECONNREFUSED', Oct 17 12:53:28 volumio volumio[30577]: syscall: 'connect', Oct 17 12:53:28 volumio volumio[30577]: address: '34.49.30.2', Oct 17 12:53:28 volumio volumio[30577]: port: 80 Oct 17 12:53:28 volumio volumio[30577]: } Oct 17 12:53:28 volumio volumio[30577]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:29 volumio sudo[30815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:52' Oct 17 12:53:29 volumio sudo[30815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:29 volumio sudo[30815]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:29 volumio volumio-remote-updater[703]: [2026-10-17 12:53:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 17 12:53:29 volumio volumio-remote-updater[703]: [2026-10-17 12:53:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 17 12:53:29 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:29.565+11:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:53184->127.0.0.1:3000: read: connection reset by peer" Oct 17 12:53:29 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:29.567+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:29 volumio sudo[30736]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 12:53:29 volumio sudo[30746]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:29 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 12:53:29 volumio systemd[1]: volumio.service: Consumed 12.313s CPU time. Oct 17 12:53:29 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:29 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:29 volumio mpd[30798]: 2026-10-17T12:53:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:29 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 113. Oct 17 12:53:29 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:29 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:29 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 12:53:29 volumio systemd[1]: volumio.service: Consumed 12.313s CPU time. Oct 17 12:53:29 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 12:53:29 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:30 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:30 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:30.568+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:30 volumio volumio[30834]: info: ------------------------------------------- Oct 17 12:53:30 volumio volumio[30834]: info: ----- Volumio3 ---- Oct 17 12:53:30 volumio volumio[30834]: info: ------------------------------------------- Oct 17 12:53:30 volumio volumio[30834]: info: ----- System startup ---- Oct 17 12:53:30 volumio volumio[30834]: info: ------------------------------------------- Oct 17 12:53:31 volumio volumio[30834]: info: MYVOLUMIO Environment detected Oct 17 12:53:31 volumio volumio[30834]: info: Plugin folders cleanup Oct 17 12:53:31 volumio volumio[30834]: info: Scanning into folder /volumio/app/plugins/ Oct 17 12:53:31 volumio volumio[30834]: info: Scanning category audio_interface Oct 17 12:53:31 volumio volumio[30834]: info: Scanning category miscellanea Oct 17 12:53:31 volumio volumio[30834]: info: Scanning category music_service Oct 17 12:53:31 volumio volumio[30834]: info: Scanning category plugins.json Oct 17 12:53:31 volumio volumio[30834]: info: Scanning category system_controller Oct 17 12:53:31 volumio volumio[30834]: info: Scanning category user_interface Oct 17 12:53:31 volumio volumio[30834]: info: Scanning into folder /data/plugins/ Oct 17 12:53:31 volumio volumio[30834]: info: Scanning category user_interface Oct 17 12:53:31 volumio volumio[30834]: info: Plugin folders cleanup completed Oct 17 12:53:31 volumio volumio[30834]: info: ------------------------------------------- Oct 17 12:53:31 volumio volumio[30834]: info: ----- Core plugins startup ---- Oct 17 12:53:31 volumio volumio[30834]: info: ------------------------------------------- Oct 17 12:53:31 volumio volumio[30834]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 12:53:31 volumio volumio[30834]: info: Adding plugin upnp to MyMusic Plugins Oct 17 12:53:31 volumio volumio[30834]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 12:53:31 volumio volumio[30834]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 12:53:31 volumio volumio[30834]: info: Loading plugins from folder /data/plugins/ Oct 17 12:53:31 volumio volumio[30834]: info: Loading plugin "system"... Oct 17 12:53:31 volumio volumio[30834]: info: Loading plugin "appearance"... Oct 17 12:53:31 volumio volumio[30834]: info: Loading plugin "network"... Oct 17 12:53:31 volumio volumio[30834]: info: Refreshing Cached IP Addresses Oct 17 12:53:31 volumio sudo[30862]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:31 volumio sudo[30862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:31 volumio sudo[30864]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:31 volumio sudo[30864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:31 volumio sudo[30862]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:31 volumio volumio[30834]: info: Loading plugin "services"... Oct 17 12:53:31 volumio volumio[30834]: info: Loading plugin "volumio5onboarding"... Oct 17 12:53:31 volumio sudo[30864]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:32 volumio sudo[30872]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 12:53:32 volumio sudo[30872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:32 volumio volumio[30834]: info: Loading plugin "alsa_controller"... Oct 17 12:53:32 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:32 volumio volumio[30834]: info: Loading plugin "wizard"... Oct 17 12:53:32 volumio volumio[30834]: info: Loading plugin "networkfs"... Oct 17 12:53:32 volumio volumio[30834]: info: Starting Udev Watcher for removable devices Oct 17 12:53:32 volumio volumio[30834]: info: Ignoring mount for partition: boot Oct 17 12:53:32 volumio volumio[30834]: info: Ignoring mount for partition: volumio Oct 17 12:53:32 volumio volumio[30834]: info: Ignoring mount for partition: volumio_data Oct 17 12:53:32 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:32 volumio volumio[30834]: info: Loading plugin "volumio_command_line_client"... Oct 17 12:53:32 volumio volumio[30834]: info: Loading plugin "upnp"... Oct 17 12:53:32 volumio volumio[30834]: info: [1792202012092] Starting Upmpd Daemon Oct 17 12:53:32 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:32 volumio volumio[30834]: info: Loading plugin "my_music"... Oct 17 12:53:32 volumio volumio[30834]: info: Loading plugin "mpd"... Oct 17 12:53:32 volumio volumio[30834]: info: Loading plugin "upnp_browser"... Oct 17 12:53:33 volumio volumio[30834]: info: Starting UPNP Browser Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "alarm-clock"... Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "novaui"... Oct 17 12:53:33 volumio volumio[30834]: info: Applying required configuration parameters for plugin novaui Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "airplay_emulation"... Oct 17 12:53:33 volumio volumio[30834]: info: Starting Shairport Sync Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "last_100"... Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "webradio"... Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "i2s_dacs"... Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "volumiodiscovery"... Oct 17 12:53:33 volumio volumio[30834]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:33 volumio volumio[30834]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:33 volumio node[30834]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:33 volumio volumio[30834]: *** WARNING *** For more information see Oct 17 12:53:33 volumio volumio[30834]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:33 volumio volumio[30834]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:33 volumio volumio[30834]: *** WARNING *** For more information see Oct 17 12:53:33 volumio node[30834]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:33 volumio node[30834]: *** WARNING *** For more information see Oct 17 12:53:33 volumio node[30834]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:33 volumio node[30834]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:33 volumio node[30834]: *** WARNING *** For more information see Oct 17 12:53:33 volumio volumio[30834]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 12:53:33 volumio volumio[30834]: info: Discovery: Started advertising with name: Volumio Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "outputs"... Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "albumart"... Oct 17 12:53:33 volumio volumio[30834]: info: Plugin example_plugin is not enabled Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "inputs"... Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "updater_comm"... Oct 17 12:53:33 volumio volumio[30834]: info: Plugin mpdemulation is not enabled Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "rest_api"... Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "websocket"... Oct 17 12:53:33 volumio volumio[30834]: info: Starting Socket.io Server version 1.7.4 Oct 17 12:53:33 volumio volumio[30834]: info: Loading plugin "lastfm"... Oct 17 12:53:33 volumio volumio[30894]: Forking 3 albumart workers Oct 17 12:53:33 volumio volumio[30834]: info: Loading i18n strings for locale en Oct 17 12:53:33 volumio volumio[30834]: Updating browse sources language Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:33 volumio volumio[30834]: Express server listening on port 3000 Oct 17 12:53:33 volumio volumio[30834]: [Metrics] WebUI: 3s 299.76ms Oct 17 12:53:33 volumio volumio[30834]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:33 volumio volumio[30834]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:33 volumio volumio[30834]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:33 volumio volumio[30834]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:34 volumio volumio[30834]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 17 12:53:34 volumio volumio[30834]: info: CoreStateMachine::pushState Oct 17 12:53:34 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:34 volumio volumio[30834]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:34 volumio volumio[30834]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:34 volumio volumio[30834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:34 volumio volumio[30834]: info: Reloading queue from file Oct 17 12:53:34 volumio volumio[30834]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:34 volumio volumio[30834]: info: CoreStateMachine::pushState Oct 17 12:53:34 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:34 volumio volumio[30834]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:34 volumio volumio[30834]: info: CoreStateMachine::pushState Oct 17 12:53:34 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:34 volumio volumio[30834]: info: CoreStateMachine::setRandom null Oct 17 12:53:34 volumio volumio[30834]: info: CoreStateMachine::pushState Oct 17 12:53:34 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:34 volumio volumio[30834]: info: Setting Device type: Raspberry PI Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:34 volumio volumio[30834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:34 volumio volumio[30834]: info: Completed loading Core Plugins Oct 17 12:53:34 volumio volumio[30834]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:34 volumio volumio[30834]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:34 volumio volumio[30834]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:34 volumio volumio[30834]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:34 volumio volumio[30834]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:34 volumio volumio[30834]: info: Discovery: Found device Volumioffice Oct 17 12:53:34 volumio volumio[30834]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:34 volumio volumio[30834]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:34 volumio volumio[30834]: info: Reading ALSA contributions from plugins. Oct 17 12:53:34 volumio volumio[30834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:34 volumio volumio[30834]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:34 volumio volumio[30834]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:34 volumio volumio[30906]: Starting albumart workers Oct 17 12:53:34 volumio volumio[30905]: Starting albumart workers Oct 17 12:53:34 volumio volumio[30834]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:34 volumio volumio[30834]: info: Discovery: Found device Volumio Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:34 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:34 volumio sudo[30872]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:34 volumio volumio[30834]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:34 volumio volumio[30834]: info: Discovery: Found device Volumio Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:34 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:34 volumio volumio[30834]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:34 volumio volumio[30834]: info: Output device has changed, restarting MPD Oct 17 12:53:34 volumio volumio[30834]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:34 volumio sudo[30960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:34 volumio sudo[30960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:34 volumio sudo[30960]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:34 volumio volumio-remote-updater[703]: [2026-10-17 12:53:34] [connect] Successful connection Oct 17 12:53:34 volumio volumio[30834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:34 volumio volumio[30834]: info: ___________ START PLUGINS ___________ Oct 17 12:53:34 volumio sudo[30961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:34 volumio sudo[30961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:34 volumio volumio[30834]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:34 volumio volumio[30834]: info: Creating MPD Configuration file Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:34 volumio volumio[30834]: info: [1792202014620] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:34 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:34 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:34 volumio sudo[30970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:34 volumio sudo[30970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:34 volumio sudo[30971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:34 volumio sudo[30971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:34 volumio volumio[30834]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:34 volumio sudo[30971]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:34 volumio sudo[30973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:34 volumio sudo[30973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:34 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:34 volumio systemd[1]: mpd.service: Consumed 1.482s CPU time. Oct 17 12:53:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:34 volumio volumio[30834]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:34 volumio volumio[30904]: Starting albumart workers Oct 17 12:53:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:34 volumio volumio[30834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:34 volumio volumio[30834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:34 volumio volumio[30834]: info: [1792202014775] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:34 volumio volumio[30834]: info: [1792202014784] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:34 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:34 volumio sudo[30970]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:34 volumio volumio[30834]: info: Initializing BBC Radios Oct 17 12:53:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:34 volumio volumio[30834]: info: [1792202014893] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:34 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:34 volumio volumio[30834]: Cannot find translation for source LastFM Oct 17 12:53:34 volumio volumio[30834]: info: [LastFM] scrobbler initiated! Oct 17 12:53:34 volumio volumio[30834]: info: [LastFM] extended logging: false Oct 17 12:53:34 volumio volumio[30834]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:34 volumio volumio[30834]: info: [LastFM] Left init routine Oct 17 12:53:34 volumio volumio[30834]: info: [LastFM] Socket already connected: false Oct 17 12:53:34 volumio volumio[30834]: info: Volumio Calling Home Oct 17 12:53:34 volumio sudo[30993]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:34 volumio sudo[30993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:34 volumio sudo[30993]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:35 volumio volumio[30834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:35 volumio volumio[30834]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:35 volumio volumio[30834]: info: Received Get System Info Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:35 volumio volumio[30834]: info: Discovery: Getting this device information Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:35 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:35 volumio volumio[30834]: info: Received Get System Version Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:35 volumio volumio[30834]: info: Received Get System Info Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:35 volumio volumio[30834]: info: Discovery: Getting this device information Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:35 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 17 12:53:35 volumio sudo[31029]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 17 12:53:35 volumio sudo[31029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:35 volumio sudo[31034]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:35 volumio sudo[31034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:35 volumio sudo[31029]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:35 volumio sudo[31040]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:35 volumio sudo[31040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:35 volumio volumio[30834]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 17 12:53:35 volumio sudo[31034]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:35 volumio sudo[31040]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:35 volumio sudo[31047]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 12:53:35 volumio sudo[31047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:35 volumio volumio[30834]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:35 volumio volumio[30834]: info: CoreCommandRouter::volumioGetQueue Oct 17 12:53:35 volumio sudo[31055]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:35 volumio volumio[30834]: info: CoreStateMachine::getQueue Oct 17 12:53:35 volumio volumio[30834]: info: CorePlayQueue::getQueue Oct 17 12:53:35 volumio sudo[31055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:35 volumio sudo[31050]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:35 volumio sudo[31047]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:35 volumio sudo[31050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:35 volumio sudo[31055]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:35 volumio sudo[31050]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:35 volumio volumio[30834]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:35 volumio volumio[30834]: Error: connect ECONNREFUSED 34.49.30.2:80 Oct 17 12:53:35 volumio volumio[30834]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 12:53:35 volumio volumio[30834]: errno: -111, Oct 17 12:53:35 volumio volumio[30834]: code: 'ECONNREFUSED', Oct 17 12:53:35 volumio volumio[30834]: syscall: 'connect', Oct 17 12:53:35 volumio volumio[30834]: address: '34.49.30.2', Oct 17 12:53:35 volumio volumio[30834]: port: 80 Oct 17 12:53:35 volumio volumio[30834]: } Oct 17 12:53:35 volumio volumio[30834]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:36 volumio sudo[31074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:52' Oct 17 12:53:36 volumio sudo[31074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:36 volumio sudo[31074]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:36 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:36.395+11:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:53236->127.0.0.1:3000: read: connection reset by peer" Oct 17 12:53:36 volumio volumio-remote-updater[703]: [2026-10-17 12:53:36] [error] handle_read_http_response error: asio.system:104 (Connection reset by peer) Oct 17 12:53:36 volumio volumio-remote-updater[703]: [2026-10-17 12:53:36] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 17 12:53:36 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:36.397+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 12:53:36 volumio sudo[30973]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:36 volumio sudo[30961]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 12:53:36 volumio systemd[1]: volumio.service: Consumed 12.281s CPU time. Oct 17 12:53:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:36 volumio mpd[31007]: 2026-10-17T12:53:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:36 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:36 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 114. Oct 17 12:53:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:36 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 12:53:36 volumio systemd[1]: volumio.service: Consumed 12.281s CPU time. Oct 17 12:53:36 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 12:53:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:36 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:37 volumio volumio[31091]: info: ------------------------------------------- Oct 17 12:53:37 volumio volumio[31091]: info: ----- Volumio3 ---- Oct 17 12:53:37 volumio volumio[31091]: info: ------------------------------------------- Oct 17 12:53:37 volumio volumio[31091]: info: ----- System startup ---- Oct 17 12:53:37 volumio volumio[31091]: info: ------------------------------------------- Oct 17 12:53:37 volumio volumio[31091]: info: MYVOLUMIO Environment detected Oct 17 12:53:38 volumio volumio[31091]: info: Plugin folders cleanup Oct 17 12:53:38 volumio volumio[31091]: info: Scanning into folder /volumio/app/plugins/ Oct 17 12:53:38 volumio volumio[31091]: info: Scanning category audio_interface Oct 17 12:53:38 volumio volumio[31091]: info: Scanning category miscellanea Oct 17 12:53:38 volumio volumio[31091]: info: Scanning category music_service Oct 17 12:53:38 volumio volumio[31091]: info: Scanning category plugins.json Oct 17 12:53:38 volumio volumio[31091]: info: Scanning category system_controller Oct 17 12:53:38 volumio volumio[31091]: info: Scanning category user_interface Oct 17 12:53:38 volumio volumio[31091]: info: Scanning into folder /data/plugins/ Oct 17 12:53:38 volumio volumio[31091]: info: Scanning category user_interface Oct 17 12:53:38 volumio volumio[31091]: info: Plugin folders cleanup completed Oct 17 12:53:38 volumio volumio[31091]: info: ------------------------------------------- Oct 17 12:53:38 volumio volumio[31091]: info: ----- Core plugins startup ---- Oct 17 12:53:38 volumio volumio[31091]: info: ------------------------------------------- Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 12:53:38 volumio volumio[31091]: info: Adding plugin upnp to MyMusic Plugins Oct 17 12:53:38 volumio volumio[31091]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 12:53:38 volumio volumio[31091]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugins from folder /data/plugins/ Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "system"... Oct 17 12:53:38 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:38.057+11:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to check if setup wizard should be shown: could not get wizard status: context deadline exceeded" Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "appearance"... Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "network"... Oct 17 12:53:38 volumio volumio[31091]: info: Refreshing Cached IP Addresses Oct 17 12:53:38 volumio sudo[31119]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:38 volumio sudo[31119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:38 volumio sudo[31121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:38 volumio sudo[31121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "services"... Oct 17 12:53:38 volumio sudo[31119]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:38 volumio sudo[31121]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "volumio5onboarding"... Oct 17 12:53:38 volumio sudo[31129]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 12:53:38 volumio sudo[31129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "alsa_controller"... Oct 17 12:53:38 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "wizard"... Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "networkfs"... Oct 17 12:53:38 volumio volumio[31091]: info: Starting Udev Watcher for removable devices Oct 17 12:53:38 volumio volumio[31091]: info: Ignoring mount for partition: boot Oct 17 12:53:38 volumio volumio[31091]: info: Ignoring mount for partition: volumio Oct 17 12:53:38 volumio volumio[31091]: info: Ignoring mount for partition: volumio_data Oct 17 12:53:38 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "volumio_command_line_client"... Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "upnp"... Oct 17 12:53:38 volumio volumio[31091]: info: [1792202018794] Starting Upmpd Daemon Oct 17 12:53:38 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "my_music"... Oct 17 12:53:38 volumio volumio[31091]: info: Loading plugin "mpd"... Oct 17 12:53:39 volumio volumio[31091]: info: Loading plugin "upnp_browser"... Oct 17 12:53:39 volumio volumio[31091]: info: Starting UPNP Browser Oct 17 12:53:39 volumio volumio[31091]: info: Loading plugin "alarm-clock"... Oct 17 12:53:39 volumio volumio[31091]: info: Loading plugin "novaui"... Oct 17 12:53:39 volumio volumio[31091]: info: Applying required configuration parameters for plugin novaui Oct 17 12:53:39 volumio volumio[31091]: info: Loading plugin "airplay_emulation"... Oct 17 12:53:39 volumio volumio[31091]: info: Starting Shairport Sync Oct 17 12:53:39 volumio volumio[31091]: info: Loading plugin "last_100"... Oct 17 12:53:39 volumio volumio[31091]: info: Loading plugin "webradio"... Oct 17 12:53:39 volumio volumio[31091]: info: Loading plugin "i2s_dacs"... Oct 17 12:53:39 volumio volumio[31091]: info: Loading plugin "volumiodiscovery"... Oct 17 12:53:39 volumio volumio[31091]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:39 volumio volumio[31091]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:39 volumio node[31091]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:39 volumio volumio[31091]: *** WARNING *** For more information see Oct 17 12:53:39 volumio volumio[31091]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:39 volumio volumio[31091]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:39 volumio volumio[31091]: *** WARNING *** For more information see Oct 17 12:53:39 volumio node[31091]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:39 volumio node[31091]: *** WARNING *** For more information see Oct 17 12:53:39 volumio node[31091]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:39 volumio node[31091]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:39 volumio node[31091]: *** WARNING *** For more information see Oct 17 12:53:39 volumio volumio[31091]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 12:53:39 volumio volumio[31091]: info: Discovery: Started advertising with name: Volumio Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:40 volumio volumio[31091]: info: Loading plugin "outputs"... Oct 17 12:53:40 volumio volumio[31091]: info: Loading plugin "albumart"... Oct 17 12:53:40 volumio volumio[31091]: info: Plugin example_plugin is not enabled Oct 17 12:53:40 volumio volumio[31091]: info: Loading plugin "inputs"... Oct 17 12:53:40 volumio volumio[31091]: info: Loading plugin "updater_comm"... Oct 17 12:53:40 volumio volumio[31091]: info: Plugin mpdemulation is not enabled Oct 17 12:53:40 volumio volumio[31091]: info: Loading plugin "rest_api"... Oct 17 12:53:40 volumio volumio[31091]: info: Loading plugin "websocket"... Oct 17 12:53:40 volumio volumio[31091]: info: Starting Socket.io Server version 1.7.4 Oct 17 12:53:40 volumio volumio[31091]: info: Loading plugin "lastfm"... Oct 17 12:53:40 volumio volumio[31151]: Forking 3 albumart workers Oct 17 12:53:40 volumio volumio[31091]: info: Loading i18n strings for locale en Oct 17 12:53:40 volumio volumio[31091]: Updating browse sources language Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: Express server listening on port 3000 Oct 17 12:53:40 volumio volumio[31091]: [Metrics] WebUI: 3s 273.09ms Oct 17 12:53:40 volumio volumio[31091]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:40 volumio volumio[31091]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:40 volumio volumio[31091]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:40 volumio volumio[31091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 12:53:40 volumio volumio[31091]: info: CoreStateMachine::pushState Oct 17 12:53:40 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:40 volumio volumio[31091]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:40 volumio volumio[31091]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:40 volumio volumio[31091]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:40 volumio volumio[31091]: info: Reloading queue from file Oct 17 12:53:40 volumio volumio[31091]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:40 volumio volumio[31091]: info: CoreStateMachine::pushState Oct 17 12:53:40 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:40 volumio volumio[31091]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:40 volumio volumio[31091]: info: CoreStateMachine::pushState Oct 17 12:53:40 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:40 volumio volumio[31091]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:40 volumio volumio[31091]: info: CoreStateMachine::setRandom null Oct 17 12:53:41 volumio volumio[31091]: info: CoreStateMachine::pushState Oct 17 12:53:41 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:41 volumio volumio[31091]: info: Setting Device type: Raspberry PI Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:41 volumio volumio[31091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:41 volumio volumio[31091]: info: Completed loading Core Plugins Oct 17 12:53:41 volumio volumio[31091]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:41 volumio volumio[31091]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:41 volumio volumio[31091]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:41 volumio volumio[31091]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:41 volumio volumio[31091]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:41 volumio volumio[31091]: info: Reading ALSA contributions from plugins. Oct 17 12:53:41 volumio volumio[31091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:41 volumio volumio[31163]: Starting albumart workers Oct 17 12:53:41 volumio volumio[31091]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:41 volumio volumio[31091]: info: Discovery: Found device Volumioffice Oct 17 12:53:41 volumio volumio[31161]: Starting albumart workers Oct 17 12:53:41 volumio volumio[31162]: Starting albumart workers Oct 17 12:53:41 volumio volumio[31091]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:41 volumio volumio[31091]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:41 volumio sudo[31129]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:41 volumio volumio[31091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:41 volumio volumio[31091]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:41 volumio volumio[31091]: info: Discovery: Found device Volumio Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:41 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:41 volumio volumio[31091]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:41 volumio volumio[31091]: info: Discovery: Found device Volumio Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:41 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:41 volumio volumio[31091]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:41 volumio volumio[31091]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:41 volumio volumio[31091]: info: Output device has changed, restarting MPD Oct 17 12:53:41 volumio volumio[31091]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:41 volumio sudo[31215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:41 volumio sudo[31215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:41 volumio sudo[31216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:41 volumio sudo[31215]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:41 volumio sudo[31216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:41 volumio volumio[31091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:41 volumio volumio[31091]: info: ___________ START PLUGINS ___________ Oct 17 12:53:41 volumio volumio[31091]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:41 volumio volumio[31091]: info: Creating MPD Configuration file Oct 17 12:53:41 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:41 volumio sudo[31231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:41 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:41 volumio volumio[31091]: info: [1792202021631] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:41 volumio sudo[31231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:41 volumio sudo[31225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:41 volumio sudo[31225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:41 volumio volumio[31091]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:41 volumio sudo[31231]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:41 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:41 volumio systemd[1]: mpd.service: Consumed 1.638s CPU time. Oct 17 12:53:41 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:41 volumio volumio[31091]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:41 volumio sudo[31233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:41 volumio sudo[31233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:41 volumio volumio[31091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:41 volumio volumio[31091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:41 volumio volumio[31091]: info: [1792202021753] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:41 volumio volumio[31091]: info: [1792202021757] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:41 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:41 volumio volumio[31091]: info: Initializing BBC Radios Oct 17 12:53:41 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:41 volumio sudo[31225]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:41 volumio volumio[31091]: info: [1792202021847] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:41 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:41 volumio volumio[31091]: Cannot find translation for source LastFM Oct 17 12:53:41 volumio volumio[31091]: info: [LastFM] scrobbler initiated! Oct 17 12:53:41 volumio volumio[31091]: info: [LastFM] extended logging: false Oct 17 12:53:41 volumio volumio[31091]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:41 volumio volumio[31091]: info: [LastFM] Left init routine Oct 17 12:53:41 volumio volumio[31091]: info: [LastFM] Socket already connected: true Oct 17 12:53:41 volumio volumio[31091]: info: Volumio Calling Home Oct 17 12:53:41 volumio sudo[31265]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:41 volumio sudo[31265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:41 volumio sudo[31265]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:42 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:42 volumio volumio[31091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:42 volumio volumio[31091]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:42 volumio volumio[31091]: info: Received Get System Info Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:42 volumio volumio[31091]: info: Discovery: Getting this device information Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:42 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:42 volumio volumio[31091]: info: Received Get System Version Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:42 volumio volumio[31091]: info: Received Get System Info Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:42 volumio volumio[31091]: info: Discovery: Getting this device information Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:42 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 17 12:53:42 volumio sudo[31294]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:42 volumio sudo[31283]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 17 12:53:42 volumio sudo[31283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:42 volumio sudo[31294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:42 volumio sudo[31289]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:42 volumio volumio[31091]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 17 12:53:42 volumio sudo[31289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 17 12:53:42 volumio sudo[31294]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:42 volumio sudo[31283]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:42 volumio sudo[31289]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:42 volumio sudo[31303]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:42 volumio sudo[31303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:42 volumio sudo[31299]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:42 volumio volumio[31091]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::volumioGetQueue Oct 17 12:53:42 volumio volumio[31091]: info: CoreStateMachine::getQueue Oct 17 12:53:42 volumio volumio[31091]: info: CorePlayQueue::getQueue Oct 17 12:53:42 volumio sudo[31299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:42 volumio sudo[31299]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:42 volumio sudo[31308]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:42 volumio sudo[31303]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:42 volumio sudo[31308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:42 volumio sudo[31308]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:42 volumio volumio[31091]: info: MPD Permissions set Oct 17 12:53:42 volumio volumio[31091]: info: MPD Permissions set Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 17 12:53:42 volumio volumio[31091]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 17 12:53:42 volumio volumio[31091]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:42 volumio volumio[31091]: Error: connect ECONNREFUSED 34.49.30.2:80 Oct 17 12:53:42 volumio volumio[31091]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 12:53:42 volumio volumio[31091]: errno: -111, Oct 17 12:53:42 volumio volumio[31091]: code: 'ECONNREFUSED', Oct 17 12:53:42 volumio volumio[31091]: syscall: 'connect', Oct 17 12:53:42 volumio volumio[31091]: address: '34.49.30.2', Oct 17 12:53:42 volumio volumio[31091]: port: 80 Oct 17 12:53:42 volumio volumio[31091]: } Oct 17 12:53:42 volumio volumio[31091]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:42 volumio sudo[31330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:52' Oct 17 12:53:42 volumio sudo[31330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:43 volumio sudo[31330]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:43 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:43.194+11:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 17 12:53:43 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 12:53:43 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:43.196+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:43 volumio sudo[31216]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:43 volumio sudo[31233]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:43 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 12:53:43 volumio systemd[1]: volumio.service: Consumed 12.369s CPU time. Oct 17 12:53:43 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:43 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 115. Oct 17 12:53:43 volumio mpd[31281]: 2026-10-17T12:53:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:43 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:43 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 12:53:43 volumio systemd[1]: volumio.service: Consumed 12.369s CPU time. Oct 17 12:53:43 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 12:53:43 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:43 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:43 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:43 volumio volumio-remote-updater[703]: [2026-10-17 12:53:43] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 17 12:53:43 volumio volumio-remote-updater[703]: [2026-10-17 12:53:43] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 17 12:53:43 volumio volumio-remote-updater[703]: [2026-10-17 12:53:43] [error] handle_connect error: Connection refused Oct 17 12:53:44 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:44.198+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:44 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:44.217+11:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 17 12:53:44 volumio volumio[31353]: info: ------------------------------------------- Oct 17 12:53:44 volumio volumio[31353]: info: ----- Volumio3 ---- Oct 17 12:53:44 volumio volumio[31353]: info: ------------------------------------------- Oct 17 12:53:44 volumio volumio[31353]: info: ----- System startup ---- Oct 17 12:53:44 volumio volumio[31353]: info: ------------------------------------------- Oct 17 12:53:44 volumio volumio[31353]: info: MYVOLUMIO Environment detected Oct 17 12:53:44 volumio volumio[31353]: info: Plugin folders cleanup Oct 17 12:53:44 volumio volumio[31353]: info: Scanning into folder /volumio/app/plugins/ Oct 17 12:53:44 volumio volumio[31353]: info: Scanning category audio_interface Oct 17 12:53:44 volumio volumio[31353]: info: Scanning category miscellanea Oct 17 12:53:44 volumio volumio[31353]: info: Scanning category music_service Oct 17 12:53:44 volumio volumio[31353]: info: Scanning category plugins.json Oct 17 12:53:44 volumio volumio[31353]: info: Scanning category system_controller Oct 17 12:53:44 volumio volumio[31353]: info: Scanning category user_interface Oct 17 12:53:44 volumio volumio[31353]: info: Scanning into folder /data/plugins/ Oct 17 12:53:44 volumio volumio[31353]: info: Scanning category user_interface Oct 17 12:53:44 volumio volumio[31353]: info: Plugin folders cleanup completed Oct 17 12:53:44 volumio volumio[31353]: info: ------------------------------------------- Oct 17 12:53:44 volumio volumio[31353]: info: ----- Core plugins startup ---- Oct 17 12:53:44 volumio volumio[31353]: info: ------------------------------------------- Oct 17 12:53:44 volumio volumio[31353]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 12:53:44 volumio volumio[31353]: info: Adding plugin upnp to MyMusic Plugins Oct 17 12:53:44 volumio volumio[31353]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 12:53:44 volumio volumio[31353]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 12:53:44 volumio volumio[31353]: info: Loading plugins from folder /data/plugins/ Oct 17 12:53:44 volumio volumio[31353]: info: Loading plugin "system"... Oct 17 12:53:44 volumio volumio[31353]: info: Loading plugin "appearance"... Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "network"... Oct 17 12:53:45 volumio volumio[31353]: info: Refreshing Cached IP Addresses Oct 17 12:53:45 volumio sudo[31381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:45 volumio sudo[31381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:45 volumio sudo[31383]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:45 volumio sudo[31383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "services"... Oct 17 12:53:45 volumio sudo[31381]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "volumio5onboarding"... Oct 17 12:53:45 volumio sudo[31383]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:45 volumio sudo[31390]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "alsa_controller"... Oct 17 12:53:45 volumio sudo[31390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:45 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "wizard"... Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "networkfs"... Oct 17 12:53:45 volumio volumio[31353]: info: Starting Udev Watcher for removable devices Oct 17 12:53:45 volumio volumio[31353]: info: Ignoring mount for partition: boot Oct 17 12:53:45 volumio volumio[31353]: info: Ignoring mount for partition: volumio Oct 17 12:53:45 volumio volumio[31353]: info: Ignoring mount for partition: volumio_data Oct 17 12:53:45 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "volumio_command_line_client"... Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "upnp"... Oct 17 12:53:45 volumio volumio[31353]: info: [1792202025683] Starting Upmpd Daemon Oct 17 12:53:45 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "my_music"... Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "mpd"... Oct 17 12:53:45 volumio volumio[31353]: info: Loading plugin "upnp_browser"... Oct 17 12:53:46 volumio volumio[31353]: info: Starting UPNP Browser Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "alarm-clock"... Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "novaui"... Oct 17 12:53:46 volumio volumio[31353]: info: Applying required configuration parameters for plugin novaui Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "airplay_emulation"... Oct 17 12:53:46 volumio volumio[31353]: info: Starting Shairport Sync Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "last_100"... Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "webradio"... Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "i2s_dacs"... Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "volumiodiscovery"... Oct 17 12:53:46 volumio volumio[31353]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:46 volumio volumio[31353]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:46 volumio volumio[31353]: *** WARNING *** For more information see Oct 17 12:53:46 volumio volumio[31353]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:46 volumio volumio[31353]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:46 volumio node[31353]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:46 volumio volumio[31353]: *** WARNING *** For more information see Oct 17 12:53:46 volumio node[31353]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:46 volumio node[31353]: *** WARNING *** For more information see Oct 17 12:53:46 volumio node[31353]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:46 volumio node[31353]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:46 volumio node[31353]: *** WARNING *** For more information see Oct 17 12:53:46 volumio volumio[31353]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 12:53:46 volumio volumio[31353]: info: Discovery: Started advertising with name: Volumio Oct 17 12:53:46 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "outputs"... Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "albumart"... Oct 17 12:53:46 volumio volumio[31353]: info: Plugin example_plugin is not enabled Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "inputs"... Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "updater_comm"... Oct 17 12:53:46 volumio volumio[31353]: info: Plugin mpdemulation is not enabled Oct 17 12:53:46 volumio volumio[31353]: info: Loading plugin "rest_api"... Oct 17 12:53:47 volumio volumio[31353]: info: Loading plugin "websocket"... Oct 17 12:53:47 volumio volumio[31353]: info: Starting Socket.io Server version 1.7.4 Oct 17 12:53:47 volumio volumio[31353]: info: Loading plugin "lastfm"... Oct 17 12:53:47 volumio volumio[31413]: Forking 3 albumart workers Oct 17 12:53:47 volumio volumio[31353]: info: Loading i18n strings for locale en Oct 17 12:53:47 volumio volumio[31353]: Updating browse sources language Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: Express server listening on port 3000 Oct 17 12:53:47 volumio volumio[31353]: [Metrics] WebUI: 3s 301.22ms Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:47 volumio volumio[31353]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:47 volumio volumio[31353]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::pushState Oct 17 12:53:47 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:47 volumio volumio[31353]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:47 volumio volumio[31353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:47 volumio volumio[31353]: info: Reloading queue from file Oct 17 12:53:47 volumio volumio[31353]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::pushState Oct 17 12:53:47 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::pushState Oct 17 12:53:47 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::setRandom null Oct 17 12:53:47 volumio volumio[31353]: info: CoreStateMachine::pushState Oct 17 12:53:47 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:47 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:47 volumio volumio[31353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:47 volumio volumio[31353]: info: Setting Device type: Raspberry PI Oct 17 12:53:47 volumio volumio[31353]: info: Completed loading Core Plugins Oct 17 12:53:47 volumio volumio[31353]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:47 volumio volumio[31353]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:47 volumio volumio[31353]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:47 volumio volumio[31353]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:47 volumio volumio[31353]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:47 volumio volumio[31353]: info: Reading ALSA contributions from plugins. Oct 17 12:53:47 volumio volumio[31353]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:47 volumio volumio[31353]: info: Discovery: Found device Volumioffice Oct 17 12:53:47 volumio volumio[31353]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:48 volumio volumio[31353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:48 volumio volumio[31423]: Starting albumart workers Oct 17 12:53:48 volumio volumio[31425]: Starting albumart workers Oct 17 12:53:48 volumio volumio[31424]: Starting albumart workers Oct 17 12:53:48 volumio volumio[31353]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 12:53:48 volumio sudo[31390]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:48 volumio volumio[31353]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:48 volumio volumio[31353]: info: Discovery: Found device Volumio Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:48 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:48 volumio volumio[31353]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:48 volumio volumio[31353]: info: Discovery: Found device Volumio Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:48 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:48 volumio volumio[31353]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:48 volumio volumio[31353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:48 volumio volumio[31353]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:48 volumio volumio[31353]: info: Output device has changed, restarting MPD Oct 17 12:53:48 volumio sudo[31477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:48 volumio volumio[31353]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:48 volumio sudo[31477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:48 volumio sudo[31477]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:48 volumio sudo[31478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:48 volumio sudo[31478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:48 volumio volumio[31353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:48 volumio volumio[31353]: info: ___________ START PLUGINS ___________ Oct 17 12:53:48 volumio volumio[31353]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:48 volumio volumio[31353]: info: Creating MPD Configuration file Oct 17 12:53:48 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:48 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:48 volumio sudo[31486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:48 volumio sudo[31486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:48 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:48 volumio systemd[1]: mpd.service: Consumed 1.553s CPU time. Oct 17 12:53:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:48 volumio sudo[31488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:48 volumio sudo[31488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:48 volumio sudo[31491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:48 volumio volumio[31353]: info: [1792202028485] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:48 volumio volumio[31353]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:48 volumio sudo[31491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:48 volumio sudo[31488]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:48 volumio volumio[31353]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:48 volumio volumio[31353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:48 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:48 volumio sudo[31486]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:48 volumio volumio[31353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:48 volumio volumio[31353]: info: [1792202028591] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:48 volumio volumio[31353]: info: [1792202028595] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:48 volumio volumio[31353]: info: Initializing BBC Radios Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:48 volumio volumio[31353]: info: [1792202028669] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:48 volumio volumio[31353]: Cannot find translation for source LastFM Oct 17 12:53:48 volumio volumio[31353]: info: [LastFM] scrobbler initiated! Oct 17 12:53:48 volumio volumio[31353]: info: [LastFM] extended logging: false Oct 17 12:53:48 volumio volumio[31353]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:48 volumio volumio[31353]: info: [LastFM] Left init routine Oct 17 12:53:48 volumio volumio[31353]: info: [LastFM] Socket already connected: true Oct 17 12:53:48 volumio volumio[31353]: info: Volumio Calling Home Oct 17 12:53:48 volumio sudo[31519]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:48 volumio sudo[31519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:48 volumio sudo[31519]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:48 volumio volumio[31353]: info: Received Get System Version Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:48 volumio volumio[31353]: info: Received Get System Info Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:48 volumio volumio[31353]: info: Discovery: Getting this device information Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:48 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:48 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 17 12:53:49 volumio sudo[31545]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 17 12:53:49 volumio sudo[31545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:49 volumio sudo[31545]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:49 volumio sudo[31550]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:49 volumio sudo[31550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:49 volumio sudo[31550]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:49 volumio sudo[31557]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:49 volumio sudo[31557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:49 volumio volumio[31353]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 17 12:53:49 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 17 12:53:49 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 17 12:53:49 volumio sudo[31557]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:49 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 12:53:49 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 12:53:49 volumio sudo[31563]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:49 volumio volumio[31353]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:49 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:49 volumio sudo[31563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:49 volumio volumio[31353]: info: CoreCommandRouter::volumioGetQueue Oct 17 12:53:49 volumio volumio[31353]: info: CoreStateMachine::getQueue Oct 17 12:53:49 volumio volumio[31353]: info: CorePlayQueue::getQueue Oct 17 12:53:49 volumio sudo[31563]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:49 volumio sudo[31568]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:49 volumio sudo[31568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:49 volumio sudo[31568]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:49 volumio sudo[31570]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:49 volumio sudo[31570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:49 volumio sudo[31570]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:49 volumio volumio[31353]: info: MPD Permissions set Oct 17 12:53:49 volumio volumio[31353]: info: MPD Permissions set Oct 17 12:53:49 volumio volumio[31353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 17 12:53:49 volumio volumio[31353]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:49 volumio volumio[31353]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:49 volumio volumio[31353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 17 12:53:49 volumio volumio[31353]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:49 volumio volumio[31353]: Error: connect ECONNREFUSED 34.49.30.2:80 Oct 17 12:53:49 volumio volumio[31353]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 12:53:49 volumio volumio[31353]: errno: -111, Oct 17 12:53:49 volumio volumio[31353]: code: 'ECONNREFUSED', Oct 17 12:53:49 volumio volumio[31353]: syscall: 'connect', Oct 17 12:53:49 volumio volumio[31353]: address: '34.49.30.2', Oct 17 12:53:49 volumio volumio[31353]: port: 80 Oct 17 12:53:49 volumio volumio[31353]: } Oct 17 12:53:49 volumio volumio[31353]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:53:49 volumio sudo[31592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:52' Oct 17 12:53:49 volumio sudo[31592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:49 volumio sudo[31592]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:50 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:50.044+11:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:46218->127.0.0.1:3000: read: connection reset by peer" Oct 17 12:53:50 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:50.047+11:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:46256->127.0.0.1:3000: read: connection reset by peer" Oct 17 12:53:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 12:53:50 volumio sudo[31491]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:50 volumio sudo[31478]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 12:53:50 volumio systemd[1]: volumio.service: Consumed 12.314s CPU time. Oct 17 12:53:50 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:50 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:50 volumio mpd[31543]: 2026-10-17T12:53:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 116. Oct 17 12:53:50 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:50 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 12:53:50 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 12:53:50 volumio systemd[1]: volumio.service: Consumed 12.314s CPU time. Oct 17 12:53:50 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 12:53:50 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 12:53:50 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:51 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:51.050+11:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 12:53:51 volumio volumio[31609]: info: ------------------------------------------- Oct 17 12:53:51 volumio volumio[31609]: info: ----- Volumio3 ---- Oct 17 12:53:51 volumio volumio[31609]: info: ------------------------------------------- Oct 17 12:53:51 volumio volumio[31609]: info: ----- System startup ---- Oct 17 12:53:51 volumio volumio[31609]: info: ------------------------------------------- Oct 17 12:53:51 volumio volumio[31609]: info: MYVOLUMIO Environment detected Oct 17 12:53:51 volumio volumio[31609]: info: Plugin folders cleanup Oct 17 12:53:51 volumio volumio[31609]: info: Scanning into folder /volumio/app/plugins/ Oct 17 12:53:51 volumio volumio[31609]: info: Scanning category audio_interface Oct 17 12:53:51 volumio volumio[31609]: info: Scanning category miscellanea Oct 17 12:53:51 volumio volumio[31609]: info: Scanning category music_service Oct 17 12:53:51 volumio volumio[31609]: info: Scanning category plugins.json Oct 17 12:53:51 volumio volumio[31609]: info: Scanning category system_controller Oct 17 12:53:51 volumio volumio[31609]: info: Scanning category user_interface Oct 17 12:53:51 volumio volumio[31609]: info: Scanning into folder /data/plugins/ Oct 17 12:53:51 volumio volumio[31609]: info: Scanning category user_interface Oct 17 12:53:51 volumio volumio[31609]: info: Plugin folders cleanup completed Oct 17 12:53:51 volumio volumio[31609]: info: ------------------------------------------- Oct 17 12:53:51 volumio volumio[31609]: info: ----- Core plugins startup ---- Oct 17 12:53:51 volumio volumio[31609]: info: ------------------------------------------- Oct 17 12:53:51 volumio volumio[31609]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 12:53:51 volumio volumio[31609]: info: Adding plugin upnp to MyMusic Plugins Oct 17 12:53:51 volumio volumio[31609]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 12:53:51 volumio volumio[31609]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 12:53:51 volumio volumio[31609]: info: Loading plugins from folder /data/plugins/ Oct 17 12:53:51 volumio volumio[31609]: info: Loading plugin "system"... Oct 17 12:53:51 volumio volumio[31609]: info: Loading plugin "appearance"... Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "network"... Oct 17 12:53:52 volumio volumio[31609]: info: Refreshing Cached IP Addresses Oct 17 12:53:52 volumio sudo[31637]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:52 volumio sudo[31637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:52 volumio sudo[31639]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:52 volumio sudo[31639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "services"... Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "volumio5onboarding"... Oct 17 12:53:52 volumio sudo[31637]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:52 volumio sudo[31639]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "alsa_controller"... Oct 17 12:53:52 volumio sudo[31646]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 12:53:52 volumio sudo[31646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:52 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "wizard"... Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "networkfs"... Oct 17 12:53:52 volumio volumio[31609]: info: Starting Udev Watcher for removable devices Oct 17 12:53:52 volumio volumio[31609]: info: Ignoring mount for partition: boot Oct 17 12:53:52 volumio volumio[31609]: info: Ignoring mount for partition: volumio Oct 17 12:53:52 volumio volumio[31609]: info: Ignoring mount for partition: volumio_data Oct 17 12:53:52 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "volumio_command_line_client"... Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "upnp"... Oct 17 12:53:52 volumio volumio[31609]: info: [1792202032528] Starting Upmpd Daemon Oct 17 12:53:52 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "my_music"... Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "mpd"... Oct 17 12:53:52 volumio volumio[31609]: info: Loading plugin "upnp_browser"... Oct 17 12:53:53 volumio volumio[31609]: info: Starting UPNP Browser Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "alarm-clock"... Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "novaui"... Oct 17 12:53:53 volumio volumio[31609]: info: Applying required configuration parameters for plugin novaui Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "airplay_emulation"... Oct 17 12:53:53 volumio volumio[31609]: info: Starting Shairport Sync Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "last_100"... Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "webradio"... Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "i2s_dacs"... Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "volumiodiscovery"... Oct 17 12:53:53 volumio volumio[31609]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:53 volumio volumio[31609]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:53 volumio node[31609]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:53 volumio volumio[31609]: *** WARNING *** For more information see Oct 17 12:53:53 volumio volumio[31609]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:53 volumio volumio[31609]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:53 volumio volumio[31609]: *** WARNING *** For more information see Oct 17 12:53:53 volumio node[31609]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:53 volumio node[31609]: *** WARNING *** For more information see Oct 17 12:53:53 volumio node[31609]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 12:53:53 volumio node[31609]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 12:53:53 volumio node[31609]: *** WARNING *** For more information see Oct 17 12:53:53 volumio volumio[31609]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 12:53:53 volumio volumio[31609]: info: Discovery: Started advertising with name: Volumio Oct 17 12:53:53 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "outputs"... Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "albumart"... Oct 17 12:53:53 volumio volumio[31609]: info: Plugin example_plugin is not enabled Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "inputs"... Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "updater_comm"... Oct 17 12:53:53 volumio volumio[31609]: info: Plugin mpdemulation is not enabled Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "rest_api"... Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "websocket"... Oct 17 12:53:53 volumio volumio[31609]: info: Starting Socket.io Server version 1.7.4 Oct 17 12:53:53 volumio volumio[31609]: info: Loading plugin "lastfm"... Oct 17 12:53:53 volumio volumio-remote-updater[703]: [2026-10-17 12:53:53] [connect] Successful connection Oct 17 12:53:54 volumio volumio[31669]: Forking 3 albumart workers Oct 17 12:53:54 volumio volumio[31609]: info: Loading i18n strings for locale en Oct 17 12:53:54 volumio volumio[31609]: Updating browse sources language Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::initPlayerControls Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: Express server listening on port 3000 Oct 17 12:53:54 volumio volumio[31609]: [Metrics] WebUI: 3s 282.87ms Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::resetVolumioState Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::getcurrentVolume Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:54 volumio volumio[31609]: info: Volumio Network Manager: Network status updated: 1 Oct 17 12:53:54 volumio volumio[31609]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::pushState Oct 17 12:53:54 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::updateTrackBlock Oct 17 12:53:54 volumio volumio[31609]: info: CorePlayQueue::getTrackBlock Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 12:53:54 volumio volumio[31609]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: Reloading queue from file Oct 17 12:53:54 volumio volumio[31609]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.24 UA: Mozilla/5.0 (X11; Linux x86_64; rv:153.0) Gecko/20100101 Firefox/153.0 Engine version: 3 Transport: websocket Total Clients: 2 Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::pushState Oct 17 12:53:54 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::setRepeat null single undefined Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::pushState Oct 17 12:53:54 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::setRandom null Oct 17 12:53:54 volumio volumio[31609]: info: CoreStateMachine::pushState Oct 17 12:53:54 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioPushState Oct 17 12:53:54 volumio volumio[31609]: info: Setting Device type: Raspberry PI Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 12:53:54 volumio volumio-remote-updater[703]: [2026-10-17 12:53:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792202033 101 Oct 17 12:53:54 volumio volumio[31609]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Oct 17 12:53:54 volumio volumio[31609]: info: Completed loading Core Plugins Oct 17 12:53:54 volumio volumio[31609]: info: Preparing to generate the ALSA configuration file Oct 17 12:53:54 volumio volumio[31609]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 12:53:54 volumio volumio[31609]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Oct 17 12:53:54 volumio volumio[31609]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 12:53:54 volumio volumio[31609]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 12:53:54 volumio volumio[31609]: info: Reading ALSA contributions from plugins. Oct 17 12:53:54 volumio volumio[31609]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:54 volumio volumio[31609]: info: Discovery: adding e8b8274c-998a-4c65-9592-e606c3391eb7 Oct 17 12:53:54 volumio volumio[31609]: info: Discovery: Found device Volumioffice Oct 17 12:53:54 volumio volumio[31609]: info: Discovery: Connecting to remote: 192.168.1.18 Oct 17 12:53:54 volumio volumio[31609]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:54 volumio volumio[31609]: verbose: New Socket.io Connection to 192.168.1.10:3000 from 192.168.1.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 12:53:54 volumio volumio[31609]: info: Discovery: adding 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:54 volumio volumio[31609]: info: Discovery: Found device Volumio Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:54 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:54 volumio volumio[31609]: info: Discovery: this is already registered, 4a4bea71-ec8c-476c-8fda-9b12e43a1a6e Oct 17 12:53:54 volumio volumio[31609]: info: Discovery: Found device Volumio Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:54 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:54 volumio volumio[31609]: info: Discovery: Connected to remote: 192.168.1.18 Oct 17 12:53:54 volumio volumio[31609]: info: Asound.conf file unchanged, so no further update is needed Oct 17 12:53:54 volumio volumio[31609]: info: Output device has changed, restarting MPD Oct 17 12:53:54 volumio volumio[31609]: info: Output device has changed, restarting Shairport Sync Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:54 volumio sudo[31733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:54 volumio sudo[31733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:54 volumio sudo[31733]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:54 volumio sudo[31735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:54 volumio sudo[31735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:54 volumio volumio[31609]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:54 volumio volumio[31609]: info: ___________ START PLUGINS ___________ Oct 17 12:53:54 volumio volumio[31681]: Starting albumart workers Oct 17 12:53:54 volumio sudo[31646]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:54 volumio volumio[31680]: Starting albumart workers Oct 17 12:53:54 volumio volumio[31609]: info: ControllerMpd::onStart: Initializing MPD Oct 17 12:53:54 volumio volumio[31609]: info: Creating MPD Configuration file Oct 17 12:53:54 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 12:53:54 volumio upmpdcli[1604]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:54 volumio volumio[31609]: info: [1792202034984] CoreMusicLibrary::Adding element Media Servers Oct 17 12:53:54 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:55 volumio sudo[31743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 12:53:55 volumio sudo[31743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio volumio[31609]: info: UPNP Browser: Client initialized successfully Oct 17 12:53:55 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:55 volumio sudo[31745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 12:53:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:55 volumio volumio[31609]: info: Nova UI: unpacking bundled version 0.260911.1 Oct 17 12:53:55 volumio sudo[31745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio systemd[1]: mpd.service: Consumed 1.524s CPU time. Oct 17 12:53:55 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:55 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:55 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:55 volumio sudo[31747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 12:53:55 volumio sudo[31747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio sudo[31745]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:55 volumio volumio[31609]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:55 volumio volumio[31609]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:55 volumio volumio[31609]: info: [1792202035121] CoreMusicLibrary::Adding element Last_100 Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:55 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 12:53:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:55 volumio volumio[31609]: info: [1792202035127] CoreMusicLibrary::Adding element Webradio Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:55 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:55 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 12:53:55 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 12:53:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 12:53:55 volumio volumio[31609]: info: Initializing BBC Radios Oct 17 12:53:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 12:53:55 volumio sudo[31743]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio volumio[31679]: Starting albumart workers Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 12:53:55 volumio volumio[31609]: info: [1792202035271] CoreMusicLibrary::Adding element LastFM Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:55 volumio volumio[31609]: Cannot find translation for source LastFM Oct 17 12:53:55 volumio volumio[31609]: info: [LastFM] scrobbler initiated! Oct 17 12:53:55 volumio volumio[31609]: info: [LastFM] extended logging: false Oct 17 12:53:55 volumio volumio[31609]: info: [LastFM] try scrobble stream/radio plays: false Oct 17 12:53:55 volumio sudo[31764]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 12:53:55 volumio sudo[31764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 12:53:55 volumio sudo[31764]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio volumio[31609]: info: [LastFM] Left init routine Oct 17 12:53:55 volumio volumio[31609]: info: [LastFM] Socket already connected: false Oct 17 12:53:55 volumio volumio[31609]: info: Volumio Calling Home Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 12:53:55 volumio volumio[31609]: info: Received Get System Version Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 12:53:55 volumio volumio[31609]: info: Received Get System Info Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:55 volumio volumio[31609]: info: Discovery: Getting this device information Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:55 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 17 12:53:55 volumio sudo[31803]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 17 12:53:55 volumio sudo[31803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio sudo[31813]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:55 volumio sudo[31809]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:55 volumio sudo[31813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio sudo[31809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio volumio[31609]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 17 12:53:55 volumio sudo[31813]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 17 12:53:55 volumio sudo[31803]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio sudo[31818]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 12:53:55 volumio sudo[31818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:55 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:55 volumio sudo[31809]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio volumio[31609]: info: CoreCommandRouter::volumioGetQueue Oct 17 12:53:55 volumio volumio[31609]: info: CoreStateMachine::getQueue Oct 17 12:53:55 volumio volumio[31609]: info: CorePlayQueue::getQueue Oct 17 12:53:55 volumio sudo[31828]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 12:53:55 volumio sudo[31818]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio sudo[31828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio sudo[31824]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 12:53:55 volumio sudo[31828]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio sudo[31824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:55 volumio sudo[31824]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:55 volumio volumio[31609]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:56 volumio volumio[31609]: info: MPD Permissions set Oct 17 12:53:56 volumio volumio[31609]: info: MPD Permissions set Oct 17 12:53:56 volumio volumio[31609]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 17 12:53:56 volumio volumio[31609]: info: Received Get System Info Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 12:53:56 volumio volumio[31609]: info: Discovery: Getting this device information Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:56 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 12:53:56 volumio volumio[31609]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::volumioGetState Oct 17 12:53:56 volumio volumio[31609]: info: CorePlayQueue::getTrack 0 Oct 17 12:53:56 volumio volumio[31609]: info: Starting Shairport Sync Oct 17 12:53:56 volumio volumio[31609]: info: Starting Shairport Sync Oct 17 12:53:56 volumio volumio[31609]: info: Starting Shairport Sync Oct 17 12:53:56 volumio sudo[31852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 12:53:56 volumio sudo[31852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:56 volumio sudo[31851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 12:53:56 volumio sudo[31851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:56 volumio sudo[31854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 12:53:56 volumio sudo[31854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: appearance , registerThirdPartyUI Oct 17 12:53:56 volumio volumio[31609]: info: Registering Third Party UI: {"uiName":"nova","uiPath":"/data/nova/current","uiPrettyName":"Nova"} Oct 17 12:53:56 volumio volumio[31609]: info: UI already registered: {"uiName":"nova","uiPath":"/data/nova/current","uiPrettyName":"Nova"} Oct 17 12:53:56 volumio volumio[31609]: info: Nova UI: serving version 0.261012.0 Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentUpdaterChannel Oct 17 12:53:56 volumio volumio[31609]: info: Nova UI: checking for updates on the stable channel: https://static.vlmapi.io/volumio/assets/v4/themes/nova/manifest.json Oct 17 12:53:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 12:53:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 12:53:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 12:53:56 volumio systemd[1]: shairport-sync.service: Consumed 2.969s CPU time. Oct 17 12:53:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 12:53:56 volumio sudo[31852]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:56 volumio volumio[31609]: info: Shairport-Sync Started Oct 17 12:53:56 volumio volumio[31609]: Error adding Membership: Error: addMembership EINVAL Oct 17 12:53:56 volumio sudo[31851]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 12:53:56 volumio volumio[31609]: info: Shairport-Sync Started Oct 17 12:53:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 12:53:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 12:53:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 12:53:56 volumio sudo[31854]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:56 volumio volumio[31609]: info: Shairport-Sync Started Oct 17 12:53:56 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 17 12:53:56 volumio volumio[31609]: info: Nova UI: update check: up to date Oct 17 12:53:56 volumio mpd[31779]: 2026-10-17T12:53:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 12:53:56 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 12:53:56 volumio sudo[31735]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:56 volumio sudo[31747]: pam_unix(sudo:session): session closed for user root Oct 17 12:53:56 volumio upmpdcli[1604]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 17 12:53:57 volumio volumio[31609]: info: Completed starting Core Plugins Oct 17 12:53:57 volumio volumio[31609]: info: ------------------------------------------- Oct 17 12:53:57 volumio volumio[31609]: info: ----- MyVolumio plugins startup ---- Oct 17 12:53:57 volumio volumio[31609]: info: ------------------------------------------- Oct 17 12:53:57 volumio volumio[31609]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 17 12:53:57 volumio volumio[31609]: error: MPD error: The expression evaluated to a falsy value: Oct 17 12:53:57 volumio volumio[31609]: assert.ok(self.idling) Oct 17 12:53:57 volumio volumio[31609]: error: The expression evaluated to a falsy value: Oct 17 12:53:57 volumio volumio[31609]: assert.ok(self.idling) Oct 17 12:53:57 volumio volumio[31609]: error: updateQueue error: null Oct 17 12:53:57 volumio volumio[31609]: info: MPD running with PID31779 Oct 17 12:53:57 volumio volumio[31609]: ,establishing connection Oct 17 12:53:57 volumio volumio[31609]: error: updateQueue error: null Oct 17 12:53:57 volumio ntpd[995]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 17 12:53:57 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 17 12:53:57 volumio volumio[31609]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 17 12:53:57 volumio volumio5-onboarding[1727]: time=2026-10-17T12:53:57.907+11:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 17 12:54:00 volumio volumio[31609]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:54:00 volumio volumio[31609]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Oct 17 12:54:00 volumio volumio[31609]: at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:118:26) { Oct 17 12:54:00 volumio volumio[31609]: errno: -3007, Oct 17 12:54:00 volumio volumio[31609]: code: 'ENOTFOUND', Oct 17 12:54:00 volumio volumio[31609]: syscall: 'getaddrinfo', Oct 17 12:54:00 volumio volumio[31609]: hostname: 'ws.audioscrobbler.com' Oct 17 12:54:00 volumio volumio[31609]: } Oct 17 12:54:00 volumio volumio[31609]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 12:54:00 volumio sudo[31903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 12:53' Oct 17 12:54:00 volumio sudo[31903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ebd772339e193fb4b3a98c68e63a0009e72d6f27" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 14 07:39:23 UTC 2026" VOLUMIO_VERSION="4.204" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="915e71dafc26fd850574d238b4f947d5"