Aug 26 06:47:01 volumio volumio[1274]: info: Preload queue cleared Aug 26 06:47:01 volumio volumio[1274]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::ClearQueue Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::stop Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::clearPlayQueue Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::saveQueue Aug 26 06:47:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushQueue Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::addQueueItems Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::addQueueItems Aug 26 06:47:01 volumio volumio[1274]: info: Preload queue cleared Aug 26 06:47:01 volumio volumio[1274]: info: Adding Item to queue: webeighties/0 Aug 26 06:47:01 volumio volumio[1274]: info: Exploding uri webeighties/0 in service 80s80s Aug 26 06:47:01 volumio volumio[1274]: info: [1787712421179] [80s80s] explodeUri: webeighties/0 Aug 26 06:47:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushQueue Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::saveQueue Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Aug 26 06:47:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPlay Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::play index 0 Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::stop Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::play index undefined Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::startPlaybackTimer Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:01 volumio volumio[1274]: info: [1787712421186] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Aug 26 06:47:01 volumio volumio[1274]: info: [1787712421187] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Aug 26 06:47:01 volumio volumio[1274]: info: [1787712421692] [80s80s] received new event containing 2 songs. Aug 26 06:47:01 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 06:47:01 volumio volumio[1274]: info: sendMpdCommand stop took 1 milliseconds Aug 26 06:47:01 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 06:47:01 volumio volumio[1274]: info: Aug 26 06:47:01 volumio volumio[1274]: ---------------------------- MPD announces system playlist update Aug 26 06:47:01 volumio volumio[1274]: info: Ignoring MPD Status Update Aug 26 06:47:01 volumio volumio[1274]: info: sendMpdCommand clear took 1 milliseconds Aug 26 06:47:01 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 26 06:47:01 volumio volumio[1274]: error: updateQueue error: null Aug 26 06:47:01 volumio volumio[1274]: info: Aug 26 06:47:01 volumio volumio[1274]: ---------------------------- MPD announces state update: options Aug 26 06:47:01 volumio volumio[1274]: info: ------------------------------ 2ms Aug 26 06:47:01 volumio volumio[1274]: info: sendMpdCommand consume 1 took 2 milliseconds Aug 26 06:47:01 volumio volumio[1274]: info: ControllerMpd::getState Aug 26 06:47:01 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Aug 26 06:47:01 volumio volumio[1274]: info: [1787712421699] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Aug 26 06:47:01 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Aug 26 06:47:01 volumio volumio[1274]: info: sendMpdCommand status took 1 milliseconds Aug 26 06:47:01 volumio volumio[1274]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 1 milliseconds Aug 26 06:47:01 volumio volumio[1274]: verbose: ControllerMpd::parseState Aug 26 06:47:01 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand play Aug 26 06:47:01 volumio volumio[1274]: info: ControllerMpd::pushState Aug 26 06:47:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::pushState Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:01 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 06:47:01 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:01 volumio volumio[1274]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 26 06:47:01 volumio volumio5-onboarding[1562]: time=2026-08-26T06:47:01.711+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.12:47758 @ 0x3401680" state=STATUS_STOPPED positionMs=503 volume=10 Aug 26 06:47:01 volumio volumio5-onboarding[1562]: time=2026-08-26T06:47:01.711+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.12:47758 @ 0x3401680" id=http://streams.80s80s.de/web/mp3-192/volumio title="80s80s Real 80s Radio" Aug 26 06:47:01 volumio volumio[1274]: info: ------------------------------ 18ms Aug 26 06:47:01 volumio volumio[1274]: info: Aug 26 06:47:01 volumio volumio[1274]: ---------------------------- MPD announces system playlist update Aug 26 06:47:01 volumio volumio[1274]: info: Ignoring MPD Status Update Aug 26 06:47:01 volumio volumio[1274]: info: FusionDsp - Volumio is not playing Aug 26 06:47:01 volumio volumio[1274]: info: FusionDsp - Clipped samples monitor stopped Aug 26 06:47:01 volumio volumio[1274]: info: sendMpdCommand play took 16 milliseconds Aug 26 06:47:01 volumio volumio[1274]: info: ------------------------------ 7ms Aug 26 06:47:01 volumio volumio[1274]: info: [1787712421724] [80s80s] Pushing the next song state: Eurythmics - Sweet Dreams (Are Made of This) Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:01 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:01 volumio volumio[1274]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music114/v4/ee/bc/55/eebc5593-3a6d-99c4-8b9c-063692fd3389/source/600x600bb.jpg","name":"Eurythmics - Sweet Dreams (Are Made of This)","title":"Sweet Dreams (Are Made of This)","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"200","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 26 06:47:01 volumio volumio[1274]: verbose: CURRENT POSITION 0 Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::syncState stateService play Aug 26 06:47:01 volumio volumio[1274]: info: CoreStateMachine::syncState currentStatus stop Aug 26 06:47:01 volumio volumio[1274]: info: [1787712421726] [80s80s] PlayNextTrack API delay: 30 Aug 26 06:47:01 volumio volumio[1274]: info: [1787712421739] [80s80s] Setting timer to: 200000 milliseconds. Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:01 volumio volumio[1274]: info: CorePlayQueue::getTrack 1 Aug 26 06:47:04 volumio volumio[1274]: info: Aug 26 06:47:04 volumio volumio[1274]: ---------------------------- MPD announces state update: player Aug 26 06:47:04 volumio volumio[1274]: info: ControllerMpd::getState Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Aug 26 06:47:04 volumio mpd[1743]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 26 06:47:04 volumio mpd[1743]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 26 06:47:04 volumio mpd[1743]: player: problems opening audio device while playing "http://streams.80s80s.de/web/mp3-192/volumio" Aug 26 06:47:04 volumio volumio[1274]: info: Aug 26 06:47:04 volumio volumio[1274]: ---------------------------- MPD announces state update: player Aug 26 06:47:04 volumio volumio[1274]: error: MPD returned error for command status: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 26 06:47:04 volumio volumio[1274]: info: sendMpdCommand status took 5 milliseconds Aug 26 06:47:04 volumio volumio[1274]: info: ControllerMpd::getState Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::parseState Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 06:47:04 volumio volumio[1274]: info: sendMpdCommand clearerror took 2 milliseconds Aug 26 06:47:04 volumio volumio[1274]: info: sendMpdCommand status took 1 milliseconds Aug 26 06:47:04 volumio volumio[1274]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::parseState Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::parseTrackInfo Aug 26 06:47:04 volumio volumio[1274]: info: ControllerMpd::pushState Aug 26 06:47:04 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Aug 26 06:47:04 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:04 volumio volumio[1274]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 26 06:47:04 volumio volumio[1274]: info: ------------------------------ 10ms Aug 26 06:47:04 volumio volumio[1274]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 06:47:04 volumio volumio[1274]: verbose: ControllerMpd::parseTrackInfo Aug 26 06:47:04 volumio volumio[1274]: info: ControllerMpd::pushState Aug 26 06:47:04 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Aug 26 06:47:04 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:04 volumio volumio[1274]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 26 06:47:04 volumio volumio[1274]: info: ------------------------------ 10ms Aug 26 06:47:14 volumio volumio[1274]: info: CoreCommandRouter::volumioPlay Aug 26 06:47:14 volumio volumio[1274]: info: CoreStateMachine::play index undefined Aug 26 06:47:14 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:47:14 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:47:20 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 06:47:20 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 06:47:20 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 26 06:47:34 volumio volumio[1274]: info: Enabling plugin backup_restore Aug 26 06:47:34 volumio volumio[1274]: info: Loading plugin "backup_restore"... Aug 26 06:47:35 volumio volumio[1274]: info: Applying required configuration parameters for plugin backup_restore Aug 26 06:47:35 volumio volumio[1274]: info: PLUGIN START: backup_restore Aug 26 06:47:35 volumio volumio[1274]: info: Done. Aug 26 06:47:35 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 06:47:39 volumio volumio[1274]: info: Enabling plugin rpi_eeprom_updater Aug 26 06:47:39 volumio volumio[1274]: info: Loading plugin "rpi_eeprom_updater"... Aug 26 06:47:39 volumio volumio[1274]: info: Applying required configuration parameters for plugin rpi_eeprom_updater Aug 26 06:47:39 volumio volumio[1274]: info: PLUGIN START: rpi_eeprom_updater Aug 26 06:47:39 volumio volumio[1274]: info: [RpiEepromUpdater] Plugin started successfully Aug 26 06:47:39 volumio volumio[1274]: info: Done. Aug 26 06:47:39 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 06:48:03 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: rp2 , handleBrowseUri Aug 26 06:48:05 volumio volumio[1274]: info: Preload queue cleared Aug 26 06:48:17 volumio volumio[1274]: info: Preload queue cleared Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::ClearQueue Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::stop Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::stPlaybackTimer Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::pushState Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::serviceStop Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::serviceStop Aug 26 06:48:17 volumio volumio[1274]: info: ControllerMpd::stop Aug 26 06:48:17 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::clearPlayQueue Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::saveQueue Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::volumioPushQueue Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::addQueueItems Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::addQueueItems Aug 26 06:48:17 volumio volumio[1274]: info: Preload queue cleared Aug 26 06:48:17 volumio volumio[1274]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D Aug 26 06:48:17 volumio volumio[1274]: info: Exploding uri rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D in service rp2 Aug 26 06:48:17 volumio volumio5-onboarding[1562]: time=2026-08-26T06:48:17.664+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.12:47758 @ 0x3401680" state=STATUS_STOPPED positionMs=0 volume=10 Aug 26 06:48:17 volumio volumio5-onboarding[1562]: time=2026-08-26T06:48:17.664+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.12:47758 @ 0x3401680" id=http://streams.80s80s.de/web/mp3-192/volumio title="Eurythmics - Sweet Dreams (Are Made of This)" Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::volumioPushQueue Aug 26 06:48:17 volumio mpd[1743]: player: played "http://streams.80s80s.de/web/mp3-192/volumio" Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::saveQueue Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::volumioPlay Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::play index 0 Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::stop Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::play index undefined Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::startPlaybackTimer Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:48:17 volumio volumio[1274]: info: [rp2] clearAddPlayTrack: rp2/channel@id=0 Aug 26 06:48:17 volumio volumio[1274]: info: FusionDsp - Volumio is not playing Aug 26 06:48:17 volumio volumio[1274]: info: FusionDsp - Clipped samples monitor stopped Aug 26 06:48:17 volumio volumio[1274]: info: Aug 26 06:48:17 volumio volumio[1274]: ---------------------------- MPD announces state update: player Aug 26 06:48:17 volumio volumio[1274]: info: sendMpdCommand stop took 47 milliseconds Aug 26 06:48:17 volumio volumio[1274]: info: ControllerMpd::getState Aug 26 06:48:17 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand status Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::pushState Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:48:17 volumio volumio[1274]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rp2 Received 80s80s Aug 26 06:48:17 volumio volumio5-onboarding[1562]: time=2026-08-26T06:48:17.705+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.12:47758 @ 0x3401680" state=STATUS_STOPPED positionMs=0 volume=10 Aug 26 06:48:17 volumio volumio5-onboarding[1562]: time=2026-08-26T06:48:17.705+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.12:47758 @ 0x3401680" id="rp2/channel@id=0" title="The Main Mix" Aug 26 06:48:17 volumio volumio[1274]: info: FusionDsp - Volumio is not playing Aug 26 06:48:17 volumio volumio[1274]: info: FusionDsp - Clipped samples monitor stopped Aug 26 06:48:17 volumio volumio[1274]: info: sendMpdCommand status took 13 milliseconds Aug 26 06:48:17 volumio volumio[1274]: verbose: ControllerMpd::parseState Aug 26 06:48:17 volumio volumio[1274]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 06:48:17 volumio volumio[1274]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 06:48:17 volumio volumio[1274]: verbose: ControllerMpd::parseTrackInfo Aug 26 06:48:17 volumio volumio[1274]: info: ControllerMpd::pushState Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::servicePushState Aug 26 06:48:17 volumio volumio[1274]: info: CoreStateMachine::pushState Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:48:17 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Aug 26 06:48:17 volumio volumio[1274]: info: CorePlayQueue::getTrack 0 Aug 26 06:48:17 volumio volumio[1274]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rp2 Received mpd Aug 26 06:48:17 volumio volumio5-onboarding[1562]: time=2026-08-26T06:48:17.719+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.12:47758 @ 0x3401680" state=STATUS_STOPPED positionMs=0 volume=10 Aug 26 06:48:17 volumio volumio5-onboarding[1562]: time=2026-08-26T06:48:17.719+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.12:47758 @ 0x3401680" id="rp2/channel@id=0" title="The Main Mix" Aug 26 06:48:17 volumio volumio[1274]: info: ------------------------------ 23ms Aug 26 06:48:17 volumio volumio[1274]: info: FusionDsp - Volumio is not playing Aug 26 06:48:17 volumio volumio[1274]: info: FusionDsp - Clipped samples monitor stopped Aug 26 06:48:18 volumio volumio[1274]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=0&audio_type= Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] Obtained block for channel "0" Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] ------------- Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] Block summary Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] ------------- Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] Stream URL: https://audio.radioparadise.stream/audio/blocks/0/x/1539/4/b/1539-0.flac Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] Tracks: Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] 0. Autumn Tactics (4:48 | elapsed: 18m 29s) Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] 1. raat ki rani (Khruangbin Remix) (4:31 | elapsed: 23m 17s) Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] 2. Exist (w/ Richard Houghten) (3:11 | elapsed: 27m 49s) Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] Aug 26 06:48:19 volumio volumio[1274]: verbose: [rp2] Current track scheduled playback vs. current time: 8/26/2026, 6:43:52 AM <-> 8/26/2026, 6:48:19 AM Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] Going to start playback of current track at 4:27 (track position in stream: 18:29) Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] Starting mpv Aug 26 06:48:19 volumio volumio[1274]: /bin/sh: 1: mpv: not found Aug 26 06:48:19 volumio volumio[1274]: error: [rp2] [mpv] Failed to get mpv version: Command failed: mpv --version Aug 26 06:48:19 volumio volumio[1274]: /bin/sh: 1: mpv: not found Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] [mpv] mpv process spawned Aug 26 06:48:19 volumio volumio[1274]: verbose: [rp2] Waiting for player event "playing"... Aug 26 06:48:19 volumio volumio[1274]: info: [rp2] [mpv] (PID: 3430) /bin/sh: 1: mpv: not found Aug 26 06:48:19 volumio volumio[1274]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 06:48:19 volumio volumio[1274]: TypeError: Cannot read properties of null (reading 'reset') Aug 26 06:48:19 volumio volumio[1274]: at /data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:207:48 Aug 26 06:48:19 volumio volumio[1274]: at ChildProcess. (/data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:221:19) Aug 26 06:48:19 volumio volumio[1274]: at ChildProcess.emit (node:events:514:28) Aug 26 06:48:19 volumio volumio[1274]: at maybeClose (node:internal/child_process:1105:16) Aug 26 06:48:19 volumio volumio[1274]: at Socket. (node:internal/child_process:457:11) Aug 26 06:48:19 volumio volumio[1274]: at Socket.emit (node:events:514:28) Aug 26 06:48:19 volumio volumio[1274]: at Pipe. (node:net:337:12) Aug 26 06:48:19 volumio volumio[1274]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 06:48:19 volumio sudo[3445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 06:47' Aug 26 06:48:19 volumio sudo[3445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"