-- 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"