Feb 21 18:04:03 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 21 18:04:03 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 21 18:04:21 volumio volumio[1342]: info: CALLMETHOD: system_controller system enableLiveLog true Feb 21 18:04:21 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Feb 21 18:04:21 volumio volumio[1342]: info: Launching a new LiveLog session Feb 21 18:05:06 volumio volumio[1342]: info: Disabling plugin roseaudiosys Feb 21 18:05:06 volumio volumio[1342]: info: Done. Feb 21 18:05:08 volumio volumio[1342]: info: Enabling plugin roseaudiosys Feb 21 18:05:08 volumio volumio[1342]: info: Loading plugin "roseaudiosys"... Feb 21 18:05:08 volumio volumio[1342]: info: Applying required configuration parameters for plugin roseaudiosys Feb 21 18:05:08 volumio volumio[1342]: info: PLUGIN START: roseaudiosys Feb 21 18:05:08 volumio volumio[1342]: info: [roseaudiosys] onStart > Starting Rose Audio System Initialization Plugin! Feb 21 18:05:08 volumio volumio[1342]: info: [roseaudiosys] initializePlayer > Rose Audio Femto Player initialization completed successfully! Feb 21 18:05:08 volumio volumio[1342]: info: [roseaudiosys] onStart > Rose Audio System Initialization Plugin started successfully! Feb 21 18:05:08 volumio volumio[1342]: info: Done. Feb 21 18:05:28 volumio volumio[1342]: info: CoreCommandRouter::volumioGetState Feb 21 18:05:28 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 18:05:30 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 18:05:34 volumio volumio[1342]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 18:05:34 volumio volumio[1342]: info: Preparing to save Alsa Options, stopping services first Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::volumioGetState Feb 21 18:05:34 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::volumioPause Feb 21 18:05:34 volumio volumio[1342]: info: CoreStateMachine::pause Feb 21 18:05:34 volumio volumio[1342]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 18:05:34 volumio volumio[1342]: info: Found match in Cards Database: setting mixer for card HDMI 0 Out Feb 21 18:05:34 volumio volumio[1342]: info: Device 0 does not have any Mixer Control Available Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 18:05:34 volumio volumio[1342]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 18:05:34 volumio volumio[1342]: info: Disabling external Volume Control Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 18:05:34 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 21 18:05:34 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 21 18:05:34 volumio volumio[1342]: info: Preparing to generate the ALSA configuration file Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 18:05:34 volumio volumio[1342]: info: Asound.conf file written Feb 21 18:05:34 volumio sudo[2175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 21 18:05:34 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 18:05:34 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Feb 21 18:05:34 volumio volumio[1342]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 21 18:05:34 volumio volumio[1342]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 21 18:05:34 volumio volumio[1342]: info: Output device has changed, restarting MPD Feb 21 18:05:34 volumio volumio[1342]: info: Output device has changed, restarting Shairport Sync Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 18:05:34 volumio sudo[2181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 18:05:34 volumio sudo[2181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 18:05:34 volumio sudo[2181]: pam_unix(sudo:session): session closed for user root Feb 21 18:05:34 volumio sudo[2183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 18:05:34 volumio volumio[1342]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 18:05:34 volumio sudo[2183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 18:05:34 volumio volumio[1342]: info: Output device has changed, restarting MPD Feb 21 18:05:34 volumio volumio[1342]: info: Output device has changed, restarting Shairport Sync Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 18:05:34 volumio sudo[2193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 18:05:34 volumio sudo[2193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 18:05:34 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 21 18:05:34 volumio sudo[2192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 18:05:34 volumio sudo[2192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 18:05:34 volumio volumio[1342]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 18:05:34 volumio sudo[2192]: pam_unix(sudo:session): session closed for user root Feb 21 18:05:34 volumio volumio[1342]: info: MPD Permissions set Feb 21 18:05:34 volumio volumio[1342]: info: MPD Permissions set Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:34 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:35 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 18:05:35 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 18:05:35 volumio volumio[1342]: info: Starting Shairport Sync Feb 21 18:05:35 volumio volumio[1342]: info: Starting Shairport Sync Feb 21 18:05:35 volumio sudo[2213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 18:05:35 volumio sudo[2213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 18:05:35 volumio sudo[2212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 18:05:35 volumio sudo[2212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 18:05:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 18:05:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 18:05:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 18:05:35 volumio systemd[1]: shairport-sync.service: Consumed 1.614s CPU time. Feb 21 18:05:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 18:05:35 volumio sudo[2213]: pam_unix(sudo:session): session closed for user root Feb 21 18:05:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 18:05:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 18:05:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 18:05:35 volumio volumio[1342]: info: Shairport-Sync Started Feb 21 18:05:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 18:05:35 volumio sudo[2212]: pam_unix(sudo:session): session closed for user root Feb 21 18:05:35 volumio volumio[1342]: info: Shairport-Sync Started Feb 21 18:05:35 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 18:05:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 18:05:35 volumio systemd[1]: mpd.service: Consumed 1.230s CPU time. Feb 21 18:05:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 18:05:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 18:05:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 18:05:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 18:05:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 18:05:35 volumio sudo[2232]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 18:05:35 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 18:05:35 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root Feb 21 18:05:35 volumio mpd[2236]: 2026-02-21T18:05:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 18:05:35 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 21 18:05:35 volumio sudo[2183]: pam_unix(sudo:session): session closed for user root Feb 21 18:05:35 volumio sudo[2193]: pam_unix(sudo:session): session closed for user root Feb 21 18:05:35 volumio volumio[1342]: error: MPD error: The expression evaluated to a falsy value: Feb 21 18:05:35 volumio volumio[1342]: assert.ok(self.idling) Feb 21 18:05:35 volumio volumio[1342]: error: The expression evaluated to a falsy value: Feb 21 18:05:35 volumio volumio[1342]: assert.ok(self.idling) Feb 21 18:05:35 volumio volumio[1342]: error: updateQueue error: null Feb 21 18:05:38 volumio volumio[1342]: info: CoreCommandRouter::volumioGetState Feb 21 18:05:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 21 18:05:38 volumio volumio[1342]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 18:05:38 volumio volumio[1342]: info: CoreStateMachine::getcurrentVolume Feb 21 18:05:38 volumio volumio[1342]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 18:05:38 volumio volumio[1342]: info: CoreStateMachine::pushState Feb 21 18:05:38 volumio volumio[1342]: info: CorePlayQueue::getTrack 0 Feb 21 18:05:38 volumio volumio[1342]: info: CoreCommandRouter::volumioPushState Feb 21 18:05:40 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 18:05:40 volumio volumio[1342]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 21 18:05:42 volumio volumio[1342]: info: Disabling plugin roseaudiosys Feb 21 18:05:42 volumio volumio[1342]: info: Done. Feb 21 18:05:46 volumio volumio[1342]: info: Enabling plugin roseaudiosys Feb 21 18:05:46 volumio volumio[1342]: info: Loading plugin "roseaudiosys"... Feb 21 18:05:46 volumio volumio[1342]: info: Applying required configuration parameters for plugin roseaudiosys Feb 21 18:05:46 volumio volumio[1342]: info: PLUGIN START: roseaudiosys Feb 21 18:05:46 volumio volumio[1342]: info: [roseaudiosys] onStart > Starting Rose Audio System Initialization Plugin! Feb 21 18:05:46 volumio volumio[1342]: info: [roseaudiosys] onStart > Rose Audio System Initialization Plugin started successfully! Feb 21 18:05:46 volumio volumio[1342]: info: Done. Feb 21 18:05:46 volumio volumio[1342]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 18:05:46 volumio volumio[1342]: TypeError: self.Log is not a function Feb 21 18:05:46 volumio volumio[1342]: at roseaudiosys.initializePlayer (/data/plugins/system_hardware/roseaudiosys/index.js:218:9) Feb 21 18:05:46 volumio volumio[1342]: at /data/plugins/system_hardware/roseaudiosys/index.js:171:8 Feb 21 18:05:46 volumio volumio[1342]: at new Promise () Feb 21 18:05:46 volumio volumio[1342]: at roseaudiosys.waitSystemReadyAndInitialize (/data/plugins/system_hardware/roseaudiosys/index.js:153:2) Feb 21 18:05:46 volumio volumio[1342]: at roseaudiosys.onStart (/data/plugins/system_hardware/roseaudiosys/index.js:259:7) Feb 21 18:05:46 volumio volumio[1342]: at PluginManager.startPlugin (/volumio/app/pluginmanager.js:431:30) Feb 21 18:05:46 volumio volumio[1342]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 21 18:05:46 volumio volumio[1342]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 21 18:05:46 volumio volumio[1342]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 18:05:46 volumio sudo[2271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 18:04' Feb 21 18:05:46 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Feb 17 14:57:51 UTC 2026" VOLUMIO_VERSION="4.100" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4d5a1db83da2c0ab0045c3d64d81fedc"