-- Logs begin at Tue 2025-06-24 21:04:19 BST, end at Tue 2025-06-24 21:09:16 BST. -- Jun 24 21:08:03 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:07 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Jun 24 21:08:07 volumio volumio[969]: info: Preload queue cleared Jun 24 21:08:07 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 21:08:07 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 21:08:07 volumio volumio[969]: info: Discovery: Getting this device information Jun 24 21:08:07 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:07 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:07 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 21:08:09 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::ClearQueue Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::stop Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::stPlaybackTimer Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::updateTrackBlock Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrackBlock Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::pushState Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::serviceStop Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::serviceStop Jun 24 21:08:10 volumio volumio[969]: info: ControllerMpd::stop Jun 24 21:08:10 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::clearPlayQueue Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::saveQueue Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::volumioPushQueue Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::addQueueItems Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::addQueueItems Jun 24 21:08:10 volumio volumio[969]: info: Preload queue cleared Jun 24 21:08:10 volumio volumio[969]: info: Adding Item to queue: webrp/0 Jun 24 21:08:10 volumio volumio[969]: info: Exploding uri webrp/0 in service radio_paradise Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::volumioPushQueue Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::saveQueue Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::updateTrackBlock Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrackBlock Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::volumioPlay Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::play index 0 Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::stop Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::play index undefined Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::startPlaybackTimer Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:10 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand stop Jun 24 21:08:10 volumio volumio[969]: info: Jun 24 21:08:10 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:10 volumio volumio[969]: info: sendMpdCommand stop took 100 milliseconds Jun 24 21:08:10 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:10 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:10 volumio volumio[969]: info: sendMpdCommand stop took 26 milliseconds Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::pushState Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:10 volumio volumio[969]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00006RU5B.jpg","uri":"https://stream.radioparadise.com/flac","name":"Like a Stone","title":"Like a Stone","artist":"Audioslave","album":"Audioslave","streaming":true,"disableUiControls":true,"duration":40,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 24 21:08:10 volumio volumio[969]: verbose: CURRENT POSITION 0 Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::syncState stateService stop Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::syncState currentStatus stop Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::pushState Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jun 24 21:08:10 volumio volumio[969]: info: No code Jun 24 21:08:10 volumio volumio[969]: info: CoreStateMachine::pushState Jun 24 21:08:10 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:10 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jun 24 21:08:10 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand clear Jun 24 21:08:10 volumio volumio[969]: info: Jun 24 21:08:10 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:10 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:10 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:10 volumio volumio[969]: info: Jun 24 21:08:10 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:10 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:10 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:10 volumio volumio[969]: info: Jun 24 21:08:10 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:10 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:10 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:11 volumio volumio[969]: info: Jun 24 21:08:11 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:11 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand status took 144 milliseconds Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand clear took 114 milliseconds Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand status took 59 milliseconds Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand status took 58 milliseconds Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand status took 56 milliseconds Jun 24 21:08:11 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:11 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:11 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 24 21:08:11 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:11 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:11 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:11 volumio volumio[969]: info: CoreStateMachine::pushState Jun 24 21:08:11 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:11 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jun 24 21:08:11 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:11 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:11 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:11 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:11 volumio volumio[969]: info: CoreStateMachine::pushState Jun 24 21:08:11 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:11 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jun 24 21:08:11 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:11 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:11 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:11 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:11 volumio volumio[969]: info: CoreStateMachine::pushState Jun 24 21:08:11 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:11 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jun 24 21:08:11 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:11 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 173ms Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 171ms Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 171ms Jun 24 21:08:11 volumio volumio[969]: info: Jun 24 21:08:11 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:11 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:11 volumio volumio[969]: info: Jun 24 21:08:11 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:11 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:11 volumio volumio[969]: info: Jun 24 21:08:11 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:11 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:11 volumio volumio[969]: error: updateQueue error: null Jun 24 21:08:11 volumio volumio[969]: error: updateQueue error: null Jun 24 21:08:11 volumio volumio[969]: error: updateQueue error: null Jun 24 21:08:11 volumio volumio[969]: error: updateQueue error: null Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 160ms Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand playlistinfo took 153 milliseconds Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand consume 1 took 152 milliseconds Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 42ms Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 40ms Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 40ms Jun 24 21:08:11 volumio volumio[969]: verbose: ControllerMpd::parseTrackInfo Jun 24 21:08:11 volumio volumio[969]: info: [1750795691177] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Jun 24 21:08:11 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Jun 24 21:08:11 volumio volumio[969]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 311ms Jun 24 21:08:11 volumio volumio[969]: info: Jun 24 21:08:11 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:11 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 4 milliseconds Jun 24 21:08:11 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand play Jun 24 21:08:11 volumio volumio[969]: info: Jun 24 21:08:11 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:11 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:11 volumio volumio[969]: info: Jun 24 21:08:11 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:11 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:11 volumio volumio[969]: info: Jun 24 21:08:11 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:11 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 31ms Jun 24 21:08:11 volumio volumio[969]: info: sendMpdCommand play took 11 milliseconds Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 11ms Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 8ms Jun 24 21:08:11 volumio volumio[969]: info: ------------------------------ 6ms Jun 24 21:08:11 volumio volumio[969]: info: [1750795691214] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:12 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand status took 85 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand status took 85 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand status took 83 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand status took 85 milliseconds Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:12 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:12 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces system playlist update Jun 24 21:08:12 volumio volumio[969]: info: Ignoring MPD Status Update Jun 24 21:08:12 volumio volumio[969]: info: Jun 24 21:08:12 volumio volumio[969]: ---------------------------- MPD announces state update: player Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::getState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand status Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 44ms Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand status took 35 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 20ms Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand status took 18 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 17ms Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand status took 15 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 14ms Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand status took 12 milliseconds Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseTrackInfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseTrackInfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseTrackInfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseTrackInfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseState Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:12 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:12 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:12 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:12 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:12 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:12 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:12 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:12 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 154ms Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 150ms Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 149ms Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 147ms Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 24 21:08:12 volumio volumio[969]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseTrackInfo Jun 24 21:08:12 volumio volumio[969]: verbose: ControllerMpd::parseTrackInfo Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:12 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:12 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:12 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:12 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:12 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:12 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:12 volumio volumio[969]: info: ControllerMpd::pushState Jun 24 21:08:12 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:12 volumio volumio[969]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 85ms Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 58ms Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 55ms Jun 24 21:08:12 volumio volumio[969]: info: ------------------------------ 51ms Jun 24 21:08:14 volumio volumio[969]: info: [1750795694458] [RadioParadise] received new metadata: {"time":288,"artist":"Yeah Yeah Yeahs","title":"Skeletons","album":"It's Blitz!","year":"2009","cover":"https://img.radioparadise.com/covers/l/B001UJIMF0.jpg","cover_med":"https://img.radioparadise.com/covers/m/B001UJIMF0.jpg","cover_small":"https://img.radioparadise.com/covers/s/B001UJIMF0.jpg"} Jun 24 21:08:14 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:14 volumio volumio[969]: info: CoreCommandRouter::servicePushState Jun 24 21:08:14 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:14 volumio volumio[969]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B001UJIMF0.jpg","uri":"https://stream.radioparadise.com/flac","name":"Skeletons","title":"Skeletons","artist":"Yeah Yeah Yeahs","album":"It's Blitz!","streaming":true,"disableUiControls":true,"duration":293,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 24 21:08:14 volumio volumio[969]: verbose: CURRENT POSITION 0 Jun 24 21:08:14 volumio volumio[969]: info: CoreStateMachine::syncState stateService play Jun 24 21:08:14 volumio volumio[969]: info: CoreStateMachine::syncState currentStatus stop Jun 24 21:08:14 volumio volumio[969]: info: [1750795694461] [RadioParadise] setting new timer with duration of 293000 seconds. Jun 24 21:08:14 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:14 volumio volumio[969]: info: CorePlayQueue::getTrack 1 Jun 24 21:08:15 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:20 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:21 volumio volumio[969]: info: CoreCommandRouter::volumioPlay Jun 24 21:08:21 volumio volumio[969]: info: CoreStateMachine::play index undefined Jun 24 21:08:21 volumio volumio[969]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 21:08:21 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:26 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:29 volumio volumio[969]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.611&uuid=9dd53091ce7102d64889ef7871562069" http://updates.volumio.org/downloader-v1/track-device Jun 24 21:08:29 volumio volumio[969]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 24 21:08:29 volumio volumio[969]: Dload Upload Total Spent Left Speed Jun 24 21:08:29 volumio volumio[969]: [10.4K blob data] Jun 24 21:08:29 volumio volumio[969]: curl: (28) Failed to connect to updates.volumio.org port 80: Connection timed out Jun 24 21:08:29 volumio volumio[969]: retrying in 5 seconds, trial 0 Jun 24 21:08:29 volumio volumio[969]: info: Volumio Calling Home Jun 24 21:08:30 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 24 21:08:32 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:33 volumio volumio[969]: info: CoreCommandRouter::volumioPlay Jun 24 21:08:33 volumio volumio[969]: info: CoreStateMachine::play index undefined Jun 24 21:08:33 volumio volumio[969]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 24 21:08:33 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:37 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:37 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 21:08:37 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 21:08:37 volumio volumio[969]: info: Discovery: Getting this device information Jun 24 21:08:37 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:37 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:37 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 21:08:42 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 21:08:42 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 21:08:42 volumio volumio[969]: info: Discovery: Getting this device information Jun 24 21:08:42 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:42 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:42 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 21:08:43 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:43 volumio volumio[969]: verbose: New Socket.io Connection to 192.168.1.194:3000 from 192.168.1.95 UA: Dart/3.5 (dart:io) Total Clients: 6 Jun 24 21:08:43 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:43 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:43 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 24 21:08:43 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 24 21:08:44 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 21:08:44 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 21:08:44 volumio volumio[969]: info: Discovery: Getting this device information Jun 24 21:08:44 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:44 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:44 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 21:08:45 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:45 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:46 volumio volumio[969]: verbose: New Socket.io Connection to 192.168.1.194:3000 from 192.168.1.95 UA: Dart/3.5 (dart:io) Total Clients: 7 Jun 24 21:08:46 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:46 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:46 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 24 21:08:46 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 24 21:08:46 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:46 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:48 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 21:08:48 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 21:08:48 volumio volumio[969]: info: Discovery: Getting this device information Jun 24 21:08:48 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:48 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:48 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 21:08:48 volumio sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 21:08:48 volumio sudo[1986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 21:08:48 volumio sudo[1986]: pam_unix(sudo:session): session closed for user root Jun 24 21:08:48 volumio sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 21:08:48 volumio sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 21:08:48 volumio sudo[1988]: pam_unix(sudo:session): session closed for user root Jun 24 21:08:48 volumio volumio[969]: verbose: New Socket.io Connection to 192.168.1.194 from 192.168.1.95 UA: Mozilla/5.0 (Linux; Android 11; SM-A505FN Build/RP1A.200720.012; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Total Clients: 8 Jun 24 21:08:48 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:08:49 volumio sudo[1994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 21:08:49 volumio sudo[1994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 21:08:49 volumio sudo[1994]: pam_unix(sudo:session): session closed for user root Jun 24 21:08:49 volumio sudo[1997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 21:08:49 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 21:08:49 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Jun 24 21:08:49 volumio volumio[969]: verbose: New Socket.io Connection to 192.168.1.194 from 192.168.1.95 UA: Mozilla/5.0 (Linux; Android 11; SM-A505FN Build/RP1A.200720.012; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Total Clients: 9 Jun 24 21:08:50 volumio volumio-remote-updater[581]: curl_easy_perform() failed: Timeout was reached Jun 24 21:08:50 volumio volumio-remote-updater[581]: terminate called after throwing an instance of 'std::invalid_argument' Jun 24 21:08:50 volumio volumio-remote-updater[581]: what(): parse error - unexpected end of input Jun 24 21:08:50 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Jun 24 21:08:50 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Jun 24 21:08:50 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jun 24 21:08:50 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Jun 24 21:08:50 volumio systemd[1]: Stopped volumio-remote-updater.service. Jun 24 21:08:50 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:08:50 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:08:50 volumio systemd[1]: Started volumio-remote-updater.service. Jun 24 21:08:50 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 24 21:08:50 volumio volumio[969]: info: Listing playlists Jun 24 21:08:50 volumio volumio-remote-updater[2001]: Error: No active session Jun 24 21:08:50 volumio volumio-remote-updater[2001]: [2025-06-24 21:08:50] [connect] Successful connection Jun 24 21:08:50 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 24 21:08:50 volumio volumio-remote-updater[2001]: [2025-06-24 21:08:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750795730 101 Jun 24 21:08:50 volumio volumio[969]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 10 Jun 24 21:08:50 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 24 21:08:50 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 24 21:08:50 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 24 21:08:51 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 24 21:08:52 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 24 21:08:54 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:09:00 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:09:05 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:09:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 24 21:09:08 volumio volumio[969]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 24 21:09:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 24 21:09:08 volumio volumio[969]: info: Received Get System Version Jun 24 21:09:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 21:09:08 volumio volumio[969]: info: Received Get System Info Jun 24 21:09:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 21:09:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 21:09:08 volumio volumio[969]: info: Discovery: Getting this device information Jun 24 21:09:08 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:09:08 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:09:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 21:09:11 volumio wpa_supplicant[844]: wlan0: Failed to initiate sched scan Jun 24 21:09:12 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 24 21:09:12 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 24 21:09:12 volumio volumio[969]: info: Discovery: Getting this device information Jun 24 21:09:12 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jun 24 21:09:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jun 24 21:09:12 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 24 21:09:15 volumio volumio[969]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 21:09:15 volumio volumio[969]: Error: connect ETIMEDOUT 199.232.54.79:80 Jun 24 21:09:15 volumio volumio[969]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 24 21:09:15 volumio volumio[969]: errno: -110, Jun 24 21:09:15 volumio volumio[969]: code: 'ETIMEDOUT', Jun 24 21:09:15 volumio volumio[969]: syscall: 'connect', Jun 24 21:09:15 volumio volumio[969]: address: '199.232.54.79', Jun 24 21:09:15 volumio volumio[969]: port: 80 Jun 24 21:09:15 volumio volumio[969]: } Jun 24 21:09:15 volumio volumio[969]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 21:09:16 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 21:08 Jun 24 21:09:16 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 03:58:37 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1d6379ebb89c40061afa8aee78176887"