Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: Enabling plugin motherearthradio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: Loading plugin "motherearthradio"... Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: Applying required configuration parameters for plugin motherearthradio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: [1744731360339] [MotherEarth] API delay: 5 Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: PLUGIN START: motherearthradio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: [1744731360341] CoreMusicLibrary::Adding element Mother Earth Radio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: Cannot find translation for source Randomizer Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: Cannot find translation for source 80s80s Radio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: Cannot find translation for source Mother Earth Radio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: Enabling plugin motherearthradio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: Loading plugin "motherearthradio"... Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: Applying required configuration parameters for plugin motherearthradio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: [1744731360829] [MotherEarth] API delay: 5 Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: PLUGIN START: motherearthradio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: [1744731360831] CoreMusicLibrary::Adding element Mother Earth Radio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: Cannot find translation for source Randomizer Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: Cannot find translation for source 80s80s Radio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: Cannot find translation for source Mother Earth Radio Apr 15 17:36:00 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:15 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 17:36:18 rpi4-ws1280 volumio[1271]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/radio_paradise/2.0.13/volumio/bookworm/armhf Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/radio_paradise/2.0.13/volumio/bookworm/armhf Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Folder /tmp/plugins removed Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Check plugin dependencies Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Checking if plugin already exists Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Rename folder Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Move to category Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Checking if install.sh is present Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Executing install.sh Apr 15 17:36:19 rpi4-ws1280 sudo[5197]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/radio_paradise/install.sh Apr 15 17:36:19 rpi4-ws1280 sudo[5197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:36:19 rpi4-ws1280 sudo[5197]: pam_unix(sudo:session): session closed for user root Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Installing radio paradise Dependencies Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: Plugin install end detected on script Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Install script completed Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Adding reference to registry Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Done installing plugin. Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Folder /tmp/plugins removed Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 17:36:19 rpi4-ws1280 volumio[1271]: info: Folder /data/temp removed Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: Enabling plugin radio_paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: Loading plugin "radio_paradise"... Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: [1744731381511] [RadioParadise] API delay: 5 Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: PLUGIN START: radio_paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: [1744731381515] CoreMusicLibrary::Adding element Radio Paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: Cannot find translation for source Randomizer Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: Cannot find translation for source 80s80s Radio Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: Cannot find translation for source Mother Earth Radio Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: Cannot find translation for source Radio Paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: Discovery: Getting this device information Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: Enabling plugin radio_paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: Loading plugin "radio_paradise"... Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: Applying required configuration parameters for plugin radio_paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: [1744731381895] [RadioParadise] API delay: 5 Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: PLUGIN START: radio_paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: [1744731381896] CoreMusicLibrary::Adding element Radio Paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: Cannot find translation for source Randomizer Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: Cannot find translation for source 80s80s Radio Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: Cannot find translation for source Mother Earth Radio Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: Cannot find translation for source Radio Paradise Apr 15 17:36:21 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: Enabling plugin radio_paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: Loading plugin "radio_paradise"... Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: Applying required configuration parameters for plugin radio_paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: [1744731382311] [RadioParadise] API delay: 5 Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: PLUGIN START: radio_paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: [1744731382312] CoreMusicLibrary::Adding element Radio Paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: Cannot find translation for source Randomizer Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: Cannot find translation for source 80s80s Radio Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: Cannot find translation for source Mother Earth Radio Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: Cannot find translation for source Radio Paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: Enabling plugin radio_paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: Loading plugin "radio_paradise"... Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: Applying required configuration parameters for plugin radio_paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: [1744731382753] [RadioParadise] API delay: 5 Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: PLUGIN START: radio_paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: [1744731382756] CoreMusicLibrary::Adding element Radio Paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: Cannot find translation for source Randomizer Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: Cannot find translation for source 80s80s Radio Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: Cannot find translation for source Mother Earth Radio Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: Cannot find translation for source Radio Paradise Apr 15 17:36:22 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:32 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 17:36:34 rpi4-ws1280 volumio[1271]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/spop/4.1.4/volumio/bookworm/armhf Apr 15 17:36:40 rpi4-ws1280 volumio[1271]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/spop/4.1.4/volumio/bookworm/armhf Apr 15 17:36:40 rpi4-ws1280 volumio[1271]: info: Folder /tmp/plugins removed Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Check plugin dependencies Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Checking if plugin already exists Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Rename folder Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Move to category Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Checking if install.sh is present Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Executing install.sh Apr 15 17:36:41 rpi4-ws1280 sudo[5243]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Apr 15 17:36:41 rpi4-ws1280 sudo[5243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Installing Go-librespot Apr 15 17:36:41 rpi4-ws1280 volumio[1271]: info: Checking old vollibrespot installs Apr 15 17:36:41 rpi4-ws1280 systemd[1]: Reloading. Apr 15 17:36:42 rpi4-ws1280 systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:42 rpi4-ws1280 systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:42 rpi4-ws1280 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:42 rpi4-ws1280 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:42 rpi4-ws1280 volumio[1271]: info: Downloading daemon Apr 15 17:36:43 rpi4-ws1280 ntpd[947]: PROTO: 45.90.187.8 unlink local addr 192.168.1.33 -> Apr 15 17:36:44 rpi4-ws1280 volumio[1271]: info: Creating directories Apr 15 17:36:44 rpi4-ws1280 volumio[1271]: info: Creating data directory Apr 15 17:36:44 rpi4-ws1280 volumio[1271]: info: Creating Start Script Apr 15 17:36:44 rpi4-ws1280 systemd[1]: Reloading. Apr 15 17:36:44 rpi4-ws1280 systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:44 rpi4-ws1280 systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:45 rpi4-ws1280 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:45 rpi4-ws1280 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:45 rpi4-ws1280 sudo[5243]: pam_unix(sudo:session): session closed for user root Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: Plugin install end detected on script Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: info: Install script completed Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: info: Adding reference to registry Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: info: Done installing plugin. Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: info: Folder /tmp/plugins removed Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 17:36:45 rpi4-ws1280 volumio[1271]: info: Folder /data/temp removed Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Enabling plugin spop Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Loading plugin "spop"... Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: PLUGIN START: spop Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Creating Spotify config file Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Enabling plugin spop Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Loading plugin "spop"... Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: PLUGIN START: spop Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Creating Spotify config file Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:47 rpi4-ws1280 volumio[1271]: info: Spotify config file written Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Spotify config file written Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: No need to fix Spotify hosts Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: No need to fix Spotify hosts Apr 15 17:36:48 rpi4-ws1280 sudo[5313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 15 17:36:48 rpi4-ws1280 sudo[5311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 15 17:36:48 rpi4-ws1280 sudo[5311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:36:48 rpi4-ws1280 sudo[5313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:36:48 rpi4-ws1280 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:48 rpi4-ws1280 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 15 17:36:48 rpi4-ws1280 go-librespot[5316]: go-librespot daemon starting... Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 15 17:36:48 rpi4-ws1280 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 15 17:36:48 rpi4-ws1280 sudo[5313]: pam_unix(sudo:session): session closed for user root Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 15 17:36:48 rpi4-ws1280 sudo[5311]: pam_unix(sudo:session): session closed for user root Apr 15 17:36:48 rpi4-ws1280 go-librespot[5318]: go-librespot daemon starting... Apr 15 17:36:48 rpi4-ws1280 go-librespot[5319]: time="2025-04-15T17:36:48+02:00" level=info msg="running go-librespot 0.2.0" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5319]: time="2025-04-15T17:36:48+02:00" level=debug msg="no app state found" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5319]: time="2025-04-15T17:36:48+02:00" level=debug msg="stored credentials not found" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5319]: time="2025-04-15T17:36:48+02:00" level=info msg="generated new device id: b8ae4b5651083606f5f3f092a434ea531e159414" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5319]: time="2025-04-15T17:36:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Enabling plugin spop Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Loading plugin "spop"... Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: PLUGIN START: spop Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Creating Spotify config file Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Spotify config file written Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: No need to fix Spotify hosts Apr 15 17:36:48 rpi4-ws1280 sudo[5327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 15 17:36:48 rpi4-ws1280 sudo[5327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 15 17:36:48 rpi4-ws1280 systemd[1]: go-librespot-daemon.service: Killing process 5325 (go-librespot) with signal SIGKILL. Apr 15 17:36:48 rpi4-ws1280 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 15 17:36:48 rpi4-ws1280 go-librespot[5330]: go-librespot daemon starting... Apr 15 17:36:48 rpi4-ws1280 sudo[5327]: pam_unix(sudo:session): session closed for user root Apr 15 17:36:48 rpi4-ws1280 go-librespot[5332]: time="2025-04-15T17:36:48+02:00" level=info msg="running go-librespot 0.2.0" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5332]: time="2025-04-15T17:36:48+02:00" level=debug msg="app state loaded" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5332]: time="2025-04-15T17:36:48+02:00" level=debug msg="stored credentials not found" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5332]: time="2025-04-15T17:36:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5332]: time="2025-04-15T17:36:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5332]: time="2025-04-15T17:36:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5332]: time="2025-04-15T17:36:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 15 17:36:48 rpi4-ws1280 go-librespot[5332]: time="2025-04-15T17:36:48+02:00" level=info msg="zeroconf server listening on port 37195" Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Enabling plugin spop Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Loading plugin "spop"... Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: PLUGIN START: spop Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Creating Spotify config file Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: Spotify config file written Apr 15 17:36:48 rpi4-ws1280 volumio[1271]: info: No need to fix Spotify hosts Apr 15 17:36:48 rpi4-ws1280 sudo[5340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 15 17:36:48 rpi4-ws1280 sudo[5340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 15 17:36:48 rpi4-ws1280 systemd[1]: go-librespot-daemon.service: Killing process 5335 (go-librespot) with signal SIGKILL. Apr 15 17:36:48 rpi4-ws1280 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 15 17:36:48 rpi4-ws1280 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 15 17:36:49 rpi4-ws1280 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 15 17:36:49 rpi4-ws1280 go-librespot[5342]: go-librespot daemon starting... Apr 15 17:36:49 rpi4-ws1280 sudo[5340]: pam_unix(sudo:session): session closed for user root Apr 15 17:36:49 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:49+02:00" level=info msg="running go-librespot 0.2.0" Apr 15 17:36:49 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:49+02:00" level=debug msg="app state loaded" Apr 15 17:36:49 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:49+02:00" level=debug msg="stored credentials not found" Apr 15 17:36:49 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 15 17:36:49 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 15 17:36:49 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 15 17:36:49 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 15 17:36:49 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:49+02:00" level=info msg="zeroconf server listening on port 42877" Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: go-librespot daemon successfully initialized Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: go-librespot daemon successfully initialized Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: go-librespot daemon successfully initialized Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: Discovery: Getting this device information Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:36:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 17:36:52 rpi4-ws1280 volumio[1271]: info: go-librespot daemon successfully initialized Apr 15 17:36:52 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 17:36:54 rpi4-ws1280 volumio[1271]: info: Initializing connection to go-librespot Websocket Apr 15 17:36:54 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:54+02:00" level=debug msg="new websocket client" Apr 15 17:36:54 rpi4-ws1280 volumio[1271]: info: Connection to go-librespot Websocket established Apr 15 17:36:54 rpi4-ws1280 volumio[1271]: info: Initializing connection to go-librespot Websocket Apr 15 17:36:54 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:54+02:00" level=debug msg="new websocket client" Apr 15 17:36:54 rpi4-ws1280 volumio[1271]: info: Connection to go-librespot Websocket established Apr 15 17:36:54 rpi4-ws1280 volumio[1271]: info: Initializing connection to go-librespot Websocket Apr 15 17:36:54 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:54+02:00" level=debug msg="new websocket client" Apr 15 17:36:54 rpi4-ws1280 volumio[1271]: info: Connection to go-librespot Websocket established Apr 15 17:36:54 rpi4-ws1280 volumio[1271]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/fusiondsp/2.0.73/volumio/bookworm/armhf Apr 15 17:36:55 rpi4-ws1280 volumio[1271]: info: Initializing connection to go-librespot Websocket Apr 15 17:36:55 rpi4-ws1280 go-librespot[5343]: time="2025-04-15T17:36:55+02:00" level=debug msg="new websocket client" Apr 15 17:36:55 rpi4-ws1280 volumio[1271]: info: Connection to go-librespot Websocket established Apr 15 17:36:56 rpi4-ws1280 volumio[1271]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/fusiondsp/2.0.73/volumio/bookworm/armhf Apr 15 17:36:56 rpi4-ws1280 volumio[1271]: info: Folder /tmp/plugins removed Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Check plugin dependencies Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Checking if plugin already exists Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Rename folder Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Getting Spotify volume Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Getting Spotify volume Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Move to category Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Checking if install.sh is present Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Getting Spotify volume Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Executing install.sh Apr 15 17:36:57 rpi4-ws1280 sudo[5381]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Apr 15 17:36:57 rpi4-ws1280 sudo[5381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: creating filters folder and copying demo filters Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: SPOTIFY: SPOTIFY VOLUME undefined Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: SPOTIFY: VOLUMIO VOLUME 50 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Aligning Spotify Volume to Volumio Volume Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: Setting Spotify Volume from Volumio: 50 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: copying demo flters Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: ./presets/ Apr 15 17:36:57 rpi4-ws1280 volumio[1271]: info: ./presets/2XEQ15/ Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: Getting Spotify volume Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Apr 15 17:36:58 rpi4-ws1280 sudo[5406]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 sudo[5406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/PEQ/ Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/convfir/ Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/convfir/kef.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/convfir/test.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/EQ15/ Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/EQ15/.Bass-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/EQ15/.Classic-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/EQ15/.Voice-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: ./presets/EQ15/.Rock-FusionDsp.json Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: Installing/fusiondsp dependencies Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: Hit:2 https://deb.nodesource.com/node_20.x bookworm InRelease Apr 15 17:36:58 rpi4-ws1280 volumio[1271]: info: Hit:3 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Apr 15 17:36:59 rpi4-ws1280 volumio[1271]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Apr 15 17:36:59 rpi4-ws1280 volumio[1271]: info: Sending Spotify command with payload to local API: /player/volume Apr 15 17:37:02 rpi4-ws1280 volumio[1271]: info: Reading package lists... Apr 15 17:37:03 rpi4-ws1280 volumio[1271]: info: Building dependency tree... Apr 15 17:37:05 rpi4-ws1280 volumio[1271]: info: Reading state information... Apr 15 17:37:05 rpi4-ws1280 sudo[5406]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:05 rpi4-ws1280 volumio[1271]: info: 5 packages can be upgraded. Run 'apt list --upgradable' to see them. Apr 15 17:37:05 rpi4-ws1280 sudo[5621]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install --no-install-recommends python3-venv drc Apr 15 17:37:05 rpi4-ws1280 sudo[5621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:37:05 rpi4-ws1280 volumio[1271]: info: Reading package lists... Apr 15 17:37:05 rpi4-ws1280 volumio[1271]: info: Building dependency tree... Apr 15 17:37:05 rpi4-ws1280 volumio[1271]: info: Reading state information... Apr 15 17:37:06 rpi4-ws1280 volumio[1271]: info: The following additional packages will be installed: Apr 15 17:37:06 rpi4-ws1280 volumio[1271]: info: python3-pip-whl python3-setuptools-whl python3.11-venv Apr 15 17:37:12 rpi4-ws1280 volumio[1271]: info: The following NEW packages will be installed: Apr 15 17:37:12 rpi4-ws1280 volumio[1271]: info: drc python3-pip-whl python3-setuptools-whl python3-venv python3.11-venv Apr 15 17:37:12 rpi4-ws1280 volumio[1271]: info: 0 upgraded, 5 newly installed, 0 to remove and 5 not upgraded. Apr 15 17:37:12 rpi4-ws1280 volumio[1271]: info: Need to get 2954 kB of archives. Apr 15 17:37:12 rpi4-ws1280 volumio[1271]: info: After this operation, 3696 kB of additional disk space will be used. Apr 15 17:37:12 rpi4-ws1280 volumio[1271]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf python3-pip-whl all 23.0.1+dfsg-1+rpt1 [1718 kB] Apr 15 17:37:13 rpi4-ws1280 volumio[1271]: info: Get:2 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf drc armhf 3.2.3~dfsg0-1 [118 kB] Apr 15 17:37:13 rpi4-ws1280 volumio[1271]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-setuptools-whl all 66.1.1-1+deb12u1 [1112 kB] Apr 15 17:37:13 rpi4-ws1280 volumio[1271]: info: Get:5 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-venv armhf 3.11.2-1 [1188 B] Apr 15 17:37:13 rpi4-ws1280 volumio[1271]: info: Get:4 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3.11-venv armhf 3.11.2-6+deb12u5 [5892 B] Apr 15 17:37:14 rpi4-ws1280 volumio[1271]: info: Fetched 2954 kB in 1s (3497 kB/s) Apr 15 17:37:14 rpi4-ws1280 volumio[1271]: info: Selecting previously unselected package drc. Apr 15 17:37:14 rpi4-ws1280 volumio[1271]: [620B blob data] Apr 15 17:37:14 rpi4-ws1280 volumio[1271]: info: Preparing to unpack .../drc_3.2.3~dfsg0-1_armhf.deb ... Apr 15 17:37:14 rpi4-ws1280 volumio[1271]: info: Unpacking drc (3.2.3~dfsg0-1) ... Apr 15 17:37:15 rpi4-ws1280 volumio[1271]: info: Selecting previously unselected package python3-pip-whl. Apr 15 17:37:15 rpi4-ws1280 volumio[1271]: info: Preparing to unpack .../python3-pip-whl_23.0.1+dfsg-1+rpt1_all.deb ... Apr 15 17:37:15 rpi4-ws1280 volumio[1271]: info: Unpacking python3-pip-whl (23.0.1+dfsg-1+rpt1) ... Apr 15 17:37:16 rpi4-ws1280 volumio[1271]: info: Selecting previously unselected package python3-setuptools-whl. Apr 15 17:37:16 rpi4-ws1280 volumio[1271]: info: Preparing to unpack .../python3-setuptools-whl_66.1.1-1+deb12u1_all.deb ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Unpacking python3-setuptools-whl (66.1.1-1+deb12u1) ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Selecting previously unselected package python3.11-venv. Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Preparing to unpack .../python3.11-venv_3.11.2-6+deb12u5_armhf.deb ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Unpacking python3.11-venv (3.11.2-6+deb12u5) ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Selecting previously unselected package python3-venv. Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Preparing to unpack .../python3-venv_3.11.2-1_armhf.deb ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Unpacking python3-venv (3.11.2-1) ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Setting up drc (3.2.3~dfsg0-1) ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Setting up python3-setuptools-whl (66.1.1-1+deb12u1) ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Setting up python3-pip-whl (23.0.1+dfsg-1+rpt1) ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Setting up python3.11-venv (3.11.2-6+deb12u5) ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Setting up python3-venv (3.11.2-1) ... Apr 15 17:37:17 rpi4-ws1280 volumio[1271]: info: Processing triggers for man-db (2.11.2-2) ... Apr 15 17:37:18 rpi4-ws1280 sudo[5621]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:18 rpi4-ws1280 volumio[1271]: info: creating sysytemd service Apr 15 17:37:18 rpi4-ws1280 sudo[5728]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Apr 15 17:37:18 rpi4-ws1280 sudo[5728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:37:18 rpi4-ws1280 systemd[1]: Reloading. Apr 15 17:37:19 rpi4-ws1280 systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:37:19 rpi4-ws1280 systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:37:19 rpi4-ws1280 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:37:19 rpi4-ws1280 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:37:19 rpi4-ws1280 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:37:19 rpi4-ws1280 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 15 17:37:19 rpi4-ws1280 sudo[5728]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:19 rpi4-ws1280 volumio[1271]: info: copying hw detection script Apr 15 17:37:19 rpi4-ws1280 volumio[1271]: info: Detected cpu architecture as armv7l Apr 15 17:37:21 rpi4-ws1280 sudo[5757]: root : PWD=/tmp ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Apr 15 17:37:21 rpi4-ws1280 sudo[5757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:37:21 rpi4-ws1280 sudo[5757]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:21 rpi4-ws1280 sudo[5759]: root : PWD=/tmp ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Apr 15 17:37:21 rpi4-ws1280 sudo[5759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:37:21 rpi4-ws1280 sudo[5759]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:21 rpi4-ws1280 volumio[1271]: info: Downloading camillagui ... Please wait! Apr 15 17:37:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 17:37:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 17:37:21 rpi4-ws1280 volumio[1271]: info: Discovery: Getting this device information Apr 15 17:37:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:37:21 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:37:21 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 17:37:24 rpi4-ws1280 volumio[1271]: info: Extracting camillagui ... Apr 15 17:37:26 rpi4-ws1280 sudo[5381]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: Plugin install end detected on script Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: info: Install script completed Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: info: Adding reference to registry Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: info: Done installing plugin. Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: info: Folder /tmp/plugins removed Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: info: Folder /tmp/downloaded_plugin.zip removed Apr 15 17:37:26 rpi4-ws1280 volumio[1271]: info: Folder /data/temp removed Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Enabling plugin fusiondsp Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Loading plugin "fusiondsp"... Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Preparing to generate the ALSA configuration file Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Reading ALSA contributions from plugins. Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Asound.conf file written Apr 15 17:37:48 rpi4-ws1280 sudo[5813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 15 17:37:48 rpi4-ws1280 sudo[5813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 sudo[5813]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting MPD Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting Shairport Sync Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:48 rpi4-ws1280 sudo[5819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 17:37:48 rpi4-ws1280 sudo[5819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 sudo[5819]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:48 rpi4-ws1280 sudo[5821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 17:37:48 rpi4-ws1280 sudo[5821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 vtcs[5004]: [2025-04-15 17:37:48.671] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 15 17:37:48 rpi4-ws1280 vtcs[5004]: [2025-04-15 17:37:48.672] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: PLUGIN START: fusiondsp Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 15 17:37:48 rpi4-ws1280 sudo[5831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:48 rpi4-ws1280 sudo[5831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Loading i18n strings for locale en Apr 15 17:37:48 rpi4-ws1280 systemd[1]: mpd.service: Deactivated successfully. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: mpd.service: Consumed 1.286s CPU time. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: mpd.socket: Deactivated successfully. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: FusionDsp - mixtype--------------------- Hardware Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Preparing to generate the ALSA configuration file Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Reading ALSA contributions from plugins. Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: MPD Permissions set Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Apr 15 17:37:48 rpi4-ws1280 sudo[5836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:48 rpi4-ws1280 sudo[5836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 15 17:37:48 rpi4-ws1280 systemd[1]: vtcs.service: Deactivated successfully. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Starting Shairport Sync Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Asound.conf file unchanged, so no further update is needed Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting MPD Apr 15 17:37:48 rpi4-ws1280 sudo[5831]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:48 rpi4-ws1280 sudo[5836]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting Shairport Sync Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:48 rpi4-ws1280 sudo[5845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 17:37:48 rpi4-ws1280 sudo[5845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 sudo[5848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 17:37:48 rpi4-ws1280 sudo[5848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 sudo[5850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 17:37:48 rpi4-ws1280 sudo[5848]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:48 rpi4-ws1280 sudo[5850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 15 17:37:48 rpi4-ws1280 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: shairport-sync.service: Consumed 1.990s CPU time. Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Enabling plugin fusiondsp Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Loading plugin "fusiondsp"... Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Applying required configuration parameters for plugin fusiondsp Apr 15 17:37:48 rpi4-ws1280 volumio[1271]: info: Preparing to generate the ALSA configuration file Apr 15 17:37:48 rpi4-ws1280 sudo[5845]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:48 rpi4-ws1280 sudo[5863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:48 rpi4-ws1280 sudo[5863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:48 rpi4-ws1280 sudo[5865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:48 rpi4-ws1280 sudo[5865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: MPD Permissions set Apr 15 17:37:48 rpi4-ws1280 systemd[1]: mpd.service: Deactivated successfully. Apr 15 17:37:48 rpi4-ws1280 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Reading ALSA contributions from plugins. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Shairport-Sync Started Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 systemd[1]: mpd.socket: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Starting Shairport Sync Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Asound.conf file unchanged, so no further update is needed Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting MPD Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 17:37:49 rpi4-ws1280 sudo[5889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting Shairport Sync Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:49 rpi4-ws1280 sudo[5889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 sudo[5892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 17:37:49 rpi4-ws1280 sudo[5892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 sudo[5892]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 sudo[5863]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 sudo[5865]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 sudo[5894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 17:37:49 rpi4-ws1280 sudo[5894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: PLUGIN START: fusiondsp Apr 15 17:37:49 rpi4-ws1280 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Loading i18n strings for locale en Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: FusionDsp - mixtype--------------------- Hardware Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Preparing to generate the ALSA configuration file Apr 15 17:37:49 rpi4-ws1280 sudo[5908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:49 rpi4-ws1280 sudo[5908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Reading ALSA contributions from plugins. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: MPD Permissions set Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 24 Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:49 rpi4-ws1280 sudo[5891]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 17:37:49 rpi4-ws1280 sudo[5889]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 sudo[5891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:37:49 rpi4-ws1280 sudo[5912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:49 rpi4-ws1280 sudo[5912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 sudo[5891]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Shairport-Sync Started Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Starting Shairport Sync Apr 15 17:37:49 rpi4-ws1280 systemd[1]: mpd.service: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Asound.conf file unchanged, so no further update is needed Apr 15 17:37:49 rpi4-ws1280 sudo[5922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 17:37:49 rpi4-ws1280 sudo[5922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting MPD Apr 15 17:37:49 rpi4-ws1280 sudo[5912]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 sudo[5908]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting Shairport Sync Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:49 rpi4-ws1280 sudo[5937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 17:37:49 rpi4-ws1280 systemd[1]: mpd.socket: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 sudo[5937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 17:37:49 rpi4-ws1280 sudo[5937]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 sudo[5941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 17:37:49 rpi4-ws1280 sudo[5941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Enabling plugin fusiondsp Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Loading plugin "fusiondsp"... Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Applying required configuration parameters for plugin fusiondsp Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Preparing to generate the ALSA configuration file Apr 15 17:37:49 rpi4-ws1280 sudo[5954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 17:37:49 rpi4-ws1280 sudo[5954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 17:37:49 rpi4-ws1280 sudo[5956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:49 rpi4-ws1280 sudo[5956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: MPD Permissions set Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Reading ALSA contributions from plugins. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Starting Shairport Sync Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:49 rpi4-ws1280 sudo[5922]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 systemd[1]: mpd.service: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Shairport-Sync Started Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Asound.conf file unchanged, so no further update is needed Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting MPD Apr 15 17:37:49 rpi4-ws1280 sudo[5967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 17:37:49 rpi4-ws1280 sudo[5954]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 sudo[5967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 sudo[5956]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting Shairport Sync Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:49 rpi4-ws1280 systemd[1]: mpd.socket: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 17:37:49 rpi4-ws1280 sudo[5986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 17:37:49 rpi4-ws1280 sudo[5986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: PLUGIN START: fusiondsp Apr 15 17:37:49 rpi4-ws1280 sudo[5971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 17:37:49 rpi4-ws1280 sudo[5971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 sudo[5971]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 17:37:49 rpi4-ws1280 sudo[5999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:49 rpi4-ws1280 sudo[5999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Loading i18n strings for locale en Apr 15 17:37:49 rpi4-ws1280 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: FusionDsp - mixtype--------------------- Hardware Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Preparing to generate the ALSA configuration file Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:37:49 rpi4-ws1280 sudo[5967]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 systemd[1]: mpd.service: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 17:37:49 rpi4-ws1280 sudo[6005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:49 rpi4-ws1280 sudo[6005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 sudo[5999]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Reading ALSA contributions from plugins. Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: MPD Permissions set Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Shairport-Sync Started Apr 15 17:37:49 rpi4-ws1280 systemd[1]: mpd.socket: Deactivated successfully. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Starting Shairport Sync Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Asound.conf file unchanged, so no further update is needed Apr 15 17:37:49 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting MPD Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 17:37:49 rpi4-ws1280 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 17:37:49 rpi4-ws1280 sudo[6029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 17:37:49 rpi4-ws1280 sudo[6029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:49 rpi4-ws1280 sudo[6032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 17:37:49 rpi4-ws1280 sudo[6032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 sudo[6032]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting Shairport Sync Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:50 rpi4-ws1280 sudo[6005]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 sudo[6034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 17:37:50 rpi4-ws1280 sudo[6034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 15 17:37:50 rpi4-ws1280 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: mpd.service: Deactivated successfully. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: mpd.socket: Deactivated successfully. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: MPD Permissions set Apr 15 17:37:50 rpi4-ws1280 sudo[6046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:50 rpi4-ws1280 sudo[6046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Starting Shairport Sync Apr 15 17:37:50 rpi4-ws1280 sudo[6049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:50 rpi4-ws1280 sudo[6049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 17:37:50 rpi4-ws1280 sudo[6058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 17:37:50 rpi4-ws1280 sudo[6058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:50 rpi4-ws1280 sudo[6029]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Shairport-Sync Started Apr 15 17:37:50 rpi4-ws1280 sudo[6059]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 17:37:50 rpi4-ws1280 sudo[6059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:37:50 rpi4-ws1280 sudo[6046]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 sudo[6059]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 sudo[6049]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 15 17:37:50 rpi4-ws1280 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: shairport-sync.service: Start request repeated too quickly. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:50 rpi4-ws1280 sudo[6058]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: Job for shairport-sync.service failed because start of the service was attempted too often. Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: To force a start use "systemctl reset-failed shairport-sync.service" Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: followed by "systemctl start shairport-sync.service" again. Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Enabling plugin fusiondsp Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Loading plugin "fusiondsp"... Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Applying required configuration parameters for plugin fusiondsp Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Preparing to generate the ALSA configuration file Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Reading ALSA contributions from plugins. Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Asound.conf file unchanged, so no further update is needed Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting MPD Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting Shairport Sync Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:50 rpi4-ws1280 sudo[6081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 17:37:50 rpi4-ws1280 sudo[6081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 sudo[6081]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 sudo[6083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 17:37:50 rpi4-ws1280 sudo[6083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: PLUGIN START: fusiondsp Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Loading i18n strings for locale en Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: FusionDsp - mixtype--------------------- Hardware Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Preparing to generate the ALSA configuration file Apr 15 17:37:50 rpi4-ws1280 sudo[6093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:50 rpi4-ws1280 sudo[6093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 systemd[1]: mpd.service: Deactivated successfully. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: mpd.socket: Deactivated successfully. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Done. Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Reading ALSA contributions from plugins. Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: MPD Permissions set Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 sudo[6097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:50 rpi4-ws1280 sudo[6097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 17:37:50 rpi4-ws1280 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: camilladsp service started and running in background, instance 1 Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: FusionDsp loaded Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Adding Signal Path Element [object Object] Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Adding fusiondspeq DSP Signal Path Element Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: FusionDsp - ---- installed callbackRead Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 15 17:37:50 rpi4-ws1280 sudo[6093]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: error: FusionDsp - Reload WebSocket error: [object Object] Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Starting Shairport Sync Apr 15 17:37:50 rpi4-ws1280 sudo[6120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 15 17:37:50 rpi4-ws1280 sudo[6120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Asound.conf file unchanged, so no further update is needed Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting MPD Apr 15 17:37:50 rpi4-ws1280 sudo[6097]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 sudo[6113]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 17:37:50 rpi4-ws1280 sudo[6113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:37:50 rpi4-ws1280 sudo[6123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 17:37:50 rpi4-ws1280 sudo[6123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 sudo[6127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 17:37:50 rpi4-ws1280 sudo[6127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 sudo[6113]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Output device has changed, restarting Shairport Sync Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:50 rpi4-ws1280 sudo[6127]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:50 rpi4-ws1280 sudo[6132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 17:37:50 rpi4-ws1280 sudo[6132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:50 rpi4-ws1280 volumio[1271]: info: MPD Permissions set Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Apr 15 17:37:51 rpi4-ws1280 sudo[6142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 systemd[1]: shairport-sync.service: Start request repeated too quickly. Apr 15 17:37:51 rpi4-ws1280 sudo[6142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:51 rpi4-ws1280 systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Apr 15 17:37:51 rpi4-ws1280 sudo[6120]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:51 rpi4-ws1280 systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:51 rpi4-ws1280 sudo[6144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 15 17:37:51 rpi4-ws1280 sudo[6144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Starting Shairport Sync Apr 15 17:37:51 rpi4-ws1280 sudo[6123]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: Job for shairport-sync.service failed because start of the service was attempted too often. Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: To force a start use "systemctl reset-failed shairport-sync.service" Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: followed by "systemctl start shairport-sync.service" again. Apr 15 17:37:51 rpi4-ws1280 systemd[1]: mpd.service: Deactivated successfully. Apr 15 17:37:51 rpi4-ws1280 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 17:37:51 rpi4-ws1280 sudo[6155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 17:37:51 rpi4-ws1280 sudo[6155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:51 rpi4-ws1280 systemd[1]: mpd.socket: Deactivated successfully. Apr 15 17:37:51 rpi4-ws1280 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 17:37:51 rpi4-ws1280 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 17:37:51 rpi4-ws1280 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 15 17:37:51 rpi4-ws1280 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: camilladsp service started and running in background, instance 2 Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp loaded Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Adding Signal Path Element [object Object] Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Updating fusiondspeq DSP Signal Path Element Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - ---- installed callbackRead Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - ---- read samplerate, raw: Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - invalid sample rate Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - ---- read samplerate, raw: Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - invalid sample rate Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - Reload WebSocket error: [object Object] Apr 15 17:37:51 rpi4-ws1280 systemd[1]: shairport-sync.service: Start request repeated too quickly. Apr 15 17:37:51 rpi4-ws1280 sudo[6142]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:51 rpi4-ws1280 systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Apr 15 17:37:51 rpi4-ws1280 systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 15 17:37:51 rpi4-ws1280 sudo[6155]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: Job for shairport-sync.service failed because start of the service was attempted too often. Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: To force a start use "systemctl reset-failed shairport-sync.service" Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: followed by "systemctl start shairport-sync.service" again. Apr 15 17:37:51 rpi4-ws1280 sudo[6144]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:51 rpi4-ws1280 sudo[6177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 15 17:37:51 rpi4-ws1280 sudo[6177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:51 rpi4-ws1280 sudo[6177]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:51 rpi4-ws1280 sudo[6167]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 17:37:51 rpi4-ws1280 sudo[6167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 17:37:51 rpi4-ws1280 sudo[6167]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Discovery: Getting this device information Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioGetState Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: camilladsp service started and running in background, instance 3 Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp loaded Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Adding Signal Path Element [object Object] Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Updating fusiondspeq DSP Signal Path Element Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - ---- installed callbackRead Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - ---- read samplerate, raw: Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - invalid sample rate Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - ---- read samplerate, raw: Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - invalid sample rate Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: FusionDsp - ---- read samplerate, raw: Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - invalid sample rate Apr 15 17:37:51 rpi4-ws1280 sudo[6197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 15 17:37:51 rpi4-ws1280 sudo[6197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: 0+0 records in Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: 0+0 records out Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: 0 bytes copied, 0.000204899 s, 0.0 kB/s Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: error: FusionDsp - Reload WebSocket error: [object Object] Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: TidalConnect service stoped! Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: TidalConnect service stoped! Apr 15 17:37:51 rpi4-ws1280 sudo[6197]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 15 17:37:51 rpi4-ws1280 volumio[1271]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 15 17:37:52 rpi4-ws1280 sudo[6215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 15 17:37:52 rpi4-ws1280 sudo[6215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:52 rpi4-ws1280 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 15 17:37:52 rpi4-ws1280 sudo[6215]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: TidalConnect service stoped! Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: TidalConnect service stoped! Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 15 17:37:52 rpi4-ws1280 sudo[6229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 15 17:37:52 rpi4-ws1280 sudo[6229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:52 rpi4-ws1280 sudo[6229]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: TidalConnect service stoped! Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Executing endpoint tc_getconfig Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: TidalConnect service stoped! Apr 15 17:37:52 rpi4-ws1280 vtcs[6218]: STARTING TidalConnect services, version: 1.5.0.45 Apr 15 17:37:52 rpi4-ws1280 vtcs[6218]: STARTED TidalConnect services. Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Executing endpoint tc_connect Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Connecting to TidalConnect Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreStateMachine::pushState Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: MRS: Pushing multiroomSync output Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreStateMachine::pushState Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: MRS: Pushing multiroomSync output update for this device Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: MRS: Pushing multiroomSync output Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 15 17:37:52 rpi4-ws1280 sudo[6255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 15 17:37:52 rpi4-ws1280 sudo[6255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Volumio is not playing Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Clipped samples monitor stopped Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Volumio is not playing Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Clipped samples monitor stopped Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Volumio is not playing Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Clipped samples monitor stopped Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Volumio is not playing Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Clipped samples monitor stopped Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Volumio is not playing Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Clipped samples monitor stopped Apr 15 17:37:52 rpi4-ws1280 sudo[6257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Volumio is not playing Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: FusionDsp - Clipped samples monitor stopped Apr 15 17:37:52 rpi4-ws1280 sudo[6257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 17:37:52 rpi4-ws1280 sudo[6255]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: Error: ENOENT: no such file or directory, unlink '/tmp/sU90x0B7F0JBhU20yAvSTTxf2VzCEaTDKF1arSC504dL0uqOHvMhYfs1YYP9TtFHfFvXOgH3dFaTq3ycl41PcjONn0402DKQhyz3' Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at Object.unlinkSync (node:fs:1899:3) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at TidalConnectClient.emit (node:events:514:28) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at Array.forEach () Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at Socket.emit (node:events:514:28) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at addChunk (node:internal/streams/readable:343:12) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at readableAddChunk (node:internal/streams/readable:316:9) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at Readable.push (node:internal/streams/readable:253:10) Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: errno: -2, Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: syscall: 'unlink', Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: code: 'ENOENT', Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: path: '/tmp/sU90x0B7F0JBhU20yAvSTTxf2VzCEaTDKF1arSC504dL0uqOHvMhYfs1YYP9TtFHfFvXOgH3dFaTq3ycl41PcjONn0402DKQhyz3' Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: } Apr 15 17:37:52 rpi4-ws1280 volumio[1271]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 17:37:52 rpi4-ws1280 sudo[6257]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 mpd[6182]: Apr 15 17:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 15 17:37:52 rpi4-ws1280 systemd[1]: Started mpd.service - Music Player Daemon. Apr 15 17:37:52 rpi4-ws1280 sudo[5850]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 sudo[6083]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 sudo[6034]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 sudo[5941]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 sudo[6132]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 sudo[5894]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 sudo[5986]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:52 rpi4-ws1280 sudo[5821]: pam_unix(sudo:session): session closed for user root Apr 15 17:37:53 rpi4-ws1280 sudo[6276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-15 17:36' Apr 15 17:37:53 rpi4-ws1280 sudo[6276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="8a3b6fcf0dbd7d42207332f6d209906dd2daab56" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Apr 12 14:26:54 UTC 2025" VOLUMIO_VERSION="0.054" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="25d223077b0ad14151d6c5b08157bae8"