-- Logs begin at Mon 2025-12-22 10:09:46 UTC, end at Sat 2025-12-27 06:23:45 UTC. -- Dec 27 06:22:01 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aventura/7e6ba9d0-c80f-4cbf-9d24-7dd33ffbdf24.jpg' Dec 27 06:22:01 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Becky%20G/c433467f-85ca-4d5c-9d67-f2dbdb57a68a.jpg' Dec 27 06:22:03 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dillon%20Francis/52796cdb-8571-4112-98ca-4255ab996a1d.jpg' Dec 27 06:22:04 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 06:22:04 ms-113 volumio[10971]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 27 06:22:04 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 27 06:22:04 ms-113 volumio[10971]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Dec 27 06:22:04 ms-113 volumio[10971]: info: Received Get System Version Dec 27 06:22:04 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:22:04 ms-113 volumio[10971]: info: Received Get System Info Dec 27 06:22:04 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 06:22:04 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 06:22:04 ms-113 volumio[10971]: info: Discovery: Getting this device information Dec 27 06:22:04 ms-113 volumio[10971]: info: CoreCommandRouter::volumioGetState Dec 27 06:22:04 ms-113 volumio[10971]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:04 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 06:22:05 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Drefquila/cef582c1-71ec-4118-bdaf-7e2a66fae7a1.jpg' Dec 27 06:22:05 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Duke%20Dumont/ae67c4c1-d6dc-4679-bef2-9925f2ed091b.jpg' Dec 27 06:22:05 ms-113 volumio[10971]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 27 06:22:05 ms-113 volumio[10971]: info: Completed starting MyVolumio Plugin Dec 27 06:22:05 ms-113 volumio[10971]: info: BOOT COMPLETED Dec 27 06:22:05 ms-113 volumio[10971]: [Metrics] CommandRouter: 33s 705.74ms Dec 27 06:22:05 ms-113 volumio[10971]: info: CoreCommandRouter::volumiosetStartupVolume Dec 27 06:22:05 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:05 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:05 ms-113 volumio[10971]: info: CoreCommandRouter::Close All Modals sent Dec 27 06:22:05 ms-113 volumio[10971]: info: CoreCommandRouter::Close All Modals sent Dec 27 06:22:06 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:22:06 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:06 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 27 06:22:06 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 06:22:06 ms-113 volumio[10971]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 27 06:22:07 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Empire%20Of%20The%20Sun/3338ece2-01c0-48f0-b65e-73577896f476.jpg' Dec 27 06:22:07 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Empire%20of%20the%20Sun/fb1a34ba-2d44-4f15-b130-8062260c83d0.jpg' Dec 27 06:22:07 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Enrique%20Iglesias/641000d2-7f7c-4d36-a52d-952e1f01ff5f.jpg' Dec 27 06:22:09 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Fedde%20Le%20Grand/e445dd2d-58dd-47f6-99bc-90a1bd454e65.jpg' Dec 27 06:22:09 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Foster%20The%20People/15e389e5-048b-46f8-b410-ba0aadf51d7e.jpg' Dec 27 06:22:09 ms-113 volumio[10971]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Fritz%20Kalkbrenner/d48235bd-f941-4f93-8c58-392ff46d7e33.jpg' Dec 27 06:22:18 ms-113 ntpd[665]: Soliciting pool server 240b:4005:12b:fb00:d11d:fbb7:f895:76be Dec 27 06:22:23 ms-113 volumio[10971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:22:23 ms-113 volumio[10971]: Error: connect EHOSTUNREACH 10.10.34.36:443 Dec 27 06:22:23 ms-113 volumio[10971]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 06:22:23 ms-113 volumio[10971]: errno: -113, Dec 27 06:22:23 ms-113 volumio[10971]: code: 'EHOSTUNREACH', Dec 27 06:22:23 ms-113 volumio[10971]: syscall: 'connect', Dec 27 06:22:23 ms-113 volumio[10971]: address: '10.10.34.36', Dec 27 06:22:23 ms-113 volumio[10971]: port: 443 Dec 27 06:22:23 ms-113 volumio[10971]: } Dec 27 06:22:23 ms-113 volumio[10971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:22:23 ms-113 ntpd[665]: Soliciting pool server 88.99.76.248 Dec 27 06:22:23 ms-113 sudo[11188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:21 Dec 27 06:22:23 ms-113 sudo[11188]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:23 ms-113 sudo[11188]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:23 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 27 06:22:23 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 27 06:22:23 ms-113 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 27 06:22:23 ms-113 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 27 06:22:23 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:22:23 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:22:23 ms-113 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 06:22:23 ms-113 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Dec 27 06:22:23 ms-113 systemd[1]: Stopped Volumio Backend Module. Dec 27 06:22:23 ms-113 systemd[1]: Started Volumio Backend Module. Dec 27 06:22:23 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:22:23 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:22:24 ms-113 volumio[11198]: info: ------------------------------------------- Dec 27 06:22:24 ms-113 volumio[11198]: info: ----- Volumio3 ---- Dec 27 06:22:24 ms-113 volumio[11198]: info: ------------------------------------------- Dec 27 06:22:24 ms-113 volumio[11198]: info: ----- System startup ---- Dec 27 06:22:24 ms-113 volumio[11198]: info: ------------------------------------------- Dec 27 06:22:25 ms-113 volumio[11198]: info: MYVOLUMIO Environment detected Dec 27 06:22:25 ms-113 volumio[11198]: info: Plugin folders cleanup Dec 27 06:22:25 ms-113 volumio[11198]: info: Scanning into folder /volumio/app/plugins/ Dec 27 06:22:25 ms-113 volumio[11198]: info: Scanning category audio_interface Dec 27 06:22:25 ms-113 volumio[11198]: info: Scanning category miscellanea Dec 27 06:22:25 ms-113 volumio[11198]: info: Scanning category music_service Dec 27 06:22:25 ms-113 volumio[11198]: info: Scanning category plugins.json Dec 27 06:22:25 ms-113 volumio[11198]: info: Scanning category system_controller Dec 27 06:22:25 ms-113 volumio[11198]: info: Scanning category user_interface Dec 27 06:22:25 ms-113 volumio[11198]: info: Scanning into folder /data/plugins/ Dec 27 06:22:25 ms-113 volumio[11198]: info: Plugin folders cleanup completed Dec 27 06:22:25 ms-113 volumio[11198]: info: ------------------------------------------- Dec 27 06:22:25 ms-113 volumio[11198]: info: ----- Core plugins startup ---- Dec 27 06:22:25 ms-113 volumio[11198]: info: ------------------------------------------- Dec 27 06:22:25 ms-113 volumio[11198]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 06:22:25 ms-113 volumio[11198]: info: Adding plugin upnp to MyMusic Plugins Dec 27 06:22:25 ms-113 volumio[11198]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 06:22:25 ms-113 volumio[11198]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 06:22:25 ms-113 volumio[11198]: info: Loading plugins from folder /data/plugins/ Dec 27 06:22:25 ms-113 volumio[11198]: info: Loading plugin "system"... Dec 27 06:22:25 ms-113 volumio[11198]: info: Loading plugin "appearance"... Dec 27 06:22:25 ms-113 volumio[11198]: info: Loading plugin "network"... Dec 27 06:22:25 ms-113 volumio[11198]: info: Refreshing Cached IP Addresses Dec 27 06:22:25 ms-113 sudo[11222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:22:25 ms-113 sudo[11222]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:25 ms-113 sudo[11222]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:25 ms-113 sudo[11224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:22:25 ms-113 sudo[11224]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "services"... Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "alsa_controller"... Dec 27 06:22:26 ms-113 sudo[11224]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:26 ms-113 sudo[11227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 06:22:26 ms-113 sudo[11227]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "wizard"... Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "volumio_command_line_client"... Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "upnp"... Dec 27 06:22:26 ms-113 volumio[11198]: info: [1766816546099] Starting Upmpd Daemon Dec 27 06:22:26 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "my_music"... Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "mpd"... Dec 27 06:22:26 ms-113 volumio[11198]: info: Creating MPD Configuration file Dec 27 06:22:26 ms-113 sudo[11235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:22:26 ms-113 sudo[11235]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "upnp_browser"... Dec 27 06:22:26 ms-113 sudo[11235]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:26 ms-113 sudo[11237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:22:26 ms-113 sudo[11237]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:26 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:22:26 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:22:26 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:22:26 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "networkfs"... Dec 27 06:22:26 ms-113 volumio[11198]: info: Cannot mount NAS ms-113-kish at system boot, trial number 1 ,retrying in 5 seconds Dec 27 06:22:26 ms-113 volumio[11198]: info: Starting Udev Watcher for removable devices Dec 27 06:22:26 ms-113 volumio[11198]: info: Ignoring mount for partition: boot Dec 27 06:22:26 ms-113 volumio[11198]: info: Ignoring mount for partition: volumio Dec 27 06:22:26 ms-113 volumio[11198]: info: Ignoring mount for partition: volumio_data Dec 27 06:22:26 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:26 ms-113 volumio[11198]: info: Loading plugin "alarm-clock"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "airplay_emulation"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Starting Shairport Sync Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "last_100"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "webradio"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "i2s_dacs"... Dec 27 06:22:27 ms-113 volumio[11198]: info: I2S DAC not set, start Auto-detection Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "volumiodiscovery"... Dec 27 06:22:27 ms-113 volumio[11198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:27 ms-113 volumio[11198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:27 ms-113 volumio[11198]: *** WARNING *** For more information see Dec 27 06:22:27 ms-113 node[11198]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:27 ms-113 volumio[11198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:27 ms-113 volumio[11198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:27 ms-113 volumio[11198]: *** WARNING *** For more information see Dec 27 06:22:27 ms-113 node[11198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:27 ms-113 node[11198]: *** WARNING *** For more information see Dec 27 06:22:27 ms-113 node[11198]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:27 ms-113 node[11198]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:27 ms-113 node[11198]: *** WARNING *** For more information see Dec 27 06:22:27 ms-113 volumio[11198]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 06:22:27 ms-113 mpd[11242]: Dec 27 06:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:22:27 ms-113 volumio[11198]: info: Discovery: Started advertising with name: MS-113 Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "outputs"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "albumart"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Plugin example_plugin is not enabled Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "inputs"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "updater_comm"... Dec 27 06:22:27 ms-113 mpd[11242]: Dec 27 06:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:22:27 ms-113 mpd[11242]: Dec 27 06:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:22:27 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:22:27 ms-113 sudo[11237]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:27 ms-113 volumio[11198]: info: Plugin mpdemulation is not enabled Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "rest_api"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading plugin "websocket"... Dec 27 06:22:27 ms-113 volumio[11198]: info: Loading i18n strings for locale en Dec 27 06:22:27 ms-113 volumio[11198]: Updating browse sources language Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::initPlayerControls Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: Express server listening on port 3000 Dec 27 06:22:27 ms-113 volumio[11198]: [Metrics] WebUI: 2s 811.42ms Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreStateMachine::resetVolumioState Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreStateMachine::getcurrentVolume Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: Forking 3 albumart workers Dec 27 06:22:27 ms-113 volumio[11198]: info: MPD Permissions set Dec 27 06:22:27 ms-113 volumio[11198]: info: MPD running with PID11242 Dec 27 06:22:27 ms-113 volumio[11198]: ,establishing connection Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:27 ms-113 volumio[11198]: info: Reloading queue from file Dec 27 06:22:27 ms-113 volumio[11198]: error: updateQueue error: null Dec 27 06:22:27 ms-113 volumio[11198]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 06:22:27 ms-113 volumio[11198]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreStateMachine::pushState Dec 27 06:22:27 ms-113 volumio[11198]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreStateMachine::updateTrackBlock Dec 27 06:22:27 ms-113 volumio[11198]: info: CorePlayQueue::getTrackBlock Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreStateMachine::setRepeat false single undefined Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreStateMachine::pushState Dec 27 06:22:27 ms-113 volumio[11198]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreStateMachine::setRandom false Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreStateMachine::pushState Dec 27 06:22:27 ms-113 volumio[11198]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:27 ms-113 volumio[11198]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:27 ms-113 volumio[11198]: info: Setting Device type: Raspberry PI Dec 27 06:22:28 ms-113 volumio[11198]: info: Completed loading Core Plugins Dec 27 06:22:28 ms-113 volumio[11198]: info: Preparing to generate the ALSA configuration file Dec 27 06:22:28 ms-113 volumio[11198]: Starting albumart workers Dec 27 06:22:28 ms-113 volumio[11198]: error: updateQueue error: null Dec 27 06:22:28 ms-113 volumio[11198]: Starting albumart workers Dec 27 06:22:28 ms-113 volumio[11198]: Starting albumart workers Dec 27 06:22:28 ms-113 volumio[11198]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreStateMachine::pushState Dec 27 06:22:28 ms-113 volumio[11198]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:28 ms-113 volumio[11198]: info: Asound.conf file unchanged, so no further update is needed Dec 27 06:22:28 ms-113 volumio[11198]: info: Output device has changed, restarting MPD Dec 27 06:22:28 ms-113 volumio[11198]: info: Output device has changed, restarting Shairport Sync Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:28 ms-113 sudo[11296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:22:28 ms-113 sudo[11296]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:28 ms-113 sudo[11296]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:28 ms-113 sudo[11306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:22:28 ms-113 sudo[11306]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:28 ms-113 volumio[11198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:28 ms-113 volumio[11198]: info: ___________ START PLUGINS ___________ Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:28 ms-113 volumio[11198]: info: [1766816548568] CoreMusicLibrary::Adding element Media Servers Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:28 ms-113 volumio[11198]: Cannot find translation for sourceMedia Servers Dec 27 06:22:28 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:28 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:22:28 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:22:28 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:22:28 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:28] [connect] Successful connection Dec 27 06:22:28 ms-113 volumio[11198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:28 ms-113 volumio[11198]: info: [1766816548695] CoreMusicLibrary::Adding element Last_100 Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:28 ms-113 volumio[11198]: Cannot find translation for sourceMedia Servers Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:28 ms-113 volumio[11198]: info: [1766816548698] CoreMusicLibrary::Adding element Webradio Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:28 ms-113 volumio[11198]: Cannot find translation for sourceMedia Servers Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:22:28 ms-113 volumio[11198]: info: Volumio Calling Home Dec 27 06:22:28 ms-113 volumio[11198]: info: Completed starting Core Plugins Dec 27 06:22:28 ms-113 volumio[11198]: info: ------------------------------------------- Dec 27 06:22:28 ms-113 volumio[11198]: info: ----- MyVolumio plugins startup ---- Dec 27 06:22:28 ms-113 volumio[11198]: info: ------------------------------------------- Dec 27 06:22:28 ms-113 volumio[11198]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 06:22:28 ms-113 volumio[11198]: info: MPD Permissions set Dec 27 06:22:28 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766816548 101 Dec 27 06:22:28 ms-113 volumio[11198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 27 06:22:28 ms-113 sudo[11227]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:28 ms-113 volumio[11198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:28 ms-113 volumio[11198]: info: Discovery: adding 35aff5c4-1eea-4ef6-9ca9-12e5bbf60d42 Dec 27 06:22:28 ms-113 volumio[11198]: info: mDNS: Found device MS-113 Dec 27 06:22:28 ms-113 volumio[11198]: info: CoreCommandRouter::volumioGetState Dec 27 06:22:28 ms-113 volumio[11198]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:28 ms-113 volumio[11198]: info: Starting Shairport Sync Dec 27 06:22:29 ms-113 volumio[11198]: info: Starting Shairport Sync Dec 27 06:22:29 ms-113 sudo[11345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:29 ms-113 sudo[11345]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:29 ms-113 volumio[11198]: info: Starting Shairport Sync Dec 27 06:22:29 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:22:29 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:22:29 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:29 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:29 ms-113 sudo[11345]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:29 ms-113 sudo[11348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:29 ms-113 sudo[11348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:29 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:22:29 ms-113 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 27 06:22:29 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:22:29 ms-113 sudo[11351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:29 ms-113 sudo[11351]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:29 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:29 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:29 ms-113 sudo[11348]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:29 ms-113 volumio[11198]: info: Shairport-Sync Started Dec 27 06:22:29 ms-113 volumio[11198]: Error adding Membership: Error: addMembership EINVAL Dec 27 06:22:29 ms-113 volumio[11198]: info: Volumio called home Dec 27 06:22:29 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:22:29 ms-113 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 27 06:22:29 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:22:29 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:29 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:29 ms-113 sudo[11351]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:29 ms-113 volumio[11198]: info: Shairport-Sync Started Dec 27 06:22:29 ms-113 volumio[11198]: info: Shairport-Sync Started Dec 27 06:22:29 ms-113 volumio[11198]: info: CoreCommandRouter::volumioGetState Dec 27 06:22:29 ms-113 volumio[11198]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:29 ms-113 mpd[11338]: Dec 27 06:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:22:29 ms-113 mpd[11338]: Dec 27 06:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:22:29 ms-113 mpd[11338]: Dec 27 06:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:22:29 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:22:29 ms-113 sudo[11306]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:29 ms-113 volumio[11198]: error: updateQueue error: null Dec 27 06:22:30 ms-113 volumio[11198]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 3 Dec 27 06:22:30 ms-113 volumio[11198]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 4 Dec 27 06:22:30 ms-113 volumio[11198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:22:30 ms-113 volumio[11198]: Error: connect EHOSTUNREACH 10.10.34.36:443 Dec 27 06:22:30 ms-113 volumio[11198]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 06:22:30 ms-113 volumio[11198]: errno: -113, Dec 27 06:22:30 ms-113 volumio[11198]: code: 'EHOSTUNREACH', Dec 27 06:22:30 ms-113 volumio[11198]: syscall: 'connect', Dec 27 06:22:30 ms-113 volumio[11198]: address: '10.10.34.36', Dec 27 06:22:30 ms-113 volumio[11198]: port: 443 Dec 27 06:22:30 ms-113 volumio[11198]: } Dec 27 06:22:30 ms-113 volumio[11198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:22:31 ms-113 sudo[11369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:21 Dec 27 06:22:31 ms-113 sudo[11369]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:31 ms-113 sudo[11369]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:31 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 27 06:22:31 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 27 06:22:31 ms-113 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 27 06:22:31 ms-113 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 27 06:22:31 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:22:31 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:22:31 ms-113 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 06:22:31 ms-113 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14. Dec 27 06:22:31 ms-113 systemd[1]: Stopped Volumio Backend Module. Dec 27 06:22:31 ms-113 systemd[1]: Started Volumio Backend Module. Dec 27 06:22:31 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:22:31 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:22:32 ms-113 volumio[11379]: info: ------------------------------------------- Dec 27 06:22:32 ms-113 volumio[11379]: info: ----- Volumio3 ---- Dec 27 06:22:32 ms-113 volumio[11379]: info: ------------------------------------------- Dec 27 06:22:32 ms-113 volumio[11379]: info: ----- System startup ---- Dec 27 06:22:32 ms-113 volumio[11379]: info: ------------------------------------------- Dec 27 06:22:33 ms-113 volumio[11379]: info: MYVOLUMIO Environment detected Dec 27 06:22:33 ms-113 volumio[11379]: info: Plugin folders cleanup Dec 27 06:22:33 ms-113 volumio[11379]: info: Scanning into folder /volumio/app/plugins/ Dec 27 06:22:33 ms-113 volumio[11379]: info: Scanning category audio_interface Dec 27 06:22:33 ms-113 volumio[11379]: info: Scanning category miscellanea Dec 27 06:22:33 ms-113 volumio[11379]: info: Scanning category music_service Dec 27 06:22:33 ms-113 volumio[11379]: info: Scanning category plugins.json Dec 27 06:22:33 ms-113 volumio[11379]: info: Scanning category system_controller Dec 27 06:22:33 ms-113 volumio[11379]: info: Scanning category user_interface Dec 27 06:22:33 ms-113 volumio[11379]: info: Scanning into folder /data/plugins/ Dec 27 06:22:33 ms-113 volumio[11379]: info: Plugin folders cleanup completed Dec 27 06:22:33 ms-113 volumio[11379]: info: ------------------------------------------- Dec 27 06:22:33 ms-113 volumio[11379]: info: ----- Core plugins startup ---- Dec 27 06:22:33 ms-113 volumio[11379]: info: ------------------------------------------- Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 06:22:33 ms-113 volumio[11379]: info: Adding plugin upnp to MyMusic Plugins Dec 27 06:22:33 ms-113 volumio[11379]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 06:22:33 ms-113 volumio[11379]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugins from folder /data/plugins/ Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "system"... Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "appearance"... Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "network"... Dec 27 06:22:33 ms-113 volumio[11379]: info: Refreshing Cached IP Addresses Dec 27 06:22:33 ms-113 sudo[11404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:22:33 ms-113 sudo[11404]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:33 ms-113 sudo[11404]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:33 ms-113 sudo[11406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:22:33 ms-113 sudo[11406]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "services"... Dec 27 06:22:33 ms-113 sudo[11406]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "alsa_controller"... Dec 27 06:22:33 ms-113 sudo[11410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 06:22:33 ms-113 sudo[11410]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "wizard"... Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "volumio_command_line_client"... Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "upnp"... Dec 27 06:22:33 ms-113 volumio[11379]: info: [1766816553820] Starting Upmpd Daemon Dec 27 06:22:33 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "my_music"... Dec 27 06:22:33 ms-113 volumio[11379]: info: Loading plugin "mpd"... Dec 27 06:22:34 ms-113 volumio[11379]: info: Creating MPD Configuration file Dec 27 06:22:34 ms-113 sudo[11417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:22:34 ms-113 sudo[11417]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "upnp_browser"... Dec 27 06:22:34 ms-113 sudo[11417]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:34 ms-113 sudo[11419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:22:34 ms-113 sudo[11419]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:34 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:22:34 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:22:34 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:22:34 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "networkfs"... Dec 27 06:22:34 ms-113 volumio[11379]: info: Cannot mount NAS ms-113-kish at system boot, trial number 1 ,retrying in 5 seconds Dec 27 06:22:34 ms-113 volumio[11379]: info: Starting Udev Watcher for removable devices Dec 27 06:22:34 ms-113 volumio[11379]: info: Ignoring mount for partition: boot Dec 27 06:22:34 ms-113 volumio[11379]: info: Ignoring mount for partition: volumio Dec 27 06:22:34 ms-113 volumio[11379]: info: Ignoring mount for partition: volumio_data Dec 27 06:22:34 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "alarm-clock"... Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "airplay_emulation"... Dec 27 06:22:34 ms-113 volumio[11379]: info: Starting Shairport Sync Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "last_100"... Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "webradio"... Dec 27 06:22:34 ms-113 mpd[11424]: Dec 27 06:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "i2s_dacs"... Dec 27 06:22:34 ms-113 volumio[11379]: info: I2S DAC not set, start Auto-detection Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "volumiodiscovery"... Dec 27 06:22:34 ms-113 volumio[11379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:34 ms-113 volumio[11379]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:34 ms-113 node[11379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:34 ms-113 volumio[11379]: *** WARNING *** For more information see Dec 27 06:22:34 ms-113 volumio[11379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:34 ms-113 volumio[11379]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:34 ms-113 volumio[11379]: *** WARNING *** For more information see Dec 27 06:22:34 ms-113 node[11379]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:34 ms-113 node[11379]: *** WARNING *** For more information see Dec 27 06:22:34 ms-113 node[11379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:34 ms-113 node[11379]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:34 ms-113 node[11379]: *** WARNING *** For more information see Dec 27 06:22:34 ms-113 volumio[11379]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 06:22:34 ms-113 volumio[11379]: info: Discovery: Started advertising with name: MS-113 Dec 27 06:22:34 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "outputs"... Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "albumart"... Dec 27 06:22:34 ms-113 mpd[11424]: Dec 27 06:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:22:34 ms-113 mpd[11424]: Dec 27 06:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:22:34 ms-113 volumio[11379]: info: Plugin example_plugin is not enabled Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "inputs"... Dec 27 06:22:34 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:22:34 ms-113 sudo[11419]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:34 ms-113 volumio[11379]: info: Loading plugin "updater_comm"... Dec 27 06:22:35 ms-113 volumio[11379]: info: Plugin mpdemulation is not enabled Dec 27 06:22:35 ms-113 volumio[11379]: info: Loading plugin "rest_api"... Dec 27 06:22:35 ms-113 volumio[11379]: info: Loading plugin "websocket"... Dec 27 06:22:35 ms-113 volumio[11379]: info: Loading i18n strings for locale en Dec 27 06:22:35 ms-113 volumio[11379]: Updating browse sources language Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::initPlayerControls Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: Express server listening on port 3000 Dec 27 06:22:35 ms-113 volumio[11379]: [Metrics] WebUI: 2s 786.67ms Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreStateMachine::resetVolumioState Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreStateMachine::getcurrentVolume Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: Forking 3 albumart workers Dec 27 06:22:35 ms-113 volumio[11379]: info: MPD Permissions set Dec 27 06:22:35 ms-113 volumio[11379]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 1 Dec 27 06:22:35 ms-113 volumio[11379]: info: MPD running with PID11424 Dec 27 06:22:35 ms-113 volumio[11379]: ,establishing connection Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:35 ms-113 volumio[11379]: info: Reloading queue from file Dec 27 06:22:35 ms-113 volumio[11379]: error: updateQueue error: null Dec 27 06:22:35 ms-113 volumio[11379]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 06:22:35 ms-113 volumio[11379]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreStateMachine::pushState Dec 27 06:22:35 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreStateMachine::updateTrackBlock Dec 27 06:22:35 ms-113 volumio[11379]: info: CorePlayQueue::getTrackBlock Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreStateMachine::setRepeat false single undefined Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreStateMachine::pushState Dec 27 06:22:35 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreStateMachine::setRandom false Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreStateMachine::pushState Dec 27 06:22:35 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:35 ms-113 volumio[11379]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:35 ms-113 volumio[11379]: info: Setting Device type: Raspberry PI Dec 27 06:22:35 ms-113 volumio[11379]: Starting albumart workers Dec 27 06:22:35 ms-113 volumio[11379]: Starting albumart workers Dec 27 06:22:35 ms-113 volumio[11379]: error: updateQueue error: null Dec 27 06:22:35 ms-113 volumio[11379]: Starting albumart workers Dec 27 06:22:35 ms-113 volumio[11379]: info: Completed loading Core Plugins Dec 27 06:22:35 ms-113 volumio[11379]: info: Preparing to generate the ALSA configuration file Dec 27 06:22:36 ms-113 volumio[11379]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreStateMachine::pushState Dec 27 06:22:36 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:36 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:36] [connect] Successful connection Dec 27 06:22:36 ms-113 volumio[11379]: info: Asound.conf file unchanged, so no further update is needed Dec 27 06:22:36 ms-113 volumio[11379]: info: Output device has changed, restarting MPD Dec 27 06:22:36 ms-113 sudo[11501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:22:36 ms-113 sudo[11501]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:36 ms-113 sudo[11501]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:36 ms-113 volumio[11379]: info: Output device has changed, restarting Shairport Sync Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:36 ms-113 sudo[11504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:22:36 ms-113 sudo[11504]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:36 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:22:36 ms-113 volumio[11379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:36 ms-113 volumio[11379]: info: ___________ START PLUGINS ___________ Dec 27 06:22:36 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:22:36 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:36 ms-113 volumio[11379]: info: [1766816556482] CoreMusicLibrary::Adding element Media Servers Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:36 ms-113 volumio[11379]: Cannot find translation for sourceMedia Servers Dec 27 06:22:36 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:36 ms-113 volumio[11379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:36 ms-113 volumio[11379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:36 ms-113 volumio[11379]: info: [1766816556604] CoreMusicLibrary::Adding element Last_100 Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:36 ms-113 volumio[11379]: Cannot find translation for sourceMedia Servers Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:36 ms-113 volumio[11379]: info: [1766816556607] CoreMusicLibrary::Adding element Webradio Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:36 ms-113 volumio[11379]: Cannot find translation for sourceMedia Servers Dec 27 06:22:36 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:22:36 ms-113 volumio[11379]: info: Volumio Calling Home Dec 27 06:22:36 ms-113 sudo[11410]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:36 ms-113 volumio[11379]: info: Completed starting Core Plugins Dec 27 06:22:36 ms-113 volumio[11379]: info: ------------------------------------------- Dec 27 06:22:36 ms-113 volumio[11379]: info: ----- MyVolumio plugins startup ---- Dec 27 06:22:36 ms-113 volumio[11379]: info: ------------------------------------------- Dec 27 06:22:36 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 06:22:36 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766816556 101 Dec 27 06:22:36 ms-113 volumio[11379]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Dec 27 06:22:37 ms-113 volumio[11379]: info: Discovery: adding 35aff5c4-1eea-4ef6-9ca9-12e5bbf60d42 Dec 27 06:22:37 ms-113 volumio[11379]: info: mDNS: Found device MS-113 Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::volumioGetState Dec 27 06:22:37 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:37 ms-113 volumio[11379]: info: MPD Permissions set Dec 27 06:22:37 ms-113 volumio[11379]: info: Volumio called home Dec 27 06:22:37 ms-113 volumio[11379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:37 ms-113 volumio[11379]: info: Starting Shairport Sync Dec 27 06:22:37 ms-113 volumio[11379]: info: Starting Shairport Sync Dec 27 06:22:37 ms-113 sudo[11526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:37 ms-113 sudo[11526]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:37 ms-113 volumio[11379]: info: Starting Shairport Sync Dec 27 06:22:37 ms-113 sudo[11529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:37 ms-113 sudo[11529]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:37 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:22:37 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:22:37 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:37 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:37 ms-113 mpd[11517]: Dec 27 06:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:22:37 ms-113 sudo[11526]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:37 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:22:37 ms-113 sudo[11533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:37 ms-113 sudo[11533]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:37 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:22:37 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:37 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:37 ms-113 sudo[11529]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:37 ms-113 volumio[11379]: info: Shairport-Sync Started Dec 27 06:22:37 ms-113 volumio[11379]: Error adding Membership: Error: addMembership EINVAL Dec 27 06:22:37 ms-113 volumio[11379]: info: CoreCommandRouter::volumioGetState Dec 27 06:22:37 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:37 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:22:37 ms-113 volumio[11379]: info: Shairport-Sync Started Dec 27 06:22:37 ms-113 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 27 06:22:37 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:22:37 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:37 ms-113 systemd[1]: shairport-sync.service: Start request repeated too quickly. Dec 27 06:22:37 ms-113 systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Dec 27 06:22:37 ms-113 systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:37 ms-113 sudo[11533]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:37 ms-113 volumio[11379]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Dec 27 06:22:37 ms-113 volumio[11379]: Job for shairport-sync.service failed. Dec 27 06:22:37 ms-113 volumio[11379]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Dec 27 06:22:37 ms-113 mpd[11517]: Dec 27 06:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:22:37 ms-113 mpd[11517]: Dec 27 06:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:22:37 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:22:37 ms-113 sudo[11504]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:37 ms-113 volumio[11379]: error: updateQueue error: null Dec 27 06:22:38 ms-113 volumio[11379]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 4 Dec 27 06:22:39 ms-113 volumio[11379]: info: Cannot mount NAS ms-113-kish at system boot, trial number 2 ,retrying in 5 seconds Dec 27 06:22:42 ms-113 volumio[11379]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 5 Dec 27 06:22:43 ms-113 volumio[11379]: info: CoreCommandRouter::volumioGetState Dec 27 06:22:43 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:43 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 06:22:43 ms-113 volumio[11379]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 06:22:43 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:43 ms-113 volumio[11379]: info: Listing playlists Dec 27 06:22:43 ms-113 volumio[11379]: info: CoreCommandRouter::volumioGetQueue Dec 27 06:22:43 ms-113 volumio[11379]: info: CoreStateMachine::getQueue Dec 27 06:22:43 ms-113 volumio[11379]: info: CorePlayQueue::getQueue Dec 27 06:22:43 ms-113 sudo[11540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:22:43 ms-113 sudo[11540]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:43 ms-113 sudo[11540]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:43 ms-113 sudo[11543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:22:43 ms-113 sudo[11543]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:43 ms-113 sudo[11543]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:43 ms-113 sudo[11546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 27 06:22:43 ms-113 sudo[11546]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:43 ms-113 sudo[11546]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:43 ms-113 volumio[11379]: info: Upmpdcli Daemon Started Dec 27 06:22:44 ms-113 volumio[11379]: info: Cannot mount NAS ms-113-kish at system boot, trial number 3 ,retrying in 5 seconds Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 27 06:22:45 ms-113 volumio[11379]: info: Adding plugin bluetooth to MyMusic Plugins Dec 27 06:22:45 ms-113 volumio[11379]: info: Adding plugin multiroom to MyMusic Plugins Dec 27 06:22:45 ms-113 volumio[11379]: info: Adding plugin metavolumio to MyMusic Plugins Dec 27 06:22:45 ms-113 volumio[11379]: info: Adding plugin cd_controller to MyMusic Plugins Dec 27 06:22:45 ms-113 volumio[11379]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 27 06:22:45 ms-113 volumio[11379]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 27 06:22:45 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:45 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:45 ms-113 volumio[11379]: info: Starting MyVolumio Remote Streaming Endpoints Dec 27 06:22:45 ms-113 volumio[11379]: info: MyVolumio not started Dec 27 06:22:45 ms-113 volumio[11379]: info: Initializing device activation check Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 27 06:22:45 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 27 06:22:46 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 27 06:22:46 ms-113 volumio[11379]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 27 06:22:46 ms-113 volumio[11379]: info: Streaming services startup Dec 27 06:22:46 ms-113 volumio[11379]: info: Starting Streaming Daemon Dec 27 06:22:46 ms-113 sudo[11549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 06:22:46 ms-113 sudo[11549]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:46 ms-113 systemd[1]: Stopping Volumio Streaming Daemon... Dec 27 06:22:46 ms-113 systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Dec 27 06:22:46 ms-113 systemd[1]: volumio-streaming-daemon.service: Succeeded. Dec 27 06:22:46 ms-113 systemd[1]: Stopped Volumio Streaming Daemon. Dec 27 06:22:46 ms-113 systemd[1]: Started Volumio Streaming Daemon. Dec 27 06:22:46 ms-113 sudo[11549]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:46 ms-113 volumio-streaming-daemon[11555]: ############################ Dec 27 06:22:46 ms-113 volumio-streaming-daemon[11555]: # Volumio Streaming Daemon # Dec 27 06:22:46 ms-113 volumio-streaming-daemon[11555]: # Running on port 7777 # Dec 27 06:22:46 ms-113 volumio-streaming-daemon[11555]: ############################ Dec 27 06:22:46 ms-113 volumio-streaming-daemon[11555]: Environment: production Dec 27 06:22:48 ms-113 volumio-streaming-daemon[11555]: Environment: production Dec 27 06:22:48 ms-113 volumio[11379]: info: Fetching Streaming Services browse cache Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreCommandRouter::volumioPlay Dec 27 06:22:48 ms-113 volumio[11379]: verbose: UNSET VOLATILE Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::play index undefined Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 06:22:48 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::startPlaybackTimer Dec 27 06:22:48 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::clearAddPlayTracks NAS/ms-113-kish/Collection September 2024/Latin/1da Banton - No Wahala (Latin Remix).mp3 Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 06:22:48 ms-113 volumio[11379]: info: sendMpdCommand stop took 3 milliseconds Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 06:22:48 ms-113 volumio[11379]: info: Dec 27 06:22:48 ms-113 volumio[11379]: ---------------------------- MPD announces system playlist update Dec 27 06:22:48 ms-113 volumio[11379]: info: Ignoring MPD Status Update Dec 27 06:22:48 ms-113 volumio[11379]: info: sendMpdCommand clear took 2 milliseconds Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::sendMpdCommand add "NAS/ms-113-kish/Collection September 2024/Latin/1da Banton - No Wahala (Latin Remix).mp3" Dec 27 06:22:48 ms-113 volumio[11379]: error: updateQueue error: null Dec 27 06:22:48 ms-113 volumio[11379]: info: Dec 27 06:22:48 ms-113 volumio[11379]: ---------------------------- MPD announces system playlist update Dec 27 06:22:48 ms-113 volumio[11379]: info: Ignoring MPD Status Update Dec 27 06:22:48 ms-113 volumio[11379]: info: ------------------------------ 7ms Dec 27 06:22:48 ms-113 volumio[11379]: info: sendMpdCommand add "NAS/ms-113-kish/Collection September 2024/Latin/1da Banton - No Wahala (Latin Remix).mp3" took 5 milliseconds Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::sendMpdCommand play Dec 27 06:22:48 ms-113 volumio[11379]: info: ------------------------------ 2ms Dec 27 06:22:48 ms-113 volumio[11379]: info: sendMpdCommand play took 2 milliseconds Dec 27 06:22:48 ms-113 volumio[11379]: info: Dec 27 06:22:48 ms-113 volumio[11379]: ---------------------------- MPD announces state update: player Dec 27 06:22:48 ms-113 volumio[11379]: info: ControllerMpd::getState Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::sendMpdCommand status Dec 27 06:22:48 ms-113 volumio[11379]: info: Dec 27 06:22:48 ms-113 volumio[11379]: ---------------------------- MPD announces state update: player Dec 27 06:22:48 ms-113 volumio[11379]: info: sendMpdCommand status took 7 milliseconds Dec 27 06:22:48 ms-113 volumio[11379]: info: ControllerMpd::getState Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::sendMpdCommand status Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::parseState Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 06:22:48 ms-113 volumio[11379]: info: sendMpdCommand status took 5 milliseconds Dec 27 06:22:48 ms-113 volumio[11379]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::parseState Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::parseTrackInfo Dec 27 06:22:48 ms-113 volumio[11379]: info: ControllerMpd::pushState Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreCommandRouter::servicePushState Dec 27 06:22:48 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:48 ms-113 volumio[11379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"No Wahala (Latin Remix)","artist":"1da Banton","album":"No Wahala (Latin Remix)","uri":"NAS/ms-113-kish/Collection September 2024/Latin/1da Banton - No Wahala (Latin Remix).mp3","trackType":"mp3"} Dec 27 06:22:48 ms-113 volumio[11379]: verbose: CURRENT POSITION 0 Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::syncState stateService play Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::syncState currentStatus stop Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::pushState Dec 27 06:22:48 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:48 ms-113 volumio[11379]: info: ------------------------------ 26ms Dec 27 06:22:48 ms-113 volumio[11379]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 27 06:22:48 ms-113 volumio[11379]: verbose: ControllerMpd::parseTrackInfo Dec 27 06:22:48 ms-113 volumio[11379]: info: ControllerMpd::pushState Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreCommandRouter::servicePushState Dec 27 06:22:48 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:48 ms-113 volumio[11379]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":158,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"No Wahala (Latin Remix)","artist":"1da Banton","album":"No Wahala (Latin Remix)","uri":"NAS/ms-113-kish/Collection September 2024/Latin/1da Banton - No Wahala (Latin Remix).mp3","trackType":"mp3"} Dec 27 06:22:48 ms-113 volumio[11379]: verbose: CURRENT POSITION 0 Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::syncState stateService play Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::syncState currentStatus play Dec 27 06:22:48 ms-113 volumio[11379]: info: Received an update from plugin. extracting info from payload Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::pushState Dec 27 06:22:48 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreStateMachine::pushState Dec 27 06:22:48 ms-113 volumio[11379]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:48 ms-113 volumio[11379]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:48 ms-113 volumio[11379]: info: ------------------------------ 42ms Dec 27 06:22:49 ms-113 volumio[11379]: info: Cannot mount NAS ms-113-kish at system boot, trial number 4 ,retrying in 5 seconds Dec 27 06:22:49 ms-113 volumio[11379]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:22:49 ms-113 volumio[11379]: Error: connect EHOSTUNREACH 10.10.34.36:443 Dec 27 06:22:49 ms-113 volumio[11379]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 06:22:49 ms-113 volumio[11379]: errno: -113, Dec 27 06:22:49 ms-113 volumio[11379]: code: 'EHOSTUNREACH', Dec 27 06:22:49 ms-113 volumio[11379]: syscall: 'connect', Dec 27 06:22:49 ms-113 volumio[11379]: address: '10.10.34.36', Dec 27 06:22:49 ms-113 volumio[11379]: port: 443 Dec 27 06:22:49 ms-113 volumio[11379]: } Dec 27 06:22:49 ms-113 volumio[11379]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:22:49 ms-113 ntpd[665]: Soliciting pool server 85.215.189.120 Dec 27 06:22:49 ms-113 sudo[11575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:21 Dec 27 06:22:49 ms-113 sudo[11575]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:49 ms-113 sudo[11575]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:49 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 27 06:22:49 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 27 06:22:49 ms-113 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 27 06:22:49 ms-113 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 27 06:22:50 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:22:50 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:22:50 ms-113 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 06:22:50 ms-113 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 15. Dec 27 06:22:50 ms-113 systemd[1]: Stopped Volumio Backend Module. Dec 27 06:22:50 ms-113 systemd[1]: Started Volumio Backend Module. Dec 27 06:22:50 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:22:50 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:22:51 ms-113 volumio[11585]: info: ------------------------------------------- Dec 27 06:22:51 ms-113 volumio[11585]: info: ----- Volumio3 ---- Dec 27 06:22:51 ms-113 volumio[11585]: info: ------------------------------------------- Dec 27 06:22:51 ms-113 volumio[11585]: info: ----- System startup ---- Dec 27 06:22:51 ms-113 volumio[11585]: info: ------------------------------------------- Dec 27 06:22:51 ms-113 volumio[11585]: info: MYVOLUMIO Environment detected Dec 27 06:22:51 ms-113 volumio[11585]: info: Plugin folders cleanup Dec 27 06:22:51 ms-113 volumio[11585]: info: Scanning into folder /volumio/app/plugins/ Dec 27 06:22:51 ms-113 volumio[11585]: info: Scanning category audio_interface Dec 27 06:22:51 ms-113 volumio[11585]: info: Scanning category miscellanea Dec 27 06:22:51 ms-113 volumio[11585]: info: Scanning category music_service Dec 27 06:22:51 ms-113 volumio[11585]: info: Scanning category plugins.json Dec 27 06:22:51 ms-113 volumio[11585]: info: Scanning category system_controller Dec 27 06:22:51 ms-113 volumio[11585]: info: Scanning category user_interface Dec 27 06:22:51 ms-113 volumio[11585]: info: Scanning into folder /data/plugins/ Dec 27 06:22:51 ms-113 volumio[11585]: info: Plugin folders cleanup completed Dec 27 06:22:51 ms-113 volumio[11585]: info: ------------------------------------------- Dec 27 06:22:51 ms-113 volumio[11585]: info: ----- Core plugins startup ---- Dec 27 06:22:51 ms-113 volumio[11585]: info: ------------------------------------------- Dec 27 06:22:51 ms-113 volumio[11585]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 06:22:51 ms-113 volumio[11585]: info: Adding plugin upnp to MyMusic Plugins Dec 27 06:22:51 ms-113 volumio[11585]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 06:22:51 ms-113 volumio[11585]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 06:22:51 ms-113 volumio[11585]: info: Loading plugins from folder /data/plugins/ Dec 27 06:22:51 ms-113 volumio[11585]: info: Loading plugin "system"... Dec 27 06:22:51 ms-113 volumio[11585]: info: Loading plugin "appearance"... Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "network"... Dec 27 06:22:52 ms-113 volumio[11585]: info: Refreshing Cached IP Addresses Dec 27 06:22:52 ms-113 sudo[11609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:22:52 ms-113 sudo[11609]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:52 ms-113 sudo[11609]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:52 ms-113 sudo[11611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:22:52 ms-113 sudo[11611]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "services"... Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "alsa_controller"... Dec 27 06:22:52 ms-113 sudo[11611]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:52 ms-113 sudo[11615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 06:22:52 ms-113 sudo[11615]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "wizard"... Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "volumio_command_line_client"... Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "upnp"... Dec 27 06:22:52 ms-113 volumio[11585]: info: [1766816572358] Starting Upmpd Daemon Dec 27 06:22:52 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "my_music"... Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "mpd"... Dec 27 06:22:52 ms-113 volumio[11585]: info: Creating MPD Configuration file Dec 27 06:22:52 ms-113 sudo[11622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:22:52 ms-113 sudo[11622]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "upnp_browser"... Dec 27 06:22:52 ms-113 sudo[11622]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:52 ms-113 sudo[11624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:22:52 ms-113 sudo[11624]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:52 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:22:52 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:22:52 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:22:52 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:22:52 ms-113 volumio[11585]: info: Loading plugin "networkfs"... Dec 27 06:22:52 ms-113 volumio[11585]: info: Cannot mount NAS ms-113-kish at system boot, trial number 1 ,retrying in 5 seconds Dec 27 06:22:52 ms-113 volumio[11585]: info: Starting Udev Watcher for removable devices Dec 27 06:22:53 ms-113 volumio[11585]: info: Ignoring mount for partition: boot Dec 27 06:22:53 ms-113 volumio[11585]: info: Ignoring mount for partition: volumio Dec 27 06:22:53 ms-113 volumio[11585]: info: Ignoring mount for partition: volumio_data Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "alarm-clock"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "airplay_emulation"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Starting Shairport Sync Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "last_100"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "webradio"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "i2s_dacs"... Dec 27 06:22:53 ms-113 volumio[11585]: info: I2S DAC not set, start Auto-detection Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "volumiodiscovery"... Dec 27 06:22:53 ms-113 mpd[11629]: Dec 27 06:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:22:53 ms-113 volumio[11585]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:53 ms-113 volumio[11585]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:53 ms-113 node[11585]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:53 ms-113 volumio[11585]: *** WARNING *** For more information see Dec 27 06:22:53 ms-113 volumio[11585]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:53 ms-113 volumio[11585]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:53 ms-113 volumio[11585]: *** WARNING *** For more information see Dec 27 06:22:53 ms-113 node[11585]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:53 ms-113 node[11585]: *** WARNING *** For more information see Dec 27 06:22:53 ms-113 node[11585]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:22:53 ms-113 node[11585]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:22:53 ms-113 node[11585]: *** WARNING *** For more information see Dec 27 06:22:53 ms-113 volumio[11585]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 06:22:53 ms-113 volumio[11585]: info: Discovery: Started advertising with name: MS-113 Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "outputs"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "albumart"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Plugin example_plugin is not enabled Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "inputs"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "updater_comm"... Dec 27 06:22:53 ms-113 mpd[11629]: Dec 27 06:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:22:53 ms-113 mpd[11629]: Dec 27 06:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:22:53 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:22:53 ms-113 sudo[11624]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:53 ms-113 volumio[11585]: info: Plugin mpdemulation is not enabled Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "rest_api"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading plugin "websocket"... Dec 27 06:22:53 ms-113 volumio[11585]: info: Loading i18n strings for locale en Dec 27 06:22:53 ms-113 volumio[11585]: Updating browse sources language Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::initPlayerControls Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: Express server listening on port 3000 Dec 27 06:22:53 ms-113 volumio[11585]: [Metrics] WebUI: 2s 836.86ms Dec 27 06:22:53 ms-113 volumio[11585]: Forking 3 albumart workers Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreStateMachine::resetVolumioState Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreStateMachine::getcurrentVolume Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:22:53 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:53 ms-113 volumio[11585]: info: MPD Permissions set Dec 27 06:22:53 ms-113 volumio[11585]: info: MPD running with PID11629 Dec 27 06:22:53 ms-113 volumio[11585]: ,establishing connection Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:54 ms-113 volumio[11585]: info: Reloading queue from file Dec 27 06:22:54 ms-113 volumio[11585]: error: updateQueue error: null Dec 27 06:22:54 ms-113 volumio[11585]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 06:22:54 ms-113 volumio[11585]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreStateMachine::pushState Dec 27 06:22:54 ms-113 volumio[11585]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreStateMachine::updateTrackBlock Dec 27 06:22:54 ms-113 volumio[11585]: info: CorePlayQueue::getTrackBlock Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreStateMachine::setRepeat false single undefined Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreStateMachine::pushState Dec 27 06:22:54 ms-113 volumio[11585]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreStateMachine::setRandom false Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreStateMachine::pushState Dec 27 06:22:54 ms-113 volumio[11585]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:54 ms-113 volumio[11585]: info: Setting Device type: Raspberry PI Dec 27 06:22:54 ms-113 volumio[11585]: Starting albumart workers Dec 27 06:22:54 ms-113 volumio[11585]: Starting albumart workers Dec 27 06:22:54 ms-113 volumio[11585]: Starting albumart workers Dec 27 06:22:54 ms-113 volumio[11585]: error: updateQueue error: null Dec 27 06:22:54 ms-113 volumio[11585]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 1 Dec 27 06:22:54 ms-113 volumio[11585]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 2 Dec 27 06:22:54 ms-113 volumio[11585]: info: Completed loading Core Plugins Dec 27 06:22:54 ms-113 volumio[11585]: info: Preparing to generate the ALSA configuration file Dec 27 06:22:54 ms-113 volumio[11585]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreStateMachine::pushState Dec 27 06:22:54 ms-113 volumio[11585]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:54 ms-113 volumio[11585]: info: CoreCommandRouter::volumioPushState Dec 27 06:22:54 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:54] [connect] Successful connection Dec 27 06:22:55 ms-113 sudo[11615]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:55 ms-113 volumio[11585]: info: Asound.conf file unchanged, so no further update is needed Dec 27 06:22:55 ms-113 volumio[11585]: info: Output device has changed, restarting MPD Dec 27 06:22:55 ms-113 sudo[11707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:22:55 ms-113 sudo[11707]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:55 ms-113 volumio[11585]: info: Output device has changed, restarting Shairport Sync Dec 27 06:22:55 ms-113 sudo[11707]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:55 ms-113 sudo[11710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:22:55 ms-113 sudo[11710]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:55 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:22:55 ms-113 volumio[11585]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:55 ms-113 volumio[11585]: info: ___________ START PLUGINS ___________ Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:55 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:22:55 ms-113 volumio[11585]: info: [1766816575349] CoreMusicLibrary::Adding element Media Servers Dec 27 06:22:55 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:55 ms-113 volumio[11585]: Cannot find translation for sourceMedia Servers Dec 27 06:22:55 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:55 ms-113 volumio[11585]: info: [1766816575454] CoreMusicLibrary::Adding element Last_100 Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:55 ms-113 volumio[11585]: Cannot find translation for sourceMedia Servers Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:22:55 ms-113 volumio[11585]: info: [1766816575458] CoreMusicLibrary::Adding element Webradio Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:22:55 ms-113 volumio[11585]: Cannot find translation for sourceMedia Servers Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:22:55 ms-113 volumio[11585]: info: Volumio Calling Home Dec 27 06:22:55 ms-113 volumio[11585]: info: Completed starting Core Plugins Dec 27 06:22:55 ms-113 volumio[11585]: info: ------------------------------------------- Dec 27 06:22:55 ms-113 volumio[11585]: info: ----- MyVolumio plugins startup ---- Dec 27 06:22:55 ms-113 volumio[11585]: info: ------------------------------------------- Dec 27 06:22:55 ms-113 volumio[11585]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 06:22:55 ms-113 volumio[11585]: info: Discovery: adding 35aff5c4-1eea-4ef6-9ca9-12e5bbf60d42 Dec 27 06:22:55 ms-113 volumio[11585]: info: mDNS: Found device MS-113 Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::volumioGetState Dec 27 06:22:55 ms-113 volumio[11585]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:55 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766816574 101 Dec 27 06:22:55 ms-113 volumio[11585]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Dec 27 06:22:55 ms-113 volumio[11585]: info: MPD Permissions set Dec 27 06:22:55 ms-113 volumio[11585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:22:55 ms-113 volumio[11585]: info: Starting Shairport Sync Dec 27 06:22:55 ms-113 volumio[11585]: info: Starting Shairport Sync Dec 27 06:22:55 ms-113 sudo[11732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:55 ms-113 sudo[11732]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:55 ms-113 volumio[11585]: info: Starting Shairport Sync Dec 27 06:22:55 ms-113 sudo[11734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:55 ms-113 sudo[11734]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:55 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:55 ms-113 sudo[11732]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:55 ms-113 sudo[11739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:22:55 ms-113 sudo[11739]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:55 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:22:55 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:22:55 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:55 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:22:55 ms-113 sudo[11734]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:55 ms-113 sudo[11739]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:55 ms-113 volumio[11585]: info: Shairport-Sync Started Dec 27 06:22:55 ms-113 volumio[11585]: Error adding Membership: Error: addMembership EINVAL Dec 27 06:22:55 ms-113 volumio[11585]: info: Shairport-Sync Started Dec 27 06:22:55 ms-113 volumio[11585]: info: Shairport-Sync Started Dec 27 06:22:55 ms-113 volumio[11585]: info: Volumio called home Dec 27 06:22:55 ms-113 volumio[11585]: info: CoreCommandRouter::volumioGetState Dec 27 06:22:55 ms-113 volumio[11585]: info: CorePlayQueue::getTrack 0 Dec 27 06:22:56 ms-113 mpd[11724]: Dec 27 06:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:22:56 ms-113 mpd[11724]: Dec 27 06:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:22:56 ms-113 mpd[11724]: Dec 27 06:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:22:56 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:22:56 ms-113 sudo[11710]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:56 ms-113 volumio[11585]: error: updateQueue error: null Dec 27 06:22:57 ms-113 volumio[11585]: info: Cannot mount NAS ms-113-kish at system boot, trial number 2 ,retrying in 5 seconds Dec 27 06:22:58 ms-113 volumio[11585]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:22:58 ms-113 volumio[11585]: Error: connect EHOSTUNREACH 10.10.34.36:443 Dec 27 06:22:58 ms-113 volumio[11585]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 06:22:58 ms-113 volumio[11585]: errno: -113, Dec 27 06:22:58 ms-113 volumio[11585]: code: 'EHOSTUNREACH', Dec 27 06:22:58 ms-113 volumio[11585]: syscall: 'connect', Dec 27 06:22:58 ms-113 volumio[11585]: address: '10.10.34.36', Dec 27 06:22:58 ms-113 volumio[11585]: port: 443 Dec 27 06:22:58 ms-113 volumio[11585]: } Dec 27 06:22:58 ms-113 volumio[11585]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:22:58 ms-113 sudo[11758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:21 Dec 27 06:22:58 ms-113 sudo[11758]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:22:58 ms-113 sudo[11758]: pam_unix(sudo:session): session closed for user root Dec 27 06:22:58 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 27 06:22:58 ms-113 volumio-remote-updater[491]: [2025-12-27 06:22:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 27 06:22:58 ms-113 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 27 06:22:58 ms-113 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 27 06:22:58 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:22:58 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:22:59 ms-113 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 06:22:59 ms-113 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 16. Dec 27 06:22:59 ms-113 systemd[1]: Stopped Volumio Backend Module. Dec 27 06:22:59 ms-113 systemd[1]: Started Volumio Backend Module. Dec 27 06:22:59 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:22:59 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:00 ms-113 volumio[11768]: info: ------------------------------------------- Dec 27 06:23:00 ms-113 volumio[11768]: info: ----- Volumio3 ---- Dec 27 06:23:00 ms-113 volumio[11768]: info: ------------------------------------------- Dec 27 06:23:00 ms-113 volumio[11768]: info: ----- System startup ---- Dec 27 06:23:00 ms-113 volumio[11768]: info: ------------------------------------------- Dec 27 06:23:00 ms-113 ntpd[665]: Soliciting pool server 188.68.34.173 Dec 27 06:23:00 ms-113 volumio[11768]: info: MYVOLUMIO Environment detected Dec 27 06:23:00 ms-113 volumio[11768]: info: Plugin folders cleanup Dec 27 06:23:00 ms-113 volumio[11768]: info: Scanning into folder /volumio/app/plugins/ Dec 27 06:23:00 ms-113 volumio[11768]: info: Scanning category audio_interface Dec 27 06:23:00 ms-113 volumio[11768]: info: Scanning category miscellanea Dec 27 06:23:00 ms-113 volumio[11768]: info: Scanning category music_service Dec 27 06:23:00 ms-113 volumio[11768]: info: Scanning category plugins.json Dec 27 06:23:00 ms-113 volumio[11768]: info: Scanning category system_controller Dec 27 06:23:00 ms-113 volumio[11768]: info: Scanning category user_interface Dec 27 06:23:00 ms-113 volumio[11768]: info: Scanning into folder /data/plugins/ Dec 27 06:23:00 ms-113 volumio[11768]: info: Plugin folders cleanup completed Dec 27 06:23:00 ms-113 volumio[11768]: info: ------------------------------------------- Dec 27 06:23:00 ms-113 volumio[11768]: info: ----- Core plugins startup ---- Dec 27 06:23:00 ms-113 volumio[11768]: info: ------------------------------------------- Dec 27 06:23:00 ms-113 volumio[11768]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 06:23:00 ms-113 volumio[11768]: info: Adding plugin upnp to MyMusic Plugins Dec 27 06:23:00 ms-113 volumio[11768]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 06:23:00 ms-113 volumio[11768]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 06:23:00 ms-113 volumio[11768]: info: Loading plugins from folder /data/plugins/ Dec 27 06:23:00 ms-113 volumio[11768]: info: Loading plugin "system"... Dec 27 06:23:00 ms-113 volumio[11768]: info: Loading plugin "appearance"... Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "network"... Dec 27 06:23:01 ms-113 volumio[11768]: info: Refreshing Cached IP Addresses Dec 27 06:23:01 ms-113 sudo[11792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:23:01 ms-113 sudo[11792]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:01 ms-113 sudo[11792]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:01 ms-113 sudo[11794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:23:01 ms-113 sudo[11794]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "services"... Dec 27 06:23:01 ms-113 sudo[11794]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "alsa_controller"... Dec 27 06:23:01 ms-113 sudo[11798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 06:23:01 ms-113 sudo[11798]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "wizard"... Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "volumio_command_line_client"... Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "upnp"... Dec 27 06:23:01 ms-113 volumio[11768]: info: [1766816581301] Starting Upmpd Daemon Dec 27 06:23:01 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "my_music"... Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "mpd"... Dec 27 06:23:01 ms-113 volumio[11768]: info: Creating MPD Configuration file Dec 27 06:23:01 ms-113 sudo[11805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:01 ms-113 sudo[11805]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "upnp_browser"... Dec 27 06:23:01 ms-113 sudo[11805]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:01 ms-113 sudo[11808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:01 ms-113 sudo[11808]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:01 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:01 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:01 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:01 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:01 ms-113 volumio[11768]: info: Loading plugin "networkfs"... Dec 27 06:23:01 ms-113 volumio[11768]: info: Cannot mount NAS ms-113-kish at system boot, trial number 1 ,retrying in 5 seconds Dec 27 06:23:01 ms-113 volumio[11768]: info: Starting Udev Watcher for removable devices Dec 27 06:23:02 ms-113 volumio[11768]: info: Ignoring mount for partition: boot Dec 27 06:23:02 ms-113 volumio[11768]: info: Ignoring mount for partition: volumio Dec 27 06:23:02 ms-113 volumio[11768]: info: Ignoring mount for partition: volumio_data Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "alarm-clock"... Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "airplay_emulation"... Dec 27 06:23:02 ms-113 volumio[11768]: info: Starting Shairport Sync Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "last_100"... Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "webradio"... Dec 27 06:23:02 ms-113 mpd[11812]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "i2s_dacs"... Dec 27 06:23:02 ms-113 volumio[11768]: info: I2S DAC not set, start Auto-detection Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "volumiodiscovery"... Dec 27 06:23:02 ms-113 volumio[11768]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:02 ms-113 volumio[11768]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:02 ms-113 node[11768]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:02 ms-113 volumio[11768]: *** WARNING *** For more information see Dec 27 06:23:02 ms-113 volumio[11768]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:02 ms-113 volumio[11768]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:02 ms-113 volumio[11768]: *** WARNING *** For more information see Dec 27 06:23:02 ms-113 node[11768]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:02 ms-113 node[11768]: *** WARNING *** For more information see Dec 27 06:23:02 ms-113 node[11768]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:02 ms-113 node[11768]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:02 ms-113 node[11768]: *** WARNING *** For more information see Dec 27 06:23:02 ms-113 volumio[11768]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 06:23:02 ms-113 volumio[11768]: info: Discovery: Started advertising with name: MS-113 Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "outputs"... Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "albumart"... Dec 27 06:23:02 ms-113 mpd[11812]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:02 ms-113 mpd[11812]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:02 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:02 ms-113 sudo[11808]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:02 ms-113 volumio[11768]: info: Plugin example_plugin is not enabled Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "inputs"... Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "updater_comm"... Dec 27 06:23:02 ms-113 volumio[11768]: info: Plugin mpdemulation is not enabled Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "rest_api"... Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading plugin "websocket"... Dec 27 06:23:02 ms-113 volumio[11768]: info: Loading i18n strings for locale en Dec 27 06:23:02 ms-113 volumio[11768]: Updating browse sources language Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::initPlayerControls Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: Express server listening on port 3000 Dec 27 06:23:02 ms-113 volumio[11768]: [Metrics] WebUI: 2s 786.22ms Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreStateMachine::resetVolumioState Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreStateMachine::getcurrentVolume Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: Forking 3 albumart workers Dec 27 06:23:02 ms-113 volumio[11768]: info: MPD Permissions set Dec 27 06:23:02 ms-113 volumio[11768]: info: MPD running with PID11812 Dec 27 06:23:02 ms-113 volumio[11768]: ,establishing connection Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:02 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:03 ms-113 volumio[11768]: info: Reloading queue from file Dec 27 06:23:03 ms-113 volumio[11768]: error: updateQueue error: null Dec 27 06:23:03 ms-113 volumio[11768]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 06:23:03 ms-113 volumio[11768]: Starting albumart workers Dec 27 06:23:03 ms-113 volumio[11768]: Starting albumart workers Dec 27 06:23:03 ms-113 volumio[11768]: Starting albumart workers Dec 27 06:23:03 ms-113 volumio[11768]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreStateMachine::pushState Dec 27 06:23:03 ms-113 volumio[11768]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreStateMachine::updateTrackBlock Dec 27 06:23:03 ms-113 volumio[11768]: info: CorePlayQueue::getTrackBlock Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreStateMachine::setRepeat false single undefined Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreStateMachine::pushState Dec 27 06:23:03 ms-113 volumio[11768]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreStateMachine::setRandom false Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreStateMachine::pushState Dec 27 06:23:03 ms-113 volumio[11768]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:03 ms-113 volumio[11768]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:03 ms-113 volumio[11768]: info: Setting Device type: Raspberry PI Dec 27 06:23:03 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:03] [connect] Successful connection Dec 27 06:23:04 ms-113 volumio[11768]: error: updateQueue error: null Dec 27 06:23:04 ms-113 volumio[11768]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 1 Dec 27 06:23:04 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766816583 101 Dec 27 06:23:04 ms-113 volumio[11768]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Dec 27 06:23:04 ms-113 volumio[11768]: info: Completed loading Core Plugins Dec 27 06:23:04 ms-113 volumio[11768]: info: Preparing to generate the ALSA configuration file Dec 27 06:23:04 ms-113 sudo[11798]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:04 ms-113 volumio[11768]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreStateMachine::pushState Dec 27 06:23:04 ms-113 volumio[11768]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:04 ms-113 volumio[11768]: info: Asound.conf file unchanged, so no further update is needed Dec 27 06:23:04 ms-113 volumio[11768]: info: Output device has changed, restarting MPD Dec 27 06:23:04 ms-113 sudo[11889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:04 ms-113 sudo[11889]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:04 ms-113 sudo[11889]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:04 ms-113 volumio[11768]: info: Output device has changed, restarting Shairport Sync Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:04 ms-113 sudo[11892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:04 ms-113 sudo[11892]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:04 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:04 ms-113 volumio[11768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:04 ms-113 volumio[11768]: info: ___________ START PLUGINS ___________ Dec 27 06:23:04 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:04 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:04 ms-113 volumio[11768]: info: [1766816584285] CoreMusicLibrary::Adding element Media Servers Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:04 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:04 ms-113 volumio[11768]: Cannot find translation for sourceMedia Servers Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:04 ms-113 volumio[11768]: info: [1766816584388] CoreMusicLibrary::Adding element Last_100 Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:04 ms-113 volumio[11768]: Cannot find translation for sourceMedia Servers Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:04 ms-113 volumio[11768]: info: [1766816584392] CoreMusicLibrary::Adding element Webradio Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:04 ms-113 volumio[11768]: Cannot find translation for sourceMedia Servers Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:23:04 ms-113 volumio[11768]: info: Volumio Calling Home Dec 27 06:23:04 ms-113 volumio[11768]: info: Completed starting Core Plugins Dec 27 06:23:04 ms-113 volumio[11768]: info: ------------------------------------------- Dec 27 06:23:04 ms-113 volumio[11768]: info: ----- MyVolumio plugins startup ---- Dec 27 06:23:04 ms-113 volumio[11768]: info: ------------------------------------------- Dec 27 06:23:04 ms-113 volumio[11768]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 06:23:04 ms-113 volumio[11768]: info: Discovery: adding 35aff5c4-1eea-4ef6-9ca9-12e5bbf60d42 Dec 27 06:23:04 ms-113 volumio[11768]: info: mDNS: Found device MS-113 Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:04 ms-113 volumio[11768]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:04 ms-113 volumio[11768]: info: MPD Permissions set Dec 27 06:23:04 ms-113 volumio[11768]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:04 ms-113 volumio[11768]: info: Starting Shairport Sync Dec 27 06:23:04 ms-113 volumio[11768]: info: Starting Shairport Sync Dec 27 06:23:04 ms-113 sudo[11914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:04 ms-113 sudo[11914]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:04 ms-113 volumio[11768]: info: Starting Shairport Sync Dec 27 06:23:04 ms-113 sudo[11917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:04 ms-113 sudo[11917]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:04 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:04 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:04 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:04 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:04 ms-113 sudo[11914]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:04 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:04 ms-113 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 27 06:23:04 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:04 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:04 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:04 ms-113 sudo[11917]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:04 ms-113 sudo[11920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:04 ms-113 sudo[11920]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:04 ms-113 volumio[11768]: info: Shairport-Sync Started Dec 27 06:23:04 ms-113 volumio[11768]: Error adding Membership: Error: addMembership EINVAL Dec 27 06:23:04 ms-113 volumio[11768]: info: Shairport-Sync Started Dec 27 06:23:04 ms-113 volumio[11768]: info: Volumio called home Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:04 ms-113 volumio[11768]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:04 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:04 ms-113 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 27 06:23:04 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:04 ms-113 volumio[11768]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:04 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:04 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:04 ms-113 sudo[11920]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:04 ms-113 volumio[11768]: info: Shairport-Sync Started Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:04 ms-113 volumio[11768]: info: Listing playlists Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreCommandRouter::volumioGetQueue Dec 27 06:23:04 ms-113 volumio[11768]: info: CoreStateMachine::getQueue Dec 27 06:23:04 ms-113 volumio[11768]: info: CorePlayQueue::getQueue Dec 27 06:23:05 ms-113 mpd[11906]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:05 ms-113 mpd[11906]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:05 ms-113 mpd[11906]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:05 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:05 ms-113 sudo[11892]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:05 ms-113 volumio[11768]: error: updateQueue error: null Dec 27 06:23:06 ms-113 volumio[11768]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:06 ms-113 volumio[11768]: Error: connect EHOSTUNREACH 10.10.34.36:443 Dec 27 06:23:06 ms-113 volumio[11768]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 06:23:06 ms-113 volumio[11768]: errno: -113, Dec 27 06:23:06 ms-113 volumio[11768]: code: 'EHOSTUNREACH', Dec 27 06:23:06 ms-113 volumio[11768]: syscall: 'connect', Dec 27 06:23:06 ms-113 volumio[11768]: address: '10.10.34.36', Dec 27 06:23:06 ms-113 volumio[11768]: port: 443 Dec 27 06:23:06 ms-113 volumio[11768]: } Dec 27 06:23:06 ms-113 volumio[11768]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:06 ms-113 sudo[11938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:22 Dec 27 06:23:06 ms-113 sudo[11938]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:06 ms-113 sudo[11938]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:06 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 27 06:23:06 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 27 06:23:07 ms-113 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 27 06:23:07 ms-113 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 27 06:23:07 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:23:07 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:07 ms-113 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 06:23:07 ms-113 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Dec 27 06:23:07 ms-113 systemd[1]: Stopped Volumio Backend Module. Dec 27 06:23:07 ms-113 systemd[1]: Started Volumio Backend Module. Dec 27 06:23:07 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:23:07 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:08 ms-113 volumio[11948]: info: ------------------------------------------- Dec 27 06:23:08 ms-113 volumio[11948]: info: ----- Volumio3 ---- Dec 27 06:23:08 ms-113 volumio[11948]: info: ------------------------------------------- Dec 27 06:23:08 ms-113 volumio[11948]: info: ----- System startup ---- Dec 27 06:23:08 ms-113 volumio[11948]: info: ------------------------------------------- Dec 27 06:23:08 ms-113 volumio[11948]: info: MYVOLUMIO Environment detected Dec 27 06:23:08 ms-113 volumio[11948]: info: Plugin folders cleanup Dec 27 06:23:08 ms-113 volumio[11948]: info: Scanning into folder /volumio/app/plugins/ Dec 27 06:23:08 ms-113 volumio[11948]: info: Scanning category audio_interface Dec 27 06:23:08 ms-113 volumio[11948]: info: Scanning category miscellanea Dec 27 06:23:08 ms-113 volumio[11948]: info: Scanning category music_service Dec 27 06:23:08 ms-113 volumio[11948]: info: Scanning category plugins.json Dec 27 06:23:08 ms-113 volumio[11948]: info: Scanning category system_controller Dec 27 06:23:08 ms-113 volumio[11948]: info: Scanning category user_interface Dec 27 06:23:08 ms-113 volumio[11948]: info: Scanning into folder /data/plugins/ Dec 27 06:23:08 ms-113 volumio[11948]: info: Plugin folders cleanup completed Dec 27 06:23:08 ms-113 volumio[11948]: info: ------------------------------------------- Dec 27 06:23:08 ms-113 volumio[11948]: info: ----- Core plugins startup ---- Dec 27 06:23:08 ms-113 volumio[11948]: info: ------------------------------------------- Dec 27 06:23:08 ms-113 volumio[11948]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 06:23:08 ms-113 volumio[11948]: info: Adding plugin upnp to MyMusic Plugins Dec 27 06:23:08 ms-113 volumio[11948]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 06:23:08 ms-113 volumio[11948]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 06:23:08 ms-113 volumio[11948]: info: Loading plugins from folder /data/plugins/ Dec 27 06:23:08 ms-113 volumio[11948]: info: Loading plugin "system"... Dec 27 06:23:08 ms-113 volumio[11948]: info: Loading plugin "appearance"... Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "network"... Dec 27 06:23:09 ms-113 volumio[11948]: info: Refreshing Cached IP Addresses Dec 27 06:23:09 ms-113 sudo[11972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:23:09 ms-113 sudo[11972]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:09 ms-113 sudo[11972]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:09 ms-113 sudo[11975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "services"... Dec 27 06:23:09 ms-113 sudo[11975]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "alsa_controller"... Dec 27 06:23:09 ms-113 sudo[11975]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:09 ms-113 sudo[11977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 06:23:09 ms-113 sudo[11977]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "wizard"... Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "volumio_command_line_client"... Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "upnp"... Dec 27 06:23:09 ms-113 volumio[11948]: info: [1766816589595] Starting Upmpd Daemon Dec 27 06:23:09 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "my_music"... Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "mpd"... Dec 27 06:23:09 ms-113 volumio[11948]: info: Creating MPD Configuration file Dec 27 06:23:09 ms-113 sudo[11985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:09 ms-113 sudo[11985]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:09 ms-113 volumio[11948]: info: Loading plugin "upnp_browser"... Dec 27 06:23:09 ms-113 sudo[11985]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:09 ms-113 sudo[11987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:09 ms-113 sudo[11987]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:09 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:09 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:09 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:09 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "networkfs"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Cannot mount NAS ms-113-kish at system boot, trial number 1 ,retrying in 5 seconds Dec 27 06:23:10 ms-113 volumio[11948]: info: Starting Udev Watcher for removable devices Dec 27 06:23:10 ms-113 volumio[11948]: info: Ignoring mount for partition: boot Dec 27 06:23:10 ms-113 volumio[11948]: info: Ignoring mount for partition: volumio Dec 27 06:23:10 ms-113 volumio[11948]: info: Ignoring mount for partition: volumio_data Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "alarm-clock"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "airplay_emulation"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Starting Shairport Sync Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "last_100"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "webradio"... Dec 27 06:23:10 ms-113 mpd[11992]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "i2s_dacs"... Dec 27 06:23:10 ms-113 volumio[11948]: info: I2S DAC not set, start Auto-detection Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "volumiodiscovery"... Dec 27 06:23:10 ms-113 volumio[11948]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:10 ms-113 node[11948]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:10 ms-113 volumio[11948]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:10 ms-113 node[11948]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:10 ms-113 volumio[11948]: *** WARNING *** For more information see Dec 27 06:23:10 ms-113 node[11948]: *** WARNING *** For more information see Dec 27 06:23:10 ms-113 volumio[11948]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:10 ms-113 node[11948]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:10 ms-113 volumio[11948]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:10 ms-113 node[11948]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:10 ms-113 volumio[11948]: *** WARNING *** For more information see Dec 27 06:23:10 ms-113 node[11948]: *** WARNING *** For more information see Dec 27 06:23:10 ms-113 volumio[11948]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 06:23:10 ms-113 volumio[11948]: info: Discovery: Started advertising with name: MS-113 Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "outputs"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "albumart"... Dec 27 06:23:10 ms-113 mpd[11992]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:10 ms-113 mpd[11992]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:10 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:10 ms-113 sudo[11987]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:10 ms-113 volumio[11948]: info: Plugin example_plugin is not enabled Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "inputs"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "updater_comm"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Plugin mpdemulation is not enabled Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "rest_api"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading plugin "websocket"... Dec 27 06:23:10 ms-113 volumio[11948]: info: Loading i18n strings for locale en Dec 27 06:23:10 ms-113 volumio[11948]: Updating browse sources language Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::initPlayerControls Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:10 ms-113 volumio[11948]: Express server listening on port 3000 Dec 27 06:23:10 ms-113 volumio[11948]: [Metrics] WebUI: 2s 866.63ms Dec 27 06:23:10 ms-113 volumio[11948]: Forking 3 albumart workers Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreStateMachine::resetVolumioState Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreStateMachine::getcurrentVolume Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:10 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: MPD Permissions set Dec 27 06:23:11 ms-113 volumio[11948]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 1 Dec 27 06:23:11 ms-113 volumio[11948]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 2 Dec 27 06:23:11 ms-113 volumio[11948]: info: MPD running with PID11992 Dec 27 06:23:11 ms-113 volumio[11948]: ,establishing connection Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:11 ms-113 volumio[11948]: info: Reloading queue from file Dec 27 06:23:11 ms-113 volumio[11948]: Starting albumart workers Dec 27 06:23:11 ms-113 volumio[11948]: Starting albumart workers Dec 27 06:23:11 ms-113 volumio[11948]: error: updateQueue error: null Dec 27 06:23:11 ms-113 volumio[11948]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 06:23:11 ms-113 volumio[11948]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreStateMachine::pushState Dec 27 06:23:11 ms-113 volumio[11948]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreStateMachine::updateTrackBlock Dec 27 06:23:11 ms-113 volumio[11948]: info: CorePlayQueue::getTrackBlock Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreStateMachine::setRepeat false single undefined Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreStateMachine::pushState Dec 27 06:23:11 ms-113 volumio[11948]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreStateMachine::setRandom false Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreStateMachine::pushState Dec 27 06:23:11 ms-113 volumio[11948]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:11 ms-113 volumio[11948]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:11 ms-113 volumio[11948]: info: Setting Device type: Raspberry PI Dec 27 06:23:11 ms-113 volumio[11948]: Starting albumart workers Dec 27 06:23:11 ms-113 volumio[11948]: error: updateQueue error: null Dec 27 06:23:12 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:11] [connect] Successful connection Dec 27 06:23:12 ms-113 volumio[11948]: info: Completed loading Core Plugins Dec 27 06:23:12 ms-113 volumio[11948]: info: Preparing to generate the ALSA configuration file Dec 27 06:23:12 ms-113 volumio[11948]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreStateMachine::pushState Dec 27 06:23:12 ms-113 volumio[11948]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:12 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766816591 101 Dec 27 06:23:12 ms-113 volumio[11948]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Dec 27 06:23:12 ms-113 sudo[11977]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:12 ms-113 volumio[11948]: info: Asound.conf file unchanged, so no further update is needed Dec 27 06:23:12 ms-113 volumio[11948]: info: Output device has changed, restarting MPD Dec 27 06:23:12 ms-113 sudo[12069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:12 ms-113 sudo[12069]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:12 ms-113 volumio[11948]: info: Output device has changed, restarting Shairport Sync Dec 27 06:23:12 ms-113 sudo[12069]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:12 ms-113 sudo[12072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:12 ms-113 sudo[12072]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:12 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:12 ms-113 volumio[11948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:12 ms-113 volumio[11948]: info: ___________ START PLUGINS ___________ Dec 27 06:23:12 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:12 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:12 ms-113 volumio[11948]: info: [1766816592700] CoreMusicLibrary::Adding element Media Servers Dec 27 06:23:12 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:12 ms-113 volumio[11948]: Cannot find translation for sourceMedia Servers Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:12 ms-113 volumio[11948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:12 ms-113 volumio[11948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:12 ms-113 volumio[11948]: info: [1766816592807] CoreMusicLibrary::Adding element Last_100 Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:12 ms-113 volumio[11948]: Cannot find translation for sourceMedia Servers Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:12 ms-113 volumio[11948]: info: [1766816592810] CoreMusicLibrary::Adding element Webradio Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:12 ms-113 volumio[11948]: Cannot find translation for sourceMedia Servers Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:23:12 ms-113 volumio[11948]: info: Volumio Calling Home Dec 27 06:23:12 ms-113 volumio[11948]: info: Completed starting Core Plugins Dec 27 06:23:12 ms-113 volumio[11948]: info: ------------------------------------------- Dec 27 06:23:12 ms-113 volumio[11948]: info: ----- MyVolumio plugins startup ---- Dec 27 06:23:12 ms-113 volumio[11948]: info: ------------------------------------------- Dec 27 06:23:12 ms-113 volumio[11948]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 06:23:12 ms-113 volumio[11948]: info: Discovery: adding 35aff5c4-1eea-4ef6-9ca9-12e5bbf60d42 Dec 27 06:23:12 ms-113 volumio[11948]: info: mDNS: Found device MS-113 Dec 27 06:23:12 ms-113 volumio[11948]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:12 ms-113 volumio[11948]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:12 ms-113 volumio[11948]: info: MPD Permissions set Dec 27 06:23:12 ms-113 volumio[11948]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:12 ms-113 volumio[11948]: Error: connect EHOSTUNREACH 10.10.34.36:443 Dec 27 06:23:12 ms-113 volumio[11948]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 06:23:12 ms-113 volumio[11948]: errno: -113, Dec 27 06:23:12 ms-113 volumio[11948]: code: 'EHOSTUNREACH', Dec 27 06:23:12 ms-113 volumio[11948]: syscall: 'connect', Dec 27 06:23:12 ms-113 volumio[11948]: address: '10.10.34.36', Dec 27 06:23:12 ms-113 volumio[11948]: port: 443 Dec 27 06:23:12 ms-113 volumio[11948]: } Dec 27 06:23:12 ms-113 volumio[11948]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:13 ms-113 sudo[12100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:22 Dec 27 06:23:13 ms-113 sudo[12100]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:13 ms-113 mpd[12085]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:13 ms-113 sudo[12100]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:13 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 27 06:23:13 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 27 06:23:13 ms-113 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 27 06:23:13 ms-113 sudo[12072]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:13 ms-113 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 27 06:23:13 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:23:13 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:13 ms-113 mpd[12085]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:13 ms-113 mpd[12085]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:13 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:13 ms-113 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 06:23:13 ms-113 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18. Dec 27 06:23:13 ms-113 systemd[1]: Stopped Volumio Backend Module. Dec 27 06:23:13 ms-113 systemd[1]: Started Volumio Backend Module. Dec 27 06:23:13 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:23:13 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:14 ms-113 volumio[12112]: info: ------------------------------------------- Dec 27 06:23:14 ms-113 volumio[12112]: info: ----- Volumio3 ---- Dec 27 06:23:14 ms-113 volumio[12112]: info: ------------------------------------------- Dec 27 06:23:14 ms-113 volumio[12112]: info: ----- System startup ---- Dec 27 06:23:14 ms-113 volumio[12112]: info: ------------------------------------------- Dec 27 06:23:15 ms-113 volumio[12112]: info: MYVOLUMIO Environment detected Dec 27 06:23:15 ms-113 volumio[12112]: info: Plugin folders cleanup Dec 27 06:23:15 ms-113 volumio[12112]: info: Scanning into folder /volumio/app/plugins/ Dec 27 06:23:15 ms-113 volumio[12112]: info: Scanning category audio_interface Dec 27 06:23:15 ms-113 volumio[12112]: info: Scanning category miscellanea Dec 27 06:23:15 ms-113 volumio[12112]: info: Scanning category music_service Dec 27 06:23:15 ms-113 volumio[12112]: info: Scanning category plugins.json Dec 27 06:23:15 ms-113 volumio[12112]: info: Scanning category system_controller Dec 27 06:23:15 ms-113 volumio[12112]: info: Scanning category user_interface Dec 27 06:23:15 ms-113 volumio[12112]: info: Scanning into folder /data/plugins/ Dec 27 06:23:15 ms-113 volumio[12112]: info: Plugin folders cleanup completed Dec 27 06:23:15 ms-113 volumio[12112]: info: ------------------------------------------- Dec 27 06:23:15 ms-113 volumio[12112]: info: ----- Core plugins startup ---- Dec 27 06:23:15 ms-113 volumio[12112]: info: ------------------------------------------- Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 06:23:15 ms-113 volumio[12112]: info: Adding plugin upnp to MyMusic Plugins Dec 27 06:23:15 ms-113 volumio[12112]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 06:23:15 ms-113 volumio[12112]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugins from folder /data/plugins/ Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "system"... Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "appearance"... Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "network"... Dec 27 06:23:15 ms-113 volumio[12112]: info: Refreshing Cached IP Addresses Dec 27 06:23:15 ms-113 sudo[12136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:23:15 ms-113 sudo[12136]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:15 ms-113 sudo[12136]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:15 ms-113 sudo[12138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:23:15 ms-113 sudo[12138]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "services"... Dec 27 06:23:15 ms-113 sudo[12138]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "alsa_controller"... Dec 27 06:23:15 ms-113 sudo[12142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 06:23:15 ms-113 sudo[12142]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "wizard"... Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "volumio_command_line_client"... Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "upnp"... Dec 27 06:23:15 ms-113 volumio[12112]: info: [1766816595797] Starting Upmpd Daemon Dec 27 06:23:15 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "my_music"... Dec 27 06:23:15 ms-113 volumio[12112]: info: Loading plugin "mpd"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Creating MPD Configuration file Dec 27 06:23:16 ms-113 sudo[12149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:16 ms-113 sudo[12149]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "upnp_browser"... Dec 27 06:23:16 ms-113 sudo[12149]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:16 ms-113 sudo[12152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:16 ms-113 sudo[12152]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:16 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:16 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:16 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:16 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "networkfs"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Cannot mount NAS ms-113-kish at system boot, trial number 1 ,retrying in 5 seconds Dec 27 06:23:16 ms-113 volumio[12112]: info: Starting Udev Watcher for removable devices Dec 27 06:23:16 ms-113 volumio[12112]: info: Ignoring mount for partition: boot Dec 27 06:23:16 ms-113 volumio[12112]: info: Ignoring mount for partition: volumio Dec 27 06:23:16 ms-113 volumio[12112]: info: Ignoring mount for partition: volumio_data Dec 27 06:23:16 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "alarm-clock"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "airplay_emulation"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Starting Shairport Sync Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "last_100"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "webradio"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "i2s_dacs"... Dec 27 06:23:16 ms-113 volumio[12112]: info: I2S DAC not set, start Auto-detection Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "volumiodiscovery"... Dec 27 06:23:16 ms-113 mpd[12156]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:16 ms-113 volumio[12112]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:16 ms-113 volumio[12112]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:16 ms-113 volumio[12112]: *** WARNING *** For more information see Dec 27 06:23:16 ms-113 node[12112]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:16 ms-113 volumio[12112]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:16 ms-113 volumio[12112]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:16 ms-113 volumio[12112]: *** WARNING *** For more information see Dec 27 06:23:16 ms-113 node[12112]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:16 ms-113 node[12112]: *** WARNING *** For more information see Dec 27 06:23:16 ms-113 node[12112]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:16 ms-113 node[12112]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:16 ms-113 node[12112]: *** WARNING *** For more information see Dec 27 06:23:16 ms-113 volumio[12112]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 06:23:16 ms-113 volumio[12112]: info: Discovery: Started advertising with name: MS-113 Dec 27 06:23:16 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "outputs"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "albumart"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Plugin example_plugin is not enabled Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "inputs"... Dec 27 06:23:16 ms-113 volumio[12112]: info: Loading plugin "updater_comm"... Dec 27 06:23:16 ms-113 mpd[12156]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:16 ms-113 mpd[12156]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:16 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:16 ms-113 sudo[12152]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:17 ms-113 volumio[12112]: info: Plugin mpdemulation is not enabled Dec 27 06:23:17 ms-113 volumio[12112]: info: Loading plugin "rest_api"... Dec 27 06:23:17 ms-113 volumio[12112]: info: Loading plugin "websocket"... Dec 27 06:23:17 ms-113 volumio[12112]: info: Loading i18n strings for locale en Dec 27 06:23:17 ms-113 volumio[12112]: Updating browse sources language Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::initPlayerControls Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: Express server listening on port 3000 Dec 27 06:23:17 ms-113 volumio[12112]: [Metrics] WebUI: 2s 784.07ms Dec 27 06:23:17 ms-113 volumio[12112]: Forking 3 albumart workers Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreStateMachine::resetVolumioState Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreStateMachine::getcurrentVolume Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: MPD Permissions set Dec 27 06:23:17 ms-113 volumio[12112]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 1 Dec 27 06:23:17 ms-113 volumio[12112]: info: MPD running with PID12156 Dec 27 06:23:17 ms-113 volumio[12112]: ,establishing connection Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:17 ms-113 volumio[12112]: info: Reloading queue from file Dec 27 06:23:17 ms-113 volumio[12112]: error: updateQueue error: null Dec 27 06:23:17 ms-113 volumio[12112]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 06:23:17 ms-113 volumio[12112]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreStateMachine::pushState Dec 27 06:23:17 ms-113 volumio[12112]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreStateMachine::updateTrackBlock Dec 27 06:23:17 ms-113 volumio[12112]: info: CorePlayQueue::getTrackBlock Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreStateMachine::setRepeat false single undefined Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreStateMachine::pushState Dec 27 06:23:17 ms-113 volumio[12112]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreStateMachine::setRandom false Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreStateMachine::pushState Dec 27 06:23:17 ms-113 volumio[12112]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:17 ms-113 volumio[12112]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:17 ms-113 volumio[12112]: info: Setting Device type: Raspberry PI Dec 27 06:23:17 ms-113 volumio[12112]: Starting albumart workers Dec 27 06:23:17 ms-113 volumio[12112]: Starting albumart workers Dec 27 06:23:17 ms-113 volumio[12112]: Starting albumart workers Dec 27 06:23:17 ms-113 volumio[12112]: error: updateQueue error: null Dec 27 06:23:18 ms-113 volumio[12112]: info: Completed loading Core Plugins Dec 27 06:23:18 ms-113 volumio[12112]: info: Preparing to generate the ALSA configuration file Dec 27 06:23:18 ms-113 volumio[12112]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreStateMachine::pushState Dec 27 06:23:18 ms-113 volumio[12112]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:18 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:18] [connect] Successful connection Dec 27 06:23:18 ms-113 sudo[12142]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:18 ms-113 volumio[12112]: info: Asound.conf file unchanged, so no further update is needed Dec 27 06:23:18 ms-113 volumio[12112]: info: Output device has changed, restarting MPD Dec 27 06:23:18 ms-113 sudo[12233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:18 ms-113 sudo[12233]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:18 ms-113 sudo[12233]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:18 ms-113 volumio[12112]: info: Output device has changed, restarting Shairport Sync Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:18 ms-113 sudo[12236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:18 ms-113 sudo[12236]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:18 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:18 ms-113 volumio[12112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:18 ms-113 volumio[12112]: info: ___________ START PLUGINS ___________ Dec 27 06:23:18 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:18 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:18 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:18 ms-113 volumio[12112]: info: [1766816598732] CoreMusicLibrary::Adding element Media Servers Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:18 ms-113 volumio[12112]: Cannot find translation for sourceMedia Servers Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:18 ms-113 volumio[12112]: info: [1766816598837] CoreMusicLibrary::Adding element Last_100 Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:18 ms-113 volumio[12112]: Cannot find translation for sourceMedia Servers Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:18 ms-113 volumio[12112]: info: [1766816598839] CoreMusicLibrary::Adding element Webradio Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:18 ms-113 volumio[12112]: Cannot find translation for sourceMedia Servers Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:23:18 ms-113 volumio[12112]: info: Volumio Calling Home Dec 27 06:23:18 ms-113 volumio[12112]: info: Completed starting Core Plugins Dec 27 06:23:18 ms-113 volumio[12112]: info: ------------------------------------------- Dec 27 06:23:18 ms-113 volumio[12112]: info: ----- MyVolumio plugins startup ---- Dec 27 06:23:18 ms-113 volumio[12112]: info: ------------------------------------------- Dec 27 06:23:18 ms-113 volumio[12112]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 06:23:18 ms-113 volumio[12112]: info: Discovery: adding 35aff5c4-1eea-4ef6-9ca9-12e5bbf60d42 Dec 27 06:23:18 ms-113 volumio[12112]: info: mDNS: Found device MS-113 Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:18 ms-113 volumio[12112]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:18 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766816598 101 Dec 27 06:23:18 ms-113 volumio[12112]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Dec 27 06:23:18 ms-113 volumio[12112]: info: MPD Permissions set Dec 27 06:23:18 ms-113 volumio[12112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:18 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:19 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:19 ms-113 volumio[12112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:19 ms-113 volumio[12112]: info: Starting Shairport Sync Dec 27 06:23:19 ms-113 volumio[12112]: info: Starting Shairport Sync Dec 27 06:23:19 ms-113 sudo[12258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:19 ms-113 sudo[12258]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:19 ms-113 volumio[12112]: info: Starting Shairport Sync Dec 27 06:23:19 ms-113 sudo[12261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:19 ms-113 sudo[12261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:19 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:19 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:19 ms-113 sudo[12263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:19 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:19 ms-113 sudo[12263]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:19 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:19 ms-113 sudo[12258]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:19 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:19 ms-113 volumio[12112]: info: Shairport-Sync Started Dec 27 06:23:19 ms-113 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 27 06:23:19 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:19 ms-113 volumio[12112]: Error adding Membership: Error: addMembership EINVAL Dec 27 06:23:19 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:19 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:19 ms-113 sudo[12261]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:19 ms-113 volumio[12112]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:19 ms-113 volumio[12112]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:19 ms-113 sudo[12263]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:19 ms-113 volumio[12112]: info: Shairport-Sync Started Dec 27 06:23:19 ms-113 volumio[12112]: info: Shairport-Sync Started Dec 27 06:23:19 ms-113 volumio[12112]: info: Volumio called home Dec 27 06:23:19 ms-113 mpd[12249]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:19 ms-113 mpd[12249]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:19 ms-113 mpd[12249]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:19 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:19 ms-113 sudo[12236]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:19 ms-113 volumio[12112]: error: updateQueue error: null Dec 27 06:23:19 ms-113 volumio[12112]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 4 Dec 27 06:23:21 ms-113 volumio[12112]: info: Cannot mount NAS ms-113-kish at system boot, trial number 2 ,retrying in 5 seconds Dec 27 06:23:23 ms-113 volumio[12112]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:23 ms-113 volumio[12112]: Error: connect EHOSTUNREACH 10.10.34.36:443 Dec 27 06:23:23 ms-113 volumio[12112]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 06:23:23 ms-113 volumio[12112]: errno: -113, Dec 27 06:23:23 ms-113 volumio[12112]: code: 'EHOSTUNREACH', Dec 27 06:23:23 ms-113 volumio[12112]: syscall: 'connect', Dec 27 06:23:23 ms-113 volumio[12112]: address: '10.10.34.36', Dec 27 06:23:23 ms-113 volumio[12112]: port: 443 Dec 27 06:23:23 ms-113 volumio[12112]: } Dec 27 06:23:23 ms-113 volumio[12112]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:23 ms-113 sudo[12281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:22 Dec 27 06:23:23 ms-113 sudo[12281]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:23 ms-113 sudo[12281]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:23 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 27 06:23:23 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 27 06:23:23 ms-113 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 27 06:23:23 ms-113 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 27 06:23:23 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:23:23 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:24 ms-113 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 06:23:24 ms-113 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19. Dec 27 06:23:24 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:23:24 ms-113 systemd[1]: Stopped Volumio Backend Module. Dec 27 06:23:24 ms-113 systemd[1]: Started Volumio Backend Module. Dec 27 06:23:24 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:25 ms-113 volumio[12292]: info: ------------------------------------------- Dec 27 06:23:25 ms-113 volumio[12292]: info: ----- Volumio3 ---- Dec 27 06:23:25 ms-113 volumio[12292]: info: ------------------------------------------- Dec 27 06:23:25 ms-113 volumio[12292]: info: ----- System startup ---- Dec 27 06:23:25 ms-113 volumio[12292]: info: ------------------------------------------- Dec 27 06:23:25 ms-113 ntpd[665]: Soliciting pool server 2a0e:b107:27d0:1::5 Dec 27 06:23:25 ms-113 volumio[12292]: info: MYVOLUMIO Environment detected Dec 27 06:23:25 ms-113 volumio[12292]: info: Plugin folders cleanup Dec 27 06:23:25 ms-113 volumio[12292]: info: Scanning into folder /volumio/app/plugins/ Dec 27 06:23:25 ms-113 volumio[12292]: info: Scanning category audio_interface Dec 27 06:23:25 ms-113 volumio[12292]: info: Scanning category miscellanea Dec 27 06:23:25 ms-113 volumio[12292]: info: Scanning category music_service Dec 27 06:23:25 ms-113 volumio[12292]: info: Scanning category plugins.json Dec 27 06:23:25 ms-113 volumio[12292]: info: Scanning category system_controller Dec 27 06:23:25 ms-113 volumio[12292]: info: Scanning category user_interface Dec 27 06:23:25 ms-113 volumio[12292]: info: Scanning into folder /data/plugins/ Dec 27 06:23:25 ms-113 volumio[12292]: info: Plugin folders cleanup completed Dec 27 06:23:25 ms-113 volumio[12292]: info: ------------------------------------------- Dec 27 06:23:25 ms-113 volumio[12292]: info: ----- Core plugins startup ---- Dec 27 06:23:25 ms-113 volumio[12292]: info: ------------------------------------------- Dec 27 06:23:25 ms-113 volumio[12292]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 06:23:25 ms-113 volumio[12292]: info: Adding plugin upnp to MyMusic Plugins Dec 27 06:23:25 ms-113 volumio[12292]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 06:23:25 ms-113 volumio[12292]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 06:23:25 ms-113 volumio[12292]: info: Loading plugins from folder /data/plugins/ Dec 27 06:23:25 ms-113 volumio[12292]: info: Loading plugin "system"... Dec 27 06:23:25 ms-113 volumio[12292]: info: Loading plugin "appearance"... Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "network"... Dec 27 06:23:26 ms-113 volumio[12292]: info: Refreshing Cached IP Addresses Dec 27 06:23:26 ms-113 sudo[12315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:23:26 ms-113 sudo[12315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:26 ms-113 sudo[12315]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:26 ms-113 sudo[12317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:23:26 ms-113 sudo[12317]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "services"... Dec 27 06:23:26 ms-113 sudo[12317]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "alsa_controller"... Dec 27 06:23:26 ms-113 sudo[12321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 06:23:26 ms-113 sudo[12321]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "wizard"... Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "volumio_command_line_client"... Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "upnp"... Dec 27 06:23:26 ms-113 volumio[12292]: info: [1766816606299] Starting Upmpd Daemon Dec 27 06:23:26 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "my_music"... Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "mpd"... Dec 27 06:23:26 ms-113 volumio[12292]: info: Creating MPD Configuration file Dec 27 06:23:26 ms-113 sudo[12328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:26 ms-113 sudo[12328]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "upnp_browser"... Dec 27 06:23:26 ms-113 sudo[12328]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:26 ms-113 sudo[12330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:26 ms-113 sudo[12330]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:26 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:26 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:26 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:26 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:26 ms-113 volumio[12292]: info: Loading plugin "networkfs"... Dec 27 06:23:26 ms-113 volumio[12292]: info: Cannot mount NAS ms-113-kish at system boot, trial number 1 ,retrying in 5 seconds Dec 27 06:23:26 ms-113 volumio[12292]: info: Starting Udev Watcher for removable devices Dec 27 06:23:27 ms-113 volumio[12292]: info: Ignoring mount for partition: boot Dec 27 06:23:27 ms-113 volumio[12292]: info: Ignoring mount for partition: volumio Dec 27 06:23:27 ms-113 volumio[12292]: info: Ignoring mount for partition: volumio_data Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "alarm-clock"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "airplay_emulation"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Starting Shairport Sync Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "last_100"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "webradio"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "i2s_dacs"... Dec 27 06:23:27 ms-113 volumio[12292]: info: I2S DAC not set, start Auto-detection Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "volumiodiscovery"... Dec 27 06:23:27 ms-113 mpd[12335]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:27 ms-113 volumio[12292]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:27 ms-113 volumio[12292]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:27 ms-113 volumio[12292]: *** WARNING *** For more information see Dec 27 06:23:27 ms-113 node[12292]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:27 ms-113 volumio[12292]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:27 ms-113 volumio[12292]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:27 ms-113 volumio[12292]: *** WARNING *** For more information see Dec 27 06:23:27 ms-113 node[12292]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:27 ms-113 node[12292]: *** WARNING *** For more information see Dec 27 06:23:27 ms-113 node[12292]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:27 ms-113 node[12292]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:27 ms-113 node[12292]: *** WARNING *** For more information see Dec 27 06:23:27 ms-113 volumio[12292]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 06:23:27 ms-113 volumio[12292]: info: Discovery: Started advertising with name: MS-113 Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "outputs"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "albumart"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Plugin example_plugin is not enabled Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "inputs"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "updater_comm"... Dec 27 06:23:27 ms-113 mpd[12335]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:27 ms-113 mpd[12335]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:27 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:27 ms-113 sudo[12330]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:27 ms-113 volumio[12292]: info: Plugin mpdemulation is not enabled Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "rest_api"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading plugin "websocket"... Dec 27 06:23:27 ms-113 volumio[12292]: info: Loading i18n strings for locale en Dec 27 06:23:27 ms-113 volumio[12292]: Updating browse sources language Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::initPlayerControls Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: Express server listening on port 3000 Dec 27 06:23:27 ms-113 volumio[12292]: [Metrics] WebUI: 2s 747.09ms Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreStateMachine::resetVolumioState Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreStateMachine::getcurrentVolume Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: Forking 3 albumart workers Dec 27 06:23:27 ms-113 volumio[12292]: info: MPD Permissions set Dec 27 06:23:27 ms-113 volumio[12292]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 1 Dec 27 06:23:27 ms-113 volumio[12292]: info: MPD running with PID12335 Dec 27 06:23:27 ms-113 volumio[12292]: ,establishing connection Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:27 ms-113 volumio[12292]: info: Reloading queue from file Dec 27 06:23:28 ms-113 volumio[12292]: error: updateQueue error: null Dec 27 06:23:28 ms-113 volumio[12292]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 06:23:28 ms-113 volumio[12292]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreStateMachine::pushState Dec 27 06:23:28 ms-113 volumio[12292]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreStateMachine::updateTrackBlock Dec 27 06:23:28 ms-113 volumio[12292]: info: CorePlayQueue::getTrackBlock Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreStateMachine::setRepeat false single undefined Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreStateMachine::pushState Dec 27 06:23:28 ms-113 volumio[12292]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreStateMachine::setRandom false Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreStateMachine::pushState Dec 27 06:23:28 ms-113 volumio[12292]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:28 ms-113 volumio[12292]: info: Setting Device type: Raspberry PI Dec 27 06:23:28 ms-113 volumio[12292]: Starting albumart workers Dec 27 06:23:28 ms-113 volumio[12292]: error: updateQueue error: null Dec 27 06:23:28 ms-113 volumio[12292]: Starting albumart workers Dec 27 06:23:28 ms-113 volumio[12292]: Starting albumart workers Dec 27 06:23:28 ms-113 volumio[12292]: info: Completed loading Core Plugins Dec 27 06:23:28 ms-113 volumio[12292]: info: Preparing to generate the ALSA configuration file Dec 27 06:23:28 ms-113 volumio[12292]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreStateMachine::pushState Dec 27 06:23:28 ms-113 volumio[12292]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:28 ms-113 volumio[12292]: info: Asound.conf file unchanged, so no further update is needed Dec 27 06:23:28 ms-113 volumio[12292]: info: Output device has changed, restarting MPD Dec 27 06:23:28 ms-113 sudo[12413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:28 ms-113 sudo[12413]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:28 ms-113 sudo[12413]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:28 ms-113 volumio[12292]: info: Output device has changed, restarting Shairport Sync Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:28 ms-113 sudo[12416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:28 ms-113 sudo[12416]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:28 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:28 ms-113 volumio[12292]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:28 ms-113 volumio[12292]: info: ___________ START PLUGINS ___________ Dec 27 06:23:28 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:28] [connect] Successful connection Dec 27 06:23:28 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:28 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:28 ms-113 volumio[12292]: info: [1766816608951] CoreMusicLibrary::Adding element Media Servers Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:28 ms-113 volumio[12292]: Cannot find translation for sourceMedia Servers Dec 27 06:23:28 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:28 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:29 ms-113 volumio[12292]: info: [1766816609060] CoreMusicLibrary::Adding element Last_100 Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:29 ms-113 volumio[12292]: Cannot find translation for sourceMedia Servers Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:29 ms-113 volumio[12292]: info: [1766816609064] CoreMusicLibrary::Adding element Webradio Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:29 ms-113 volumio[12292]: Cannot find translation for sourceMedia Servers Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:23:29 ms-113 volumio[12292]: info: Volumio Calling Home Dec 27 06:23:29 ms-113 sudo[12321]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:29 ms-113 volumio[12292]: info: Completed starting Core Plugins Dec 27 06:23:29 ms-113 volumio[12292]: info: ------------------------------------------- Dec 27 06:23:29 ms-113 volumio[12292]: info: ----- MyVolumio plugins startup ---- Dec 27 06:23:29 ms-113 volumio[12292]: info: ------------------------------------------- Dec 27 06:23:29 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 06:23:29 ms-113 volumio[12292]: info: Discovery: adding 35aff5c4-1eea-4ef6-9ca9-12e5bbf60d42 Dec 27 06:23:29 ms-113 volumio[12292]: info: mDNS: Found device MS-113 Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:29 ms-113 volumio[12292]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:29 ms-113 volumio[12292]: info: MPD Permissions set Dec 27 06:23:29 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766816608 101 Dec 27 06:23:29 ms-113 volumio[12292]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Dec 27 06:23:29 ms-113 volumio[12292]: info: Volumio called home Dec 27 06:23:29 ms-113 volumio[12292]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:29 ms-113 volumio[12292]: info: Starting Shairport Sync Dec 27 06:23:29 ms-113 volumio[12292]: info: Starting Shairport Sync Dec 27 06:23:29 ms-113 mpd[12429]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:29 ms-113 sudo[12438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:29 ms-113 sudo[12438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:29 ms-113 volumio[12292]: info: Starting Shairport Sync Dec 27 06:23:29 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:29 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:29 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:29 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:29 ms-113 sudo[12438]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:29 ms-113 sudo[12441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:29 ms-113 sudo[12441]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:29 ms-113 sudo[12444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:29 ms-113 sudo[12444]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:29 ms-113 volumio[12292]: info: Shairport-Sync Started Dec 27 06:23:29 ms-113 volumio[12292]: Error adding Membership: Error: addMembership EINVAL Dec 27 06:23:29 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:29 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:29 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:29 ms-113 mpd[12429]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:29 ms-113 mpd[12429]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:29 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:29 ms-113 volumio[12292]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:29 ms-113 volumio[12292]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:29 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:29 ms-113 sudo[12416]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:29 ms-113 sudo[12444]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:29 ms-113 sudo[12441]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:29 ms-113 volumio[12292]: info: Shairport-Sync Started Dec 27 06:23:29 ms-113 volumio[12292]: info: Shairport-Sync Started Dec 27 06:23:29 ms-113 volumio[12292]: error: updateQueue error: null Dec 27 06:23:30 ms-113 ntpd[665]: Soliciting pool server 188.68.34.173 Dec 27 06:23:30 ms-113 volumio[12292]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 4 Dec 27 06:23:31 ms-113 volumio[12292]: info: Cannot mount NAS ms-113-kish at system boot, trial number 2 ,retrying in 5 seconds Dec 27 06:23:35 ms-113 volumio[12292]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 5 Dec 27 06:23:35 ms-113 volumio[12292]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:35 ms-113 volumio[12292]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:35 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 06:23:35 ms-113 volumio[12292]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 06:23:35 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:35 ms-113 volumio[12292]: info: Listing playlists Dec 27 06:23:35 ms-113 volumio[12292]: info: CoreCommandRouter::volumioGetQueue Dec 27 06:23:35 ms-113 volumio[12292]: info: CoreStateMachine::getQueue Dec 27 06:23:35 ms-113 volumio[12292]: info: CorePlayQueue::getQueue Dec 27 06:23:36 ms-113 sudo[12456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:23:36 ms-113 sudo[12456]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:36 ms-113 sudo[12456]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:36 ms-113 sudo[12459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:23:36 ms-113 sudo[12459]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:36 ms-113 sudo[12459]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:36 ms-113 sudo[12462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 27 06:23:36 ms-113 sudo[12462]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:36 ms-113 sudo[12462]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:36 ms-113 volumio[12292]: info: Upmpdcli Daemon Started Dec 27 06:23:36 ms-113 volumio[12292]: info: Cannot mount NAS ms-113-kish at system boot, trial number 3 ,retrying in 5 seconds Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 27 06:23:37 ms-113 volumio[12292]: info: Adding plugin bluetooth to MyMusic Plugins Dec 27 06:23:37 ms-113 volumio[12292]: info: Adding plugin multiroom to MyMusic Plugins Dec 27 06:23:37 ms-113 volumio[12292]: info: Adding plugin metavolumio to MyMusic Plugins Dec 27 06:23:37 ms-113 volumio[12292]: info: Adding plugin cd_controller to MyMusic Plugins Dec 27 06:23:37 ms-113 volumio[12292]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 27 06:23:37 ms-113 volumio[12292]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 27 06:23:37 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 27 06:23:38 ms-113 volumio[12292]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 27 06:23:38 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 27 06:23:38 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:38 ms-113 volumio[12292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:38 ms-113 volumio[12292]: info: Starting MyVolumio Remote Streaming Endpoints Dec 27 06:23:38 ms-113 volumio[12292]: info: MyVolumio not started Dec 27 06:23:38 ms-113 volumio[12292]: info: Initializing device activation check Dec 27 06:23:38 ms-113 volumio[12292]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 27 06:23:38 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 27 06:23:38 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 27 06:23:38 ms-113 volumio[12292]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 27 06:23:38 ms-113 volumio[12292]: info: Streaming services startup Dec 27 06:23:38 ms-113 volumio[12292]: info: Starting Streaming Daemon Dec 27 06:23:38 ms-113 sudo[12465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 06:23:38 ms-113 sudo[12465]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:38 ms-113 systemd[1]: Stopping Volumio Streaming Daemon... Dec 27 06:23:38 ms-113 systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Dec 27 06:23:38 ms-113 systemd[1]: volumio-streaming-daemon.service: Succeeded. Dec 27 06:23:38 ms-113 systemd[1]: Stopped Volumio Streaming Daemon. Dec 27 06:23:38 ms-113 systemd[1]: Started Volumio Streaming Daemon. Dec 27 06:23:38 ms-113 sudo[12465]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:38 ms-113 volumio-streaming-daemon[12469]: ############################ Dec 27 06:23:38 ms-113 volumio-streaming-daemon[12469]: # Volumio Streaming Daemon # Dec 27 06:23:38 ms-113 volumio-streaming-daemon[12469]: # Running on port 7777 # Dec 27 06:23:38 ms-113 volumio-streaming-daemon[12469]: ############################ Dec 27 06:23:38 ms-113 volumio-streaming-daemon[12469]: Environment: production Dec 27 06:23:38 ms-113 volumio[12292]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:38 ms-113 volumio[12292]: Error: connect EHOSTUNREACH 10.10.34.36:443 Dec 27 06:23:38 ms-113 volumio[12292]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 06:23:38 ms-113 volumio[12292]: errno: -113, Dec 27 06:23:38 ms-113 volumio[12292]: code: 'EHOSTUNREACH', Dec 27 06:23:38 ms-113 volumio[12292]: syscall: 'connect', Dec 27 06:23:38 ms-113 volumio[12292]: address: '10.10.34.36', Dec 27 06:23:38 ms-113 volumio[12292]: port: 443 Dec 27 06:23:38 ms-113 volumio[12292]: } Dec 27 06:23:38 ms-113 volumio[12292]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:39 ms-113 sudo[12486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:22 Dec 27 06:23:39 ms-113 sudo[12486]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:39 ms-113 sudo[12486]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:39 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:39] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 27 06:23:39 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:39] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 27 06:23:39 ms-113 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 27 06:23:39 ms-113 systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 27 06:23:39 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:23:39 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:39 ms-113 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 06:23:39 ms-113 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20. Dec 27 06:23:39 ms-113 systemd[1]: Started dynamicswap service. Dec 27 06:23:39 ms-113 systemd[1]: Stopped Volumio Backend Module. Dec 27 06:23:39 ms-113 systemd[1]: Started Volumio Backend Module. Dec 27 06:23:39 ms-113 systemd[1]: dynamicswap.service: Succeeded. Dec 27 06:23:40 ms-113 volumio[12497]: info: ------------------------------------------- Dec 27 06:23:40 ms-113 volumio[12497]: info: ----- Volumio3 ---- Dec 27 06:23:40 ms-113 volumio[12497]: info: ------------------------------------------- Dec 27 06:23:40 ms-113 volumio[12497]: info: ----- System startup ---- Dec 27 06:23:40 ms-113 volumio[12497]: info: ------------------------------------------- Dec 27 06:23:40 ms-113 volumio[12497]: info: MYVOLUMIO Environment detected Dec 27 06:23:40 ms-113 volumio[12497]: info: Plugin folders cleanup Dec 27 06:23:40 ms-113 volumio[12497]: info: Scanning into folder /volumio/app/plugins/ Dec 27 06:23:40 ms-113 volumio[12497]: info: Scanning category audio_interface Dec 27 06:23:40 ms-113 volumio[12497]: info: Scanning category miscellanea Dec 27 06:23:40 ms-113 volumio[12497]: info: Scanning category music_service Dec 27 06:23:40 ms-113 volumio[12497]: info: Scanning category plugins.json Dec 27 06:23:40 ms-113 volumio[12497]: info: Scanning category system_controller Dec 27 06:23:40 ms-113 volumio[12497]: info: Scanning category user_interface Dec 27 06:23:40 ms-113 volumio[12497]: info: Scanning into folder /data/plugins/ Dec 27 06:23:40 ms-113 volumio[12497]: info: Plugin folders cleanup completed Dec 27 06:23:40 ms-113 volumio[12497]: info: ------------------------------------------- Dec 27 06:23:40 ms-113 volumio[12497]: info: ----- Core plugins startup ---- Dec 27 06:23:40 ms-113 volumio[12497]: info: ------------------------------------------- Dec 27 06:23:40 ms-113 volumio[12497]: info: Loading plugins from folder /volumio/app/plugins/ Dec 27 06:23:40 ms-113 volumio[12497]: info: Adding plugin upnp to MyMusic Plugins Dec 27 06:23:40 ms-113 volumio[12497]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 27 06:23:40 ms-113 volumio[12497]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 27 06:23:40 ms-113 volumio[12497]: info: Loading plugins from folder /data/plugins/ Dec 27 06:23:40 ms-113 volumio[12497]: info: Loading plugin "system"... Dec 27 06:23:40 ms-113 volumio[12497]: info: Loading plugin "appearance"... Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "network"... Dec 27 06:23:41 ms-113 volumio[12497]: info: Refreshing Cached IP Addresses Dec 27 06:23:41 ms-113 sudo[12520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 06:23:41 ms-113 sudo[12520]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:41 ms-113 sudo[12520]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:41 ms-113 sudo[12523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "services"... Dec 27 06:23:41 ms-113 sudo[12523]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "alsa_controller"... Dec 27 06:23:41 ms-113 sudo[12523]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:41 ms-113 sudo[12525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 27 06:23:41 ms-113 sudo[12525]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "wizard"... Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "volumio_command_line_client"... Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "upnp"... Dec 27 06:23:41 ms-113 volumio[12497]: info: [1766816621553] Starting Upmpd Daemon Dec 27 06:23:41 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "my_music"... Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "mpd"... Dec 27 06:23:41 ms-113 volumio[12497]: info: Creating MPD Configuration file Dec 27 06:23:41 ms-113 sudo[12533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:41 ms-113 sudo[12533]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:41 ms-113 volumio[12497]: info: Loading plugin "upnp_browser"... Dec 27 06:23:41 ms-113 sudo[12533]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:41 ms-113 sudo[12536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:41 ms-113 sudo[12536]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:41 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:41 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:41 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:41 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "networkfs"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Cannot mount NAS ms-113-kish at system boot, trial number 1 ,retrying in 5 seconds Dec 27 06:23:42 ms-113 volumio[12497]: info: Starting Udev Watcher for removable devices Dec 27 06:23:42 ms-113 volumio[12497]: info: Ignoring mount for partition: boot Dec 27 06:23:42 ms-113 volumio[12497]: info: Ignoring mount for partition: volumio Dec 27 06:23:42 ms-113 volumio[12497]: info: Ignoring mount for partition: volumio_data Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "alarm-clock"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "airplay_emulation"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Starting Shairport Sync Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "last_100"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "webradio"... Dec 27 06:23:42 ms-113 mpd[12540]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "i2s_dacs"... Dec 27 06:23:42 ms-113 volumio[12497]: info: I2S DAC not set, start Auto-detection Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "volumiodiscovery"... Dec 27 06:23:42 ms-113 volumio[12497]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:42 ms-113 volumio[12497]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:42 ms-113 volumio[12497]: *** WARNING *** For more information see Dec 27 06:23:42 ms-113 node[12497]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:42 ms-113 volumio[12497]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:42 ms-113 volumio[12497]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:42 ms-113 volumio[12497]: *** WARNING *** For more information see Dec 27 06:23:42 ms-113 node[12497]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:42 ms-113 node[12497]: *** WARNING *** For more information see Dec 27 06:23:42 ms-113 node[12497]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 06:23:42 ms-113 node[12497]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 06:23:42 ms-113 node[12497]: *** WARNING *** For more information see Dec 27 06:23:42 ms-113 volumio[12497]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 06:23:42 ms-113 volumio[12497]: info: Discovery: Started advertising with name: MS-113 Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "outputs"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "albumart"... Dec 27 06:23:42 ms-113 mpd[12540]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:42 ms-113 mpd[12540]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:42 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:42 ms-113 sudo[12536]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:42 ms-113 volumio[12497]: info: Plugin example_plugin is not enabled Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "inputs"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "updater_comm"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Plugin mpdemulation is not enabled Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "rest_api"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading plugin "websocket"... Dec 27 06:23:42 ms-113 volumio[12497]: info: Loading i18n strings for locale en Dec 27 06:23:42 ms-113 volumio[12497]: Updating browse sources language Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::initPlayerControls Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: Express server listening on port 3000 Dec 27 06:23:42 ms-113 volumio[12497]: [Metrics] WebUI: 2s 781.56ms Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreStateMachine::resetVolumioState Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreStateMachine::getcurrentVolume Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:42 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:42 ms-113 volumio[12497]: Forking 3 albumart workers Dec 27 06:23:42 ms-113 volumio[12497]: info: MPD Permissions set Dec 27 06:23:43 ms-113 volumio[12497]: info: MPD running with PID12540 Dec 27 06:23:43 ms-113 volumio[12497]: ,establishing connection Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:43 ms-113 volumio[12497]: info: Reloading queue from file Dec 27 06:23:43 ms-113 volumio[12497]: error: updateQueue error: null Dec 27 06:23:43 ms-113 volumio[12497]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 06:23:43 ms-113 volumio[12497]: Starting albumart workers Dec 27 06:23:43 ms-113 volumio[12497]: Starting albumart workers Dec 27 06:23:43 ms-113 volumio[12497]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreStateMachine::pushState Dec 27 06:23:43 ms-113 volumio[12497]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreStateMachine::updateTrackBlock Dec 27 06:23:43 ms-113 volumio[12497]: info: CorePlayQueue::getTrackBlock Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 06:23:43 ms-113 volumio[12497]: Starting albumart workers Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreStateMachine::setRepeat false single undefined Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreStateMachine::pushState Dec 27 06:23:43 ms-113 volumio[12497]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreStateMachine::setRandom false Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreStateMachine::pushState Dec 27 06:23:43 ms-113 volumio[12497]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 06:23:43 ms-113 volumio[12497]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:43 ms-113 volumio[12497]: info: Setting Device type: Raspberry PI Dec 27 06:23:43 ms-113 volumio[12497]: error: updateQueue error: null Dec 27 06:23:44 ms-113 volumio[12497]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 1 Dec 27 06:23:44 ms-113 volumio[12497]: verbose: New Socket.io Connection to 10.113.2.50 from 10.113.2.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Total Clients: 2 Dec 27 06:23:44 ms-113 volumio[12497]: info: Completed loading Core Plugins Dec 27 06:23:44 ms-113 volumio[12497]: info: Preparing to generate the ALSA configuration file Dec 27 06:23:44 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:44] [connect] Successful connection Dec 27 06:23:44 ms-113 volumio[12497]: info: VolumeController:: Volume=30 Mute =false Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreStateMachine::pushState Dec 27 06:23:44 ms-113 volumio[12497]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::volumioPushState Dec 27 06:23:44 ms-113 sudo[12525]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:44 ms-113 volumio-remote-updater[491]: [2025-12-27 06:23:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766816624 101 Dec 27 06:23:44 ms-113 volumio[12497]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Dec 27 06:23:44 ms-113 volumio[12497]: info: Asound.conf file unchanged, so no further update is needed Dec 27 06:23:44 ms-113 volumio[12497]: info: Output device has changed, restarting MPD Dec 27 06:23:44 ms-113 sudo[12617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 06:23:44 ms-113 sudo[12617]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:44 ms-113 sudo[12617]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:44 ms-113 volumio[12497]: info: Output device has changed, restarting Shairport Sync Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:44 ms-113 sudo[12620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 06:23:44 ms-113 sudo[12620]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:44 ms-113 systemd[1]: Stopping Music Player Daemon... Dec 27 06:23:44 ms-113 volumio[12497]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:44 ms-113 volumio[12497]: info: ___________ START PLUGINS ___________ Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:44 ms-113 volumio[12497]: info: [1766816624650] CoreMusicLibrary::Adding element Media Servers Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:44 ms-113 volumio[12497]: Cannot find translation for sourceMedia Servers Dec 27 06:23:44 ms-113 systemd[1]: mpd.service: Succeeded. Dec 27 06:23:44 ms-113 systemd[1]: Stopped Music Player Daemon. Dec 27 06:23:44 ms-113 systemd[1]: Starting Music Player Daemon... Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:44 ms-113 volumio[12497]: info: [1766816624751] CoreMusicLibrary::Adding element Last_100 Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:44 ms-113 volumio[12497]: Cannot find translation for sourceMedia Servers Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 06:23:44 ms-113 volumio[12497]: info: [1766816624755] CoreMusicLibrary::Adding element Webradio Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 06:23:44 ms-113 volumio[12497]: Cannot find translation for sourceMedia Servers Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 06:23:44 ms-113 volumio[12497]: info: Volumio Calling Home Dec 27 06:23:44 ms-113 volumio[12497]: info: Completed starting Core Plugins Dec 27 06:23:44 ms-113 volumio[12497]: info: ------------------------------------------- Dec 27 06:23:44 ms-113 volumio[12497]: info: ----- MyVolumio plugins startup ---- Dec 27 06:23:44 ms-113 volumio[12497]: info: ------------------------------------------- Dec 27 06:23:44 ms-113 volumio[12497]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 06:23:44 ms-113 volumio[12497]: info: Discovery: adding 35aff5c4-1eea-4ef6-9ca9-12e5bbf60d42 Dec 27 06:23:44 ms-113 volumio[12497]: info: mDNS: Found device MS-113 Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:44 ms-113 volumio[12497]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:44 ms-113 volumio[12497]: info: MPD Permissions set Dec 27 06:23:44 ms-113 volumio[12497]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 06:23:44 ms-113 volumio[12497]: info: Starting Shairport Sync Dec 27 06:23:44 ms-113 volumio[12497]: info: Starting Shairport Sync Dec 27 06:23:44 ms-113 sudo[12642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:44 ms-113 sudo[12642]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:44 ms-113 volumio[12497]: info: Starting Shairport Sync Dec 27 06:23:45 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:45 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:45 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:45 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:45 ms-113 sudo[12645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:45 ms-113 sudo[12645]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:45 ms-113 sudo[12642]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:45 ms-113 sudo[12647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 06:23:45 ms-113 sudo[12647]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 06:23:45 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:45 ms-113 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 27 06:23:45 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:45 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:45 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:45 ms-113 sudo[12645]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:45 ms-113 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 06:23:45 ms-113 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 27 06:23:45 ms-113 systemd[1]: shairport-sync.service: Succeeded. Dec 27 06:23:45 ms-113 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:45 ms-113 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 06:23:45 ms-113 volumio[12497]: info: Shairport-Sync Started Dec 27 06:23:45 ms-113 volumio[12497]: Error adding Membership: Error: addMembership EINVAL Dec 27 06:23:45 ms-113 volumio[12497]: info: Shairport-Sync Started Dec 27 06:23:45 ms-113 volumio[12497]: info: Volumio called home Dec 27 06:23:45 ms-113 sudo[12647]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:45 ms-113 volumio[12497]: info: CoreCommandRouter::volumioGetState Dec 27 06:23:45 ms-113 volumio[12497]: info: CorePlayQueue::getTrack 0 Dec 27 06:23:45 ms-113 volumio[12497]: info: Shairport-Sync Started Dec 27 06:23:45 ms-113 volumio[12497]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:45 ms-113 volumio[12497]: TypeError: Cannot read property 'slice' of null Dec 27 06:23:45 ms-113 volumio[12497]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:714:41) Dec 27 06:23:45 ms-113 volumio[12497]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:724:7) Dec 27 06:23:45 ms-113 volumio[12497]: at Object.onceWrapper (events.js:422:26) Dec 27 06:23:45 ms-113 volumio[12497]: at ClientRequest.emit (events.js:315:20) Dec 27 06:23:45 ms-113 volumio[12497]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Dec 27 06:23:45 ms-113 volumio[12497]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Dec 27 06:23:45 ms-113 volumio[12497]: at TLSSocket.socketOnData (_http_client.js:509:22) Dec 27 06:23:45 ms-113 volumio[12497]: at TLSSocket.emit (events.js:315:20) Dec 27 06:23:45 ms-113 volumio[12497]: at addChunk (internal/streams/readable.js:309:12) Dec 27 06:23:45 ms-113 volumio[12497]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 27 06:23:45 ms-113 volumio[12497]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Dec 27 06:23:45 ms-113 volumio[12497]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 27 06:23:45 ms-113 volumio[12497]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 06:23:45 ms-113 mpd[12635]: Dec 27 06:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 06:23:45 ms-113 mpd[12635]: Dec 27 06:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 27 06:23:45 ms-113 mpd[12635]: Dec 27 06:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 27 06:23:45 ms-113 systemd[1]: Started Music Player Daemon. Dec 27 06:23:45 ms-113 sudo[12620]: pam_unix(sudo:session): session closed for user root Dec 27 06:23:45 ms-113 sudo[12666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 06:22 Dec 27 06:23:45 ms-113 sudo[12666]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"