-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Thu 2026-04-02 16:42:18 UTC. -- Apr 02 16:41:01 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:01 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:01 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:01 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:01 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:01 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:01 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:01 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:01 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:41:02 primo volumio[3448]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Apr 02 16:41:02 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:02.280Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:02 primo volumio[3448]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-b592cl_l Apr 02 16:41:02 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:02.307Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:02 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:02.448Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:41:05 primo volumio[3448]: 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 Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.075Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.100Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.128Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.317Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: 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) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.377Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: 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) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.398Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: 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) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.423Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: 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) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.618Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: 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) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.773Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: 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) Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.797Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: info: Building wheels for collected packages: camilladsp-plot Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.942Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:05 primo volumio[3448]: info: Running setup.py bdist_wheel for camilladsp-plot: started Apr 02 16:41:05 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:05.964Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:07 primo volumio[3448]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Apr 02 16:41:07 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:07.831Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:07 primo volumio[3448]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-b11w6lp1/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Apr 02 16:41:07 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:07.859Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:07 primo volumio[3448]: info: Successfully built camilladsp-plot Apr 02 16:41:07 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:07.911Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:08 primo volumio[3448]: info: copying hw detection script Apr 02 16:41:08 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:08.570Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:08 primo volumio[3448]: info: Detected cpu architecture as aarch64 Apr 02 16:41:08 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:08.603Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:08 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:08 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:08 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:08 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:08 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:08 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:08 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:08 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:08 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:41:09 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:09.656Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:41:10 primo sudo[6118]: 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 Apr 02 16:41:10 primo sudo[6118]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:41:10 primo sudo[6118]: pam_unix(sudo:session): session closed for user root Apr 02 16:41:10 primo sudo[6120]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Apr 02 16:41:10 primo sudo[6120]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:41:10 primo sudo[6120]: pam_unix(sudo:session): session closed for user root Apr 02 16:41:10 primo sudo[6122]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Apr 02 16:41:10 primo sudo[6122]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:41:10 primo volumio[3448]: info: Reading package lists... Apr 02 16:41:10 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:10.558Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:11 primo volumio[3448]: info: Building dependency tree... Apr 02 16:41:11 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:11.347Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:11 primo volumio[3448]: info: Reading state information... Apr 02 16:41:11 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:11.371Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:12 primo volumio[3448]: info: drc is already the newest version (3.2.2~dfsg0-2). Apr 02 16:41:12 primo sudo[6122]: pam_unix(sudo:session): session closed for user root Apr 02 16:41:12 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:12.964Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:12 primo sudo[5788]: pam_unix(sudo:session): session closed for user root Apr 02 16:41:12 primo volumio[3448]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Apr 02 16:41:12 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:12.982Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:13 primo volumio[3448]: info: Install script completed Apr 02 16:41:13 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:13.009Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:13 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:13.020Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 02 16:41:13 primo volumio[3448]: info: Done installing plugin. Apr 02 16:41:13 primo volumio[3448]: info: Enabling plugin fusiondsp Apr 02 16:41:13 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 02 16:41:13 primo volumio[3448]: 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":"Spotify","name":"spop","category":"music_service","version":"4.4.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Ferrum Streaming Control Technology","name":"ferrum_streaming_control_technology","category":"user_interface","version":"1.0.2","icon":"fa-play","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.7.6","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Apr 02 16:41:13 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 02 16:41:13 primo volumio[3448]: info: Folder /tmp/plugins removed Apr 02 16:41:13 primo volumio[3448]: info: Folder /tmp/downloaded_plugin.zip removed Apr 02 16:41:13 primo volumio[3448]: Plugin install end detected on script Apr 02 16:41:13 primo volumio[3448]: info: Folder /data/temp removed Apr 02 16:41:16 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:16 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:16 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:16 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:16 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:16 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:16 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:16 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:16 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:41:16 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:16.928Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:41:23 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:23 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:23 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:23 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:23 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:23 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:23 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:23 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:23 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:41:24 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:24.016Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:41:30 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:30 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:30 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:30 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:30 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:30 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:30 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:30 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:30 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:41:31 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:31.452Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:41:37 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:37 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:37 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:37 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:37 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:37 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:37 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:37 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:37 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:41:38 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:38.724Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:41:44 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:44 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:44 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:44 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:44 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:44 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:44 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:45 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:45 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:41:45 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:45.834Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:41:52 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:52 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:52 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:52 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:52 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:52 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:52 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:52 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:52 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:41:52 primo volumio5-onboarding[4282]: time=2026-04-02T16:41:52.979Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:41:59 primo volumio[3448]: info: Received Get System Info Apr 02 16:41:59 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:41:59 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:41:59 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:41:59 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:41:59 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:41:59 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:41:59 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:41:59 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:42:00 primo volumio5-onboarding[4282]: time=2026-04-02T16:42:00.114Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:42:06 primo volumio[3448]: info: Received Get System Info Apr 02 16:42:06 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:42:06 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:42:06 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:42:06 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:42:06 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:42:06 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:42:06 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:42:06 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:42:07 primo volumio5-onboarding[4282]: time=2026-04-02T16:42:07.376Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:42:13 primo volumio[3448]: info: Received Get System Info Apr 02 16:42:13 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 16:42:13 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 16:42:13 primo volumio[3448]: info: Discovery: Getting this device information Apr 02 16:42:13 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:42:13 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:42:13 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 16:42:13 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 02 16:42:13 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 02 16:42:14 primo volumio5-onboarding[4282]: time=2026-04-02T16:42:14.551Z level=INFO msg="service successfully established" component=discovery/localnet Apr 02 16:42:17 primo volumio[3448]: info: Enabling plugin fusiondsp Apr 02 16:42:17 primo volumio[3448]: info: Loading plugin "fusiondsp"... Apr 02 16:42:17 primo volumio[3448]: info: Applying required configuration parameters for plugin fusiondsp Apr 02 16:42:17 primo volumio[3448]: info: Preparing to generate the ALSA configuration file Apr 02 16:42:17 primo volumio[3448]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 02 16:42:17 primo volumio[3448]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 02 16:42:17 primo volumio[3448]: info: Reading ALSA contributions from plugins. Apr 02 16:42:17 primo volumio[3448]: info: Asound.conf file unchanged, so no further update is needed Apr 02 16:42:17 primo volumio[3448]: info: Output device has changed, restarting MPD Apr 02 16:42:17 primo volumio[3448]: info: Output device has changed, restarting Shairport Sync Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:17 primo sudo[6292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 02 16:42:17 primo sudo[6292]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 16:42:17 primo sudo[6292]: pam_unix(sudo:session): session closed for user root Apr 02 16:42:17 primo sudo[6294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 02 16:42:17 primo sudo[6294]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:42:17 primo systemd[1]: Stopping Music Player Daemon... Apr 02 16:42:17 primo volumio[3448]: No protocol specified Apr 02 16:42:17 primo volumio[3448]: xcb_connection_has_error() returned true Apr 02 16:42:17 primo volumio[3448]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 02 16:42:17 primo volumio[3448]: No protocol specified Apr 02 16:42:17 primo volumio[3448]: xcb_connection_has_error() returned true Apr 02 16:42:17 primo volumio[3448]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:17 primo volumio[3448]: info: QobuzConnect: setDeactiveState invoked Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:42:17 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:42:17 primo volumio[3448]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:17 primo vtcs[4831]: [2026-04-02 16:42:17.474] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 02 16:42:17 primo vtcs[4831]: [2026-04-02 16:42:17.481] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 02 16:42:17 primo kernel: aml_tdm_open Apr 02 16:42:17 primo kernel: Not init audio effects Apr 02 16:42:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 02 16:42:17 primo kernel: tdm playback mute: 1, lane_cnt = 8 Apr 02 16:42:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 02 16:42:17 primo volumio[3448]: info: PLUGIN START: fusiondsp Apr 02 16:42:17 primo volumio[3448]: info: Loading i18n strings for locale it Apr 02 16:42:17 primo sudo[6305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:17 primo volumio[3448]: info: FusionDsp - mixtype--------------------- Hardware Apr 02 16:42:17 primo sudo[6305]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:42:17 primo volumio[3448]: info: Preparing to generate the ALSA configuration file Apr 02 16:42:17 primo systemd[1]: Stopping Volumio Tidal Connect Service... Apr 02 16:42:17 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 02 16:42:17 primo volumio[3448]: info: Done. Apr 02 16:42:17 primo systemd[1]: vtcs.service: Succeeded. Apr 02 16:42:17 primo systemd[1]: Stopped Volumio Tidal Connect Service. Apr 02 16:42:17 primo systemd[1]: mpd.service: Succeeded. Apr 02 16:42:17 primo systemd[1]: Stopped Music Player Daemon. Apr 02 16:42:17 primo sudo[6305]: pam_unix(sudo:session): session closed for user root Apr 02 16:42:17 primo sudo[6309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 02 16:42:17 primo sudo[6309]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:42:17 primo systemd[1]: Starting Music Player Daemon... Apr 02 16:42:17 primo sudo[6309]: pam_unix(sudo:session): session closed for user root Apr 02 16:42:17 primo volumio[3448]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 02 16:42:17 primo volumio[3448]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 02 16:42:17 primo volumio[3448]: info: Reading ALSA contributions from plugins. Apr 02 16:42:17 primo volumio[3448]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 16:42:17 primo sudo[6326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 02 16:42:17 primo sudo[6326]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:42:17 primo volumio[3448]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 16:42:17 primo volumio[3448]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 02 16:42:17 primo sudo[6326]: pam_unix(sudo:session): session closed for user root Apr 02 16:42:17 primo volumio[3448]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 16:42:17 primo volumio[3448]: info: MPD Permissions set Apr 02 16:42:17 primo volumio[3448]: info: CoreStateMachine::pushState Apr 02 16:42:17 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::volumioPushState Apr 02 16:42:17 primo sudo[6328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:42:17 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:42:17 primo volumio[3448]: info: MRS: Pushing multiroomSync output update for this device Apr 02 16:42:17 primo volumio[3448]: info: MRS: Pushing multiroomSync output Apr 02 16:42:17 primo volumio[3448]: info: CoreStateMachine::pushState Apr 02 16:42:17 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::volumioPushState Apr 02 16:42:17 primo sudo[6328]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::volumioGetState Apr 02 16:42:17 primo volumio[3448]: info: CorePlayQueue::getTrack 0 Apr 02 16:42:17 primo volumio[3448]: info: MRS: Pushing multiroomSync output update for this device Apr 02 16:42:17 primo volumio[3448]: info: MRS: Pushing multiroomSync output Apr 02 16:42:17 primo sudo[6312]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 02 16:42:17 primo sudo[6312]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 02 16:42:17 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 02 16:42:17 primo sudo[6312]: pam_unix(sudo:session): session closed for user root Apr 02 16:42:17 primo volumio[3448]: info: Updating RAAT Signal Path Apr 02 16:42:17 primo volumio[3448]: info: Updating RAAT Signal Path Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:17 primo qobuz-connect[4805]: 20260402 16:42:17.886 [4805.4805] INFO SampleApp: Stopping Local configuration server Apr 02 16:42:17 primo qobuz-connect[4805]: 20260402 16:42:17.897 [4805.4805] INFO SampleApp: shat down connection on UNIX socket Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 02 16:42:17 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 02 16:42:17 primo systemd[1]: qobuz-connect.service: Succeeded. Apr 02 16:42:17 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 02 16:42:17 primo systemd[1]: Started Volumio Qobuz Connect Service. Apr 02 16:42:17 primo sudo[6328]: pam_unix(sudo:session): session closed for user root Apr 02 16:42:18 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:18 primo volumio[3448]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 02 16:42:18 primo volumio[3448]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 02 16:42:18 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:18 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:18 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:18 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:18 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 02 16:42:18 primo volumio[3448]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 02 16:42:18 primo volumio[3448]: info: Not Reporting Auto name since its the default one Apr 02 16:42:18 primo volumio[3448]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 16:42:18 primo volumio[3448]: TypeError: Cannot read property 'off' of undefined Apr 02 16:42:18 primo volumio[3448]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Apr 02 16:42:18 primo volumio[3448]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 02 16:42:18 primo volumio[3448]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 02 16:42:18 primo volumio[3448]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Apr 02 16:42:18 primo volumio[3448]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Apr 02 16:42:18 primo volumio[3448]: at Socket.emit (events.js:400:28) Apr 02 16:42:18 primo volumio[3448]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 02 16:42:18 primo volumio[3448]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 02 16:42:18 primo volumio[3448]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 16:42:18 primo sudo[6356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-02 16:41 Apr 02 16:42:18 primo sudo[6356]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 12:16:07 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="88686576587e39571d6cf25f3920fbc0"