-- Logs begin at Sat 2025-12-13 11:09:59 GMT, end at Fri 2025-12-19 17:40:34 GMT. -- Dec 19 17:39:45 volumio volumio[4466]: error: error Dec 19 17:40:03 volumio volumio[4466]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:40:03 volumio volumio[4466]: Error: connect EHOSTUNREACH 158.69.209.125:443 Dec 19 17:40:03 volumio volumio[4466]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 19 17:40:03 volumio volumio[4466]: errno: -113, Dec 19 17:40:03 volumio volumio[4466]: code: 'EHOSTUNREACH', Dec 19 17:40:03 volumio volumio[4466]: syscall: 'connect', Dec 19 17:40:03 volumio volumio[4466]: address: '158.69.209.125', Dec 19 17:40:03 volumio volumio[4466]: port: 443 Dec 19 17:40:03 volumio volumio[4466]: } Dec 19 17:40:03 volumio volumio[4466]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:40:03 volumio sudo[19605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-19 17:39 Dec 19 17:40:03 volumio sudo[19605]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:03 volumio sudo[19605]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:03 volumio volumio-remote-updater[588]: [2025-12-19 17:40:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 19 17:40:03 volumio volumio-remote-updater[588]: [2025-12-19 17:40:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 19 17:40:03 volumio go-librespot[4715]: time="2025-12-19T17:40:03Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 19 17:40:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 19 17:40:03 volumio systemd[1]: media-My_Passport.mount: Succeeded. Dec 19 17:40:03 volumio ntfs-3g[4563]: Unmounting /dev/sda1 (My Passport) Dec 19 17:40:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 19 17:40:03 volumio systemd[1]: Started dynamicswap service. Dec 19 17:40:03 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 19 17:40:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 19 17:40:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Dec 19 17:40:04 volumio systemd[1]: Stopped Volumio Backend Module. Dec 19 17:40:04 volumio systemd[1]: Started Volumio Backend Module. Dec 19 17:40:04 volumio systemd[1]: Started dynamicswap service. Dec 19 17:40:04 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 19 17:40:05 volumio volumio[19628]: info: ------------------------------------------- Dec 19 17:40:05 volumio volumio[19628]: info: ----- Volumio3 ---- Dec 19 17:40:05 volumio volumio[19628]: info: ------------------------------------------- Dec 19 17:40:05 volumio volumio[19628]: info: ----- System startup ---- Dec 19 17:40:05 volumio volumio[19628]: info: ------------------------------------------- Dec 19 17:40:05 volumio volumio[19628]: info: MYVOLUMIO Environment detected Dec 19 17:40:05 volumio volumio[19628]: info: Plugin folders cleanup Dec 19 17:40:05 volumio volumio[19628]: info: Scanning into folder /volumio/app/plugins/ Dec 19 17:40:05 volumio volumio[19628]: info: Scanning category audio_interface Dec 19 17:40:05 volumio volumio[19628]: info: Scanning category miscellanea Dec 19 17:40:05 volumio volumio[19628]: info: Scanning category music_service Dec 19 17:40:05 volumio volumio[19628]: info: Scanning category plugins.json Dec 19 17:40:05 volumio volumio[19628]: info: Scanning category system_controller Dec 19 17:40:05 volumio volumio[19628]: info: Scanning category user_interface Dec 19 17:40:05 volumio volumio[19628]: info: Scanning into folder /data/plugins/ Dec 19 17:40:05 volumio volumio[19628]: info: Scanning category music_service Dec 19 17:40:05 volumio volumio[19628]: info: Plugin folders cleanup completed Dec 19 17:40:05 volumio volumio[19628]: info: ------------------------------------------- Dec 19 17:40:05 volumio volumio[19628]: info: ----- Core plugins startup ---- Dec 19 17:40:05 volumio volumio[19628]: info: ------------------------------------------- Dec 19 17:40:05 volumio volumio[19628]: info: Loading plugins from folder /volumio/app/plugins/ Dec 19 17:40:05 volumio volumio[19628]: info: Adding plugin upnp to MyMusic Plugins Dec 19 17:40:05 volumio volumio[19628]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 19 17:40:05 volumio volumio[19628]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 19 17:40:05 volumio volumio[19628]: info: Loading plugins from folder /data/plugins/ Dec 19 17:40:05 volumio volumio[19628]: info: Loading plugin "system"... Dec 19 17:40:05 volumio volumio[19628]: info: Loading plugin "appearance"... Dec 19 17:40:06 volumio volumio[19628]: info: Loading plugin "network"... Dec 19 17:40:06 volumio volumio[19628]: info: Refreshing Cached IP Addresses Dec 19 17:40:06 volumio sudo[19656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 17:40:06 volumio sudo[19658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:40:06 volumio volumio[19628]: info: Loading plugin "services"... Dec 19 17:40:06 volumio sudo[19656]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:06 volumio volumio[19628]: info: Loading plugin "alsa_controller"... Dec 19 17:40:06 volumio sudo[19658]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:06 volumio sudo[19658]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:06 volumio sudo[19670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 19 17:40:06 volumio sudo[19656]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:06 volumio sudo[19670]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:06 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 17:40:06 volumio volumio[19628]: info: Loading plugin "wizard"... Dec 19 17:40:06 volumio volumio[19628]: info: Loading plugin "networkfs"... Dec 19 17:40:06 volumio volumio[19628]: info: Starting Udev Watcher for removable devices Dec 19 17:40:06 volumio volumio[19628]: info: Ignoring mount for partition: BOOT Dec 19 17:40:06 volumio volumio[19628]: info: Ignoring mount for partition: volumio Dec 19 17:40:06 volumio volumio[19628]: info: Ignoring mount for partition: volumio_data Dec 19 17:40:06 volumio volumio[19628]: info: Mounting Device My_Passport Dec 19 17:40:06 volumio sudo[19714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/My_Passport -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 19 17:40:06 volumio sudo[19714]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:06 volumio sudo[19670]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:08 volumio volumio-remote-updater[588]: [2025-12-19 17:40:08] [connect] Successful connection Dec 19 17:40:10 volumio ntfs-3g[19725]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 19 17:40:10 volumio ntfs-3g[19725]: Mounted /dev/sda1 (Read-Write, label "My Passport", NTFS 3.1) Dec 19 17:40:10 volumio ntfs-3g[19725]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 19 17:40:10 volumio ntfs-3g[19725]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Dec 19 17:40:10 volumio ntfs-3g[19725]: Global ownership and permissions enforced, configuration type 7 Dec 19 17:40:10 volumio sudo[19714]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:10 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 17:40:10 volumio volumio[19628]: info: Loading plugin "volumio_command_line_client"... Dec 19 17:40:10 volumio volumio[19628]: info: Loading plugin "upnp"... Dec 19 17:40:10 volumio volumio[19628]: info: [1766166010764] Starting Upmpd Daemon Dec 19 17:40:10 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 17:40:10 volumio volumio[19628]: info: Loading plugin "my_music"... Dec 19 17:40:10 volumio volumio[19628]: info: Loading plugin "mpd"... Dec 19 17:40:10 volumio volumio[19628]: info: Loading plugin "upnp_browser"... Dec 19 17:40:11 volumio volumio[19628]: info: Loading plugin "alarm-clock"... Dec 19 17:40:11 volumio volumio[19628]: info: Loading plugin "airplay_emulation"... Dec 19 17:40:11 volumio volumio[19628]: info: Starting Shairport Sync Dec 19 17:40:11 volumio volumio[19628]: info: Loading plugin "last_100"... Dec 19 17:40:11 volumio volumio[19628]: info: Loading plugin "webradio"... Dec 19 17:40:11 volumio volumio[19628]: info: Loading plugin "i2s_dacs"... Dec 19 17:40:11 volumio volumio[19628]: info: I2S DAC not set, start Auto-detection Dec 19 17:40:11 volumio volumio[19628]: info: Loading plugin "volumiodiscovery"... Dec 19 17:40:11 volumio volumio[19628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 19 17:40:11 volumio volumio[19628]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 17:40:11 volumio volumio[19628]: *** WARNING *** For more information see Dec 19 17:40:11 volumio volumio[19628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 19 17:40:11 volumio volumio[19628]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 17:40:11 volumio node[19628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 19 17:40:11 volumio volumio[19628]: *** WARNING *** For more information see Dec 19 17:40:11 volumio node[19628]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 17:40:11 volumio node[19628]: *** WARNING *** For more information see Dec 19 17:40:11 volumio node[19628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 19 17:40:11 volumio node[19628]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 17:40:11 volumio node[19628]: *** WARNING *** For more information see Dec 19 17:40:11 volumio volumio[19628]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 19 17:40:11 volumio volumio[19628]: info: Discovery: Started advertising with name: Volumio Dec 19 17:40:11 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 17:40:11 volumio volumio[19628]: info: Loading plugin "spop"... Dec 19 17:40:12 volumio volumio[19628]: info: Loading plugin "outputs"... Dec 19 17:40:12 volumio volumio[19628]: info: Loading plugin "albumart"... Dec 19 17:40:12 volumio volumio[19628]: info: Plugin example_plugin is not enabled Dec 19 17:40:12 volumio volumio[19628]: info: Loading plugin "inputs"... Dec 19 17:40:12 volumio volumio[19628]: info: Loading plugin "updater_comm"... Dec 19 17:40:12 volumio volumio[19628]: info: Plugin mpdemulation is not enabled Dec 19 17:40:12 volumio volumio[19628]: info: Loading plugin "rest_api"... Dec 19 17:40:12 volumio volumio[19628]: info: Loading plugin "websocket"... Dec 19 17:40:12 volumio volumio[19628]: info: Starting Socket.io Server version 2.3.0 Dec 19 17:40:12 volumio volumio[19628]: info: Loading i18n strings for locale en Dec 19 17:40:12 volumio volumio[19628]: Updating browse sources language Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:40:12 volumio volumio[19628]: Forking 3 albumart workers Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::initPlayerControls Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: Express server listening on port 3000 Dec 19 17:40:12 volumio volumio[19628]: [Metrics] WebUI: 7s 487.45ms Dec 19 17:40:12 volumio volumio[19628]: info: CoreStateMachine::resetVolumioState Dec 19 17:40:12 volumio volumio[19628]: info: CoreStateMachine::getcurrentVolume Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 19 17:40:12 volumio volumio[19628]: info: Completed loading Core Plugins Dec 19 17:40:12 volumio volumio[19628]: info: Preparing to generate the ALSA configuration file Dec 19 17:40:12 volumio volumio[19628]: info: Volumio Network Manager: Network status updated: 2 Dec 19 17:40:12 volumio volumio[19628]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Dec 19 17:40:12 volumio volumio-remote-updater[588]: [2025-12-19 17:40:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766166008 101 Dec 19 17:40:12 volumio volumio[19628]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 17:40:12 volumio volumio[19628]: info: Discovery: Getting this device information Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:12 volumio volumio[19628]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 17:40:12 volumio volumio[19628]: info: Reloading queue from file Dec 19 17:40:12 volumio volumio[19628]: info: VolumeController:: Volume=41 Mute =false Dec 19 17:40:12 volumio volumio[19628]: info: CoreStateMachine::pushState Dec 19 17:40:12 volumio volumio[19628]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::volumioPushState Dec 19 17:40:12 volumio volumio[19628]: info: CoreStateMachine::updateTrackBlock Dec 19 17:40:12 volumio volumio[19628]: info: CorePlayQueue::getTrackBlock Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 17:40:12 volumio volumio[19628]: info: Asound.conf file unchanged, so no further update is needed Dec 19 17:40:12 volumio volumio[19628]: info: Output device has changed, restarting MPD Dec 19 17:40:12 volumio volumio[19628]: info: Output device has changed, restarting Shairport Sync Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:12 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:12 volumio sudo[19772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 17:40:13 volumio sudo[19773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 17:40:13 volumio sudo[19772]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:13 volumio volumio[19628]: Starting albumart workers Dec 19 17:40:13 volumio sudo[19773]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:13 volumio sudo[19772]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:13 volumio volumio[19628]: info: ___________ START PLUGINS ___________ Dec 19 17:40:13 volumio volumio[19628]: Starting albumart workers Dec 19 17:40:13 volumio volumio[19628]: info: ControllerMpd::onStart: Initializing MPD Dec 19 17:40:13 volumio volumio[19628]: info: Creating MPD Configuration file Dec 19 17:40:13 volumio systemd[1]: Stopping Music Player Daemon... Dec 19 17:40:13 volumio volumio[19628]: Starting albumart workers Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 17:40:13 volumio volumio[19628]: info: [1766166013107] CoreMusicLibrary::Adding element Media Servers Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:40:13 volumio sudo[19793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:13 volumio sudo[19795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 17:40:13 volumio sudo[19793]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:13 volumio sudo[19795]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:13 volumio sudo[19793]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:13 volumio systemd[1]: mpd.service: Succeeded. Dec 19 17:40:13 volumio systemd[1]: Stopped Music Player Daemon. Dec 19 17:40:13 volumio systemd[1]: Starting Music Player Daemon... Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 17:40:13 volumio volumio[19628]: info: [1766166013249] CoreMusicLibrary::Adding element Last_100 Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 17:40:13 volumio volumio[19628]: info: [1766166013252] CoreMusicLibrary::Adding element Webradio Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 17:40:13 volumio volumio[19628]: info: Initializing BBC Radios Dec 19 17:40:13 volumio sudo[19813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 17:40:13 volumio sudo[19813]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:13 volumio sudo[19813]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:13 volumio volumio[19628]: info: Creating Spotify config file Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: Volumio Calling Home Dec 19 17:40:13 volumio volumio[19628]: info: Setting Device type: Tinkerboard Dec 19 17:40:13 volumio volumio[19628]: info: CoreStateMachine::setRepeat null single undefined Dec 19 17:40:13 volumio volumio[19628]: info: CoreStateMachine::pushState Dec 19 17:40:13 volumio volumio[19628]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::volumioPushState Dec 19 17:40:13 volumio volumio[19628]: info: CoreStateMachine::setRandom null Dec 19 17:40:13 volumio volumio[19628]: info: CoreStateMachine::pushState Dec 19 17:40:13 volumio volumio[19628]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::volumioPushState Dec 19 17:40:13 volumio volumio[19628]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 19 17:40:13 volumio volumio[19628]: info: MPD Permissions set Dec 19 17:40:13 volumio volumio[19628]: info: MPD Permissions set Dec 19 17:40:13 volumio volumio[19628]: info: Spotify config file written Dec 19 17:40:13 volumio sudo[19867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 17:40:13 volumio sudo[19867]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 19 17:40:13 volumio systemd[1]: Stopping go-librespot Daemon... Dec 19 17:40:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 19 17:40:13 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Dec 19 17:40:13 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 17:40:13 volumio systemd[1]: Started go-librespot Daemon. Dec 19 17:40:13 volumio volumio[19628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 19 17:40:13 volumio sudo[19867]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio go-librespot[19873]: go-librespot daemon starting... Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 17:40:13 volumio go-librespot[19873]: time="2025-12-19T17:40:13Z" level=info msg="running go-librespot 0.4.0" Dec 19 17:40:13 volumio go-librespot[19873]: time="2025-12-19T17:40:13Z" level=debug msg="app state loaded" Dec 19 17:40:13 volumio go-librespot[19873]: time="2025-12-19T17:40:13Z" level=debug msg="stored credentials not found" Dec 19 17:40:13 volumio go-librespot[19873]: time="2025-12-19T17:40:13Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:13 volumio volumio[19628]: info: No need to fix Spotify hosts Dec 19 17:40:13 volumio volumio[19628]: info: Discovery: adding a66f02f1-f6b2-4285-9139-8891b0b63153 Dec 19 17:40:13 volumio volumio[19628]: info: Discovery: Found device Volumio Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:13 volumio volumio[19628]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:13 volumio volumio[19628]: verbose: New Socket.io Connection to 192.168.0.127 from 192.168.0.19 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 19 17:40:13 volumio volumio[19628]: verbose: New Socket.io Connection to 192.168.0.127 from 192.168.0.19 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 19 17:40:13 volumio volumio[19628]: info: VolumeController:: Volume=41 Mute =false Dec 19 17:40:13 volumio volumio[19628]: info: CoreStateMachine::pushState Dec 19 17:40:13 volumio volumio[19628]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::volumioPushState Dec 19 17:40:13 volumio volumio[19628]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 19 17:40:13 volumio volumio[19628]: info: Starting Shairport Sync Dec 19 17:40:13 volumio volumio[19628]: info: Starting Shairport Sync Dec 19 17:40:13 volumio volumio[19628]: info: Starting Shairport Sync Dec 19 17:40:13 volumio sudo[19896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 17:40:13 volumio sudo[19896]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:13 volumio volumio[19628]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:13 volumio volumio[19628]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:13 volumio sudo[19898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 17:40:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 19 17:40:13 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 19 17:40:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:13 volumio sudo[19898]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:13 volumio sudo[19896]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:13 volumio sudo[19904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 17:40:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 19 17:40:13 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 19 17:40:14 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 19 17:40:14 volumio sudo[19904]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:14 volumio sudo[19898]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:14 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 17:40:14 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 17:40:14 volumio volumio[19628]: info: Discovery: Getting this device information Dec 19 17:40:14 volumio volumio[19628]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:14 volumio volumio[19628]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:14 volumio volumio[19628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 17:40:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 19 17:40:14 volumio volumio[19628]: info: Shairport-Sync Started Dec 19 17:40:14 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 19 17:40:14 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 19 17:40:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:14 volumio volumio[19628]: Error adding Membership: Error: addMembership EINVAL Dec 19 17:40:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:14 volumio volumio[19628]: info: Shairport-Sync Started Dec 19 17:40:14 volumio sudo[19904]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:14 volumio volumio[19628]: info: Shairport-Sync Started Dec 19 17:40:14 volumio mpd[19842]: Dec 19 17:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 17:40:15 volumio systemd[1]: Started Music Player Daemon. Dec 19 17:40:15 volumio sudo[19795]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:15 volumio sudo[19773]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:15 volumio volumio[19628]: info: Completed starting Core Plugins Dec 19 17:40:15 volumio volumio[19628]: info: ------------------------------------------- Dec 19 17:40:15 volumio volumio[19628]: info: ----- MyVolumio plugins startup ---- Dec 19 17:40:15 volumio volumio[19628]: info: ------------------------------------------- Dec 19 17:40:15 volumio volumio[19628]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 19 17:40:15 volumio volumio[19628]: error: MPD error: The expression evaluated to a falsy value: Dec 19 17:40:15 volumio volumio[19628]: assert.ok(self.idling) Dec 19 17:40:15 volumio volumio[19628]: error: The expression evaluated to a falsy value: Dec 19 17:40:15 volumio volumio[19628]: assert.ok(self.idling) Dec 19 17:40:15 volumio volumio[19628]: info: MPD running with PID19842 Dec 19 17:40:15 volumio volumio[19628]: ,establishing connection Dec 19 17:40:15 volumio volumio[19628]: error: updateQueue error: null Dec 19 17:40:15 volumio volumio[19628]: error: updateQueue error: null Dec 19 17:40:16 volumio volumio[19628]: info: go-librespot daemon successfully initialized Dec 19 17:40:18 volumio volumio[19628]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:40:18 volumio volumio[19628]: Error: connect EHOSTUNREACH 151.101.38.53:443 Dec 19 17:40:18 volumio volumio[19628]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 19 17:40:18 volumio volumio[19628]: errno: -113, Dec 19 17:40:18 volumio volumio[19628]: code: 'EHOSTUNREACH', Dec 19 17:40:18 volumio volumio[19628]: syscall: 'connect', Dec 19 17:40:18 volumio volumio[19628]: address: '151.101.38.53', Dec 19 17:40:18 volumio volumio[19628]: port: 443 Dec 19 17:40:18 volumio volumio[19628]: } Dec 19 17:40:18 volumio volumio[19628]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:40:18 volumio go-librespot[19873]: time="2025-12-19T17:40:18Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 19 17:40:18 volumio go-librespot[19873]: time="2025-12-19T17:40:18Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 19 17:40:18 volumio go-librespot[19873]: time="2025-12-19T17:40:18Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 19 17:40:19 volumio go-librespot[19873]: time="2025-12-19T17:40:19Z" level=info msg="zeroconf server listening on port 33897" Dec 19 17:40:19 volumio sudo[19965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-19 17:39 Dec 19 17:40:19 volumio sudo[19965]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:19 volumio sudo[19965]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:19 volumio volumio-remote-updater[588]: [2025-12-19 17:40:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 19 17:40:19 volumio volumio-remote-updater[588]: [2025-12-19 17:40:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 19 17:40:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 19 17:40:19 volumio systemd[1]: media-My_Passport.mount: Succeeded. Dec 19 17:40:19 volumio ntfs-3g[19725]: Unmounting /dev/sda1 (My Passport) Dec 19 17:40:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 19 17:40:19 volumio systemd[1]: Started dynamicswap service. Dec 19 17:40:19 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 19 17:40:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 19 17:40:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Dec 19 17:40:19 volumio systemd[1]: Stopped Volumio Backend Module. Dec 19 17:40:19 volumio systemd[1]: Started Volumio Backend Module. Dec 19 17:40:19 volumio systemd[1]: Started dynamicswap service. Dec 19 17:40:19 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 19 17:40:20 volumio volumio[19988]: info: ------------------------------------------- Dec 19 17:40:20 volumio volumio[19988]: info: ----- Volumio3 ---- Dec 19 17:40:20 volumio volumio[19988]: info: ------------------------------------------- Dec 19 17:40:20 volumio volumio[19988]: info: ----- System startup ---- Dec 19 17:40:20 volumio volumio[19988]: info: ------------------------------------------- Dec 19 17:40:20 volumio volumio[19988]: info: MYVOLUMIO Environment detected Dec 19 17:40:21 volumio volumio[19988]: info: Plugin folders cleanup Dec 19 17:40:21 volumio volumio[19988]: info: Scanning into folder /volumio/app/plugins/ Dec 19 17:40:21 volumio volumio[19988]: info: Scanning category audio_interface Dec 19 17:40:21 volumio volumio[19988]: info: Scanning category miscellanea Dec 19 17:40:21 volumio volumio[19988]: info: Scanning category music_service Dec 19 17:40:21 volumio volumio[19988]: info: Scanning category plugins.json Dec 19 17:40:21 volumio volumio[19988]: info: Scanning category system_controller Dec 19 17:40:21 volumio volumio[19988]: info: Scanning category user_interface Dec 19 17:40:21 volumio volumio[19988]: info: Scanning into folder /data/plugins/ Dec 19 17:40:21 volumio volumio[19988]: info: Scanning category music_service Dec 19 17:40:21 volumio volumio[19988]: info: Plugin folders cleanup completed Dec 19 17:40:21 volumio volumio[19988]: info: ------------------------------------------- Dec 19 17:40:21 volumio volumio[19988]: info: ----- Core plugins startup ---- Dec 19 17:40:21 volumio volumio[19988]: info: ------------------------------------------- Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugins from folder /volumio/app/plugins/ Dec 19 17:40:21 volumio volumio[19988]: info: Adding plugin upnp to MyMusic Plugins Dec 19 17:40:21 volumio volumio[19988]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 19 17:40:21 volumio volumio[19988]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugins from folder /data/plugins/ Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugin "system"... Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugin "appearance"... Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugin "network"... Dec 19 17:40:21 volumio volumio[19988]: info: Refreshing Cached IP Addresses Dec 19 17:40:21 volumio sudo[20015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 17:40:21 volumio sudo[20017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:40:21 volumio sudo[20015]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugin "services"... Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugin "alsa_controller"... Dec 19 17:40:21 volumio sudo[20017]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:21 volumio sudo[20034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 19 17:40:21 volumio sudo[20015]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:21 volumio sudo[20017]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:21 volumio sudo[20034]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:21 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugin "wizard"... Dec 19 17:40:21 volumio volumio[19988]: info: Loading plugin "networkfs"... Dec 19 17:40:21 volumio volumio[19988]: info: Starting Udev Watcher for removable devices Dec 19 17:40:21 volumio volumio[19988]: info: Ignoring mount for partition: BOOT Dec 19 17:40:21 volumio volumio[19988]: info: Ignoring mount for partition: volumio Dec 19 17:40:21 volumio volumio[19988]: info: Ignoring mount for partition: volumio_data Dec 19 17:40:21 volumio volumio[19988]: info: Mounting Device My_Passport Dec 19 17:40:21 volumio sudo[20071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/My_Passport -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 19 17:40:21 volumio sudo[20071]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:21 volumio sudo[20034]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:24 volumio volumio-remote-updater[588]: [2025-12-19 17:40:24] [connect] Successful connection Dec 19 17:40:25 volumio sudo[20071]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:25 volumio ntfs-3g[20122]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 19 17:40:25 volumio ntfs-3g[20122]: Mounted /dev/sda1 (Read-Write, label "My Passport", NTFS 3.1) Dec 19 17:40:25 volumio ntfs-3g[20122]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 19 17:40:25 volumio ntfs-3g[20122]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Dec 19 17:40:25 volumio ntfs-3g[20122]: Global ownership and permissions enforced, configuration type 7 Dec 19 17:40:25 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 17:40:25 volumio volumio[19988]: info: Loading plugin "volumio_command_line_client"... Dec 19 17:40:25 volumio volumio[19988]: info: Loading plugin "upnp"... Dec 19 17:40:25 volumio volumio[19988]: info: [1766166025902] Starting Upmpd Daemon Dec 19 17:40:25 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 17:40:25 volumio volumio[19988]: info: Loading plugin "my_music"... Dec 19 17:40:25 volumio volumio[19988]: info: Loading plugin "mpd"... Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "upnp_browser"... Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "alarm-clock"... Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "airplay_emulation"... Dec 19 17:40:26 volumio volumio[19988]: info: Starting Shairport Sync Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "last_100"... Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "webradio"... Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "i2s_dacs"... Dec 19 17:40:26 volumio volumio[19988]: info: I2S DAC not set, start Auto-detection Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "volumiodiscovery"... Dec 19 17:40:26 volumio volumio[19988]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 19 17:40:26 volumio volumio[19988]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 17:40:26 volumio volumio[19988]: *** WARNING *** For more information see Dec 19 17:40:26 volumio volumio[19988]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 19 17:40:26 volumio node[19988]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 19 17:40:26 volumio volumio[19988]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 17:40:26 volumio volumio[19988]: *** WARNING *** For more information see Dec 19 17:40:26 volumio node[19988]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 17:40:26 volumio node[19988]: *** WARNING *** For more information see Dec 19 17:40:26 volumio node[19988]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 19 17:40:26 volumio node[19988]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 17:40:26 volumio node[19988]: *** WARNING *** For more information see Dec 19 17:40:26 volumio volumio[19988]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 19 17:40:26 volumio volumio[19988]: info: Discovery: Started advertising with name: Volumio Dec 19 17:40:26 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "spop"... Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "outputs"... Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "albumart"... Dec 19 17:40:26 volumio volumio[19988]: info: Plugin example_plugin is not enabled Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "inputs"... Dec 19 17:40:26 volumio volumio[19988]: info: Loading plugin "updater_comm"... Dec 19 17:40:27 volumio volumio[19988]: info: Plugin mpdemulation is not enabled Dec 19 17:40:27 volumio volumio[19988]: info: Loading plugin "rest_api"... Dec 19 17:40:27 volumio volumio[19988]: info: Loading plugin "websocket"... Dec 19 17:40:27 volumio volumio[19988]: info: Starting Socket.io Server version 2.3.0 Dec 19 17:40:27 volumio volumio[19988]: info: Loading i18n strings for locale en Dec 19 17:40:27 volumio volumio[19988]: Updating browse sources language Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::initPlayerControls Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: Express server listening on port 3000 Dec 19 17:40:27 volumio volumio[19988]: [Metrics] WebUI: 6s 868.46ms Dec 19 17:40:27 volumio volumio[19988]: info: CoreStateMachine::resetVolumioState Dec 19 17:40:27 volumio volumio[19988]: info: CoreStateMachine::getcurrentVolume Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 19 17:40:27 volumio volumio[19988]: info: Completed loading Core Plugins Dec 19 17:40:27 volumio volumio[19988]: Forking 3 albumart workers Dec 19 17:40:27 volumio volumio[19988]: info: Preparing to generate the ALSA configuration file Dec 19 17:40:27 volumio volumio[19988]: info: Volumio Network Manager: Network status updated: 2 Dec 19 17:40:27 volumio volumio[19988]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Dec 19 17:40:27 volumio volumio-remote-updater[588]: [2025-12-19 17:40:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766166024 101 Dec 19 17:40:27 volumio volumio[19988]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Dec 19 17:40:27 volumio volumio[19988]: info: Reloading queue from file Dec 19 17:40:27 volumio volumio[19988]: info: Asound.conf file unchanged, so no further update is needed Dec 19 17:40:27 volumio volumio[19988]: info: Output device has changed, restarting MPD Dec 19 17:40:27 volumio volumio[19988]: info: Output device has changed, restarting Shairport Sync Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:27 volumio sudo[20169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 17:40:27 volumio sudo[20170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 17:40:27 volumio sudo[20169]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:27 volumio sudo[20170]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:27 volumio sudo[20169]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:27 volumio volumio[19988]: info: ___________ START PLUGINS ___________ Dec 19 17:40:27 volumio systemd[1]: Stopping Music Player Daemon... Dec 19 17:40:27 volumio volumio[19988]: info: ControllerMpd::onStart: Initializing MPD Dec 19 17:40:27 volumio volumio[19988]: info: Creating MPD Configuration file Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 17:40:27 volumio volumio[19988]: info: [1766166027558] CoreMusicLibrary::Adding element Media Servers Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:40:27 volumio sudo[20191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:27 volumio sudo[20191]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:27 volumio sudo[20190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:27 volumio sudo[20190]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:27 volumio systemd[1]: mpd.service: Succeeded. Dec 19 17:40:27 volumio systemd[1]: Stopped Music Player Daemon. Dec 19 17:40:27 volumio systemd[1]: Starting Music Player Daemon... Dec 19 17:40:27 volumio sudo[20190]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 17:40:27 volumio volumio[19988]: info: [1766166027736] CoreMusicLibrary::Adding element Last_100 Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 17:40:27 volumio volumio[19988]: info: [1766166027739] CoreMusicLibrary::Adding element Webradio Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 17:40:27 volumio volumio[19988]: info: Initializing BBC Radios Dec 19 17:40:27 volumio sudo[20205]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 17:40:27 volumio sudo[20205]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:27 volumio sudo[20205]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: Creating Spotify config file Dec 19 17:40:27 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:27 volumio volumio[19988]: info: Volumio Calling Home Dec 19 17:40:27 volumio volumio[19988]: Starting albumart workers Dec 19 17:40:27 volumio volumio[19988]: Starting albumart workers Dec 19 17:40:27 volumio volumio[19988]: Starting albumart workers Dec 19 17:40:28 volumio volumio[19988]: info: CoreStateMachine::setRepeat null single undefined Dec 19 17:40:28 volumio volumio[19988]: info: CoreStateMachine::pushState Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioPushState Dec 19 17:40:28 volumio volumio[19988]: info: CoreStateMachine::setRandom null Dec 19 17:40:28 volumio volumio[19988]: info: CoreStateMachine::pushState Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioPushState Dec 19 17:40:28 volumio volumio[19988]: info: Setting Device type: Tinkerboard Dec 19 17:40:28 volumio volumio[19988]: info: VolumeController:: Volume=41 Mute =false Dec 19 17:40:28 volumio volumio[19988]: info: CoreStateMachine::pushState Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioPushState Dec 19 17:40:28 volumio volumio[19988]: info: CoreStateMachine::updateTrackBlock Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrackBlock Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 17:40:28 volumio volumio[19988]: info: MPD Permissions set Dec 19 17:40:28 volumio volumio[19988]: info: MPD Permissions set Dec 19 17:40:28 volumio volumio[19988]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 19 17:40:28 volumio volumio[19988]: info: Spotify config file written Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 17:40:28 volumio volumio[19988]: info: Discovery: Getting this device information Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 17:40:28 volumio volumio[19988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 19 17:40:28 volumio sudo[20254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 17:40:28 volumio sudo[20254]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio systemd[1]: Stopping go-librespot Daemon... Dec 19 17:40:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 19 17:40:28 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Dec 19 17:40:28 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 17:40:28 volumio systemd[1]: Started go-librespot Daemon. Dec 19 17:40:28 volumio sudo[20254]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 17:40:28 volumio volumio[19988]: info: No need to fix Spotify hosts Dec 19 17:40:28 volumio go-librespot[20270]: go-librespot daemon starting... Dec 19 17:40:28 volumio go-librespot[20270]: time="2025-12-19T17:40:28Z" level=info msg="running go-librespot 0.4.0" Dec 19 17:40:28 volumio go-librespot[20270]: time="2025-12-19T17:40:28Z" level=debug msg="app state loaded" Dec 19 17:40:28 volumio go-librespot[20270]: time="2025-12-19T17:40:28Z" level=debug msg="stored credentials not found" Dec 19 17:40:28 volumio go-librespot[20270]: time="2025-12-19T17:40:28Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 17:40:28 volumio volumio[19988]: info: VolumeController:: Volume=41 Mute =false Dec 19 17:40:28 volumio volumio[19988]: info: CoreStateMachine::pushState Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioPushState Dec 19 17:40:28 volumio go-librespot[20270]: time="2025-12-19T17:40:28Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: connect: no route to host" Dec 19 17:40:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 17:40:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 17:40:28 volumio volumio[19988]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 17:40:28 volumio volumio[19988]: info: Discovery: Getting this device information Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 17:40:28 volumio volumio[19988]: info: Discovery: adding a66f02f1-f6b2-4285-9139-8891b0b63153 Dec 19 17:40:28 volumio volumio[19988]: info: Discovery: Found device Volumio Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:28 volumio volumio[19988]: info: Starting Shairport Sync Dec 19 17:40:28 volumio volumio[19988]: info: Starting Shairport Sync Dec 19 17:40:28 volumio volumio[19988]: info: Starting Shairport Sync Dec 19 17:40:28 volumio sudo[20291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 17:40:28 volumio sudo[20291]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:28 volumio sudo[20293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 19 17:40:28 volumio volumio[19988]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:28 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 19 17:40:28 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 19 17:40:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:28 volumio sudo[20300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 17:40:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:28 volumio sudo[20291]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:28 volumio sudo[20293]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:28 volumio sudo[20300]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 19 17:40:28 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 19 17:40:28 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 19 17:40:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 19 17:40:28 volumio sudo[20293]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:28 volumio volumio[19988]: info: Shairport-Sync Started Dec 19 17:40:28 volumio volumio[19988]: Error adding Membership: Error: addMembership EINVAL Dec 19 17:40:28 volumio sudo[20300]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:28 volumio volumio[19988]: info: Shairport-Sync Started Dec 19 17:40:28 volumio volumio[19988]: verbose: New Socket.io Connection to 192.168.0.127 from 192.168.0.19 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 19 17:40:28 volumio volumio[19988]: info: Shairport-Sync Started Dec 19 17:40:29 volumio mpd[20233]: Dec 19 17:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 17:40:30 volumio systemd[1]: Started Music Player Daemon. Dec 19 17:40:30 volumio sudo[20170]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:30 volumio sudo[20191]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:30 volumio volumio[19988]: info: Completed starting Core Plugins Dec 19 17:40:30 volumio volumio[19988]: info: ------------------------------------------- Dec 19 17:40:30 volumio volumio[19988]: info: ----- MyVolumio plugins startup ---- Dec 19 17:40:30 volumio volumio[19988]: info: ------------------------------------------- Dec 19 17:40:30 volumio volumio[19988]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 19 17:40:30 volumio volumio[19988]: error: MPD error: The expression evaluated to a falsy value: Dec 19 17:40:30 volumio volumio[19988]: assert.ok(self.idling) Dec 19 17:40:30 volumio volumio[19988]: error: The expression evaluated to a falsy value: Dec 19 17:40:30 volumio volumio[19988]: assert.ok(self.idling) Dec 19 17:40:30 volumio volumio[19988]: info: MPD running with PID20233 Dec 19 17:40:30 volumio volumio[19988]: ,establishing connection Dec 19 17:40:30 volumio volumio[19988]: error: updateQueue error: null Dec 19 17:40:30 volumio volumio[19988]: error: updateQueue error: null Dec 19 17:40:31 volumio volumio[19988]: info: go-librespot daemon successfully initialized Dec 19 17:40:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 19 17:40:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 19 17:40:31 volumio systemd[1]: Stopped go-librespot Daemon. Dec 19 17:40:31 volumio systemd[1]: Started go-librespot Daemon. Dec 19 17:40:31 volumio go-librespot[20348]: go-librespot daemon starting... Dec 19 17:40:31 volumio go-librespot[20348]: time="2025-12-19T17:40:31Z" level=info msg="running go-librespot 0.4.0" Dec 19 17:40:31 volumio go-librespot[20348]: time="2025-12-19T17:40:31Z" level=debug msg="app state loaded" Dec 19 17:40:31 volumio go-librespot[20348]: time="2025-12-19T17:40:31Z" level=debug msg="stored credentials not found" Dec 19 17:40:31 volumio go-librespot[20348]: time="2025-12-19T17:40:31Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 17:40:31 volumio go-librespot[20348]: time="2025-12-19T17:40:31Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp 35.186.224.24:443: connect: no route to host" Dec 19 17:40:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 19 17:40:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 17:40:32 volumio volumio[19988]: info: Discovery: Getting this device information Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:32 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 19 17:40:32 volumio sudo[20357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 19 17:40:32 volumio sudo[20357]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:32 volumio sudo[20363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 19 17:40:32 volumio sudo[20370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 19 17:40:32 volumio sudo[20357]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:32 volumio sudo[20370]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:32 volumio sudo[20377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 19 17:40:32 volumio sudo[20363]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:32 volumio sudo[20370]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:32 volumio sudo[20387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 17:40:32 volumio sudo[20377]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:32 volumio sudo[20363]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:32 volumio sudo[20394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 17:40:32 volumio sudo[20387]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:32 volumio sudo[20377]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:32 volumio sudo[20394]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 19 17:40:32 volumio sudo[20387]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:32 volumio sudo[20394]: pam_unix(sudo:session): session closed for user root Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 17:40:32 volumio volumio[19988]: info: Discovery: Getting this device information Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:32 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:32 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 17:40:32 volumio volumio[19988]: info: Discovery: Getting this device information Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::volumioGetState Dec 19 17:40:32 volumio volumio[19988]: info: CorePlayQueue::getTrack 0 Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 19 17:40:32 volumio volumio[19988]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.19 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 19 17:40:32 volumio volumio[19988]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 19 17:40:33 volumio volumio[19988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:40:33 volumio volumio[19988]: Error: connect EHOSTUNREACH 151.101.38.53:443 Dec 19 17:40:33 volumio volumio[19988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 19 17:40:33 volumio volumio[19988]: errno: -113, Dec 19 17:40:33 volumio volumio[19988]: code: 'EHOSTUNREACH', Dec 19 17:40:33 volumio volumio[19988]: syscall: 'connect', Dec 19 17:40:33 volumio volumio[19988]: address: '151.101.38.53', Dec 19 17:40:33 volumio volumio[19988]: port: 443 Dec 19 17:40:33 volumio volumio[19988]: } Dec 19 17:40:33 volumio volumio[19988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 17:40:34 volumio sudo[20441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-19 17:39 Dec 19 17:40:34 volumio sudo[20441]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:27:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="c4da08a1dd107eaacf86346d2a49b8d1"