-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2024-09-06 12:44:33 CEST. -- Sep 06 12:43:03 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:06 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:43:07 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:11 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:13 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:43:15 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:18 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: Reached target Host and Network Name Lookups. Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: Starting Preprocess NFS configuration... Sep 06 12:43:19 rpi4-hfb-dac2 volumio[1060]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: nfs-config.service: Succeeded. Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: Started Preprocess NFS configuration. Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: Starting Notify NFS peers of a restart... Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Sep 06 12:43:19 rpi4-hfb-dac2 sm-notify[2974]: Version 1.3.3 starting Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: rpc-statd-notify.service: Succeeded. Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: Started Notify NFS peers of a restart. Sep 06 12:43:19 rpi4-hfb-dac2 volumio[1060]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 12:43:19 rpi4-hfb-dac2 volumio[1060]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 12:43:19 rpi4-hfb-dac2 rpc.statd[2976]: Version 1.3.3 starting Sep 06 12:43:19 rpi4-hfb-dac2 rpc.statd[2976]: Flags: TI-RPC Sep 06 12:43:19 rpi4-hfb-dac2 rpc.statd[2976]: Failed to read /var/lib/nfs/state: Success Sep 06 12:43:19 rpi4-hfb-dac2 rpc.statd[2976]: Initializing NSM state Sep 06 12:43:19 rpi4-hfb-dac2 systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Sep 06 12:43:19 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:19 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 06 12:43:20 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:43:23 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:27 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:27 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:43:28 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 06 12:43:28 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand update Sep 06 12:43:28 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand update took 6 milliseconds Sep 06 12:43:31 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:34 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:43:35 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioNext Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::next Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stPlaybackTimer Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 0 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::serviceStop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 0 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::serviceStop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 114 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::play index undefined Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::startPlaybackTimer Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 2 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand clear Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand clear took 8 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 7 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac" Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Woke Up This Morning","artist":"Ike & Tina Turner","album":"The Hits Collection","uri":"USB/Music/Ike & Tina Turner/(2012) - The Hits Collection/0131 - Ike & Tina Turner - Woke Up This Morning.flac","trackType":"flac"} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 286ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 277ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 276ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 276ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: error: updateQueue error: null Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 281ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand add "USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac" took 277 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 6ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 6ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 5ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 103ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand play took 100 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 99ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 98ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 97ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 21 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 21 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 20 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 19 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 11 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1628 Kbps","isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":254,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1739 Kbps","isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":78,"duration":254,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1739 Kbps","isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":89,"duration":254,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1739 Kbps","isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 73ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 173ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 175ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 174ms Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 157 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 9 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":430,"duration":254,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1867 Kbps","isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:37 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 234ms Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 214 milliseconds Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 213 milliseconds Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 214 milliseconds Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":254,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1901 Kbps","isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":254,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1901 Kbps","isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":254,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1901 Kbps","isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 343ms Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 336ms Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 335ms Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:38 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioNext Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::next Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stPlaybackTimer Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::serviceStop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 16 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::serviceStop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::stop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 58 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::play index undefined Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::startPlaybackTimer Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 9 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 7 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 2 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 1 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand clear Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand clear took 4 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac" Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I just can’t stop loving you","artist":"Michael Jackson","album":"Bad","uri":"USB/Music/Michael Jackson/(2012) - Bad/0108 - Michael Jackson - I just can’t stop loving you.flac","trackType":"flac"} Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 68ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 63ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 61ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 60ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: error: updateQueue error: null Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 63ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand add "USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac" took 62 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 8ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 7ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 6ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 45ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand play took 43 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 42ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 41ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 39ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 103 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 100 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 100 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 100 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 20 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 14 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"579 Kbps","isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"579 Kbps","isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"579 Kbps","isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"579 Kbps","isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 170ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 295ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 296ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 296ms Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 184 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 183 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 174 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 172 milliseconds Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":800,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"589 Kbps","isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":800,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"589 Kbps","isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:39 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 295ms Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 285ms Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 234 milliseconds Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 235 milliseconds Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":800,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"589 Kbps","isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":800,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"589 Kbps","isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 35 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 504ms Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 497ms Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:40 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 06 12:43:41 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:43:43 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:43 rpi4-hfb-dac2 volumio-remote-updater[730]: [2024-09-06 12:43:43] [connect] Successful connection Sep 06 12:43:43 rpi4-hfb-dac2 volumio-remote-updater[730]: [2024-09-06 12:43:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725619423 101 Sep 06 12:43:43 rpi4-hfb-dac2 volumio[1060]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 13 Sep 06 12:43:47 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:48 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioNext Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::next Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stop Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stPlaybackTimer Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::serviceStop Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 35 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::serviceStop Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::stop Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 86 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::play index undefined Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::startPlaybackTimer Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 7 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 6 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 2 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand clear Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand clear took 3 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac" Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"18 Wheeler","artist":"P!nk","album":"M!ssundaztood (Expanded Edition)","uri":"USB/Music/P!nk/(2001) - M!ssundaztood (Expanded Edition)/0102 - P!nk - 18 Wheeler.flac","trackType":"flac"} Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 71ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 67ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 66ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 65ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: error: updateQueue error: null Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 66ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand add "USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac" took 65 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 6ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 5ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 4ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 41ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand play took 40 milliseconds Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 38ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 37ms Sep 06 12:43:51 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 36ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 13 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 11 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 10 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 9 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":172,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":172,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":172,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":172,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 48ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 156ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 156ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 156ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 142 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 141 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 140 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 142 milliseconds Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":172,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":172,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":172,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":819,"duration":172,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 366ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 363ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 363ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 362ms Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:52 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioNext Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::next Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stop Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stPlaybackTimer Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::serviceStop Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 19 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::serviceStop Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::stop Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 77 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::play index undefined Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::startPlaybackTimer Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 6 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 4 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 2 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand clear Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand clear took 2 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac" Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Man of the world","artist":"Fleetwood Mac","album":"Greatest Hits","uri":"USB/Music/Fleetwood Mac/(1971) - Greatest Hits 1971/0110 - Fleetwood Mac - Man Of The World.flac","trackType":"flac"} Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 90ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 87ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 86ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: error: updateQueue error: null Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 86ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand add "USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac" took 85 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 5ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 4ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 3ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 33ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand play took 32 milliseconds Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 31ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 30ms Sep 06 12:43:54 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 29ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 7 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 2 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 10 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 6 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":228,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":228,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":228,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":228,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 45ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 130ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 129ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 129ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 130 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 129 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 129 milliseconds Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":228,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":228,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":228,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":228,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"857 Kbps","isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 321ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 326ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 325ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 324ms Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:43:55 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 06 12:43:55 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioNext Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::next Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stPlaybackTimer Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::serviceStop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 13 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::serviceStop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::stop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 124 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::play index undefined Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::startPlaybackTimer Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 6 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 6 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand clear Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand clear took 5 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac" Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Je regrette","artist":"Golden Earring","album":"The Hague","uri":"USB/Music/Golden Earring/(2015) - The Hague/0101 - Golden Earring - Je regrette.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 91ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 95ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 96ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 96ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: error: updateQueue error: null Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 102ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand add "USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac" took 99 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 15ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 12ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 38ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 28ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand play took 27 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 27ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 284ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 12 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 12 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 14 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 7 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":231,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":231,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":231,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":231,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 67ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 156ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 152ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 151ms Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 136 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 135 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 135 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 136 milliseconds Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":231,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":231,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":231,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":231,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:43:57 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 377ms Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 371ms Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 369ms Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 368ms Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:43:58 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 06 12:43:59 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioNext Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::next Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::stPlaybackTimer Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::serviceStop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 21 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::serviceStop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::stop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 134 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::play index undefined Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::startPlaybackTimer Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand stop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::updateTrackBlock Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrackBlock Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand stop took 3 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand clear Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 9 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 8 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 7 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand clear took 6 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac" Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Me name jr. gong","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0111 - Damian Marley - Me Name Jr. Gong.flac","trackType":"flac"} Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService stop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: No code Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 83ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: error: updateQueue error: null Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 83ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 78 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 78 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 78 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand add "USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac" took 79 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 11ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 10ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 8ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 99ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 98ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 99ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces system playlist update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Ignoring MPD Status Update Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 51ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand play took 46 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 42ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 41ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 40ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 5 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 2 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":300,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searching (so much bubble)","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac","trackType":"flac"} Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus stop Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":300,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searching (so much bubble)","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac","trackType":"flac"} Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":300,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searching (so much bubble)","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac","trackType":"flac"} Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":300,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searching (so much bubble)","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac","trackType":"flac"} Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":300,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"Searching (so much bubble)","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac","trackType":"flac"} Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 75ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 181ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 184ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 185ms Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: ---------------------------- MPD announces state update: player Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::getState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 10 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseState Sep 06 12:44:00 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 142 milliseconds Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 142 milliseconds Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: sendMpdCommand playlistinfo took 142 milliseconds Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":300,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1041 Kbps","isStreaming":false,"title":"Searching (so much bubble)","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac","trackType":"flac"} Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":300,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1041 Kbps","isStreaming":false,"title":"Searching (so much bubble)","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac","trackType":"flac"} Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: ControllerMpd::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::servicePushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: STATE SERVICE {"status":"play","position":0,"seek":924,"duration":300,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1041 Kbps","isStreaming":false,"title":"Searching (so much bubble)","artist":"Damian Marley","album":"Mr. Marley","uri":"USB/Music/Damian Marley/(1996) - Mr. Marley/0108 - Damian Marley - Searching (so much bubble).flac","trackType":"flac"} Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: verbose: CURRENT POSITION 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState stateService play Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: Received an update from plugin. extracting info from payload Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreStateMachine::pushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CorePlayQueue::getTrack 20 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::volumioPushState Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 262ms Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 257ms Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: ------------------------------ 250ms Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Sep 06 12:44:01 rpi4-hfb-dac2 volumio[1060]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 06 12:44:02 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:44:03 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:07 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:09 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:44:11 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:15 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:16 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:44:19 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:23 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:23 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:44:27 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:28 rpi4-hfb-dac2 sudo[2787]: pam_unix(sudo:session): session closed for user root Sep 06 12:44:28 rpi4-hfb-dac2 volumio[1060]: info: Cannot mount NAS QNAP at system boot, trial number 3 ,retrying in 5 seconds Sep 06 12:44:30 rpi4-hfb-dac2 wpa_supplicant[965]: RRM: Ignoring radio measurement request: Not RRM network Sep 06 12:44:31 rpi4-hfb-dac2 volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:44:33 rpi4-hfb-dac2 volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 12:44:33 rpi4-hfb-dac2 volumio[1060]: TypeError: Cannot read property 'replace' of undefined Sep 06 12:44:33 rpi4-hfb-dac2 volumio[1060]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Sep 06 12:44:33 rpi4-hfb-dac2 volumio[1060]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Sep 06 12:44:33 rpi4-hfb-dac2 volumio[1060]: at listOnTimeout (internal/timers.js:554:17) Sep 06 12:44:33 rpi4-hfb-dac2 volumio[1060]: at processTimers (internal/timers.js:497:7) Sep 06 12:44:33 rpi4-hfb-dac2 volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 12:44:33 rpi4-hfb-dac2 sudo[3586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 12:43 Sep 06 12:44:33 rpi4-hfb-dac2 sudo[3586]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"