-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2026-03-29 14:55:47 CEST. -- Mar 29 14:54:01 volumio-due volumio[1171]: info: MyVolumio token set successfully Mar 29 14:54:01 volumio-due volumio[1171]: info: MYVOLUMIO: Adding device Mar 29 14:54:01 volumio-due volumio[1171]: info: MYVOLUMIO: Evaluating Server Mar 29 14:54:01 volumio-due volumio[1171]: info: MyVolumio status changed Mar 29 14:54:01 volumio-due volumio[1171]: info: Streaming services startup Mar 29 14:54:01 volumio-due volumio[1171]: info: Starting Streaming Daemon Mar 29 14:54:01 volumio-due volumio[1171]: info: Removing browser output: myVolumio user plan is not superstar Mar 29 14:54:01 volumio-due volumio[1171]: info: Removing audio output: Mar 29 14:54:01 volumio-due volumio[1171]: info: Stoppping Tunnel 1 Mar 29 14:54:01 volumio-due sudo[5589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 14:54:01 volumio-due sudo[5589]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:01 volumio-due sudo[5592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 29 14:54:01 volumio-due sudo[5592]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:01 volumio-due sudo[5589]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:01 volumio-due volumio[1171]: error: Cannot start Volumio Streaming Daemon Mar 29 14:54:01 volumio-due volumio[1171]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 14:54:01 volumio-due volumio[1171]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 14:54:01 volumio-due sudo[5592]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:01 volumio-due volumio[1171]: info: Remote SSH Stopped Mar 29 14:54:01 volumio-due volumio[1171]: info: Setting Geolocation for MyVolumio to eu6 Mar 29 14:54:01 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:01 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:01 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:02 volumio-due volumio[1171]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 14:54:02 volumio-due volumio[1171]: info: Updating MyVolumio device info Mar 29 14:54:02 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:02 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:02 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:03 volumio-due volumio[1171]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 14:54:06 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:06 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:06 volumio-due volumio[1171]: info: MYVOLUMIO: Adding device Mar 29 14:54:06 volumio-due volumio[1171]: info: MYVOLUMIO: Evaluating Server Mar 29 14:54:07 volumio-due volumio[1171]: info: Setting Geolocation for MyVolumio to eu4 Mar 29 14:54:07 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:07 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:07 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:07 volumio-due volumio[1171]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 14:54:08 volumio-due volumio[1171]: info: Updating MyVolumio device info Mar 29 14:54:08 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:08 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:08 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:08 volumio-due volumio[1171]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 14:54:12 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 14:54:12 volumio-due volumio[1171]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 29 14:54:12 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:12 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:54:17 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 14:54:18 volumio-due volumio[1171]: info: FusionDsp - Rock-FusionDsp Mar 29 14:54:18 volumio-due volumio[1171]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:54:18 volumio-due volumio[1171]: info: FusionDsp - Rock-FusionDsp Mar 29 14:54:18 volumio-due volumio[1171]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 29 14:54:18 volumio-due volumio[1171]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 29 14:54:18 volumio-due volumio[1171]: info: Completed starting MyVolumio Plugin Mar 29 14:54:18 volumio-due volumio[1171]: [Metrics] CommandRouter: 79s 807.70ms Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreCommandRouter::volumiosetStartupVolume Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 14:54:18 volumio-due volumio[1171]: info: VolumeController:: Setting startup Volume 10 Mar 29 14:54:18 volumio-due volumio[1171]: info: VolumeController::SetAlsaVolume10 Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreCommandRouter::Close All Modals sent Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreStateMachine::pushState Mar 29 14:54:18 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:54:18 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushState Mar 29 14:54:18 volumio-due volumio[1171]: info: [LastFM] Current track has sufficient metadata: title (LL COOL J - I Need Love) and artist (80s80s Love) passed on explicitly Mar 29 14:54:18 volumio-due volumio[1171]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Mar 29 14:54:18 volumio-due volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 29 14:54:18 volumio-due volumio[1171]: SPOTIFY: SPOTIFY VOLUME 100 Mar 29 14:54:18 volumio-due volumio[1171]: SPOTIFY: VOLUMIO VOLUME 10 Mar 29 14:54:18 volumio-due volumio[1171]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 29 14:54:18 volumio-due volumio[1171]: info: Setting Spotify Volume from Volumio: 10 Mar 29 14:54:18 volumio-due volumio[1171]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 29 14:54:18 volumio-due volumio[1171]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 29 14:54:18 volumio-due volumio[1171]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 29 14:54:18 volumio-due volumio[1171]: error: FusionDsp - WebSocket error: [object Object] Mar 29 14:54:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetQueue Mar 29 14:54:19 volumio-due volumio[1171]: info: CoreStateMachine::getQueue Mar 29 14:54:19 volumio-due volumio[1171]: info: CorePlayQueue::getQueue Mar 29 14:54:19 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 29 14:54:19 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 14:54:19 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 29 14:54:20 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:20 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:20 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetQueue Mar 29 14:54:20 volumio-due volumio[1171]: info: CoreStateMachine::getQueue Mar 29 14:54:20 volumio-due volumio[1171]: info: CorePlayQueue::getQueue Mar 29 14:54:20 volumio-due volumio[1171]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Mar 29 14:54:20 volumio-due volumio[1171]: info: Sending Spotify command with payload to local API: /player/volume Mar 29 14:54:21 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:21 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:21 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetQueue Mar 29 14:54:21 volumio-due volumio[1171]: info: CoreStateMachine::getQueue Mar 29 14:54:21 volumio-due volumio[1171]: info: CorePlayQueue::getQueue Mar 29 14:54:22 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:22 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:22 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetQueue Mar 29 14:54:22 volumio-due volumio[1171]: info: CoreStateMachine::getQueue Mar 29 14:54:22 volumio-due volumio[1171]: info: CorePlayQueue::getQueue Mar 29 14:54:23 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:23 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:23 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetQueue Mar 29 14:54:23 volumio-due volumio[1171]: info: CoreStateMachine::getQueue Mar 29 14:54:23 volumio-due volumio[1171]: info: CorePlayQueue::getQueue Mar 29 14:54:24 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:24 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:24 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetQueue Mar 29 14:54:24 volumio-due volumio[1171]: info: CoreStateMachine::getQueue Mar 29 14:54:24 volumio-due volumio[1171]: info: CorePlayQueue::getQueue Mar 29 14:54:25 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:25 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:25 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetQueue Mar 29 14:54:25 volumio-due volumio[1171]: info: CoreStateMachine::getQueue Mar 29 14:54:25 volumio-due volumio[1171]: info: CorePlayQueue::getQueue Mar 29 14:54:25 volumio-due volumio[1171]: info: BOOT COMPLETED Mar 29 14:54:25 volumio-due volumio[1171]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Mar 29 14:54:25 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:25.874+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:26 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetQueue Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreStateMachine::getQueue Mar 29 14:54:26 volumio-due volumio[1171]: info: CorePlayQueue::getQueue Mar 29 14:54:26 volumio-due volumio[1171]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Mar 29 14:54:26 volumio-due volumio[1171]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:54:26 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPause Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreStateMachine::pause Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreStateMachine::servicePause Mar 29 14:54:26 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::servicePause Mar 29 14:54:26 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand pause Mar 29 14:54:26 volumio-due volumio[1171]: info: mpdhttpout --- Volumio set on pause Mar 29 14:54:26 volumio-due volumio[1171]: info: Mar 29 14:54:26 volumio-due volumio[1171]: ---------------------------- MPD announces state update: player Mar 29 14:54:26 volumio-due volumio[1171]: info: sendMpdCommand pause took 3 milliseconds Mar 29 14:54:26 volumio-due volumio[1171]: info: ControllerMpd::getState Mar 29 14:54:26 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:54:26 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::servicePushState Mar 29 14:54:26 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:26 volumio-due volumio[1171]: verbose: STATE SERVICE {"status":"pause","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music114/v4/76/c5/e4/76c5e459-f7db-ea07-5452-f11b1b77d13f/source/600x600bb.jpg","name":"LL COOL J - I Need Love","title":"I Need Love","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"245","seek":48674,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 14:54:26 volumio-due volumio[1171]: verbose: CURRENT POSITION 0 Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreStateMachine::syncState stateService pause Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreStateMachine::syncState currentStatus pause Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreStateMachine::pushState Mar 29 14:54:26 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushState Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:54:26 volumio-due volumio[1171]: info: sendMpdCommand status took 52 milliseconds Mar 29 14:54:26 volumio-due volumio[1171]: verbose: ControllerMpd::parseState Mar 29 14:54:26 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:54:26 volumio-due volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 29 14:54:26 volumio-due volumio[1171]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 29 14:54:26 volumio-due volumio[1171]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:54:26 volumio-due volumio[1171]: info: ControllerMpd::pushState Mar 29 14:54:26 volumio-due volumio[1171]: info: CoreCommandRouter::servicePushState Mar 29 14:54:26 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:54:26 volumio-due volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 29 14:54:26 volumio-due volumio[1171]: info: ------------------------------ 72ms Mar 29 14:54:26 volumio-due volumio[1171]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 29 14:54:28 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 29 14:54:28 volumio-due sudo[9572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 14:54:28 volumio-due sudo[9572]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:28 volumio-due systemd[1]: musicservicesshield.service: Succeeded. Mar 29 14:54:28 volumio-due systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 29 14:54:28 volumio-due systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 29 14:54:28 volumio-due systemd[1]: Stopping Music Player Daemon... Mar 29 14:54:28 volumio-due volumio[1171]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Mar 29 14:54:28 volumio-due volumio[1171]: info: FusionDsp - Stopping FusionDsp service Mar 29 14:54:28 volumio-due volumio[1171]: info: camilladsp stopping service pid 9574... Mar 29 14:54:28 volumio-due systemd[1]: mpd.service: Succeeded. Mar 29 14:54:28 volumio-due systemd[1]: Stopped Music Player Daemon. Mar 29 14:54:28 volumio-due volumio[1171]: info: camilladsp service terminated, instance 1 Mar 29 14:54:28 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:28.339+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:28 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:28.349+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:28 volumio-due systemd[1]: Starting Music Player Daemon... Mar 29 14:54:28 volumio-due sudo[9579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Mar 29 14:54:28 volumio-due sudo[9579]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:28 volumio-due systemd[1]: Stopping FusionDsp Daemon... Mar 29 14:54:28 volumio-due volumio[1593]: Backend configuration: Mar 29 14:54:28 volumio-due volumio[1593]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Mar 29 14:54:28 volumio-due volumio[1593]: active_config_txt: null Mar 29 14:54:28 volumio-due volumio[1593]: camilla_host: 127.0.0.1 Mar 29 14:54:28 volumio-due volumio[1593]: camilla_port: 9876 Mar 29 14:54:28 volumio-due volumio[1593]: coeff_dir: /data/INTERNAL/FusionDsp/filters Mar 29 14:54:28 volumio-due volumio[1593]: config_dir: /data/configuration/audio_interface/fusiondsp Mar 29 14:54:28 volumio-due volumio[1593]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Mar 29 14:54:28 volumio-due volumio[1593]: log_file: /tmp/camilladsp.log Mar 29 14:54:28 volumio-due volumio[1593]: on_get_active_config: null Mar 29 14:54:28 volumio-due volumio[1593]: on_set_active_config: null Mar 29 14:54:28 volumio-due volumio[1593]: port: 5011 Mar 29 14:54:28 volumio-due volumio[1593]: supported_capture_types: null Mar 29 14:54:28 volumio-due volumio[1593]: supported_playback_types: null Mar 29 14:54:28 volumio-due volumio[1593]: update_config_symlink: false Mar 29 14:54:28 volumio-due volumio[1593]: update_config_txt: false Mar 29 14:54:28 volumio-due volumio[1593]: ======== Running on http://0.0.0.0:5011 ======== Mar 29 14:54:28 volumio-due volumio[1593]: (Press CTRL+C to quit) Mar 29 14:54:28 volumio-due sudo[9575]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 14:54:28 volumio-due sudo[9575]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:28 volumio-due sudo[9575]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:28 volumio-due systemd[1]: fusiondsp.service: Succeeded. Mar 29 14:54:28 volumio-due systemd[1]: Stopped FusionDsp Daemon. Mar 29 14:54:28 volumio-due sudo[9579]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:28 volumio-due volumio[1171]: info: FusionDsp - Reporting Fusion DSP Disabled Mar 29 14:54:28 volumio-due volumio[1171]: info: Removing fusiondspeq DSP Signal Path Element Mar 29 14:54:28 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:28.775+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:28 volumio-due volumio[1171]: info: Check plugin dependencies Mar 29 14:54:28 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 14:54:28 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:28.847+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:28 volumio-due volumio[1171]: info: Rename folder Mar 29 14:54:28 volumio-due volumio[1171]: info: Folder /tmp/downloaded_plugin.zip removed Mar 29 14:54:28 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:28.917+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:28 volumio-due volumio[1171]: info: Move to category Mar 29 14:54:29 volumio-due mpd[9585]: Mar 29 14:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 14:54:29 volumio-due systemd[1]: Started Music Player Daemon. Mar 29 14:54:29 volumio-due sudo[9572]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:29 volumio-due systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 29 14:54:29 volumio-due volumio[9596]: cset: --> shielding system active with Mar 29 14:54:29 volumio-due volumio[9596]: cset: "system" cpuset of CPUSPEC(1-3) with 276 tasks running Mar 29 14:54:29 volumio-due volumio[9596]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 29 14:54:29 volumio-due systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 29 14:54:30 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:30.162+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:30 volumio-due volumio[1171]: info: Checking if install.sh is present Mar 29 14:54:30 volumio-due volumio[1171]: info: Executing install.sh Mar 29 14:54:30 volumio-due volumio[1171]: error: updateQueue error: null Mar 29 14:54:30 volumio-due sudo[9613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Mar 29 14:54:30 volumio-due sudo[9613]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:30 volumio-due volumio[1171]: info: creating filters folder and copying demo filters Mar 29 14:54:30 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:30.263+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:30 volumio-due volumio[1171]: info: copying demo flters Mar 29 14:54:30 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:30.308+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/ Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.194+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/2XEQ15/ Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.208+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.208+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due sudo[9638]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Mar 29 14:54:31 volumio-due sudo[9638]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.238+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.259+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/PEQ/ Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/convfir/ Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/convfir/kef.json Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/convfir/test.json Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/EQ15/ Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/EQ15/.Bass-FusionDsp.json Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/EQ15/.Classic-FusionDsp.json Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/EQ15/.Voice-FusionDsp.json Mar 29 14:54:31 volumio-due volumio[1171]: info: ./presets/EQ15/.Rock-FusionDsp.json Mar 29 14:54:31 volumio-due volumio[1171]: info: Installing/fusiondsp dependencies Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.314+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.315+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.316+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.327+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.328+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.329+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.330+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.330+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.331+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.332+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.333+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.333+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumiologrotate[567]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 29 14:54:31 volumio-due volumiologrotate[567]: ls: cannot access 'DUE': No such file or directory Mar 29 14:54:31 volumio-due volumio[1171]: info: Hit:1 https://archive2.volumio.org/debian buster InRelease Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.793+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:31 volumio-due volumio[1171]: info: Hit:2 https://apt2.volumio.org/raspbian buster InRelease Mar 29 14:54:31 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:31.812+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:35 volumio-due volumio[1171]: info: Reading package lists... Mar 29 14:54:35 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:35.256+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:35 volumio-due volumio[1171]: info: Building dependency tree... Mar 29 14:54:35 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:35.812+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:35 volumio-due volumio[1171]: info: Reading state information... Mar 29 14:54:35 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:35.832+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:35 volumio-due volumio[1171]: info: 64 packages can be upgraded. Run 'apt list --upgradable' to see them. Mar 29 14:54:35 volumio-due sudo[9638]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:35 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:35.880+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:35 volumio-due sudo[9828]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Mar 29 14:54:35 volumio-due sudo[9828]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:35 volumio-due volumio[1171]: info: Reading package lists... Mar 29 14:54:35 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:35.970+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:36 volumio-due volumio[1171]: info: Building dependency tree... Mar 29 14:54:36 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:36.438+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:36 volumio-due volumio[1171]: info: Reading state information... Mar 29 14:54:36 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:36.458+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:38 volumio-due volumio[1171]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Mar 29 14:54:38 volumio-due sudo[9828]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:38 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:38.523+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:38 volumio-due volumio[1171]: info: python3-pip is already the newest version (18.1-5+rpt1). Mar 29 14:54:38 volumio-due volumio[1171]: info: 0 upgraded, 0 newly installed, 0 to remove and 65 not upgraded. Mar 29 14:54:38 volumio-due sudo[9832]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Mar 29 14:54:38 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:38.537+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:38 volumio-due sudo[9832]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:38 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:38.538+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:38 volumio-due sudo[9832]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:38 volumio-due volumio[1171]: info: lib/systemd/system/fusiondsp.service Mar 29 14:54:38 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:38.565+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio[1171]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Mar 29 14:54:40 volumio-due volumio[1171]: info: more info at http://www.winimage.com/zLibDll/unzip.html Mar 29 14:54:40 volumio-due volumio[1171]: info: Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.269+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due sudo[9836]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Mar 29 14:54:40 volumio-due volumio[1171]: info: cgui-1.0.0.zip opened Mar 29 14:54:40 volumio-due sudo[9836]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/ Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/LICENSE.txt Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/README.md Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/build/ Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/robots.txt Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/logo512.png Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/asset-manifest.json Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/index.html Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/.put_statics_here Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/favicon.ico Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/logo192.png Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/manifest.json Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/css-variables.css Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/service-worker.js Mar 29 14:54:40 volumio-due sudo[9836]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/build/static/ Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/build/static/css/ Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/main.1503f6cd.css Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/build/static/media/ Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/build/static/js/ Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/main.71e11955.js Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/main.71e11955.js.map Mar 29 14:54:40 volumio-due sudo[9838]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Mar 29 14:54:40 volumio-due sudo[9838]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/config/ Mar 29 14:54:40 volumio-due sudo[9838]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/config/camillagui.yml Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/config/gui-config.yml Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/backend/ Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/filters_test.py Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/version.py Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/filters.py Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/settings.py Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/views.py Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/filterdefaults.py Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__init__.py Mar 29 14:54:40 volumio-due volumio[1171]: info: creating directory: cgui/backend/__pycache__/ Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/routes.py Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/backend/filemanagement.py Mar 29 14:54:40 volumio-due volumio[1171]: info: extracting: cgui/main.py Mar 29 14:54:40 volumio-due sudo[9840]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Mar 29 14:54:40 volumio-due sudo[9840]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:40 volumio-due sudo[9840]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.474+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.475+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.476+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.476+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.477+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.478+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.478+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.479+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.480+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.481+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.481+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.483+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.486+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.486+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.487+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.488+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.489+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.489+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.490+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.491+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.492+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.493+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.494+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.494+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.495+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.496+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.497+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.497+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.498+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.499+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.500+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.501+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.502+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.502+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.503+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.504+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.505+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.506+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.507+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.508+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.508+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.509+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.510+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.511+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.511+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.512+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.513+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.514+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.514+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.515+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.516+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.517+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.517+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.518+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.519+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.520+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.521+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.522+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.523+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.524+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.524+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.525+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.525+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.526+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.526+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.527+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.528+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.529+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.530+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.530+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.531+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:40 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:40.532+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:45 volumio-due volumio[1171]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Mar 29 14:54:45 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:45.282+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:45 volumio-due volumio[1171]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Mar 29 14:54:45 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:45.301+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:45 volumio-due volumio[1171]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-tyjoyb9b Mar 29 14:54:45 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:45.311+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:46 volumio-due volumio[1171]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages Mar 29 14:54:46 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:46.996+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:47 volumio-due volumio[1171]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Mar 29 14:54:47 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:47.021+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:47 volumio-due volumio[1171]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Mar 29 14:54:47 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:47.032+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:47 volumio-due volumio[1171]: info: Building wheels for collected packages: camilladsp Mar 29 14:54:47 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:47.060+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:47 volumio-due volumio[1171]: info: Running setup.py bdist_wheel for camilladsp: started Mar 29 14:54:47 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:47.073+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:47 volumio-due volumio[1171]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Mar 29 14:54:47 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:47.899+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:47 volumio-due volumio[1171]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-zq55_qsh/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Mar 29 14:54:47 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:47.919+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:47 volumio-due volumio[1171]: info: Successfully built camilladsp Mar 29 14:54:47 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:47.941+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:51 volumio-due volumio[1171]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Mar 29 14:54:51 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:51.926+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:51 volumio-due volumio[1171]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Mar 29 14:54:51 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:51.949+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:51 volumio-due volumio[1171]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-v1tzd5va Mar 29 14:54:51 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:51.959+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.542+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.565+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.577+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.659+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.688+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.784+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.808+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.868+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.896+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.912+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Building wheels for collected packages: camilladsp-plot Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.964+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:53 volumio-due volumio[1171]: info: Running setup.py bdist_wheel for camilladsp-plot: started Mar 29 14:54:53 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:53.989+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:54 volumio-due volumio[1171]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Mar 29 14:54:54 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:54.840+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:54 volumio-due volumio[1171]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-1yww_0kt/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Mar 29 14:54:54 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:54.861+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:54 volumio-due volumio[1171]: info: Successfully built camilladsp-plot Mar 29 14:54:54 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:54.877+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:55 volumio-due volumio[1171]: info: copying hw detection script Mar 29 14:54:55 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:55.256+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:55 volumio-due volumio[1171]: info: Detected cpu architecture as armv7l Mar 29 14:54:55 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:55.278+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:56 volumio-due sudo[9930]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Mar 29 14:54:56 volumio-due sudo[9930]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:56 volumio-due sudo[9930]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:56 volumio-due sudo[9932]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Mar 29 14:54:56 volumio-due sudo[9932]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:56 volumio-due sudo[9932]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:56 volumio-due sudo[9934]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Mar 29 14:54:56 volumio-due sudo[9934]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 14:54:56 volumio-due volumio[1171]: info: Reading package lists... Mar 29 14:54:56 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:56.365+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:56 volumio-due volumio[1171]: info: Building dependency tree... Mar 29 14:54:56 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:56.832+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:56 volumio-due volumio[1171]: info: Reading state information... Mar 29 14:54:56 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:56.860+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:57 volumio-due volumio[1171]: info: drc is already the newest version (3.2.2~dfsg0-2). Mar 29 14:54:57 volumio-due sudo[9934]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:57 volumio-due sudo[9613]: pam_unix(sudo:session): session closed for user root Mar 29 14:54:57 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:57.698+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:57 volumio-due volumio[1171]: info: 0 upgraded, 0 newly installed, 0 to remove and 65 not upgraded. Mar 29 14:54:57 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:57.709+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:57 volumio-due volumio[1171]: info: Install script completed Mar 29 14:54:57 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:57.731+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:57 volumio-due volumio5-onboarding[1352]: time=2026-03-29T14:54:57.743+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 14:54:57 volumio-due volumio[1171]: info: Done installing plugin. Mar 29 14:54:57 volumio-due volumio[1171]: info: Enabling plugin fusiondsp Mar 29 14:54:57 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 14:54:57 volumio-due volumio[1171]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Mpd http & Icecast output","name":"mpdoutput","category":"audio_interface","version":"1.0.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"1.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Bandcamp Discover","name":"bandcamp","category":"music_service","version":"1.1.1","icon":"fa-bandcamp","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"DMD2 Music","name":"dmd2_music","category":"music_service","version":"1.1.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin","name":"jellyfin","category":"music_service","version":"1.0.9","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Mixcloud","name":"mixcloud","category":"music_service","version":"1.0.1","icon":"fa-mixcloud","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.2.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"pandora","name":"pandora","category":"music_service","version":"2.12.1","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Podcast","name":"podcast","category":"music_service","version":"2.2.0","icon":"fa-podcast","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RADIO 357","name":"rad357pl","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.0.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SWARM Station","name":"swarmstation","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.0.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_controller","version":"1.0.4","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics Evo Sabre","name":"audiophonics_evo_sabre","category":"system_hardware","version":"1.0.0","icon":"fa-hdd-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_hardware","version":"0.8.3","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Control","name":"gpio_control","category":"system_hardware","version":"1.0.1","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"IR Remote Controller","name":"ir_controller","category":"system_hardware","version":"3.4.11","icon":"fa-magic","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RemotePi","name":"remotepi","category":"system_hardware","version":"3.2.0","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.5","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"LastFM Scrobbler","name":"lastfm","category":"user_interface","version":"1.5.6","icon":"fa-lastfm","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.5.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Randomizer","name":"randomizer","category":"user_interface","version":"3.1.2","icon":"fa-random","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.4.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Mar 29 14:54:57 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 14:54:57 volumio-due volumio[1171]: info: Folder /tmp/plugins removed Mar 29 14:54:57 volumio-due volumio[1171]: info: Folder /tmp/downloaded_plugin.zip removed Mar 29 14:54:57 volumio-due volumio[1171]: Plugin install end detected on script Mar 29 14:54:57 volumio-due volumio[1171]: info: Folder /data/temp removed Mar 29 14:54:57 volumio-due volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:54:57 volumio-due volumio[1171]: info: FusionDsp - Rock-FusionDsp Mar 29 14:54:57 volumio-due volumio[1171]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 29 14:54:57 volumio-due volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:54:57 volumio-due volumio[1171]: info: FusionDsp - Rock-FusionDsp Mar 29 14:54:58 volumio-due volumio[1171]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 29 14:55:15 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:55:15 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: Preload queue cleared Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::ClearQueue Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::stop Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::updateTrackBlock Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrackBlock Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::stPlaybackTimer Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::pushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::serviceStop Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::serviceStop Mar 29 14:55:19 volumio-due volumio[1171]: info: ControllerMpd::stop Mar 29 14:55:19 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::clearPlayQueue Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::saveQueue Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::addQueueItems Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::addQueueItems Mar 29 14:55:19 volumio-due volumio[1171]: info: Preload queue cleared Mar 29 14:55:19 volumio-due volumio[1171]: info: Adding Item to queue: webeighties/2 Mar 29 14:55:19 volumio-due volumio[1171]: info: Exploding uri webeighties/2 in service 80s80s Mar 29 14:55:19 volumio-due volumio[1171]: info: [1774788919608] [80s80s] explodeUri: webeighties/2 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushQueue Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::saveQueue Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::updateTrackBlock Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrackBlock Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPlay Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::play index 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::stop Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::play index undefined Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::startPlaybackTimer Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: [1774788919652] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Mar 29 14:55:19 volumio-due volumio[1171]: info: [1774788919652] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Mar 29 14:55:19 volumio-due volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 29 14:55:19 volumio-due volumio[1171]: info: sendMpdCommand stop took 74 milliseconds Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::servicePushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::pushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music114/v4/76/c5/e4/76c5e459-f7db-ea07-5452-f11b1b77d13f/source/600x600bb.jpg","name":"LL COOL J - I Need Love","title":"I Need Love","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"245","seek":48674,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 14:55:19 volumio-due volumio[1171]: verbose: CURRENT POSITION 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::syncState stateService stop Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::pushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushState Mar 29 14:55:19 volumio-due volumio[1171]: info: No code Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::pushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushState Mar 29 14:55:19 volumio-due volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 29 14:55:19 volumio-due volumio[1171]: info: [1774788919912] [80s80s] received new event containing 2 songs. Mar 29 14:55:19 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 14:55:19 volumio-due volumio[1171]: info: sendMpdCommand stop took 1 milliseconds Mar 29 14:55:19 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 14:55:19 volumio-due volumio[1171]: info: Mar 29 14:55:19 volumio-due volumio[1171]: ---------------------------- MPD announces system playlist update Mar 29 14:55:19 volumio-due volumio[1171]: info: Ignoring MPD Status Update Mar 29 14:55:19 volumio-due volumio[1171]: info: sendMpdCommand clear took 1 milliseconds Mar 29 14:55:19 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 29 14:55:19 volumio-due volumio[1171]: error: updateQueue error: null Mar 29 14:55:19 volumio-due volumio[1171]: info: Mar 29 14:55:19 volumio-due volumio[1171]: ---------------------------- MPD announces state update: options Mar 29 14:55:19 volumio-due volumio[1171]: info: ------------------------------ 2ms Mar 29 14:55:19 volumio-due volumio[1171]: info: sendMpdCommand consume 1 took 2 milliseconds Mar 29 14:55:19 volumio-due volumio[1171]: info: ControllerMpd::getState Mar 29 14:55:19 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:55:19 volumio-due volumio[1171]: info: [1774788919919] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Mar 29 14:55:19 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Mar 29 14:55:19 volumio-due volumio[1171]: info: sendMpdCommand status took 8 milliseconds Mar 29 14:55:19 volumio-due volumio[1171]: verbose: ControllerMpd::parseState Mar 29 14:55:19 volumio-due volumio[1171]: info: ControllerMpd::pushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::servicePushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::pushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::volumioPushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 29 14:55:19 volumio-due volumio[1171]: info: ------------------------------ 26ms Mar 29 14:55:19 volumio-due volumio[1171]: info: Mar 29 14:55:19 volumio-due volumio[1171]: ---------------------------- MPD announces system playlist update Mar 29 14:55:19 volumio-due volumio[1171]: info: Ignoring MPD Status Update Mar 29 14:55:19 volumio-due volumio[1171]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 26 milliseconds Mar 29 14:55:19 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Mar 29 14:55:19 volumio-due volumio[1171]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Mar 29 14:55:19 volumio-due volumio[1171]: info: ------------------------------ 26ms Mar 29 14:55:19 volumio-due volumio[1171]: info: sendMpdCommand play took 15 milliseconds Mar 29 14:55:19 volumio-due volumio[1171]: info: [1774788919972] [80s80s] Pushing the next song state: Whitney Houston - I Wanna Dance with Somebody (Who Loves Me) Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreCommandRouter::servicePushState Mar 29 14:55:19 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:19 volumio-due volumio[1171]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/70/2c/95/702c950c-ff2c-1426-ad40-eee87027ae09/source/600x600bb.jpg","name":"Whitney Houston - I Wanna Dance with Somebody (Who Loves Me)","title":"I Wanna Dance with Somebody (Who Loves Me)","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"276","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 29 14:55:19 volumio-due volumio[1171]: verbose: CURRENT POSITION 0 Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::syncState stateService play Mar 29 14:55:19 volumio-due volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Mar 29 14:55:19 volumio-due volumio[1171]: info: [1774788919975] [80s80s] PlayNextTrack API delay: 30 Mar 29 14:55:19 volumio-due volumio[1171]: info: [1774788919987] [80s80s] Setting timer to: 276000 milliseconds. Mar 29 14:55:20 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:20 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 1 Mar 29 14:55:22 volumio-due volumio[1171]: info: Mar 29 14:55:22 volumio-due volumio[1171]: ---------------------------- MPD announces state update: player Mar 29 14:55:22 volumio-due volumio[1171]: info: ControllerMpd::getState Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:55:22 volumio-due volumio[1171]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 29 14:55:22 volumio-due volumio[1171]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 29 14:55:22 volumio-due volumio[1171]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 29 14:55:22 volumio-due volumio[1171]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 29 14:55:22 volumio-due volumio[1171]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 29 14:55:22 volumio-due volumio[1171]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 29 14:55:22 volumio-due volumio[1171]: info: Mar 29 14:55:22 volumio-due volumio[1171]: ---------------------------- MPD announces state update: player Mar 29 14:55:22 volumio-due volumio[1171]: info: sendMpdCommand status took 15 milliseconds Mar 29 14:55:22 volumio-due volumio[1171]: info: ControllerMpd::getState Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::parseState Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:55:22 volumio-due volumio[1171]: info: sendMpdCommand status took 1 milliseconds Mar 29 14:55:22 volumio-due volumio[1171]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::parseState Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:55:22 volumio-due volumio[1171]: info: ControllerMpd::pushState Mar 29 14:55:22 volumio-due volumio[1171]: info: CoreCommandRouter::servicePushState Mar 29 14:55:22 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:22 volumio-due volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 29 14:55:22 volumio-due volumio[1171]: info: ------------------------------ 20ms Mar 29 14:55:22 volumio-due volumio[1171]: error: FusionDsp - WebSocket error: [object Object] Mar 29 14:55:22 volumio-due volumio[1171]: error: FusionDsp - WebSocket error: [object Object] Mar 29 14:55:22 volumio-due volumio[1171]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:55:22 volumio-due volumio[1171]: info: ControllerMpd::pushState Mar 29 14:55:22 volumio-due volumio[1171]: info: CoreCommandRouter::servicePushState Mar 29 14:55:22 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:22 volumio-due volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 29 14:55:22 volumio-due volumio[1171]: info: ------------------------------ 8ms Mar 29 14:55:22 volumio-due volumio[1171]: info: Mar 29 14:55:22 volumio-due volumio[1171]: ---------------------------- MPD announces system playlist update Mar 29 14:55:22 volumio-due volumio[1171]: info: Ignoring MPD Status Update Mar 29 14:55:22 volumio-due volumio[1171]: info: Mar 29 14:55:22 volumio-due volumio[1171]: ---------------------------- MPD announces state update: player Mar 29 14:55:22 volumio-due volumio[1171]: info: ControllerMpd::getState Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Mar 29 14:55:22 volumio-due volumio[1171]: info: ------------------------------ 2ms Mar 29 14:55:22 volumio-due volumio[1171]: info: sendMpdCommand status took 1 milliseconds Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::parseState Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 14:55:22 volumio-due volumio[1171]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 14:55:22 volumio-due volumio[1171]: verbose: ControllerMpd::parseTrackInfo Mar 29 14:55:22 volumio-due volumio[1171]: info: ControllerMpd::pushState Mar 29 14:55:22 volumio-due volumio[1171]: info: CoreCommandRouter::servicePushState Mar 29 14:55:22 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:22 volumio-due volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 29 14:55:22 volumio-due volumio[1171]: info: ------------------------------ 5ms Mar 29 14:55:27 volumio-due volumio[1171]: info: CoreCommandRouter::volumioGetState Mar 29 14:55:27 volumio-due volumio[1171]: info: CorePlayQueue::getTrack 0 Mar 29 14:55:31 volumio-due volumiologrotate[567]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 29 14:55:31 volumio-due volumiologrotate[567]: ls: cannot access 'DUE': No such file or directory Mar 29 14:55:41 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 14:55:41 volumio-due volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 14:55:41 volumio-due volumio[1171]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 14:55:41 volumio-due volumio[1171]: info: FusionDsp - Rock-FusionDsp Mar 29 14:55:41 volumio-due volumio[1171]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 29 14:55:46 volumio-due volumio[1171]: info: Starting Uninstall of plugin audio_interface - fusiondsp Mar 29 14:55:46 volumio-due volumio[1171]: info: Uninstalling plugin fusiondsp Mar 29 14:55:46 volumio-due volumio[1171]: info: FusionDsp - Stopping FusionDsp service Mar 29 14:55:46 volumio-due volumio[1171]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 14:55:46 volumio-due volumio[1171]: TypeError: Cannot read property 'stop' of null Mar 29 14:55:46 volumio-due volumio[1171]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Mar 29 14:55:46 volumio-due volumio[1171]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Mar 29 14:55:46 volumio-due volumio[1171]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Mar 29 14:55:46 volumio-due volumio[1171]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Mar 29 14:55:46 volumio-due volumio[1171]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1271:45) Mar 29 14:55:46 volumio-due volumio[1171]: at Socket.emit (events.js:315:20) Mar 29 14:55:46 volumio-due volumio[1171]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 29 14:55:46 volumio-due volumio[1171]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 29 14:55:46 volumio-due volumio[1171]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 14:55:47 volumio-due sudo[10071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-29 14:54 Mar 29 14:55:47 volumio-due sudo[10071]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"