-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sat 2025-05-24 18:27:04 CST. -- May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:01 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:01 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:01 volumio volumio[3142]: info: Preload queue cleared May 24 18:26:01 volumio volumio[3142]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::ClearQueue May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::stop May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::clearPlayQueue May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::saveQueue May 24 18:26:01 volumio volumio[3142]: info: CoreCommandRouter::volumioPushQueue May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::addQueueItems May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::addQueueItems May 24 18:26:01 volumio volumio[3142]: info: Preload queue cleared May 24 18:26:01 volumio volumio[3142]: info: Adding Item to queue: http://185.33.21.112:80/70s_128 May 24 18:26:01 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 24 18:26:01 volumio volumio[3142]: info: CoreCommandRouter::volumioPushQueue May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::saveQueue May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::updateTrackBlock May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::getTrackBlock May 24 18:26:01 volumio volumio[3142]: info: CoreCommandRouter::volumioPlay May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::play index 0 May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::stop May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::play index undefined May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:01 volumio volumio[3142]: info: CoreStateMachine::startPlaybackTimer May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:01 volumio volumio[3142]: info: [1748082361526] ControllerWebradio::clearAddPlayTrack May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand stop May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:01 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand status took 264 milliseconds May 24 18:26:01 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:01 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:01 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand status took 403 milliseconds May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand stop took 270 milliseconds May 24 18:26:01 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand clear May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:01 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:01 volumio volumio[3142]: info: ------------------------------ 146ms May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand status took 142 milliseconds May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 141 milliseconds May 24 18:26:01 volumio volumio[3142]: info: ------------------------------ 141ms May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand status took 138 milliseconds May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand status took 4 milliseconds May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 3 milliseconds May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand clear took 3 milliseconds May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand load "http://185.33.21.112:80/70s_128" May 24 18:26:01 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:01 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:01 volumio volumio[3142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 24 18:26:01 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:01 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:01 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:01 volumio volumio[3142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 24 18:26:01 volumio volumio[3142]: info: ------------------------------ 424ms May 24 18:26:01 volumio volumio[3142]: info: ------------------------------ 424ms May 24 18:26:01 volumio volumio[3142]: info: May 24 18:26:01 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:01 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:01 volumio volumio[3142]: error: updateQueue error: null May 24 18:26:01 volumio volumio[3142]: info: ------------------------------ 20ms May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 19 milliseconds May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 13 milliseconds May 24 18:26:01 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 12 milliseconds May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:01 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:01 volumio volumio[3142]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 18:26:01 volumio volumio[3142]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 18:26:01 volumio volumio[3142]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 18:26:01 volumio volumio[3142]: info: ------------------------------ 171ms May 24 18:26:01 volumio volumio[3142]: info: ------------------------------ 165ms May 24 18:26:01 volumio volumio[3142]: info: ------------------------------ 31ms May 24 18:26:04 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand add "http://185.33.21.112:80/70s_128" May 24 18:26:04 volumio volumio[3142]: info: sendMpdCommand status took 3081 milliseconds May 24 18:26:04 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:04 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:04 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:04 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:04 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:04 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:26:04 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:04 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:04 volumio volumio[3142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 24 18:26:04 volumio volumio[3142]: info: ------------------------------ 3098ms May 24 18:26:04 volumio volumio[3142]: info: May 24 18:26:04 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:04 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:04 volumio volumio[3142]: info: May 24 18:26:04 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:04 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:04 volumio volumio[3142]: info: sendMpdCommand add "http://185.33.21.112:80/70s_128" took 22 milliseconds May 24 18:26:04 volumio volumio[3142]: info: ------------------------------ 5ms May 24 18:26:04 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 18:26:04 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand play May 24 18:26:04 volumio volumio[3142]: info: May 24 18:26:04 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:04 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:04 volumio volumio[3142]: info: ------------------------------ 11ms May 24 18:26:04 volumio volumio[3142]: info: sendMpdCommand play took 9 milliseconds May 24 18:26:04 volumio volumio[3142]: info: ------------------------------ 8ms May 24 18:26:07 volumio volumio[3142]: info: CoreCommandRouter::volumioPlay May 24 18:26:07 volumio volumio[3142]: info: CoreStateMachine::play index undefined May 24 18:26:07 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:07 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:07 volumio volumio[3142]: info: CoreStateMachine::startPlaybackTimer May 24 18:26:07 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:07 volumio volumio[3142]: info: [1748082367262] ControllerWebradio::clearAddPlayTrack May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand stop May 24 18:26:07 volumio volumio[3142]: info: May 24 18:26:07 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:07 volumio volumio[3142]: info: sendMpdCommand stop took 6 milliseconds May 24 18:26:07 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand clear May 24 18:26:07 volumio volumio[3142]: info: May 24 18:26:07 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:07 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:07 volumio volumio[3142]: info: May 24 18:26:07 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:07 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:07 volumio volumio[3142]: info: sendMpdCommand status took 7 milliseconds May 24 18:26:07 volumio volumio[3142]: info: sendMpdCommand clear took 8 milliseconds May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand load "http://185.33.21.112:80/70s_128" May 24 18:26:07 volumio volumio[3142]: info: May 24 18:26:07 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:07 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:07 volumio volumio[3142]: error: updateQueue error: null May 24 18:26:07 volumio volumio[3142]: info: sendMpdCommand status took 12 milliseconds May 24 18:26:07 volumio volumio[3142]: info: ------------------------------ 10ms May 24 18:26:07 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 8 milliseconds May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:07 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:07 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:07 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:07 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:07 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:07 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:26:07 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:07 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:07 volumio volumio[3142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 24 18:26:07 volumio volumio[3142]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 18:26:07 volumio volumio[3142]: info: ------------------------------ 32ms May 24 18:26:07 volumio volumio[3142]: info: ------------------------------ 37ms May 24 18:26:09 volumio volumio[3142]: error: updateQueue error: null May 24 18:26:09 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand add "http://185.33.21.112:80/70s_128" May 24 18:26:09 volumio volumio[3142]: info: ------------------------------ 2411ms May 24 18:26:09 volumio volumio[3142]: info: May 24 18:26:09 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:09 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:09 volumio volumio[3142]: info: sendMpdCommand add "http://185.33.21.112:80/70s_128" took 5 milliseconds May 24 18:26:09 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 18:26:09 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand play May 24 18:26:09 volumio volumio[3142]: info: May 24 18:26:09 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:09 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:09 volumio volumio[3142]: info: ------------------------------ 11ms May 24 18:26:09 volumio volumio[3142]: info: sendMpdCommand play took 9 milliseconds May 24 18:26:09 volumio volumio[3142]: info: ------------------------------ 7ms May 24 18:26:12 volumio volumio[3142]: info: May 24 18:26:12 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:12 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:12 volumio volumio[3142]: info: May 24 18:26:12 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:12 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:12 volumio volumio[3142]: info: sendMpdCommand status took 252 milliseconds May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:12 volumio volumio[3142]: info: May 24 18:26:12 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:12 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:12 volumio volumio[3142]: info: May 24 18:26:12 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:12 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:12 volumio volumio[3142]: info: sendMpdCommand status took 256 milliseconds May 24 18:26:12 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 4 milliseconds May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:12 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:12 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:12 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stevie Wonder - Superstition","artist":"1.FM - Absolute 70's Pop Radio","album":null,"uri":"http://185.33.21.112:80/70s_128","trackType":""} May 24 18:26:12 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus stop May 24 18:26:12 volumio volumio[3142]: info: ------------------------------ 266ms May 24 18:26:12 volumio volumio[3142]: info: May 24 18:26:12 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:12 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:12 volumio volumio[3142]: info: May 24 18:26:12 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:12 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:12 volumio volumio[3142]: info: ------------------------------ 15ms May 24 18:26:12 volumio volumio[3142]: info: sendMpdCommand status took 14 milliseconds May 24 18:26:12 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 10 milliseconds May 24 18:26:12 volumio volumio[3142]: info: ------------------------------ 7ms May 24 18:26:12 volumio volumio[3142]: info: sendMpdCommand status took 5 milliseconds May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:12 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:12 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:12 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stevie Wonder - Superstition","artist":"1.FM - Absolute 70's Pop Radio","album":null,"uri":"http://185.33.21.112:80/70s_128","trackType":""} May 24 18:26:12 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus play May 24 18:26:12 volumio volumio[3142]: info: Received an update from plugin. extracting info from payload May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:12 volumio volumio[3142]: info: ------------------------------ 293ms May 24 18:26:12 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 23 milliseconds May 24 18:26:12 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 23 milliseconds May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:12 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:12 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:12 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:12 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stevie Wonder - Superstition","artist":"1.FM - Absolute 70's Pop Radio","album":null,"uri":"http://185.33.21.112:80/70s_128","trackType":""} May 24 18:26:12 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus play May 24 18:26:12 volumio volumio[3142]: info: Received an update from plugin. extracting info from payload May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:12 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:12 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:12 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stevie Wonder - Superstition","artist":"1.FM - Absolute 70's Pop Radio","album":null,"uri":"http://185.33.21.112:80/70s_128","trackType":""} May 24 18:26:12 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus play May 24 18:26:12 volumio volumio[3142]: info: Received an update from plugin. extracting info from payload May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:12 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:12 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:12 volumio volumio[3142]: info: ------------------------------ 73ms May 24 18:26:12 volumio volumio[3142]: info: ------------------------------ 64ms May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:26:13 volumio volumio[3142]: info: Discovery: Getting this device information May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:26:13 volumio volumio[3142]: info: Discovery: Getting this device information May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:26:13 volumio volumio[3142]: verbose: New Socket.io Connection to 192.168.31.86:3000 from 192.168.31.183 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 24 18:26:13 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 24 18:26:14 volumio sudo[32269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 18:26:14 volumio sudo[32269]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:26:14 volumio sudo[32269]: pam_unix(sudo:session): session closed for user root May 24 18:26:14 volumio sudo[32271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:26:14 volumio sudo[32271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:26:14 volumio sudo[32271]: pam_unix(sudo:session): session closed for user root May 24 18:26:14 volumio volumio[3142]: verbose: New Socket.io Connection to 192.168.31.86 from 192.168.31.183 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_0_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 24 18:26:14 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 18:26:15 volumio sudo[32275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 18:26:15 volumio sudo[32275]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:26:15 volumio sudo[32275]: pam_unix(sudo:session): session closed for user root May 24 18:26:15 volumio sudo[32278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:26:15 volumio sudo[32278]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:26:15 volumio sudo[32278]: pam_unix(sudo:session): session closed for user root May 24 18:26:15 volumio volumio[3142]: verbose: New Socket.io Connection to 192.168.31.86 from 192.168.31.183 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_0_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::volumioGetVisibleSources May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 18:26:15 volumio volumio[3142]: info: Received Get System Info May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:26:15 volumio volumio[3142]: info: Discovery: Getting this device information May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:15 volumio volumio[3142]: info: Listing playlists May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 18:26:15 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 24 18:26:16 volumio volumio[3142]: info: CoreCommandRouter::volumioGetQueue May 24 18:26:16 volumio volumio[3142]: info: CoreStateMachine::getQueue May 24 18:26:16 volumio volumio[3142]: info: CorePlayQueue::getQueue May 24 18:26:16 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 24 18:26:16 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 18:26:16 volumio volumio[3142]: info: Received Get System Info May 24 18:26:16 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:26:16 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:26:16 volumio volumio[3142]: info: Discovery: Getting this device information May 24 18:26:16 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:16 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:26:17 volumio volumio[3142]: info: Preload queue cleared May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::ClearQueue May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::stop May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::stPlaybackTimer May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::updateTrackBlock May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::getTrackBlock May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::serviceStop May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::serviceStop May 24 18:26:17 volumio volumio[3142]: info: [1748082377782] ControllerWebradio::stop May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand stop May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::clearPlayQueue May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::saveQueue May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::volumioPushQueue May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::addQueueItems May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::addQueueItems May 24 18:26:17 volumio volumio[3142]: info: Preload queue cleared May 24 18:26:17 volumio volumio[3142]: info: Adding Item to queue: http://185.33.21.112:80/ccountry_64a May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::volumioPushQueue May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::saveQueue May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::updateTrackBlock May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::getTrackBlock May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::volumioPlay May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::play index 0 May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::stop May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::play index undefined May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::startPlaybackTimer May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:17 volumio volumio[3142]: info: [1748082377797] ControllerWebradio::clearAddPlayTrack May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand stop May 24 18:26:17 volumio volumio[3142]: info: May 24 18:26:17 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:17 volumio volumio[3142]: info: sendMpdCommand stop took 137 milliseconds May 24 18:26:17 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:17 volumio volumio[3142]: info: sendMpdCommand stop took 118 milliseconds May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand clear May 24 18:26:17 volumio volumio[3142]: info: May 24 18:26:17 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:17 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:17 volumio volumio[3142]: info: May 24 18:26:17 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:17 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:17 volumio volumio[3142]: info: sendMpdCommand status took 3 milliseconds May 24 18:26:17 volumio volumio[3142]: info: sendMpdCommand clear took 2 milliseconds May 24 18:26:17 volumio volumio[3142]: info: sendMpdCommand status took 2 milliseconds May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand load "http://185.33.21.112:80/ccountry_64a" May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:17 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:17 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:26:17 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:17 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:17 volumio volumio[3142]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 24 18:26:17 volumio volumio[3142]: info: ------------------------------ 11ms May 24 18:26:17 volumio volumio[3142]: info: May 24 18:26:17 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:17 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:17 volumio volumio[3142]: error: updateQueue error: null May 24 18:26:17 volumio volumio[3142]: info: ------------------------------ 12ms May 24 18:26:17 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 11 milliseconds May 24 18:26:17 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:17 volumio volumio[3142]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 18:26:17 volumio volumio[3142]: info: ------------------------------ 17ms May 24 18:26:18 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:26:18 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:26:18 volumio volumio[3142]: info: Discovery: Getting this device information May 24 18:26:18 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:18 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:18 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:26:18 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:26:18 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:26:18 volumio volumio[3142]: info: Discovery: Getting this device information May 24 18:26:18 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:18 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:18 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:26:20 volumio volumio[3142]: error: updateQueue error: null May 24 18:26:20 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand add "http://185.33.21.112:80/ccountry_64a" May 24 18:26:20 volumio volumio[3142]: info: ------------------------------ 2132ms May 24 18:26:20 volumio volumio[3142]: info: May 24 18:26:20 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:20 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:20 volumio volumio[3142]: info: sendMpdCommand add "http://185.33.21.112:80/ccountry_64a" took 2 milliseconds May 24 18:26:20 volumio volumio[3142]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 18:26:20 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand play May 24 18:26:20 volumio volumio[3142]: info: May 24 18:26:20 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:20 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:20 volumio volumio[3142]: info: ------------------------------ 6ms May 24 18:26:20 volumio volumio[3142]: info: sendMpdCommand play took 5 milliseconds May 24 18:26:20 volumio volumio[3142]: info: ------------------------------ 5ms May 24 18:26:22 volumio volumio[3142]: info: May 24 18:26:22 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:22 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:22 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:22 volumio volumio[3142]: info: May 24 18:26:22 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:22 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:22 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:23 volumio volumio[3142]: info: May 24 18:26:23 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand status took 247 milliseconds May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand status took 248 milliseconds May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:23 volumio volumio[3142]: info: May 24 18:26:23 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand status took 3 milliseconds May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 3 milliseconds May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 2 milliseconds May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ccountry_64a","artist":null,"album":null,"uri":"http://185.33.21.112:80/ccountry_64a","trackType":""} May 24 18:26:23 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus stop May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ccountry_64a","artist":null,"album":null,"uri":"http://185.33.21.112:80/ccountry_64a","trackType":""} May 24 18:26:23 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus play May 24 18:26:23 volumio volumio[3142]: info: Received an update from plugin. extracting info from payload May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: ------------------------------ 259ms May 24 18:26:23 volumio volumio[3142]: info: ------------------------------ 261ms May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand status took 12 milliseconds May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 10 milliseconds May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ccountry_64a","artist":null,"album":null,"uri":"http://185.33.21.112:80/ccountry_64a","trackType":""} May 24 18:26:23 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus play May 24 18:26:23 volumio volumio[3142]: info: Received an update from plugin. extracting info from payload May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: ------------------------------ 24ms May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 11 milliseconds May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ccountry_64a","artist":null,"album":null,"uri":"http://185.33.21.112:80/ccountry_64a","trackType":""} May 24 18:26:23 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus play May 24 18:26:23 volumio volumio[3142]: info: Received an update from plugin. extracting info from payload May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: ------------------------------ 38ms May 24 18:26:23 volumio volumio[3142]: info: May 24 18:26:23 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:23 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:23 volumio volumio[3142]: info: May 24 18:26:23 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:23 volumio volumio[3142]: info: May 24 18:26:23 volumio volumio[3142]: ---------------------------- MPD announces system playlist update May 24 18:26:23 volumio volumio[3142]: info: Ignoring MPD Status Update May 24 18:26:23 volumio volumio[3142]: info: May 24 18:26:23 volumio volumio[3142]: ---------------------------- MPD announces state update: player May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::getState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand status May 24 18:26:23 volumio volumio[3142]: info: ------------------------------ 3ms May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand status took 3 milliseconds May 24 18:26:23 volumio volumio[3142]: info: ------------------------------ 2ms May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand status took 3 milliseconds May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseState May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 2 milliseconds May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":387,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Lefty Frizzell - Saginaw, Michigan","artist":"1.FM - Classic Country Radio","album":null,"uri":"http://185.33.21.112:80/ccountry_64a","trackType":""} May 24 18:26:23 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus play May 24 18:26:23 volumio volumio[3142]: info: Received an update from plugin. extracting info from payload May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: ------------------------------ 14ms May 24 18:26:23 volumio volumio[3142]: info: sendMpdCommand playlistinfo took 10 milliseconds May 24 18:26:23 volumio volumio[3142]: verbose: ControllerMpd::parseTrackInfo May 24 18:26:23 volumio volumio[3142]: info: ControllerMpd::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::servicePushState May 24 18:26:23 volumio volumio[3142]: info: CorePlayQueue::getTrack 0 May 24 18:26:23 volumio volumio[3142]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Lefty Frizzell - Saginaw, Michigan","artist":"1.FM - Classic Country Radio","album":null,"uri":"http://185.33.21.112:80/ccountry_64a","trackType":""} May 24 18:26:23 volumio volumio[3142]: verbose: CURRENT POSITION 0 May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState stateService play May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::syncState currentStatus play May 24 18:26:23 volumio volumio[3142]: info: Received an update from plugin. extracting info from payload May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: CoreStateMachine::pushState May 24 18:26:23 volumio volumio[3142]: info: CoreCommandRouter::volumioPushState May 24 18:26:23 volumio volumio[3142]: info: ------------------------------ 21ms May 24 18:26:25 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 24 18:26:42 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:26:42 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:26:42 volumio volumio[3142]: info: Discovery: Getting this device information May 24 18:26:42 volumio volumio[3142]: info: CoreCommandRouter::volumioGetState May 24 18:26:42 volumio volumio[3142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:27:04 volumio volumio[3142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:27:04 volumio volumio[3142]: Error: connect ETIMEDOUT 108.160.172.204:80 May 24 18:27:04 volumio volumio[3142]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 24 18:27:04 volumio volumio[3142]: errno: -110, May 24 18:27:04 volumio volumio[3142]: code: 'ETIMEDOUT', May 24 18:27:04 volumio volumio[3142]: syscall: 'connect', May 24 18:27:04 volumio volumio[3142]: address: '108.160.172.204', May 24 18:27:04 volumio volumio[3142]: port: 80 May 24 18:27:04 volumio volumio[3142]: } May 24 18:27:04 volumio volumio[3142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:27:04 volumio sudo[32414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 18:26 May 24 18:27:04 volumio sudo[32414]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"