-- Logs begin at Wed 2024-07-17 15:46:30 UTC, end at Thu 2024-07-18 15:53:36 UTC. -- Jul 18 15:52:01 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:52:09 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:52:16 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:52:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 15:52:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 15:52:18 volumio volumio[1250]: info: Discovery: Getting this device information Jul 18 15:52:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jul 18 15:52:18 volumio volumio[1250]: info: CorePlayQueue::getTrack 27 Jul 18 15:52:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jul 18 15:52:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 18 15:52:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 18 15:52:21 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 18 15:52:24 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioNext Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::next Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 15:52:24 volumio volumio[1250]: verbose: UNSET VOLATILE Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::stPlaybackTimer Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 27 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::serviceStop Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 27 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::serviceStop Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::stop Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 15:52:24 volumio volumio[1250]: info: touch_display: Setting screensaver timeout to 60 seconds. Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand stop took 77 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::play index undefined Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::startPlaybackTimer Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::clearAddPlayTracks USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand stop took 5 milliseconds Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:24 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand status took 16 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand clear took 15 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand status took 14 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand status took 13 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand status took 12 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand status took 10 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand status took 8 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand status took 8 milliseconds Jul 18 15:52:24 volumio volumio[1250]: info: sendMpdCommand status took 7 milliseconds Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand add "USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav" Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:24 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:24 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: No code Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:24 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: No code Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:24 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: No code Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:24 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: No code Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:24 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: No code Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:24 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: No code Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:24 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: No code Jul 18 15:52:24 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:24 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:24 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:24 volumio volumio[1250]: info: ------------------------------ 162ms Jul 18 15:52:24 volumio volumio[1250]: info: ------------------------------ 162ms Jul 18 15:52:24 volumio volumio[1250]: info: ------------------------------ 161ms Jul 18 15:52:24 volumio volumio[1250]: info: ------------------------------ 162ms Jul 18 15:52:24 volumio volumio[1250]: info: ------------------------------ 162ms Jul 18 15:52:24 volumio volumio[1250]: info: ------------------------------ 160ms Jul 18 15:52:24 volumio volumio[1250]: info: ------------------------------ 161ms Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:24 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:24 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:24 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:24 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:24 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:24 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:24 volumio volumio[1250]: info: Jul 18 15:52:24 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:24 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: error: updateQueue error: null Jul 18 15:52:25 volumio volumio[1250]: info: Jul 18 15:52:25 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:25 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 230ms Jul 18 15:52:25 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 228 milliseconds Jul 18 15:52:25 volumio volumio[1250]: info: sendMpdCommand add "USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav" took 227 milliseconds Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 66ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 62ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 56ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 53ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 50ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 46ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 44ms Jul 18 15:52:25 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:25 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand play Jul 18 15:52:25 volumio volumio[1250]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 252ms Jul 18 15:52:25 volumio volumio[1250]: info: Jul 18 15:52:25 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:25 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: info: Jul 18 15:52:25 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:25 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: info: Jul 18 15:52:25 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:25 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: info: Jul 18 15:52:25 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:25 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: info: Jul 18 15:52:25 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:25 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: info: Jul 18 15:52:25 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:25 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: info: Jul 18 15:52:25 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:25 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jul 18 15:52:25 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 66ms Jul 18 15:52:25 volumio volumio[1250]: info: sendMpdCommand play took 61 milliseconds Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 58ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 56ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 55ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 52ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 51ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 51ms Jul 18 15:52:25 volumio volumio[1250]: info: ------------------------------ 50ms Jul 18 15:52:25 volumio volumio[1250]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 18 15:52:31 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: touch_display: Setting screensaver timeout to 60 seconds. Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 7 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 7 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 7 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 6 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 5 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 4 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 5 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 4 milliseconds Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: Jul 18 15:52:33 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 19 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 16 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 16 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 15 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 15 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 14 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 14 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand status took 14 milliseconds Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 143ms Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 120 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 119 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 119 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 119 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 119 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 118 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 119 milliseconds Jul 18 15:52:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 119 milliseconds Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":348,"duration":344,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:33 volumio volumio[1250]: verbose: CURRENT POSITION 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:33 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:33 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 246ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 241ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 241ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 241ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 240ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 241ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 241ms Jul 18 15:52:33 volumio volumio[1250]: info: ------------------------------ 240ms Jul 18 15:52:33 volumio volumio[1250]: info: touch_display: Setting screensaver timeout to 0 seconds. Jul 18 15:52:39 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:52:47 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioNext Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::next Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::stop Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 15:52:51 volumio volumio[1250]: verbose: UNSET VOLATILE Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::stPlaybackTimer Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::serviceStop Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 28 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::serviceStop Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::stop Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand stop took 27 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::play index undefined Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::startPlaybackTimer Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::clearAddPlayTracks USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 18 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand stop took 16 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 10 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 10 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 8 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 7 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 5 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 5 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 3 milliseconds Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: info: touch_display: Setting screensaver timeout to 60 seconds. Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand clear took 12 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand add "USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav" Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Sublime","artist":"Molly Johnson","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/03 Molly Johnson - Sweet Sublime.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: No code Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 15:52:51 volumio volumio[1250]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 15:52:51 volumio volumio[1250]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 15:52:51 volumio volumio[1250]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 15:52:51 volumio volumio[1250]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 15:52:51 volumio volumio[1250]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 15:52:51 volumio volumio[1250]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 54ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 46ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 45ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 44ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 42ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 41ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 40ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 38ms Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: error: updateQueue error: null Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 32ms Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand add "USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav" took 30 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 9ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 8ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 9ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 8ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 7ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 6ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 7ms Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand play Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:51 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 39ms Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand play took 34 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 33ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 32ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 32ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 30ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 29ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 29ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 28ms Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 265 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 265 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 264 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 262 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 261 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 260 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 259 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 258 milliseconds Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: Jul 18 15:52:51 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 18 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 19 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 14 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 13 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 13 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 12 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 11 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 11 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand status took 10 milliseconds Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":116,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":162,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":162,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":162,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":162,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 362ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 362ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 361ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 360ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 360ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 368ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 368ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 367ms Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 102 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 102 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 101 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 101 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 102 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 102 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 102 milliseconds Jul 18 15:52:51 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 102 milliseconds Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:51 volumio volumio[1250]: verbose: CURRENT POSITION 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus play Jul 18 15:52:51 volumio volumio[1250]: info: Received an update from plugin. extracting info from payload Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 205ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 196ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 194ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 194ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 194ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 194ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 193ms Jul 18 15:52:51 volumio volumio[1250]: info: ------------------------------ 194ms Jul 18 15:52:52 volumio volumio[1250]: info: touch_display: Setting screensaver timeout to 0 seconds. Jul 18 15:52:54 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:52:57 volumio volumio[1250]: info: CoreCommandRouter::volumioNext Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::next Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::stop Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 15:52:57 volumio volumio[1250]: verbose: UNSET VOLATILE Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::stPlaybackTimer Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock Jul 18 15:52:57 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:57 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:57 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:57 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::serviceStop Jul 18 15:52:57 volumio volumio[1250]: info: CorePlayQueue::getTrack 29 Jul 18 15:52:57 volumio volumio[1250]: info: CoreCommandRouter::serviceStop Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::stop Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 15:52:57 volumio volumio[1250]: info: touch_display: Setting screensaver timeout to 60 seconds. Jul 18 15:52:57 volumio volumio[1250]: info: Jul 18 15:52:57 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:57 volumio volumio[1250]: info: sendMpdCommand stop took 70 milliseconds Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::play index undefined Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 15:52:57 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::startPlaybackTimer Jul 18 15:52:57 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::clearAddPlayTracks USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/05 Monica Mancini - Anywhere The Hearts Goes.wav Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 15:52:57 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock Jul 18 15:52:57 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock Jul 18 15:52:57 volumio volumio[1250]: info: sendMpdCommand status took 4 milliseconds Jul 18 15:52:57 volumio volumio[1250]: info: sendMpdCommand stop took 3 milliseconds Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 15:52:57 volumio volumio[1250]: info: Jul 18 15:52:57 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:57 volumio volumio[1250]: info: Jul 18 15:52:57 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:57 volumio volumio[1250]: info: Jul 18 15:52:57 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:57 volumio volumio[1250]: info: Jul 18 15:52:57 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:57 volumio volumio[1250]: info: Jul 18 15:52:57 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:57 volumio volumio[1250]: info: Jul 18 15:52:57 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:57 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:57 volumio volumio[1250]: info: Jul 18 15:52:57 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:57 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand clear took 11 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 11 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 9 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 8 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 8 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 7 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 7 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 6 milliseconds Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand add "USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/05 Monica Mancini - Anywhere The Hearts Goes.wav" Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Smiling In The Cold","artist":"Thomas Baggerman Trio On Sax Maarten Hogenhuis","album":null,"uri":"USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/04 Thomas Baggerman Trio On Sax Maarten Hogenhuis - Smiling In The Cold.wav","trackType":"wav"} Jul 18 15:52:58 volumio volumio[1250]: verbose: CURRENT POSITION 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: No code Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:58 volumio volumio[1250]: verbose: CURRENT POSITION 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: No code Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:58 volumio volumio[1250]: verbose: CURRENT POSITION 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: No code Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:58 volumio volumio[1250]: verbose: CURRENT POSITION 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: No code Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:58 volumio volumio[1250]: verbose: CURRENT POSITION 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: No code Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:58 volumio volumio[1250]: verbose: CURRENT POSITION 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: No code Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:58 volumio volumio[1250]: verbose: CURRENT POSITION 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: No code Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 18 15:52:58 volumio volumio[1250]: verbose: CURRENT POSITION 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: No code Jul 18 15:52:58 volumio volumio[1250]: info: CoreStateMachine::pushState Jul 18 15:52:58 volumio volumio[1250]: info: CorePlayQueue::getTrack 30 Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Jul 18 15:52:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 149ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 144ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 151ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 151ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 152ms Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: error: updateQueue error: null Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 176ms Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand add "USB/FREE1TB/SPECIAL SELECTION MUSIC 01/STS Master Recordings - Volume 2-20230217T153732Z-001/STS Master Recordings - Volume 2/05 Monica Mancini - Anywhere The Hearts Goes.wav" took 172 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 26ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 25ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 24ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 23ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 22ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 22ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 21ms Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand play Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces system playlist update Jul 18 15:52:58 volumio volumio[1250]: info: Ignoring MPD Status Update Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 89ms Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand play took 87 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 86ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 85ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 85ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 84ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 83ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 82ms Jul 18 15:52:58 volumio volumio[1250]: info: ------------------------------ 81ms Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 35 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 33 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 32 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 32 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 30 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 29 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 28 milliseconds Jul 18 15:52:58 volumio volumio[1250]: info: sendMpdCommand status took 31 milliseconds Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::parseState Jul 18 15:52:58 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 15:52:58 volumio volumio[1250]: info: Jul 18 15:52:58 volumio volumio[1250]: ---------------------------- MPD announces state update: player Jul 18 15:52:58 volumio volumio[1250]: info: ControllerMpd::getState Jul 18 15:53:02 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:53:09 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:53:17 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:53:24 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:53:28 volumio systemd-journal[202]: Suppressed 537 messages from /system.slice/volumio.service Jul 18 15:53:28 volumio volumio[1250]: info: Listing playlists Jul 18 15:53:32 volumio wpa_supplicant[944]: wlan0: Failed to initiate sched scan Jul 18 15:53:35 volumio volumio[1250]: info: CoreCommandRouter::volumioAddQueueItems Jul 18 15:53:35 volumio volumio[1250]: info: CoreStateMachine::addQueueItems Jul 18 15:53:35 volumio volumio[1250]: info: CorePlayQueue::addQueueItems Jul 18 15:53:35 volumio volumio[1250]: info: Adding Item to queue: music-library/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent Jul 18 15:53:35 volumio volumio[1250]: info: Exploding uri music-library/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent in service mpd Jul 18 15:53:35 volumio volumio[1250]: info: ALBUMART /albumart?cacheid=98&web=TOMA%20%26%20MAMI%20with%20SATOSHI//extralarge&path=%2Fmnt%2FUSB%2FFREE1TB%2FSPECIAL%20SELECTION%20MUSIC%2001%2Fstereo%20sound%20hires%20referent%2F01%20Down%20by%20the%20Salley%20Gardens%2F01%20Down%20by%20the%20Salley%20Gardens%2F01%20Down%20by%20the%20Salley%20Gardens%2044116.wav&metadata=false Jul 18 15:53:35 volumio volumio[1250]: info: URI /mnt/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent/01 Down by the Salley Gardens/01 Down by the Salley Gardens/01 Down by the Salley Gardens 44116.wav Jul 18 15:53:35 volumio volumio[1250]: info: ALBUMART /albumart?cacheid=98&web=TOMA%20%26%20MAMI%20with%20SATOSHI//extralarge&path=%2Fmnt%2FUSB%2FFREE1TB%2FSPECIAL%20SELECTION%20MUSIC%2001%2Fstereo%20sound%20hires%20referent%2F01%20Down%20by%20the%20Salley%20Gardens%2F01%20Down%20by%20the%20Salley%20Gardens%2F02%20Down%20by%20the%20Salley%20Gardens%2019224.wav&metadata=false Jul 18 15:53:35 volumio volumio[1250]: info: URI /mnt/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent/01 Down by the Salley Gardens/01 Down by the Salley Gardens/02 Down by the Salley Gardens 19224.wav Jul 18 15:53:35 volumio volumio[1250]: info: ALBUMART /albumart?cacheid=98&web=TOMA%20%26%20MAMI%20with%20SATOSHI//extralarge&path=%2Fmnt%2FUSB%2FFREE1TB%2FSPECIAL%20SELECTION%20MUSIC%2001%2Fstereo%20sound%20hires%20referent%2F01%20Down%20by%20the%20Salley%20Gardens%2F01%20Down%20by%20the%20Salley%20Gardens%2F03%20Down%20by%20the%20Salley%20Gardens%2038432.wav&metadata=false Jul 18 15:53:35 volumio volumio[1250]: info: URI /mnt/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent/01 Down by the Salley Gardens/01 Down by the Salley Gardens/03 Down by the Salley Gardens 38432.wav Jul 18 15:53:35 volumio volumio[1250]: info: ALBUMART /albumart?cacheid=98&web=TOMA%20%26%20MAMI%20with%20SATOSHI/Stereo%20Sound%20Hi-Res%20Reference%20Check%20Disc/extralarge&path=%2Fmnt%2FUSB%2FFREE1TB%2FSPECIAL%20SELECTION%20MUSIC%2001%2Fstereo%20sound%20hires%20referent%2F01%20Down%20by%20the%20Salley%20Gardens%2F01%20Down%20by%20the%20Salley%20Gardens%2F04%20Down%20by%20the%20Salley%20Gardens%20DSD128.dsf&metadata=false Jul 18 15:53:35 volumio volumio[1250]: info: URI /mnt/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent/01 Down by the Salley Gardens/01 Down by the Salley Gardens/04 Down by the Salley Gardens DSD128.dsf Jul 18 15:53:35 volumio volumio[1250]: info: ALBUMART /albumart?cacheid=98&web=TOMA%20%26%20MAMI%20with%20SATOSHI/Stereo%20Sound%20Hi-Res%20Reference%20Check%20Disc/extralarge&path=%2Fmnt%2FUSB%2FFREE1TB%2FSPECIAL%20SELECTION%20MUSIC%2001%2Fstereo%20sound%20hires%20referent%2F01%20Down%20by%20the%20Salley%20Gardens%2F01%20Down%20by%20the%20Salley%20Gardens%2F05%20Down%20by%20the%20Salley%20Gardens%20DSD256.dsf&metadata=false Jul 18 15:53:35 volumio volumio[1250]: info: URI /mnt/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent/01 Down by the Salley Gardens/01 Down by the Salley Gardens/05 Down by the Salley Gardens DSD256.dsf Jul 18 15:53:35 volumio volumio[1250]: info: ALBUMART /albumart?cacheid=98&web=//extralarge&path=%2Fmnt%2FUSB%2FFREE1TB%2FSPECIAL%20SELECTION%20MUSIC%2001%2Fstereo%20sound%20hires%20referent%2F01%20Down%20by%20the%20Salley%20Gardens%2F01%20Down%20by%20the%20Salley%20Gardens.zip&metadata=false Jul 18 15:53:35 volumio volumio[1250]: info: URI /mnt/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent/01 Down by the Salley Gardens/01 Down by the Salley Gardens.zip Jul 18 15:53:35 volumio volumio[1250]: info: ALBUMART /albumart?cacheid=98&web=//extralarge&path=%2Fmnt%2FUSB%2FFREE1TB%2FSPECIAL%20SELECTION%20MUSIC%2001%2Fstereo%20sound%20hires%20referent%2F01%20Down%20by%20the%20Salley%20Gardens%2F01%20Down%20by%20the%20Salley%20Gardens.zip&metadata=false Jul 18 15:53:35 volumio volumio[1250]: info: URI /mnt/USB/FREE1TB/SPECIAL SELECTION MUSIC 01/stereo sound hires referent/01 Down by the Salley Gardens/01 Down by the Salley Gardens.zip Jul 18 15:53:35 volumio volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 15:53:35 volumio volumio[1250]: Error: Unable to resolve or reject the same promise twice Jul 18 15:53:35 volumio volumio[1250]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 18 15:53:35 volumio volumio[1250]: at /volumio/app/plugins/music_service/mpd/index.js:2520:21 Jul 18 15:53:35 volumio volumio[1250]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 18 15:53:35 volumio volumio[1250]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 18 15:53:35 volumio volumio[1250]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 18 15:53:35 volumio volumio[1250]: at emitOne (events.js:116:13) Jul 18 15:53:35 volumio volumio[1250]: at Socket.emit (events.js:211:7) Jul 18 15:53:35 volumio volumio[1250]: at addChunk (_stream_readable.js:263:12) Jul 18 15:53:35 volumio volumio[1250]: at readableAddChunk (_stream_readable.js:246:13) Jul 18 15:53:35 volumio volumio[1250]: at Socket.Readable.push (_stream_readable.js:208:10) Jul 18 15:53:35 volumio volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 15:53:36 volumio sudo[5705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 15:52 Jul 18 15:53:36 volumio sudo[5705]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"