-- Logs begin at Mon 2025-09-15 22:40:27 CST, end at Mon 2025-09-15 22:55:46 CST. -- Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 15 22:54:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 22:54:22 volumio volumio[942]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 15 22:54:22 volumio volumio[942]: info: Preparing to save Alsa Options, stopping services first Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Sep 15 22:54:22 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::volumioPause Sep 15 22:54:22 volumio volumio[942]: info: CoreStateMachine::pause Sep 15 22:54:22 volumio volumio[942]: info: CoreStateMachine::stPlaybackTimer Sep 15 22:54:22 volumio volumio[942]: info: CoreStateMachine::servicePause Sep 15 22:54:22 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::servicePause Sep 15 22:54:22 volumio volumio[942]: info: ControllerMpd::pause Sep 15 22:54:22 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand pause Sep 15 22:54:22 volumio volumio[942]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 22:54:22 volumio volumio[942]: info: Found match in Cards Database: setting mixer for card HDMI Sep 15 22:54:22 volumio volumio[942]: info: Setting mixer IEC958 for card HDMI Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 15 22:54:22 volumio volumio[942]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 15 22:54:22 volumio volumio[942]: info: Disabling external Volume Control Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 22:54:22 volumio volumio[942]: info: Preparing to generate the ALSA configuration file Sep 15 22:54:22 volumio volumio[942]: info: Sep 15 22:54:22 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:54:22 volumio volumio[942]: info: sendMpdCommand pause took 204 milliseconds Sep 15 22:54:22 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:54:22 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:54:22 volumio volumio[942]: Unable to parse: Sep 15 22:54:22 volumio volumio[942]: Simple mixer control 'IEC958',0 Sep 15 22:54:22 volumio volumio[942]: Capabilities: pswitch pswitch-joined Sep 15 22:54:22 volumio volumio[942]: Playback channels: Mono Sep 15 22:54:22 volumio volumio[942]: Mono: Playback [on] Sep 15 22:54:22 volumio volumio[942]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 15 22:54:22 volumio volumio[942]: info: sendMpdCommand status took 20 milliseconds Sep 15 22:54:22 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:54:22 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:54:22 volumio volumio[942]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 15 22:54:22 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:54:22 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:54:22 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:22 volumio volumio[942]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":45464,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1107 Kbps","isStreaming":false,"title":"Addicted To That Rush","artist":"Mr. Big","album":"Big, Bigger, Biggest! The Best Of Mr. Big","uri":"USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/01 - Addicted To That Rush.flac","trackType":"flac"} Sep 15 22:54:22 volumio volumio[942]: verbose: CURRENT POSITION 0 Sep 15 22:54:22 volumio volumio[942]: info: CoreStateMachine::syncState stateService pause Sep 15 22:54:22 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus pause Sep 15 22:54:22 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:54:22 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:54:22 volumio volumio[942]: info: CoreStateMachine::stPlaybackTimer Sep 15 22:54:22 volumio volumio[942]: info: ------------------------------ 46ms Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 15 22:54:22 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 22:54:22 volumio volumio[942]: info: Asound.conf file unchanged, so no further update is needed Sep 15 22:54:22 volumio volumio[942]: info: Output device has changed, restarting MPD Sep 15 22:54:23 volumio sudo[4554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 22:54:23 volumio sudo[4554]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 22:54:23 volumio volumio[942]: info: Output device has changed, restarting Shairport Sync Sep 15 22:54:23 volumio sudo[4554]: pam_unix(sudo:session): session closed for user root Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 22:54:23 volumio sudo[4557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 22:54:23 volumio sudo[4557]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 22:54:23 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 22:54:23 volumio volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 22:54:23 volumio volumio[942]: Unable to parse: Sep 15 22:54:23 volumio volumio[942]: Simple mixer control 'IEC958',0 Sep 15 22:54:23 volumio volumio[942]: Capabilities: pswitch pswitch-joined Sep 15 22:54:23 volumio volumio[942]: Playback channels: Mono Sep 15 22:54:23 volumio volumio[942]: Mono: Playback [on] Sep 15 22:54:23 volumio volumio[942]: info: VolumeController:: Volume=undefined Mute =false Sep 15 22:54:23 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:54:23 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:54:23 volumio volumio[942]: info: MPD Permissions set Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 15 22:54:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 22:54:23 volumio volumio[942]: info: Starting Shairport Sync Sep 15 22:54:23 volumio sudo[4570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 22:54:23 volumio sudo[4570]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 22:54:23 volumio systemd[1]: mpd.service: Succeeded. Sep 15 22:54:23 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 22:54:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 22:54:23 volumio systemd[1]: Starting Music Player Daemon... Sep 15 22:54:23 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 22:54:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 22:54:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 22:54:23 volumio sudo[4570]: pam_unix(sudo:session): session closed for user root Sep 15 22:54:23 volumio volumio[942]: info: Shairport-Sync Started Sep 15 22:54:23 volumio sudo[4572]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 22:54:23 volumio sudo[4572]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 22:54:23 volumio sudo[4572]: pam_unix(sudo:session): session closed for user root Sep 15 22:54:24 volumio mpd[4578]: Sep 15 22:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 22:54:24 volumio systemd[1]: Started Music Player Daemon. Sep 15 22:54:24 volumio sudo[4557]: pam_unix(sudo:session): session closed for user root Sep 15 22:54:24 volumio volumio[942]: error: updateQueue error: null Sep 15 22:54:26 volumio volumio[942]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 15 22:54:26 volumio volumio[942]: info: CoreStateMachine::getcurrentVolume Sep 15 22:54:26 volumio volumio[942]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 22:54:26 volumio volumio[942]: Unable to parse: Sep 15 22:54:26 volumio volumio[942]: Simple mixer control 'IEC958',0 Sep 15 22:54:26 volumio volumio[942]: Capabilities: pswitch pswitch-joined Sep 15 22:54:26 volumio volumio[942]: Playback channels: Mono Sep 15 22:54:26 volumio volumio[942]: Mono: Playback [on] Sep 15 22:54:26 volumio volumio[942]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 15 22:54:26 volumio volumio[942]: Unable to parse: Sep 15 22:54:26 volumio volumio[942]: Simple mixer control 'IEC958',0 Sep 15 22:54:26 volumio volumio[942]: Capabilities: pswitch pswitch-joined Sep 15 22:54:26 volumio volumio[942]: Playback channels: Mono Sep 15 22:54:26 volumio volumio[942]: Mono: Playback [on] Sep 15 22:54:26 volumio volumio[942]: info: VolumeController:: Volume=undefined Mute =false Sep 15 22:54:26 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:54:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:54:26 volumio volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 15 22:54:26 volumio volumio[942]: info: CorePlayQueue::getTrackBlock Sep 15 22:54:26 volumio volumio[942]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 22:54:26 volumio volumio[942]: Unable to parse: Sep 15 22:54:26 volumio volumio[942]: Simple mixer control 'IEC958',0 Sep 15 22:54:26 volumio volumio[942]: Capabilities: pswitch pswitch-joined Sep 15 22:54:26 volumio volumio[942]: Playback channels: Mono Sep 15 22:54:26 volumio volumio[942]: Mono: Playback [on] Sep 15 22:54:26 volumio volumio[942]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 15 22:54:26 volumio volumio[942]: Unable to parse: Sep 15 22:54:26 volumio volumio[942]: Simple mixer control 'IEC958',0 Sep 15 22:54:26 volumio volumio[942]: Capabilities: pswitch pswitch-joined Sep 15 22:54:26 volumio volumio[942]: Playback channels: Mono Sep 15 22:54:26 volumio volumio[942]: Mono: Playback [on] Sep 15 22:54:26 volumio volumio[942]: info: VolumeController:: Volume=undefined Mute =false Sep 15 22:54:26 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:54:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:54:47 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Sep 15 22:54:47 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::volumioNext Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::next Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::stop Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrackBlock Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::stPlaybackTimer Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::serviceStop Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::serviceStop Sep 15 22:54:55 volumio volumio[942]: info: ControllerMpd::stop Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand stop took 5 milliseconds Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::play index undefined Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrack 1 Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::startPlaybackTimer Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrack 1 Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::clearAddPlayTracks USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/02 - Rock & Roll Over.flac Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrackBlock Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand stop took 3 milliseconds Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 22:54:55 volumio volumio[942]: info: Sep 15 22:54:55 volumio volumio[942]: ---------------------------- MPD announces system playlist update Sep 15 22:54:55 volumio volumio[942]: info: Ignoring MPD Status Update Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand clear took 2 milliseconds Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand add "USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/02 - Rock & Roll Over.flac" Sep 15 22:54:55 volumio volumio[942]: error: updateQueue error: null Sep 15 22:54:55 volumio volumio[942]: info: Sep 15 22:54:55 volumio volumio[942]: ---------------------------- MPD announces system playlist update Sep 15 22:54:55 volumio volumio[942]: info: Ignoring MPD Status Update Sep 15 22:54:55 volumio volumio[942]: info: ------------------------------ 4ms Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand add "USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/02 - Rock & Roll Over.flac" took 2 milliseconds Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand play Sep 15 22:54:55 volumio volumio[942]: info: ------------------------------ 4ms Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand play took 5 milliseconds Sep 15 22:54:55 volumio volumio[942]: info: Sep 15 22:54:55 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:54:55 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:54:55 volumio volumio[942]: info: Sep 15 22:54:55 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:54:55 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand status took 2 milliseconds Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand status took 3 milliseconds Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:54:55 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrack 1 Sep 15 22:54:55 volumio volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock & Roll Over","artist":"Mr. Big","album":"Big, Bigger, Biggest! The Best Of Mr. Big","uri":"USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/02 - Rock & Roll Over.flac","trackType":"flac"} Sep 15 22:54:55 volumio volumio[942]: verbose: CURRENT POSITION 1 Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::syncState stateService play Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 15 22:54:55 volumio volumio[942]: info: ------------------------------ 25ms Sep 15 22:54:55 volumio volumio[942]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 15 22:54:55 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:54:55 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrack 1 Sep 15 22:54:55 volumio volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock & Roll Over","artist":"Mr. Big","album":"Big, Bigger, Biggest! The Best Of Mr. Big","uri":"USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/02 - Rock & Roll Over.flac","trackType":"flac"} Sep 15 22:54:55 volumio volumio[942]: verbose: CURRENT POSITION 1 Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::syncState stateService play Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus play Sep 15 22:54:55 volumio volumio[942]: info: Received an update from plugin. extracting info from payload Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrack 1 Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:54:55 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:54:55 volumio volumio[942]: info: CorePlayQueue::getTrack 1 Sep 15 22:54:55 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:54:55 volumio volumio[942]: info: ------------------------------ 49ms Sep 15 22:55:02 volumio volumio[942]: info: Preload queue cleared Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::ClearQueue Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::stop Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::stPlaybackTimer Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrackBlock Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 1 Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::serviceStop Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 1 Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::serviceStop Sep 15 22:55:02 volumio volumio[942]: info: ControllerMpd::stop Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::clearPlayQueue Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::saveQueue Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::addQueueItems Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::addQueueItems Sep 15 22:55:02 volumio volumio[942]: info: Preload queue cleared Sep 15 22:55:02 volumio volumio[942]: info: Adding Item to queue: genres://Hard%20Rock/Mr.%20Big/Big%2C%20Bigger%2C%20Biggest!%20The%20Best%20Of%20Mr.%20Big Sep 15 22:55:02 volumio volumio[942]: info: Exploding uri genres://Hard%20Rock/Mr.%20Big/Big%2C%20Bigger%2C%20Biggest!%20The%20Best%20Of%20Mr.%20Big in service mpd Sep 15 22:55:02 volumio volumio[942]: info: Sep 15 22:55:02 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand stop took 19 milliseconds Sep 15 22:55:02 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::saveQueue Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrackBlock Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPlay Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::play index 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::stop Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::play index undefined Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::startPlaybackTimer Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::clearAddPlayTracks USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/01 - Addicted To That Rush.flac Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand status took 18 milliseconds Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand stop took 4 milliseconds Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:55:02 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock & Roll Over","artist":"Mr. Big","album":"Big, Bigger, Biggest! The Best Of Mr. Big","uri":"USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/02 - Rock & Roll Over.flac","trackType":"flac"} Sep 15 22:55:02 volumio volumio[942]: verbose: CURRENT POSITION 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::syncState stateService stop Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:02 volumio volumio[942]: info: No code Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:02 volumio volumio[942]: info: ------------------------------ 52ms Sep 15 22:55:02 volumio volumio[942]: info: Sep 15 22:55:02 volumio volumio[942]: ---------------------------- MPD announces system playlist update Sep 15 22:55:02 volumio volumio[942]: info: Ignoring MPD Status Update Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand clear took 32 milliseconds Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand add "USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/01 - Addicted To That Rush.flac" Sep 15 22:55:02 volumio volumio[942]: error: updateQueue error: null Sep 15 22:55:02 volumio volumio[942]: info: Sep 15 22:55:02 volumio volumio[942]: ---------------------------- MPD announces system playlist update Sep 15 22:55:02 volumio volumio[942]: info: Ignoring MPD Status Update Sep 15 22:55:02 volumio volumio[942]: info: ------------------------------ 7ms Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand add "USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/01 - Addicted To That Rush.flac" took 6 milliseconds Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand play Sep 15 22:55:02 volumio volumio[942]: info: ------------------------------ 3ms Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand play took 2 milliseconds Sep 15 22:55:02 volumio volumio[942]: info: Sep 15 22:55:02 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:55:02 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:55:02 volumio volumio[942]: info: Sep 15 22:55:02 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:55:02 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand status took 1 milliseconds Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand status took 3 milliseconds Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:55:02 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"Addicted To That Rush","artist":"Mr. Big","album":"Big, Bigger, Biggest! The Best Of Mr. Big","uri":"USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/01 - Addicted To That Rush.flac","trackType":"flac"} Sep 15 22:55:02 volumio volumio[942]: verbose: CURRENT POSITION 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::syncState stateService play Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 15 22:55:02 volumio volumio[942]: info: ------------------------------ 14ms Sep 15 22:55:02 volumio volumio[942]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 15 22:55:02 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:55:02 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"Addicted To That Rush","artist":"Mr. Big","album":"Big, Bigger, Biggest! The Best Of Mr. Big","uri":"USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/01 - Addicted To That Rush.flac","trackType":"flac"} Sep 15 22:55:02 volumio volumio[942]: verbose: CURRENT POSITION 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::syncState stateService play Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus play Sep 15 22:55:02 volumio volumio[942]: info: Received an update from plugin. extracting info from payload Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:02 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:02 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:02 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:02 volumio volumio[942]: info: ------------------------------ 42ms Sep 15 22:55:08 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 22:55:08 volumio volumio[942]: info: CURURI: albums:// Sep 15 22:55:08 volumio volumio[942]: info: listAlbums - loading Albums from cache Sep 15 22:55:08 volumio volumio[942]: info: Preload queue cleared Sep 15 22:55:18 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 22:55:18 volumio volumio[942]: info: CURURI: music-library Sep 15 22:55:18 volumio volumio[942]: info: Preload queue cleared Sep 15 22:55:20 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 22:55:20 volumio volumio[942]: info: CURURI: music-library/USB Sep 15 22:55:20 volumio volumio[942]: info: Preload queue cleared Sep 15 22:55:23 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 15 22:55:23 volumio volumio[942]: info: CURURI: music-library/USB/My_Music Sep 15 22:55:23 volumio volumio[942]: info: Preload queue cleared Sep 15 22:55:26 volumio volumio[942]: info: Preload queue cleared Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::ClearQueue Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::stop Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::stPlaybackTimer Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrackBlock Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::serviceStop Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::serviceStop Sep 15 22:55:26 volumio volumio[942]: info: ControllerMpd::stop Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::clearPlayQueue Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::saveQueue Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::addQueueItems Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::addQueueItems Sep 15 22:55:26 volumio volumio[942]: info: Preload queue cleared Sep 15 22:55:26 volumio volumio[942]: info: Adding Item to queue: music-library/USB/My_Music/Blue - Best of Blue Sep 15 22:55:26 volumio volumio[942]: info: Exploding uri music-library/USB/My_Music/Blue - Best of Blue in service mpd Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20All%20Rise%20(Radio%20Version).flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - All Rise (Radio Version).flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Best%20In%20Me%20(2004).flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Best In Me (2004).flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Breathe%20Easy.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Breathe Easy.flac Sep 15 22:55:26 volumio volumio[942]: info: Sep 15 22:55:26 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Bubblin'%20(Single%20Version).flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Bubblin' (Single Version).flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Curtain%20Falls.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Curtain Falls.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Fly%20By%20II.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Fly By II.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Get%20Down%20On%20It.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Get Down On It.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Guilty.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Guilty.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20If%20You%20Come%20Back.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - If You Come Back.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Love%20At%20First%20Sight.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Love At First Sight.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20One%20Love.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - One Love.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Signed%2C%20Sealed%2C%20Delivered%20I'm%20Yours.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Signed, Sealed, Delivered I'm Yours.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Sorry%20Seems%20to%20Be%20the%20Hardest%20Word%20(Radio%20Edit).flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Sorry Seems to Be the Hardest Word (Radio Edit).flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20Too%20Close.flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - Too Close.flac Sep 15 22:55:26 volumio volumio[942]: info: ALBUMART /albumart?cacheid=43&web=Blue/Best%20of%20Blue/extralarge&path=%2Fmnt%2FUSB%2FMy_Music%2FBlue%20-%20Best%20of%20Blue%2FBlue%20-%20U%20Make%20Me%20Wanna%20(Radio%20Edit).flac&metadata=false Sep 15 22:55:26 volumio volumio[942]: info: URI /mnt/USB/My_Music/Blue - Best of Blue/Blue - U Make Me Wanna (Radio Edit).flac Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand stop took 36 milliseconds Sep 15 22:55:26 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushQueue Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::saveQueue Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::updateTrackBlock Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrackBlock Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPlay Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::play index 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::stop Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::play index undefined Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::startPlaybackTimer Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::clearAddPlayTracks USB/My_Music/Blue - Best of Blue/Blue - All Rise (Radio Version).flac Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand status took 14 milliseconds Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand stop took 4 milliseconds Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 22:55:26 volumio volumio[942]: info: Sep 15 22:55:26 volumio volumio[942]: ---------------------------- MPD announces system playlist update Sep 15 22:55:26 volumio volumio[942]: info: Ignoring MPD Status Update Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand clear took 3 milliseconds Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand add "USB/My_Music/Blue - Best of Blue/Blue - All Rise (Radio Version).flac" Sep 15 22:55:26 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Addicted To That Rush","artist":"Mr. Big","album":"Big, Bigger, Biggest! The Best Of Mr. Big","uri":"USB/My_Music/Mr. Big - Big, Bigger, Biggest! The Best Of Mr. Big/01 - Addicted To That Rush.flac","trackType":"flac"} Sep 15 22:55:26 volumio volumio[942]: verbose: CURRENT POSITION 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::syncState stateService stop Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:26 volumio volumio[942]: info: No code Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:26 volumio volumio[942]: info: ------------------------------ 53ms Sep 15 22:55:26 volumio volumio[942]: error: updateQueue error: null Sep 15 22:55:26 volumio volumio[942]: info: Sep 15 22:55:26 volumio volumio[942]: ---------------------------- MPD announces system playlist update Sep 15 22:55:26 volumio volumio[942]: info: Ignoring MPD Status Update Sep 15 22:55:26 volumio volumio[942]: info: ------------------------------ 30ms Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand add "USB/My_Music/Blue - Best of Blue/Blue - All Rise (Radio Version).flac" took 28 milliseconds Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand play Sep 15 22:55:26 volumio volumio[942]: info: Sep 15 22:55:26 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:55:26 volumio volumio[942]: info: ------------------------------ 10ms Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand play took 8 milliseconds Sep 15 22:55:26 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:55:26 volumio volumio[942]: info: Sep 15 22:55:26 volumio volumio[942]: ---------------------------- MPD announces state update: player Sep 15 22:55:26 volumio volumio[942]: info: ControllerMpd::getState Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand status Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand status took 1 milliseconds Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand status took 4 milliseconds Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::parseState Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:55:26 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"799 Kbps","isStreaming":false,"title":"All Rise (Radio Version)","artist":"Blue","album":"Best of Blue","uri":"USB/My_Music/Blue - Best of Blue/Blue - All Rise (Radio Version).flac","trackType":"flac"} Sep 15 22:55:26 volumio volumio[942]: verbose: CURRENT POSITION 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::syncState stateService play Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus stop Sep 15 22:55:26 volumio volumio[942]: info: ------------------------------ 12ms Sep 15 22:55:26 volumio volumio[942]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 15 22:55:26 volumio volumio[942]: verbose: ControllerMpd::parseTrackInfo Sep 15 22:55:26 volumio volumio[942]: info: ControllerMpd::pushState Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::servicePushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"799 Kbps","isStreaming":false,"title":"All Rise (Radio Version)","artist":"Blue","album":"Best of Blue","uri":"USB/My_Music/Blue - Best of Blue/Blue - All Rise (Radio Version).flac","trackType":"flac"} Sep 15 22:55:26 volumio volumio[942]: verbose: CURRENT POSITION 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::syncState stateService play Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::syncState currentStatus play Sep 15 22:55:26 volumio volumio[942]: info: Received an update from plugin. extracting info from payload Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:26 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:26 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:26 volumio volumio[942]: info: ------------------------------ 31ms Sep 15 22:55:29 volumio systemd[1]: Starting Cleanup of Temporary Directories... Sep 15 22:55:29 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Sep 15 22:55:29 volumio systemd[1]: Started Cleanup of Temporary Directories. Sep 15 22:55:34 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Sep 15 22:55:34 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:45 volumio volumio[942]: info: VolumeController::SetAlsaVolume+ Sep 15 22:55:45 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:45 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:45 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:45 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:45 volumio volumio[942]: info: VolumeController::SetAlsaVolume+ Sep 15 22:55:45 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:45 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:45 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:45 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:46 volumio volumio[942]: info: VolumeController::SetAlsaVolume+ Sep 15 22:55:46 volumio volumio[942]: info: CoreStateMachine::pushState Sep 15 22:55:46 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Sep 15 22:55:46 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 22:55:46 volumio volumio[942]: info: CoreCommandRouter::volumioPushState Sep 15 22:55:46 volumio volumio[942]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 22:55:46 volumio volumio[942]: Error: connect ETIMEDOUT 199.96.62.75:443 Sep 15 22:55:46 volumio volumio[942]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 15 22:55:46 volumio volumio[942]: errno: -110, Sep 15 22:55:46 volumio volumio[942]: code: 'ETIMEDOUT', Sep 15 22:55:46 volumio volumio[942]: syscall: 'connect', Sep 15 22:55:46 volumio volumio[942]: address: '199.96.62.75', Sep 15 22:55:46 volumio volumio[942]: port: 443 Sep 15 22:55:46 volumio volumio[942]: } Sep 15 22:55:46 volumio volumio[942]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 22:55:46 volumio sudo[4843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 22:54 Sep 15 22:55:46 volumio sudo[4843]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"