-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sun 2025-12-28 22:48:23 CST. -- Dec 28 22:47:05 volumio volumio[855]: info: CoreCommandRouter::volumioPrevious Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::previous Dec 28 22:47:05 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:05 volumio volumio[855]: info: ControllerMpd::seek Dec 28 22:47:05 volumio volumio[855]: error: null Dec 28 22:47:05 volumio volumio[855]: info: Dec 28 22:47:05 volumio volumio[855]: ---------------------------- MPD announces state update: player Dec 28 22:47:05 volumio volumio[855]: info: ControllerMpd::getState Dec 28 22:47:05 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Dec 28 22:47:05 volumio volumio[855]: info: Dec 28 22:47:05 volumio volumio[855]: ---------------------------- MPD announces state update: player Dec 28 22:47:05 volumio volumio[855]: info: ControllerMpd::getState Dec 28 22:47:05 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Dec 28 22:47:05 volumio volumio[855]: info: sendMpdCommand status took 4 milliseconds Dec 28 22:47:05 volumio volumio[855]: info: sendMpdCommand status took 2 milliseconds Dec 28 22:47:05 volumio volumio[855]: verbose: ControllerMpd::parseState Dec 28 22:47:05 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 22:47:05 volumio volumio[855]: verbose: ControllerMpd::parseState Dec 28 22:47:05 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 22:47:05 volumio volumio[855]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 22:47:05 volumio volumio[855]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 22:47:05 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Dec 28 22:47:05 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Dec 28 22:47:05 volumio volumio[855]: info: ControllerMpd::pushState Dec 28 22:47:05 volumio volumio[855]: info: CoreCommandRouter::servicePushState Dec 28 22:47:05 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:05 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1829 Kbps","isStreaming":false,"title":"月夜","artist":"布仁巴雅尔","album":"《天边》布仁巴雅尔特别纪念版","uri":"USB/峰的音乐/《天边》布仁巴雅尔特别纪念版/02.月夜.flac","trackType":"flac"} Dec 28 22:47:05 volumio volumio[855]: verbose: CURRENT POSITION 0 Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Dec 28 22:47:05 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::pushState Dec 28 22:47:05 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:05 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::pushState Dec 28 22:47:05 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:05 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Dec 28 22:47:05 volumio volumio[855]: info: ControllerMpd::pushState Dec 28 22:47:05 volumio volumio[855]: info: CoreCommandRouter::servicePushState Dec 28 22:47:05 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:05 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1829 Kbps","isStreaming":false,"title":"月夜","artist":"布仁巴雅尔","album":"《天边》布仁巴雅尔特别纪念版","uri":"USB/峰的音乐/《天边》布仁巴雅尔特别纪念版/02.月夜.flac","trackType":"flac"} Dec 28 22:47:05 volumio volumio[855]: verbose: CURRENT POSITION 0 Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Dec 28 22:47:05 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::pushState Dec 28 22:47:05 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:05 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Dec 28 22:47:05 volumio volumio[855]: info: CoreStateMachine::pushState Dec 28 22:47:05 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:05 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Dec 28 22:47:05 volumio volumio[855]: info: ------------------------------ 57ms Dec 28 22:47:05 volumio volumio[855]: info: ------------------------------ 56ms Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 22:47:16 volumio volumio[855]: info: Retrieving Cloud Streaming UI Dec 28 22:47:16 volumio volumio[855]: info: Getting Tidal Cloud Configuration Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 22:47:16 volumio volumio[855]: info: Getting Qobuz Cloud Configuration Dec 28 22:47:16 volumio volumio[855]: info: Asking plugin for UI Config Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 22:47:16 volumio volumio[855]: info: Getting Spotify Cloud Configuration Dec 28 22:47:16 volumio volumio[855]: info: Asking plugin for UI Config Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 22:47:16 volumio volumio[855]: info: Saving Spotify Acccount Dec 28 22:47:16 volumio volumio[855]: info: Got Tidal Cloud Configuration Dec 28 22:47:16 volumio volumio[855]: info: Got it Dec 28 22:47:16 volumio volumio[855]: info: Got it Dec 28 22:47:16 volumio volumio[855]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 22:47:16 volumio volumio[855]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 28 22:47:20 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:24 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:28 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:32 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:36 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand rescan Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Dec 28 22:47:38 volumio volumio[855]: info: Dec 28 22:47:38 volumio volumio[855]: ---------------------------- MPD announces state update: update Dec 28 22:47:38 volumio volumio[855]: info: sendMpdCommand rescan took 4 milliseconds Dec 28 22:47:38 volumio volumio[855]: info: ControllerMpd::getState Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Dec 28 22:47:38 volumio volumio[855]: info: sendMpdCommand status took 3 milliseconds Dec 28 22:47:38 volumio volumio[855]: info: sendMpdCommand status took 2 milliseconds Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::parseState Dec 28 22:47:38 volumio volumio[855]: info: Command Router : Notfying DB Updatetrue Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::Close All Modals sent Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::parseState Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Dec 28 22:47:38 volumio volumio[855]: info: Dec 28 22:47:38 volumio volumio[855]: ---------------------------- MPD announces state update: update Dec 28 22:47:38 volumio volumio[855]: info: ControllerMpd::getState Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Dec 28 22:47:38 volumio volumio[855]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 28 22:47:38 volumio volumio[855]: info: sendMpdCommand status took 3 milliseconds Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::parseState Dec 28 22:47:38 volumio volumio[855]: info: Command Router : Notfying DB Updatetrue Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::Close All Modals sent Dec 28 22:47:38 volumio volumio[855]: info: ControllerMpd::pushState Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::servicePushState Dec 28 22:47:38 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:38 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":33484,"duration":234,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1668 Kbps","isStreaming":false,"title":"月夜","artist":"布仁巴雅尔","album":"《天边》布仁巴雅尔特别纪念版","uri":"USB/峰的音乐/《天边》布仁巴雅尔特别纪念版/02.月夜.flac","trackType":"flac"} Dec 28 22:47:38 volumio volumio[855]: verbose: CURRENT POSITION 0 Dec 28 22:47:38 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Dec 28 22:47:38 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Dec 28 22:47:38 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Dec 28 22:47:38 volumio volumio[855]: info: CoreStateMachine::pushState Dec 28 22:47:38 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Dec 28 22:47:38 volumio volumio[855]: info: CoreStateMachine::pushState Dec 28 22:47:38 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Dec 28 22:47:38 volumio volumio[855]: info: ------------------------------ 138ms Dec 28 22:47:38 volumio volumio[855]: info: sendMpdCommand status took 90 milliseconds Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::parseState Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 22:47:38 volumio volumio[855]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 22:47:38 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Dec 28 22:47:38 volumio volumio[855]: info: ControllerMpd::pushState Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::servicePushState Dec 28 22:47:38 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:38 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":33484,"duration":234,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1668 Kbps","isStreaming":false,"title":"月夜","artist":"布仁巴雅尔","album":"《天边》布仁巴雅尔特别纪念版","uri":"USB/峰的音乐/《天边》布仁巴雅尔特别纪念版/02.月夜.flac","trackType":"flac"} Dec 28 22:47:38 volumio volumio[855]: verbose: CURRENT POSITION 0 Dec 28 22:47:38 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Dec 28 22:47:38 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Dec 28 22:47:38 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Dec 28 22:47:38 volumio volumio[855]: info: CoreStateMachine::pushState Dec 28 22:47:38 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Dec 28 22:47:38 volumio volumio[855]: info: CoreStateMachine::pushState Dec 28 22:47:38 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Dec 28 22:47:38 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Dec 28 22:47:38 volumio volumio[855]: info: ------------------------------ 114ms Dec 28 22:47:40 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:44 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:48 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:52 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:47:56 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:48:00 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:48:02 volumio volumio[855]: info: Disabling MyMusic plugin upnp Dec 28 22:48:02 volumio sudo[5785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 28 22:48:02 volumio sudo[5785]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:02 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 28 22:48:02 volumio volumio[855]: error: Upnp client error: Error: This socket has been ended by the other party Dec 28 22:48:04 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:48:05 volumio volumio[855]: info: Enabling MyMusic plugin upnp Dec 28 22:48:05 volumio volumio[855]: info: Enabling plugin upnp Dec 28 22:48:05 volumio volumio[855]: info: Loading plugin "upnp"... Dec 28 22:48:05 volumio volumio[855]: info: [1766933285414] Starting Upmpd Daemon Dec 28 22:48:05 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 22:48:05 volumio volumio[855]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 22:48:05 volumio volumio[855]: Error: listen EADDRINUSE: address already in use :::6599 Dec 28 22:48:05 volumio volumio[855]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Dec 28 22:48:05 volumio volumio[855]: at listenInCluster (net.js:1366:12) Dec 28 22:48:05 volumio volumio[855]: at Server.listen (net.js:1452:7) Dec 28 22:48:05 volumio volumio[855]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 28 22:48:05 volumio volumio[855]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 28 22:48:05 volumio volumio[855]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 28 22:48:05 volumio volumio[855]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 28 22:48:05 volumio volumio[855]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Dec 28 22:48:05 volumio volumio[855]: code: 'EADDRINUSE', Dec 28 22:48:05 volumio volumio[855]: errno: -98, Dec 28 22:48:05 volumio volumio[855]: syscall: 'listen', Dec 28 22:48:05 volumio volumio[855]: address: '::', Dec 28 22:48:05 volumio volumio[855]: port: 6599 Dec 28 22:48:05 volumio volumio[855]: } Dec 28 22:48:05 volumio volumio[855]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 22:48:06 volumio sudo[5798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 22:47 Dec 28 22:48:06 volumio sudo[5798]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:06 volumio sudo[5798]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 28 22:48:12 volumio sudo[5785]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:12 volumio volumio-remote-updater[564]: [2025-12-28 14:48:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 28 22:48:12 volumio volumio-remote-updater[564]: [2025-12-28 14:48:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 28 22:48:12 volumio systemd[1]: media-\xe5\xb3\xb0\xe7\x9a\x84\xe9\x9f\xb3\xe4\xb9\x90.mount: Succeeded. Dec 28 22:48:12 volumio ntfs-3g[955]: Unmounting /dev/sda1 (峰的音乐) Dec 28 22:48:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 28 22:48:12 volumio systemd[1]: upmpdcli.service: Succeeded. Dec 28 22:48:12 volumio systemd[1]: Stopped UPnP Renderer front-end to MPD. Dec 28 22:48:12 volumio systemd[1]: Started dynamicswap service. Dec 28 22:48:12 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 28 22:48:13 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 28 22:48:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 28 22:48:13 volumio systemd[1]: Stopped Volumio Backend Module. Dec 28 22:48:13 volumio systemd[1]: Started Volumio Backend Module. Dec 28 22:48:13 volumio systemd[1]: Started dynamicswap service. Dec 28 22:48:13 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 28 22:48:14 volumio volumio[5829]: info: ------------------------------------------- Dec 28 22:48:14 volumio volumio[5829]: info: ----- Volumio3 ---- Dec 28 22:48:14 volumio volumio[5829]: info: ------------------------------------------- Dec 28 22:48:14 volumio volumio[5829]: info: ----- System startup ---- Dec 28 22:48:14 volumio volumio[5829]: info: ------------------------------------------- Dec 28 22:48:15 volumio volumio[5829]: info: MYVOLUMIO Environment detected Dec 28 22:48:15 volumio volumio[5829]: info: Plugin folders cleanup Dec 28 22:48:15 volumio volumio[5829]: info: Scanning into folder /volumio/app/plugins/ Dec 28 22:48:15 volumio volumio[5829]: info: Scanning category audio_interface Dec 28 22:48:15 volumio volumio[5829]: info: Scanning category miscellanea Dec 28 22:48:15 volumio volumio[5829]: info: Scanning category music_service Dec 28 22:48:15 volumio volumio[5829]: info: Scanning category plugins.json Dec 28 22:48:15 volumio volumio[5829]: info: Scanning category system_controller Dec 28 22:48:15 volumio volumio[5829]: info: Scanning category user_interface Dec 28 22:48:15 volumio volumio[5829]: info: Scanning into folder /data/plugins/ Dec 28 22:48:15 volumio volumio[5829]: info: Plugin folders cleanup completed Dec 28 22:48:15 volumio volumio[5829]: info: ------------------------------------------- Dec 28 22:48:15 volumio volumio[5829]: info: ----- Core plugins startup ---- Dec 28 22:48:15 volumio volumio[5829]: info: ------------------------------------------- Dec 28 22:48:15 volumio volumio[5829]: info: Loading plugins from folder /volumio/app/plugins/ Dec 28 22:48:15 volumio volumio[5829]: info: Adding plugin upnp to MyMusic Plugins Dec 28 22:48:15 volumio volumio[5829]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 28 22:48:15 volumio volumio[5829]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 28 22:48:15 volumio volumio[5829]: info: Loading plugins from folder /data/plugins/ Dec 28 22:48:15 volumio volumio[5829]: info: Loading plugin "system"... Dec 28 22:48:15 volumio volumio[5829]: info: Loading plugin "appearance"... Dec 28 22:48:16 volumio volumio[5829]: info: Loading plugin "network"... Dec 28 22:48:16 volumio volumio[5829]: info: Refreshing Cached IP Addresses Dec 28 22:48:16 volumio sudo[5859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 22:48:16 volumio sudo[5859]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:16 volumio sudo[5859]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:16 volumio sudo[5861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 22:48:16 volumio sudo[5861]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:16 volumio sudo[5861]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:16 volumio volumio[5829]: info: Loading plugin "services"... Dec 28 22:48:16 volumio volumio[5829]: info: Loading plugin "alsa_controller"... Dec 28 22:48:16 volumio sudo[5874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 22:48:16 volumio sudo[5874]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:16 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 22:48:16 volumio volumio[5829]: info: Loading plugin "wizard"... Dec 28 22:48:16 volumio volumio[5829]: info: Loading plugin "networkfs"... Dec 28 22:48:16 volumio volumio[5829]: info: Starting Udev Watcher for removable devices Dec 28 22:48:16 volumio volumio[5829]: info: Ignoring mount for partition: boot Dec 28 22:48:16 volumio volumio[5829]: info: Ignoring mount for partition: volumio Dec 28 22:48:16 volumio volumio[5829]: info: Ignoring mount for partition: volumio_data Dec 28 22:48:16 volumio volumio[5829]: info: Mounting Device 峰的音乐 Dec 28 22:48:16 volumio sudo[5888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/峰的音乐 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 28 22:48:16 volumio sudo[5888]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:17 volumio ntfs-3g[5891]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 28 22:48:17 volumio ntfs-3g[5891]: Mounted /dev/sda1 (Read-Write, label "峰的音乐", NTFS 3.1) Dec 28 22:48:17 volumio ntfs-3g[5891]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 28 22:48:17 volumio ntfs-3g[5891]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Dec 28 22:48:17 volumio ntfs-3g[5891]: Global ownership and permissions enforced, configuration type 7 Dec 28 22:48:17 volumio sudo[5888]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:17 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 22:48:17 volumio volumio[5829]: info: Loading plugin "volumio_command_line_client"... Dec 28 22:48:17 volumio volumio[5829]: info: Loading plugin "upnp"... Dec 28 22:48:17 volumio volumio[5829]: info: [1766933297579] Starting Upmpd Daemon Dec 28 22:48:17 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 22:48:17 volumio volumio[5829]: info: Loading plugin "my_music"... Dec 28 22:48:17 volumio volumio[5829]: info: Loading plugin "mpd"... Dec 28 22:48:17 volumio sudo[5874]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:17 volumio volumio-remote-updater[564]: [2025-12-28 14:48:17] [connect] Successful connection Dec 28 22:48:17 volumio volumio[5829]: info: Loading plugin "upnp_browser"... Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "alarm-clock"... Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "airplay_emulation"... Dec 28 22:48:18 volumio volumio[5829]: info: Starting Shairport Sync Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "last_100"... Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "webradio"... Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "i2s_dacs"... Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "volumiodiscovery"... Dec 28 22:48:18 volumio volumio[5829]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 22:48:18 volumio volumio[5829]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 22:48:18 volumio volumio[5829]: *** WARNING *** For more information see Dec 28 22:48:18 volumio node[5829]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 22:48:18 volumio volumio[5829]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 22:48:18 volumio volumio[5829]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 22:48:18 volumio volumio[5829]: *** WARNING *** For more information see Dec 28 22:48:18 volumio node[5829]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 22:48:18 volumio node[5829]: *** WARNING *** For more information see Dec 28 22:48:18 volumio node[5829]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 22:48:18 volumio node[5829]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 22:48:18 volumio node[5829]: *** WARNING *** For more information see Dec 28 22:48:18 volumio volumio[5829]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 28 22:48:18 volumio volumio[5829]: info: Discovery: Started advertising with name: Volumio Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "outputs"... Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "albumart"... Dec 28 22:48:18 volumio volumio[5829]: info: Plugin example_plugin is not enabled Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "inputs"... Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "updater_comm"... Dec 28 22:48:18 volumio volumio[5829]: info: Plugin mpdemulation is not enabled Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "rest_api"... Dec 28 22:48:18 volumio volumio[5829]: info: Loading plugin "websocket"... Dec 28 22:48:18 volumio volumio[5829]: Forking 3 albumart workers Dec 28 22:48:18 volumio volumio[5829]: info: Starting Socket.io Server version 2.3.0 Dec 28 22:48:18 volumio volumio[5829]: info: Loading i18n strings for locale zh Dec 28 22:48:18 volumio volumio[5829]: Updating browse sources language Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::initPlayerControls Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 22:48:18 volumio volumio[5829]: Express server listening on port 3000 Dec 28 22:48:18 volumio volumio[5829]: [Metrics] WebUI: 4s 974.02ms Dec 28 22:48:18 volumio volumio[5829]: info: CoreStateMachine::resetVolumioState Dec 28 22:48:18 volumio volumio[5829]: info: CoreStateMachine::getcurrentVolume Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 22:48:18 volumio volumio[5829]: info: CoreStateMachine::pushState Dec 28 22:48:18 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 22:48:18 volumio volumio[5829]: info: CoreCommandRouter::volumioPushState Dec 28 22:48:19 volumio volumio[5829]: info: Volumio Network Manager: Network status updated: 1 Dec 28 22:48:19 volumio volumio[5829]: verbose: New Socket.io Connection to 192.168.10.93 from 192.168.10.123 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 1 Dec 28 22:48:19 volumio volumio[5829]: verbose: New Socket.io Connection to 192.168.10.93 from 192.168.10.123 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 2 Dec 28 22:48:19 volumio volumio[5829]: verbose: New Socket.io Connection to 192.168.10.93 from 192.168.10.123 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 3 Dec 28 22:48:19 volumio volumio[5829]: verbose: New Socket.io Connection to 192.168.10.93 from 192.168.10.123 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Dec 28 22:48:19 volumio volumio-remote-updater[564]: [2025-12-28 14:48:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766933297 101 Dec 28 22:48:19 volumio volumio[5829]: 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: 5 Dec 28 22:48:19 volumio volumio[5829]: verbose: New Socket.io Connection to 192.168.10.93 from 192.168.10.123 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Dec 28 22:48:19 volumio volumio[5829]: info: Reloading queue from file Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreStateMachine::setRepeat null single undefined Dec 28 22:48:19 volumio volumio[5829]: info: CoreStateMachine::pushState Dec 28 22:48:19 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::volumioPushState Dec 28 22:48:19 volumio volumio[5829]: info: CoreStateMachine::setRandom null Dec 28 22:48:19 volumio volumio[5829]: info: CoreStateMachine::pushState Dec 28 22:48:19 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::volumioPushState Dec 28 22:48:19 volumio volumio[5829]: info: Setting Device type: Raspberry PI Dec 28 22:48:19 volumio volumio[5829]: info: Completed loading Core Plugins Dec 28 22:48:19 volumio volumio[5829]: info: Preparing to generate the ALSA configuration file Dec 28 22:48:19 volumio volumio[5829]: Starting albumart workers Dec 28 22:48:19 volumio volumio[5829]: info: Asound.conf file unchanged, so no further update is needed Dec 28 22:48:19 volumio volumio[5829]: info: Output device has changed, restarting MPD Dec 28 22:48:19 volumio volumio[5829]: Starting albumart workers Dec 28 22:48:19 volumio volumio[5829]: info: Output device has changed, restarting Shairport Sync Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 22:48:19 volumio sudo[5931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 22:48:19 volumio sudo[5931]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:19 volumio sudo[5931]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:19 volumio sudo[5933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 22:48:19 volumio sudo[5933]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:19 volumio volumio[5829]: Starting albumart workers Dec 28 22:48:19 volumio volumio[5829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 22:48:19 volumio volumio[5829]: info: ___________ START PLUGINS ___________ Dec 28 22:48:19 volumio volumio[5829]: info: ControllerMpd::onStart: Initializing MPD Dec 28 22:48:19 volumio volumio[5829]: info: Creating MPD Configuration file Dec 28 22:48:19 volumio systemd[1]: Stopping Music Player Daemon... Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 22:48:19 volumio sudo[5940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 22:48:19 volumio sudo[5940]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 22:48:19 volumio sudo[5940]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:19 volumio volumio[5829]: info: [1766933299411] CoreMusicLibrary::Adding element 媒体服务器 Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 22:48:19 volumio sudo[5942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 22:48:19 volumio sudo[5942]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 22:48:19 volumio volumio[5829]: info: [1766933299535] CoreMusicLibrary::Adding element Last_100 Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 22:48:19 volumio volumio[5829]: info: [1766933299539] CoreMusicLibrary::Adding element Webradio Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 22:48:19 volumio volumio[5829]: info: Initializing BBC Radios Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: Volumio Calling Home Dec 28 22:48:19 volumio volumio[5829]: verbose: New Socket.io Connection to 192.168.10.93 from 192.168.10.123 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Dec 28 22:48:19 volumio volumio[5829]: info: MPD Permissions set Dec 28 22:48:19 volumio volumio[5829]: info: MPD Permissions set Dec 28 22:48:19 volumio volumio[5829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 22:48:19 volumio volumio[5829]: info: Starting Shairport Sync Dec 28 22:48:19 volumio volumio[5829]: info: Starting Shairport Sync Dec 28 22:48:20 volumio volumio[5829]: info: Starting Shairport Sync Dec 28 22:48:20 volumio sudo[5999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 22:48:20 volumio sudo[5999]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:20 volumio sudo[6001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 22:48:20 volumio sudo[6001]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 28 22:48:20 volumio sudo[6004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 22:48:20 volumio sudo[6004]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetState Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:20 volumio volumio[5829]: info: Discovery: adding 2c0ec615-82eb-46dd-95ac-bf22df4e43d2 Dec 28 22:48:20 volumio volumio[5829]: info: Discovery: Found device Volumio Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetState Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:20 volumio volumio[5829]: info: Discovery: this is already registered, 2c0ec615-82eb-46dd-95ac-bf22df4e43d2 Dec 28 22:48:20 volumio volumio[5829]: info: Discovery: Found device Volumio Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetState Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 22:48:20 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 28 22:48:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetState Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 28 22:48:20 volumio volumio[5829]: info: Received Get System Info Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 22:48:20 volumio volumio[5829]: info: Discovery: Getting this device information Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetState Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetState Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:20 volumio volumio[5829]: info: Listing playlists Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetQueue Dec 28 22:48:20 volumio volumio[5829]: info: CoreStateMachine::getQueue Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getQueue Dec 28 22:48:20 volumio volumio[5829]: info: Received Get System Info Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 22:48:20 volumio volumio[5829]: info: Discovery: Getting this device information Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetState Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::volumioGetState Dec 28 22:48:20 volumio volumio[5829]: info: CorePlayQueue::getTrack 0 Dec 28 22:48:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 28 22:48:20 volumio sudo[6001]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:20 volumio sudo[5999]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:20 volumio sudo[6004]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:20 volumio volumio[5829]: info: Shairport-Sync Started Dec 28 22:48:20 volumio volumio[5829]: Error adding Membership: Error: addMembership EINVAL Dec 28 22:48:20 volumio volumio[5829]: info: Shairport-Sync Started Dec 28 22:48:20 volumio volumio[5829]: info: Shairport-Sync Started Dec 28 22:48:20 volumio systemd[1]: mpd.service: Succeeded. Dec 28 22:48:20 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 22:48:20 volumio systemd[1]: Starting Music Player Daemon... Dec 28 22:48:20 volumio volumio[5829]: info: Volumio called home Dec 28 22:48:20 volumio sudo[6010]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 22:48:20 volumio sudo[6010]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 22:48:20 volumio sudo[6010]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:20 volumio volumio[5829]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 22:48:21 volumio mpd[6012]: Dec 28 22:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 22:48:21 volumio systemd[1]: Started Music Player Daemon. Dec 28 22:48:21 volumio sudo[5933]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:21 volumio sudo[5942]: pam_unix(sudo:session): session closed for user root Dec 28 22:48:21 volumio volumio[5829]: info: Completed starting Core Plugins Dec 28 22:48:21 volumio volumio[5829]: info: ------------------------------------------- Dec 28 22:48:21 volumio volumio[5829]: info: ----- MyVolumio plugins startup ---- Dec 28 22:48:21 volumio volumio[5829]: info: ------------------------------------------- Dec 28 22:48:21 volumio volumio[5829]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 28 22:48:21 volumio volumio[5829]: info: MPD running with PID6012 Dec 28 22:48:21 volumio volumio[5829]: ,establishing connection Dec 28 22:48:21 volumio volumio[5829]: error: MPD error: The expression evaluated to a falsy value: Dec 28 22:48:21 volumio volumio[5829]: assert.ok(self.idling) Dec 28 22:48:21 volumio volumio[5829]: error: The expression evaluated to a falsy value: Dec 28 22:48:21 volumio volumio[5829]: assert.ok(self.idling) Dec 28 22:48:21 volumio volumio[5829]: error: MPD error: The expression evaluated to a falsy value: Dec 28 22:48:21 volumio volumio[5829]: assert.ok(self.idling) Dec 28 22:48:21 volumio volumio[5829]: error: The expression evaluated to a falsy value: Dec 28 22:48:21 volumio volumio[5829]: assert.ok(self.idling) Dec 28 22:48:21 volumio volumio[5829]: error: updateQueue error: null Dec 28 22:48:23 volumio volumio[5829]: info: Enabling MyMusic plugin multiroom Dec 28 22:48:23 volumio volumio[5829]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Dec 28 22:48:23 volumio volumio[5829]: info: [MyVolumio PluginManager] Plugin audio_interface multiroom not in user plan, not enabling Dec 28 22:48:23 volumio volumio[5829]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 22:48:23 volumio volumio[5829]: TypeError: Cannot read property 'toUpperCase' of undefined Dec 28 22:48:23 volumio volumio[5829]: at MyVolumioPluginManager.pushMyVolumioRequiredAccountModal (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:19516) Dec 28 22:48:23 volumio volumio[5829]: at MyVolumioPluginManager.enablePlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:18418) Dec 28 22:48:23 volumio volumio[5829]: at MyVolumioPluginManager.enableAndStartPlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:17039) Dec 28 22:48:23 volumio volumio[5829]: at PluginManager.enableDisableMyMusicPlugin (/volumio/app/pluginmanager.js:2024:48) Dec 28 22:48:23 volumio volumio[5829]: at CoreCommandRouter.enableDisableMyMusicPlugin (/volumio/app/index.js:2107:29) Dec 28 22:48:23 volumio volumio[5829]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1935:59) Dec 28 22:48:23 volumio volumio[5829]: at Socket.emit (events.js:315:20) Dec 28 22:48:23 volumio volumio[5829]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 28 22:48:23 volumio volumio[5829]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 28 22:48:23 volumio volumio[5829]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 22:48:23 volumio sudo[6027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 22:47 Dec 28 22:48:23 volumio sudo[6027]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"