-- Logs begin at Wed 2025-05-14 20:43:44 CEST, end at Wed 2025-05-14 21:12:50 CEST. -- May 14 21:11:07 volumio-hd volumio[966]: info: Preload queue cleared May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioReplaceandPlayItems May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::ClearQueue May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::stop May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::stPlaybackTimer May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::updateTrackBlock May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrackBlock May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::serviceStop May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::serviceStop May 14 21:11:07 volumio-hd volumio[966]: info: [1747249867577] ControllerUPNPBrowser::stop May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand stop May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::clearPlayQueue May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::saveQueue May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushQueue May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::addQueueItems May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::addQueueItems May 14 21:11:07 volumio-hd volumio[966]: info: Preload queue cleared May 14 21:11:07 volumio-hd volumio[966]: info: Adding Item to queue: upnp/folder/http://192.168.178.25:50001/ContentDirectory/control@22$5722 May 14 21:11:07 volumio-hd volumio[966]: info: Exploding uri upnp/folder/http://192.168.178.25:50001/ContentDirectory/control@22$5722 in service upnp_browser May 14 21:11:07 volumio-hd volumio[966]: info: May 14 21:11:07 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand stop took 61 milliseconds May 14 21:11:07 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:07 volumio-hd volumio[966]: info: May 14 21:11:07 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:07 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand status took 8 milliseconds May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand status took 3 milliseconds May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand playlistinfo took 6 milliseconds May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand playlistinfo took 7 milliseconds May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::parseTrackInfo May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::parseTrackInfo May 14 21:11:07 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: 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":"69433.dsf","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69433.dsf","trackType":"dsf"} May 14 21:11:07 volumio-hd volumio[966]: verbose: CURRENT POSITION 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService stop May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus stop May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:07 volumio-hd volumio[966]: info: No code May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:07 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: 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":"69433.dsf","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69433.dsf","trackType":"dsf"} May 14 21:11:07 volumio-hd volumio[966]: verbose: CURRENT POSITION 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService stop May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus stop May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:07 volumio-hd volumio[966]: info: No code May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:07 volumio-hd volumio[966]: info: ------------------------------ 110ms May 14 21:11:07 volumio-hd volumio[966]: info: ------------------------------ 95ms May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushQueue May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::saveQueue May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::updateTrackBlock May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrackBlock May 14 21:11:07 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPlay May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::play index 0 May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::stop May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::play index undefined May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 0 May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::startPlaybackTimer May 14 21:11:07 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 0 May 14 21:11:07 volumio-hd volumio[966]: info: [1747249867811] ControllerUPNPBrowser::clearAddPlayTrack May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand stop May 14 21:11:07 volumio-hd volumio[966]: info: touch_display: Setting screensaver timeout to 300 seconds. May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand stop took 7 milliseconds May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand clear May 14 21:11:07 volumio-hd volumio[966]: info: May 14 21:11:07 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:11:07 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand clear took 15 milliseconds May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.25:50002/m/NDLNA/69286.wav" May 14 21:11:07 volumio-hd volumio[966]: info: May 14 21:11:07 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:11:07 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:11:07 volumio-hd volumio[966]: error: updateQueue error: null May 14 21:11:07 volumio-hd volumio[966]: info: ------------------------------ 62ms May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.25:50002/m/NDLNA/69286.wav" May 14 21:11:07 volumio-hd volumio[966]: info: ------------------------------ 60ms May 14 21:11:07 volumio-hd volumio[966]: info: May 14 21:11:07 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:11:07 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand add "http://192.168.178.25:50002/m/NDLNA/69286.wav" took 1 milliseconds May 14 21:11:07 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService mpd May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand play May 14 21:11:07 volumio-hd volumio[966]: info: May 14 21:11:07 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:11:07 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:11:07 volumio-hd volumio[966]: info: ------------------------------ 3ms May 14 21:11:07 volumio-hd volumio[966]: info: sendMpdCommand play took 2 milliseconds May 14 21:11:07 volumio-hd volumio[966]: info: ------------------------------ 4ms May 14 21:11:07 volumio-hd volumio[966]: info: May 14 21:11:07 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:07 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:07 volumio-hd volumio[966]: info: May 14 21:11:07 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:07 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:07 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:08 volumio-hd volumio[966]: info: May 14 21:11:08 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:08 volumio-hd volumio[966]: info: sendMpdCommand status took 21 milliseconds May 14 21:11:08 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:11:08 volumio-hd volumio[966]: info: May 14 21:11:08 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:08 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:08 volumio-hd volumio[966]: info: sendMpdCommand status took 6 milliseconds May 14 21:11:08 volumio-hd volumio[966]: info: sendMpdCommand playlistinfo took 6 milliseconds May 14 21:11:08 volumio-hd volumio[966]: info: sendMpdCommand status took 3 milliseconds May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::parseTrackInfo May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:11:08 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:08 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 0 May 14 21:11:08 volumio-hd volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3004,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"69286.wav","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69286.wav","trackType":"wav"} May 14 21:11:08 volumio-hd volumio[966]: verbose: CURRENT POSITION 0 May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService play May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus stop May 14 21:11:08 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:08 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 0 May 14 21:11:08 volumio-hd volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3004,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"69286.wav","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69286.wav","trackType":"wav"} May 14 21:11:08 volumio-hd volumio[966]: verbose: CURRENT POSITION 0 May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService play May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus play May 14 21:11:08 volumio-hd volumio[966]: info: Received an update from plugin. extracting info from payload May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:08 volumio-hd volumio[966]: info: ------------------------------ 60ms May 14 21:11:08 volumio-hd volumio[966]: info: ------------------------------ 84ms May 14 21:11:08 volumio-hd volumio[966]: info: sendMpdCommand playlistinfo took 57 milliseconds May 14 21:11:08 volumio-hd volumio[966]: info: sendMpdCommand playlistinfo took 59 milliseconds May 14 21:11:08 volumio-hd volumio[966]: verbose: ControllerMpd::parseTrackInfo May 14 21:11:08 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:08 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 0 May 14 21:11:08 volumio-hd volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3004,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"69286.wav","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69286.wav","trackType":"wav"} May 14 21:11:08 volumio-hd volumio[966]: verbose: CURRENT POSITION 0 May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService play May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus play May 14 21:11:08 volumio-hd volumio[966]: info: Received an update from plugin. extracting info from payload May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:08 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:08 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 0 May 14 21:11:08 volumio-hd volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3004,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"69286.wav","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69286.wav","trackType":"wav"} May 14 21:11:08 volumio-hd volumio[966]: verbose: CURRENT POSITION 0 May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService play May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus play May 14 21:11:08 volumio-hd volumio[966]: info: Received an update from plugin. extracting info from payload May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:08 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:08 volumio-hd volumio[966]: info: ------------------------------ 167ms May 14 21:11:08 volumio-hd volumio[966]: info: ------------------------------ 168ms May 14 21:11:08 volumio-hd volumio[966]: info: touch_display: Setting screensaver timeout to 0 seconds. May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioNext May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::next May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::next May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand next May 14 21:11:12 volumio-hd volumio[966]: info: May 14 21:11:12 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand next took 22 milliseconds May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:12 volumio-hd volumio[966]: info: May 14 21:11:12 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand status took 3 milliseconds May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand status took 1 milliseconds May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 0 May 14 21:11:12 volumio-hd volumio[966]: 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} May 14 21:11:12 volumio-hd volumio[966]: verbose: CURRENT POSITION 0 May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService stop May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus play May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::play index undefined May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: 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} May 14 21:11:12 volumio-hd volumio[966]: verbose: CURRENT POSITION 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService stop May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus stop May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: No code May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::startPlaybackTimer May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: info: [1747249872206] ControllerUPNPBrowser::clearAddPlayTrack May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand stop May 14 21:11:12 volumio-hd volumio[966]: info: ------------------------------ 61ms May 14 21:11:12 volumio-hd volumio[966]: info: ------------------------------ 60ms May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand stop took 22 milliseconds May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand clear May 14 21:11:12 volumio-hd volumio[966]: info: May 14 21:11:12 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:11:12 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand clear took 12 milliseconds May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.25:50002/m/NDLNA/69285.flac" May 14 21:11:12 volumio-hd volumio[966]: info: May 14 21:11:12 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:11:12 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:11:12 volumio-hd volumio[966]: error: updateQueue error: null May 14 21:11:12 volumio-hd volumio[966]: info: ------------------------------ 41ms May 14 21:11:12 volumio-hd volumio[966]: info: touch_display: Setting screensaver timeout to 300 seconds. May 14 21:11:12 volumio-hd volumio[966]: error: updateQueue error: null May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.25:50002/m/NDLNA/69285.flac" May 14 21:11:12 volumio-hd volumio[966]: info: ------------------------------ 49ms May 14 21:11:12 volumio-hd volumio[966]: info: May 14 21:11:12 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:11:12 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand add "http://192.168.178.25:50002/m/NDLNA/69285.flac" took 2 milliseconds May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService mpd May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand play May 14 21:11:12 volumio-hd volumio[966]: info: May 14 21:11:12 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:11:12 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:11:12 volumio-hd volumio[966]: info: ------------------------------ 10ms May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand play took 3 milliseconds May 14 21:11:12 volumio-hd volumio[966]: info: ------------------------------ 2ms May 14 21:11:12 volumio-hd volumio[966]: info: May 14 21:11:12 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:12 volumio-hd volumio[966]: info: May 14 21:11:12 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand clearerror May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand status took 14 milliseconds May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand clearerror May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand status took 14 milliseconds May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand clearerror took 11 milliseconds May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand clearerror took 11 milliseconds May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand playlistinfo took 3 milliseconds May 14 21:11:12 volumio-hd volumio[966]: info: sendMpdCommand playlistinfo took 3 milliseconds May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::parseTrackInfo May 14 21:11:12 volumio-hd volumio[966]: verbose: ControllerMpd::parseTrackInfo May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: 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":"69285.flac","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69285.flac","trackType":"flac"} May 14 21:11:12 volumio-hd volumio[966]: verbose: CURRENT POSITION 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService stop May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus stop May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: No code May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:11:12 volumio-hd volumio[966]: 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":"69285.flac","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69285.flac","trackType":"flac"} May 14 21:11:12 volumio-hd volumio[966]: verbose: CURRENT POSITION 1 May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService stop May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus stop May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: No code May 14 21:11:12 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:11:12 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:11:12 volumio-hd volumio[966]: info: ------------------------------ 154ms May 14 21:11:12 volumio-hd volumio[966]: info: ------------------------------ 156ms May 14 21:11:55 volumio-hd volumiologrotate[412]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 14 21:11:55 volumio-hd volumiologrotate[412]: ls: cannot access 'HD': No such file or directory May 14 21:12:03 volumio-hd volumio[966]: info: CoreCommandRouter::volumioGetState May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 14 21:12:09 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 14 21:12:16 volumio-hd volumio[966]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 14 21:12:16 volumio-hd sudo[4773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 21:12:16 volumio-hd sudo[4773]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd sudo[4773]: pam_unix(sudo:session): session closed for user root May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd sudo[4778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 21:12:16 volumio-hd sudo[4778]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 21:12:16 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:16 volumio-hd volumio[966]: info: MPD Permissions set May 14 21:12:16 volumio-hd systemd[1]: Stopping Music Player Daemon... May 14 21:12:16 volumio-hd systemd[1]: mpd.service: Succeeded. May 14 21:12:16 volumio-hd systemd[1]: Stopped Music Player Daemon. May 14 21:12:16 volumio-hd systemd[1]: Starting Music Player Daemon... May 14 21:12:16 volumio-hd sudo[4784]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 21:12:16 volumio-hd sudo[4784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:16 volumio-hd sudo[4784]: pam_unix(sudo:session): session closed for user root May 14 21:12:16 volumio-hd mpd[4786]: May 14 21:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 21:12:17 volumio-hd systemd[1]: Started Music Player Daemon. May 14 21:12:17 volumio-hd sudo[4778]: pam_unix(sudo:session): session closed for user root May 14 21:12:17 volumio-hd volumio[966]: error: updateQueue error: null May 14 21:12:18 volumio-hd volumio[966]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 14 21:12:18 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 14 21:12:18 volumio-hd sudo[4790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 21:12:18 volumio-hd sudo[4790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:18 volumio-hd sudo[4790]: pam_unix(sudo:session): session closed for user root May 14 21:12:19 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:19 volumio-hd sudo[4793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 21:12:19 volumio-hd sudo[4793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:19 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 21:12:19 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:12:19 volumio-hd volumio[966]: info: MPD Permissions set May 14 21:12:19 volumio-hd systemd[1]: Stopping Music Player Daemon... May 14 21:12:19 volumio-hd systemd[1]: mpd.service: Succeeded. May 14 21:12:19 volumio-hd systemd[1]: Stopped Music Player Daemon. May 14 21:12:19 volumio-hd systemd[1]: Starting Music Player Daemon... May 14 21:12:19 volumio-hd sudo[4799]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 21:12:19 volumio-hd sudo[4799]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:12:19 volumio-hd sudo[4799]: pam_unix(sudo:session): session closed for user root May 14 21:12:19 volumio-hd mpd[4801]: May 14 21:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 21:12:19 volumio-hd systemd[1]: Started Music Player Daemon. May 14 21:12:19 volumio-hd sudo[4793]: pam_unix(sudo:session): session closed for user root May 14 21:12:19 volumio-hd volumio[966]: error: updateQueue error: null May 14 21:12:21 volumio-hd volumio[966]: info: CoreCommandRouter::volumioGetState May 14 21:12:22 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPlay May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::play index undefined May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 21:12:22 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::startPlaybackTimer May 14 21:12:22 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:12:22 volumio-hd volumio[966]: info: [1747249942417] ControllerUPNPBrowser::clearAddPlayTrack May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand stop May 14 21:12:22 volumio-hd volumio[966]: info: sendMpdCommand stop took 2 milliseconds May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand clear May 14 21:12:22 volumio-hd volumio[966]: info: May 14 21:12:22 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:12:22 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:12:22 volumio-hd volumio[966]: info: sendMpdCommand clear took 1 milliseconds May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.25:50002/m/NDLNA/69285.flac" May 14 21:12:22 volumio-hd volumio[966]: error: updateQueue error: null May 14 21:12:22 volumio-hd volumio[966]: info: ------------------------------ 2ms May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.25:50002/m/NDLNA/69285.flac" May 14 21:12:22 volumio-hd volumio[966]: info: May 14 21:12:22 volumio-hd volumio[966]: ---------------------------- MPD announces system playlist update May 14 21:12:22 volumio-hd volumio[966]: info: Ignoring MPD Status Update May 14 21:12:22 volumio-hd volumio[966]: info: sendMpdCommand add "http://192.168.178.25:50002/m/NDLNA/69285.flac" took 1 milliseconds May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::setConsumeUpdateService mpd May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand play May 14 21:12:22 volumio-hd volumio[966]: info: ------------------------------ 2ms May 14 21:12:22 volumio-hd volumio[966]: info: sendMpdCommand play took 3 milliseconds May 14 21:12:22 volumio-hd volumio[966]: info: May 14 21:12:22 volumio-hd volumio[966]: ---------------------------- MPD announces state update: player May 14 21:12:22 volumio-hd volumio[966]: info: ControllerMpd::getState May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand status May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand clearerror May 14 21:12:22 volumio-hd volumio[966]: info: sendMpdCommand status took 14 milliseconds May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::parseState May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 14 21:12:22 volumio-hd volumio[966]: info: sendMpdCommand clearerror took 3 milliseconds May 14 21:12:22 volumio-hd volumio[966]: info: sendMpdCommand playlistinfo took 22 milliseconds May 14 21:12:22 volumio-hd volumio[966]: verbose: ControllerMpd::parseTrackInfo May 14 21:12:22 volumio-hd volumio[966]: info: ControllerMpd::pushState May 14 21:12:22 volumio-hd volumio[966]: info: CoreCommandRouter::servicePushState May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:12:22 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:12:22 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:12:22 volumio-hd volumio[966]: info: CorePlayQueue::getTrack 1 May 14 21:12:22 volumio-hd volumio[966]: 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":"69285.flac","artist":null,"album":null,"uri":"http://192.168.178.25:50002/m/NDLNA/69285.flac","trackType":"flac"} May 14 21:12:22 volumio-hd volumio[966]: verbose: CURRENT POSITION 1 May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::syncState stateService stop May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::syncState currentStatus stop May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:12:22 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:12:22 volumio-hd volumio[966]: info: No code May 14 21:12:22 volumio-hd volumio[966]: info: CoreStateMachine::pushState May 14 21:12:22 volumio-hd volumio[966]: info: CoreCommandRouter::volumioPushState May 14 21:12:22 volumio-hd volumio[966]: info: ------------------------------ 173ms May 14 21:12:34 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 14 21:12:34 volumio-hd volumio[966]: info: Preload queue cleared May 14 21:12:35 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 14 21:12:35 volumio-hd volumio[966]: info: Preload queue cleared May 14 21:12:45 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 14 21:12:46 volumio-hd volumio[966]: info: Preload queue cleared May 14 21:12:49 volumio-hd volumio[966]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 14 21:12:49 volumio-hd volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 21:12:49 volumio-hd volumio[966]: TypeError: Cannot read property 'length' of undefined May 14 21:12:49 volumio-hd volumio[966]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 14 21:12:49 volumio-hd volumio[966]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 14 21:12:49 volumio-hd volumio[966]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 14 21:12:49 volumio-hd volumio[966]: at Parser.emit (events.js:315:20) May 14 21:12:49 volumio-hd volumio[966]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 14 21:12:49 volumio-hd volumio[966]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 14 21:12:49 volumio-hd volumio[966]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 14 21:12:49 volumio-hd volumio[966]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 14 21:12:49 volumio-hd volumio[966]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 14 21:12:49 volumio-hd volumio[966]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 14 21:12:49 volumio-hd volumio[966]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 14 21:12:49 volumio-hd volumio[966]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 14 21:12:49 volumio-hd volumio[966]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 14 21:12:49 volumio-hd volumio[966]: at IncomingMessage.emit (events.js:327:22) May 14 21:12:49 volumio-hd volumio[966]: at endReadableNT (internal/streams/readable.js:1327:12) May 14 21:12:49 volumio-hd volumio[966]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 14 21:12:49 volumio-hd volumio[966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 21:12:50 volumio-hd sudo[4864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-14 21:11 May 14 21:12:50 volumio-hd sudo[4864]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"