-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2026-02-10 12:28:33 UTC. -- Feb 10 12:27:43 pecanpiplus systemd-timedated[1008]: Changed local time to Tue Feb 10 12:27:43 2026 Feb 10 12:27:43 pecanpiplus sudo[1006]: pam_unix(sudo:session): session closed for user root Feb 10 12:27:43 pecanpiplus volumio-time-update[646]: volumio-time-update-util: System time updated successfully. Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "network"... Feb 10 12:27:43 pecanpiplus systemd[1]: Starting Daily man-db regeneration... Feb 10 12:27:43 pecanpiplus systemd[1]: Starting Daily apt download activities... Feb 10 12:27:43 pecanpiplus systemd[1]: Started Volumio Time Update Utility. Feb 10 12:27:43 pecanpiplus systemd[1]: Reached target Multi-User System. Feb 10 12:27:43 pecanpiplus systemd[1]: Reached target Graphical Interface. Feb 10 12:27:43 pecanpiplus systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 10 12:27:43 pecanpiplus volumio[825]: info: Refreshing Cached IP Addresses Feb 10 12:27:43 pecanpiplus systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 10 12:27:43 pecanpiplus systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "services"... Feb 10 12:27:43 pecanpiplus sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 12:27:43 pecanpiplus sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 12:27:43 pecanpiplus sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:27:43 pecanpiplus sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:27:43 pecanpiplus sudo[1016]: pam_unix(sudo:session): session closed for user root Feb 10 12:27:43 pecanpiplus sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 10 12:27:43 pecanpiplus sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "alsa_controller"... Feb 10 12:27:43 pecanpiplus sudo[1018]: pam_unix(sudo:session): session closed for user root Feb 10 12:27:43 pecanpiplus sudo[1025]: pam_unix(sudo:session): session closed for user root Feb 10 12:27:43 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "wizard"... Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "networkfs"... Feb 10 12:27:43 pecanpiplus volumio[825]: info: Starting Udev Watcher for removable devices Feb 10 12:27:43 pecanpiplus volumio[825]: info: Ignoring mount for partition: boot Feb 10 12:27:43 pecanpiplus volumio[825]: info: Ignoring mount for partition: volumio Feb 10 12:27:43 pecanpiplus volumio[825]: info: Ignoring mount for partition: volumio_data Feb 10 12:27:43 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "volumio_command_line_client"... Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "upnp"... Feb 10 12:27:43 pecanpiplus volumio[825]: info: [1770726463928] Starting Upmpd Daemon Feb 10 12:27:43 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "my_music"... Feb 10 12:27:43 pecanpiplus volumio[825]: info: Loading plugin "mpd"... Feb 10 12:27:44 pecanpiplus volumio-remote-updater[619]: [2026-02-10 12:27:44] [connect] Successful connection Feb 10 12:27:45 pecanpiplus volumio[825]: info: Loading plugin "upnp_browser"... Feb 10 12:27:46 pecanpiplus systemd[1]: man-db.service: Succeeded. Feb 10 12:27:46 pecanpiplus systemd[1]: Started Daily man-db regeneration. Feb 10 12:27:47 pecanpiplus volumio[825]: info: Loading plugin "alarm-clock"... Feb 10 12:27:47 pecanpiplus volumio[825]: info: Loading plugin "manifestui"... Feb 10 12:27:48 pecanpiplus volumio[825]: info: Loading plugin "metavolumio"... Feb 10 12:27:48 pecanpiplus systemd[1]: apt-daily.service: Succeeded. Feb 10 12:27:48 pecanpiplus systemd[1]: Started Daily apt download activities. Feb 10 12:27:48 pecanpiplus systemd[1]: Starting Daily apt upgrade and clean activities... Feb 10 12:27:49 pecanpiplus dbus-daemon[638]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.10' (uid=0 pid=996 comm="/usr/bin/vivaldi --kiosk --no-sandbox --disable-ba") Feb 10 12:27:49 pecanpiplus systemd[1]: Condition check resulted in Bluetooth service being skipped. Feb 10 12:27:50 pecanpiplus systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 10 12:27:50 pecanpiplus systemd[1]: Started Daily apt upgrade and clean activities. Feb 10 12:27:50 pecanpiplus systemd[1]: Startup finished in 12.713s (kernel) + 23.612s (userspace) = 36.326s. Feb 10 12:27:53 pecanpiplus volumio[825]: info: Loading plugin "airplay_emulation"... Feb 10 12:27:53 pecanpiplus volumio[825]: info: Starting Shairport Sync Feb 10 12:27:53 pecanpiplus volumio[825]: info: Loading plugin "cd_controller"... Feb 10 12:27:53 pecanpiplus volumio[825]: info: Loading plugin "last_100"... Feb 10 12:27:53 pecanpiplus volumio[825]: info: Loading plugin "qobuzconnect"... Feb 10 12:27:53 pecanpiplus volumio[825]: info: Loading plugin "raat"... Feb 10 12:27:54 pecanpiplus volumio[825]: info: RAAT Plugin loaded Feb 10 12:27:54 pecanpiplus volumio[825]: info: Adding restartRAATSocket REST API Endpoint Feb 10 12:27:54 pecanpiplus volumio[825]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Feb 10 12:27:54 pecanpiplus volumio[825]: info: Loading plugin "streaming_services"... Feb 10 12:27:58 pecanpiplus volumio[825]: info: Starting Streaming Service Transparent Proxy Feb 10 12:27:58 pecanpiplus volumio[825]: info: Loading plugin "tidalconnect"... Feb 10 12:27:58 pecanpiplus volumio[825]: info: Loading plugin "webradio"... Feb 10 12:27:58 pecanpiplus volumio[825]: info: Loading plugin "i2s_dacs"... Feb 10 12:27:58 pecanpiplus volumio[825]: info: I2S DAC not set, start Auto-detection Feb 10 12:27:58 pecanpiplus volumio[825]: info: Loading plugin "volumiodiscovery"... Feb 10 12:27:59 pecanpiplus volumio-remote-updater[619]: [2026-02-10 12:27:59] [connect] Successful connection Feb 10 12:27:59 pecanpiplus volumio[825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 12:27:59 pecanpiplus node[825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 12:27:59 pecanpiplus node[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 12:27:59 pecanpiplus volumio[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 12:27:59 pecanpiplus volumio[825]: *** WARNING *** For more information see Feb 10 12:27:59 pecanpiplus volumio[825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 12:27:59 pecanpiplus volumio[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 12:27:59 pecanpiplus volumio[825]: *** WARNING *** For more information see Feb 10 12:27:59 pecanpiplus node[825]: *** WARNING *** For more information see Feb 10 12:27:59 pecanpiplus node[825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 12:27:59 pecanpiplus node[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 12:27:59 pecanpiplus node[825]: *** WARNING *** For more information see Feb 10 12:27:59 pecanpiplus volumio[825]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 12:28:00 pecanpiplus volumio[825]: info: Discovery: Started advertising with name: PecanPiPlus Feb 10 12:28:00 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 12:28:00 pecanpiplus volumio[825]: info: Loading plugin "spop"... Feb 10 12:28:00 pecanpiplus systemd[1]: systemd-fsckd.service: Succeeded. Feb 10 12:28:01 pecanpiplus volumio[825]: STREAMING PROXY: Starting server on port 3245 Feb 10 12:28:01 pecanpiplus volumio[825]: Node JS runtime: 14 Feb 10 12:28:02 pecanpiplus volumio[825]: info: Loading plugin "ytcr"... Feb 10 12:28:05 pecanpiplus volumio[825]: info: Loading plugin "ytmusic"... Feb 10 12:28:07 pecanpiplus volumio[825]: info: Loading plugin "multiroom"... Feb 10 12:28:09 pecanpiplus volumio[825]: info: Applying required configuration parameters for plugin multiroom Feb 10 12:28:09 pecanpiplus sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 10 12:28:09 pecanpiplus sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:09 pecanpiplus sudo[1324]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:09 pecanpiplus volumio[825]: info: MRS: MultiRoom plugin initialized Feb 10 12:28:09 pecanpiplus volumio[825]: info: MRS: STOPPING SNAPCLIENT Feb 10 12:28:09 pecanpiplus volumio[825]: info: MRS: Snap server stop Feb 10 12:28:09 pecanpiplus sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 10 12:28:09 pecanpiplus sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:09 pecanpiplus volumio[825]: info: MRS: STOPPING volumioStreaming Feb 10 12:28:09 pecanpiplus sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 10 12:28:09 pecanpiplus sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:09 pecanpiplus sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 10 12:28:09 pecanpiplus sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:09 pecanpiplus volumio[825]: info: Loading plugin "outputs"... Feb 10 12:28:09 pecanpiplus sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 10 12:28:09 pecanpiplus sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:09 pecanpiplus volumio[825]: info: Loading plugin "albumart"... Feb 10 12:28:09 pecanpiplus sudo[1349]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:09 pecanpiplus sudo[1340]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:09 pecanpiplus volumio[825]: info: Plugin example_plugin is not enabled Feb 10 12:28:09 pecanpiplus sudo[1346]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:09 pecanpiplus volumio[825]: info: Loading plugin "hi_res_audio"... Feb 10 12:28:09 pecanpiplus sudo[1343]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:10 pecanpiplus volumio[825]: Forking 3 albumart workers Feb 10 12:28:11 pecanpiplus volumio[825]: Starting albumart workers Feb 10 12:28:11 pecanpiplus volumio[825]: Starting albumart workers Feb 10 12:28:11 pecanpiplus volumio[825]: Starting albumart workers Feb 10 12:28:12 pecanpiplus volumio[825]: info: Applying required configuration parameters for plugin hi_res_audio Feb 10 12:28:12 pecanpiplus volumio[825]: info: Loading plugin "inputs"... Feb 10 12:28:12 pecanpiplus volumio[825]: info: Loading plugin "qobuz"... Feb 10 12:28:13 pecanpiplus systemd[1]: systemd-timedated.service: Succeeded. Feb 10 12:28:14 pecanpiplus volumio[825]: info: Loading plugin "smart_inputs"... Feb 10 12:28:14 pecanpiplus dbus-daemon[638]: [system] Failed to activate service 'org.bluez': timed out (service_start_timeout=25000ms) Feb 10 12:28:14 pecanpiplus volumio[825]: info: Loading plugin "tidal"... Feb 10 12:28:14 pecanpiplus volumio-remote-updater[619]: [2026-02-10 12:28:14] [connect] Successful connection Feb 10 12:28:16 pecanpiplus volumio[825]: info: Loading plugin "acontrol"... Feb 10 12:28:16 pecanpiplus volumio[825]: info: Adding this device properties Feb 10 12:28:16 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Feb 10 12:28:16 pecanpiplus volumio[825]: info: Setting Additional Device Volumio Properties: [object Object] Feb 10 12:28:16 pecanpiplus volumio[825]: info: Loading plugin "oem_helper"... Feb 10 12:28:17 pecanpiplus volumio[825]: info: Applying required configuration parameters for plugin oem_helper Feb 10 12:28:17 pecanpiplus volumio[825]: info: Loading plugin "updater_comm"... Feb 10 12:28:17 pecanpiplus volumio[825]: info: Plugin mpdemulation is not enabled Feb 10 12:28:17 pecanpiplus volumio[825]: info: Loading plugin "rest_api"... Feb 10 12:28:17 pecanpiplus volumio[825]: info: Loading plugin "websocket"... Feb 10 12:28:17 pecanpiplus volumio[825]: info: Starting Socket.io Server version 2.3.0 Feb 10 12:28:17 pecanpiplus volumio[825]: info: Loading plugin "fusiondsp"... Feb 10 12:28:18 pecanpiplus volumio[825]: info: Applying required configuration parameters for plugin fusiondsp Feb 10 12:28:18 pecanpiplus volumio[825]: info: Loading plugin "Systeminfo"... Feb 10 12:28:19 pecanpiplus volumio[825]: info: Loading i18n strings for locale nl Feb 10 12:28:19 pecanpiplus volumio[825]: Updating browse sources language Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::initPlayerControls Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 12:28:19 pecanpiplus volumio[825]: Express server listening on port 3000 Feb 10 12:28:19 pecanpiplus volumio[825]: [Metrics] WebUI: 42s 149.98ms Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreStateMachine::resetVolumioState Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreStateMachine::getcurrentVolume Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreStateMachine::pushState Feb 10 12:28:19 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioPushState Feb 10 12:28:19 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:19 pecanpiplus sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 12:28:19 pecanpiplus sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:19 pecanpiplus sudo[1424]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:19 pecanpiplus sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 12:28:19 pecanpiplus sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:19 pecanpiplus volumio[825]: info: Volumio Network Manager: Network status updated: 1 Feb 10 12:28:19 pecanpiplus volumio[825]: info: MRS: Removed streaming files Feb 10 12:28:19 pecanpiplus volumio[825]: info: MRS: volumioStreaming STOPPED Feb 10 12:28:19 pecanpiplus volumio[825]: info: MRS: SNAPSERVER STOPPED Feb 10 12:28:19 pecanpiplus volumio[825]: info: MRS: SNAPCLIENT STOPPED Feb 10 12:28:19 pecanpiplus volumio[825]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 10 12:28:19 pecanpiplus volumio[825]: wlan0 Interface doesn't support scanning. Feb 10 12:28:19 pecanpiplus volumio[825]: info: Cannot use regular scanning, forcing with ap-force Feb 10 12:28:19 pecanpiplus sudo[1426]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:19 pecanpiplus sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 10 12:28:19 pecanpiplus sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:19 pecanpiplus sudo[1432]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:19 pecanpiplus volumio[825]: command failed: No such device (-19) Feb 10 12:28:19 pecanpiplus volumio[825]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 10 12:28:19 pecanpiplus volumio[825]: command failed: No such device (-19) Feb 10 12:28:20 pecanpiplus volumio[825]: info: Reloading queue from file Feb 10 12:28:20 pecanpiplus volumio[825]: info: Setting Device type: Raspberry PI Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreStateMachine::setRepeat null single undefined Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreStateMachine::pushState Feb 10 12:28:20 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioPushState Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreStateMachine::setRandom null Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreStateMachine::pushState Feb 10 12:28:20 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioPushState Feb 10 12:28:20 pecanpiplus volumio[825]: info: Completed loading Core Plugins Feb 10 12:28:20 pecanpiplus volumio[825]: info: Preparing to generate the ALSA configuration file Feb 10 12:28:20 pecanpiplus sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 12:28:20 pecanpiplus sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:20 pecanpiplus systemd[1]: Started UPnP Renderer front-end to MPD. Feb 10 12:28:20 pecanpiplus sudo[1442]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:20 pecanpiplus volumio[825]: info: Discovery: adding 57d77d0b-b424-4c56-b4f0-e35d5784213e Feb 10 12:28:20 pecanpiplus volumio[825]: info: Discovery: Found device PecanPiPlus Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:20 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:20 pecanpiplus volumio[825]: info: MRS: Pushing multiroomSync output for this device Feb 10 12:28:20 pecanpiplus volumio[825]: info: MRS: Pushing multiroomSync output Feb 10 12:28:20 pecanpiplus volumio[825]: info: Adding audio output: Feb 10 12:28:20 pecanpiplus volumio[825]: info: Adding audio output: Feb 10 12:28:20 pecanpiplus volumio[825]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 10 12:28:20 pecanpiplus volumio[825]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 10 12:28:20 pecanpiplus volumio[825]: info: Reading ALSA contributions from plugins. Feb 10 12:28:20 pecanpiplus volumio[825]: info: Upmpdcli Daemon Started Feb 10 12:28:20 pecanpiplus volumio[825]: info: Asound.conf file unchanged, so no further update is needed Feb 10 12:28:20 pecanpiplus volumio[825]: info: Output device has changed, restarting MPD Feb 10 12:28:20 pecanpiplus sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 12:28:20 pecanpiplus sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:20 pecanpiplus volumio[825]: info: Output device has changed, restarting Shairport Sync Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:20 pecanpiplus sudo[1447]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:20 pecanpiplus sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 12:28:20 pecanpiplus sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:20 pecanpiplus volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 12:28:20 pecanpiplus volumio[825]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:20 pecanpiplus volumio[825]: info: ___________ START PLUGINS ___________ Feb 10 12:28:20 pecanpiplus volumio[825]: info: ControllerMpd::onStart: Initializing MPD Feb 10 12:28:20 pecanpiplus volumio[825]: info: Creating MPD Configuration file Feb 10 12:28:20 pecanpiplus systemd[1]: Listening on mpd.socket. Feb 10 12:28:20 pecanpiplus systemd[1]: Starting Music Player Daemon... Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 12:28:20 pecanpiplus volumio[825]: info: [1770726500929] CoreMusicLibrary::Adding element Media Servers Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 12:28:20 pecanpiplus sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 12:28:20 pecanpiplus sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 12:28:20 pecanpiplus sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:20 pecanpiplus sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:20 pecanpiplus sudo[1460]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:20 pecanpiplus volumio[825]: info: Adding METAVOLUMIO REST API Endpoints Feb 10 12:28:20 pecanpiplus volumio[825]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 10 12:28:20 pecanpiplus volumio[825]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 10 12:28:20 pecanpiplus volumio[825]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 10 12:28:20 pecanpiplus volumio[825]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:20 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:21 pecanpiplus sudo[1458]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 12:28:21 pecanpiplus sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:21 pecanpiplus sudo[1458]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:21 pecanpiplus systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 10 12:28:21 pecanpiplus systemd[1]: mpd.service: Succeeded. Feb 10 12:28:21 pecanpiplus systemd[1]: Stopped Music Player Daemon. Feb 10 12:28:21 pecanpiplus volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:21 pecanpiplus volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 12:28:21 pecanpiplus volumio[825]: info: Preparing CD Folders Feb 10 12:28:21 pecanpiplus volumio[825]: info: Adding CD REST API Endpoints Feb 10 12:28:21 pecanpiplus volumio[825]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 10 12:28:21 pecanpiplus volumio[825]: info: Starting UDEV Watcher for CD Feb 10 12:28:21 pecanpiplus volumio[825]: info: Detecting CD presence with UDEV Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 10 12:28:21 pecanpiplus systemd[1]: Starting Music Player Daemon... Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 12:28:21 pecanpiplus volumio[825]: info: [1770726501145] CoreMusicLibrary::Adding element Last_100 Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 12:28:21 pecanpiplus volumio[825]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 10 12:28:21 pecanpiplus volumio[825]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 10 12:28:21 pecanpiplus sudo[1469]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 12:28:21 pecanpiplus sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:21 pecanpiplus sudo[1469]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 12:28:21 pecanpiplus sudo[1469]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:21 pecanpiplus volumio[825]: info: Starting RAAT Plugin Feb 10 12:28:21 pecanpiplus sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 10 12:28:21 pecanpiplus sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 10 12:28:21 pecanpiplus volumio[825]: info: Additional UI Settings Added for plugin music_service/raat Feb 10 12:28:21 pecanpiplus volumio[825]: info: Registering DSP Elements listener and retrieving current ones Feb 10 12:28:21 pecanpiplus volumio[825]: info: Additional DSP elements updated Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:21 pecanpiplus volumio[825]: info: Updating RAAT Signal Path Feb 10 12:28:21 pecanpiplus volumio[825]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 10 12:28:21 pecanpiplus volumio[825]: info: Streaming services startup Feb 10 12:28:21 pecanpiplus volumio[825]: info: Starting Streaming Daemon Feb 10 12:28:21 pecanpiplus sudo[1477]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:21 pecanpiplus sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 10 12:28:21 pecanpiplus sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:21 pecanpiplus systemd[1]: Started Volumio Qobuz Connect Service. Feb 10 12:28:21 pecanpiplus sudo[1483]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:21 pecanpiplus sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 10 12:28:21 pecanpiplus sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 12:28:21 pecanpiplus volumio[825]: info: [1770726501569] CoreMusicLibrary::Adding element Webradio Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 12:28:21 pecanpiplus volumio[825]: info: Initializing BBC Radios Feb 10 12:28:21 pecanpiplus sudo[1486]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:21 pecanpiplus sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 10 12:28:21 pecanpiplus sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:21 pecanpiplus sudo[1494]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:21 pecanpiplus volumio[825]: info: Creating Spotify config file Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 12:28:21 pecanpiplus volumio[825]: info: [1770726501893] CoreMusicLibrary::Adding element YouTube Music Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 12:28:21 pecanpiplus volumio[825]: Cannot find translation for source YouTube Music Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 10 12:28:21 pecanpiplus volumio[825]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 10 12:28:21 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:22 pecanpiplus volumio[825]: error: Hi Res Audio Failed Login: Missing Login Data Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding inputs REST Endpoints Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 10 12:28:22 pecanpiplus volumio[825]: info: Scanning Audio Inputs Feb 10 12:28:22 pecanpiplus volumio[825]: info: Checking against Known Cards name Feb 10 12:28:22 pecanpiplus volumio[825]: info: Checking against Known Cards name Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding Server instance for streaming Feb 10 12:28:22 pecanpiplus volumio[825]: info: Enabling Advanced system settings configuration Feb 10 12:28:22 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Feb 10 12:28:22 pecanpiplus volumio[825]: info: Additional UI Settings Added for plugin system_controller/acontrol Feb 10 12:28:22 pecanpiplus volumio[825]: info: Touch Event Listener Process Starting Feb 10 12:28:22 pecanpiplus sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Feb 10 12:28:22 pecanpiplus sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:22 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 12:28:22 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:22 pecanpiplus volumio[825]: info: Loading i18n strings for locale nl Feb 10 12:28:22 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 10 12:28:22 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:22 pecanpiplus volumio[825]: info: FusionDsp - mixtype--------------------- None Feb 10 12:28:22 pecanpiplus volumio[825]: info: Volumio Calling Home Feb 10 12:28:22 pecanpiplus volumio[825]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 10 12:28:22 pecanpiplus volumio[825]: (node:825) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Feb 10 12:28:22 pecanpiplus volumio[825]: (Use `node --trace-deprecation ...` to show where the warning was created) Feb 10 12:28:22 pecanpiplus volumio[825]: info: Preparing to generate the ALSA configuration file Feb 10 12:28:22 pecanpiplus volumio[825]: info: Stopping AccessToken refresher cron for QOBUZ Feb 10 12:28:22 pecanpiplus volumio[825]: info: AccessToken refresher cron started for QOBUZ Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding TIDAL REST API Endpoints Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding QOBUZ REST API Endpoints Feb 10 12:28:22 pecanpiplus volumio[825]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Feb 10 12:28:23 pecanpiplus volumio[825]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 10 12:28:23 pecanpiplus volumio[825]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 10 12:28:23 pecanpiplus volumio[825]: info: Reading ALSA contributions from plugins. Feb 10 12:28:23 pecanpiplus volumio[825]: error: Cannot start Volumio Streaming Daemon Feb 10 12:28:23 pecanpiplus volumio[825]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 10 12:28:23 pecanpiplus volumio[825]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 10 12:28:23 pecanpiplus volumio[825]: info: RAAT Albumart path created successfully Feb 10 12:28:23 pecanpiplus volumio[825]: info: MPD Permissions set Feb 10 12:28:23 pecanpiplus volumio[825]: info: MPD Permissions set Feb 10 12:28:23 pecanpiplus volumio[825]: info: Executing endpoint qc_getconfig Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.586 [1487.1487] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 10 12:28:23 pecanpiplus volumio[825]: info: Spotify config file written Feb 10 12:28:23 pecanpiplus sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 10 12:28:23 pecanpiplus sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:23 pecanpiplus systemd[1]: Started go-librespot Daemon. Feb 10 12:28:23 pecanpiplus sudo[1550]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:23 pecanpiplus go-librespot[1559]: go-librespot daemon starting... Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.899 [1487.1487] INFO VolumeManager: [0x6e1280]: Setting new playback volume: 75 Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.899 [1487.1487] INFO VolumeManager: [0x6e1280]: Setting new mute state: 0 Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.899 [1487.1487] INFO QobuzConnect: [0x6e1c50]: Client initialized! Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.899 [1487.1487] INFO SampleApp: Starting Avahi advertising, name: PecanPiPlus, service name: _qobuz-connect._tcp Feb 10 12:28:23 pecanpiplus volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.913 [1487.1487] INFO LocalConfigManager: [0x6e0b60]: Starting Local Configuration server Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.913 [1487.1487] INFO SampleApp: Starting Local configuration server Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.913 [1487.1487] INFO SampleApp: Playback volume changed: 75 Feb 10 12:28:23 pecanpiplus qobuz-connect[1487]: 20260210 12:28:23.914 [1487.1487] INFO SampleApp: Connected to UNIX socket client 0x6d6f60 Feb 10 12:28:23 pecanpiplus volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 10 12:28:23 pecanpiplus volumio[825]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 10 12:28:23 pecanpiplus volumio[825]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 10 12:28:23 pecanpiplus volumio[825]: info: Volumio called home Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:23 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: No need to fix Spotify hosts Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:24 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:24 pecanpiplus volumio[825]: info: Starting Shairport Sync Feb 10 12:28:24 pecanpiplus volumio[825]: info: Starting Shairport Sync Feb 10 12:28:24 pecanpiplus volumio[825]: info: Starting Shairport Sync Feb 10 12:28:24 pecanpiplus sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 12:28:24 pecanpiplus sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:24 pecanpiplus sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 12:28:24 pecanpiplus sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:24 pecanpiplus sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 12:28:24 pecanpiplus sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:24 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:24 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:24 pecanpiplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 10 12:28:24 pecanpiplus volumio[825]: info: camilladsp spawned new process with pid 1592, instance 1, run: true Feb 10 12:28:24 pecanpiplus volumio[825]: info: camilladsp service started and running in background, instance 1 Feb 10 12:28:24 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:24 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:24Z" level=info msg="running go-librespot 0.4.0" Feb 10 12:28:24 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:24Z" level=debug msg="app state loaded" Feb 10 12:28:24 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:24Z" level=debug msg="stored credentials not found" Feb 10 12:28:24 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:24Z" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 12:28:24 pecanpiplus volumio[825]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Feb 10 12:28:25 pecanpiplus volumio[825]: info: FusionDsp loaded Feb 10 12:28:25 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 12:28:25 pecanpiplus sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 10 12:28:25 pecanpiplus sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:25 pecanpiplus volumio[825]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 10 12:28:25 pecanpiplus volumio[825]: info: Adding Signal Path Element [object Object] Feb 10 12:28:25 pecanpiplus volumio[825]: info: Adding fusiondspeq DSP Signal Path Element Feb 10 12:28:25 pecanpiplus volumio[825]: info: Additional DSP elements updated Feb 10 12:28:25 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:25 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:25 pecanpiplus volumio[825]: info: Updating RAAT Signal Path Feb 10 12:28:25 pecanpiplus volumio[825]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 10 12:28:25 pecanpiplus volumio[825]: info: FusionDsp - ---- installed callbackRead Feb 10 12:28:25 pecanpiplus systemd[1]: shairport-sync.service: Succeeded. Feb 10 12:28:25 pecanpiplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 10 12:28:25 pecanpiplus volumio[825]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 10 12:28:25 pecanpiplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 10 12:28:25 pecanpiplus volumio[825]: info: MRS: Found cast device: KPN-DIW7022-e092a438b4a4106c78bad47177d1e35d Feb 10 12:28:25 pecanpiplus volumio[825]: info: Adding audio output: Feb 10 12:28:25 pecanpiplus volumio[825]: info: MRS: Found cast device: KPN-DIW7022-0af049d26979577d4b53624e9623cbaa Feb 10 12:28:25 pecanpiplus volumio[825]: info: Adding audio output: Feb 10 12:28:25 pecanpiplus sudo[1578]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:25 pecanpiplus sudo[1580]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:25 pecanpiplus sudo[1582]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:25 pecanpiplus volumio[825]: ACTIVITY STATUS CHANGED TO: 0 Feb 10 12:28:25 pecanpiplus volumio[825]: info: TidalConnect service stoped! Feb 10 12:28:25 pecanpiplus volumio[825]: info: Asound.conf file unchanged, so no further update is needed Feb 10 12:28:25 pecanpiplus volumio[825]: info: Output device has changed, restarting MPD Feb 10 12:28:25 pecanpiplus sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 12:28:25 pecanpiplus sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:25 pecanpiplus volumio[825]: info: Output device has changed, restarting Shairport Sync Feb 10 12:28:25 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:25 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:25 pecanpiplus sudo[1611]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:25 pecanpiplus systemd[1]: Started FusionDsp Daemon. Feb 10 12:28:25 pecanpiplus sudo[1596]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:25 pecanpiplus sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 12:28:25 pecanpiplus sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:25 pecanpiplus volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 12:28:25 pecanpiplus volumio[825]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 10 12:28:25 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:25 pecanpiplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 10 12:28:25 pecanpiplus systemd[1]: mpd.service: Succeeded. Feb 10 12:28:25 pecanpiplus systemd[1]: Stopped Music Player Daemon. Feb 10 12:28:25 pecanpiplus volumio[825]: info: QobuzConnect: setDeactiveState invoked Feb 10 12:28:25 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:25 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:25 pecanpiplus volumio[825]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 10 12:28:25 pecanpiplus volumio[825]: info: Preparing to generate the ALSA configuration file Feb 10 12:28:25 pecanpiplus systemd[1]: Starting Music Player Daemon... Feb 10 12:28:25 pecanpiplus sudo[1622]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 12:28:25 pecanpiplus sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:25 pecanpiplus sudo[1622]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 12:28:25 pecanpiplus sudo[1622]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:26 pecanpiplus volumio[825]: info: Shairport-Sync Started Feb 10 12:28:26 pecanpiplus volumio[825]: Error adding Membership: Error: addMembership EINVAL Feb 10 12:28:26 pecanpiplus sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 10 12:28:26 pecanpiplus volumio[825]: info: Shairport-Sync Started Feb 10 12:28:26 pecanpiplus sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:26 pecanpiplus sudo[1631]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:26 pecanpiplus sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 10 12:28:26 pecanpiplus sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:26 pecanpiplus qobuz-connect[1487]: 20260210 12:28:26.633 [1487.1487] INFO SampleApp: Stopping Local configuration server Feb 10 12:28:26 pecanpiplus systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 10 12:28:26 pecanpiplus qobuz-connect[1487]: 20260210 12:28:26.644 [1487.1487] INFO SampleApp: shat down connection on UNIX socket Feb 10 12:28:26 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:26 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:26 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:26 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:26 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 12:28:26 pecanpiplus volumio[825]: info: Not Reporting Auto name since its the default one Feb 10 12:28:26 pecanpiplus systemd[1]: qobuz-connect.service: Succeeded. Feb 10 12:28:26 pecanpiplus systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 10 12:28:26 pecanpiplus volumio[825]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 10 12:28:26 pecanpiplus volumio[825]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 10 12:28:26 pecanpiplus volumio[825]: info: Reading ALSA contributions from plugins. Feb 10 12:28:26 pecanpiplus systemd[1]: Started Volumio Qobuz Connect Service. Feb 10 12:28:26 pecanpiplus sudo[1633]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:26 pecanpiplus volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 10 12:28:26 pecanpiplus volumio[825]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 10 12:28:26 pecanpiplus volumio[825]: info: MPD Permissions set Feb 10 12:28:26 pecanpiplus volumio[825]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 10 12:28:26 pecanpiplus volumio[825]: info: QobuzConnect: setDeactiveState invoked Feb 10 12:28:26 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:26 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:26 pecanpiplus volumio[825]: info: Shairport-Sync Started Feb 10 12:28:26 pecanpiplus volumio[825]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 10 12:28:27 pecanpiplus volumio[825]: info: MRS: Getting audio outputs on start Feb 10 12:28:27 pecanpiplus volumio[825]: info: MRS: Requesting all other devices output Feb 10 12:28:27 pecanpiplus volumio[825]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 10 12:28:27 pecanpiplus volumio[825]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:27 pecanpiplus sudo[1647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 10 12:28:27 pecanpiplus sudo[1647]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:27 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:27Z" 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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 12:28:27 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:27Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 10 12:28:27 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:27Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 10 12:28:27 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:27Z" level=info msg="zeroconf server listening on port 44139" Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:27 pecanpiplus systemd[1]: Started Volumio Tidal Connect Service. Feb 10 12:28:27 pecanpiplus sudo[1647]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:27 pecanpiplus volumio[825]: info: Executing endpoint qc_getconfig Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.338 [1639.1639] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.346 [1639.1639] INFO VolumeManager: [0x126b280]: Setting new playback volume: 75 Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.346 [1639.1639] INFO VolumeManager: [0x126b280]: Setting new mute state: 0 Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.346 [1639.1639] INFO QobuzConnect: [0x126bc50]: Client initialized! Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.346 [1639.1639] INFO SampleApp: Starting Avahi advertising, name: PecanPiPlus, service name: _qobuz-connect._tcp Feb 10 12:28:27 pecanpiplus volumio[825]: info: go-librespot daemon successfully initialized Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.411 [1639.1639] INFO LocalConfigManager: [0x126ab60]: Starting Local Configuration server Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.411 [1639.1639] INFO SampleApp: Starting Local configuration server Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.423 [1639.1639] INFO SampleApp: Connected to UNIX socket client 0x1260f60 Feb 10 12:28:27 pecanpiplus qobuz-connect[1639]: 20260210 12:28:27.488 [1639.1639] INFO SampleApp: Playback volume changed: 75 Feb 10 12:28:27 pecanpiplus sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 10 12:28:27 pecanpiplus sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:27 pecanpiplus sudo[1662]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:27 pecanpiplus sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 10 12:28:27 pecanpiplus sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:27 pecanpiplus volumio[825]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 10 12:28:27 pecanpiplus volumio[825]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 10 12:28:27 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:27 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:27 pecanpiplus volumio[825]: info: Starting Shairport Sync Feb 10 12:28:27 pecanpiplus systemd[1]: Started RAAT DAEMON. Feb 10 12:28:27 pecanpiplus sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 12:28:27 pecanpiplus sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:27 pecanpiplus volumio[825]: info: Asound.conf file unchanged, so no further update is needed Feb 10 12:28:27 pecanpiplus volumio[825]: info: Output device has changed, restarting MPD Feb 10 12:28:27 pecanpiplus sudo[1664]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:27 pecanpiplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 10 12:28:27 pecanpiplus systemd[1]: shairport-sync.service: Succeeded. Feb 10 12:28:27 pecanpiplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 10 12:28:27 pecanpiplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 10 12:28:28 pecanpiplus sudo[1668]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:28 pecanpiplus sudo[1674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 12:28:28 pecanpiplus sudo[1674]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:28 pecanpiplus volumio[825]: info: Output device has changed, restarting Shairport Sync Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:28 pecanpiplus sudo[1674]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:28 pecanpiplus sudo[1684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 12:28:28 pecanpiplus sudo[1684]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:28 pecanpiplus volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 12:28:28 pecanpiplus volumio[825]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:28 pecanpiplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 10 12:28:28 pecanpiplus systemd[1]: mpd.service: Succeeded. Feb 10 12:28:28 pecanpiplus systemd[1]: Stopped Music Player Daemon. Feb 10 12:28:28 pecanpiplus volumio[825]: info: QobuzConnect: setDeactiveState invoked Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:28 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:28 pecanpiplus volumio[825]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 10 12:28:28 pecanpiplus volumio[825]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} Feb 10 12:28:28 pecanpiplus volumio[825]: info: Preparing to generate the ALSA configuration file Feb 10 12:28:28 pecanpiplus systemd[1]: Starting Music Player Daemon... Feb 10 12:28:28 pecanpiplus sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 10 12:28:28 pecanpiplus sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:28 pecanpiplus sudo[1691]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 12:28:28 pecanpiplus sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:28 pecanpiplus systemd[1]: Stopping Volumio Tidal Connect Service... Feb 10 12:28:28 pecanpiplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 10 12:28:28 pecanpiplus sudo[1691]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 12:28:28 pecanpiplus sudo[1691]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:28 pecanpiplus volumio[825]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 10 12:28:28 pecanpiplus volumio[825]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 10 12:28:28 pecanpiplus volumio[825]: info: Reading ALSA contributions from plugins. Feb 10 12:28:28 pecanpiplus sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 10 12:28:28 pecanpiplus sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:28 pecanpiplus volumio[825]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 10 12:28:28 pecanpiplus volumio[825]: info: MPD Permissions set Feb 10 12:28:28 pecanpiplus volumio[825]: info: Shairport-Sync Started Feb 10 12:28:28 pecanpiplus volumio[825]: info: Raat Daemon started successfully Feb 10 12:28:28 pecanpiplus volumio[825]: info: Executing endpoint restartRAATSocket Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 10 12:28:28 pecanpiplus systemd[1]: vtcs.service: Succeeded. Feb 10 12:28:28 pecanpiplus systemd[1]: Stopped Volumio Tidal Connect Service. Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:28 pecanpiplus sudo[1695]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:28 pecanpiplus sudo[1702]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:28 pecanpiplus sudo[1710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 10 12:28:28 pecanpiplus sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:28 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:28 pecanpiplus systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 10 12:28:28 pecanpiplus qobuz-connect[1639]: 20260210 12:28:28.679 [1639.1639] INFO SampleApp: Stopping Local configuration server Feb 10 12:28:28 pecanpiplus qobuz-connect[1639]: 20260210 12:28:28.689 [1639.1639] INFO SampleApp: shat down connection on UNIX socket Feb 10 12:28:28 pecanpiplus systemd[1]: qobuz-connect.service: Succeeded. Feb 10 12:28:28 pecanpiplus systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 10 12:28:28 pecanpiplus volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 10 12:28:28 pecanpiplus systemd[1]: Started Volumio Qobuz Connect Service. Feb 10 12:28:28 pecanpiplus volumio[825]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 10 12:28:28 pecanpiplus volumio[825]: info: QobuzConnect: setDeactiveState invoked Feb 10 12:28:28 pecanpiplus sudo[1710]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:28 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:28 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 12:28:29 pecanpiplus volumio[825]: info: Not Reporting Auto name since its the default one Feb 10 12:28:29 pecanpiplus volumio[825]: info: Starting Shairport Sync Feb 10 12:28:29 pecanpiplus sudo[1720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 12:28:29 pecanpiplus sudo[1720]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:29 pecanpiplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 10 12:28:29 pecanpiplus systemd[1]: shairport-sync.service: Succeeded. Feb 10 12:28:29 pecanpiplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 10 12:28:29 pecanpiplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 10 12:28:29 pecanpiplus volumio[825]: info: Executing endpoint qc_getconfig Feb 10 12:28:29 pecanpiplus sudo[1720]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.449 [1713.1713] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.459 [1713.1713] INFO VolumeManager: [0xc4c280]: Setting new playback volume: 75 Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.459 [1713.1713] INFO VolumeManager: [0xc4c280]: Setting new mute state: 0 Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.459 [1713.1713] INFO QobuzConnect: [0xc4cc50]: Client initialized! Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.459 [1713.1713] INFO SampleApp: Starting Avahi advertising, name: PecanPiPlus, service name: _qobuz-connect._tcp Feb 10 12:28:29 pecanpiplus volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 5 Feb 10 12:28:29 pecanpiplus volumio[825]: info: Asound.conf file unchanged, so no further update is needed Feb 10 12:28:29 pecanpiplus volumio[825]: info: Output device has changed, restarting MPD Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.486 [1713.1713] INFO LocalConfigManager: [0xc4bb60]: Starting Local Configuration server Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.486 [1713.1713] INFO SampleApp: Starting Local configuration server Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.487 [1713.1713] INFO SampleApp: Connected to UNIX socket client 0xc41f60 Feb 10 12:28:29 pecanpiplus sudo[1729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 12:28:29 pecanpiplus sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:29 pecanpiplus sudo[1729]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:29 pecanpiplus volumio[825]: info: Output device has changed, restarting Shairport Sync Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:29 pecanpiplus qobuz-connect[1713]: 20260210 12:28:29.598 [1713.1713] INFO SampleApp: Playback volume changed: 75 Feb 10 12:28:29 pecanpiplus sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 12:28:29 pecanpiplus sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:29 pecanpiplus volumio-remote-updater[619]: [2026-02-10 12:28:29] [connect] Successful connection Feb 10 12:28:29 pecanpiplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 10 12:28:29 pecanpiplus systemd[1]: mpd.service: Succeeded. Feb 10 12:28:29 pecanpiplus systemd[1]: Stopped Music Player Daemon. Feb 10 12:28:29 pecanpiplus volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 12:28:29 pecanpiplus volumio[825]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:29 pecanpiplus systemd[1]: Starting Music Player Daemon... Feb 10 12:28:29 pecanpiplus volumio[825]: info: QobuzConnect: setDeactiveState invoked Feb 10 12:28:29 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:29 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:29 pecanpiplus volumio[825]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 10 12:28:29 pecanpiplus volumio[825]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} Feb 10 12:28:29 pecanpiplus volumio[825]: info: Preparing to generate the ALSA configuration file Feb 10 12:28:29 pecanpiplus sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 10 12:28:29 pecanpiplus sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:29 pecanpiplus sudo[1737]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 12:28:29 pecanpiplus sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:29 pecanpiplus sudo[1737]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 12:28:29 pecanpiplus sudo[1737]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:30 pecanpiplus volumio[825]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 10 12:28:30 pecanpiplus volumio[825]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 10 12:28:30 pecanpiplus volumio[825]: info: Shairport-Sync Started Feb 10 12:28:30 pecanpiplus volumio[825]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 10 12:28:30 pecanpiplus volumio[825]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 10 12:28:30 pecanpiplus volumio[825]: info: Reading ALSA contributions from plugins. Feb 10 12:28:30 pecanpiplus sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 10 12:28:30 pecanpiplus sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:30 pecanpiplus sudo[1741]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:30 pecanpiplus sudo[1749]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:30 pecanpiplus volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:30 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:30 pecanpiplus volumio[825]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Feb 10 12:28:30 pecanpiplus volumio[825]: info: MPD Permissions set Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 10 12:28:30 pecanpiplus sudo[1752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 10 12:28:30 pecanpiplus sudo[1752]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:30 pecanpiplus qobuz-connect[1713]: 20260210 12:28:30.189 [1713.1713] INFO SampleApp: Stopping Local configuration server Feb 10 12:28:30 pecanpiplus systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 10 12:28:30 pecanpiplus qobuz-connect[1713]: 20260210 12:28:30.200 [1713.1713] INFO SampleApp: shat down connection on UNIX socket Feb 10 12:28:30 pecanpiplus systemd[1]: qobuz-connect.service: Succeeded. Feb 10 12:28:30 pecanpiplus systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 10 12:28:30 pecanpiplus systemd[1]: Started Volumio Qobuz Connect Service. Feb 10 12:28:30 pecanpiplus sudo[1752]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:30 pecanpiplus volumio-remote-updater[619]: [2026-02-10 12:28:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1770726509 101 Feb 10 12:28:30 pecanpiplus sudo[1759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 10 12:28:30 pecanpiplus sudo[1759]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:30 pecanpiplus sudo[1759]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:30 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:30Z" level=debug msg="obtained new client token: AADyp2KJQw5bnK+AmegImUKevtXM9m7C8/kyyTNYJj4l2GWoZUXKvUbYXI+U03UpSBe/B7321C3uY17/PbCQU8SSHJj9KWaAj5h8FJ9GOp0oFmk2fOpoxy3zVg+pQ6uFtkKOFuKc8Xf4KLOaz9fqGWWoKfiFuND7cUrPMBKPyl6YY4Xah4GQOaDpnoQSrXPP7uOKcemDBbMzh1sgfNIt/Fq3jaOu20ZhV6VBNTjqW29C4s8nar+YXPHQSA==" Feb 10 12:28:30 pecanpiplus sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 10 12:28:30 pecanpiplus sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 12:28:30 pecanpiplus volumio[825]: info: Not Reporting Auto name since its the default one Feb 10 12:28:30 pecanpiplus volumio[825]: info: Initializing connection to go-librespot Websocket Feb 10 12:28:30 pecanpiplus volumio[825]: info: RAAT: Requesting Headphone Status Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 10 12:28:30 pecanpiplus volumio[825]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getHeadphoneStatus] in plugin inputs Feb 10 12:28:30 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:30Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 10 12:28:30 pecanpiplus volumio[825]: info: TidalConnect service started! Feb 10 12:28:30 pecanpiplus volumio[825]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 10 12:28:30 pecanpiplus volumio[825]: info: QobuzConnect: setDeactiveState invoked Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:30 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:30 pecanpiplus systemd[1]: Stopping RAAT DAEMON... Feb 10 12:28:30 pecanpiplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 10 12:28:30 pecanpiplus systemd[1]: raat-daemon.service: Succeeded. Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:30 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:30 pecanpiplus systemd[1]: Stopped RAAT DAEMON. Feb 10 12:28:30 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:30Z" level=debug msg="completed keyexchange" Feb 10 12:28:30 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:30Z" level=debug msg="completed challenge" Feb 10 12:28:30 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:30Z" level=info msg="authenticated AP" username="11******84" Feb 10 12:28:31 pecanpiplus systemd[1]: Started RAAT DAEMON. Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:31 pecanpiplus sudo[1765]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="new websocket client" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=info msg="authenticated Login5" username="11******84" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=info msg="accepted zeroconf from S24 van ralf" username="11******84" Feb 10 12:28:31 pecanpiplus volumio[825]: info: Executing endpoint qc_getconfig Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="dealer connection opened" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=trace msg="starting accesspoint recv loop" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=trace msg="starting dealer recv loop" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=trace msg="received accesspoint ping" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=trace msg="received accesspoint pong ack" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="received connection id: NDVmYzc5OWUtNDE2...NTZFMjE4MjhCOQ==" Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.257 [1756.1756] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.273 [1756.1756] INFO VolumeManager: [0x1ce4280]: Setting new playback volume: 75 Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.274 [1756.1756] INFO VolumeManager: [0x1ce4280]: Setting new mute state: 0 Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.274 [1756.1756] INFO QobuzConnect: [0x1ce4c50]: Client initialized! Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.274 [1756.1756] INFO SampleApp: Starting Avahi advertising, name: PecanPiPlus, service name: _qobuz-connect._tcp Feb 10 12:28:31 pecanpiplus volumio[825]: info: Connection to go-librespot Websocket established Feb 10 12:28:31 pecanpiplus volumio[825]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 10 12:28:31 pecanpiplus volumio[825]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 10 12:28:31 pecanpiplus volumio[825]: info: Raat Daemon started successfully Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.333 [1756.1756] INFO LocalConfigManager: [0x1ce3b60]: Starting Local Configuration server Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.333 [1756.1756] INFO SampleApp: Starting Local configuration server Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.333 [1756.1756] INFO SampleApp: Connected to UNIX socket client 0x1cd9f60 Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="put connect state because NEW_DEVICE" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="update volume requested to 65535/65535" Feb 10 12:28:31 pecanpiplus qobuz-connect[1756]: 20260210 12:28:31.407 [1756.1756] INFO SampleApp: Playback volume changed: 75 Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="put connect state because VOLUME_CHANGED" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=trace msg="emitting websocket event: volume" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="handling transfer player command from d57731a00142bd2879768409e947a6b873661412" Feb 10 12:28:31 pecanpiplus volumio[825]: info: Starting Shairport Sync Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZEVXcRpjfyEarvAe" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=trace msg="fetched new page 0 with 30 items (list: 30)" uri="spotify:playlist:37i9dQZEVXcRpjfyEarvAe" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="loading track (paused: true, position: 97822ms)" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:31 pecanpiplus sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 10 12:28:31 pecanpiplus sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=trace msg="emitting websocket event: will_play" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="selected format OGG_VORBIS_320 (c3d7f2005063ef39c8afcb16907ae1575ec9c5a1)" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="requested aes key for file c3d7f2005063ef39c8afcb16907ae1575ec9c5a1, gid: 1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:31 pecanpiplus sudo[1782]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3526" Feb 10 12:28:31 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:31Z" level=trace msg="found 2 cdn urls" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:31 pecanpiplus sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 12:28:31 pecanpiplus sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:31 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:31 pecanpiplus sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 10 12:28:31 pecanpiplus sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:31 pecanpiplus volumio[825]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Feb 10 12:28:31 pecanpiplus volumio[825]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Feb 10 12:28:31 pecanpiplus volumio[825]: info: Setting Volumio Volume from Spotify: 100 Feb 10 12:28:31 pecanpiplus volumio[825]: info: VolumeController::SetAlsaVolume100 Feb 10 12:28:31 pecanpiplus volumio[825]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZEVXcRpjfyEarvAe","uri":"spotify:track:1zQGO7jITUE8D8w8mry8My","play_origin":"driving-jumpstart"}} Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:31 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreStateMachine::pushState Feb 10 12:28:31 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioPushState Feb 10 12:28:31 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:31 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:31 pecanpiplus volumio[825]: info: MRS: Pushing multiroomSync output update for this device Feb 10 12:28:31 pecanpiplus volumio[825]: info: MRS: Pushing multiroomSync output Feb 10 12:28:31 pecanpiplus volumio[825]: error: Help! Some callbacks for volumioPushState are crashing! Feb 10 12:28:31 pecanpiplus volumio[825]: error: Cannot read property 'sendVolumeMute' of undefined Feb 10 12:28:31 pecanpiplus volumio[825]: info: TidalConnect service stoped! Feb 10 12:28:31 pecanpiplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 10 12:28:31 pecanpiplus systemd[1]: Stopping RAAT DAEMON... Feb 10 12:28:31 pecanpiplus systemd[1]: shairport-sync.service: Succeeded. Feb 10 12:28:31 pecanpiplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 10 12:28:31 pecanpiplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 10 12:28:31 pecanpiplus systemd[1]: raat-daemon.service: Succeeded. Feb 10 12:28:31 pecanpiplus systemd[1]: Stopped RAAT DAEMON. Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="fetched first chunk of 31, total size is 16130192 bytes" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus volumio[825]: ACTIVITY STATUS CHANGED TO: 0 Feb 10 12:28:32 pecanpiplus systemd[1]: Started RAAT DAEMON. Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2209" Feb 10 12:28:32 pecanpiplus sudo[1796]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:32 pecanpiplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 10 12:28:32 pecanpiplus sudo[1792]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:32 pecanpiplus volumio[825]: info: Asound.conf file unchanged, so no further update is needed Feb 10 12:28:32 pecanpiplus volumio[825]: info: Output device has changed, restarting MPD Feb 10 12:28:32 pecanpiplus sudo[1845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 12:28:32 pecanpiplus sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:32 pecanpiplus sudo[1845]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="fetched chunk 1/30, size: 524288" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus volumio[825]: info: Output device has changed, restarting Shairport Sync Feb 10 12:28:32 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:32 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 12:28:32 pecanpiplus sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 12:28:32 pecanpiplus sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:32 pecanpiplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 10 12:28:32 pecanpiplus systemd[1]: mpd.service: Succeeded. Feb 10 12:28:32 pecanpiplus systemd[1]: Stopped Music Player Daemon. Feb 10 12:28:32 pecanpiplus volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 12:28:32 pecanpiplus volumio[825]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 10 12:28:32 pecanpiplus volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 12:28:32 pecanpiplus volumio[825]: info: QobuzConnect: setDeactiveState invoked Feb 10 12:28:32 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:32 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:32 pecanpiplus volumio[825]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 10 12:28:32 pecanpiplus volumio[825]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="fetched chunk 8/30, size: 524288" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=trace msg="seek to 97822ms (diff: 61ms, samples: 4313950, bytes: 4246215)" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="created new output device" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=info msg="loaded track \"Speak Low\" (paused: true, position: 97822ms, duration: 375426ms, prefetched: false)" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus systemd[1]: Starting Music Player Daemon... Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="fetched chunk 2/30, size: 524288" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus sudo[1857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 10 12:28:32 pecanpiplus sudo[1857]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="fetched chunk 11/30, size: 524288" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="fetched chunk 10/30, size: 524288" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="fetched chunk 9/30, size: 524288" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=trace msg="emitting websocket event: metadata" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=trace msg="emitting websocket event: active" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="sending successful reply for dealer request" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 10 12:28:32 pecanpiplus sudo[1857]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=trace msg="emitting websocket event: paused" Feb 10 12:28:32 pecanpiplus volumio[825]: info: Updating RAAT Signal Path Feb 10 12:28:32 pecanpiplus sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 10 12:28:32 pecanpiplus sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:32 pecanpiplus volumio[825]: info: Shairport-Sync Started Feb 10 12:28:32 pecanpiplus volumio[825]: info: Raat Daemon started successfully Feb 10 12:28:32 pecanpiplus sudo[1853]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 12:28:32 pecanpiplus sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:32 pecanpiplus sudo[1853]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 10 12:28:32 pecanpiplus sudo[1853]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="handling resume player command from d57731a00142bd2879768409e947a6b873661412" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=trace msg="seek to 97822ms (diff: 61ms, samples: 4313950, bytes: 4246215)" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus sudo[1865]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:32 pecanpiplus volumio[825]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1zQGO7jITUE8D8w8mry8My","name":"Speak Low","artist_names":["McCoy Tyner Trio"],"album_name":"Inception","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029115aabbeef150d6b35d0b98","position":97822,"duration":375426,"release_date":"year:1962","track_number":6,"disc_number":1}} Feb 10 12:28:32 pecanpiplus volumio[825]: SPOTIFY: received: {"type":"active","data":null} Feb 10 12:28:32 pecanpiplus volumio[825]: info: Aligning Spotify Volume to Volumio Volume Feb 10 12:28:32 pecanpiplus volumio[825]: info: CoreCommandRouter::volumioGetState Feb 10 12:28:32 pecanpiplus volumio[825]: info: CorePlayQueue::getTrack 0 Feb 10 12:28:32 pecanpiplus volumio[825]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZEVXcRpjfyEarvAe","uri":"spotify:track:1zQGO7jITUE8D8w8mry8My","play_origin":"driving-jumpstart"}} Feb 10 12:28:32 pecanpiplus volumio[825]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="resume track at 97761ms" Feb 10 12:28:32 pecanpiplus volumio[825]: TypeError: Cannot read property 'service' of undefined Feb 10 12:28:32 pecanpiplus volumio[825]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Feb 10 12:28:32 pecanpiplus volumio[825]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Feb 10 12:28:32 pecanpiplus volumio[825]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Feb 10 12:28:32 pecanpiplus volumio[825]: at WebSocket.emit (events.js:315:20) Feb 10 12:28:32 pecanpiplus volumio[825]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 10 12:28:32 pecanpiplus volumio[825]: at Receiver.emit (events.js:315:20) Feb 10 12:28:32 pecanpiplus volumio[825]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 10 12:28:32 pecanpiplus volumio[825]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 10 12:28:32 pecanpiplus volumio[825]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 10 12:28:32 pecanpiplus volumio[825]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 10 12:28:32 pecanpiplus volumio[825]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 10 12:28:32 pecanpiplus volumio[825]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 10 12:28:32 pecanpiplus volumio[825]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 10 12:28:32 pecanpiplus volumio[825]: at Socket.emit (events.js:315:20) Feb 10 12:28:32 pecanpiplus volumio[825]: at addChunk (internal/streams/readable.js:309:12) Feb 10 12:28:32 pecanpiplus volumio[825]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 10 12:28:32 pecanpiplus volumio[825]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="fetched chunk 3/30, size: 524288" uri="spotify:track:1zQGO7jITUE8D8w8mry8My" Feb 10 12:28:32 pecanpiplus sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 10 12:28:32 pecanpiplus sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 10 12:28:32 pecanpiplus qobuz-connect[1756]: 20260210 12:28:32.936 [1756.1756] INFO SampleApp: Stopping Local configuration server Feb 10 12:28:32 pecanpiplus systemd[1]: Stopping Volumio Qobuz Connect Service... Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=trace msg="scheduling prefetch in 248s" Feb 10 12:28:32 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:32Z" level=debug msg="sending successful reply for dealer request" Feb 10 12:28:32 pecanpiplus qobuz-connect[1756]: 20260210 12:28:32.946 [1756.1756] INFO SampleApp: shat down connection on UNIX socket Feb 10 12:28:32 pecanpiplus systemd[1]: qobuz-connect.service: Succeeded. Feb 10 12:28:32 pecanpiplus systemd[1]: Stopped Volumio Qobuz Connect Service. Feb 10 12:28:33 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:33Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 10 12:28:33 pecanpiplus go-librespot[1559]: time="2026-02-10T12:28:33Z" level=trace msg="emitting websocket event: playing" Feb 10 12:28:33 pecanpiplus systemd[1]: Started Volumio Qobuz Connect Service. Feb 10 12:28:33 pecanpiplus sudo[1875]: pam_unix(sudo:session): session closed for user root Feb 10 12:28:33 pecanpiplus sudo[1902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-10 12:27 Feb 10 12:28:33 pecanpiplus sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="orchard" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 11:31:12 AM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi-kiosk" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Orchard Audio PecanPi+ Streamer Premium" VOLUMIO_VENDOR="Orchard Audio" VOLUMIO_MODEL="PecanPi+ Streamer Premium" VOLUMIO_HASH="f159ac367d37bc6cca69884c342f5920"