Feb 08 17:26:22 volumio-4 ntpd[1012]: PROTO: 172.104.28.175 unlink local addr 10.0.0.34 -> Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 08 17:26:34 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 08 17:26:47 volumio-4 volumio[1215]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 08 17:26:47 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 08 17:26:47 volumio-4 volumio[1215]: info: Preparing to save Alsa Options, stopping services first Feb 08 17:26:47 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioGetState Feb 08 17:26:47 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 0 Feb 08 17:26:47 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPause Feb 08 17:26:47 volumio-4 volumio[1215]: info: CoreStateMachine::pause Feb 08 17:26:47 volumio-4 volumio[1215]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Digital Hifi Audio"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 08 17:26:47 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 08 17:26:48 volumio-4 volumio[1215]: info: Setting mixer Extension Unit for card Digital Hifi Audio Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 08 17:26:48 volumio-4 volumio[1215]: info: Updating Volume Controller Parameters: Device: 5 Name: Digital Hifi Audio Mixer: Extension Unit Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 08 17:26:48 volumio-4 volumio[1215]: info: Disabling external Volume Control Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 08 17:26:48 volumio-4 volumio[1215]: info: Preparing to generate the ALSA configuration file Feb 08 17:26:48 volumio-4 volumio[1215]: Unable to parse: Feb 08 17:26:48 volumio-4 volumio[1215]: Simple mixer control 'Extension Unit',0 Feb 08 17:26:48 volumio-4 volumio[1215]: Capabilities: pswitch pswitch-joined Feb 08 17:26:48 volumio-4 volumio[1215]: Playback channels: Mono Feb 08 17:26:48 volumio-4 volumio[1215]: Mono: Playback [off] Feb 08 17:26:48 volumio-4 volumio[1215]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 08 17:26:48 volumio-4 volumio[1215]: Unable to parse: Feb 08 17:26:48 volumio-4 volumio[1215]: Simple mixer control 'Extension Unit',0 Feb 08 17:26:48 volumio-4 volumio[1215]: Capabilities: pswitch pswitch-joined Feb 08 17:26:48 volumio-4 volumio[1215]: Playback channels: Mono Feb 08 17:26:48 volumio-4 volumio[1215]: Mono: Playback [off] Feb 08 17:26:48 volumio-4 volumio[1215]: info: VolumeController:: Volume=undefined Mute =false Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:26:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 0 Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:26:48 volumio-4 volumio[1215]: info: Asound.conf file written Feb 08 17:26:48 volumio-4 sudo[2892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 08 17:26:48 volumio-4 sudo[2892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 17:26:48 volumio-4 sudo[2892]: pam_unix(sudo:session): session closed for user root Feb 08 17:26:48 volumio-4 volumio[1215]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 08 17:26:48 volumio-4 volumio[1215]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 08 17:26:48 volumio-4 volumio[1215]: No state is present for card Audio Feb 08 17:26:48 volumio-4 volumio[1215]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0158" "" "" Feb 08 17:26:48 volumio-4 volumio[1215]: Hardware is initialized using a generic method Feb 08 17:26:48 volumio-4 volumio[1215]: No state is present for card Audio Feb 08 17:26:48 volumio-4 volumio[1215]: info: Output device has changed, restarting MPD Feb 08 17:26:48 volumio-4 volumio[1215]: info: Output device has changed, restarting Shairport Sync Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 17:26:48 volumio-4 sudo[2898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 17:26:48 volumio-4 sudo[2898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 17:26:48 volumio-4 sudo[2900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 17:26:48 volumio-4 sudo[2900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 17:26:48 volumio-4 sudo[2898]: pam_unix(sudo:session): session closed for user root Feb 08 17:26:48 volumio-4 volumio[1215]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 17:26:48 volumio-4 volumio[1215]: info: Output device has changed, restarting MPD Feb 08 17:26:48 volumio-4 volumio[1215]: info: Output device has changed, restarting Shairport Sync Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 17:26:48 volumio-4 sudo[2908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 17:26:48 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 17:26:48 volumio-4 sudo[2908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 17:26:48 volumio-4 sudo[2908]: pam_unix(sudo:session): session closed for user root Feb 08 17:26:48 volumio-4 sudo[2910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 17:26:48 volumio-4 sudo[2910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 17:26:48 volumio-4 volumio[1215]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 17:26:48 volumio-4 volumio[1215]: info: MPD Permissions set Feb 08 17:26:48 volumio-4 volumio[1215]: info: MPD Permissions set Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 17:26:48 volumio-4 volumio[1215]: info: Starting Shairport Sync Feb 08 17:26:48 volumio-4 volumio[1215]: info: Starting Shairport Sync Feb 08 17:26:48 volumio-4 sudo[2932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 17:26:48 volumio-4 sudo[2932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 17:26:48 volumio-4 sudo[2934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 17:26:48 volumio-4 sudo[2934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 17:26:48 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 17:26:48 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 17:26:48 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 17:26:48 volumio-4 systemd[1]: shairport-sync.service: Consumed 3.524s CPU time. Feb 08 17:26:48 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 17:26:48 volumio-4 sudo[2932]: pam_unix(sudo:session): session closed for user root Feb 08 17:26:48 volumio-4 volumio[1215]: info: Shairport-Sync Started Feb 08 17:26:48 volumio-4 sudo[2934]: pam_unix(sudo:session): session closed for user root Feb 08 17:26:48 volumio-4 volumio[1215]: info: Shairport-Sync Started Feb 08 17:26:48 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Feb 08 17:26:48 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 17:26:48 volumio-4 systemd[1]: mpd.service: Consumed 4.419s CPU time. Feb 08 17:26:48 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Feb 08 17:26:48 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 17:26:48 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 17:26:48 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 17:26:48 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 17:26:48 volumio-4 sudo[2953]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 17:26:48 volumio-4 sudo[2953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 17:26:48 volumio-4 sudo[2953]: pam_unix(sudo:session): session closed for user root Feb 08 17:26:50 volumio-4 mpd[2955]: 2026-02-08T17:26:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 17:26:50 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 17:26:50 volumio-4 sudo[2910]: pam_unix(sudo:session): session closed for user root Feb 08 17:26:50 volumio-4 sudo[2900]: pam_unix(sudo:session): session closed for user root Feb 08 17:26:50 volumio-4 volumio[1215]: error: MPD error: The expression evaluated to a falsy value: Feb 08 17:26:50 volumio-4 volumio[1215]: assert.ok(self.idling) Feb 08 17:26:50 volumio-4 volumio[1215]: error: The expression evaluated to a falsy value: Feb 08 17:26:50 volumio-4 volumio[1215]: assert.ok(self.idling) Feb 08 17:26:50 volumio-4 volumio[1215]: error: updateQueue error: null Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreStateMachine::getcurrentVolume Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 17:26:52 volumio-4 volumio[1215]: Unable to parse: Feb 08 17:26:52 volumio-4 volumio[1215]: Simple mixer control 'Extension Unit',0 Feb 08 17:26:52 volumio-4 volumio[1215]: Capabilities: pswitch pswitch-joined Feb 08 17:26:52 volumio-4 volumio[1215]: Playback channels: Mono Feb 08 17:26:52 volumio-4 volumio[1215]: Mono: Playback [off] Feb 08 17:26:52 volumio-4 volumio[1215]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 08 17:26:52 volumio-4 volumio[1215]: Unable to parse: Feb 08 17:26:52 volumio-4 volumio[1215]: Simple mixer control 'Extension Unit',0 Feb 08 17:26:52 volumio-4 volumio[1215]: Capabilities: pswitch pswitch-joined Feb 08 17:26:52 volumio-4 volumio[1215]: Playback channels: Mono Feb 08 17:26:52 volumio-4 volumio[1215]: Mono: Playback [off] Feb 08 17:26:52 volumio-4 volumio[1215]: info: VolumeController:: Volume=undefined Mute =false Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:26:52 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 0 Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreStateMachine::updateTrackBlock Feb 08 17:26:52 volumio-4 volumio[1215]: info: CorePlayQueue::getTrackBlock Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 17:26:52 volumio-4 volumio[1215]: Unable to parse: Feb 08 17:26:52 volumio-4 volumio[1215]: Simple mixer control 'Extension Unit',0 Feb 08 17:26:52 volumio-4 volumio[1215]: Capabilities: pswitch pswitch-joined Feb 08 17:26:52 volumio-4 volumio[1215]: Playback channels: Mono Feb 08 17:26:52 volumio-4 volumio[1215]: Mono: Playback [off] Feb 08 17:26:52 volumio-4 volumio[1215]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 08 17:26:52 volumio-4 volumio[1215]: Unable to parse: Feb 08 17:26:52 volumio-4 volumio[1215]: Simple mixer control 'Extension Unit',0 Feb 08 17:26:52 volumio-4 volumio[1215]: Capabilities: pswitch pswitch-joined Feb 08 17:26:52 volumio-4 volumio[1215]: Playback channels: Mono Feb 08 17:26:52 volumio-4 volumio[1215]: Mono: Playback [off] Feb 08 17:26:52 volumio-4 volumio[1215]: info: VolumeController:: Volume=undefined Mute =false Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:26:52 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 0 Feb 08 17:26:52 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:26:57 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioGetState Feb 08 17:26:57 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 0 Feb 08 17:27:07 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioGetQueue Feb 08 17:27:07 volumio-4 volumio[1215]: info: CoreStateMachine::getQueue Feb 08 17:27:07 volumio-4 volumio[1215]: info: CorePlayQueue::getQueue Feb 08 17:27:21 volumio-4 ntpd[1012]: PROTO: 45.79.111.167 unlink local addr 10.0.0.34 -> Feb 08 17:27:21 volumio-4 ntpd[1012]: PROTO: 170.187.147.56 unlink local addr 10.0.0.34 -> Feb 08 17:27:29 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPlay Feb 08 17:27:29 volumio-4 volumio[1215]: info: CoreStateMachine::play index 6 Feb 08 17:27:29 volumio-4 volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 17:27:29 volumio-4 volumio[1215]: info: CoreStateMachine::stop Feb 08 17:27:29 volumio-4 volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 17:27:29 volumio-4 volumio[1215]: info: CoreStateMachine::play index undefined Feb 08 17:27:29 volumio-4 volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 08 17:27:29 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:29 volumio-4 volumio[1215]: info: CoreStateMachine::startPlaybackTimer Feb 08 17:27:29 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:29 volumio-4 volumio[1215]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac Feb 08 17:27:29 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand stop Feb 08 17:27:29 volumio-4 volumio[1215]: info: sendMpdCommand stop took 1 milliseconds Feb 08 17:27:29 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand clear Feb 08 17:27:29 volumio-4 volumio[1215]: info: Feb 08 17:27:29 volumio-4 volumio[1215]: ---------------------------- MPD announces system playlist update Feb 08 17:27:29 volumio-4 volumio[1215]: info: Ignoring MPD Status Update Feb 08 17:27:29 volumio-4 volumio[1215]: info: sendMpdCommand clear took 1 milliseconds Feb 08 17:27:29 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac" Feb 08 17:27:29 volumio-4 volumio[1215]: info: Feb 08 17:27:29 volumio-4 volumio[1215]: ---------------------------- MPD announces system playlist update Feb 08 17:27:29 volumio-4 volumio[1215]: info: Ignoring MPD Status Update Feb 08 17:27:29 volumio-4 volumio[1215]: error: updateQueue error: null Feb 08 17:27:29 volumio-4 volumio[1215]: info: Feb 08 17:27:29 volumio-4 volumio[1215]: ---------------------------- MPD announces system playlist update Feb 08 17:27:29 volumio-4 volumio[1215]: info: Ignoring MPD Status Update Feb 08 17:27:29 volumio-4 volumio[1215]: info: ------------------------------ 5ms Feb 08 17:27:29 volumio-4 volumio[1215]: info: sendMpdCommand add "USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac" took 3 milliseconds Feb 08 17:27:29 volumio-4 volumio[1215]: info: ------------------------------ 3ms Feb 08 17:27:29 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand play Feb 08 17:27:29 volumio-4 volumio[1215]: info: Feb 08 17:27:29 volumio-4 volumio[1215]: ---------------------------- MPD announces system playlist update Feb 08 17:27:29 volumio-4 volumio[1215]: info: Ignoring MPD Status Update Feb 08 17:27:29 volumio-4 volumio[1215]: info: ------------------------------ 4ms Feb 08 17:27:29 volumio-4 volumio[1215]: info: sendMpdCommand play took 2 milliseconds Feb 08 17:27:29 volumio-4 volumio[1215]: info: ------------------------------ 2ms Feb 08 17:27:30 volumio-4 volumio[1215]: info: Feb 08 17:27:30 volumio-4 volumio[1215]: ---------------------------- MPD announces state update: player Feb 08 17:27:30 volumio-4 volumio[1215]: info: ControllerMpd::getState Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Feb 08 17:27:30 volumio-4 volumio[1215]: info: Feb 08 17:27:30 volumio-4 volumio[1215]: ---------------------------- MPD announces state update: player Feb 08 17:27:30 volumio-4 volumio[1215]: info: ControllerMpd::getState Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Feb 08 17:27:30 volumio-4 volumio[1215]: info: Feb 08 17:27:30 volumio-4 volumio[1215]: ---------------------------- MPD announces state update: player Feb 08 17:27:30 volumio-4 volumio[1215]: info: sendMpdCommand status took 16 milliseconds Feb 08 17:27:30 volumio-4 volumio[1215]: info: sendMpdCommand status took 15 milliseconds Feb 08 17:27:30 volumio-4 volumio[1215]: info: ControllerMpd::getState Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::parseState Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::parseState Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 17:27:30 volumio-4 volumio[1215]: info: Feb 08 17:27:30 volumio-4 volumio[1215]: ---------------------------- MPD announces state update: player Feb 08 17:27:30 volumio-4 volumio[1215]: info: ControllerMpd::getState Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Feb 08 17:27:30 volumio-4 volumio[1215]: info: sendMpdCommand status took 5 milliseconds Feb 08 17:27:30 volumio-4 volumio[1215]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 08 17:27:30 volumio-4 volumio[1215]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::parseState Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::parseTrackInfo Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::parseTrackInfo Feb 08 17:27:30 volumio-4 volumio[1215]: info: ControllerMpd::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::servicePushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":24,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Start","artist":"Grobschnitt","album":"Live - Hilchenbach 1974-2","uri":"USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac","trackType":"flac"} Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: CURRENT POSITION 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::syncState stateService play Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::syncState currentStatus stop Feb 08 17:27:30 volumio-4 volumio[1215]: info: ControllerMpd::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::servicePushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":24,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"774 Kbps","isStreaming":false,"title":"Start","artist":"Grobschnitt","album":"Live - Hilchenbach 1974-2","uri":"USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac","trackType":"flac"} Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: CURRENT POSITION 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::syncState stateService play Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::syncState currentStatus play Feb 08 17:27:30 volumio-4 volumio[1215]: info: Received an update from plugin. extracting info from payload Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: ------------------------------ 39ms Feb 08 17:27:30 volumio-4 volumio[1215]: info: ------------------------------ 49ms Feb 08 17:27:30 volumio-4 volumio[1215]: info: sendMpdCommand status took 29 milliseconds Feb 08 17:27:30 volumio-4 volumio[1215]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::parseState Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::parseTrackInfo Feb 08 17:27:30 volumio-4 volumio[1215]: info: ControllerMpd::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::servicePushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":24,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"737 Kbps","isStreaming":false,"title":"Start","artist":"Grobschnitt","album":"Live - Hilchenbach 1974-2","uri":"USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac","trackType":"flac"} Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: CURRENT POSITION 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::syncState stateService play Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::syncState currentStatus play Feb 08 17:27:30 volumio-4 volumio[1215]: info: Received an update from plugin. extracting info from payload Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: ------------------------------ 59ms Feb 08 17:27:30 volumio-4 volumio[1215]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: ControllerMpd::parseTrackInfo Feb 08 17:27:30 volumio-4 volumio[1215]: info: ControllerMpd::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::servicePushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":24,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"759 Kbps","isStreaming":false,"title":"Start","artist":"Grobschnitt","album":"Live - Hilchenbach 1974-2","uri":"USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac","trackType":"flac"} Feb 08 17:27:30 volumio-4 volumio[1215]: verbose: CURRENT POSITION 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::syncState stateService play Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::syncState currentStatus play Feb 08 17:27:30 volumio-4 volumio[1215]: info: Received an update from plugin. extracting info from payload Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:30 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:30 volumio-4 volumio[1215]: info: ------------------------------ 81ms Feb 08 17:27:33 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioGetState Feb 08 17:27:33 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 7 Feb 08 17:27:48 volumio-4 volumio[1215]: info: Prefetching next song Feb 08 17:27:48 volumio-4 volumio[1215]: info: DOING PREFETCH IN MPD Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.2 - Solar Music Hilchenbach.flac" Feb 08 17:27:48 volumio-4 volumio[1215]: info: Feb 08 17:27:48 volumio-4 volumio[1215]: ---------------------------- MPD announces system playlist update Feb 08 17:27:48 volumio-4 volumio[1215]: info: Ignoring MPD Status Update Feb 08 17:27:48 volumio-4 volumio[1215]: info: sendMpdCommand add "USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.2 - Solar Music Hilchenbach.flac" took 2 milliseconds Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 08 17:27:48 volumio-4 volumio[1215]: info: Feb 08 17:27:48 volumio-4 volumio[1215]: ---------------------------- MPD announces system playlist update Feb 08 17:27:48 volumio-4 volumio[1215]: info: Ignoring MPD Status Update Feb 08 17:27:48 volumio-4 volumio[1215]: info: Feb 08 17:27:48 volumio-4 volumio[1215]: ---------------------------- MPD announces state update: options Feb 08 17:27:48 volumio-4 volumio[1215]: info: ------------------------------ 4ms Feb 08 17:27:48 volumio-4 volumio[1215]: info: sendMpdCommand consume 1 took 4 milliseconds Feb 08 17:27:48 volumio-4 volumio[1215]: info: ControllerMpd::getState Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Feb 08 17:27:48 volumio-4 volumio[1215]: info: ------------------------------ 3ms Feb 08 17:27:48 volumio-4 volumio[1215]: info: Feb 08 17:27:48 volumio-4 volumio[1215]: ---------------------------- MPD announces state update: options Feb 08 17:27:48 volumio-4 volumio[1215]: info: ControllerMpd::getState Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Feb 08 17:27:48 volumio-4 volumio[1215]: info: sendMpdCommand status took 2 milliseconds Feb 08 17:27:48 volumio-4 volumio[1215]: info: sendMpdCommand status took 1 milliseconds Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::parseState Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::parseState Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 08 17:27:48 volumio-4 volumio[1215]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 08 17:27:48 volumio-4 volumio[1215]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::parseTrackInfo Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: ControllerMpd::parseTrackInfo Feb 08 17:27:48 volumio-4 volumio[1215]: info: ControllerMpd::pushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreCommandRouter::servicePushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19096,"duration":24,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"732 Kbps","isStreaming":false,"title":"Start","artist":"Grobschnitt","album":"Live - Hilchenbach 1974-2","uri":"USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac","trackType":"flac"} Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: CURRENT POSITION 6 Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::syncState stateService play Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::syncState currentStatus play Feb 08 17:27:48 volumio-4 volumio[1215]: info: Received an update from plugin. extracting info from payload Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: ControllerMpd::pushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreCommandRouter::servicePushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19096,"duration":24,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"732 Kbps","isStreaming":false,"title":"Start","artist":"Grobschnitt","album":"Live - Hilchenbach 1974-2","uri":"USB/Elements/Music/Grobschnitt1974LiveHilchenbach/Grobschnitt - Live - Hilchenbach (1974)FLAC/2.1 - Start.flac","trackType":"flac"} Feb 08 17:27:48 volumio-4 volumio[1215]: verbose: CURRENT POSITION 6 Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::syncState stateService play Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::syncState currentStatus play Feb 08 17:27:48 volumio-4 volumio[1215]: info: Received an update from plugin. extracting info from payload Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::pushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::getTrack 6 Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioPushState Feb 08 17:27:48 volumio-4 volumio[1215]: info: ------------------------------ 51ms Feb 08 17:27:48 volumio-4 volumio[1215]: info: ------------------------------ 49ms Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreCommandRouter::volumioAddQueueItems Feb 08 17:27:48 volumio-4 volumio[1215]: info: CoreStateMachine::addQueueItems Feb 08 17:27:48 volumio-4 volumio[1215]: info: CorePlayQueue::addQueueItems Feb 08 17:27:48 volumio-4 volumio[1215]: info: Preload queue cleared Feb 08 17:27:48 volumio-4 volumio[1215]: info: Adding Item to queue: music-library/USB/Elements/Music/3rd Ear Experience Feb 08 17:27:48 volumio-4 volumio[1215]: info: Exploding uri music-library/USB/Elements/Music/3rd Ear Experience in service mpd Feb 08 17:27:48 volumio-4 volumio[1215]: info: ALBUMART /albumart?cacheid=417&web=3rd%20Ear%20Experience/Stones%20of%20a%20Feather/extralarge&path=%2Fmnt%2FUSB%2FElements%2FMusic%2F3rd%20Ear%20Experience%2F3rd%20Ear%20Experience%20-%20Stoned%20Gold%2F3rd%20Ear%20Experience%20-%20Stones%20of%20a%20Feather%20-%2001%20Flight%20of%20the%20Annunaki.flac&metadata=false Feb 08 17:27:48 volumio-4 volumio[1215]: info: URI /mnt/USB/Elements/Music/3rd Ear Experience/3rd Ear Experience - Stoned Gold/3rd Ear Experience - Stones of a Feather - 01 Flight of the Annunaki.flac Feb 08 17:27:48 volumio-4 volumio[1215]: info: ALBUMART /albumart?cacheid=417&web=3rd%20Ear%20Experience/Stones%20of%20a%20Feather/extralarge&path=%2Fmnt%2FUSB%2FElements%2FMusic%2F3rd%20Ear%20Experience%2F3rd%20Ear%20Experience%20-%20Stoned%20Gold%2F3rd%20Ear%20Experience%20-%20Stones%20of%20a%20Feather%20-%2002%20The%20Old%20Woman's%20Dance.flac&metadata=false Feb 08 17:27:48 volumio-4 volumio[1215]: info: URI /mnt/USB/Elements/Music/3rd Ear Experience/3rd Ear Experience - Stoned Gold/3rd Ear Experience - Stones of a Feather - 02 The Old Woman's Dance.flac Feb 08 17:27:48 volumio-4 volumio[1215]: info: ALBUMART /albumart?cacheid=417&web=3rd%20Ear%20Experience/Stones%20of%20a%20Feather/extralarge&path=%2Fmnt%2FUSB%2FElements%2FMusic%2F3rd%20Ear%20Experience%2F3rd%20Ear%20Experience%20-%20Stoned%20Gold%2F3rd%20Ear%20Experience%20-%20Stones%20of%20a%20Feather%20-%2003%20Return%20of%20the%20Peacock.flac&metadata=false Feb 08 17:27:48 volumio-4 volumio[1215]: info: URI /mnt/USB/Elements/Music/3rd Ear Experience/3rd Ear Experience - Stoned Gold/3rd Ear Experience - Stones of a Feather - 03 Return of the Peacock.flac Feb 08 17:27:48 volumio-4 volumio[1215]: info: ALBUMART /albumart?cacheid=417&web=3rd%20Ear%20Experience/Stones%20of%20a%20Feather/extralarge&path=%2Fmnt%2FUSB%2FElements%2FMusic%2F3rd%20Ear%20Experience%2F3rd%20Ear%20Experience%20-%20Stoned%20Gold%2F3rd%20Ear%20Experience%20-%20Stones%20of%20a%20Feather%20-%2004%20Chungo.flac&metadata=false Feb 08 17:27:48 volumio-4 volumio[1215]: info: URI /mnt/USB/Elements/Music/3rd Ear Experience/3rd Ear Experience - Stoned Gold/3rd Ear Experience - Stones of a Feather - 04 Chungo.flac Feb 08 17:27:48 volumio-4 volumio[1215]: info: ALBUMART /albumart?cacheid=417&web=3rd%20Ear%20Experience/Stones%20of%20a%20Feather/extralarge&path=%2Fmnt%2FUSB%2FElements%2FMusic%2F3rd%20Ear%20Experience%2F3rd%20Ear%20Experience%20-%20Stoned%20Gold%2F3rd%20Ear%20Experience%20-%20Stones%20of%20a%20Feather%20-%2005%20The%20Balladeers%20Tale.flac&metadata=false Feb 08 17:27:48 volumio-4 volumio[1215]: info: URI /mnt/USB/Elements/Music/3rd Ear Experience/3rd Ear Experience - Stoned Gold/3rd Ear Experience - Stones of a Feather - 05 The Balladeers Tale.flac Feb 08 17:27:48 volumio-4 volumio[1215]: info: ALBUMART /albumart?cacheid=417&web=3rd%20Ear%20Experience/Stoned%20Gold/extralarge&path=%2Fmnt%2FUSB%2FElements%2FMusic%2F3rd%20Ear%20Experience%2F3rd%20Ear%20Experience%20-%20Stoned%20Gold.zip&metadata=false Feb 08 17:27:48 volumio-4 volumio[1215]: info: URI /mnt/USB/Elements/Music/3rd Ear Experience/3rd Ear Experience - Stoned Gold.zip Feb 08 17:27:48 volumio-4 volumio[1215]: info: ALBUMART /albumart?cacheid=417&web=3rd%20Ear%20Experience/Stoned%20Gold/extralarge&path=%2Fmnt%2FUSB%2FElements%2FMusic%2F3rd%20Ear%20Experience%2F3rd%20Ear%20Experience%20-%20Stoned%20Gold.zip&metadata=false Feb 08 17:27:48 volumio-4 volumio[1215]: info: URI /mnt/USB/Elements/Music/3rd Ear Experience/3rd Ear Experience - Stoned Gold.zip Feb 08 17:27:48 volumio-4 volumio[1215]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 17:27:49 volumio-4 volumio[1215]: Error: Unable to resolve or reject the same promise twice Feb 08 17:27:49 volumio-4 volumio[1215]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 08 17:27:49 volumio-4 volumio[1215]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Feb 08 17:27:49 volumio-4 volumio[1215]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 08 17:27:49 volumio-4 volumio[1215]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 08 17:27:49 volumio-4 volumio[1215]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 08 17:27:49 volumio-4 volumio[1215]: at Socket.emit (node:events:514:28) Feb 08 17:27:49 volumio-4 volumio[1215]: at addChunk (node:internal/streams/readable:343:12) Feb 08 17:27:49 volumio-4 volumio[1215]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 08 17:27:49 volumio-4 volumio[1215]: at Readable.push (node:internal/streams/readable:253:10) Feb 08 17:27:49 volumio-4 volumio[1215]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 08 17:27:49 volumio-4 volumio[1215]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 17:27:49 volumio-4 sudo[3073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 17:26' Feb 08 17:27:49 volumio-4 sudo[3073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"