-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Wed 2025-06-18 13:03:06 UTC. -- Jun 18 13:02:00 volumio volumio[2882]: info: Discovery: Started advertising with name: Volumio Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:00 volumio volumio[2882]: info: Loading plugin "outputs"... Jun 18 13:02:00 volumio volumio[2882]: info: Loading plugin "albumart"... Jun 18 13:02:00 volumio volumio[2882]: info: Plugin example_plugin is not enabled Jun 18 13:02:00 volumio volumio[2882]: info: Loading plugin "inputs"... Jun 18 13:02:00 volumio volumio[2882]: info: Loading plugin "updater_comm"... Jun 18 13:02:00 volumio volumio[2882]: info: Plugin mpdemulation is not enabled Jun 18 13:02:00 volumio volumio[2882]: info: Loading plugin "rest_api"... Jun 18 13:02:00 volumio volumio[2882]: Forking 3 albumart workers Jun 18 13:02:00 volumio volumio[2882]: info: Loading plugin "websocket"... Jun 18 13:02:00 volumio volumio[2882]: info: Starting Socket.io Server version 2.3.0 Jun 18 13:02:00 volumio volumio[2882]: info: Loading i18n strings for locale zh Jun 18 13:02:00 volumio volumio[2882]: Updating browse sources language Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::initPlayerControls Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: Express server listening on port 3000 Jun 18 13:02:00 volumio volumio[2882]: [Metrics] WebUI: 1s 983.36ms Jun 18 13:02:00 volumio volumio[2882]: info: Setting Device type: x86 Jun 18 13:02:00 volumio volumio[2882]: info: CoreStateMachine::resetVolumioState Jun 18 13:02:00 volumio volumio[2882]: info: CoreStateMachine::getcurrentVolume Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:00 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:00 volumio volumio[2882]: Starting albumart workers Jun 18 13:02:00 volumio volumio[2882]: Starting albumart workers Jun 18 13:02:00 volumio volumio[2882]: Starting albumart workers Jun 18 13:02:00 volumio volumio[2882]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 18 13:02:00 volumio volumio[2882]: info: Completed loading Core Plugins Jun 18 13:02:00 volumio volumio[2882]: info: Preparing to generate the ALSA configuration file Jun 18 13:02:00 volumio volumio[2882]: info: Volumio Network Manager: Network status updated: 2 Jun 18 13:02:00 volumio volumio[2882]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 18 13:02:01 volumio sudo[2923]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio volumio[2882]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:01 volumio volumio[2882]: info: CoreStateMachine::pushState Jun 18 13:02:01 volumio volumio[2882]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:01 volumio volumio[2882]: info: CoreStateMachine::updateTrackBlock Jun 18 13:02:01 volumio volumio[2882]: info: CorePlayQueue::getTrackBlock Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:01 volumio volumio[2882]: info: Asound.conf file unchanged, so no further update is needed Jun 18 13:02:01 volumio volumio[2882]: info: Output device has changed, restarting MPD Jun 18 13:02:01 volumio sudo[3006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:01 volumio sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:01 volumio volumio[2882]: info: Output device has changed, restarting Shairport Sync Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:01 volumio sudo[3006]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio sudo[3009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:01 volumio sudo[3009]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:01 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 13:02:01 volumio volumio[2882]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:01 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:01 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:01 volumio volumio[2882]: info: ___________ START PLUGINS ___________ Jun 18 13:02:01 volumio volumio[2882]: info: ControllerMpd::onStart: Initializing MPD Jun 18 13:02:01 volumio volumio[2882]: info: Creating MPD Configuration file Jun 18 13:02:01 volumio sudo[3016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:01 volumio sudo[3016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:01 volumio volumio[2882]: info: [1750251721336] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:01 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:01 volumio sudo[3016]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio sudo[3018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:01 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:01 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 13:02:01 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:01 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:01 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:01 volumio volumio[2882]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:01 volumio volumio[2882]: info: [1750251721379] CoreMusicLibrary::Adding element Last_100 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:01 volumio volumio[2882]: info: [1750251721381] CoreMusicLibrary::Adding element Webradio Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:01 volumio volumio[2882]: info: Initializing BBC Radios Jun 18 13:02:01 volumio sudo[3024]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 13:02:01 volumio sudo[3024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:01 volumio sudo[3024]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: Volumio Calling Home Jun 18 13:02:01 volumio volumio[2882]: info: CoreStateMachine::setRepeat null single undefined Jun 18 13:02:01 volumio volumio[2882]: info: CoreStateMachine::pushState Jun 18 13:02:01 volumio volumio[2882]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:01 volumio volumio[2882]: info: CoreStateMachine::setRandom null Jun 18 13:02:01 volumio volumio[2882]: info: CoreStateMachine::pushState Jun 18 13:02:01 volumio volumio[2882]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:01 volumio volumio[2882]: info: MPD Permissions set Jun 18 13:02:01 volumio volumio[2882]: info: MPD Permissions set Jun 18 13:02:01 volumio volumio[2882]: info: Reloading queue from file Jun 18 13:02:01 volumio volumio[2882]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:01 volumio volumio[2882]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:01 volumio volumio[2882]: info: CoreStateMachine::pushState Jun 18 13:02:01 volumio volumio[2882]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:01 volumio volumio[2882]: info: Starting Shairport Sync Jun 18 13:02:01 volumio volumio[2882]: info: Starting Shairport Sync Jun 18 13:02:01 volumio sudo[3058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:01 volumio volumio[2882]: info: Starting Shairport Sync Jun 18 13:02:01 volumio sudo[3058]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:01 volumio sudo[3060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:01 volumio sudo[3060]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:01 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:01 volumio volumio[2882]: info: Discovery: adding a81651f1-8cc0-4310-a134-18e897fb72eb Jun 18 13:02:01 volumio volumio[2882]: info: Discovery: Found device Volumio Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:01 volumio volumio[2882]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:01 volumio sudo[3064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:01 volumio sudo[3064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:01 volumio volumio[2882]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:01 volumio volumio[2882]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:01 volumio sudo[3058]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:01 volumio volumio[2882]: info: Shairport-Sync Started Jun 18 13:02:01 volumio volumio[2882]: Error adding Membership: Error: addMembership EINVAL Jun 18 13:02:01 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:01 volumio sudo[3060]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio sudo[3064]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio volumio[2882]: info: Shairport-Sync Started Jun 18 13:02:01 volumio volumio[2882]: info: Shairport-Sync Started Jun 18 13:02:01 volumio mpd[3031]: Jun 18 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 13:02:01 volumio systemd[1]: Started Music Player Daemon. Jun 18 13:02:01 volumio sudo[3009]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio sudo[3018]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:01 volumio volumio[2882]: info: Completed starting Core Plugins Jun 18 13:02:01 volumio volumio[2882]: info: ------------------------------------------- Jun 18 13:02:01 volumio volumio[2882]: info: ----- MyVolumio plugins startup ---- Jun 18 13:02:01 volumio volumio[2882]: info: ------------------------------------------- Jun 18 13:02:01 volumio volumio[2882]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 13:02:01 volumio volumio[2882]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:02:01 volumio volumio[2882]: assert.ok(self.idling) Jun 18 13:02:01 volumio volumio[2882]: error: The expression evaluated to a falsy value: Jun 18 13:02:01 volumio volumio[2882]: assert.ok(self.idling) Jun 18 13:02:01 volumio volumio[2882]: info: MPD running with PID3031 Jun 18 13:02:01 volumio volumio[2882]: ,establishing connection Jun 18 13:02:01 volumio volumio[2882]: error: updateQueue error: null Jun 18 13:02:01 volumio volumio[2882]: error: updateQueue error: null Jun 18 13:02:01 volumio volumio[2882]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 13:02:01 volumio volumio[2882]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 18 13:02:01 volumio volumio[2882]: info: Volumio called home Jun 18 13:02:02 volumio volumio-remote-updater[829]: [2025-06-18 13:02:02] [connect] Successful connection Jun 18 13:02:02 volumio volumio-remote-updater[829]: [2025-06-18 13:02:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750251722 101 Jun 18 13:02:02 volumio volumio[2882]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jun 18 13:02:03 volumio volumio[2882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:03 volumio volumio[2882]: Error: connect ECONNREFUSED 31.13.69.245:80 Jun 18 13:02:03 volumio volumio[2882]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 18 13:02:03 volumio volumio[2882]: errno: -111, Jun 18 13:02:03 volumio volumio[2882]: code: 'ECONNREFUSED', Jun 18 13:02:03 volumio volumio[2882]: syscall: 'connect', Jun 18 13:02:03 volumio volumio[2882]: address: '31.13.69.245', Jun 18 13:02:03 volumio volumio[2882]: port: 80 Jun 18 13:02:03 volumio volumio[2882]: } Jun 18 13:02:03 volumio volumio[2882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:03 volumio sudo[3086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 13:01 Jun 18 13:02:03 volumio sudo[3086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:03 volumio sudo[3086]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:06 volumio volumio-remote-updater[829]: [2025-06-18 13:02:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 13:02:06 volumio volumio-remote-updater[829]: [2025-06-18 13:02:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 13:02:06 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 18 13:02:06 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 18 13:02:06 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:06 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:06 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 13:02:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jun 18 13:02:06 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 13:02:06 volumio systemd[1]: Started Volumio Backend Module. Jun 18 13:02:06 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:06 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:07 volumio volumio[3102]: info: ------------------------------------------- Jun 18 13:02:07 volumio volumio[3102]: info: ----- Volumio3 ---- Jun 18 13:02:07 volumio volumio[3102]: info: ------------------------------------------- Jun 18 13:02:07 volumio volumio[3102]: info: ----- System startup ---- Jun 18 13:02:07 volumio volumio[3102]: info: ------------------------------------------- Jun 18 13:02:07 volumio volumio[3102]: info: MYVOLUMIO Environment detected Jun 18 13:02:07 volumio volumio[3102]: info: Plugin folders cleanup Jun 18 13:02:07 volumio volumio[3102]: info: Scanning into folder /volumio/app/plugins/ Jun 18 13:02:07 volumio volumio[3102]: info: Scanning category audio_interface Jun 18 13:02:07 volumio volumio[3102]: info: Scanning category miscellanea Jun 18 13:02:07 volumio volumio[3102]: info: Scanning category music_service Jun 18 13:02:07 volumio volumio[3102]: info: Scanning category plugins.json Jun 18 13:02:07 volumio volumio[3102]: info: Scanning category system_controller Jun 18 13:02:07 volumio volumio[3102]: info: Scanning category user_interface Jun 18 13:02:07 volumio volumio[3102]: info: Scanning into folder /data/plugins/ Jun 18 13:02:07 volumio volumio[3102]: info: Plugin folders cleanup completed Jun 18 13:02:07 volumio volumio[3102]: info: ------------------------------------------- Jun 18 13:02:07 volumio volumio[3102]: info: ----- Core plugins startup ---- Jun 18 13:02:07 volumio volumio[3102]: info: ------------------------------------------- Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 13:02:07 volumio volumio[3102]: info: Adding plugin upnp to MyMusic Plugins Jun 18 13:02:07 volumio volumio[3102]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 13:02:07 volumio volumio[3102]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugins from folder /data/plugins/ Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "system"... Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "appearance"... Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "network"... Jun 18 13:02:07 volumio volumio[3102]: info: Refreshing Cached IP Addresses Jun 18 13:02:07 volumio sudo[3129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 13:02:07 volumio sudo[3129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:07 volumio sudo[3129]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:07 volumio sudo[3131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 13:02:07 volumio sudo[3131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:07 volumio sudo[3131]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "services"... Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "alsa_controller"... Jun 18 13:02:07 volumio sudo[3141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 13:02:07 volumio sudo[3141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:07 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "wizard"... Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "networkfs"... Jun 18 13:02:07 volumio volumio[3102]: info: Cannot mount NAS 8100 at system boot, trial number 1 ,retrying in 5 seconds Jun 18 13:02:07 volumio volumio[3102]: info: Starting Udev Watcher for removable devices Jun 18 13:02:07 volumio volumio[3102]: info: Ignoring mount for partition: boot Jun 18 13:02:07 volumio volumio[3102]: info: Ignoring mount for partition: volumio Jun 18 13:02:07 volumio volumio[3102]: info: Ignoring mount for partition: volumio_data Jun 18 13:02:07 volumio volumio[3102]: info: Ignoring mount for partition: 系统 Jun 18 13:02:07 volumio volumio[3102]: error: Cannot associate FS Label, not mounting Jun 18 13:02:07 volumio volumio[3102]: info: Ignoring mount for partition: 软件 Jun 18 13:02:07 volumio volumio[3102]: info: Ignoring mount for partition: 娱乐 Jun 18 13:02:07 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "volumio_command_line_client"... Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "upnp"... Jun 18 13:02:07 volumio volumio[3102]: info: [1750251727991] Starting Upmpd Daemon Jun 18 13:02:07 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "my_music"... Jun 18 13:02:07 volumio volumio[3102]: info: Loading plugin "mpd"... Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "upnp_browser"... Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "alarm-clock"... Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "airplay_emulation"... Jun 18 13:02:08 volumio volumio[3102]: info: Starting Shairport Sync Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "last_100"... Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "webradio"... Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "i2s_dacs"... Jun 18 13:02:08 volumio volumio[3102]: info: I2S DAC not set, start Auto-detection Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "volumiodiscovery"... Jun 18 13:02:08 volumio volumio[3102]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:08 volumio volumio[3102]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:08 volumio volumio[3102]: *** WARNING *** For more information see Jun 18 13:02:08 volumio node[3102]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:08 volumio volumio[3102]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:08 volumio volumio[3102]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:08 volumio volumio[3102]: *** WARNING *** For more information see Jun 18 13:02:08 volumio node[3102]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:08 volumio node[3102]: *** WARNING *** For more information see Jun 18 13:02:08 volumio node[3102]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:08 volumio node[3102]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:08 volumio node[3102]: *** WARNING *** For more information see Jun 18 13:02:08 volumio volumio[3102]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 13:02:08 volumio volumio[3102]: info: Discovery: Started advertising with name: Volumio Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "outputs"... Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "albumart"... Jun 18 13:02:08 volumio volumio[3102]: info: Plugin example_plugin is not enabled Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "inputs"... Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "updater_comm"... Jun 18 13:02:08 volumio volumio[3102]: info: Plugin mpdemulation is not enabled Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "rest_api"... Jun 18 13:02:08 volumio volumio[3102]: Forking 3 albumart workers Jun 18 13:02:08 volumio volumio[3102]: info: Loading plugin "websocket"... Jun 18 13:02:08 volumio volumio[3102]: info: Starting Socket.io Server version 2.3.0 Jun 18 13:02:08 volumio volumio[3102]: info: Loading i18n strings for locale zh Jun 18 13:02:08 volumio volumio[3102]: Updating browse sources language Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::initPlayerControls Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: Express server listening on port 3000 Jun 18 13:02:08 volumio volumio[3102]: [Metrics] WebUI: 1s 269.06ms Jun 18 13:02:08 volumio volumio[3102]: info: Setting Device type: x86 Jun 18 13:02:08 volumio volumio[3102]: info: CoreStateMachine::resetVolumioState Jun 18 13:02:08 volumio volumio[3102]: info: CoreStateMachine::getcurrentVolume Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 18 13:02:08 volumio volumio[3102]: info: Completed loading Core Plugins Jun 18 13:02:08 volumio volumio[3102]: info: Preparing to generate the ALSA configuration file Jun 18 13:02:08 volumio volumio[3102]: info: Volumio Network Manager: Network status updated: 2 Jun 18 13:02:08 volumio volumio[3102]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 18 13:02:08 volumio volumio[3102]: info: Asound.conf file unchanged, so no further update is needed Jun 18 13:02:08 volumio volumio[3102]: info: Output device has changed, restarting MPD Jun 18 13:02:08 volumio sudo[3202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:08 volumio sudo[3202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:08 volumio volumio[3102]: Starting albumart workers Jun 18 13:02:08 volumio volumio[3102]: info: Output device has changed, restarting Shairport Sync Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:08 volumio sudo[3202]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:08 volumio sudo[3205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:08 volumio sudo[3205]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:08 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 13:02:08 volumio volumio[3102]: Starting albumart workers Jun 18 13:02:08 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:08 volumio volumio[3102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:08 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:08 volumio volumio[3102]: info: ___________ START PLUGINS ___________ Jun 18 13:02:08 volumio volumio[3102]: info: ControllerMpd::onStart: Initializing MPD Jun 18 13:02:08 volumio volumio[3102]: info: Creating MPD Configuration file Jun 18 13:02:08 volumio volumio[3102]: Starting albumart workers Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:08 volumio volumio[3102]: info: [1750251728725] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:08 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:08 volumio sudo[3212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:08 volumio sudo[3212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:08 volumio sudo[3214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:08 volumio sudo[3212]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:08 volumio sudo[3214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 13:02:08 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:08 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:08 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:08 volumio volumio[3102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:08 volumio volumio[3102]: info: [1750251728811] CoreMusicLibrary::Adding element Last_100 Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:08 volumio volumio[3102]: info: [1750251728813] CoreMusicLibrary::Adding element Webradio Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:08 volumio sudo[3220]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 13:02:08 volumio sudo[3220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:08 volumio volumio[3102]: info: Initializing BBC Radios Jun 18 13:02:08 volumio sudo[3220]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:08 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:08 volumio volumio[3102]: info: Volumio Calling Home Jun 18 13:02:09 volumio volumio[3102]: info: CoreStateMachine::setRepeat null single undefined Jun 18 13:02:09 volumio volumio[3102]: info: CoreStateMachine::pushState Jun 18 13:02:09 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:09 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:09 volumio volumio[3102]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:09 volumio volumio[3102]: info: CoreStateMachine::setRandom null Jun 18 13:02:09 volumio volumio[3102]: info: CoreStateMachine::pushState Jun 18 13:02:09 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:09 volumio volumio[3102]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:09 volumio volumio[3102]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:09 volumio volumio[3102]: info: CoreStateMachine::pushState Jun 18 13:02:09 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:09 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:09 volumio volumio[3102]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:09 volumio volumio[3102]: info: CoreStateMachine::updateTrackBlock Jun 18 13:02:09 volumio volumio[3102]: info: CorePlayQueue::getTrackBlock Jun 18 13:02:09 volumio volumio[3102]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:09 volumio mpd[3226]: Jun 18 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 13:02:09 volumio systemd[1]: Started Music Player Daemon. Jun 18 13:02:09 volumio sudo[3205]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:09 volumio sudo[3214]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:09 volumio volumio[3102]: info: Completed starting Core Plugins Jun 18 13:02:09 volumio volumio[3102]: info: ------------------------------------------- Jun 18 13:02:09 volumio volumio[3102]: info: ----- MyVolumio plugins startup ---- Jun 18 13:02:09 volumio volumio[3102]: info: ------------------------------------------- Jun 18 13:02:09 volumio volumio[3102]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 13:02:09 volumio volumio[3102]: info: MPD Permissions set Jun 18 13:02:09 volumio volumio[3102]: info: MPD Permissions set Jun 18 13:02:10 volumio volumio[3102]: info: MPD running with PID3226 Jun 18 13:02:10 volumio volumio[3102]: ,establishing connection Jun 18 13:02:10 volumio volumio[3102]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:10 volumio volumio[3102]: info: CoreStateMachine::pushState Jun 18 13:02:10 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:10 volumio volumio[3102]: info: Volumio called home Jun 18 13:02:10 volumio sudo[3141]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:10 volumio volumio[3102]: info: Reloading queue from file Jun 18 13:02:10 volumio volumio[3102]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:02:10 volumio volumio[3102]: assert.ok(self.idling) Jun 18 13:02:10 volumio volumio[3102]: error: The expression evaluated to a falsy value: Jun 18 13:02:10 volumio volumio[3102]: assert.ok(self.idling) Jun 18 13:02:10 volumio volumio[3102]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:02:10 volumio volumio[3102]: assert.ok(self.idling) Jun 18 13:02:10 volumio volumio[3102]: error: The expression evaluated to a falsy value: Jun 18 13:02:10 volumio volumio[3102]: assert.ok(self.idling) Jun 18 13:02:10 volumio volumio[3102]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:10 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:11 volumio volumio[3102]: error: updateQueue error: null Jun 18 13:02:11 volumio volumio[3102]: info: Starting Shairport Sync Jun 18 13:02:11 volumio volumio[3102]: info: Starting Shairport Sync Jun 18 13:02:11 volumio sudo[3283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:11 volumio sudo[3283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:11 volumio volumio[3102]: info: Starting Shairport Sync Jun 18 13:02:11 volumio sudo[3286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:11 volumio sudo[3286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:11 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:11 volumio sudo[3289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:11 volumio sudo[3289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:11 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:11 volumio volumio[3102]: info: Discovery: adding a81651f1-8cc0-4310-a134-18e897fb72eb Jun 18 13:02:11 volumio volumio[3102]: info: Discovery: Found device Volumio Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:11 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:11 volumio sudo[3283]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:11 volumio volumio[3102]: info: Shairport-Sync Started Jun 18 13:02:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:11 volumio volumio[3102]: Error adding Membership: Error: addMembership EINVAL Jun 18 13:02:11 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 13:02:11 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:11 volumio sudo[3286]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:11 volumio sudo[3289]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:11 volumio volumio[3102]: info: Shairport-Sync Started Jun 18 13:02:11 volumio volumio[3102]: info: Shairport-Sync Started Jun 18 13:02:11 volumio volumio[3102]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:11 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:11 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 13:02:11 volumio volumio[3102]: info: Received Get System Info Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 13:02:11 volumio volumio[3102]: info: Discovery: Getting this device information Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:11 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:11 volumio volumio[3102]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:11 volumio volumio[3102]: info: Listing playlists Jun 18 13:02:11 volumio volumio[3102]: info: CoreCommandRouter::volumioGetQueue Jun 18 13:02:11 volumio volumio[3102]: info: CoreStateMachine::getQueue Jun 18 13:02:11 volumio volumio[3102]: info: CorePlayQueue::getQueue Jun 18 13:02:11 volumio volumio-remote-updater[829]: [2025-06-18 13:02:11] [connect] Successful connection Jun 18 13:02:11 volumio volumio[3102]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jun 18 13:02:11 volumio volumio-remote-updater[829]: [2025-06-18 13:02:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750251731 101 Jun 18 13:02:12 volumio volumio[3102]: info: Cannot mount NAS 8100 at system boot, trial number 2 ,retrying in 5 seconds Jun 18 13:02:13 volumio volumio[3102]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E5%96%80%E4%BB%80%E5%99%B6%E5%B0%94%E8%83%A1%E6%9D%A8/8e6ae173-24a6-40a3-9476-b6564d0aa540.jpg' Jun 18 13:02:16 volumio volumio[3102]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:16 volumio volumio[3102]: Error: connect ECONNREFUSED 31.13.69.245:80 Jun 18 13:02:16 volumio volumio[3102]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 18 13:02:16 volumio volumio[3102]: errno: -111, Jun 18 13:02:16 volumio volumio[3102]: code: 'ECONNREFUSED', Jun 18 13:02:16 volumio volumio[3102]: syscall: 'connect', Jun 18 13:02:16 volumio volumio[3102]: address: '31.13.69.245', Jun 18 13:02:16 volumio volumio[3102]: port: 80 Jun 18 13:02:16 volumio volumio[3102]: } Jun 18 13:02:16 volumio volumio[3102]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:16 volumio sudo[3305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 13:01 Jun 18 13:02:16 volumio sudo[3305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:16 volumio sudo[3305]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:16 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 18 13:02:16 volumio volumio-remote-updater[829]: [2025-06-18 13:02:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 13:02:16 volumio volumio-remote-updater[829]: [2025-06-18 13:02:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 13:02:16 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 18 13:02:16 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:16 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:16 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 13:02:16 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Jun 18 13:02:16 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:16 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 13:02:16 volumio systemd[1]: Started Volumio Backend Module. Jun 18 13:02:16 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:17 volumio volumio[3316]: info: ------------------------------------------- Jun 18 13:02:17 volumio volumio[3316]: info: ----- Volumio3 ---- Jun 18 13:02:17 volumio volumio[3316]: info: ------------------------------------------- Jun 18 13:02:17 volumio volumio[3316]: info: ----- System startup ---- Jun 18 13:02:17 volumio volumio[3316]: info: ------------------------------------------- Jun 18 13:02:17 volumio volumio[3316]: info: MYVOLUMIO Environment detected Jun 18 13:02:17 volumio volumio[3316]: info: Plugin folders cleanup Jun 18 13:02:17 volumio volumio[3316]: info: Scanning into folder /volumio/app/plugins/ Jun 18 13:02:17 volumio volumio[3316]: info: Scanning category audio_interface Jun 18 13:02:17 volumio volumio[3316]: info: Scanning category miscellanea Jun 18 13:02:17 volumio volumio[3316]: info: Scanning category music_service Jun 18 13:02:17 volumio volumio[3316]: info: Scanning category plugins.json Jun 18 13:02:17 volumio volumio[3316]: info: Scanning category system_controller Jun 18 13:02:17 volumio volumio[3316]: info: Scanning category user_interface Jun 18 13:02:17 volumio volumio[3316]: info: Scanning into folder /data/plugins/ Jun 18 13:02:17 volumio volumio[3316]: info: Plugin folders cleanup completed Jun 18 13:02:17 volumio volumio[3316]: info: ------------------------------------------- Jun 18 13:02:17 volumio volumio[3316]: info: ----- Core plugins startup ---- Jun 18 13:02:17 volumio volumio[3316]: info: ------------------------------------------- Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 13:02:17 volumio volumio[3316]: info: Adding plugin upnp to MyMusic Plugins Jun 18 13:02:17 volumio volumio[3316]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 13:02:17 volumio volumio[3316]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugins from folder /data/plugins/ Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "system"... Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "appearance"... Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "network"... Jun 18 13:02:17 volumio volumio[3316]: info: Refreshing Cached IP Addresses Jun 18 13:02:17 volumio sudo[3342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 13:02:17 volumio sudo[3342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:17 volumio sudo[3342]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:17 volumio sudo[3344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 13:02:17 volumio sudo[3344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:17 volumio sudo[3344]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "services"... Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "alsa_controller"... Jun 18 13:02:17 volumio sudo[3353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 13:02:17 volumio sudo[3353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:17 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "wizard"... Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "networkfs"... Jun 18 13:02:17 volumio volumio[3316]: info: Cannot mount NAS 8100 at system boot, trial number 1 ,retrying in 5 seconds Jun 18 13:02:17 volumio volumio[3316]: info: Starting Udev Watcher for removable devices Jun 18 13:02:17 volumio volumio[3316]: info: Ignoring mount for partition: boot Jun 18 13:02:17 volumio volumio[3316]: info: Ignoring mount for partition: volumio Jun 18 13:02:17 volumio volumio[3316]: info: Ignoring mount for partition: volumio_data Jun 18 13:02:17 volumio volumio[3316]: info: Ignoring mount for partition: 系统 Jun 18 13:02:17 volumio volumio[3316]: error: Cannot associate FS Label, not mounting Jun 18 13:02:17 volumio volumio[3316]: info: Ignoring mount for partition: 软件 Jun 18 13:02:17 volumio volumio[3316]: info: Ignoring mount for partition: 娱乐 Jun 18 13:02:17 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "volumio_command_line_client"... Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "upnp"... Jun 18 13:02:17 volumio volumio[3316]: info: [1750251737966] Starting Upmpd Daemon Jun 18 13:02:17 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "my_music"... Jun 18 13:02:17 volumio volumio[3316]: info: Loading plugin "mpd"... Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "upnp_browser"... Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "alarm-clock"... Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "airplay_emulation"... Jun 18 13:02:18 volumio volumio[3316]: info: Starting Shairport Sync Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "last_100"... Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "webradio"... Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "i2s_dacs"... Jun 18 13:02:18 volumio volumio[3316]: info: I2S DAC not set, start Auto-detection Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "volumiodiscovery"... Jun 18 13:02:18 volumio volumio[3316]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:18 volumio volumio[3316]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:18 volumio volumio[3316]: *** WARNING *** For more information see Jun 18 13:02:18 volumio node[3316]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:18 volumio volumio[3316]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:18 volumio volumio[3316]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:18 volumio volumio[3316]: *** WARNING *** For more information see Jun 18 13:02:18 volumio node[3316]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:18 volumio node[3316]: *** WARNING *** For more information see Jun 18 13:02:18 volumio node[3316]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:18 volumio node[3316]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:18 volumio node[3316]: *** WARNING *** For more information see Jun 18 13:02:18 volumio volumio[3316]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 13:02:18 volumio volumio[3316]: info: Discovery: Started advertising with name: Volumio Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "outputs"... Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "albumart"... Jun 18 13:02:18 volumio volumio[3316]: info: Plugin example_plugin is not enabled Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "inputs"... Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "updater_comm"... Jun 18 13:02:18 volumio volumio[3316]: info: Plugin mpdemulation is not enabled Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "rest_api"... Jun 18 13:02:18 volumio volumio[3316]: Forking 3 albumart workers Jun 18 13:02:18 volumio volumio[3316]: info: Loading plugin "websocket"... Jun 18 13:02:18 volumio volumio[3316]: info: Starting Socket.io Server version 2.3.0 Jun 18 13:02:18 volumio volumio[3316]: info: Loading i18n strings for locale zh Jun 18 13:02:18 volumio volumio[3316]: Updating browse sources language Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::initPlayerControls Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: Express server listening on port 3000 Jun 18 13:02:18 volumio volumio[3316]: [Metrics] WebUI: 1s 197.78ms Jun 18 13:02:18 volumio volumio[3316]: info: Setting Device type: x86 Jun 18 13:02:18 volumio volumio[3316]: info: CoreStateMachine::resetVolumioState Jun 18 13:02:18 volumio volumio[3316]: info: CoreStateMachine::getcurrentVolume Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:18 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:18 volumio volumio[3316]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 18 13:02:18 volumio volumio[3316]: info: Completed loading Core Plugins Jun 18 13:02:18 volumio volumio[3316]: info: Preparing to generate the ALSA configuration file Jun 18 13:02:18 volumio volumio[3316]: info: Volumio Network Manager: Network status updated: 2 Jun 18 13:02:18 volumio volumio[3316]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 18 13:02:18 volumio volumio[3316]: Starting albumart workers Jun 18 13:02:18 volumio volumio[3316]: Starting albumart workers Jun 18 13:02:18 volumio volumio[3316]: Starting albumart workers Jun 18 13:02:20 volumio sudo[3353]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:20 volumio volumio[3316]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:20 volumio volumio[3316]: info: CoreStateMachine::pushState Jun 18 13:02:20 volumio volumio[3316]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:20 volumio volumio[3316]: info: CoreStateMachine::updateTrackBlock Jun 18 13:02:20 volumio volumio[3316]: info: CorePlayQueue::getTrackBlock Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:20 volumio volumio[3316]: info: Asound.conf file unchanged, so no further update is needed Jun 18 13:02:20 volumio volumio[3316]: info: Output device has changed, restarting MPD Jun 18 13:02:20 volumio sudo[3484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:20 volumio sudo[3484]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:20 volumio volumio[3316]: info: Output device has changed, restarting Shairport Sync Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio sudo[3484]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:20 volumio sudo[3487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:20 volumio sudo[3487]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:20 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 13:02:20 volumio volumio[3316]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:20 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:20 volumio volumio[3316]: info: ___________ START PLUGINS ___________ Jun 18 13:02:20 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:20 volumio volumio[3316]: info: ControllerMpd::onStart: Initializing MPD Jun 18 13:02:20 volumio volumio[3316]: info: Creating MPD Configuration file Jun 18 13:02:20 volumio sudo[3494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:20 volumio sudo[3494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:20 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:20 volumio volumio[3316]: info: [1750251740696] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:20 volumio sudo[3494]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:20 volumio sudo[3496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:20 volumio sudo[3496]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:20 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 13:02:20 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:20 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:20 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:20 volumio volumio[3316]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:20 volumio volumio[3316]: info: [1750251740737] CoreMusicLibrary::Adding element Last_100 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:20 volumio volumio[3316]: info: [1750251740738] CoreMusicLibrary::Adding element Webradio Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:20 volumio volumio[3316]: info: Initializing BBC Radios Jun 18 13:02:20 volumio sudo[3502]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 13:02:20 volumio sudo[3502]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:20 volumio sudo[3502]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: Volumio Calling Home Jun 18 13:02:20 volumio volumio[3316]: info: CoreStateMachine::setRepeat null single undefined Jun 18 13:02:20 volumio volumio[3316]: info: CoreStateMachine::pushState Jun 18 13:02:20 volumio volumio[3316]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:20 volumio volumio[3316]: info: CoreStateMachine::setRandom null Jun 18 13:02:20 volumio volumio[3316]: info: CoreStateMachine::pushState Jun 18 13:02:20 volumio volumio[3316]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:20 volumio volumio[3316]: info: MPD Permissions set Jun 18 13:02:20 volumio volumio[3316]: info: MPD Permissions set Jun 18 13:02:20 volumio volumio[3316]: info: Reloading queue from file Jun 18 13:02:20 volumio volumio[3316]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:20 volumio volumio[3316]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:20 volumio volumio[3316]: info: CoreStateMachine::pushState Jun 18 13:02:20 volumio volumio[3316]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:20 volumio volumio[3316]: info: Starting Shairport Sync Jun 18 13:02:20 volumio volumio[3316]: info: Starting Shairport Sync Jun 18 13:02:20 volumio sudo[3536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:20 volumio sudo[3536]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:20 volumio volumio[3316]: info: Starting Shairport Sync Jun 18 13:02:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:20 volumio sudo[3539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:20 volumio sudo[3539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:20 volumio sudo[3541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:20 volumio sudo[3541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:20 volumio volumio[3316]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:20 volumio volumio[3316]: info: Discovery: adding a81651f1-8cc0-4310-a134-18e897fb72eb Jun 18 13:02:20 volumio volumio[3316]: info: Discovery: Found device Volumio Jun 18 13:02:20 volumio volumio[3316]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:20 volumio volumio[3316]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:20 volumio sudo[3536]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:20 volumio volumio[3316]: info: Shairport-Sync Started Jun 18 13:02:20 volumio volumio[3316]: Error adding Membership: Error: addMembership EINVAL Jun 18 13:02:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:21 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 13:02:21 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:21 volumio sudo[3539]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:21 volumio volumio[3316]: info: Shairport-Sync Started Jun 18 13:02:21 volumio sudo[3541]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:21 volumio volumio[3316]: info: Shairport-Sync Started Jun 18 13:02:21 volumio mpd[3508]: Jun 18 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 13:02:21 volumio systemd[1]: Started Music Player Daemon. Jun 18 13:02:21 volumio sudo[3487]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:21 volumio sudo[3496]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:21 volumio volumio[3316]: info: Completed starting Core Plugins Jun 18 13:02:21 volumio volumio[3316]: info: ------------------------------------------- Jun 18 13:02:21 volumio volumio[3316]: info: ----- MyVolumio plugins startup ---- Jun 18 13:02:21 volumio volumio[3316]: info: ------------------------------------------- Jun 18 13:02:21 volumio volumio[3316]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 13:02:21 volumio volumio[3316]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:02:21 volumio volumio[3316]: assert.ok(self.idling) Jun 18 13:02:21 volumio volumio[3316]: error: The expression evaluated to a falsy value: Jun 18 13:02:21 volumio volumio[3316]: assert.ok(self.idling) Jun 18 13:02:21 volumio volumio[3316]: info: MPD running with PID3508 Jun 18 13:02:21 volumio volumio[3316]: ,establishing connection Jun 18 13:02:21 volumio volumio[3316]: error: updateQueue error: null Jun 18 13:02:21 volumio volumio[3316]: error: updateQueue error: null Jun 18 13:02:21 volumio volumio[3316]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 13:02:21 volumio volumio[3316]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 18 13:02:21 volumio volumio-remote-updater[829]: [2025-06-18 13:02:21] [connect] Successful connection Jun 18 13:02:21 volumio volumio-remote-updater[829]: [2025-06-18 13:02:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750251741 101 Jun 18 13:02:21 volumio volumio[3316]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jun 18 13:02:22 volumio volumio[3316]: info: Volumio called home Jun 18 13:02:22 volumio volumio[3316]: info: Cannot mount NAS 8100 at system boot, trial number 2 ,retrying in 5 seconds Jun 18 13:02:23 volumio volumio[3316]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:23 volumio volumio[3316]: Error: connect ECONNREFUSED 31.13.69.245:80 Jun 18 13:02:23 volumio volumio[3316]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 18 13:02:23 volumio volumio[3316]: errno: -111, Jun 18 13:02:23 volumio volumio[3316]: code: 'ECONNREFUSED', Jun 18 13:02:23 volumio volumio[3316]: syscall: 'connect', Jun 18 13:02:23 volumio volumio[3316]: address: '31.13.69.245', Jun 18 13:02:23 volumio volumio[3316]: port: 80 Jun 18 13:02:23 volumio volumio[3316]: } Jun 18 13:02:23 volumio volumio[3316]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:23 volumio sudo[3562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 13:01 Jun 18 13:02:23 volumio sudo[3562]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:23 volumio sudo[3562]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:23 volumio volumio-remote-updater[829]: [2025-06-18 13:02:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 13:02:23 volumio volumio-remote-updater[829]: [2025-06-18 13:02:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 13:02:23 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 18 13:02:23 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 18 13:02:23 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:23 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:23 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 13:02:23 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Jun 18 13:02:23 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 13:02:23 volumio systemd[1]: Started Volumio Backend Module. Jun 18 13:02:23 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:23 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:23 volumio volumio[3572]: info: ------------------------------------------- Jun 18 13:02:23 volumio volumio[3572]: info: ----- Volumio3 ---- Jun 18 13:02:23 volumio volumio[3572]: info: ------------------------------------------- Jun 18 13:02:23 volumio volumio[3572]: info: ----- System startup ---- Jun 18 13:02:23 volumio volumio[3572]: info: ------------------------------------------- Jun 18 13:02:24 volumio volumio[3572]: info: MYVOLUMIO Environment detected Jun 18 13:02:24 volumio volumio[3572]: info: Plugin folders cleanup Jun 18 13:02:24 volumio volumio[3572]: info: Scanning into folder /volumio/app/plugins/ Jun 18 13:02:24 volumio volumio[3572]: info: Scanning category audio_interface Jun 18 13:02:24 volumio volumio[3572]: info: Scanning category miscellanea Jun 18 13:02:24 volumio volumio[3572]: info: Scanning category music_service Jun 18 13:02:24 volumio volumio[3572]: info: Scanning category plugins.json Jun 18 13:02:24 volumio volumio[3572]: info: Scanning category system_controller Jun 18 13:02:24 volumio volumio[3572]: info: Scanning category user_interface Jun 18 13:02:24 volumio volumio[3572]: info: Scanning into folder /data/plugins/ Jun 18 13:02:24 volumio volumio[3572]: info: Plugin folders cleanup completed Jun 18 13:02:24 volumio volumio[3572]: info: ------------------------------------------- Jun 18 13:02:24 volumio volumio[3572]: info: ----- Core plugins startup ---- Jun 18 13:02:24 volumio volumio[3572]: info: ------------------------------------------- Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 13:02:24 volumio volumio[3572]: info: Adding plugin upnp to MyMusic Plugins Jun 18 13:02:24 volumio volumio[3572]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 13:02:24 volumio volumio[3572]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugins from folder /data/plugins/ Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "system"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "appearance"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "network"... Jun 18 13:02:24 volumio volumio[3572]: info: Refreshing Cached IP Addresses Jun 18 13:02:24 volumio sudo[3599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 13:02:24 volumio sudo[3599]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:24 volumio sudo[3599]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:24 volumio sudo[3601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 13:02:24 volumio sudo[3601]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:24 volumio sudo[3601]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "services"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "alsa_controller"... Jun 18 13:02:24 volumio sudo[3610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 13:02:24 volumio sudo[3610]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:24 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "wizard"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "networkfs"... Jun 18 13:02:24 volumio volumio[3572]: info: Cannot mount NAS 8100 at system boot, trial number 1 ,retrying in 5 seconds Jun 18 13:02:24 volumio volumio[3572]: info: Starting Udev Watcher for removable devices Jun 18 13:02:24 volumio volumio[3572]: info: Ignoring mount for partition: boot Jun 18 13:02:24 volumio volumio[3572]: info: Ignoring mount for partition: volumio Jun 18 13:02:24 volumio volumio[3572]: info: Ignoring mount for partition: volumio_data Jun 18 13:02:24 volumio volumio[3572]: info: Ignoring mount for partition: 系统 Jun 18 13:02:24 volumio volumio[3572]: error: Cannot associate FS Label, not mounting Jun 18 13:02:24 volumio volumio[3572]: info: Ignoring mount for partition: 软件 Jun 18 13:02:24 volumio volumio[3572]: info: Ignoring mount for partition: 娱乐 Jun 18 13:02:24 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "volumio_command_line_client"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "upnp"... Jun 18 13:02:24 volumio volumio[3572]: info: [1750251744603] Starting Upmpd Daemon Jun 18 13:02:24 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "my_music"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "mpd"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "upnp_browser"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "alarm-clock"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "airplay_emulation"... Jun 18 13:02:24 volumio volumio[3572]: info: Starting Shairport Sync Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "last_100"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "webradio"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "i2s_dacs"... Jun 18 13:02:24 volumio volumio[3572]: info: I2S DAC not set, start Auto-detection Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "volumiodiscovery"... Jun 18 13:02:24 volumio volumio[3572]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:24 volumio volumio[3572]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:24 volumio volumio[3572]: *** WARNING *** For more information see Jun 18 13:02:24 volumio volumio[3572]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:24 volumio node[3572]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:24 volumio volumio[3572]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:24 volumio volumio[3572]: *** WARNING *** For more information see Jun 18 13:02:24 volumio node[3572]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:24 volumio node[3572]: *** WARNING *** For more information see Jun 18 13:02:24 volumio node[3572]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:24 volumio node[3572]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:24 volumio node[3572]: *** WARNING *** For more information see Jun 18 13:02:24 volumio volumio[3572]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 13:02:24 volumio volumio[3572]: info: Discovery: Started advertising with name: Volumio Jun 18 13:02:24 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "outputs"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "albumart"... Jun 18 13:02:24 volumio volumio[3572]: info: Plugin example_plugin is not enabled Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "inputs"... Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "updater_comm"... Jun 18 13:02:24 volumio volumio[3572]: info: Plugin mpdemulation is not enabled Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "rest_api"... Jun 18 13:02:24 volumio volumio[3572]: Forking 3 albumart workers Jun 18 13:02:24 volumio volumio[3572]: info: Loading plugin "websocket"... Jun 18 13:02:24 volumio volumio[3572]: info: Starting Socket.io Server version 2.3.0 Jun 18 13:02:24 volumio volumio[3572]: info: Loading i18n strings for locale zh Jun 18 13:02:24 volumio volumio[3572]: Updating browse sources language Jun 18 13:02:24 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::initPlayerControls Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: Express server listening on port 3000 Jun 18 13:02:25 volumio volumio[3572]: [Metrics] WebUI: 1s 280.09ms Jun 18 13:02:25 volumio volumio[3572]: info: Setting Device type: x86 Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::resetVolumioState Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::getcurrentVolume Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 18 13:02:25 volumio volumio[3572]: info: Completed loading Core Plugins Jun 18 13:02:25 volumio volumio[3572]: info: Preparing to generate the ALSA configuration file Jun 18 13:02:25 volumio volumio[3572]: info: Volumio Network Manager: Network status updated: 2 Jun 18 13:02:25 volumio volumio[3572]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 18 13:02:25 volumio volumio[3572]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::pushState Jun 18 13:02:25 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::updateTrackBlock Jun 18 13:02:25 volumio volumio[3572]: info: CorePlayQueue::getTrackBlock Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:25 volumio volumio[3572]: info: Asound.conf file unchanged, so no further update is needed Jun 18 13:02:25 volumio volumio[3572]: info: Output device has changed, restarting MPD Jun 18 13:02:25 volumio volumio[3572]: Starting albumart workers Jun 18 13:02:25 volumio volumio[3572]: Starting albumart workers Jun 18 13:02:25 volumio volumio[3572]: info: Output device has changed, restarting Shairport Sync Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:25 volumio sudo[3676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:25 volumio sudo[3676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:25 volumio sudo[3676]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:25 volumio sudo[3678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:25 volumio sudo[3678]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:25 volumio volumio[3572]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:25 volumio volumio[3572]: info: ___________ START PLUGINS ___________ Jun 18 13:02:25 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 13:02:25 volumio volumio[3572]: info: ControllerMpd::onStart: Initializing MPD Jun 18 13:02:25 volumio volumio[3572]: info: Creating MPD Configuration file Jun 18 13:02:25 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:25 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:25 volumio sudo[3686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:25 volumio sudo[3686]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:25 volumio volumio[3572]: info: [1750251745253] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:25 volumio sudo[3686]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:25 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: Starting albumart workers Jun 18 13:02:25 volumio sudo[3689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:25 volumio sudo[3689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:25 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 13:02:25 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:25 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:25 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:25 volumio volumio[3572]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:25 volumio sudo[3694]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 13:02:25 volumio sudo[3694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:25 volumio sudo[3694]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:25 volumio volumio[3572]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:25 volumio volumio[3572]: info: [1750251745388] CoreMusicLibrary::Adding element Last_100 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:25 volumio volumio[3572]: info: [1750251745394] CoreMusicLibrary::Adding element Webradio Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:25 volumio volumio[3572]: info: Initializing BBC Radios Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: Volumio Calling Home Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::setRepeat null single undefined Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::pushState Jun 18 13:02:25 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::setRandom null Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::pushState Jun 18 13:02:25 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:25 volumio volumio[3572]: info: MPD Permissions set Jun 18 13:02:25 volumio volumio[3572]: info: MPD Permissions set Jun 18 13:02:25 volumio volumio[3572]: info: Reloading queue from file Jun 18 13:02:25 volumio volumio[3572]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:25 volumio volumio[3572]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:25 volumio volumio[3572]: info: CoreStateMachine::pushState Jun 18 13:02:25 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:25 volumio volumio[3572]: info: Starting Shairport Sync Jun 18 13:02:25 volumio volumio[3572]: info: Starting Shairport Sync Jun 18 13:02:25 volumio volumio[3572]: info: Starting Shairport Sync Jun 18 13:02:25 volumio sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:25 volumio sudo[3751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:25 volumio sudo[3753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:25 volumio sudo[3753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:25 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:25 volumio sudo[3757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:25 volumio sudo[3757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:25 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:25 volumio sudo[3751]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:25 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 13:02:25 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:25 volumio volumio[3572]: info: Shairport-Sync Started Jun 18 13:02:25 volumio volumio[3572]: Error adding Membership: Error: addMembership EINVAL Jun 18 13:02:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:25 volumio sudo[3753]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:25 volumio volumio[3572]: info: Shairport-Sync Started Jun 18 13:02:25 volumio sudo[3757]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:25 volumio volumio[3572]: info: Shairport-Sync Started Jun 18 13:02:25 volumio mpd[3699]: Jun 18 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 13:02:25 volumio systemd[1]: Started Music Player Daemon. Jun 18 13:02:25 volumio sudo[3689]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:25 volumio sudo[3678]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:25 volumio volumio[3572]: info: Completed starting Core Plugins Jun 18 13:02:25 volumio volumio[3572]: info: ------------------------------------------- Jun 18 13:02:25 volumio volumio[3572]: info: ----- MyVolumio plugins startup ---- Jun 18 13:02:25 volumio volumio[3572]: info: ------------------------------------------- Jun 18 13:02:25 volumio volumio[3572]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 13:02:25 volumio volumio[3572]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:02:25 volumio volumio[3572]: assert.ok(self.idling) Jun 18 13:02:25 volumio volumio[3572]: error: The expression evaluated to a falsy value: Jun 18 13:02:25 volumio volumio[3572]: assert.ok(self.idling) Jun 18 13:02:25 volumio volumio[3572]: info: MPD running with PID3699 Jun 18 13:02:25 volumio volumio[3572]: ,establishing connection Jun 18 13:02:25 volumio volumio[3572]: error: updateQueue error: null Jun 18 13:02:25 volumio volumio[3572]: error: updateQueue error: null Jun 18 13:02:25 volumio volumio[3572]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 13:02:25 volumio volumio[3572]: info: Discovery: adding a81651f1-8cc0-4310-a134-18e897fb72eb Jun 18 13:02:25 volumio volumio[3572]: info: Discovery: Found device Volumio Jun 18 13:02:25 volumio volumio[3572]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:25 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 18 13:02:26 volumio volumio[3572]: info: Volumio called home Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:26 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:26 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 13:02:26 volumio volumio[3572]: info: Received Get System Info Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 13:02:26 volumio volumio[3572]: info: Discovery: Getting this device information Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:26 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:26 volumio volumio[3572]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:26 volumio volumio[3572]: info: Listing playlists Jun 18 13:02:26 volumio volumio[3572]: info: CoreCommandRouter::volumioGetQueue Jun 18 13:02:26 volumio volumio[3572]: info: CoreStateMachine::getQueue Jun 18 13:02:26 volumio volumio[3572]: info: CorePlayQueue::getQueue Jun 18 13:02:26 volumio sudo[3610]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:28 volumio volumio-remote-updater[829]: [2025-06-18 13:02:28] [connect] Successful connection Jun 18 13:02:28 volumio volumio-remote-updater[829]: [2025-06-18 13:02:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750251748 101 Jun 18 13:02:28 volumio volumio[3572]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jun 18 13:02:28 volumio volumio[3572]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%87%A4%E5%87%B0%E4%BC%A0%E5%A5%87/23960b98-c720-4410-8135-2141eced02ae.jpg' Jun 18 13:02:29 volumio volumio[3572]: info: Cannot mount NAS 8100 at system boot, trial number 2 ,retrying in 5 seconds Jun 18 13:02:29 volumio volumio[3572]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F/c335fd0d-133f-4273-8069-0c3fa1ef6228.jpg' Jun 18 13:02:32 volumio volumio[3572]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:32 volumio volumio[3572]: Error: connect ECONNREFUSED 31.13.69.245:80 Jun 18 13:02:32 volumio volumio[3572]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 18 13:02:32 volumio volumio[3572]: errno: -111, Jun 18 13:02:32 volumio volumio[3572]: code: 'ECONNREFUSED', Jun 18 13:02:32 volumio volumio[3572]: syscall: 'connect', Jun 18 13:02:32 volumio volumio[3572]: address: '31.13.69.245', Jun 18 13:02:32 volumio volumio[3572]: port: 80 Jun 18 13:02:32 volumio volumio[3572]: } Jun 18 13:02:32 volumio volumio[3572]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:32 volumio sudo[3778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 13:01 Jun 18 13:02:32 volumio sudo[3778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:32 volumio sudo[3778]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:32 volumio volumio-remote-updater[829]: [2025-06-18 13:02:32] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 13:02:32 volumio volumio-remote-updater[829]: [2025-06-18 13:02:32] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 13:02:32 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 18 13:02:32 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 18 13:02:32 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:32 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:33 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 13:02:33 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Jun 18 13:02:33 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:33 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 13:02:33 volumio systemd[1]: Started Volumio Backend Module. Jun 18 13:02:33 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:33 volumio volumio[3789]: info: ------------------------------------------- Jun 18 13:02:33 volumio volumio[3789]: info: ----- Volumio3 ---- Jun 18 13:02:33 volumio volumio[3789]: info: ------------------------------------------- Jun 18 13:02:33 volumio volumio[3789]: info: ----- System startup ---- Jun 18 13:02:33 volumio volumio[3789]: info: ------------------------------------------- Jun 18 13:02:33 volumio volumio[3789]: info: MYVOLUMIO Environment detected Jun 18 13:02:33 volumio volumio[3789]: info: Plugin folders cleanup Jun 18 13:02:33 volumio volumio[3789]: info: Scanning into folder /volumio/app/plugins/ Jun 18 13:02:33 volumio volumio[3789]: info: Scanning category audio_interface Jun 18 13:02:33 volumio volumio[3789]: info: Scanning category miscellanea Jun 18 13:02:33 volumio volumio[3789]: info: Scanning category music_service Jun 18 13:02:33 volumio volumio[3789]: info: Scanning category plugins.json Jun 18 13:02:33 volumio volumio[3789]: info: Scanning category system_controller Jun 18 13:02:33 volumio volumio[3789]: info: Scanning category user_interface Jun 18 13:02:33 volumio volumio[3789]: info: Scanning into folder /data/plugins/ Jun 18 13:02:33 volumio volumio[3789]: info: Plugin folders cleanup completed Jun 18 13:02:33 volumio volumio[3789]: info: ------------------------------------------- Jun 18 13:02:33 volumio volumio[3789]: info: ----- Core plugins startup ---- Jun 18 13:02:33 volumio volumio[3789]: info: ------------------------------------------- Jun 18 13:02:33 volumio volumio[3789]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 13:02:33 volumio volumio[3789]: info: Adding plugin upnp to MyMusic Plugins Jun 18 13:02:33 volumio volumio[3789]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 13:02:33 volumio volumio[3789]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 13:02:33 volumio volumio[3789]: info: Loading plugins from folder /data/plugins/ Jun 18 13:02:33 volumio volumio[3789]: info: Loading plugin "system"... Jun 18 13:02:33 volumio volumio[3789]: info: Loading plugin "appearance"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "network"... Jun 18 13:02:34 volumio volumio[3789]: info: Refreshing Cached IP Addresses Jun 18 13:02:34 volumio sudo[3815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 13:02:34 volumio sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:34 volumio sudo[3815]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:34 volumio sudo[3817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 13:02:34 volumio sudo[3817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:34 volumio sudo[3817]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "services"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "alsa_controller"... Jun 18 13:02:34 volumio sudo[3830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 13:02:34 volumio sudo[3830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:34 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "wizard"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "networkfs"... Jun 18 13:02:34 volumio volumio[3789]: info: Cannot mount NAS 8100 at system boot, trial number 1 ,retrying in 5 seconds Jun 18 13:02:34 volumio volumio[3789]: info: Starting Udev Watcher for removable devices Jun 18 13:02:34 volumio volumio[3789]: info: Ignoring mount for partition: boot Jun 18 13:02:34 volumio volumio[3789]: info: Ignoring mount for partition: volumio Jun 18 13:02:34 volumio volumio[3789]: info: Ignoring mount for partition: volumio_data Jun 18 13:02:34 volumio volumio[3789]: info: Ignoring mount for partition: 系统 Jun 18 13:02:34 volumio volumio[3789]: error: Cannot associate FS Label, not mounting Jun 18 13:02:34 volumio volumio[3789]: info: Ignoring mount for partition: 软件 Jun 18 13:02:34 volumio volumio[3789]: info: Ignoring mount for partition: 娱乐 Jun 18 13:02:34 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "volumio_command_line_client"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "upnp"... Jun 18 13:02:34 volumio volumio[3789]: info: [1750251754239] Starting Upmpd Daemon Jun 18 13:02:34 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "my_music"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "mpd"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "upnp_browser"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "alarm-clock"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "airplay_emulation"... Jun 18 13:02:34 volumio volumio[3789]: info: Starting Shairport Sync Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "last_100"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "webradio"... Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "i2s_dacs"... Jun 18 13:02:34 volumio volumio[3789]: info: I2S DAC not set, start Auto-detection Jun 18 13:02:34 volumio volumio[3789]: info: Loading plugin "volumiodiscovery"... Jun 18 13:02:34 volumio volumio[3789]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:34 volumio volumio[3789]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:34 volumio volumio[3789]: *** WARNING *** For more information see Jun 18 13:02:34 volumio volumio[3789]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:34 volumio node[3789]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:34 volumio volumio[3789]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:34 volumio volumio[3789]: *** WARNING *** For more information see Jun 18 13:02:34 volumio node[3789]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:34 volumio node[3789]: *** WARNING *** For more information see Jun 18 13:02:34 volumio node[3789]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:34 volumio node[3789]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:34 volumio node[3789]: *** WARNING *** For more information see Jun 18 13:02:34 volumio volumio[3789]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 13:02:35 volumio volumio[3789]: info: Discovery: Started advertising with name: Volumio Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:35 volumio volumio[3789]: info: Loading plugin "outputs"... Jun 18 13:02:35 volumio volumio[3789]: info: Loading plugin "albumart"... Jun 18 13:02:35 volumio volumio[3789]: info: Plugin example_plugin is not enabled Jun 18 13:02:35 volumio volumio[3789]: info: Loading plugin "inputs"... Jun 18 13:02:35 volumio volumio[3789]: info: Loading plugin "updater_comm"... Jun 18 13:02:35 volumio volumio[3789]: Forking 3 albumart workers Jun 18 13:02:35 volumio volumio[3789]: info: Plugin mpdemulation is not enabled Jun 18 13:02:35 volumio volumio[3789]: info: Loading plugin "rest_api"... Jun 18 13:02:35 volumio volumio[3789]: info: Loading plugin "websocket"... Jun 18 13:02:35 volumio volumio[3789]: info: Starting Socket.io Server version 2.3.0 Jun 18 13:02:35 volumio volumio[3789]: info: Loading i18n strings for locale zh Jun 18 13:02:35 volumio volumio[3789]: Updating browse sources language Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::initPlayerControls Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: Express server listening on port 3000 Jun 18 13:02:35 volumio volumio[3789]: [Metrics] WebUI: 1s 716.45ms Jun 18 13:02:35 volumio volumio[3789]: info: Setting Device type: x86 Jun 18 13:02:35 volumio volumio[3789]: info: CoreStateMachine::resetVolumioState Jun 18 13:02:35 volumio volumio[3789]: info: CoreStateMachine::getcurrentVolume Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:35 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:35 volumio volumio[3789]: Starting albumart workers Jun 18 13:02:35 volumio volumio[3789]: Starting albumart workers Jun 18 13:02:35 volumio volumio[3789]: Starting albumart workers Jun 18 13:02:36 volumio volumio[3789]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 18 13:02:36 volumio volumio[3789]: info: Completed loading Core Plugins Jun 18 13:02:36 volumio volumio[3789]: info: Preparing to generate the ALSA configuration file Jun 18 13:02:36 volumio volumio[3789]: info: Volumio Network Manager: Network status updated: 2 Jun 18 13:02:36 volumio volumio[3789]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 18 13:02:36 volumio sudo[3830]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:37 volumio volumio-remote-updater[829]: [2025-06-18 13:02:37] [connect] Successful connection Jun 18 13:02:38 volumio volumio[3789]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:38 volumio volumio[3789]: info: CoreStateMachine::pushState Jun 18 13:02:38 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:38 volumio volumio[3789]: info: CoreStateMachine::updateTrackBlock Jun 18 13:02:38 volumio volumio[3789]: info: CorePlayQueue::getTrackBlock Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:38 volumio volumio[3789]: info: Asound.conf file unchanged, so no further update is needed Jun 18 13:02:38 volumio volumio[3789]: info: Output device has changed, restarting MPD Jun 18 13:02:38 volumio sudo[3914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:38 volumio sudo[3914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:38 volumio volumio[3789]: info: Output device has changed, restarting Shairport Sync Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:38 volumio sudo[3914]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:38 volumio sudo[3917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:38 volumio sudo[3917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:38 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 13:02:38 volumio volumio[3789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:38 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:38 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:38 volumio volumio[3789]: info: ___________ START PLUGINS ___________ Jun 18 13:02:38 volumio volumio[3789]: info: ControllerMpd::onStart: Initializing MPD Jun 18 13:02:38 volumio volumio[3789]: info: Creating MPD Configuration file Jun 18 13:02:38 volumio sudo[3924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:38 volumio sudo[3924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:38 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:38 volumio sudo[3924]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:38 volumio volumio[3789]: info: [1750251758823] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:38 volumio sudo[3927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:38 volumio sudo[3927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:38 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 13:02:38 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:38 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:38 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:38 volumio volumio[3789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:38 volumio volumio[3789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:38 volumio volumio[3789]: info: [1750251758866] CoreMusicLibrary::Adding element Last_100 Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:38 volumio volumio[3789]: info: [1750251758867] CoreMusicLibrary::Adding element Webradio Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:38 volumio volumio[3789]: info: Initializing BBC Radios Jun 18 13:02:38 volumio sudo[3932]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 13:02:38 volumio sudo[3932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:38 volumio sudo[3932]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:38 volumio volumio[3789]: info: Volumio Calling Home Jun 18 13:02:38 volumio volumio[3789]: info: CoreStateMachine::setRepeat null single undefined Jun 18 13:02:38 volumio volumio[3789]: info: CoreStateMachine::pushState Jun 18 13:02:38 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:38 volumio volumio[3789]: info: CoreStateMachine::setRandom null Jun 18 13:02:38 volumio volumio[3789]: info: CoreStateMachine::pushState Jun 18 13:02:38 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:38 volumio volumio-remote-updater[829]: [2025-06-18 13:02:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750251757 101 Jun 18 13:02:38 volumio volumio[3789]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 18 13:02:38 volumio volumio[3789]: info: MPD Permissions set Jun 18 13:02:38 volumio volumio[3789]: info: MPD Permissions set Jun 18 13:02:38 volumio volumio[3789]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:38 volumio volumio[3789]: info: CoreStateMachine::pushState Jun 18 13:02:38 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:38 volumio volumio[3789]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:39 volumio volumio[3789]: info: Reloading queue from file Jun 18 13:02:39 volumio volumio[3789]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:39 volumio volumio[3789]: info: Discovery: adding a81651f1-8cc0-4310-a134-18e897fb72eb Jun 18 13:02:39 volumio volumio[3789]: info: Discovery: Found device Volumio Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:39 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:39 volumio volumio[3789]: info: Starting Shairport Sync Jun 18 13:02:39 volumio volumio[3789]: info: Starting Shairport Sync Jun 18 13:02:39 volumio sudo[3965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:39 volumio sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:39 volumio volumio[3789]: info: Starting Shairport Sync Jun 18 13:02:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:39 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:39 volumio sudo[3968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:39 volumio volumio[3789]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:39 volumio volumio[3789]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:39 volumio sudo[3968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:39 volumio sudo[3972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:39 volumio sudo[3972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:39 volumio sudo[3965]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:39 volumio volumio[3789]: info: Shairport-Sync Started Jun 18 13:02:39 volumio volumio[3789]: Error adding Membership: Error: addMembership EINVAL Jun 18 13:02:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:39 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 13:02:39 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:39 volumio sudo[3968]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:39 volumio sudo[3972]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:39 volumio volumio[3789]: info: Shairport-Sync Started Jun 18 13:02:39 volumio volumio[3789]: info: Shairport-Sync Started Jun 18 13:02:39 volumio mpd[3939]: Jun 18 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 13:02:39 volumio volumio[3789]: info: Cannot mount NAS 8100 at system boot, trial number 2 ,retrying in 5 seconds Jun 18 13:02:39 volumio systemd[1]: Started Music Player Daemon. Jun 18 13:02:39 volumio sudo[3917]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:39 volumio sudo[3927]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:39 volumio volumio[3789]: info: Completed starting Core Plugins Jun 18 13:02:39 volumio volumio[3789]: info: ------------------------------------------- Jun 18 13:02:39 volumio volumio[3789]: info: ----- MyVolumio plugins startup ---- Jun 18 13:02:39 volumio volumio[3789]: info: ------------------------------------------- Jun 18 13:02:39 volumio volumio[3789]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 13:02:39 volumio volumio[3789]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:02:39 volumio volumio[3789]: assert.ok(self.idling) Jun 18 13:02:39 volumio volumio[3789]: error: The expression evaluated to a falsy value: Jun 18 13:02:39 volumio volumio[3789]: assert.ok(self.idling) Jun 18 13:02:39 volumio volumio[3789]: error: updateQueue error: null Jun 18 13:02:39 volumio volumio[3789]: info: MPD running with PID3939 Jun 18 13:02:39 volumio volumio[3789]: ,establishing connection Jun 18 13:02:39 volumio volumio[3789]: error: updateQueue error: null Jun 18 13:02:39 volumio volumio[3789]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 18 13:02:39 volumio volumio[3789]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 18 13:02:39 volumio volumio[3789]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:39 volumio volumio[3789]: Error: connect ECONNREFUSED 31.13.69.245:443 Jun 18 13:02:39 volumio volumio[3789]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 18 13:02:39 volumio volumio[3789]: errno: -111, Jun 18 13:02:39 volumio volumio[3789]: code: 'ECONNREFUSED', Jun 18 13:02:39 volumio volumio[3789]: syscall: 'connect', Jun 18 13:02:39 volumio volumio[3789]: address: '31.13.69.245', Jun 18 13:02:39 volumio volumio[3789]: port: 443 Jun 18 13:02:39 volumio volumio[3789]: } Jun 18 13:02:39 volumio volumio[3789]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:39 volumio sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 13:01 Jun 18 13:02:39 volumio sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:39 volumio sudo[3990]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:42 volumio volumio-remote-updater[829]: [2025-06-18 13:02:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 13:02:42 volumio volumio-remote-updater[829]: [2025-06-18 13:02:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 13:02:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 18 13:02:42 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 18 13:02:42 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:42 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:42 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 13:02:42 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Jun 18 13:02:42 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 13:02:42 volumio systemd[1]: Started Volumio Backend Module. Jun 18 13:02:42 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:42 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:43 volumio volumio[4003]: info: ------------------------------------------- Jun 18 13:02:43 volumio volumio[4003]: info: ----- Volumio3 ---- Jun 18 13:02:43 volumio volumio[4003]: info: ------------------------------------------- Jun 18 13:02:43 volumio volumio[4003]: info: ----- System startup ---- Jun 18 13:02:43 volumio volumio[4003]: info: ------------------------------------------- Jun 18 13:02:43 volumio volumio[4003]: info: MYVOLUMIO Environment detected Jun 18 13:02:43 volumio volumio[4003]: info: Plugin folders cleanup Jun 18 13:02:43 volumio volumio[4003]: info: Scanning into folder /volumio/app/plugins/ Jun 18 13:02:43 volumio volumio[4003]: info: Scanning category audio_interface Jun 18 13:02:43 volumio volumio[4003]: info: Scanning category miscellanea Jun 18 13:02:43 volumio volumio[4003]: info: Scanning category music_service Jun 18 13:02:43 volumio volumio[4003]: info: Scanning category plugins.json Jun 18 13:02:43 volumio volumio[4003]: info: Scanning category system_controller Jun 18 13:02:43 volumio volumio[4003]: info: Scanning category user_interface Jun 18 13:02:43 volumio volumio[4003]: info: Scanning into folder /data/plugins/ Jun 18 13:02:43 volumio volumio[4003]: info: Plugin folders cleanup completed Jun 18 13:02:43 volumio volumio[4003]: info: ------------------------------------------- Jun 18 13:02:43 volumio volumio[4003]: info: ----- Core plugins startup ---- Jun 18 13:02:43 volumio volumio[4003]: info: ------------------------------------------- Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 13:02:43 volumio volumio[4003]: info: Adding plugin upnp to MyMusic Plugins Jun 18 13:02:43 volumio volumio[4003]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 13:02:43 volumio volumio[4003]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugins from folder /data/plugins/ Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "system"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "appearance"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "network"... Jun 18 13:02:43 volumio volumio[4003]: info: Refreshing Cached IP Addresses Jun 18 13:02:43 volumio sudo[4030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 13:02:43 volumio sudo[4030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:43 volumio sudo[4032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 13:02:43 volumio sudo[4032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:43 volumio sudo[4030]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:43 volumio sudo[4032]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "services"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "alsa_controller"... Jun 18 13:02:43 volumio sudo[4041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 13:02:43 volumio sudo[4041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:43 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "wizard"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "networkfs"... Jun 18 13:02:43 volumio volumio[4003]: info: Cannot mount NAS 8100 at system boot, trial number 1 ,retrying in 5 seconds Jun 18 13:02:43 volumio volumio[4003]: info: Starting Udev Watcher for removable devices Jun 18 13:02:43 volumio volumio[4003]: info: Ignoring mount for partition: boot Jun 18 13:02:43 volumio volumio[4003]: info: Ignoring mount for partition: volumio Jun 18 13:02:43 volumio volumio[4003]: info: Ignoring mount for partition: volumio_data Jun 18 13:02:43 volumio volumio[4003]: info: Ignoring mount for partition: 系统 Jun 18 13:02:43 volumio volumio[4003]: error: Cannot associate FS Label, not mounting Jun 18 13:02:43 volumio volumio[4003]: info: Ignoring mount for partition: 软件 Jun 18 13:02:43 volumio volumio[4003]: info: Ignoring mount for partition: 娱乐 Jun 18 13:02:43 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "volumio_command_line_client"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "upnp"... Jun 18 13:02:43 volumio volumio[4003]: info: [1750251763709] Starting Upmpd Daemon Jun 18 13:02:43 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "my_music"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "mpd"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "upnp_browser"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "alarm-clock"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "airplay_emulation"... Jun 18 13:02:43 volumio volumio[4003]: info: Starting Shairport Sync Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "last_100"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "webradio"... Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "i2s_dacs"... Jun 18 13:02:43 volumio volumio[4003]: info: I2S DAC not set, start Auto-detection Jun 18 13:02:43 volumio volumio[4003]: info: Loading plugin "volumiodiscovery"... Jun 18 13:02:43 volumio volumio[4003]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:43 volumio volumio[4003]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:43 volumio volumio[4003]: *** WARNING *** For more information see Jun 18 13:02:43 volumio volumio[4003]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:43 volumio volumio[4003]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:43 volumio volumio[4003]: *** WARNING *** For more information see Jun 18 13:02:43 volumio node[4003]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:43 volumio node[4003]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:43 volumio node[4003]: *** WARNING *** For more information see Jun 18 13:02:43 volumio node[4003]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:43 volumio node[4003]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:43 volumio node[4003]: *** WARNING *** For more information see Jun 18 13:02:43 volumio volumio[4003]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 13:02:44 volumio volumio[4003]: info: Discovery: Started advertising with name: Volumio Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:44 volumio volumio[4003]: info: Loading plugin "outputs"... Jun 18 13:02:44 volumio volumio[4003]: info: Loading plugin "albumart"... Jun 18 13:02:44 volumio volumio[4003]: info: Plugin example_plugin is not enabled Jun 18 13:02:44 volumio volumio[4003]: info: Loading plugin "inputs"... Jun 18 13:02:44 volumio volumio[4003]: info: Loading plugin "updater_comm"... Jun 18 13:02:44 volumio volumio[4003]: Forking 3 albumart workers Jun 18 13:02:44 volumio volumio[4003]: info: Plugin mpdemulation is not enabled Jun 18 13:02:44 volumio volumio[4003]: info: Loading plugin "rest_api"... Jun 18 13:02:44 volumio volumio[4003]: info: Loading plugin "websocket"... Jun 18 13:02:44 volumio volumio[4003]: info: Starting Socket.io Server version 2.3.0 Jun 18 13:02:44 volumio volumio[4003]: info: Loading i18n strings for locale zh Jun 18 13:02:44 volumio volumio[4003]: Updating browse sources language Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::initPlayerControls Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:44 volumio volumio[4003]: Express server listening on port 3000 Jun 18 13:02:44 volumio volumio[4003]: [Metrics] WebUI: 1s 682.65ms Jun 18 13:02:44 volumio volumio[4003]: info: Setting Device type: x86 Jun 18 13:02:44 volumio volumio[4003]: info: CoreStateMachine::resetVolumioState Jun 18 13:02:44 volumio volumio[4003]: info: CoreStateMachine::getcurrentVolume Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:44 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:44 volumio volumio[4003]: Starting albumart workers Jun 18 13:02:44 volumio volumio[4003]: Starting albumart workers Jun 18 13:02:44 volumio volumio[4003]: Starting albumart workers Jun 18 13:02:44 volumio volumio[4003]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 18 13:02:44 volumio volumio[4003]: info: Completed loading Core Plugins Jun 18 13:02:44 volumio volumio[4003]: info: Preparing to generate the ALSA configuration file Jun 18 13:02:44 volumio volumio[4003]: info: Volumio Network Manager: Network status updated: 2 Jun 18 13:02:44 volumio volumio[4003]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 18 13:02:45 volumio volumio[4003]: info: Asound.conf file unchanged, so no further update is needed Jun 18 13:02:45 volumio volumio[4003]: info: Output device has changed, restarting MPD Jun 18 13:02:45 volumio sudo[4127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:45 volumio sudo[4127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:45 volumio volumio[4003]: info: Output device has changed, restarting Shairport Sync Jun 18 13:02:45 volumio sudo[4127]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:45 volumio sudo[4130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:45 volumio sudo[4130]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:45 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 13:02:45 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:45 volumio volumio[4003]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:45 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:45 volumio volumio[4003]: info: ___________ START PLUGINS ___________ Jun 18 13:02:45 volumio volumio[4003]: info: ControllerMpd::onStart: Initializing MPD Jun 18 13:02:45 volumio volumio[4003]: info: Creating MPD Configuration file Jun 18 13:02:45 volumio sudo[4137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:45 volumio sudo[4137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:45 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:45 volumio volumio[4003]: info: [1750251765053] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:45 volumio sudo[4137]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:45 volumio sudo[4142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:45 volumio sudo[4142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:45 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 13:02:45 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:45 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:45 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:45 volumio volumio[4003]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:45 volumio volumio[4003]: info: [1750251765099] CoreMusicLibrary::Adding element Last_100 Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:45 volumio volumio[4003]: info: [1750251765100] CoreMusicLibrary::Adding element Webradio Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:45 volumio sudo[4145]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 13:02:45 volumio sudo[4145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:45 volumio volumio[4003]: info: Initializing BBC Radios Jun 18 13:02:45 volumio sudo[4145]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: Volumio Calling Home Jun 18 13:02:45 volumio volumio[4003]: info: CoreStateMachine::setRepeat null single undefined Jun 18 13:02:45 volumio volumio[4003]: info: CoreStateMachine::pushState Jun 18 13:02:45 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:45 volumio volumio[4003]: info: CoreStateMachine::setRandom null Jun 18 13:02:45 volumio volumio[4003]: info: CoreStateMachine::pushState Jun 18 13:02:45 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:45 volumio volumio[4003]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:45 volumio volumio[4003]: info: CoreStateMachine::pushState Jun 18 13:02:45 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:45 volumio volumio[4003]: info: CoreStateMachine::updateTrackBlock Jun 18 13:02:45 volumio volumio[4003]: info: CorePlayQueue::getTrackBlock Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:45 volumio volumio[4003]: info: MPD Permissions set Jun 18 13:02:45 volumio volumio[4003]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 18 13:02:45 volumio volumio[4003]: info: Reloading queue from file Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:45 volumio volumio[4003]: info: Starting Shairport Sync Jun 18 13:02:45 volumio volumio[4003]: info: Starting Shairport Sync Jun 18 13:02:45 volumio sudo[4181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:45 volumio sudo[4181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:45 volumio volumio[4003]: info: Starting Shairport Sync Jun 18 13:02:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:45 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:45 volumio sudo[4184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:45 volumio sudo[4184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:45 volumio sudo[4187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:45 volumio sudo[4187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:45 volumio sudo[4181]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:45 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:45 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 13:02:45 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:45 volumio volumio[4003]: info: Shairport-Sync Started Jun 18 13:02:45 volumio sudo[4187]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:45 volumio sudo[4184]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:45 volumio volumio[4003]: Error adding Membership: Error: addMembership EINVAL Jun 18 13:02:45 volumio volumio[4003]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:45 volumio volumio[4003]: info: CoreStateMachine::pushState Jun 18 13:02:45 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:45 volumio volumio[4003]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:45 volumio volumio[4003]: info: Shairport-Sync Started Jun 18 13:02:45 volumio volumio[4003]: info: Shairport-Sync Started Jun 18 13:02:45 volumio mpd[4152]: Jun 18 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 13:02:45 volumio systemd[1]: Started Music Player Daemon. Jun 18 13:02:45 volumio sudo[4130]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:45 volumio sudo[4142]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:45 volumio volumio[4003]: info: Completed starting Core Plugins Jun 18 13:02:45 volumio volumio[4003]: info: ------------------------------------------- Jun 18 13:02:45 volumio volumio[4003]: info: ----- MyVolumio plugins startup ---- Jun 18 13:02:45 volumio volumio[4003]: info: ------------------------------------------- Jun 18 13:02:45 volumio volumio[4003]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 13:02:45 volumio volumio[4003]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:02:45 volumio volumio[4003]: assert.ok(self.idling) Jun 18 13:02:45 volumio volumio[4003]: error: The expression evaluated to a falsy value: Jun 18 13:02:45 volumio volumio[4003]: assert.ok(self.idling) Jun 18 13:02:45 volumio volumio[4003]: error: updateQueue error: null Jun 18 13:02:45 volumio volumio[4003]: info: MPD running with PID4152 Jun 18 13:02:45 volumio volumio[4003]: ,establishing connection Jun 18 13:02:45 volumio volumio[4003]: error: updateQueue error: null Jun 18 13:02:45 volumio sudo[4041]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:46 volumio volumio[4003]: info: Discovery: adding a81651f1-8cc0-4310-a134-18e897fb72eb Jun 18 13:02:46 volumio volumio[4003]: info: Discovery: Found device Volumio Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:46 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:46 volumio volumio[4003]: info: Volumio called home Jun 18 13:02:46 volumio volumio[4003]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:46 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:46 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 13:02:46 volumio volumio[4003]: info: Received Get System Info Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 13:02:46 volumio volumio[4003]: info: Discovery: Getting this device information Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:46 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:46 volumio volumio[4003]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:46 volumio volumio[4003]: info: Listing playlists Jun 18 13:02:46 volumio volumio[4003]: info: CoreCommandRouter::volumioGetQueue Jun 18 13:02:46 volumio volumio[4003]: info: CoreStateMachine::getQueue Jun 18 13:02:46 volumio volumio[4003]: info: CorePlayQueue::getQueue Jun 18 13:02:47 volumio volumio-remote-updater[829]: [2025-06-18 13:02:47] [connect] Successful connection Jun 18 13:02:47 volumio volumio-remote-updater[829]: [2025-06-18 13:02:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750251767 101 Jun 18 13:02:47 volumio volumio[4003]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jun 18 13:02:48 volumio volumio[4003]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9C%AC%E5%85%AE/dda7c0a3-8332-48ab-b57f-5fdeb5b2b906.jpg' Jun 18 13:02:48 volumio volumio[4003]: info: Cannot mount NAS 8100 at system boot, trial number 2 ,retrying in 5 seconds Jun 18 13:02:49 volumio volumio[4003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:49 volumio volumio[4003]: Error: connect ECONNREFUSED 31.13.69.245:443 Jun 18 13:02:49 volumio volumio[4003]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 18 13:02:49 volumio volumio[4003]: errno: -111, Jun 18 13:02:49 volumio volumio[4003]: code: 'ECONNREFUSED', Jun 18 13:02:49 volumio volumio[4003]: syscall: 'connect', Jun 18 13:02:49 volumio volumio[4003]: address: '31.13.69.245', Jun 18 13:02:49 volumio volumio[4003]: port: 443 Jun 18 13:02:49 volumio volumio[4003]: } Jun 18 13:02:49 volumio volumio[4003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:02:49 volumio sudo[4209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 13:01 Jun 18 13:02:49 volumio sudo[4209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:49 volumio sudo[4209]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:49 volumio volumio-remote-updater[829]: [2025-06-18 13:02:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 13:02:49 volumio volumio-remote-updater[829]: [2025-06-18 13:02:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 13:02:49 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 18 13:02:49 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 18 13:02:49 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:49 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:50 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 13:02:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Jun 18 13:02:50 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 13:02:50 volumio systemd[1]: Started Volumio Backend Module. Jun 18 13:02:50 volumio systemd[1]: Started dynamicswap service. Jun 18 13:02:50 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:02:50 volumio volumio[4219]: info: ------------------------------------------- Jun 18 13:02:50 volumio volumio[4219]: info: ----- Volumio3 ---- Jun 18 13:02:50 volumio volumio[4219]: info: ------------------------------------------- Jun 18 13:02:50 volumio volumio[4219]: info: ----- System startup ---- Jun 18 13:02:50 volumio volumio[4219]: info: ------------------------------------------- Jun 18 13:02:50 volumio volumio[4219]: info: MYVOLUMIO Environment detected Jun 18 13:02:50 volumio volumio[4219]: info: Plugin folders cleanup Jun 18 13:02:50 volumio volumio[4219]: info: Scanning into folder /volumio/app/plugins/ Jun 18 13:02:50 volumio volumio[4219]: info: Scanning category audio_interface Jun 18 13:02:50 volumio volumio[4219]: info: Scanning category miscellanea Jun 18 13:02:50 volumio volumio[4219]: info: Scanning category music_service Jun 18 13:02:50 volumio volumio[4219]: info: Scanning category plugins.json Jun 18 13:02:50 volumio volumio[4219]: info: Scanning category system_controller Jun 18 13:02:50 volumio volumio[4219]: info: Scanning category user_interface Jun 18 13:02:50 volumio volumio[4219]: info: Scanning into folder /data/plugins/ Jun 18 13:02:50 volumio volumio[4219]: info: Plugin folders cleanup completed Jun 18 13:02:50 volumio volumio[4219]: info: ------------------------------------------- Jun 18 13:02:50 volumio volumio[4219]: info: ----- Core plugins startup ---- Jun 18 13:02:50 volumio volumio[4219]: info: ------------------------------------------- Jun 18 13:02:50 volumio volumio[4219]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 13:02:50 volumio volumio[4219]: info: Adding plugin upnp to MyMusic Plugins Jun 18 13:02:50 volumio volumio[4219]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 13:02:50 volumio volumio[4219]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 13:02:50 volumio volumio[4219]: info: Loading plugins from folder /data/plugins/ Jun 18 13:02:50 volumio volumio[4219]: info: Loading plugin "system"... Jun 18 13:02:50 volumio volumio[4219]: info: Loading plugin "appearance"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "network"... Jun 18 13:02:51 volumio volumio[4219]: info: Refreshing Cached IP Addresses Jun 18 13:02:51 volumio sudo[4246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 13:02:51 volumio sudo[4246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:51 volumio sudo[4246]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:51 volumio sudo[4248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 13:02:51 volumio sudo[4248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:51 volumio sudo[4248]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "services"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "alsa_controller"... Jun 18 13:02:51 volumio sudo[4261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 13:02:51 volumio sudo[4261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:51 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "wizard"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "networkfs"... Jun 18 13:02:51 volumio volumio[4219]: info: Cannot mount NAS 8100 at system boot, trial number 1 ,retrying in 5 seconds Jun 18 13:02:51 volumio volumio[4219]: info: Starting Udev Watcher for removable devices Jun 18 13:02:51 volumio volumio[4219]: info: Ignoring mount for partition: boot Jun 18 13:02:51 volumio volumio[4219]: info: Ignoring mount for partition: volumio Jun 18 13:02:51 volumio volumio[4219]: info: Ignoring mount for partition: volumio_data Jun 18 13:02:51 volumio volumio[4219]: info: Ignoring mount for partition: 系统 Jun 18 13:02:51 volumio volumio[4219]: error: Cannot associate FS Label, not mounting Jun 18 13:02:51 volumio volumio[4219]: info: Ignoring mount for partition: 软件 Jun 18 13:02:51 volumio volumio[4219]: info: Ignoring mount for partition: 娱乐 Jun 18 13:02:51 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "volumio_command_line_client"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "upnp"... Jun 18 13:02:51 volumio volumio[4219]: info: [1750251771214] Starting Upmpd Daemon Jun 18 13:02:51 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "my_music"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "mpd"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "upnp_browser"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "alarm-clock"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "airplay_emulation"... Jun 18 13:02:51 volumio volumio[4219]: info: Starting Shairport Sync Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "last_100"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "webradio"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "i2s_dacs"... Jun 18 13:02:51 volumio volumio[4219]: info: I2S DAC not set, start Auto-detection Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "volumiodiscovery"... Jun 18 13:02:51 volumio volumio[4219]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:51 volumio volumio[4219]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:51 volumio volumio[4219]: *** WARNING *** For more information see Jun 18 13:02:51 volumio volumio[4219]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:51 volumio node[4219]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:51 volumio volumio[4219]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:51 volumio volumio[4219]: *** WARNING *** For more information see Jun 18 13:02:51 volumio node[4219]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:51 volumio node[4219]: *** WARNING *** For more information see Jun 18 13:02:51 volumio node[4219]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:02:51 volumio node[4219]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:02:51 volumio node[4219]: *** WARNING *** For more information see Jun 18 13:02:51 volumio volumio[4219]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 13:02:51 volumio volumio[4219]: info: Discovery: Started advertising with name: Volumio Jun 18 13:02:51 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "outputs"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "albumart"... Jun 18 13:02:51 volumio volumio[4219]: info: Plugin example_plugin is not enabled Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "inputs"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "updater_comm"... Jun 18 13:02:51 volumio volumio[4219]: Forking 3 albumart workers Jun 18 13:02:51 volumio volumio[4219]: info: Plugin mpdemulation is not enabled Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "rest_api"... Jun 18 13:02:51 volumio volumio[4219]: info: Loading plugin "websocket"... Jun 18 13:02:51 volumio volumio[4219]: info: Starting Socket.io Server version 2.3.0 Jun 18 13:02:51 volumio volumio[4219]: info: Loading i18n strings for locale zh Jun 18 13:02:51 volumio volumio[4219]: Updating browse sources language Jun 18 13:02:51 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::initPlayerControls Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: Express server listening on port 3000 Jun 18 13:02:52 volumio volumio[4219]: [Metrics] WebUI: 1s 547.74ms Jun 18 13:02:52 volumio volumio[4219]: info: Setting Device type: x86 Jun 18 13:02:52 volumio volumio[4219]: info: CoreStateMachine::resetVolumioState Jun 18 13:02:52 volumio volumio[4219]: info: CoreStateMachine::getcurrentVolume Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:52 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:52 volumio volumio[4219]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 18 13:02:52 volumio volumio[4219]: info: Completed loading Core Plugins Jun 18 13:02:52 volumio volumio[4219]: info: Preparing to generate the ALSA configuration file Jun 18 13:02:52 volumio volumio[4219]: info: Volumio Network Manager: Network status updated: 2 Jun 18 13:02:52 volumio volumio[4219]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 18 13:02:52 volumio volumio[4219]: Starting albumart workers Jun 18 13:02:52 volumio volumio[4219]: Starting albumart workers Jun 18 13:02:52 volumio volumio[4219]: Starting albumart workers Jun 18 13:02:53 volumio sudo[4261]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio volumio[4219]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:54 volumio volumio[4219]: info: CoreStateMachine::pushState Jun 18 13:02:54 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:54 volumio volumio[4219]: info: CoreStateMachine::updateTrackBlock Jun 18 13:02:54 volumio volumio[4219]: info: CorePlayQueue::getTrackBlock Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:02:54 volumio volumio[4219]: info: Asound.conf file unchanged, so no further update is needed Jun 18 13:02:54 volumio volumio[4219]: info: Output device has changed, restarting MPD Jun 18 13:02:54 volumio sudo[4345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:54 volumio sudo[4345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:54 volumio sudo[4345]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio volumio[4219]: info: Output device has changed, restarting Shairport Sync Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:54 volumio sudo[4348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:54 volumio sudo[4348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:54 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 13:02:54 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:54 volumio volumio[4219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:54 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:54 volumio volumio[4219]: info: ___________ START PLUGINS ___________ Jun 18 13:02:54 volumio volumio[4219]: info: ControllerMpd::onStart: Initializing MPD Jun 18 13:02:54 volumio volumio[4219]: info: Creating MPD Configuration file Jun 18 13:02:54 volumio sudo[4355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:02:54 volumio sudo[4355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:54 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:54 volumio volumio[4219]: info: [1750251774274] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:54 volumio sudo[4355]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio sudo[4358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:02:54 volumio sudo[4358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:54 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 13:02:54 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:02:54 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:02:54 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:02:54 volumio volumio[4219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:54 volumio volumio[4219]: info: [1750251774319] CoreMusicLibrary::Adding element Last_100 Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:02:54 volumio volumio[4219]: info: [1750251774321] CoreMusicLibrary::Adding element Webradio Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:54 volumio sudo[4361]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 13:02:54 volumio sudo[4361]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:54 volumio volumio[4219]: info: Initializing BBC Radios Jun 18 13:02:54 volumio sudo[4361]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: Volumio Calling Home Jun 18 13:02:54 volumio volumio[4219]: info: CoreStateMachine::setRepeat null single undefined Jun 18 13:02:54 volumio volumio[4219]: info: CoreStateMachine::pushState Jun 18 13:02:54 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:54 volumio volumio[4219]: info: CoreStateMachine::setRandom null Jun 18 13:02:54 volumio volumio[4219]: info: CoreStateMachine::pushState Jun 18 13:02:54 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:54 volumio volumio[4219]: info: MPD Permissions set Jun 18 13:02:54 volumio volumio[4219]: info: MPD Permissions set Jun 18 13:02:54 volumio volumio[4219]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 18 13:02:54 volumio volumio[4219]: info: Reloading queue from file Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:02:54 volumio volumio[4219]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:02:54 volumio volumio[4219]: info: CoreStateMachine::pushState Jun 18 13:02:54 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioPushState Jun 18 13:02:54 volumio volumio[4219]: info: Starting Shairport Sync Jun 18 13:02:54 volumio volumio[4219]: info: Starting Shairport Sync Jun 18 13:02:54 volumio sudo[4397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:54 volumio sudo[4397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:54 volumio volumio[4219]: info: Starting Shairport Sync Jun 18 13:02:54 volumio sudo[4400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:54 volumio sudo[4400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:54 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:54 volumio sudo[4403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:02:54 volumio sudo[4403]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:54 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:54 volumio volumio[4219]: info: Discovery: adding a81651f1-8cc0-4310-a134-18e897fb72eb Jun 18 13:02:54 volumio volumio[4219]: info: Discovery: Found device Volumio Jun 18 13:02:54 volumio volumio[4219]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:54 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:54 volumio sudo[4400]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio sudo[4397]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio volumio[4219]: info: Shairport-Sync Started Jun 18 13:02:54 volumio volumio[4219]: Error adding Membership: Error: addMembership EINVAL Jun 18 13:02:54 volumio volumio[4219]: info: Shairport-Sync Started Jun 18 13:02:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:02:54 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 13:02:54 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:02:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:02:54 volumio sudo[4403]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio volumio[4219]: info: Shairport-Sync Started Jun 18 13:02:54 volumio mpd[4369]: Jun 18 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 13:02:54 volumio systemd[1]: Started Music Player Daemon. Jun 18 13:02:54 volumio sudo[4358]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio sudo[4348]: pam_unix(sudo:session): session closed for user root Jun 18 13:02:54 volumio volumio[4219]: info: Completed starting Core Plugins Jun 18 13:02:54 volumio volumio[4219]: info: ------------------------------------------- Jun 18 13:02:54 volumio volumio[4219]: info: ----- MyVolumio plugins startup ---- Jun 18 13:02:54 volumio volumio[4219]: info: ------------------------------------------- Jun 18 13:02:54 volumio volumio[4219]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 13:02:54 volumio volumio[4219]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:02:54 volumio volumio[4219]: assert.ok(self.idling) Jun 18 13:02:54 volumio volumio[4219]: error: The expression evaluated to a falsy value: Jun 18 13:02:54 volumio volumio[4219]: assert.ok(self.idling) Jun 18 13:02:54 volumio volumio[4219]: error: updateQueue error: null Jun 18 13:02:54 volumio volumio[4219]: info: MPD running with PID4369 Jun 18 13:02:54 volumio volumio[4219]: ,establishing connection Jun 18 13:02:54 volumio volumio[4219]: error: updateQueue error: null Jun 18 13:02:54 volumio volumio[4219]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 13:02:54 volumio volumio[4219]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 18 13:02:54 volumio volumio-remote-updater[829]: [2025-06-18 13:02:54] [connect] Successful connection Jun 18 13:02:55 volumio volumio[4219]: info: Volumio called home Jun 18 13:02:55 volumio volumio-remote-updater[829]: [2025-06-18 13:02:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750251774 101 Jun 18 13:02:55 volumio volumio[4219]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jun 18 13:02:55 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:56 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:56 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 13:02:56 volumio volumio[4219]: info: Received Get System Info Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 13:02:56 volumio volumio[4219]: info: Discovery: Getting this device information Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:56 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::volumioGetState Jun 18 13:02:56 volumio volumio[4219]: info: CorePlayQueue::getTrack 0 Jun 18 13:02:56 volumio volumio[4219]: info: Listing playlists Jun 18 13:02:56 volumio volumio[4219]: info: CoreCommandRouter::volumioGetQueue Jun 18 13:02:56 volumio volumio[4219]: info: CoreStateMachine::getQueue Jun 18 13:02:56 volumio volumio[4219]: info: CorePlayQueue::getQueue Jun 18 13:02:56 volumio volumio[4219]: info: Cannot mount NAS 8100 at system boot, trial number 2 ,retrying in 5 seconds Jun 18 13:03:01 volumio sudo[4429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 13:03:01 volumio sudo[4429]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:01 volumio sudo[4429]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:01 volumio volumio[4219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:03:01 volumio sudo[4432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 13:03:01 volumio sudo[4432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:01 volumio sudo[4432]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:01 volumio volumio[4219]: Error: connect ECONNREFUSED 31.13.69.245:80 Jun 18 13:03:01 volumio volumio[4219]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 18 13:03:01 volumio volumio[4219]: errno: -111, Jun 18 13:03:01 volumio volumio[4219]: code: 'ECONNREFUSED', Jun 18 13:03:01 volumio volumio[4219]: syscall: 'connect', Jun 18 13:03:01 volumio volumio[4219]: address: '31.13.69.245', Jun 18 13:03:01 volumio volumio[4219]: port: 80 Jun 18 13:03:01 volumio volumio[4219]: } Jun 18 13:03:01 volumio volumio[4219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:03:01 volumio sudo[4443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 13:02 Jun 18 13:03:01 volumio sudo[4443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:01 volumio sudo[4443]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 18 13:03:03 volumio volumio-remote-updater[829]: [2025-06-18 13:03:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 18 13:03:03 volumio volumio-remote-updater[829]: [2025-06-18 13:03:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 18 13:03:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 18 13:03:03 volumio systemd[1]: Started dynamicswap service. Jun 18 13:03:03 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:03:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 18 13:03:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Jun 18 13:03:03 volumio systemd[1]: Stopped Volumio Backend Module. Jun 18 13:03:03 volumio systemd[1]: Started Volumio Backend Module. Jun 18 13:03:03 volumio systemd[1]: Started dynamicswap service. Jun 18 13:03:03 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 18 13:03:04 volumio volumio[4456]: info: ------------------------------------------- Jun 18 13:03:04 volumio volumio[4456]: info: ----- Volumio3 ---- Jun 18 13:03:04 volumio volumio[4456]: info: ------------------------------------------- Jun 18 13:03:04 volumio volumio[4456]: info: ----- System startup ---- Jun 18 13:03:04 volumio volumio[4456]: info: ------------------------------------------- Jun 18 13:03:04 volumio volumio[4456]: info: MYVOLUMIO Environment detected Jun 18 13:03:04 volumio volumio[4456]: info: Plugin folders cleanup Jun 18 13:03:04 volumio volumio[4456]: info: Scanning into folder /volumio/app/plugins/ Jun 18 13:03:04 volumio volumio[4456]: info: Scanning category audio_interface Jun 18 13:03:04 volumio volumio[4456]: info: Scanning category miscellanea Jun 18 13:03:04 volumio volumio[4456]: info: Scanning category music_service Jun 18 13:03:04 volumio volumio[4456]: info: Scanning category plugins.json Jun 18 13:03:04 volumio volumio[4456]: info: Scanning category system_controller Jun 18 13:03:04 volumio volumio[4456]: info: Scanning category user_interface Jun 18 13:03:04 volumio volumio[4456]: info: Scanning into folder /data/plugins/ Jun 18 13:03:04 volumio volumio[4456]: info: Plugin folders cleanup completed Jun 18 13:03:04 volumio volumio[4456]: info: ------------------------------------------- Jun 18 13:03:04 volumio volumio[4456]: info: ----- Core plugins startup ---- Jun 18 13:03:04 volumio volumio[4456]: info: ------------------------------------------- Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 13:03:04 volumio volumio[4456]: info: Adding plugin upnp to MyMusic Plugins Jun 18 13:03:04 volumio volumio[4456]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 18 13:03:04 volumio volumio[4456]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugins from folder /data/plugins/ Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "system"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "appearance"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "network"... Jun 18 13:03:04 volumio volumio[4456]: info: Refreshing Cached IP Addresses Jun 18 13:03:04 volumio sudo[4484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 13:03:04 volumio sudo[4484]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:04 volumio sudo[4484]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:04 volumio sudo[4486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 13:03:04 volumio sudo[4486]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:04 volumio sudo[4486]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "services"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "alsa_controller"... Jun 18 13:03:04 volumio sudo[4499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 13:03:04 volumio sudo[4499]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:04 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "wizard"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "networkfs"... Jun 18 13:03:04 volumio volumio[4456]: info: Cannot mount NAS 8100 at system boot, trial number 1 ,retrying in 5 seconds Jun 18 13:03:04 volumio volumio[4456]: info: Starting Udev Watcher for removable devices Jun 18 13:03:04 volumio volumio[4456]: info: Ignoring mount for partition: boot Jun 18 13:03:04 volumio volumio[4456]: info: Ignoring mount for partition: volumio Jun 18 13:03:04 volumio volumio[4456]: info: Ignoring mount for partition: volumio_data Jun 18 13:03:04 volumio volumio[4456]: info: Ignoring mount for partition: 系统 Jun 18 13:03:04 volumio volumio[4456]: error: Cannot associate FS Label, not mounting Jun 18 13:03:04 volumio volumio[4456]: info: Ignoring mount for partition: 软件 Jun 18 13:03:04 volumio volumio[4456]: info: Ignoring mount for partition: 娱乐 Jun 18 13:03:04 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "volumio_command_line_client"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "upnp"... Jun 18 13:03:04 volumio volumio[4456]: info: [1750251784711] Starting Upmpd Daemon Jun 18 13:03:04 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "my_music"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "mpd"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "upnp_browser"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "alarm-clock"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "airplay_emulation"... Jun 18 13:03:04 volumio volumio[4456]: info: Starting Shairport Sync Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "last_100"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "webradio"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "i2s_dacs"... Jun 18 13:03:04 volumio volumio[4456]: info: I2S DAC not set, start Auto-detection Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "volumiodiscovery"... Jun 18 13:03:04 volumio volumio[4456]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:03:04 volumio volumio[4456]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:03:04 volumio node[4456]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 13:03:04 volumio volumio[4456]: *** WARNING *** For more information see Jun 18 13:03:04 volumio volumio[4456]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:03:04 volumio volumio[4456]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:03:04 volumio volumio[4456]: *** WARNING *** For more information see Jun 18 13:03:04 volumio node[4456]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:03:04 volumio node[4456]: *** WARNING *** For more information see Jun 18 13:03:04 volumio node[4456]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 13:03:04 volumio node[4456]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 13:03:04 volumio node[4456]: *** WARNING *** For more information see Jun 18 13:03:04 volumio volumio[4456]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 13:03:04 volumio volumio[4456]: info: Discovery: Started advertising with name: Volumio Jun 18 13:03:04 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "outputs"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "albumart"... Jun 18 13:03:04 volumio volumio[4456]: info: Plugin example_plugin is not enabled Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "inputs"... Jun 18 13:03:04 volumio volumio[4456]: info: Loading plugin "updater_comm"... Jun 18 13:03:05 volumio volumio[4456]: info: Plugin mpdemulation is not enabled Jun 18 13:03:05 volumio volumio[4456]: info: Loading plugin "rest_api"... Jun 18 13:03:05 volumio volumio[4456]: info: Loading plugin "websocket"... Jun 18 13:03:05 volumio volumio[4456]: Forking 3 albumart workers Jun 18 13:03:05 volumio volumio[4456]: info: Starting Socket.io Server version 2.3.0 Jun 18 13:03:05 volumio volumio[4456]: info: Loading i18n strings for locale zh Jun 18 13:03:05 volumio volumio[4456]: Updating browse sources language Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::initPlayerControls Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: Express server listening on port 3000 Jun 18 13:03:05 volumio volumio[4456]: [Metrics] WebUI: 1s 201.23ms Jun 18 13:03:05 volumio volumio[4456]: info: Setting Device type: x86 Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::resetVolumioState Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::getcurrentVolume Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 18 13:03:05 volumio volumio[4456]: info: Completed loading Core Plugins Jun 18 13:03:05 volumio volumio[4456]: info: Preparing to generate the ALSA configuration file Jun 18 13:03:05 volumio volumio[4456]: info: Volumio Network Manager: Network status updated: 2 Jun 18 13:03:05 volumio volumio[4456]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 18 13:03:05 volumio volumio[4456]: Starting albumart workers Jun 18 13:03:05 volumio volumio[4456]: Starting albumart workers Jun 18 13:03:05 volumio volumio[4456]: Starting albumart workers Jun 18 13:03:05 volumio volumio[4456]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::pushState Jun 18 13:03:05 volumio volumio[4456]: info: CorePlayQueue::getTrack 0 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioPushState Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::updateTrackBlock Jun 18 13:03:05 volumio volumio[4456]: info: CorePlayQueue::getTrackBlock Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 13:03:05 volumio volumio[4456]: info: Asound.conf file unchanged, so no further update is needed Jun 18 13:03:05 volumio volumio[4456]: info: Output device has changed, restarting MPD Jun 18 13:03:05 volumio sudo[4585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:03:05 volumio sudo[4585]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:05 volumio volumio[4456]: info: Output device has changed, restarting Shairport Sync Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:03:05 volumio sudo[4585]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:05 volumio sudo[4588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:03:05 volumio sudo[4588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:05 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 13:03:05 volumio volumio[4456]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:03:05 volumio volumio[4456]: info: ___________ START PLUGINS ___________ Jun 18 13:03:05 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:03:05 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:03:05 volumio volumio[4456]: info: ControllerMpd::onStart: Initializing MPD Jun 18 13:03:05 volumio volumio[4456]: info: Creating MPD Configuration file Jun 18 13:03:05 volumio sudo[4595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 13:03:05 volumio sudo[4595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:05 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:03:05 volumio volumio[4456]: info: [1750251785600] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:03:05 volumio sudo[4595]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:05 volumio sudo[4598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 13:03:05 volumio sudo[4598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:03:05 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 13:03:05 volumio systemd[1]: mpd.service: Succeeded. Jun 18 13:03:05 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 13:03:05 volumio systemd[1]: Starting Music Player Daemon... Jun 18 13:03:05 volumio volumio[4456]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:03:05 volumio volumio[4456]: info: [1750251785643] CoreMusicLibrary::Adding element Last_100 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 13:03:05 volumio volumio[4456]: info: [1750251785644] CoreMusicLibrary::Adding element Webradio Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:03:05 volumio volumio[4456]: info: Initializing BBC Radios Jun 18 13:03:05 volumio sudo[4601]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 13:03:05 volumio sudo[4601]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:05 volumio sudo[4601]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: Volumio Calling Home Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::setRepeat null single undefined Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::pushState Jun 18 13:03:05 volumio volumio[4456]: info: CorePlayQueue::getTrack 0 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioPushState Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::setRandom null Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::pushState Jun 18 13:03:05 volumio volumio[4456]: info: CorePlayQueue::getTrack 0 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioPushState Jun 18 13:03:05 volumio volumio[4456]: info: MPD Permissions set Jun 18 13:03:05 volumio volumio[4456]: info: MPD Permissions set Jun 18 13:03:05 volumio volumio[4456]: info: Reloading queue from file Jun 18 13:03:05 volumio volumio[4456]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 13:03:05 volumio volumio[4456]: info: VolumeController:: Volume=51 Mute =false Jun 18 13:03:05 volumio volumio[4456]: info: CoreStateMachine::pushState Jun 18 13:03:05 volumio volumio[4456]: info: CorePlayQueue::getTrack 0 Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioPushState Jun 18 13:03:05 volumio volumio[4456]: info: Starting Shairport Sync Jun 18 13:03:05 volumio volumio[4456]: info: Starting Shairport Sync Jun 18 13:03:05 volumio sudo[4637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:03:05 volumio sudo[4637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:05 volumio volumio[4456]: info: Starting Shairport Sync Jun 18 13:03:05 volumio sudo[4639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:03:05 volumio sudo[4639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:03:05 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:03:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:03:05 volumio sudo[4643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 13:03:05 volumio sudo[4643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioGetState Jun 18 13:03:05 volumio volumio[4456]: info: CorePlayQueue::getTrack 0 Jun 18 13:03:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:03:05 volumio sudo[4637]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 13:03:05 volumio sudo[4639]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:05 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 18 13:03:05 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 13:03:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 13:03:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 13:03:05 volumio volumio[4456]: info: Shairport-Sync Started Jun 18 13:03:05 volumio sudo[4643]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:05 volumio volumio[4456]: Error adding Membership: Error: addMembership EINVAL Jun 18 13:03:05 volumio volumio[4456]: info: Shairport-Sync Started Jun 18 13:03:05 volumio volumio[4456]: info: Discovery: adding a81651f1-8cc0-4310-a134-18e897fb72eb Jun 18 13:03:05 volumio volumio[4456]: info: Discovery: Found device Volumio Jun 18 13:03:05 volumio volumio[4456]: info: CoreCommandRouter::volumioGetState Jun 18 13:03:05 volumio volumio[4456]: info: CorePlayQueue::getTrack 0 Jun 18 13:03:05 volumio volumio[4456]: info: Shairport-Sync Started Jun 18 13:03:05 volumio volumio[4456]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 18 13:03:05 volumio mpd[4609]: Jun 18 13:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 13:03:05 volumio systemd[1]: Started Music Player Daemon. Jun 18 13:03:05 volumio sudo[4598]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:05 volumio sudo[4588]: pam_unix(sudo:session): session closed for user root Jun 18 13:03:06 volumio volumio[4456]: info: Completed starting Core Plugins Jun 18 13:03:06 volumio volumio[4456]: info: ------------------------------------------- Jun 18 13:03:06 volumio volumio[4456]: info: ----- MyVolumio plugins startup ---- Jun 18 13:03:06 volumio volumio[4456]: info: ------------------------------------------- Jun 18 13:03:06 volumio volumio[4456]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 13:03:06 volumio volumio[4456]: error: MPD error: The expression evaluated to a falsy value: Jun 18 13:03:06 volumio volumio[4456]: assert.ok(self.idling) Jun 18 13:03:06 volumio volumio[4456]: error: The expression evaluated to a falsy value: Jun 18 13:03:06 volumio volumio[4456]: assert.ok(self.idling) Jun 18 13:03:06 volumio volumio[4456]: error: updateQueue error: null Jun 18 13:03:06 volumio volumio[4456]: info: MPD running with PID4609 Jun 18 13:03:06 volumio volumio[4456]: ,establishing connection Jun 18 13:03:06 volumio volumio[4456]: error: updateQueue error: null Jun 18 13:03:06 volumio volumio[4456]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:03:06 volumio volumio[4456]: Error: connect ECONNREFUSED 31.13.69.245:443 Jun 18 13:03:06 volumio volumio[4456]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 18 13:03:06 volumio volumio[4456]: errno: -111, Jun 18 13:03:06 volumio volumio[4456]: code: 'ECONNREFUSED', Jun 18 13:03:06 volumio volumio[4456]: syscall: 'connect', Jun 18 13:03:06 volumio volumio[4456]: address: '31.13.69.245', Jun 18 13:03:06 volumio volumio[4456]: port: 443 Jun 18 13:03:06 volumio volumio[4456]: } Jun 18 13:03:06 volumio volumio[4456]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 13:03:06 volumio sudo[4662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 13:02 Jun 18 13:03:06 volumio sudo[4662]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:21:23 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a9588e47e4b05f1a05bf70b4acc7555c"