Feb 26 17:08:00 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:08:00 volumio volumio[1362]: info: Received Get System Info Feb 26 17:08:00 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:08:00 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:08:00 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:08:00 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:00 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:00 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:08:00 volumio volumio[1362]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 26 17:08:01 volumio volumio[1362]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 26 17:08:01 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 26 17:08:01 volumio volumio[1362]: info: MyVolumio login type: Token Feb 26 17:08:01 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:08:01 volumio volumio[1362]: info: MyVolumio login type: Token Feb 26 17:08:01 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 17:08:01 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 26 17:08:02 volumio volumio[1362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 26 17:08:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:08:02 volumio volumio[1362]: info: Received Get System Info Feb 26 17:08:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:08:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:08:02 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:08:02 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:02 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:08:03 volumio volumio[1362]: info: MyVolumio token set successfully Feb 26 17:08:03 volumio volumio[1362]: info: MYVOLUMIO: Adding device Feb 26 17:08:03 volumio volumio[1362]: info: MYVOLUMIO: Evaluating Server Feb 26 17:08:03 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:03.401Z level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=GATu9Ohc8xTYbX7ZF3kGdYiBYfv2 tokenExpiry=2026-02-26T18:08:03.401Z Feb 26 17:08:03 volumio upmpdcli[2872]: writing RSA key Feb 26 17:08:04 volumio volumio[1362]: info: MyVolumio status changed Feb 26 17:08:04 volumio volumio[1362]: info: Streaming services startup Feb 26 17:08:04 volumio volumio[1362]: info: Starting Streaming Daemon Feb 26 17:08:04 volumio volumio[1362]: info: Removing browser output: myVolumio user plan is not superstar Feb 26 17:08:04 volumio volumio[1362]: info: Removing audio output: Feb 26 17:08:04 volumio volumio[1362]: info: Stoppping Tunnel 1 Feb 26 17:08:04 volumio sudo[2901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 26 17:08:04 volumio sudo[2901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:08:04 volumio volumio[1362]: info: Received Get System Info Feb 26 17:08:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:08:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:08:04 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:08:04 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:04 volumio sudo[2903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 26 17:08:04 volumio sudo[2903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 17:08:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 17:08:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 17:08:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 17:08:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 17:08:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 17:08:04 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:04 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:08:04 volumio sudo[2903]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:04 volumio sudo[2901]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:04 volumio volumio[1362]: error: Cannot start Volumio Streaming Daemon Feb 26 17:08:04 volumio volumio[1362]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 26 17:08:04 volumio volumio[1362]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 26 17:08:04 volumio volumio[1362]: info: Remote SSH Stopped Feb 26 17:08:04 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:04 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:06 volumio volumio[1362]: info: Setting Geolocation for MyVolumio to eu3 Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:06 volumio volumio[1362]: info: AutoStart - Check #10/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 26 17:08:06 volumio volumio[1362]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 17:08:06 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:08:07 volumio volumio[1362]: info: Updating MyVolumio device info Feb 26 17:08:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:07 volumio volumio[1362]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 26 17:08:10 volumio volumio[1362]: info: MYVOLUMIO: Adding device Feb 26 17:08:10 volumio volumio[1362]: info: MYVOLUMIO: Evaluating Server Feb 26 17:08:11 volumio volumio[1362]: info: AutoStart - Check #11/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 26 17:08:13 volumio volumio[1362]: info: Setting Geolocation for MyVolumio to eu3 Feb 26 17:08:13 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:13 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:13 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:13 volumio volumio[1362]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 26 17:08:14 volumio volumio[1362]: info: Updating MyVolumio device info Feb 26 17:08:14 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:14 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:14 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:14 volumio volumio[1362]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 26 17:08:15 volumio volumio[1362]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 26 17:08:15 volumio volumio[1362]: info: Completed starting MyVolumio Plugin Feb 26 17:08:15 volumio volumio[1362]: [Metrics] CommandRouter: 144s 357.93ms Feb 26 17:08:15 volumio volumio[1362]: info: CoreCommandRouter::volumiosetStartupVolume Feb 26 17:08:15 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 17:08:15 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:15 volumio volumio[1362]: info: CoreCommandRouter::Close All Modals sent Feb 26 17:08:15 volumio volumio[1362]: info: CoreCommandRouter::Close All Modals sent Feb 26 17:08:16 volumio volumio[1362]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 26 17:08:16 volumio volumio[1362]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 26 17:08:16 volumio volumio[1362]: info: camilladsp stopping service pid 2162... Feb 26 17:08:16 volumio volumio[1362]: grep: /proc/2162/cmdline: binary file matches Feb 26 17:08:16 volumio volumio[1362]: grep: /proc/2162/cmdline: binary file matches Feb 26 17:08:16 volumio volumio[1362]: grep: /proc/2162/cmdline: binary file matches Feb 26 17:08:16 volumio volumio[1362]: info: camilladsp service terminated, instance 1 Feb 26 17:08:16 volumio volumio[1362]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 17:08:16 volumio volumio[1362]: info: camilladsp service started and running in background, instance 1 Feb 26 17:08:16 volumio volumio[1362]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 26 17:08:16 volumio volumio[1362]: info: AutoStart - Check #12/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 26 17:08:16 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 26 17:08:16 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 17:08:16 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 26 17:08:21 volumio volumio[1362]: info: AutoStart - Check #13/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 26 17:08:22 volumio volumio[1362]: info: camilladsp respawn in 200 ms (attempt 2/10) Feb 26 17:08:22 volumio volumio[1362]: info: BOOT COMPLETED Feb 26 17:08:23 volumio volumio[1362]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Feb 26 17:08:23 volumio volumio[1362]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Feb 26 17:08:23 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:23 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:23 volumio volumio[1362]: info: CoreCommandRouter::volumioPause Feb 26 17:08:23 volumio volumio[1362]: info: CoreStateMachine::pause Feb 26 17:08:23 volumio volumio[1362]: info: mpdhttpout --- Volumio set on pause Feb 26 17:08:25 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 26 17:08:25 volumio sudo[2986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 17:08:25 volumio sudo[2986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:25 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Feb 26 17:08:25 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 26 17:08:25 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 26 17:08:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 26 17:08:26 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 26 17:08:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 26 17:08:26 volumio systemd[1]: mpd.service: Consumed 1.659s CPU time. Feb 26 17:08:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 26 17:08:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 26 17:08:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 26 17:08:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 26 17:08:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 17:08:26 volumio sudo[2989]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 17:08:26 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 17:08:26 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:26 volumio volumio[1362]: info: AutoStart - Check #14/60 - VOLUMIO_SYSTEM_STATUS = ready Feb 26 17:08:26 volumio volumio[1362]: info: AutoStart - System ready state CONFIRMED after 14 checks Feb 26 17:08:26 volumio volumio[1362]: info: AutoStart - Applying additional delay of 5000ms before playback Feb 26 17:08:28 volumio mpd[2991]: 2026-02-26T17:08:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 17:08:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 26 17:08:28 volumio sudo[2986]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:28 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 26 17:08:28 volumio volumio[1362]: error: updateQueue error: null Feb 26 17:08:29 volumio bash[3023]: cset: --> shielding system active with Feb 26 17:08:29 volumio bash[3023]: cset: "system" cpuset of CPUSPEC(1-3) with 310 tasks running Feb 26 17:08:29 volumio bash[3023]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 26 17:08:29 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 26 17:08:31 volumio volumio[1362]: info: AutoStart - startPlayback called Feb 26 17:08:31 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:31 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:31 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:31 volumio volumio[1362]: info: AutoStart - Queue has 80 items Feb 26 17:08:31 volumio volumio[1362]: info: AutoStart - Playing from position 0 Feb 26 17:08:31 volumio volumio[1362]: info: CoreCommandRouter::volumioPlay Feb 26 17:08:31 volumio volumio[1362]: info: CoreStateMachine::play index 0 Feb 26 17:08:31 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:08:31 volumio volumio[1362]: info: CoreStateMachine::stop Feb 26 17:08:31 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:08:31 volumio volumio[1362]: info: CoreStateMachine::play index undefined Feb 26 17:08:31 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:08:31 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:31 volumio volumio[1362]: info: CoreStateMachine::startPlaybackTimer Feb 26 17:08:31 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:31 volumio volumio[1362]: info: [1772125711730] ControllerUPNPBrowser::clearAddPlayTrack Feb 26 17:08:31 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 17:08:31 volumio volumio[1362]: info: sendMpdCommand stop took 2 milliseconds Feb 26 17:08:31 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 17:08:31 volumio volumio[1362]: info: Feb 26 17:08:31 volumio volumio[1362]: ---------------------------- MPD announces system playlist update Feb 26 17:08:31 volumio volumio[1362]: info: Ignoring MPD Status Update Feb 26 17:08:31 volumio volumio[1362]: info: sendMpdCommand clear took 30 milliseconds Feb 26 17:08:31 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.151:32469/object/987a1782f6b6ccd640b4/file.flac" Feb 26 17:08:32 volumio volumio[1362]: error: updateQueue error: null Feb 26 17:08:32 volumio volumio[1362]: info: ------------------------------ 312ms Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.151:32469/object/987a1782f6b6ccd640b4/file.flac" Feb 26 17:08:32 volumio volumio[1362]: info: Feb 26 17:08:32 volumio volumio[1362]: ---------------------------- MPD announces system playlist update Feb 26 17:08:32 volumio volumio[1362]: info: Ignoring MPD Status Update Feb 26 17:08:32 volumio volumio[1362]: info: sendMpdCommand add "http://192.168.0.151:32469/object/987a1782f6b6ccd640b4/file.flac" took 21 milliseconds Feb 26 17:08:32 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand play Feb 26 17:08:32 volumio volumio[1362]: info: ------------------------------ 24ms Feb 26 17:08:32 volumio volumio[1362]: info: sendMpdCommand play took 2 milliseconds Feb 26 17:08:32 volumio volumio[1362]: info: Feb 26 17:08:32 volumio volumio[1362]: ---------------------------- MPD announces state update: player Feb 26 17:08:32 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:08:32 volumio volumio[1362]: info: Feb 26 17:08:32 volumio volumio[1362]: ---------------------------- MPD announces state update: player Feb 26 17:08:32 volumio volumio[1362]: info: sendMpdCommand status took 28 milliseconds Feb 26 17:08:32 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 17:08:32 volumio volumio[1362]: info: sendMpdCommand status took 2 milliseconds Feb 26 17:08:32 volumio volumio[1362]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::parseTrackInfo Feb 26 17:08:32 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:08:32 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:08:32 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:32 volumio volumio[1362]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.flac","artist":null,"album":null,"uri":"http://192.168.0.151:32469/object/987a1782f6b6ccd640b4/file.flac","trackType":"flac"} Feb 26 17:08:32 volumio volumio[1362]: verbose: CURRENT POSITION 0 Feb 26 17:08:32 volumio volumio[1362]: info: CoreStateMachine::syncState stateService play Feb 26 17:08:32 volumio volumio[1362]: info: CoreStateMachine::syncState currentStatus stop Feb 26 17:08:32 volumio volumio[1362]: info: ------------------------------ 121ms Feb 26 17:08:32 volumio volumio[1362]: info: sendMpdCommand playlistinfo took 89 milliseconds Feb 26 17:08:32 volumio volumio[1362]: verbose: ControllerMpd::parseTrackInfo Feb 26 17:08:32 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:08:32 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:08:32 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:32 volumio volumio[1362]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.flac","artist":null,"album":null,"uri":"http://192.168.0.151:32469/object/987a1782f6b6ccd640b4/file.flac","trackType":"flac"} Feb 26 17:08:32 volumio volumio[1362]: verbose: CURRENT POSITION 0 Feb 26 17:08:32 volumio volumio[1362]: info: CoreStateMachine::syncState stateService play Feb 26 17:08:32 volumio volumio[1362]: info: CoreStateMachine::syncState currentStatus play Feb 26 17:08:32 volumio volumio[1362]: info: Received an update from plugin. extracting info from payload Feb 26 17:08:32 volumio volumio[1362]: info: CoreStateMachine::pushState Feb 26 17:08:32 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:08:32 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Feb 26 17:08:32 volumio volumio[1362]: info: CoreStateMachine::pushState Feb 26 17:08:32 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Feb 26 17:08:33 volumio volumio[1362]: info: ------------------------------ 744ms Feb 26 17:08:33 volumio volumio[1362]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 26 17:08:33 volumio volumio[1362]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 26 17:08:33 volumio volumio[1362]: info: Feb 26 17:08:33 volumio volumio[1362]: ---------------------------- MPD announces system playlist update Feb 26 17:08:33 volumio volumio[1362]: info: Ignoring MPD Status Update Feb 26 17:08:33 volumio volumio[1362]: info: Feb 26 17:08:33 volumio volumio[1362]: ---------------------------- MPD announces state update: player Feb 26 17:08:33 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:08:33 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:08:33 volumio volumio[1362]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! file.flac Feb 26 17:08:33 volumio volumio[1362]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! file.flac Feb 26 17:08:33 volumio volumio[1362]: info: PeppySpectrum ---peppyspectrum status play Feb 26 17:08:33 volumio volumio[1362]: info: PeppySpectrum ---peppyspectrum status play Feb 26 17:08:33 volumio sudo[3065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 26 17:08:33 volumio sudo[3065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:33 volumio volumio[1362]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 17:08:33 volumio volumio[1362]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 17:08:33 volumio volumio[1362]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 17:08:33 volumio volumio[1362]: info: ------------------------------ 394ms Feb 26 17:08:33 volumio volumio[1362]: info: sendMpdCommand status took 413 milliseconds Feb 26 17:08:33 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:08:33 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 17:08:33 volumio volumio[1362]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 17:08:33 volumio volumio[1362]: verbose: ControllerMpd::parseTrackInfo Feb 26 17:08:33 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:08:33 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:08:33 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:08:33 volumio volumio[1362]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2017,"duration":223,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"918 Kbps","isStreaming":false,"title":"Everywhere (2018 Remaster)","artist":"Fleetwood Mac","album":"80's Mixtape","uri":"http://192.168.0.151:32469/object/987a1782f6b6ccd640b4/file.flac","trackType":"flac"} Feb 26 17:08:33 volumio volumio[1362]: verbose: CURRENT POSITION 0 Feb 26 17:08:33 volumio volumio[1362]: info: CoreStateMachine::syncState stateService play Feb 26 17:08:33 volumio volumio[1362]: info: CoreStateMachine::syncState currentStatus play Feb 26 17:08:33 volumio volumio[1362]: info: Received an update from plugin. extracting info from payload Feb 26 17:08:33 volumio volumio[1362]: info: CoreStateMachine::pushState Feb 26 17:08:33 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:08:33 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Feb 26 17:08:33 volumio volumio[1362]: info: CoreStateMachine::pushState Feb 26 17:08:33 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Feb 26 17:08:33 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Feb 26 17:08:33 volumio sudo[3065]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:33 volumio volumio[1362]: info: ------------------------------ 630ms Feb 26 17:08:33 volumio sudo[3068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 26 17:08:33 volumio volumio[1362]: info: [LastFM] Current track has sufficient metadata: title (Everywhere (2018 Remaster)) and artist (Fleetwood Mac) passed on explicitly Feb 26 17:08:33 volumio volumio[1362]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Feb 26 17:08:33 volumio volumio[1362]: info: PeppySpectrum ---peppyspectrum status play Feb 26 17:08:33 volumio sudo[3068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:33 volumio volumio[1362]: info: PeppySpectrum ---peppyspectrum status play Feb 26 17:08:33 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:33 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:33 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:33 volumio sudo[3068]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:33 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:33 volumio sudo[3076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 26 17:08:33 volumio sudo[3076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:34 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:34 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:34 volumio sudo[3076]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:34 volumio volumio[1362]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 17:08:34 volumio volumio[1362]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 17:08:34 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:34 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:34 volumio sudo[3074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 26 17:08:34 volumio sudo[3074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:34 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:34 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:34 volumio volumio[1362]: info: peppyspectrum Daemon Started Feb 26 17:08:34 volumio volumio[1362]: info: peppyspectrum Daemon Started Feb 26 17:08:34 volumio sudo[3074]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:34 volumio volumio[1362]: info: peppyspectrum Daemon Started Feb 26 17:08:34 volumio volumio[1362]: info: peppyspectrum Daemon Started Feb 26 17:08:34 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:34 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:34 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:34 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:34 volumio startpeppyspectrum.sh[3070]: pygame 2.1.2 (SDL 2.26.5, Python 3.11.2) Feb 26 17:08:34 volumio startpeppyspectrum.sh[3070]: Hello from the pygame community. https://www.pygame.org/contribute.html Feb 26 17:08:34 volumio startpeppyspectrum.sh[3070]: Traceback (most recent call last): Feb 26 17:08:34 volumio startpeppyspectrum.sh[3070]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrum.py", line 30, in Feb 26 17:08:34 volumio startpeppyspectrum.sh[3070]: from spectrumutil import SpectrumUtil Feb 26 17:08:34 volumio startpeppyspectrum.sh[3070]: File "/data/plugins/user_interface/peppyspectrum/PeppySpectrum/spectrumutil.py", line 19, in Feb 26 17:08:34 volumio startpeppyspectrum.sh[3070]: from PIL import Image Feb 26 17:08:34 volumio startpeppyspectrum.sh[3070]: ModuleNotFoundError: No module named 'PIL' Feb 26 17:08:35 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Feb 26 17:08:35 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Feb 26 17:08:35 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:35 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:35 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:35 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:36 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:36 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:36 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:36 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:37 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:37.458Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.153:37882 Feb 26 17:08:37 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:37 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:37 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:37 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.481Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.153:37882 @ 0x2987230" latency=275.884932ms platform=PLATFORM_ANDROID version=5.260209.0 Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.482Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.153:37882 @ 0x2987230" latency=276.132525ms timeout=10s Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.482Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 17:08:38 volumio volumio[1362]: info: Received Get System Info Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:08:38 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.545Z level=INFO msg="emitting device name changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" name=Volumio Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.550Z level=INFO msg="emitting device language changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" language=en Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.571Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" timezone=Europe/London Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.572Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" available=true connected=false macAddress= ip4Address= ip6Address= Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.581Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" available=true connected=true macAddress=dc:a6:32:05:7d:b1 ip4Address=192.168.0.106/24 ip6Address= ssid=VM1902505 Feb 26 17:08:38 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:38.581Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" setupComplete=true Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 17:08:38 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 17:08:38 volumio volumio[1362]: amixer -c 4 info | grep "BossDAC" Feb 26 17:08:39 volumio volumio[1362]: Card sysdefault:4 'BossDAC'/'BossDAC' Feb 26 17:08:39 volumio volumio[1362]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 26 17:08:39 volumio volumio[1362]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 26 17:08:39 volumio volumio[1362]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 26 17:08:39 volumio volumio[1362]: amixer -c 2 info | grep "vc4-hdmi-0" Feb 26 17:08:39 volumio volumio[1362]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Feb 26 17:08:39 volumio volumio[1362]: amixer -c 3 info | grep "vc4-hdmi-1" Feb 26 17:08:39 volumio volumio[1362]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Feb 26 17:08:39 volumio volumio[1362]: amixer -c 4 info | grep "BossDAC" Feb 26 17:08:40 volumio volumio[1362]: Card sysdefault:4 'BossDAC'/'BossDAC' Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:40 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:40 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:40 volumio volumio[1362]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 7 Feb 26 17:08:40 volumio volumio[1362]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 26 17:08:40 volumio volumio[1362]: {"cmd":"/usr/local/bin/alsacap -C 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 26 17:08:40 volumio volumio[1362]: amixer -c 7 info | grep "Loopback" Feb 26 17:08:40 volumio volumio[1362]: Card sysdefault:7 'Loopback'/'Loopback 1' Feb 26 17:08:40 volumio volumio[1362]: Mixer name : 'Loopback Mixer' Feb 26 17:08:40 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:40.738Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" selectedOutputId=4 Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:40 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:40 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:40 volumio volumio[1362]: info: Received Get System Info Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:08:40 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:08:40 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:40.790Z level=INFO msg="emitting software info changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" currentVersion=4.096 latestVersion=4.096 Feb 26 17:08:40 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:40.790Z level=INFO msg="emitting software update progress event" component=server peer="192.168.0.153:37882 @ 0x2987230" status=UPDATE_STATUS_NONE progress=0 Feb 26 17:08:40 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:40.790Z level=INFO msg="emitting user changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" userId=GATu9Ohc8xTYbX7ZF3kGdYiBYfv2 Feb 26 17:08:40 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:40.791Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" providers=3 Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:08:40 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 26 17:08:41 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:41 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:41 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:41 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:42 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:42 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:42 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:42 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:43 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:43.127Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" plugins=60 Feb 26 17:08:43 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:43 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:43.132Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" state=STATUS_PLAYING positionMs=10786 volume=100 Feb 26 17:08:43 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:43.132Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" id=http://192.168.0.151:32469/object/987a1782f6b6ccd640b4/file.flac title="Everywhere (2018 Remaster)" Feb 26 17:08:43 volumio volumio5-onboarding[2835]: time=2026-02-26T17:08:43.187Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.153:37882 @ 0x2987230" latency=277.3093ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 26 17:08:43 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:43 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:43 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:43 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:44 volumio sudo[3145]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 17:08:44 volumio sudo[3143]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 17:08:44 volumio sudo[3145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:44 volumio sudo[3145]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:44 volumio sudo[3143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:44 volumio sudo[3143]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:44 volumio volumio[1362]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.153 UA: Mozilla/5.0 (Linux; Android 16; V2417A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 26 17:08:44 volumio sudo[3152]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 17:08:44 volumio sudo[3150]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 17:08:44 volumio sudo[3150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:44 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:44 volumio sudo[3152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:44 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:44 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:44 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:45 volumio sudo[3150]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:45 volumio sudo[3152]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:45 volumio volumio[1362]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 17:08:45 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 17:08:45 volumio volumio[1362]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.153 UA: Mozilla/5.0 (Linux; Android 16; V2417A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Feb 26 17:08:46 volumio sudo[3165]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 17:08:46 volumio sudo[3165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:46 volumio sudo[3165]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:46 volumio sudo[3167]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 17:08:46 volumio sudo[3167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:46 volumio sudo[3167]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:46 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:46 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:46 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:46 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:46 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:47 volumio volumio[1362]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 26 17:08:48 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:48 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:48 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:48 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:48 volumio volumio[1362]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.153 UA: Mozilla/5.0 (Linux; Android 16; V2417A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Feb 26 17:08:48 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 17:08:48 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 17:08:48 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 26 17:08:49 volumio volumio[1362]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.153 UA: Mozilla/5.0 (Linux; Android 16; V2417A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Feb 26 17:08:49 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:49 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:49 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:49 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:49 volumio volumio[1362]: info: Received Get System Version Feb 26 17:08:49 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:08:49 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Feb 26 17:08:49 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:49 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:49 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:49 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:49 volumio sudo[3192]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 17:08:49 volumio sudo[3192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:49 volumio sudo[3194]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 17:08:49 volumio sudo[3194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:08:49 volumio sudo[3192]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:50 volumio sudo[3194]: pam_unix(sudo:session): session closed for user root Feb 26 17:08:50 volumio volumio[1362]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.153 UA: Mozilla/5.0 (Linux; Android 16; V2417A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Feb 26 17:08:50 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:50 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 17:08:50 volumio volumio[1362]: info: Listing playlists Feb 26 17:08:50 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 17:08:50 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 17:08:50 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 17:08:50 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 17:08:50 volumio volumio[1362]: info: camilladsp respawn in 400 ms (attempt 3/10) Feb 26 17:08:50 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:50 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:50 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:50 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:50 volumio volumio[1362]: verbose: New Socket.io Connection to 192.168.0.106 from 192.168.0.153 UA: Mozilla/5.0 (Linux; Android 16; V2417A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:08:51 volumio volumio[1362]: info: Received Get System Info Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:08:51 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:51 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:51 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 17:08:51 volumio volumio[1362]: info: Received Get System Info Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:08:51 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:51 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:08:52 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:52 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:52 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:52 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:53 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 17:08:53 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 17:08:53 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 26 17:08:53 volumio volumio[1362]: info: Received Get System Version Feb 26 17:08:53 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:08:53 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Feb 26 17:08:53 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:53 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:53 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:53 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:54 volumio volumio[1362]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 17:08:54 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 17:08:54 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:54 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:54 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:54 volumio volumio[1362]: info: camilladsp respawn in 800 ms (attempt 4/10) Feb 26 17:08:54 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:54 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:54 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:54 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:55 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:55 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:55 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:55 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:56 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:56 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:56 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:56 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:57 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:57 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:57 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:57 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:58 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:58 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:58 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:58 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:08:59 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:08:59 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:08:59 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:08:59 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:00 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:00 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:00 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:00 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:01 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:01 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:01 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:01 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 17:09:02 volumio volumio[1362]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 26 17:09:02 volumio volumio[1362]: info: Received Get System Version Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:09:02 volumio volumio[1362]: info: Received Get System Info Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:09:02 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:02 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:02 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:02 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 17:09:03 volumio volumio[1362]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 26 17:09:03 volumio volumio[1362]: info: Received Get System Version Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:09:03 volumio volumio[1362]: info: Received Get System Info Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:09:03 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:03 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:03 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:03 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:04 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:04 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:04 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:04 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:05 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:05 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:05 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:05 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:06 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:06 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:06 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:06 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 17:09:07 volumio volumio[1362]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 26 17:09:07 volumio volumio[1362]: info: Received Get System Version Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 17:09:07 volumio volumio[1362]: info: Received Get System Info Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 17:09:07 volumio volumio[1362]: info: Discovery: Getting this device information Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:07 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:07 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:07 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:08 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:08 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:08 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:08 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:09 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:09 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:09 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:09 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:09 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 26 17:09:09 volumio volumio[1362]: info: [1772125749950] [80s80s] handleBrowseUri curUri: 80s80s Feb 26 17:09:10 volumio volumio[1362]: info: Preload queue cleared Feb 26 17:09:10 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:10 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:10 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:10 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:11 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:11 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:11 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:11 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:12 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Feb 26 17:09:12 volumio volumio[1362]: info: [1772125752076] [80s80s] handleBrowseUri curUri: 80s80s/nineties Feb 26 17:09:12 volumio volumio[1362]: info: [1772125752077] [80s80s] getRadioContent url: nineties Feb 26 17:09:12 volumio volumio[1362]: info: Preload queue cleared Feb 26 17:09:12 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:12 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:12 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:12 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:13 volumio volumio[1362]: info: CoreCommandRouter::volumioGetState Feb 26 17:09:13 volumio volumio[1362]: info: CoreCommandRouter::volumioGetQueue Feb 26 17:09:13 volumio volumio[1362]: info: CoreStateMachine::getQueue Feb 26 17:09:13 volumio volumio[1362]: info: CorePlayQueue::getQueue Feb 26 17:09:13 volumio volumio[1362]: info: Preload queue cleared Feb 26 17:09:13 volumio volumio[1362]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 17:09:13 volumio volumio[1362]: info: CoreStateMachine::ClearQueue Feb 26 17:09:13 volumio volumio[1362]: info: CoreStateMachine::stop Feb 26 17:09:13 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:09:13 volumio volumio[1362]: info: CoreStateMachine::stPlaybackTimer Feb 26 17:09:13 volumio volumio[1362]: info: CoreStateMachine::updateTrackBlock Feb 26 17:09:13 volumio volumio[1362]: info: CorePlayQueue::getTrackBlock Feb 26 17:09:13 volumio volumio[1362]: info: CoreStateMachine::pushState Feb 26 17:09:13 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:13 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:09:13 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::serviceStop Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:14 volumio volumio[1362]: info: CoreCommandRouter::serviceStop Feb 26 17:09:14 volumio volumio[1362]: info: [1772125754043] ControllerUPNPBrowser::stop Feb 26 17:09:14 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::clearPlayQueue Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::saveQueue Feb 26 17:09:14 volumio volumio[1362]: info: CoreCommandRouter::volumioPushQueue Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::addQueueItems Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::addQueueItems Feb 26 17:09:14 volumio volumio[1362]: info: Preload queue cleared Feb 26 17:09:14 volumio volumio[1362]: info: Adding Item to queue: webnineties/0 Feb 26 17:09:14 volumio volumio[1362]: info: Exploding uri webnineties/0 in service 80s80s Feb 26 17:09:14 volumio volumio[1362]: info: [1772125754062] [80s80s] explodeUri: webnineties/0 Feb 26 17:09:14 volumio volumio5-onboarding[2835]: time=2026-02-26T17:09:14.096Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" state=STATUS_STOPPED positionMs=0 volume=100 Feb 26 17:09:14 volumio volumio5-onboarding[2835]: time=2026-02-26T17:09:14.096Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" id=http://192.168.0.151:32469/object/987a1782f6b6ccd640b4/file.flac title="Everywhere (2018 Remaster)" Feb 26 17:09:14 volumio volumio[1362]: info: CoreCommandRouter::volumioPushQueue Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::saveQueue Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::updateTrackBlock Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::getTrackBlock Feb 26 17:09:14 volumio volumio[1362]: info: CoreCommandRouter::volumioPlay Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::play index 0 Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::stop Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::play index undefined Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::startPlaybackTimer Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:14 volumio volumio[1362]: info: [1772125754430] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/pop/mp3-192/volumio Feb 26 17:09:14 volumio volumio[1362]: info: [1772125754430] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=141&count=2 Feb 26 17:09:14 volumio volumio[1362]: info: PeppySpectrum ---peppyspectrum status stop Feb 26 17:09:14 volumio volumio[1362]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 17:09:14 volumio volumio[1362]: info: Feb 26 17:09:14 volumio volumio[1362]: ---------------------------- MPD announces state update: player Feb 26 17:09:14 volumio volumio[1362]: info: sendMpdCommand stop took 707 milliseconds Feb 26 17:09:14 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:09:14 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:09:14 volumio volumio[1362]: info: sendMpdCommand status took 1 milliseconds Feb 26 17:09:14 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:09:14 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 17:09:14 volumio volumio[1362]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 26 17:09:14 volumio volumio[1362]: verbose: ControllerMpd::parseTrackInfo Feb 26 17:09:14 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:09:14 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:09:14 volumio volumio[1362]: info: CoreStateMachine::pushState Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:14 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:09:14 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Feb 26 17:09:14 volumio sudo[3310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 26 17:09:14 volumio sudo[3310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:09:14 volumio sudo[3310]: pam_unix(sudo:session): session closed for user root Feb 26 17:09:14 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:14 volumio volumio[1362]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 26 17:09:15 volumio volumio5-onboarding[2835]: time=2026-02-26T17:09:15.014Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" state=STATUS_STOPPED positionMs=322 volume=100 Feb 26 17:09:15 volumio volumio5-onboarding[2835]: time=2026-02-26T17:09:15.014Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" id=http://streams.90s90s.de/pop/mp3-192/volumio title="90s90s Hits" Feb 26 17:09:15 volumio volumio[1362]: info: ------------------------------ 357ms Feb 26 17:09:15 volumio volumio[1362]: info: PeppySpectrum ---peppyspectrum status stop Feb 26 17:09:15 volumio volumio[1362]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 17:09:15 volumio sudo[3313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 26 17:09:15 volumio volumio[1362]: info: peppyspectrum Daemon Stop Feb 26 17:09:15 volumio volumio[1362]: info: camilladsp respawn in 1600 ms (attempt 5/10) Feb 26 17:09:15 volumio sudo[3313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:09:15 volumio volumio[1362]: info: [1772125755432] [80s80s] received new event containing 2 songs. Feb 26 17:09:15 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand stop Feb 26 17:09:15 volumio volumio[1362]: info: sendMpdCommand stop took 57 milliseconds Feb 26 17:09:15 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand clear Feb 26 17:09:15 volumio volumio[1362]: info: Feb 26 17:09:15 volumio volumio[1362]: ---------------------------- MPD announces system playlist update Feb 26 17:09:15 volumio volumio[1362]: info: Ignoring MPD Status Update Feb 26 17:09:15 volumio volumio[1362]: info: sendMpdCommand clear took 2 milliseconds Feb 26 17:09:15 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 26 17:09:15 volumio volumio[1362]: error: updateQueue error: null Feb 26 17:09:15 volumio volumio[1362]: info: Feb 26 17:09:15 volumio volumio[1362]: ---------------------------- MPD announces state update: options Feb 26 17:09:15 volumio volumio[1362]: info: ------------------------------ 2ms Feb 26 17:09:15 volumio volumio[1362]: info: sendMpdCommand consume 1 took 1 milliseconds Feb 26 17:09:15 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:09:15 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:09:15 volumio volumio[1362]: info: [1772125755494] [80s80s] adding url: http://streams.90s90s.de/pop/mp3-192/volumio Feb 26 17:09:15 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" Feb 26 17:09:15 volumio volumio[1362]: info: Feb 26 17:09:15 volumio volumio[1362]: ---------------------------- MPD announces system playlist update Feb 26 17:09:15 volumio volumio[1362]: info: Ignoring MPD Status Update Feb 26 17:09:15 volumio volumio[1362]: info: sendMpdCommand status took 1 milliseconds Feb 26 17:09:15 volumio volumio[1362]: info: sendMpdCommand add "http://streams.90s90s.de/pop/mp3-192/volumio" took 1 milliseconds Feb 26 17:09:15 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:09:15 volumio sudo[3313]: pam_unix(sudo:session): session closed for user root Feb 26 17:09:15 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand play Feb 26 17:09:15 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:09:15 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:09:15 volumio volumio[1362]: info: CoreStateMachine::pushState Feb 26 17:09:15 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:15 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:09:15 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Feb 26 17:09:15 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:15 volumio volumio[1362]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 26 17:09:15 volumio volumio5-onboarding[2835]: time=2026-02-26T17:09:15.764Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" state=STATUS_STOPPED positionMs=971 volume=100 Feb 26 17:09:15 volumio volumio5-onboarding[2835]: time=2026-02-26T17:09:15.765Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.153:37882 @ 0x2987230" id=http://streams.90s90s.de/pop/mp3-192/volumio title="90s90s Hits" Feb 26 17:09:15 volumio volumio[1362]: info: ------------------------------ 292ms Feb 26 17:09:15 volumio volumio[1362]: info: ------------------------------ 291ms Feb 26 17:09:15 volumio volumio[1362]: info: sendMpdCommand play took 184 milliseconds Feb 26 17:09:15 volumio volumio[1362]: info: [1772125755888] [80s80s] Pushing the next song state: Oasis - Wonderwall Feb 26 17:09:15 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:15 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:09:15 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:15 volumio volumio[1362]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music113/v4/f1/91/ef/f191effe-4cf2-932b-f2cf-a724454d2d49/source/600x600bb.jpg","name":"Oasis - Wonderwall","title":"Wonderwall","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"243","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 26 17:09:15 volumio volumio[1362]: verbose: CURRENT POSITION 0 Feb 26 17:09:15 volumio volumio[1362]: info: CoreStateMachine::syncState stateService play Feb 26 17:09:15 volumio volumio[1362]: info: CoreStateMachine::syncState currentStatus stop Feb 26 17:09:15 volumio volumio[1362]: info: [1772125755890] [80s80s] PlayNextTrack API delay: 30 Feb 26 17:09:16 volumio volumio[1362]: info: [1772125756080] [80s80s] Setting timer to: 243000 milliseconds. Feb 26 17:09:16 volumio volumio[1362]: info: PeppySpectrum ---peppyspectrum status stop Feb 26 17:09:16 volumio volumio[1362]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 17:09:16 volumio volumio[1362]: info: peppyspectrum Daemon Stop Feb 26 17:09:16 volumio sudo[3320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 26 17:09:16 volumio sudo[3320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 17:09:16 volumio volumio[1362]: info: Feb 26 17:09:16 volumio volumio[1362]: ---------------------------- MPD announces state update: player Feb 26 17:09:16 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:09:16 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:09:16 volumio volumio[1362]: info: Feb 26 17:09:16 volumio volumio[1362]: ---------------------------- MPD announces state update: player Feb 26 17:09:16 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:09:16 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:09:16 volumio volumio[1362]: info: sendMpdCommand status took 1 milliseconds Feb 26 17:09:16 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:09:16 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 17:09:16 volumio volumio[1362]: info: sendMpdCommand status took 2 milliseconds Feb 26 17:09:16 volumio volumio[1362]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 17:09:16 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:09:16 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 17:09:16 volumio volumio[1362]: verbose: ControllerMpd::parseTrackInfo Feb 26 17:09:16 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:09:16 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:09:16 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:16 volumio volumio[1362]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 26 17:09:16 volumio volumio[1362]: info: ------------------------------ 6ms Feb 26 17:09:16 volumio volumio[1362]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 26 17:09:16 volumio volumio[1362]: verbose: ControllerMpd::parseTrackInfo Feb 26 17:09:16 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:09:16 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:09:16 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:16 volumio volumio[1362]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 26 17:09:16 volumio volumio[1362]: info: ------------------------------ 6ms Feb 26 17:09:16 volumio sudo[3320]: pam_unix(sudo:session): session closed for user root Feb 26 17:09:16 volumio volumio[1362]: info: Feb 26 17:09:16 volumio volumio[1362]: ---------------------------- MPD announces system playlist update Feb 26 17:09:16 volumio volumio[1362]: info: Ignoring MPD Status Update Feb 26 17:09:16 volumio volumio[1362]: info: Feb 26 17:09:16 volumio volumio[1362]: ---------------------------- MPD announces state update: player Feb 26 17:09:16 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:09:17 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:09:17 volumio volumio[1362]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 26 17:09:17 volumio volumio[1362]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 26 17:09:17 volumio volumio[1362]: info: Feb 26 17:09:17 volumio volumio[1362]: ---------------------------- MPD announces system playlist update Feb 26 17:09:17 volumio volumio[1362]: info: Ignoring MPD Status Update Feb 26 17:09:17 volumio volumio[1362]: info: Feb 26 17:09:17 volumio volumio[1362]: ---------------------------- MPD announces state update: player Feb 26 17:09:17 volumio volumio[1362]: info: ControllerMpd::getState Feb 26 17:09:17 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand status Feb 26 17:09:17 volumio volumio[1362]: info: ------------------------------ 129ms Feb 26 17:09:17 volumio volumio[1362]: info: sendMpdCommand status took 75 milliseconds Feb 26 17:09:17 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:09:17 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 17:09:17 volumio volumio[1362]: info: peppyspectrum Daemon Stop Feb 26 17:09:17 volumio volumio[1362]: info: ------------------------------ 85ms Feb 26 17:09:17 volumio volumio[1362]: info: sendMpdCommand status took 84 milliseconds Feb 26 17:09:17 volumio volumio[1362]: info: sendMpdCommand playlistinfo took 82 milliseconds Feb 26 17:09:17 volumio volumio[1362]: verbose: ControllerMpd::parseState Feb 26 17:09:17 volumio volumio[1362]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 17:09:17 volumio volumio[1362]: verbose: ControllerMpd::parseTrackInfo Feb 26 17:09:17 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:09:17 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:09:17 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:17 volumio volumio[1362]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 26 17:09:17 volumio volumio[1362]: info: ------------------------------ 213ms Feb 26 17:09:17 volumio volumio[1362]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 17:09:17 volumio volumio[1362]: verbose: ControllerMpd::parseTrackInfo Feb 26 17:09:17 volumio volumio[1362]: info: ControllerMpd::pushState Feb 26 17:09:17 volumio volumio[1362]: info: CoreCommandRouter::servicePushState Feb 26 17:09:17 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:17 volumio volumio[1362]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 26 17:09:17 volumio volumio[1362]: info: ------------------------------ 166ms Feb 26 17:09:17 volumio volumio[1362]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 17:09:21 volumio volumio[1362]: info: camilladsp respawn in 3200 ms (attempt 6/10) Feb 26 17:09:28 volumio volumio[1362]: info: camilladsp respawn in 6400 ms (attempt 7/10) Feb 26 17:09:33 volumio volumio[1362]: info: CoreCommandRouter::volumioPlay Feb 26 17:09:33 volumio volumio[1362]: info: CoreStateMachine::play index undefined Feb 26 17:09:33 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:09:33 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:38 volumio volumio[1362]: info: CoreCommandRouter::volumioPlay Feb 26 17:09:38 volumio volumio[1362]: info: CoreStateMachine::play index undefined Feb 26 17:09:38 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:09:38 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:42 volumio volumio[1362]: info: camilladsp respawn in 10000 ms (attempt 8/10) Feb 26 17:09:43 volumio volumio[1362]: info: CoreCommandRouter::volumioPlay Feb 26 17:09:43 volumio volumio[1362]: info: CoreStateMachine::play index undefined Feb 26 17:09:43 volumio volumio[1362]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 17:09:43 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:44 volumio volumio[1362]: info: CoreCommandRouter::volumioSeek Feb 26 17:09:44 volumio volumio[1362]: info: CoreStateMachine::seek Feb 26 17:09:44 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:44 volumio volumio[1362]: info: TRACKBLOCK {"service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"nineties","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music113/v4/f1/91/ef/f191effe-4cf2-932b-f2cf-a724454d2d49/source/600x600bb.jpg","uri":"http://streams.90s90s.de/pop/mp3-192/volumio","name":"Oasis - Wonderwall","duration":"243","artist":"90s90s Hits","album":"","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 26 17:09:44 volumio volumio[1362]: info: CoreStateMachine::startPlaybackTimer Feb 26 17:09:44 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:44 volumio volumio[1362]: info: [1772125784921] [80s80s] seek to 144000 Feb 26 17:09:44 volumio volumio[1362]: info: CoreStateMachine::pushState Feb 26 17:09:44 volumio volumio[1362]: info: CorePlayQueue::getTrack 0 Feb 26 17:09:44 volumio volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 17:09:44 volumio volumio[1362]: info: CoreCommandRouter::volumioPushState Feb 26 17:09:45 volumio volumio[1362]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 17:09:45 volumio volumio[1362]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 26 17:09:45 volumio volumio[1362]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Feb 26 17:09:45 volumio volumio[1362]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Feb 26 17:09:45 volumio volumio[1362]: at /volumio/app/index.js:326:81 Feb 26 17:09:45 volumio volumio[1362]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 26 17:09:45 volumio volumio[1362]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 26 17:09:45 volumio volumio[1362]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Feb 26 17:09:45 volumio volumio[1362]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Feb 26 17:09:45 volumio volumio[1362]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1147:14) Feb 26 17:09:45 volumio volumio[1362]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1446:28) Feb 26 17:09:45 volumio volumio[1362]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:177:33) Feb 26 17:09:45 volumio volumio[1362]: at Socket.emit (node:events:514:28) Feb 26 17:09:45 volumio volumio[1362]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 26 17:09:45 volumio volumio[1362]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 26 17:09:45 volumio volumio[1362]: code: 'NumberExpected' Feb 26 17:09:45 volumio volumio[1362]: } Feb 26 17:09:45 volumio volumio[1362]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 17:09:47 volumio sudo[3516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 17:08' Feb 26 17:09:47 volumio sudo[3516]: 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"