-- Logs begin at Wed 2026-05-27 14:03:22 UTC, end at Wed 2026-05-27 15:14:26 UTC. -- May 27 15:13:27 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:27 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:27 volumio-4 volumio[649]: info: Retrieving Cloud Streaming UI May 27 15:13:27 volumio-4 volumio[649]: info: Getting Tidal Cloud Configuration May 27 15:13:27 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:27 volumio-4 volumio[649]: info: Getting Qobuz Cloud Configuration May 27 15:13:27 volumio-4 volumio[649]: info: Asking plugin for UI Config May 27 15:13:27 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:27 volumio-4 volumio[649]: info: Getting Spotify Cloud Configuration May 27 15:13:27 volumio-4 volumio[649]: info: Asking plugin for UI Config May 27 15:13:27 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:27 volumio-4 volumio[649]: info: Saving Spotify Acccount May 27 15:13:27 volumio-4 volumio[649]: info: Got Tidal Cloud Configuration May 27 15:13:27 volumio-4 volumio[649]: info: Got it May 27 15:13:27 volumio-4 volumio[649]: info: Got it May 27 15:13:27 volumio-4 volumio[649]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 27 15:13:27 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:27 volumio-4 volumio[649]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::volumioGetBrowseSources May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 15:13:28 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 27 15:13:32 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 15:13:36 volumio-4 volumio[649]: Invalid card number. May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 27 15:13:36 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 15:13:49 volumio-4 volumio[649]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 27 15:13:49 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 27 15:13:49 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 15:13:49 volumio-4 volumio[649]: info: Output device has changed, restarting MPD May 27 15:13:49 volumio-4 volumio[649]: info: Output device has changed, restarting Shairport Sync May 27 15:13:49 volumio-4 sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 15:13:49 volumio-4 sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 15:13:49 volumio-4 sudo[1459]: pam_unix(sudo:session): session closed for user root May 27 15:13:49 volumio-4 sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 15:13:49 volumio-4 sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 15:13:49 volumio-4 systemd[1]: Stopping Music Player Daemon... May 27 15:13:50 volumio-4 volumio[649]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 15:13:50 volumio-4 systemd[1]: Starting Music Player Daemon... May 27 15:13:50 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 15:13:50 volumio-4 volumio[649]: Invalid card number. May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 27 15:13:51 volumio-4 volumio[649]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:51 volumio-4 volumio[649]: error: Upnp client error: Error: This socket has been ended by the other party May 27 15:13:51 volumio-4 volumio[649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 15:13:51 volumio-4 volumio[649]: info: MPD Permissions set May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:51 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 15:13:52 volumio-4 volumio[649]: Invalid card number. May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 15:13:52 volumio-4 volumio[649]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 27 15:13:52 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 15:13:52 volumio-4 volumio[649]: info: Output device has changed, restarting MPD May 27 15:13:53 volumio-4 volumio[649]: info: Output device has changed, restarting Shairport Sync May 27 15:13:53 volumio-4 sudo[1496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 15:13:53 volumio-4 sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 15:13:53 volumio-4 sudo[1496]: pam_unix(sudo:session): session closed for user root May 27 15:13:53 volumio-4 sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 15:13:53 volumio-4 sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 15:13:54 volumio-4 systemd[1]: Stopping Music Player Daemon... May 27 15:13:54 volumio-4 volumio[649]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 15:13:54 volumio-4 systemd[1]: Starting Music Player Daemon... May 27 15:13:54 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 15:13:54 volumio-4 volumio[649]: Invalid card number. May 27 15:13:55 volumio-4 volumio[649]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 27 15:13:55 volumio-4 volumio[649]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 May 27 15:13:55 volumio-4 volumio[649]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 15:13:55 volumio-4 volumio[649]: info: VolumeController:: Volume=undefined Mute =false May 27 15:13:55 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:13:55 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:13:55 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:13:55 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:13:55 volumio-4 volumio[649]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 27 15:13:55 volumio-4 volumio[649]: info: CoreStateMachine::getcurrentVolume May 27 15:13:55 volumio-4 volumio[649]: info: CoreCommandRouter::volumioRetrievevolume May 27 15:13:55 volumio-4 volumio[649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 15:13:55 volumio-4 volumio[649]: info: MPD Permissions set May 27 15:13:56 volumio-4 volumio[649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 15:13:56 volumio-4 volumio[649]: info: Starting Shairport Sync May 27 15:13:56 volumio-4 volumio[649]: info: VolumeController:: Volume=undefined Mute =false May 27 15:13:56 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:13:56 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:13:56 volumio-4 sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 15:13:56 volumio-4 sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:56 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:57 volumio-4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 27 15:13:57 volumio-4 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 27 15:13:57 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:13:57 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:13:57 volumio-4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 27 15:13:57 volumio-4 sudo[1526]: pam_unix(sudo:session): session closed for user root May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 15:13:58 volumio-4 volumio[649]: Invalid card number. May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 27 15:13:58 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 15:13:59 volumio-4 volumio[649]: info: VolumeController:: Volume=undefined Mute =false May 27 15:13:59 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:13:59 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:13:59 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:13:59 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:13:59 volumio-4 volumio[649]: info: CoreStateMachine::updateTrackBlock May 27 15:13:59 volumio-4 volumio[649]: info: CorePlayQueue::getTrackBlock May 27 15:13:59 volumio-4 volumio[649]: info: CoreCommandRouter::volumioRetrievevolume May 27 15:13:59 volumio-4 volumio[649]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 27 15:13:59 volumio-4 volumio[649]: info: CoreStateMachine::getcurrentVolume May 27 15:13:59 volumio-4 volumio[649]: info: CoreCommandRouter::volumioRetrievevolume May 27 15:14:00 volumio-4 volumio[649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 15:14:00 volumio-4 volumio[649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 15:14:00 volumio-4 volumio[649]: info: Shairport-Sync Started May 27 15:14:00 volumio-4 volumio[649]: info: VolumeController:: Volume=undefined Mute =false May 27 15:14:00 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:00 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:00 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:00 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:00 volumio-4 volumio[649]: info: CoreStateMachine::updateTrackBlock May 27 15:14:00 volumio-4 volumio[649]: info: CorePlayQueue::getTrackBlock May 27 15:14:00 volumio-4 volumio[649]: info: CoreCommandRouter::volumioRetrievevolume May 27 15:14:00 volumio-4 volumio[649]: info: Starting Shairport Sync May 27 15:14:01 volumio-4 sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 15:14:01 volumio-4 volumio[649]: info: VolumeController:: Volume=undefined Mute =false May 27 15:14:01 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:01 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:01 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:01 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:01 volumio-4 sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 15:14:01 volumio-4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 27 15:14:01 volumio-4 volumio[649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 15:14:01 volumio-4 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 27 15:14:01 volumio-4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 27 15:14:01 volumio-4 sudo[1559]: pam_unix(sudo:session): session closed for user root May 27 15:14:02 volumio-4 systemd[1]: Started Music Player Daemon. May 27 15:14:02 volumio-4 sudo[1498]: pam_unix(sudo:session): session closed for user root May 27 15:14:02 volumio-4 sudo[1461]: pam_unix(sudo:session): session closed for user root May 27 15:14:02 volumio-4 volumio[649]: info: VolumeController:: Volume=undefined Mute =false May 27 15:14:02 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:02 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:02 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:02 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:02 volumio-4 volumio[649]: info: Shairport-Sync Started May 27 15:14:03 volumio-4 volumio[649]: error: MPD error: false == true May 27 15:14:03 volumio-4 volumio[649]: error: updateQueue error: null May 27 15:14:07 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPlay May 27 15:14:07 volumio-4 volumio[649]: verbose: UNSET VOLATILE May 27 15:14:07 volumio-4 volumio[649]: info: CoreStateMachine::play index undefined May 27 15:14:07 volumio-4 volumio[649]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:14:07 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:07 volumio-4 volumio[649]: info: CoreStateMachine::startPlaybackTimer May 27 15:14:07 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::clearAddPlayTracks USB/0D6A-9D20/Street Hawk Theme (Enzo Margaglio Remix).mp3 May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand stop May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand stop took 90 milliseconds May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clear May 27 15:14:07 volumio-4 volumio[649]: info: May 27 15:14:07 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:07 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand clear took 35 milliseconds May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand add "USB/0D6A-9D20/Street Hawk Theme (Enzo Margaglio Remix).mp3" May 27 15:14:07 volumio-4 volumio[649]: info: May 27 15:14:07 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:07 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:07 volumio-4 volumio[649]: error: updateQueue error: null May 27 15:14:07 volumio-4 volumio[649]: info: May 27 15:14:07 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:07 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:07 volumio-4 volumio[649]: info: ------------------------------ 126ms May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand add "USB/0D6A-9D20/Street Hawk Theme (Enzo Margaglio Remix).mp3" took 97 milliseconds May 27 15:14:07 volumio-4 volumio[649]: info: ------------------------------ 82ms May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand play May 27 15:14:07 volumio-4 volumio[649]: info: May 27 15:14:07 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:07 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:07 volumio-4 volumio[649]: info: ------------------------------ 101ms May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand play took 55 milliseconds May 27 15:14:07 volumio-4 volumio[649]: info: ------------------------------ 50ms May 27 15:14:07 volumio-4 volumio[649]: info: May 27 15:14:07 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:07 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:07 volumio-4 volumio[649]: info: May 27 15:14:07 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:07 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:07 volumio-4 volumio[649]: info: May 27 15:14:07 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:07 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clearerror May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand status took 129 milliseconds May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clearerror May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand status took 121 milliseconds May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:07 volumio-4 volumio[649]: info: May 27 15:14:07 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:07 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clearerror May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand status took 267 milliseconds May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand clearerror took 211 milliseconds May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand clearerror took 195 milliseconds May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 146 milliseconds May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 139 milliseconds May 27 15:14:07 volumio-4 volumio[649]: info: sendMpdCommand status took 104 milliseconds May 27 15:14:07 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:08 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:08 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:08 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:08 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:08 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:08 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:08 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:08 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Street Hawk Theme","artist":"Enzo Margaglio Remix","album":null,"uri":"USB/0D6A-9D20/Street Hawk Theme (Enzo Margaglio Remix).mp3","trackType":"mp3"} May 27 15:14:08 volumio-4 volumio[649]: verbose: CURRENT POSITION 4 May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:08 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:08 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:08 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:08 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Street Hawk Theme","artist":"Enzo Margaglio Remix","album":null,"uri":"USB/0D6A-9D20/Street Hawk Theme (Enzo Margaglio Remix).mp3","trackType":"mp3"} May 27 15:14:08 volumio-4 volumio[649]: verbose: CURRENT POSITION 4 May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:08 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:08 volumio-4 volumio[649]: info: ------------------------------ 841ms May 27 15:14:08 volumio-4 volumio[649]: info: ------------------------------ 745ms May 27 15:14:08 volumio-4 volumio[649]: info: sendMpdCommand clearerror took 511 milliseconds May 27 15:14:08 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 441 milliseconds May 27 15:14:08 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 416 milliseconds May 27 15:14:08 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:08 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:08 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:08 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:08 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Street Hawk Theme","artist":"Enzo Margaglio Remix","album":null,"uri":"USB/0D6A-9D20/Street Hawk Theme (Enzo Margaglio Remix).mp3","trackType":"mp3"} May 27 15:14:08 volumio-4 volumio[649]: verbose: CURRENT POSITION 4 May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:08 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:08 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:08 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:08 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Street Hawk Theme","artist":"Enzo Margaglio Remix","album":null,"uri":"USB/0D6A-9D20/Street Hawk Theme (Enzo Margaglio Remix).mp3","trackType":"mp3"} May 27 15:14:08 volumio-4 volumio[649]: verbose: CURRENT POSITION 4 May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:08 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:08 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 4 May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:08 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:08 volumio-4 volumio[649]: info: ------------------------------ 1051ms May 27 15:14:08 volumio-4 volumio[649]: info: ------------------------------ 859ms May 27 15:14:14 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPrevious May 27 15:14:14 volumio-4 volumio[649]: info: CoreStateMachine::previous May 27 15:14:14 volumio-4 volumio[649]: info: CoreStateMachine::updateTrackBlock May 27 15:14:14 volumio-4 volumio[649]: info: CorePlayQueue::getTrackBlock May 27 15:14:14 volumio-4 volumio[649]: info: CoreStateMachine::serviceClearAddPlay May 27 15:14:14 volumio-4 volumio[649]: info: CoreCommandRouter::serviceClearAddPlayTracks May 27 15:14:14 volumio-4 volumio[649]: error: WARNING: No clearAddPlayTracks method for service mpd May 27 15:14:16 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPlay May 27 15:14:16 volumio-4 volumio[649]: verbose: UNSET VOLATILE May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::play index undefined May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:14:16 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 8 May 27 15:14:16 volumio-4 volumio[649]: info: CoreCommandRouter::volumioNext May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::next May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::stop May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:14:16 volumio-4 volumio[649]: verbose: UNSET VOLATILE May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::play index undefined May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:14:16 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::startPlaybackTimer May 27 15:14:16 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:16 volumio-4 volumio[649]: verbose: ControllerMpd::clearAddPlayTracks USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3 May 27 15:14:16 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand stop May 27 15:14:16 volumio-4 volumio[649]: info: CoreStateMachine::updateTrackBlock May 27 15:14:16 volumio-4 volumio[649]: info: CorePlayQueue::getTrackBlock May 27 15:14:16 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:17 volumio-4 volumio[649]: info: sendMpdCommand stop took 61 milliseconds May 27 15:14:17 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clear May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:17 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:17 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:17 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:17 volumio-4 volumio[649]: info: sendMpdCommand status took 87 milliseconds May 27 15:14:17 volumio-4 volumio[649]: info: sendMpdCommand clear took 83 milliseconds May 27 15:14:17 volumio-4 volumio[649]: info: sendMpdCommand status took 65 milliseconds May 27 15:14:17 volumio-4 volumio[649]: info: sendMpdCommand status took 48 milliseconds May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand add "USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3" May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:17 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:17 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:17 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:17 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 15:14:17 volumio-4 volumio[649]: verbose: CURRENT POSITION 3 May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService stop May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:17 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:17 volumio-4 volumio[649]: info: No code May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:17 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:17 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:17 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:17 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:17 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 27 15:14:17 volumio-4 volumio[649]: verbose: CURRENT POSITION 3 May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService stop May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:17 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:17 volumio-4 volumio[649]: info: No code May 27 15:14:17 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:17 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:17 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 478ms May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 467ms May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:17 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:17 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:17 volumio-4 volumio[649]: error: updateQueue error: null May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:17 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 508ms May 27 15:14:17 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 470 milliseconds May 27 15:14:17 volumio-4 volumio[649]: info: sendMpdCommand add "USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3" took 466 milliseconds May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 76ms May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 60ms May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand play May 27 15:14:17 volumio-4 volumio[649]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 654ms May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:17 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces system playlist update May 27 15:14:17 volumio-4 volumio[649]: info: Ignoring MPD Status Update May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 162ms May 27 15:14:17 volumio-4 volumio[649]: info: sendMpdCommand play took 117 milliseconds May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 91ms May 27 15:14:17 volumio-4 volumio[649]: info: ------------------------------ 70ms May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:17 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:17 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:17 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:17 volumio-4 volumio[649]: info: May 27 15:14:17 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:17 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:18 volumio-4 volumio[649]: info: May 27 15:14:18 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:18 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clearerror May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand status took 221 milliseconds May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clearerror May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand status took 175 milliseconds May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clearerror May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand status took 166 milliseconds May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:18 volumio-4 volumio[649]: info: May 27 15:14:18 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:18 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:18 volumio-4 volumio[649]: info: May 27 15:14:18 volumio-4 volumio[649]: ---------------------------- MPD announces state update: player May 27 15:14:18 volumio-4 volumio[649]: info: ControllerMpd::getState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand status May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand clearerror May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand status took 642 milliseconds May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand clearerror took 614 milliseconds May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand clearerror took 599 milliseconds May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand clearerror took 580 milliseconds May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 546 milliseconds May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 488 milliseconds May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 465 milliseconds May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand status took 176 milliseconds May 27 15:14:18 volumio-4 volumio[649]: info: sendMpdCommand status took 131 milliseconds May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::parseState May 27 15:14:18 volumio-4 volumio[649]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 15:14:18 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:18 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:18 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:18 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Infinity, Happy Summer Song - RadioEdit","artist":"Sebastian Lightfoot","album":null,"uri":"USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3","trackType":"mp3"} May 27 15:14:18 volumio-4 volumio[649]: verbose: CURRENT POSITION 3 May 27 15:14:18 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:18 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:18 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:18 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:18 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:18 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:18 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:18 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:18 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:18 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Infinity, Happy Summer Song - RadioEdit","artist":"Sebastian Lightfoot","album":null,"uri":"USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3","trackType":"mp3"} May 27 15:14:19 volumio-4 volumio[649]: verbose: CURRENT POSITION 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:19 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Infinity, Happy Summer Song - RadioEdit","artist":"Sebastian Lightfoot","album":null,"uri":"USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3","trackType":"mp3"} May 27 15:14:19 volumio-4 volumio[649]: verbose: CURRENT POSITION 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:19 volumio-4 volumio[649]: info: ------------------------------ 1315ms May 27 15:14:19 volumio-4 volumio[649]: info: ------------------------------ 1256ms May 27 15:14:19 volumio-4 volumio[649]: info: ------------------------------ 1230ms May 27 15:14:19 volumio-4 volumio[649]: info: sendMpdCommand clearerror took 590 milliseconds May 27 15:14:19 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 500 milliseconds May 27 15:14:19 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 464 milliseconds May 27 15:14:19 volumio-4 volumio[649]: info: sendMpdCommand playlistinfo took 458 milliseconds May 27 15:14:19 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:19 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:19 volumio-4 volumio[649]: verbose: ControllerMpd::parseTrackInfo May 27 15:14:19 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Infinity, Happy Summer Song - RadioEdit","artist":"Sebastian Lightfoot","album":null,"uri":"USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3","trackType":"mp3"} May 27 15:14:19 volumio-4 volumio[649]: verbose: CURRENT POSITION 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:19 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Infinity, Happy Summer Song - RadioEdit","artist":"Sebastian Lightfoot","album":null,"uri":"USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3","trackType":"mp3"} May 27 15:14:19 volumio-4 volumio[649]: verbose: CURRENT POSITION 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:19 volumio-4 volumio[649]: info: ControllerMpd::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::servicePushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Infinity, Happy Summer Song - RadioEdit","artist":"Sebastian Lightfoot","album":null,"uri":"USB/0D6A-9D20/Sebastian Lightfoot - Infinity, Happy Summer Song - RadioEdit.mp3","trackType":"mp3"} May 27 15:14:19 volumio-4 volumio[649]: verbose: CURRENT POSITION 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState stateService pause May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::syncState currentStatus stop May 27 15:14:19 volumio-4 volumio[649]: info: CoreStateMachine::pushState May 27 15:14:19 volumio-4 volumio[649]: info: CorePlayQueue::getTrack 3 May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::volumioPushState May 27 15:14:19 volumio-4 volumio[649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 15:14:19 volumio-4 volumio[649]: info: ------------------------------ 1541ms May 27 15:14:19 volumio-4 volumio[649]: info: ------------------------------ 1039ms May 27 15:14:19 volumio-4 volumio[649]: info: ------------------------------ 997ms May 27 15:14:21 volumio-4 volumio[649]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 15:14:21 volumio-4 volumio[649]: TypeError: Cannot read property 'slice' of null May 27 15:14:21 volumio-4 volumio[649]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:714:41) May 27 15:14:21 volumio-4 volumio[649]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:724:7) May 27 15:14:21 volumio-4 volumio[649]: at Object.onceWrapper (events.js:315:30) May 27 15:14:21 volumio-4 volumio[649]: at emitOne (events.js:116:13) May 27 15:14:22 volumio-4 volumio[649]: at ClientRequest.emit (events.js:211:7) May 27 15:14:22 volumio-4 volumio[649]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) May 27 15:14:22 volumio-4 volumio[649]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) May 27 15:14:22 volumio-4 volumio[649]: at TLSSocket.socketOnData (_http_client.js:440:20) May 27 15:14:22 volumio-4 volumio[649]: at emitOne (events.js:116:13) May 27 15:14:22 volumio-4 volumio[649]: at TLSSocket.emit (events.js:211:7) May 27 15:14:22 volumio-4 volumio[649]: at addChunk (_stream_readable.js:263:12) May 27 15:14:22 volumio-4 volumio[649]: at readableAddChunk (_stream_readable.js:250:11) May 27 15:14:22 volumio-4 volumio[649]: at TLSSocket.Readable.push (_stream_readable.js:208:10) May 27 15:14:22 volumio-4 volumio[649]: at TLSWrap.onread (net.js:607:20) May 27 15:14:22 volumio-4 volumio[649]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 15:14:26 volumio-4 sudo[1619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-27 15:13 May 27 15:14:26 volumio-4 sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"