Feb 25 17:51:00 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:00 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:00 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:00 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:01 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:01 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:01 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:01 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:02 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:02 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:02 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:02 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:03 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:03 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:03 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:03 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:04 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:04 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:04 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:04 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:05 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:05 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:05 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:05 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:06 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:06 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:06 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:06 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:07 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:07 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:07 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:07 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:08 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:08 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:08 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:08 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:09 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:09 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:09 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:09 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:10 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:10 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:10 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:10 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:10 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 25 17:51:10 volumio volumio[1440]: info: [1772041870904] [80s80s] handleBrowseUri curUri: 80s80s Feb 25 17:51:10 volumio volumio[1440]: info: Preload queue cleared Feb 25 17:51:11 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:11 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:11 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:11 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:12 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 25 17:51:12 volumio volumio[1440]: info: [1772041872274] [80s80s] handleBrowseUri curUri: 80s80s/nineties Feb 25 17:51:12 volumio volumio[1440]: info: [1772041872275] [80s80s] getRadioContent url: nineties Feb 25 17:51:12 volumio volumio[1440]: info: Preload queue cleared Feb 25 17:51:12 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:12 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:12 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:12 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::volumioGetState Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::volumioGetQueue Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::getQueue Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::getQueue Feb 25 17:51:13 volumio volumio[1440]: info: Preload queue cleared Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::ClearQueue Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::stop Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::stPlaybackTimer Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::pushState Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::serviceStop Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::serviceStop Feb 25 17:51:13 volumio volumio[1440]: info: [1772041873645] ControllerUPNPBrowser::stop Feb 25 17:51:13 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::clearPlayQueue Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::saveQueue Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::addQueueItems Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::addQueueItems Feb 25 17:51:13 volumio volumio[1440]: info: Preload queue cleared Feb 25 17:51:13 volumio volumio[1440]: info: Adding Item to queue: webnineties/2 Feb 25 17:51:13 volumio volumio[1440]: info: Exploding uri webnineties/2 in service 80s80s Feb 25 17:51:13 volumio volumio[1440]: info: [1772041873648] [80s80s] explodeUri: webnineties/2 Feb 25 17:51:13 volumio volumio5-onboarding[2906]: time=2026-02-25T17:51:13.657Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.153:47634 @ 0x1ca85a0" state=STATUS_STOPPED positionMs=0 volume=98 Feb 25 17:51:13 volumio volumio5-onboarding[2906]: time=2026-02-25T17:51:13.657Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.153:47634 @ 0x1ca85a0" id=http://192.168.0.151:32469/object/52298d02aec1e8d69f70/file.mp3 title=Iris Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::volumioPushQueue Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::saveQueue Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Feb 25 17:51:13 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::play index 0 Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::stop Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:13 volumio volumio[1440]: info: CoreStateMachine::startPlaybackTimer Feb 25 17:51:13 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:13 volumio volumio[1440]: info: [1772041873685] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/eurodance/mp3-192/volumio Feb 25 17:51:13 volumio volumio[1440]: info: [1772041873685] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=188&count=2 Feb 25 17:51:13 volumio volumio[1440]: info: PeppySpectrum ---peppyspectrum status stop Feb 25 17:51:13 volumio volumio[1440]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 25 17:51:13 volumio sudo[4614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 25 17:51:13 volumio sudo[4614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 17:51:13 volumio volumio[1440]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Feb 25 17:51:13 volumio volumio[1440]: info: sendMpdCommand stop took 103 milliseconds Feb 25 17:51:13 volumio sudo[4614]: pam_unix(sudo:session): session closed for user root Feb 25 17:51:13 volumio sudo[4617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 25 17:51:13 volumio volumio[1440]: info: peppyspectrum Daemon Stop Feb 25 17:51:13 volumio sudo[4617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 17:51:13 volumio volumio[1440]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 25 17:51:13 volumio sudo[4617]: pam_unix(sudo:session): session closed for user root Feb 25 17:51:13 volumio volumio[1440]: info: PeppyMeterBasic ---peppymeterbasic failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppymeterbasic.service Feb 25 17:51:13 volumio volumio[1440]: Failed to stop peppymeterbasic.service: Unit peppymeterbasic.service not loaded. Feb 25 17:51:14 volumio volumio[1440]: info: [1772041874286] [80s80s] received new event containing 2 songs. Feb 25 17:51:14 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 17:51:14 volumio volumio[1440]: info: sendMpdCommand stop took 0 milliseconds Feb 25 17:51:14 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand clear Feb 25 17:51:14 volumio volumio[1440]: info: Feb 25 17:51:14 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Feb 25 17:51:14 volumio volumio[1440]: info: Ignoring MPD Status Update Feb 25 17:51:14 volumio volumio[1440]: info: sendMpdCommand clear took 1 milliseconds Feb 25 17:51:14 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 25 17:51:14 volumio volumio[1440]: error: updateQueue error: null Feb 25 17:51:14 volumio volumio[1440]: info: Feb 25 17:51:14 volumio volumio[1440]: ---------------------------- MPD announces state update: options Feb 25 17:51:14 volumio volumio[1440]: info: ------------------------------ 3ms Feb 25 17:51:14 volumio volumio[1440]: info: sendMpdCommand consume 1 took 2 milliseconds Feb 25 17:51:14 volumio volumio[1440]: info: ControllerMpd::getState Feb 25 17:51:14 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand status Feb 25 17:51:14 volumio volumio[1440]: info: [1772041874292] [80s80s] adding url: http://streams.90s90s.de/eurodance/mp3-192/volumio Feb 25 17:51:14 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" Feb 25 17:51:14 volumio volumio[1440]: info: sendMpdCommand status took 12 milliseconds Feb 25 17:51:14 volumio volumio[1440]: verbose: ControllerMpd::parseState Feb 25 17:51:14 volumio volumio[1440]: info: ControllerMpd::pushState Feb 25 17:51:14 volumio volumio[1440]: info: CoreCommandRouter::servicePushState Feb 25 17:51:14 volumio volumio[1440]: info: CoreStateMachine::pushState Feb 25 17:51:14 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:14 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 17:51:14 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Feb 25 17:51:14 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:14 volumio volumio[1440]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 25 17:51:14 volumio volumio5-onboarding[2906]: time=2026-02-25T17:51:14.326Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.153:47634 @ 0x1ca85a0" state=STATUS_STOPPED positionMs=500 volume=98 Feb 25 17:51:14 volumio volumio5-onboarding[2906]: time=2026-02-25T17:51:14.326Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.153:47634 @ 0x1ca85a0" id=http://streams.90s90s.de/eurodance/mp3-192/volumio title="90s90s Dance" Feb 25 17:51:14 volumio volumio[1440]: info: ------------------------------ 39ms Feb 25 17:51:14 volumio volumio[1440]: info: Feb 25 17:51:14 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Feb 25 17:51:14 volumio volumio[1440]: info: Ignoring MPD Status Update Feb 25 17:51:14 volumio volumio[1440]: info: sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" took 40 milliseconds Feb 25 17:51:14 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand play Feb 25 17:51:14 volumio volumio[1440]: info: PeppySpectrum ---peppyspectrum status stop Feb 25 17:51:14 volumio volumio[1440]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 25 17:51:14 volumio volumio[1440]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Feb 25 17:51:14 volumio sudo[4626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 25 17:51:14 volumio volumio[1440]: info: ------------------------------ 66ms Feb 25 17:51:14 volumio volumio[1440]: info: sendMpdCommand play took 47 milliseconds Feb 25 17:51:14 volumio sudo[4626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 17:51:14 volumio volumio[1440]: info: [1772041874408] [80s80s] Pushing the next song state: Snap! - The First the Last Eternity (Till the End) (feat. Summer) (GDC Mix) Feb 25 17:51:14 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:14 volumio volumio[1440]: info: CoreCommandRouter::servicePushState Feb 25 17:51:14 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:14 volumio volumio[1440]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music128/v4/34/1f/26/341f2696-4e7e-96a4-e259-9ddaa05f6243/source/600x600bb.jpg","name":"Snap! - The First the Last Eternity (Till the End) (feat. Summer) (GDC Mix)","title":"The First the Last Eternity (Till the End) (feat. Summer) (GDC Mix)","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"228","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 25 17:51:14 volumio volumio[1440]: verbose: CURRENT POSITION 0 Feb 25 17:51:14 volumio volumio[1440]: info: CoreStateMachine::syncState stateService play Feb 25 17:51:14 volumio volumio[1440]: info: CoreStateMachine::syncState currentStatus stop Feb 25 17:51:14 volumio volumio[1440]: info: [1772041874411] [80s80s] PlayNextTrack API delay: 30 Feb 25 17:51:14 volumio sudo[4629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 25 17:51:14 volumio sudo[4629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 17:51:14 volumio volumio[1440]: info: [1772041874428] [80s80s] Setting timer to: 228000 milliseconds. Feb 25 17:51:14 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:14 volumio volumio[1440]: info: CorePlayQueue::getTrack 1 Feb 25 17:51:14 volumio sudo[4629]: pam_unix(sudo:session): session closed for user root Feb 25 17:51:14 volumio volumio[1440]: info: PeppyMeterBasic ---peppymeterbasic failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppymeterbasic.service Feb 25 17:51:14 volumio volumio[1440]: Failed to stop peppymeterbasic.service: Unit peppymeterbasic.service not loaded. Feb 25 17:51:14 volumio sudo[4626]: pam_unix(sudo:session): session closed for user root Feb 25 17:51:14 volumio volumio[1440]: info: peppyspectrum Daemon Stop Feb 25 17:51:15 volumio volumio[1440]: info: Feb 25 17:51:15 volumio volumio[1440]: ---------------------------- MPD announces state update: player Feb 25 17:51:15 volumio volumio[1440]: info: ControllerMpd::getState Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand status Feb 25 17:51:15 volumio volumio[1440]: info: Feb 25 17:51:15 volumio volumio[1440]: ---------------------------- MPD announces state update: player Feb 25 17:51:15 volumio volumio[1440]: info: sendMpdCommand status took 10 milliseconds Feb 25 17:51:15 volumio volumio[1440]: info: ControllerMpd::getState Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand status Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::parseState Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 17:51:15 volumio volumio[1440]: info: sendMpdCommand status took 1 milliseconds Feb 25 17:51:15 volumio volumio[1440]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::parseState Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::parseTrackInfo Feb 25 17:51:15 volumio volumio[1440]: info: ControllerMpd::pushState Feb 25 17:51:15 volumio volumio[1440]: info: CoreCommandRouter::servicePushState Feb 25 17:51:15 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:15 volumio volumio[1440]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 25 17:51:15 volumio volumio[1440]: info: ------------------------------ 14ms Feb 25 17:51:15 volumio volumio[1440]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::parseTrackInfo Feb 25 17:51:15 volumio volumio[1440]: info: ControllerMpd::pushState Feb 25 17:51:15 volumio volumio[1440]: info: CoreCommandRouter::servicePushState Feb 25 17:51:15 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:15 volumio volumio[1440]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 25 17:51:15 volumio volumio[1440]: info: ------------------------------ 12ms Feb 25 17:51:15 volumio volumio[1440]: info: Feb 25 17:51:15 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Feb 25 17:51:15 volumio volumio[1440]: info: Ignoring MPD Status Update Feb 25 17:51:15 volumio volumio[1440]: info: Feb 25 17:51:15 volumio volumio[1440]: ---------------------------- MPD announces state update: player Feb 25 17:51:15 volumio volumio[1440]: info: ControllerMpd::getState Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand status Feb 25 17:51:15 volumio volumio[1440]: info: ------------------------------ 2ms Feb 25 17:51:15 volumio volumio[1440]: info: sendMpdCommand status took 2 milliseconds Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::parseState Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 17:51:15 volumio volumio[1440]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 25 17:51:15 volumio volumio[1440]: verbose: ControllerMpd::parseTrackInfo Feb 25 17:51:15 volumio volumio[1440]: info: ControllerMpd::pushState Feb 25 17:51:15 volumio volumio[1440]: info: CoreCommandRouter::servicePushState Feb 25 17:51:15 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:15 volumio volumio[1440]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 25 17:51:15 volumio volumio[1440]: info: ------------------------------ 10ms Feb 25 17:51:15 volumio volumio[1440]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 25 17:51:15 volumio volumio[1440]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 25 17:51:16 volumio volumio[1440]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 25 17:51:17 volumio volumio[1440]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 25 17:51:19 volumio volumio[1440]: info: camilladsp respawn in 200 ms (attempt 2/10) Feb 25 17:51:21 volumio volumio[1440]: info: camilladsp respawn in 400 ms (attempt 3/10) Feb 25 17:51:25 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:25 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:25 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:25 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:31 volumio volumio[1440]: info: camilladsp respawn in 800 ms (attempt 4/10) Feb 25 17:51:32 volumio volumio[1440]: info: Feb 25 17:51:32 volumio volumio[1440]: ---------------------------- MPD announces system playlist update Feb 25 17:51:32 volumio volumio[1440]: info: Ignoring MPD Status Update Feb 25 17:51:32 volumio volumio[1440]: info: Feb 25 17:51:32 volumio volumio[1440]: ---------------------------- MPD announces state update: player Feb 25 17:51:32 volumio volumio[1440]: info: ControllerMpd::getState Feb 25 17:51:32 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand status Feb 25 17:51:32 volumio volumio[1440]: info: ------------------------------ 3ms Feb 25 17:51:32 volumio volumio[1440]: info: sendMpdCommand status took 2 milliseconds Feb 25 17:51:32 volumio volumio[1440]: verbose: ControllerMpd::parseState Feb 25 17:51:32 volumio volumio[1440]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 17:51:32 volumio volumio[1440]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 25 17:51:32 volumio volumio[1440]: verbose: ControllerMpd::parseTrackInfo Feb 25 17:51:32 volumio volumio[1440]: info: ControllerMpd::pushState Feb 25 17:51:32 volumio volumio[1440]: info: CoreCommandRouter::servicePushState Feb 25 17:51:32 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:32 volumio volumio[1440]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 25 17:51:32 volumio volumio[1440]: info: ------------------------------ 4ms Feb 25 17:51:33 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:33 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:33 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:33 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:41 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:41 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:41 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:41 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:46 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:46 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:46 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:46 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:47 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:47 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:47 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:47 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:47 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:47 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:47 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:47 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:47 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:47 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:47 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:47 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:48 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:48 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:48 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:48 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:51 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:51 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:51 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:51 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:51 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:51 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:51 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:51 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:51:54 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:51:54 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:51:54 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:51:54 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:52:00 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:52:00 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:52:00 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:52:00 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:52:01 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:52:01 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:52:01 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:52:01 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:52:02 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 25 17:52:03 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 25 17:52:03 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 25 17:52:16 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:52:16 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:52:16 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:52:16 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:52:16 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:52:16 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:52:16 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:52:16 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:52:17 volumio volumio[1440]: info: CoreCommandRouter::volumioPlay Feb 25 17:52:17 volumio volumio[1440]: info: CoreStateMachine::play index undefined Feb 25 17:52:17 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:52:17 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:52:22 volumio volumio[1440]: info: Preload queue cleared Feb 25 17:52:22 volumio volumio[1440]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 25 17:52:22 volumio volumio[1440]: info: CoreStateMachine::ClearQueue Feb 25 17:52:22 volumio volumio[1440]: info: CoreStateMachine::stop Feb 25 17:52:22 volumio volumio[1440]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 17:52:22 volumio volumio[1440]: info: CoreStateMachine::stPlaybackTimer Feb 25 17:52:22 volumio volumio[1440]: info: CoreStateMachine::updateTrackBlock Feb 25 17:52:22 volumio volumio[1440]: info: CorePlayQueue::getTrackBlock Feb 25 17:52:22 volumio volumio[1440]: info: CoreStateMachine::pushState Feb 25 17:52:22 volumio volumio[1440]: info: CorePlayQueue::getTrack 0 Feb 25 17:52:22 volumio volumio[1440]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 17:52:22 volumio volumio[1440]: info: CoreCommandRouter::volumioPushState Feb 25 17:52:22 volumio volumio[1440]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 17:52:22 volumio volumio[1440]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 25 17:52:22 volumio volumio[1440]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Feb 25 17:52:22 volumio volumio[1440]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Feb 25 17:52:22 volumio volumio[1440]: at /volumio/app/index.js:326:81 Feb 25 17:52:22 volumio volumio[1440]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 25 17:52:22 volumio volumio[1440]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 25 17:52:22 volumio volumio[1440]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Feb 25 17:52:22 volumio volumio[1440]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Feb 25 17:52:22 volumio volumio[1440]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1247:12) Feb 25 17:52:22 volumio volumio[1440]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Feb 25 17:52:22 volumio volumio[1440]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Feb 25 17:52:22 volumio volumio[1440]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Feb 25 17:52:22 volumio volumio[1440]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Feb 25 17:52:22 volumio volumio[1440]: at Socket.emit (node:events:514:28) Feb 25 17:52:22 volumio volumio[1440]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 25 17:52:22 volumio volumio[1440]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 25 17:52:22 volumio volumio[1440]: code: 'NumberExpected' Feb 25 17:52:22 volumio volumio[1440]: } Feb 25 17:52:22 volumio volumio[1440]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 17:52:22 volumio sudo[4824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 17:51' Feb 25 17:52:22 volumio sudo[4824]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"