Oct 17 19:15:35 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 19:15:35 volumio volumio[1190]: info: CURURI: artists:// Oct 17 19:15:35 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:15:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 17 19:15:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 19:15:41 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:15:41 volumio nmbd[1071]: [2026/10/17 19:15:41.533373, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 17 19:15:41 volumio nmbd[1071]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.45 for name WORKGROUP<1d>. Oct 17 19:15:41 volumio nmbd[1071]: This response was from IP 192.168.3.77, reporting an IP address of 192.168.3.77. Oct 17 19:15:42 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 17 19:15:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 17 19:15:44 volumio volumio[1190]: info: TuneIn handleBrowseUri: tunein/local Oct 17 19:15:44 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:15:48 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::ClearQueue Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::stop Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::updateTrackBlock Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::getTrackBlock Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::stPlaybackTimer Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::serviceStop Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::serviceStop Oct 17 19:15:48 volumio volumio[1190]: info: ControllerMpd::stop Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::clearPlayQueue Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::saveQueue Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::addQueueItems Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::addQueueItems Oct 17 19:15:48 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:15:48 volumio volumio[1190]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s97605 Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 17 19:15:48 volumio volumio[1190]: info: Oct 17 19:15:48 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:48 volumio volumio[1190]: info: sendMpdCommand stop took 725 milliseconds Oct 17 19:15:48 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:48 volumio volumio[1190]: info: Oct 17 19:15:48 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:48 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:48 volumio volumio[1190]: info: Oct 17 19:15:48 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:48 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:48 volumio volumio[1190]: info: sendMpdCommand status took 19 milliseconds Oct 17 19:15:48 volumio volumio[1190]: info: sendMpdCommand status took 17 milliseconds Oct 17 19:15:48 volumio volumio[1190]: info: sendMpdCommand status took 14 milliseconds Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:48 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 17 19:15:48 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 17 19:15:48 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:48 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:48 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:48 volumio volumio[1190]: 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":"Circle of Nations","artist":"D.T.R","album":"Drive To Revolution","uri":"NAS/music/D.T.R/Drive To Revolution/01 Circle of Nations.mp3","trackType":"mp3"} Oct 17 19:15:48 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:48 volumio volumio[1190]: info: No code Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:48 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:48 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:48 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:48 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: 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":"Circle of Nations","artist":"D.T.R","album":"Drive To Revolution","uri":"NAS/music/D.T.R/Drive To Revolution/01 Circle of Nations.mp3","trackType":"mp3"} Oct 17 19:15:49 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:49 volumio volumio[1190]: info: No code Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:49 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:49 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: 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":"Circle of Nations","artist":"D.T.R","album":"Drive To Revolution","uri":"NAS/music/D.T.R/Drive To Revolution/01 Circle of Nations.mp3","trackType":"mp3"} Oct 17 19:15:49 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:49 volumio volumio[1190]: info: No code Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 363ms Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 360ms Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 359ms Oct 17 19:15:49 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::saveQueue Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::updateTrackBlock Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrackBlock Oct 17 19:15:49 volumio volumio[1190]: info: CoreCommandRouter::volumioPlay Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::play index 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::stop Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::play index undefined Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::startPlaybackTimer Oct 17 19:15:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:49 volumio volumio[1190]: info: [1792232149591] ControllerWebradio::clearAddPlayTrack Oct 17 19:15:49 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 19:15:49 volumio volumio[1190]: info: sendMpdCommand stop took 14 milliseconds Oct 17 19:15:49 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 19:15:49 volumio volumio[1190]: info: Oct 17 19:15:49 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:49 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:49 volumio volumio[1190]: info: sendMpdCommand clear took 2 milliseconds Oct 17 19:15:49 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand load "http://dadamore2.ddo.jp:8000" Oct 17 19:15:49 volumio volumio[1190]: info: Oct 17 19:15:49 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:49 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:49 volumio volumio[1190]: info: Oct 17 19:15:49 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:49 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:49 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:15:49 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:15:49 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 274ms Oct 17 19:15:49 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand add "http://dadamore2.ddo.jp:8000" Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 273ms Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 271ms Oct 17 19:15:49 volumio volumio[1190]: info: Oct 17 19:15:49 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:49 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:49 volumio volumio[1190]: info: sendMpdCommand add "http://dadamore2.ddo.jp:8000" took 4 milliseconds Oct 17 19:15:49 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 19:15:49 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand play Oct 17 19:15:49 volumio volumio[1190]: info: Oct 17 19:15:49 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:49 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:49 volumio volumio[1190]: info: Oct 17 19:15:49 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:49 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 11ms Oct 17 19:15:49 volumio volumio[1190]: info: sendMpdCommand play took 12 milliseconds Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 10ms Oct 17 19:15:49 volumio volumio[1190]: info: ------------------------------ 9ms Oct 17 19:15:49 volumio volumio[1190]: info: Oct 17 19:15:49 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:49 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:49 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:49 volumio volumio[1190]: info: Oct 17 19:15:49 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:49 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:49 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:50 volumio volumio[1190]: info: Oct 17 19:15:50 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:50 volumio volumio[1190]: info: Oct 17 19:15:50 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:50 volumio volumio[1190]: info: Oct 17 19:15:50 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:50 volumio volumio[1190]: info: Oct 17 19:15:50 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:50 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://dadamore2.ddo.jp:8000"; Could not resolve host: dadamore2.ddo.jp: Couldn't resolve host name Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand status took 48 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:50 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://dadamore2.ddo.jp:8000"; Could not resolve host: dadamore2.ddo.jp: Couldn't resolve host name Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand status took 50 milliseconds Oct 17 19:15:50 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://dadamore2.ddo.jp:8000"; Could not resolve host: dadamore2.ddo.jp: Couldn't resolve host name Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand status took 43 milliseconds Oct 17 19:15:50 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://dadamore2.ddo.jp:8000"; Could not resolve host: dadamore2.ddo.jp: Couldn't resolve host name Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand status took 43 milliseconds Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:50 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://dadamore2.ddo.jp:8000"; Could not resolve host: dadamore2.ddo.jp: Couldn't resolve host name Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand status took 132 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand clearerror took 106 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand status took 105 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand clearerror took 102 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand clearerror took 100 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand clearerror took 96 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 40 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:50 volumio volumio[1190]: 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":"dadamore2.ddo.jp:8000","artist":null,"album":null,"uri":"http://dadamore2.ddo.jp:8000","trackType":"jp:8000"} Oct 17 19:15:50 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: No code Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:50 volumio volumio[1190]: 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":"dadamore2.ddo.jp:8000","artist":null,"album":null,"uri":"http://dadamore2.ddo.jp:8000","trackType":"jp:8000"} Oct 17 19:15:50 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: No code Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:50 volumio volumio[1190]: 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":"dadamore2.ddo.jp:8000","artist":null,"album":null,"uri":"http://dadamore2.ddo.jp:8000","trackType":"jp:8000"} Oct 17 19:15:50 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: No code Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:50 volumio volumio[1190]: 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":"dadamore2.ddo.jp:8000","artist":null,"album":null,"uri":"http://dadamore2.ddo.jp:8000","trackType":"jp:8000"} Oct 17 19:15:50 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: No code Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: ------------------------------ 554ms Oct 17 19:15:50 volumio volumio[1190]: info: ------------------------------ 554ms Oct 17 19:15:50 volumio volumio[1190]: info: ------------------------------ 546ms Oct 17 19:15:50 volumio volumio[1190]: info: ------------------------------ 544ms Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand clearerror took 543 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 531 milliseconds Oct 17 19:15:50 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 530 milliseconds Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:50 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:50 volumio volumio[1190]: 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":"dadamore2.ddo.jp:8000","artist":null,"album":null,"uri":"http://dadamore2.ddo.jp:8000","trackType":"jp:8000"} Oct 17 19:15:50 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: No code Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:50 volumio volumio[1190]: 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":"dadamore2.ddo.jp:8000","artist":null,"album":null,"uri":"http://dadamore2.ddo.jp:8000","trackType":"jp:8000"} Oct 17 19:15:50 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: No code Oct 17 19:15:50 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:50 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:50 volumio volumio[1190]: info: ------------------------------ 830ms Oct 17 19:15:50 volumio volumio[1190]: info: ------------------------------ 828ms Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:50.320+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:50.328+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.004+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.004+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.005+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.005+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.006+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.006+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.007+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.007+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:50.324+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.008+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.008+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.009+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.009+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:51.010+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:50.986+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:50.316+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:51 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 17 19:15:51 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 17 19:15:51 volumio systemd[1]: setdatetime-helper.service: Consumed 2.944s CPU time. Oct 17 19:15:54 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:15:54 volumio volumio[1190]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::ClearQueue Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::stop Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:15:54 volumio volumio[1190]: info: CorePlayQueue::clearPlayQueue Oct 17 19:15:54 volumio volumio[1190]: info: CorePlayQueue::saveQueue Oct 17 19:15:54 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::addQueueItems Oct 17 19:15:54 volumio volumio[1190]: info: CorePlayQueue::addQueueItems Oct 17 19:15:54 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:15:54 volumio volumio[1190]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s299418 Oct 17 19:15:54 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 17 19:15:54 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Oct 17 19:15:54 volumio volumio[1190]: info: CorePlayQueue::saveQueue Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::updateTrackBlock Oct 17 19:15:54 volumio volumio[1190]: info: CorePlayQueue::getTrackBlock Oct 17 19:15:54 volumio volumio[1190]: info: CoreCommandRouter::volumioPlay Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::play index 0 Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::stop Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::play index undefined Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:15:54 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:54 volumio volumio[1190]: info: CoreStateMachine::startPlaybackTimer Oct 17 19:15:54 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:54 volumio volumio[1190]: info: [1792232154906] ControllerWebradio::clearAddPlayTrack Oct 17 19:15:54 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 19:15:54 volumio volumio[1190]: info: sendMpdCommand stop took 24 milliseconds Oct 17 19:15:54 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 19:15:54 volumio volumio[1190]: info: Oct 17 19:15:54 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:54 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:54 volumio volumio[1190]: info: sendMpdCommand clear took 9 milliseconds Oct 17 19:15:54 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand load "http://titan.shoutca.st:8790/" Oct 17 19:15:54 volumio volumio[1190]: info: Oct 17 19:15:54 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:54 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:54 volumio volumio[1190]: info: Oct 17 19:15:54 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:54 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:54 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:15:54 volumio volumio[1190]: info: ------------------------------ 18ms Oct 17 19:15:55 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:15:55 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand add "http://titan.shoutca.st:8790/" Oct 17 19:15:55 volumio volumio[1190]: info: ------------------------------ 584ms Oct 17 19:15:55 volumio volumio[1190]: info: ------------------------------ 583ms Oct 17 19:15:55 volumio volumio[1190]: info: Oct 17 19:15:55 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:55 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:55 volumio volumio[1190]: info: sendMpdCommand add "http://titan.shoutca.st:8790/" took 5 milliseconds Oct 17 19:15:55 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand play Oct 17 19:15:55 volumio volumio[1190]: info: Oct 17 19:15:55 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:55 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:55 volumio volumio[1190]: info: ------------------------------ 12ms Oct 17 19:15:55 volumio volumio[1190]: info: sendMpdCommand play took 9 milliseconds Oct 17 19:15:55 volumio volumio[1190]: info: ------------------------------ 7ms Oct 17 19:15:55 volumio volumio[1190]: info: Oct 17 19:15:55 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:15:55 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:15:55 volumio volumio[1190]: info: ------------------------------ 25ms Oct 17 19:15:55 volumio volumio[1190]: info: Oct 17 19:15:55 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:55 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:55 volumio volumio[1190]: info: Oct 17 19:15:55 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:55 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:55 volumio volumio[1190]: info: Oct 17 19:15:55 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:55 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:55 volumio volumio[1190]: info: Oct 17 19:15:55 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:55 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:55 volumio volumio[1190]: info: Oct 17 19:15:55 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:55 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://titan.shoutca.st:8790/"; Failed to connect to titan.shoutca.st port 8790 after 285 ms: Couldn't connect to server: Couldn't connect to server Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:55 volumio volumio[1190]: info: sendMpdCommand status took 73 milliseconds Oct 17 19:15:55 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:55 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://titan.shoutca.st:8790/"; Failed to connect to titan.shoutca.st port 8790 after 285 ms: Couldn't connect to server: Couldn't connect to server Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:55 volumio volumio[1190]: info: sendMpdCommand status took 85 milliseconds Oct 17 19:15:55 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://titan.shoutca.st:8790/"; Failed to connect to titan.shoutca.st port 8790 after 285 ms: Couldn't connect to server: Couldn't connect to server Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:55 volumio volumio[1190]: info: sendMpdCommand status took 91 milliseconds Oct 17 19:15:55 volumio volumio[1190]: error: MPD returned error for command status: Failed to decode "http://titan.shoutca.st:8790/"; Failed to connect to titan.shoutca.st port 8790 after 285 ms: Couldn't connect to server: Couldn't connect to server Oct 17 19:15:55 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 17 19:15:55 volumio volumio[1190]: info: sendMpdCommand status took 83 milliseconds Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:56 volumio volumio[1190]: info: Oct 17 19:15:56 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 17 19:15:56 volumio volumio[1190]: info: ControllerMpd::getState Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand clearerror took 217 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand status took 211 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand clearerror took 204 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand clearerror took 196 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand clearerror took 191 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand status took 11 milliseconds Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:15:56 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:56 volumio volumio[1190]: 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":"","artist":null,"album":null,"uri":"http://titan.shoutca.st:8790/","trackType":"st:8790/"} Oct 17 19:15:56 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: No code Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:56 volumio volumio[1190]: 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":"","artist":null,"album":null,"uri":"http://titan.shoutca.st:8790/","trackType":"st:8790/"} Oct 17 19:15:56 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: No code Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:56 volumio volumio[1190]: 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":"","artist":null,"album":null,"uri":"http://titan.shoutca.st:8790/","trackType":"st:8790/"} Oct 17 19:15:56 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: No code Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:56 volumio volumio[1190]: 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":"","artist":null,"album":null,"uri":"http://titan.shoutca.st:8790/","trackType":"st:8790/"} Oct 17 19:15:56 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: No code Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.270+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.273+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.276+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.280+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.283+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.287+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.290+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.293+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.296+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.299+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.303+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.306+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio[1190]: info: ------------------------------ 651ms Oct 17 19:15:56 volumio volumio[1190]: info: ------------------------------ 650ms Oct 17 19:15:56 volumio volumio[1190]: info: ------------------------------ 650ms Oct 17 19:15:56 volumio volumio[1190]: info: ------------------------------ 639ms Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 519 milliseconds Oct 17 19:15:56 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 515 milliseconds Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:56 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:15:56 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:56 volumio volumio[1190]: 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":"","artist":null,"album":null,"uri":"http://titan.shoutca.st:8790/","trackType":"st:8790/"} Oct 17 19:15:56 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: No code Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: ControllerMpd::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:15:56 volumio volumio[1190]: 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":"","artist":null,"album":null,"uri":"http://titan.shoutca.st:8790/","trackType":"st:8790/"} Oct 17 19:15:56 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio[1190]: info: No code Oct 17 19:15:56 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 17 19:15:56 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.739+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.743+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.747+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.750+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.755+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio5-onboarding[1664]: time=2026-10-17T19:15:56.759+09:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 17 19:15:56 volumio volumio[1190]: info: ------------------------------ 983ms Oct 17 19:15:56 volumio volumio[1190]: info: ------------------------------ 730ms Oct 17 19:16:00 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:16:00 volumio volumio[1190]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 19:16:00 volumio volumio[1190]: info: CoreStateMachine::ClearQueue Oct 17 19:16:00 volumio volumio[1190]: info: CoreStateMachine::stop Oct 17 19:16:00 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:16:00 volumio volumio[1190]: info: CorePlayQueue::clearPlayQueue Oct 17 19:16:00 volumio volumio[1190]: info: CorePlayQueue::saveQueue Oct 17 19:16:00 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Oct 17 19:16:00 volumio volumio[1190]: info: CoreStateMachine::addQueueItems Oct 17 19:16:00 volumio volumio[1190]: info: CorePlayQueue::addQueueItems Oct 17 19:16:00 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:16:00 volumio volumio[1190]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s350481 Oct 17 19:16:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 17 19:16:03 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Oct 17 19:16:03 volumio volumio[1190]: info: CorePlayQueue::saveQueue Oct 17 19:16:03 volumio volumio[1190]: info: CoreStateMachine::updateTrackBlock Oct 17 19:16:03 volumio volumio[1190]: info: CorePlayQueue::getTrackBlock Oct 17 19:16:03 volumio volumio[1190]: info: CoreCommandRouter::volumioPlay Oct 17 19:16:03 volumio volumio[1190]: info: CoreStateMachine::play index 0 Oct 17 19:16:03 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:16:03 volumio volumio[1190]: info: CoreStateMachine::stop Oct 17 19:16:03 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:16:03 volumio volumio[1190]: info: CoreStateMachine::play index undefined Oct 17 19:16:03 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:16:03 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:16:03 volumio volumio[1190]: info: CoreStateMachine::startPlaybackTimer Oct 17 19:16:03 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:16:03 volumio volumio[1190]: info: [1792232163463] ControllerWebradio::clearAddPlayTrack Oct 17 19:16:03 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 19:16:03 volumio volumio[1190]: info: sendMpdCommand stop took 46 milliseconds Oct 17 19:16:03 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 19:16:03 volumio volumio[1190]: info: Oct 17 19:16:03 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:16:03 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:16:03 volumio volumio[1190]: info: sendMpdCommand clear took 7 milliseconds Oct 17 19:16:03 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand load "https://streamer.radio.co/s6117a960f/listen" Oct 17 19:16:03 volumio volumio[1190]: info: Oct 17 19:16:03 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:16:03 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:16:03 volumio volumio[1190]: info: Oct 17 19:16:03 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:16:03 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:16:03 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:16:03 volumio volumio[1190]: info: ------------------------------ 12ms Oct 17 19:16:05 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:16:05 volumio volumio[1190]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::ClearQueue Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::stop Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:16:05 volumio volumio[1190]: info: CorePlayQueue::clearPlayQueue Oct 17 19:16:05 volumio volumio[1190]: info: CorePlayQueue::saveQueue Oct 17 19:16:05 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::addQueueItems Oct 17 19:16:05 volumio volumio[1190]: info: CorePlayQueue::addQueueItems Oct 17 19:16:05 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:16:05 volumio volumio[1190]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s350481 Oct 17 19:16:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 17 19:16:05 volumio volumio[1190]: info: CoreCommandRouter::volumioPushQueue Oct 17 19:16:05 volumio volumio[1190]: info: CorePlayQueue::saveQueue Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::updateTrackBlock Oct 17 19:16:05 volumio volumio[1190]: info: CorePlayQueue::getTrackBlock Oct 17 19:16:05 volumio volumio[1190]: info: CoreCommandRouter::volumioPlay Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::play index 0 Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::stop Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::play index undefined Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:16:05 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:16:05 volumio volumio[1190]: info: CoreStateMachine::startPlaybackTimer Oct 17 19:16:05 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 17 19:16:05 volumio volumio[1190]: info: [1792232165498] ControllerWebradio::clearAddPlayTrack Oct 17 19:16:05 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 19:16:09 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 17 19:16:09 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:16:10 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:16:10 volumio volumio[1190]: error: updateQueue error: null Oct 17 19:16:10 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand add "https://streamer.radio.co/s6117a960f/listen" Oct 17 19:16:10 volumio volumio[1190]: info: ------------------------------ 7120ms Oct 17 19:16:10 volumio volumio[1190]: info: ------------------------------ 7117ms Oct 17 19:16:10 volumio volumio[1190]: info: sendMpdCommand stop took 5142 milliseconds Oct 17 19:16:10 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 19:16:10 volumio volumio[1190]: info: Oct 17 19:16:10 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:16:10 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:16:10 volumio volumio[1190]: info: Oct 17 19:16:10 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:16:10 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:16:10 volumio volumio[1190]: info: sendMpdCommand add "https://streamer.radio.co/s6117a960f/listen" took 7 milliseconds Oct 17 19:16:10 volumio volumio[1190]: info: sendMpdCommand clear took 6 milliseconds Oct 17 19:16:10 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 19:16:10 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand play Oct 17 19:16:10 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand load "https://streamer.radio.co/s6117a960f/listen" Oct 17 19:16:10 volumio volumio[1190]: info: Oct 17 19:16:10 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:16:10 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:16:10 volumio volumio[1190]: info: Oct 17 19:16:10 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 17 19:16:10 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 17 19:16:13 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:16:13 volumio volumio[1190]: info: Preload queue cleared Oct 17 19:16:13 volumio volumio[1190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:16:13 volumio volumio[1190]: TypeError: Cannot read properties of null (reading 'slice') Oct 17 19:16:13 volumio volumio[1190]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 17 19:16:13 volumio volumio[1190]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 17 19:16:13 volumio volumio[1190]: at Object.onceWrapper (node:events:629:26) Oct 17 19:16:13 volumio volumio[1190]: at ClientRequest.emit (node:events:514:28) Oct 17 19:16:13 volumio volumio[1190]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Oct 17 19:16:13 volumio volumio[1190]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Oct 17 19:16:13 volumio volumio[1190]: at TLSSocket.socketOnData (node:_http_client:535:22) Oct 17 19:16:13 volumio volumio[1190]: at TLSSocket.emit (node:events:514:28) Oct 17 19:16:13 volumio volumio[1190]: at addChunk (node:internal/streams/readable:343:12) Oct 17 19:16:13 volumio volumio[1190]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 17 19:16:13 volumio volumio[1190]: at Readable.push (node:internal/streams/readable:253:10) Oct 17 19:16:13 volumio volumio[1190]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Oct 17 19:16:13 volumio volumio[1190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:16:16 volumio sudo[2993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 19:15' Oct 17 19:16:16 volumio sudo[2993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 19:16:16 volumio kernel: hwmon hwmon1: Voltage normalised PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"