Mar 29 23:46:00 volumio mpd[2259]: 2026-03-29T23:46:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 23:46:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 23:46:00 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:00 volumio volumio[1239]: error: updateQueue error: null Mar 29 23:46:00 volumio volumio[1239]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 29 23:46:00 volumio volumio[1239]: info: Preparing to save Alsa Options, stopping services first Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Mar 29 23:46:00 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::volumioPause Mar 29 23:46:00 volumio volumio[1239]: info: CoreStateMachine::pause Mar 29 23:46:00 volumio volumio[1239]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"MISSION USB Audio 2.0"}} Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 23:46:00 volumio volumio[1239]: info: Setting mixer MISSION USB Audio 2.0 for card MISSION USB Audio 2.0 Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 29 23:46:00 volumio volumio[1239]: info: Updating Volume Controller Parameters: Device: 5 Name: MISSION USB Audio 2.0 Mixer: MISSION USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 29 23:46:00 volumio volumio[1239]: info: Disabling external Volume Control Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:46:00 volumio volumio[1239]: info: Preparing to generate the ALSA configuration file Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 23:46:00 volumio volumio[1239]: info: Asound.conf file unchanged, so no further update is needed Mar 29 23:46:00 volumio volumio[1239]: info: Output device has changed, restarting MPD Mar 29 23:46:00 volumio volumio[1239]: info: Output device has changed, restarting Shairport Sync Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:46:00 volumio sudo[2292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 23:46:00 volumio sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:46:00 volumio sudo[2290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 23:46:00 volumio volumio[1239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 23:46:00 volumio sudo[2290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:46:00 volumio volumio[1239]: info: VolumeController:: Volume=100 Mute =false Mar 29 23:46:00 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:00 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:00 volumio sudo[2290]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:00 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:00.919+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=10615 volume=100 Mar 29 23:46:00 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:00.920+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:00 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 23:46:00 volumio volumio[1239]: info: MPD Permissions set Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:00 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 29 23:46:00 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 23:46:00 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 29 23:46:00 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 23:46:00 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 23:46:01 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:46:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 23:46:01 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 23:46:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 23:46:01 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:01 volumio volumio[1239]: info: Starting Shairport Sync Mar 29 23:46:01 volumio sudo[2310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 23:46:01 volumio sudo[2310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:46:01 volumio sudo[2305]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 23:46:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 23:46:01 volumio sudo[2305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 23:46:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 23:46:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 23:46:01 volumio systemd[1]: shairport-sync.service: Consumed 1.250s CPU time. Mar 29 23:46:01 volumio sudo[2305]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 23:46:01 volumio sudo[2310]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:01 volumio volumio[1239]: info: Shairport-Sync Started Mar 29 23:46:01 volumio mpd[2314]: 2026-03-29T23:46:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 23:46:01 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 23:46:01 volumio sudo[2292]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:01 volumio volumio[1239]: error: updateQueue error: null Mar 29 23:46:03 volumio volumio[1239]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 29 23:46:03 volumio volumio[1239]: info: CoreStateMachine::getcurrentVolume Mar 29 23:46:03 volumio volumio[1239]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 23:46:03 volumio volumio[1239]: info: VolumeController:: Volume=100 Mute =false Mar 29 23:46:03 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:03 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:03 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:03 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:03 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Mar 29 23:46:03 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Mar 29 23:46:03 volumio volumio[1239]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 23:46:03 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:03.458+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=13122 volume=100 Mar 29 23:46:03 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:03.462+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:03 volumio volumio[1239]: info: VolumeController:: Volume=100 Mute =false Mar 29 23:46:03 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:03 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:03 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:03 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:03.495+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=13122 volume=100 Mar 29 23:46:03 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:03.495+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:04 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:04 volumio volumio[1239]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 29 23:46:04 volumio volumio[1239]: info: CoreStateMachine::getcurrentVolume Mar 29 23:46:04 volumio volumio[1239]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 23:46:04 volumio volumio[1239]: info: VolumeController:: Volume=100 Mute =false Mar 29 23:46:04 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:04 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:04 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Mar 29 23:46:04 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Mar 29 23:46:04 volumio volumio[1239]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 23:46:04 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:04.781+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=14374 volume=100 Mar 29 23:46:04 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:04.784+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:04 volumio volumio[1239]: info: VolumeController:: Volume=100 Mute =false Mar 29 23:46:04 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:04 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:04 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:04 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:04.823+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=14374 volume=100 Mar 29 23:46:04 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:04.823+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:05 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 23:46:05 volumio volumio[1239]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 23:46:05 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 23:46:05 volumio volumio[1239]: info: Received Get System Version Mar 29 23:46:05 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:46:05 volumio volumio[1239]: info: Received Get System Info Mar 29 23:46:05 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:46:05 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:46:05 volumio volumio[1239]: info: Discovery: Getting this device information Mar 29 23:46:05 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Mar 29 23:46:05 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:05 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:46:10 volumio volumio[1239]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::volumioGetState Mar 29 23:46:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:10 volumio volumio[1239]: info: Restoring Previous Volume level: 100 false false Mar 29 23:46:10 volumio volumio[1239]: info: Output device has changed, restarting MPD Mar 29 23:46:10 volumio volumio[1239]: info: Output device has changed, restarting Shairport Sync Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:46:10 volumio sudo[2364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 23:46:10 volumio sudo[2367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 23:46:10 volumio sudo[2367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:46:10 volumio sudo[2364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:46:10 volumio sudo[2364]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:10 volumio volumio[1239]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 23:46:10 volumio volumio[1239]: info: Volume configurations have been set Mar 29 23:46:10 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 29 23:46:10 volumio volumio[1239]: info: Updating Volume Controller Parameters: Device: 5 Name: MISSION USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 29 23:46:10 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 29 23:46:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 29 23:46:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 29 23:46:10 volumio volumio[1239]: info: Disabling external Volume Control Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:46:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 23:46:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 23:46:10 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 23:46:10 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:10.864+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=20391 volume=100 Mar 29 23:46:10 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:10.864+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:10 volumio volumio[1239]: info: MPD Permissions set Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 23:46:10 volumio sudo[2379]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 23:46:10 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 23:46:10 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 23:46:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 23:46:11 volumio volumio[1239]: info: Starting Shairport Sync Mar 29 23:46:11 volumio sudo[2397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 23:46:11 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:46:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 23:46:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 23:46:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 23:46:11 volumio systemd[1]: shairport-sync.service: Consumed 1.601s CPU time. Mar 29 23:46:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 23:46:11 volumio sudo[2397]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:11 volumio volumio[1239]: info: Shairport-Sync Started Mar 29 23:46:11 volumio mpd[2392]: 2026-03-29T23:46:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 23:46:11 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 23:46:11 volumio sudo[2367]: pam_unix(sudo:session): session closed for user root Mar 29 23:46:11 volumio volumio[1239]: error: updateQueue error: null Mar 29 23:46:15 volumio volumio[1239]: info: VolumeController::SetAlsaVolume100 Mar 29 23:46:15 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:15 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:15 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:15 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:15 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:15.250+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=24902 volume=100 Mar 29 23:46:15 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:15.250+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:16 volumio volumio[1239]: info: CoreCommandRouter::volumioGetQueue Mar 29 23:46:16 volumio volumio[1239]: info: CoreStateMachine::getQueue Mar 29 23:46:16 volumio volumio[1239]: info: CorePlayQueue::getQueue Mar 29 23:46:18 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 29 23:46:20 volumio volumio[1239]: info: Preload queue cleared Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::ClearQueue Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::stop Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::clearPlayQueue Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::saveQueue Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::addQueueItems Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::addQueueItems Mar 29 23:46:20 volumio volumio[1239]: info: Preload queue cleared Mar 29 23:46:20 volumio volumio[1239]: info: Adding Item to queue: music-library/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128] Mar 29 23:46:20 volumio volumio[1239]: info: Exploding uri music-library/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128] in service mpd Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F01-I've%20Got%20You%20Under%20My%20Skin.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F02-Antonio's%20Song.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F03-Rhythm%20of%20the%20Falling'%20Rain.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/03-Rhythm of the Falling' Rain.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F04-You%20And%20The%20Night%20And%20The%20Music.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/04-You And The Night And The Music.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F05-So%20In%20Love.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/05-So In Love.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F06-Just%20Squeeze%20Me.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/06-Just Squeeze Me.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F07-Moonlight%20Serenade.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/07-Moonlight Serenade.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F08-Everytime%20We%20Say%20Goodbye.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/08-Everytime We Say Goodbye.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F09-Hallelujah%2C%20I%20Love%20Him%20So.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/09-Hallelujah, I Love Him So.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F10-%20One%20For%20My%20Baby.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/10- One For My Baby.dsf Mar 29 23:46:20 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D%2F11-Just%20the%20Way%20You%20Are.dsf&metadata=false Mar 29 23:46:20 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/11-Just the Way You Are.dsf Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::saveQueue Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::volumioPlay Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::play index 0 Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::stop Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::play index undefined Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::clearAddPlayTracks NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 23:46:20 volumio volumio[1239]: info: sendMpdCommand stop took 2 milliseconds Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 23:46:20 volumio volumio[1239]: info: Mar 29 23:46:20 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 29 23:46:20 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 29 23:46:20 volumio volumio[1239]: info: sendMpdCommand clear took 1 milliseconds Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand add "NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" Mar 29 23:46:20 volumio volumio[1239]: error: updateQueue error: null Mar 29 23:46:20 volumio volumio[1239]: info: Mar 29 23:46:20 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 29 23:46:20 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 29 23:46:20 volumio volumio[1239]: info: ------------------------------ 2ms Mar 29 23:46:20 volumio volumio[1239]: info: sendMpdCommand add "NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" took 2 milliseconds Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand play Mar 29 23:46:20 volumio volumio[1239]: info: ------------------------------ 2ms Mar 29 23:46:20 volumio volumio[1239]: info: sendMpdCommand play took 1 milliseconds Mar 29 23:46:20 volumio volumio[1239]: info: Mar 29 23:46:20 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 29 23:46:20 volumio volumio[1239]: info: ControllerMpd::getState Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 29 23:46:20 volumio volumio[1239]: info: Mar 29 23:46:20 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 29 23:46:20 volumio volumio[1239]: info: sendMpdCommand status took 51 milliseconds Mar 29 23:46:20 volumio volumio[1239]: info: ControllerMpd::getState Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 23:46:20 volumio volumio[1239]: info: sendMpdCommand status took 2 milliseconds Mar 29 23:46:20 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 29 23:46:20 volumio volumio[1239]: info: ControllerMpd::pushState Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:20 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I've Got You Under My Skin","artist":"Grace Mahya","album":"Love Songs For You","uri":"NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf","trackType":"dsf"} Mar 29 23:46:20 volumio volumio[1239]: verbose: CURRENT POSITION 0 Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Mar 29 23:46:20 volumio volumio[1239]: info: ------------------------------ 57ms Mar 29 23:46:20 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 29 23:46:20 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 29 23:46:20 volumio volumio[1239]: info: ControllerMpd::pushState Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:20 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":310,"duration":218,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"I've Got You Under My Skin","artist":"Grace Mahya","album":"Love Songs For You","uri":"NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf","trackType":"dsf"} Mar 29 23:46:20 volumio volumio[1239]: verbose: CURRENT POSITION 0 Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Mar 29 23:46:20 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:20 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:20 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:20 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:20 volumio volumio[1239]: info: ------------------------------ 26ms Mar 29 23:46:20 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:20.925+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=310 volume=100 Mar 29 23:46:20 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:20.926+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=310 volume=100 Mar 29 23:46:20 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:20.926+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:20 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:20.927+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:26 volumio volumio[1239]: info: CoreCommandRouter::volumioSeek Mar 29 23:46:26 volumio volumio[1239]: info: CoreStateMachine::seek Mar 29 23:46:26 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:26 volumio volumio[1239]: info: TRACKBLOCK {"uri":"mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf","service":"mpd","name":"I've Got You Under My Skin","artist":"Grace Mahya","album":"Love Songs For You","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D&metadata=false","duration":218,"samplerate":"5.64 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Mar 29 23:46:26 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Mar 29 23:46:26 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:26 volumio volumio[1239]: info: ControllerMpd::seek Mar 29 23:46:26 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:26 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:26 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:26 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:26 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:26.240+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=131000 volume=100 Mar 29 23:46:26 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:26.241+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:26 volumio volumio[1239]: error: null Mar 29 23:46:26 volumio volumio[1239]: info: Mar 29 23:46:26 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 29 23:46:26 volumio volumio[1239]: info: ControllerMpd::getState Mar 29 23:46:26 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 29 23:46:26 volumio volumio[1239]: info: sendMpdCommand status took 7 milliseconds Mar 29 23:46:26 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 29 23:46:26 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 23:46:26 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 23:46:26 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 29 23:46:26 volumio volumio[1239]: info: ControllerMpd::pushState Mar 29 23:46:26 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 29 23:46:26 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:26 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":131000,"duration":218,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"I've Got You Under My Skin","artist":"Grace Mahya","album":"Love Songs For You","uri":"NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf","trackType":"dsf"} Mar 29 23:46:26 volumio volumio[1239]: verbose: CURRENT POSITION 0 Mar 29 23:46:26 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 29 23:46:26 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Mar 29 23:46:26 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Mar 29 23:46:26 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:26 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:26 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:26 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:26 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:26 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:26 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:26.281+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=131000 volume=100 Mar 29 23:46:26 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:26.282+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=131000 volume=100 Mar 29 23:46:26 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:26.282+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:26 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:26.282+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:26 volumio volumio[1239]: info: ------------------------------ 98ms Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::volumioNext Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::next Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::stop Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::stPlaybackTimer Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::serviceStop Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 0 Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::serviceStop Mar 29 23:46:27 volumio volumio[1239]: info: ControllerMpd::stop Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.426+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.426+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf" title="I've Got You Under My Skin" Mar 29 23:46:27 volumio volumio[1239]: info: Mar 29 23:46:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand stop took 8 milliseconds Mar 29 23:46:27 volumio volumio[1239]: info: ControllerMpd::getState Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::play index undefined Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::clearAddPlayTracks NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand status took 5 milliseconds Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand stop took 2 milliseconds Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 23:46:27 volumio volumio[1239]: info: Mar 29 23:46:27 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 29 23:46:27 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand clear took 5 milliseconds Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand add "NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" Mar 29 23:46:27 volumio volumio[1239]: info: ControllerMpd::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I've Got You Under My Skin","artist":"Grace Mahya","album":"Love Songs For You","uri":"NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/01-I've Got You Under My Skin.dsf","trackType":"dsf"} Mar 29 23:46:27 volumio volumio[1239]: verbose: CURRENT POSITION 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService stop Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:27 volumio volumio[1239]: info: No code Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.459+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.459+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.459+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.459+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.460+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.460+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:46:27 volumio volumio[1239]: info: ------------------------------ 43ms Mar 29 23:46:27 volumio volumio[1239]: error: updateQueue error: null Mar 29 23:46:27 volumio volumio[1239]: info: Mar 29 23:46:27 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 29 23:46:27 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 29 23:46:27 volumio volumio[1239]: info: ------------------------------ 36ms Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand add "NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" took 34 milliseconds Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand play Mar 29 23:46:27 volumio volumio[1239]: info: ------------------------------ 10ms Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand play took 9 milliseconds Mar 29 23:46:27 volumio volumio[1239]: info: Mar 29 23:46:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 29 23:46:27 volumio volumio[1239]: info: ControllerMpd::getState Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 29 23:46:27 volumio volumio[1239]: info: Mar 29 23:46:27 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand status took 3 milliseconds Mar 29 23:46:27 volumio volumio[1239]: info: ControllerMpd::getState Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand status took 2 milliseconds Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 29 23:46:27 volumio volumio[1239]: info: ControllerMpd::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Antonio's Song","artist":"Grace Mahya","album":"Love Songs For You","uri":"NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf","trackType":"dsf"} Mar 29 23:46:27 volumio volumio[1239]: verbose: CURRENT POSITION 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus stop Mar 29 23:46:27 volumio volumio[1239]: info: ------------------------------ 11ms Mar 29 23:46:27 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 29 23:46:27 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 29 23:46:27 volumio volumio[1239]: info: ControllerMpd::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Antonio's Song","artist":"Grace Mahya","album":"Love Songs For You","uri":"NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf","trackType":"dsf"} Mar 29 23:46:27 volumio volumio[1239]: verbose: CURRENT POSITION 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Mar 29 23:46:27 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:27 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:27 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:27 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.600+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.600+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.602+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 23:46:27 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:27.602+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:46:27 volumio volumio[1239]: info: ------------------------------ 30ms Mar 29 23:46:28 volumio volumio[1239]: info: CoreCommandRouter::volumioSeek Mar 29 23:46:28 volumio volumio[1239]: info: CoreStateMachine::seek Mar 29 23:46:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:28 volumio volumio[1239]: info: TRACKBLOCK {"uri":"mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf","service":"mpd","name":"Antonio's Song","artist":"Grace Mahya","album":"Love Songs For You","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=676&web=Grace%20Mahya/Love%20Songs%20For%20You/extralarge&path=%2FNAS%2FNasWin%2FGrace%20Mahya%20-%20Love%20Songs%20For%20You%20(2016)%20%5BMora.JP%20DSD128%5D&metadata=false","duration":276,"samplerate":"5.64 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Mar 29 23:46:28 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Mar 29 23:46:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:28 volumio volumio[1239]: info: ControllerMpd::seek Mar 29 23:46:28 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:28 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:46:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:28 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:28.260+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=156000 volume=100 Mar 29 23:46:28 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:28.260+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:46:28 volumio volumio[1239]: error: null Mar 29 23:46:28 volumio volumio[1239]: info: Mar 29 23:46:28 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 29 23:46:28 volumio volumio[1239]: info: ControllerMpd::getState Mar 29 23:46:28 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 29 23:46:28 volumio volumio[1239]: info: sendMpdCommand status took 4 milliseconds Mar 29 23:46:28 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 29 23:46:28 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 23:46:28 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 23:46:28 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 29 23:46:28 volumio volumio[1239]: info: ControllerMpd::pushState Mar 29 23:46:28 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 29 23:46:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:28 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156000,"duration":276,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Antonio's Song","artist":"Grace Mahya","album":"Love Songs For You","uri":"NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf","trackType":"dsf"} Mar 29 23:46:28 volumio volumio[1239]: verbose: CURRENT POSITION 1 Mar 29 23:46:28 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 29 23:46:28 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Mar 29 23:46:28 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Mar 29 23:46:28 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:28 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:46:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:46:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:46:28 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:28.294+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=156000 volume=100 Mar 29 23:46:28 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:28.294+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:46:28 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:28.295+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_PLAYING positionMs=156000 volume=100 Mar 29 23:46:28 volumio volumio5-onboarding[1833]: time=2026-03-29T23:46:28.295+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:46:28 volumio volumio[1239]: info: ------------------------------ 39ms Mar 29 23:47:10 volumio volumio[1239]: info: Preload queue cleared Mar 29 23:47:10 volumio volumio[1239]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 23:47:10 volumio volumio[1239]: info: CoreStateMachine::ClearQueue Mar 29 23:47:10 volumio volumio[1239]: info: CoreStateMachine::stop Mar 29 23:47:10 volumio volumio[1239]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:47:10 volumio volumio[1239]: info: CoreStateMachine::stPlaybackTimer Mar 29 23:47:10 volumio volumio[1239]: info: CoreStateMachine::updateTrackBlock Mar 29 23:47:10 volumio volumio[1239]: info: CorePlayQueue::getTrackBlock Mar 29 23:47:10 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 29 23:47:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:47:10 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 23:47:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 29 23:47:10 volumio volumio[1239]: info: CoreStateMachine::serviceStop Mar 29 23:47:10 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 29 23:47:10 volumio volumio[1239]: info: CoreCommandRouter::serviceStop Mar 29 23:47:10 volumio volumio[1239]: info: ControllerMpd::stop Mar 29 23:47:10 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 23:47:10 volumio volumio[1239]: info: CorePlayQueue::clearPlayQueue Mar 29 23:47:10 volumio volumio[1239]: info: CorePlayQueue::saveQueue Mar 29 23:47:10 volumio volumio[1239]: info: CoreCommandRouter::volumioPushQueue Mar 29 23:47:10 volumio volumio[1239]: info: CoreStateMachine::addQueueItems Mar 29 23:47:10 volumio volumio[1239]: info: CorePlayQueue::addQueueItems Mar 29 23:47:10 volumio volumio[1239]: info: Preload queue cleared Mar 29 23:47:10 volumio volumio[1239]: info: Adding Item to queue: music-library/NAS/NasWin/Grace Mahya-Last Live at DUG 2007 Mar 29 23:47:10 volumio volumio[1239]: info: Exploding uri music-library/NAS/NasWin/Grace Mahya-Last Live at DUG 2007 in service mpd Mar 29 23:47:10 volumio volumio5-onboarding[1833]: time=2026-03-29T23:47:10.127+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 23:47:10 volumio volumio5-onboarding[1833]: time=2026-03-29T23:47:10.127+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.105:39682,00:00:00:00:00:00%01 @ 0xc00037d3e0" id="mnt/NAS/NasWin/Grace Mahya - Love Songs For You (2016) [Mora.JP DSD128]/02-Antonio's Song.dsf" title="Antonio's Song" Mar 29 23:47:10 volumio volumio[1239]: info: Mar 29 23:47:10 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 29 23:47:10 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=//extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya-Last%20Live%20at%20DUG%202007%2FGrace%20Mahya%20-%20Last%20Live%20at%20DUG.ape&metadata=false Mar 29 23:47:10 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya-Last Live at DUG 2007/Grace Mahya - Last Live at DUG.ape Mar 29 23:47:10 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Last%20Live%20at%20DUG/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya-Last%20Live%20at%20DUG%202007%2FGrace%20Mahya%20-%20Last%20Live%20at%20DUG.cue&metadata=false Mar 29 23:47:10 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya-Last Live at DUG 2007/Grace Mahya - Last Live at DUG.cue Mar 29 23:47:10 volumio volumio[1239]: info: ALBUMART /albumart?cacheid=676&web=Grace%20Mahya/Last%20Live%20at%20DUG/extralarge&path=%2Fmnt%2FNAS%2FNasWin%2FGrace%20Mahya-Last%20Live%20at%20DUG%202007%2FGrace%20Mahya%20-%20Last%20Live%20at%20DUG.cue&metadata=false Mar 29 23:47:10 volumio volumio[1239]: info: URI /mnt/NAS/NasWin/Grace Mahya-Last Live at DUG 2007/Grace Mahya - Last Live at DUG.cue Mar 29 23:47:10 volumio volumio[1239]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 23:47:10 volumio volumio[1239]: Error: Unable to resolve or reject the same promise twice Mar 29 23:47:10 volumio volumio[1239]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 29 23:47:10 volumio volumio[1239]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 29 23:47:10 volumio volumio[1239]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 29 23:47:10 volumio volumio[1239]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 29 23:47:10 volumio volumio[1239]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 29 23:47:10 volumio volumio[1239]: at Socket.emit (node:events:514:28) Mar 29 23:47:10 volumio volumio[1239]: at addChunk (node:internal/streams/readable:343:12) Mar 29 23:47:10 volumio volumio[1239]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 29 23:47:10 volumio volumio[1239]: at Readable.push (node:internal/streams/readable:253:10) Mar 29 23:47:10 volumio volumio[1239]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 29 23:47:10 volumio volumio[1239]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 23:47:10 volumio sudo[2599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 23:46' Mar 29 23:47:10 volumio sudo[2599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"