-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-02-27 23:41:50 CET. -- Feb 27 23:40:30 volumiosignature systemd-timedated[989]: Changed local time to Thu Feb 27 23:40:30 2025 Feb 27 23:40:30 volumiosignature sudo[987]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:30 volumiosignature volumio-time-update[575]: volumio-time-update-util: System time updated successfully. Feb 27 23:40:30 volumiosignature systemd[1]: Starting Daily apt download activities... Feb 27 23:40:30 volumiosignature systemd[1]: Started Volumio Time Update Utility. Feb 27 23:40:30 volumiosignature ntpd[754]: Listen normally on 3 wlan0 192.168.1.177:123 Feb 27 23:40:30 volumiosignature ntpd[754]: new interface(s) found: waking up resolver Feb 27 23:40:30 volumiosignature nmbd[716]: [2025/02/27 23:40:30.823991, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 27 23:40:30 volumiosignature systemd[1]: Started Samba NMB Daemon. Feb 27 23:40:30 volumiosignature nmbd[716]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 27 23:40:30 volumiosignature systemd[1]: Starting Samba Winbind Daemon... Feb 27 23:40:31 volumiosignature volumio-remote-updater[574]: [2025-02-27 23:40:31] [info] asio async_connect error: system:111 (Connection refused) Feb 27 23:40:31 volumiosignature volumio-remote-updater[574]: [2025-02-27 23:40:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 27 23:40:31 volumiosignature volumio-remote-updater[574]: [2025-02-27 23:40:31] [error] handle_connect error: Underlying Transport Error Feb 27 23:40:31 volumiosignature winbindd[993]: [2025/02/27 23:40:31.289029, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 27 23:40:31 volumiosignature winbindd[993]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 27 23:40:32 volumiosignature winbindd[993]: [2025/02/27 23:40:32.589263, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 27 23:40:32 volumiosignature systemd[1]: Started Samba Winbind Daemon. Feb 27 23:40:32 volumiosignature winbindd[993]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 27 23:40:32 volumiosignature systemd[1]: Starting Samba SMB Daemon... Feb 27 23:40:33 volumiosignature smbd[1016]: [2025/02/27 23:40:33.817803, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 27 23:40:33 volumiosignature smbd[1016]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 27 23:40:33 volumiosignature systemd[1]: Started Samba SMB Daemon. Feb 27 23:40:33 volumiosignature systemd[1]: Reached target Multi-User System. Feb 27 23:40:33 volumiosignature systemd[1]: Reached target Graphical Interface. Feb 27 23:40:33 volumiosignature systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 27 23:40:33 volumiosignature systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 27 23:40:33 volumiosignature systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 27 23:40:34 volumiosignature volumio[965]: info: ------------------------------------------- Feb 27 23:40:34 volumiosignature volumio[965]: info: ----- Volumio3 ---- Feb 27 23:40:34 volumiosignature volumio[965]: info: ------------------------------------------- Feb 27 23:40:34 volumiosignature volumio[965]: info: ----- System startup ---- Feb 27 23:40:34 volumiosignature volumio[965]: info: ------------------------------------------- Feb 27 23:40:35 volumiosignature volumio[965]: info: MYVOLUMIO Environment detected Feb 27 23:40:35 volumiosignature volumio[965]: info: Plugin folders cleanup Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning into folder /volumio/app/plugins/ Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category audio_interface Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category miscellanea Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category music_service Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category plugins.json Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category system_controller Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category user_interface Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning into folder /data/plugins/ Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category audio_interface Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category music_service Feb 27 23:40:35 volumiosignature volumio[965]: info: Scanning category user_interface Feb 27 23:40:35 volumiosignature volumio[965]: info: Plugin folders cleanup completed Feb 27 23:40:35 volumiosignature volumio[965]: info: ------------------------------------------- Feb 27 23:40:35 volumiosignature volumio[965]: info: ----- Core plugins startup ---- Feb 27 23:40:35 volumiosignature volumio[965]: info: ------------------------------------------- Feb 27 23:40:35 volumiosignature volumio[965]: info: Loading plugins from folder /volumio/app/plugins/ Feb 27 23:40:35 volumiosignature volumio[965]: info: Adding plugin upnp to MyMusic Plugins Feb 27 23:40:35 volumiosignature volumio[965]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 27 23:40:35 volumiosignature volumio[965]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 27 23:40:35 volumiosignature volumio[965]: info: Loading plugins from folder /data/plugins/ Feb 27 23:40:35 volumiosignature volumio[965]: info: Loading plugin "system"... Feb 27 23:40:35 volumiosignature volumio[965]: info: Loading plugin "appearance"... Feb 27 23:40:36 volumiosignature systemd[1]: systemd-fsckd.service: Succeeded. Feb 27 23:40:37 volumiosignature sh[482]: timed out Feb 27 23:40:37 volumiosignature dhcpcd[514]: timed out Feb 27 23:40:37 volumiosignature sh[482]: dhcpcd exited Feb 27 23:40:37 volumiosignature dhcpcd[514]: dhcpcd exited Feb 27 23:40:37 volumiosignature sh[482]: ifup: failed to bring up eth0 Feb 27 23:40:37 volumiosignature systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 27 23:40:37 volumiosignature systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 27 23:40:39 volumiosignature volumio[965]: info: Loading plugin "network"... Feb 27 23:40:40 volumiosignature volumio[965]: info: Refreshing Cached IP Addresses Feb 27 23:40:40 volumiosignature sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 23:40:40 volumiosignature sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:40 volumiosignature sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 23:40:40 volumiosignature sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:40 volumiosignature sudo[1043]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:40 volumiosignature sudo[1045]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:40 volumiosignature volumio[965]: info: Loading plugin "services"... Feb 27 23:40:40 volumiosignature volumio[965]: info: Loading plugin "alsa_controller"... Feb 27 23:40:40 volumiosignature sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 27 23:40:40 volumiosignature sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:40 volumiosignature systemd[1]: systemd-hostnamed.service: Succeeded. Feb 27 23:40:40 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 23:40:40 volumiosignature volumio[965]: info: Loading plugin "wizard"... Feb 27 23:40:40 volumiosignature volumio[965]: info: Loading plugin "networkfs"... Feb 27 23:40:40 volumiosignature volumio[965]: info: Starting Udev Watcher for removable devices Feb 27 23:40:40 volumiosignature sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=media,password=MediaServer1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.134/Multimedia /mnt/NAS/CarbonNAS Feb 27 23:40:40 volumiosignature sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:40 volumiosignature volumio[965]: info: Ignoring mount for partition: boot Feb 27 23:40:40 volumiosignature volumio[965]: info: Ignoring mount for partition: volumio Feb 27 23:40:40 volumiosignature volumio[965]: info: Ignoring mount for partition: volumio_data Feb 27 23:40:40 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 27 23:40:40 volumiosignature volumio[965]: info: Loading plugin "volumio_command_line_client"... Feb 27 23:40:40 volumiosignature volumio[965]: info: Loading plugin "upnp"... Feb 27 23:40:40 volumiosignature volumio[965]: info: [1740696040461] Starting Upmpd Daemon Feb 27 23:40:40 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 27 23:40:40 volumiosignature volumio[965]: info: Loading plugin "my_music"... Feb 27 23:40:40 volumiosignature volumio[965]: info: Loading plugin "mpd"... Feb 27 23:40:40 volumiosignature kernel: Key type cifs.spnego registered Feb 27 23:40:40 volumiosignature kernel: Key type cifs.idmap registered Feb 27 23:40:40 volumiosignature kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 27 23:40:40 volumiosignature kernel: CIFS: Attempting to mount //192.168.1.134/Multimedia Feb 27 23:40:41 volumiosignature volumio[965]: info: Loading plugin "upnp_browser"... Feb 27 23:40:41 volumiosignature volumio-remote-updater[574]: [2025-02-27 23:40:41] [connect] Successful connection Feb 27 23:40:41 volumiosignature sudo[1073]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:41 volumiosignature volumio[965]: info: Loading plugin "alarm-clock"... Feb 27 23:40:41 volumiosignature volumio[965]: info: Loading plugin "airplay_emulation"... Feb 27 23:40:41 volumiosignature volumio[965]: info: Starting Shairport Sync Feb 27 23:40:41 volumiosignature volumio[965]: info: Loading plugin "last_100"... Feb 27 23:40:41 volumiosignature volumio[965]: info: Loading plugin "webradio"... Feb 27 23:40:41 volumiosignature systemd[1]: apt-daily.service: Succeeded. Feb 27 23:40:41 volumiosignature systemd[1]: Started Daily apt download activities. Feb 27 23:40:41 volumiosignature systemd[1]: Starting Daily apt upgrade and clean activities... Feb 27 23:40:42 volumiosignature volumio[965]: info: Loading plugin "i2s_dacs"... Feb 27 23:40:42 volumiosignature volumio[965]: info: Loading plugin "volumiodiscovery"... Feb 27 23:40:42 volumiosignature volumio[965]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 27 23:40:42 volumiosignature volumio[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 27 23:40:42 volumiosignature volumio[965]: *** WARNING *** For more information see Feb 27 23:40:42 volumiosignature volumio[965]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 27 23:40:42 volumiosignature volumio[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 27 23:40:42 volumiosignature volumio[965]: *** WARNING *** For more information see Feb 27 23:40:42 volumiosignature node[965]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 27 23:40:42 volumiosignature node[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 27 23:40:42 volumiosignature node[965]: *** WARNING *** For more information see Feb 27 23:40:42 volumiosignature node[965]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 27 23:40:42 volumiosignature node[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 27 23:40:42 volumiosignature node[965]: *** WARNING *** For more information see Feb 27 23:40:42 volumiosignature volumio[965]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 27 23:40:42 volumiosignature volumio[965]: info: Discovery: Started advertising with name: VolumioSignature Feb 27 23:40:42 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 27 23:40:42 volumiosignature volumio[965]: info: Loading plugin "spop"... Feb 27 23:40:43 volumiosignature sudo[1053]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:43 volumiosignature systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 27 23:40:43 volumiosignature systemd[1]: Started Daily apt upgrade and clean activities. Feb 27 23:40:43 volumiosignature systemd[1]: Startup finished in 14.670s (kernel) + 41.416s (userspace) = 56.086s. Feb 27 23:40:44 volumiosignature volumio[965]: info: Loading plugin "outputs"... Feb 27 23:40:44 volumiosignature volumio[965]: info: Loading plugin "albumart"... Feb 27 23:40:44 volumiosignature volumio[965]: info: Plugin example_plugin is not enabled Feb 27 23:40:44 volumiosignature volumio[965]: info: Loading plugin "inputs"... Feb 27 23:40:44 volumiosignature volumio[965]: info: Loading plugin "updater_comm"... Feb 27 23:40:45 volumiosignature volumio[965]: Forking 3 albumart workers Feb 27 23:40:46 volumiosignature volumio[965]: Starting albumart workers Feb 27 23:40:46 volumiosignature volumio[965]: Starting albumart workers Feb 27 23:40:46 volumiosignature volumio[965]: Starting albumart workers Feb 27 23:40:47 volumiosignature volumio[965]: info: Plugin mpdemulation is not enabled Feb 27 23:40:47 volumiosignature volumio[965]: info: Loading plugin "rest_api"... Feb 27 23:40:47 volumiosignature volumio[965]: info: Loading plugin "websocket"... Feb 27 23:40:47 volumiosignature volumio[965]: info: Starting Socket.io Server version 2.3.0 Feb 27 23:40:47 volumiosignature volumio[965]: info: Plugin fusiondsp is not enabled Feb 27 23:40:47 volumiosignature volumio[965]: info: Loading plugin "RoonBridge"... Feb 27 23:40:48 volumiosignature volumio[965]: info: Applying required configuration parameters for plugin RoonBridge Feb 27 23:40:48 volumiosignature volumio[965]: info: Loading plugin "Systeminfo"... Feb 27 23:40:49 volumiosignature volumio[965]: info: Loading i18n strings for locale en Feb 27 23:40:49 volumiosignature volumio[965]: Updating browse sources language Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::initPlayerControls Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: Express server listening on port 3000 Feb 27 23:40:49 volumiosignature volumio[965]: [Metrics] WebUI: 18s 217.17ms Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreStateMachine::resetVolumioState Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreStateMachine::getcurrentVolume Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::volumioRetrievevolume Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreStateMachine::pushState Feb 27 23:40:49 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::volumioPushState Feb 27 23:40:49 volumiosignature volumio[965]: info: Volumio Network Manager: Network status updated: 2 Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:40:49 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:40:49 volumiosignature volumio[965]: info: Reloading queue from file Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreStateMachine::setRepeat null single undefined Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreStateMachine::pushState Feb 27 23:40:49 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::volumioPushState Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreStateMachine::setRandom null Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreStateMachine::pushState Feb 27 23:40:49 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:40:49 volumiosignature volumio[965]: info: CoreCommandRouter::volumioPushState Feb 27 23:40:49 volumiosignature volumio[965]: info: Setting Device type: Raspberry PI Feb 27 23:40:49 volumiosignature volumio[965]: info: Completed loading Core Plugins Feb 27 23:40:49 volumiosignature volumio[965]: info: Preparing to generate the ALSA configuration file Feb 27 23:40:49 volumiosignature volumio[965]: info: Listing playlists Feb 27 23:40:49 volumiosignature volumio[965]: info: Listing playlists Feb 27 23:40:49 volumiosignature volumio[965]: info: Asound.conf file unchanged, so no further update is needed Feb 27 23:40:49 volumiosignature volumio[965]: info: Output device has changed, restarting MPD Feb 27 23:40:50 volumiosignature volumio[965]: info: Output device has changed, restarting Shairport Sync Feb 27 23:40:50 volumiosignature sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 23:40:50 volumiosignature sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:40:50 volumiosignature sudo[1241]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:50 volumiosignature sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 23:40:50 volumiosignature sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature systemd[1]: Stopping Music Player Daemon... Feb 27 23:40:50 volumiosignature volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 23:40:50 volumiosignature volumio[965]: info: ___________ START PLUGINS ___________ Feb 27 23:40:50 volumiosignature volumio[965]: info: ControllerMpd::onStart: Initializing MPD Feb 27 23:40:50 volumiosignature volumio[965]: info: Creating MPD Configuration file Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 27 23:40:50 volumiosignature volumio[965]: info: [1740696050124] CoreMusicLibrary::Adding element Media Servers Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 23:40:50 volumiosignature sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 23:40:50 volumiosignature sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature sudo[1250]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:50 volumiosignature sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 23:40:50 volumiosignature sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:40:50 volumiosignature systemd[1]: mpd.service: Succeeded. Feb 27 23:40:50 volumiosignature systemd[1]: Stopped Music Player Daemon. Feb 27 23:40:50 volumiosignature volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 27 23:40:50 volumiosignature volumio[965]: info: [1740696050298] CoreMusicLibrary::Adding element Last_100 Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 27 23:40:50 volumiosignature volumio[965]: info: [1740696050302] CoreMusicLibrary::Adding element Webradio Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 23:40:50 volumiosignature volumio[965]: info: Initializing BBC Radios Feb 27 23:40:50 volumiosignature systemd[1]: Starting Music Player Daemon... Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: Creating Spotify config file Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: Volumio Calling Home Feb 27 23:40:50 volumiosignature sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 27 23:40:50 volumiosignature sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature sudo[1272]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 27 23:40:50 volumiosignature sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature sudo[1272]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:50 volumiosignature systemd[1]: Started RoonBridge. Feb 27 23:40:50 volumiosignature sudo[1287]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:50 volumiosignature sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 23:40:50 volumiosignature sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature volumio[965]: info: Discovery: adding 65251c6b-afe1-4ce8-88d0-0a86166b9fcf Feb 27 23:40:50 volumiosignature volumio[965]: info: Discovery: Found device VolumioSignature Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:40:50 volumiosignature sudo[1303]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:50 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:40:50 volumiosignature sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 23:40:50 volumiosignature sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature sudo[1305]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:50 volumiosignature volumio[965]: info: MPD Permissions set Feb 27 23:40:50 volumiosignature volumio[965]: info: MPD Permissions set Feb 27 23:40:50 volumiosignature volumio[965]: info: Volumio called home Feb 27 23:40:50 volumiosignature volumio[965]: info: Spotify config file written Feb 27 23:40:50 volumiosignature sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 27 23:40:50 volumiosignature sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:50 volumiosignature volumio[965]: 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 27 23:40:50 volumiosignature volumio[965]: info: No need to fix Spotify hosts Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature systemd[1]: Started go-librespot Daemon. Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature go-librespot[1312]: go-librespot daemon starting... Feb 27 23:40:50 volumiosignature sudo[1309]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:40:50 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:40:51 volumiosignature volumio[965]: info: Starting Shairport Sync Feb 27 23:40:51 volumiosignature volumio[965]: info: Starting Shairport Sync Feb 27 23:40:51 volumiosignature volumio[965]: info: Starting Shairport Sync Feb 27 23:40:51 volumiosignature sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 23:40:51 volumiosignature sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:51 volumiosignature sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 23:40:51 volumiosignature sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:51 volumiosignature systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 23:40:51 volumiosignature systemd[1]: shairport-sync.service: Succeeded. Feb 27 23:40:51 volumiosignature systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 23:40:51 volumiosignature sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 23:40:51 volumiosignature sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:51 volumiosignature systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 23:40:51 volumiosignature sudo[1323]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:51 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:40:51 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:40:51 volumiosignature systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 23:40:51 volumiosignature systemd[1]: shairport-sync.service: Succeeded. Feb 27 23:40:51 volumiosignature systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 23:40:51 volumiosignature sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 27 23:40:51 volumiosignature sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:40:51 volumiosignature systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 23:40:51 volumiosignature sudo[1325]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:51 volumiosignature systemd[1]: Started UPnP Renderer front-end to MPD. Feb 27 23:40:51 volumiosignature sudo[1340]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:51 volumiosignature sudo[1328]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:51 volumiosignature volumio[965]: info: Shairport-Sync Started Feb 27 23:40:51 volumiosignature volumio[965]: Error adding Membership: Error: addMembership EINVAL Feb 27 23:40:51 volumiosignature volumio[965]: info: Upmpdcli Daemon Started Feb 27 23:40:51 volumiosignature volumio[965]: info: Shairport-Sync Started Feb 27 23:40:51 volumiosignature volumio[965]: info: Shairport-Sync Started Feb 27 23:40:51 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:51+01:00" level=info msg="running go-librespot 0.2.0" Feb 27 23:40:51 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:51+01:00" level=debug msg="app state loaded" Feb 27 23:40:51 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:51+01:00" level=debug msg="stored credentials not found" Feb 27 23:40:51 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 27 23:40:52 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 27 23:40:52 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:52+01:00" 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 27 23:40:52 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:52+01:00" 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 27 23:40:52 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:52+01:00" level=info msg="zeroconf server listening on port 33915" Feb 27 23:40:52 volumiosignature mpd[1294]: Feb 27 23:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 27 23:40:52 volumiosignature systemd[1]: Started Music Player Daemon. Feb 27 23:40:52 volumiosignature sudo[1243]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:52 volumiosignature sudo[1252]: pam_unix(sudo:session): session closed for user root Feb 27 23:40:52 volumiosignature volumio[965]: info: Completed starting Core Plugins Feb 27 23:40:52 volumiosignature volumio[965]: info: ------------------------------------------- Feb 27 23:40:52 volumiosignature volumio[965]: info: ----- MyVolumio plugins startup ---- Feb 27 23:40:52 volumiosignature volumio[965]: info: ------------------------------------------- Feb 27 23:40:52 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 27 23:40:52 volumiosignature volumio[965]: error: MPD error: The expression evaluated to a falsy value: Feb 27 23:40:52 volumiosignature volumio[965]: assert.ok(self.idling) Feb 27 23:40:52 volumiosignature volumio[965]: error: The expression evaluated to a falsy value: Feb 27 23:40:52 volumiosignature volumio[965]: assert.ok(self.idling) Feb 27 23:40:52 volumiosignature volumio[965]: info: MPD running with PID1294 Feb 27 23:40:52 volumiosignature volumio[965]: ,establishing connection Feb 27 23:40:52 volumiosignature volumio[965]: error: updateQueue error: null Feb 27 23:40:52 volumiosignature volumio[965]: error: updateQueue error: null Feb 27 23:40:52 volumiosignature volumio[965]: verbose: New Socket.io Connection to 192.168.1.177:3000 from 192.168.1.122 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 27 23:40:52 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 27 23:40:52 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:40:52 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:40:52 volumiosignature volumio[1343]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 27 23:40:52 volumiosignature volumio[965]: info: Discovery: adding b9e77a1a-961b-459f-913f-f94b270041c8 Feb 27 23:40:52 volumiosignature volumio[965]: info: Discovery: Found device Digione Feb 27 23:40:52 volumiosignature volumio[965]: info: Discovery: Connecting to remote: 192.168.1.122 Feb 27 23:40:52 volumiosignature volumio[965]: info: Discovery: Connected to remote: 192.168.1.122 Feb 27 23:40:52 volumiosignature RoonBridge[1292]: 00:00:00.019 Warn: get lock file path: /tmp/.rnbgem0- Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.608 Trace: [childprocess] using unix child process Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.709 Debug: PathForResource, filename: ../.update Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.826 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.836 Debug: PathForResource, filename: RAATServer Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.836 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.837 Debug: PathForResource, filename: RoonBridgeHelper Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.837 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 27 23:40:53 volumiosignature RoonBridge[1292]: Initializing Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.901 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Feb 27 23:40:53 volumiosignature RoonBridge[1292]: 00:00:00.948 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 27 23:40:53 volumiosignature RoonBridge[1292]: Not Running (.o) Feb 27 23:40:54 volumiosignature RoonBridge[1292]: Running Feb 27 23:40:54 volumiosignature volumio[965]: info: go-librespot daemon successfully initialized Feb 27 23:40:54 volumiosignature RoonBridge[1292]: 00:00:00.044 Warn: get lock file path: /tmp/.rnbhgem0- Feb 27 23:40:56 volumiosignature volumio-remote-updater[574]: [2025-02-27 23:40:56] [connect] Successful connection Feb 27 23:40:56 volumiosignature volumio-remote-updater[574]: [2025-02-27 23:40:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740696056 101 Feb 27 23:40:56 volumiosignature volumio[965]: 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: 3 Feb 27 23:40:57 volumiosignature volumio[965]: info: Initializing connection to go-librespot Websocket Feb 27 23:40:57 volumiosignature volumio[965]: info: Connection to go-librespot Websocket established Feb 27 23:40:57 volumiosignature volumio[965]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 27 23:40:57 volumiosignature go-librespot[1312]: time="2025-02-27T23:40:57+01:00" level=debug msg="new websocket client" Feb 27 23:40:57 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:40:57 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:00 volumiosignature systemd[1]: systemd-timedated.service: Succeeded. Feb 27 23:41:00 volumiosignature volumio[965]: info: Getting Spotify volume Feb 27 23:41:00 volumiosignature volumio[965]: 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 27 23:41:00 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:00 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 27 23:41:00 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 27 23:41:00 volumiosignature volumio[965]: info: Adding plugin bluetooth to MyMusic Plugins Feb 27 23:41:00 volumiosignature volumio[965]: info: Adding plugin multiroom to MyMusic Plugins Feb 27 23:41:00 volumiosignature volumio[965]: info: Adding plugin metavolumio to MyMusic Plugins Feb 27 23:41:00 volumiosignature volumio[965]: info: Adding plugin cd_controller to MyMusic Plugins Feb 27 23:41:00 volumiosignature volumio[965]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 27 23:41:01 volumiosignature volumio[965]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 27 23:41:01 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 27 23:41:02 volumiosignature volumio[965]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 27 23:41:02 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 27 23:41:02 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:02 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:02 volumiosignature volumio[965]: info: Starting MyVolumio Remote Streaming Endpoints Feb 27 23:41:02 volumiosignature volumio[965]: info: MyVolumio login type: Token Feb 27 23:41:02 volumiosignature volumio[965]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 27 23:41:02 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 27 23:41:03 volumiosignature volumio[1343]: .................................................................................++++ Feb 27 23:41:04 volumiosignature volumio[965]: info: Starting Streaming Service Transparent Proxy Feb 27 23:41:04 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 27 23:41:04 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 27 23:41:04 volumiosignature volumio[965]: info: Streaming services startup Feb 27 23:41:04 volumiosignature volumio[965]: info: Starting Streaming Daemon Feb 27 23:41:04 volumiosignature sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 27 23:41:04 volumiosignature sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:04 volumiosignature volumio[965]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 27 23:41:04 volumiosignature sudo[1451]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:04 volumiosignature volumio[965]: error: Cannot start Volumio Streaming Daemon Feb 27 23:41:04 volumiosignature volumio[965]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 27 23:41:04 volumiosignature volumio[965]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 27 23:41:05 volumiosignature volumio[965]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 27 23:41:05 volumiosignature volumio[965]: STREAMING PROXY: Starting server on port 3245 Feb 27 23:41:05 volumiosignature volumio[965]: Node JS runtime: 14 Feb 27 23:41:07 volumiosignature volumio[965]: info: MyVolumio token set successfully Feb 27 23:41:07 volumiosignature volumio[965]: info: MYVOLUMIO: Adding device Feb 27 23:41:07 volumiosignature volumio[965]: info: MYVOLUMIO: Evaluating Server Feb 27 23:41:07 volumiosignature volumio[965]: info: MyVolumio status changed Feb 27 23:41:07 volumiosignature volumio[965]: info: Streaming services startup Feb 27 23:41:07 volumiosignature volumio[965]: info: Starting Streaming Daemon Feb 27 23:41:07 volumiosignature volumio[965]: info: Removing browser output: myVolumio user plan is not superstar Feb 27 23:41:07 volumiosignature volumio[965]: info: Removing audio output: Feb 27 23:41:07 volumiosignature volumio[965]: info: Stoppping Tunnel 1 Feb 27 23:41:07 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:07 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:07 volumiosignature sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 27 23:41:07 volumiosignature sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:07 volumiosignature sudo[1478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 27 23:41:07 volumiosignature sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:07 volumiosignature volumio[965]: info: Listing playlists Feb 27 23:41:07 volumiosignature volumio[965]: info: Listing playlists Feb 27 23:41:07 volumiosignature sudo[1476]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:07 volumiosignature sudo[1478]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:07 volumiosignature volumio[965]: error: Cannot start Volumio Streaming Daemon Feb 27 23:41:07 volumiosignature volumio[965]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 27 23:41:07 volumiosignature volumio[965]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 27 23:41:07 volumiosignature volumio[965]: info: Remote SSH Stopped Feb 27 23:41:07 volumiosignature volumio[965]: info: Setting Geolocation for MyVolumio to eu6 Feb 27 23:41:07 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:07 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:07 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:08 volumiosignature volumio[965]: info: Successfully Added MyVolumio device Feb 27 23:41:08 volumiosignature volumio[965]: info: Updating MyVolumio device info Feb 27 23:41:08 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:08 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:08 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:09 volumiosignature volumio[965]: info: Successfully Updated MyVolumio device Feb 27 23:41:17 volumiosignature ntpd[754]: Soliciting pool server 193.70.94.182 Feb 27 23:41:17 volumiosignature ntpd[754]: Soliciting pool server 193.59.134.156 Feb 27 23:41:17 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 23:41:17 volumiosignature volumio[965]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 27 23:41:17 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:17 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:17 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:17 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:18 volumiosignature ntpd[754]: Soliciting pool server 156.17.20.254 Feb 27 23:41:18 volumiosignature ntpd[754]: Soliciting pool server 194.177.4.2 Feb 27 23:41:19 volumiosignature ntpd[754]: Soliciting pool server 188.165.17.91 Feb 27 23:41:19 volumiosignature ntpd[754]: Soliciting pool server 213.135.57.60 Feb 27 23:41:19 volumiosignature ntpd[754]: Soliciting pool server 195.46.37.22 Feb 27 23:41:20 volumiosignature ntpd[754]: Soliciting pool server 212.127.78.21 Feb 27 23:41:20 volumiosignature ntpd[754]: Soliciting pool server 91.212.242.21 Feb 27 23:41:20 volumiosignature ntpd[754]: Soliciting pool server 89.250.197.242 Feb 27 23:41:20 volumiosignature ntpd[754]: Soliciting pool server 193.25.222.136 Feb 27 23:41:20 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 23:41:20 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:20 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 23:41:20 volumiosignature volumio-remote-updater[574]: No test mode Feb 27 23:41:20 volumiosignature volumio-remote-updater[574]: No alpha test mode Feb 27 23:41:20 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 27 23:41:20 volumiosignature volumio[965]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 27 23:41:20 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 27 23:41:21 volumiosignature ntpd[754]: Soliciting pool server 213.222.217.10 Feb 27 23:41:21 volumiosignature ntpd[754]: Soliciting pool server 162.159.200.123 Feb 27 23:41:22 volumiosignature ntpd[754]: Soliciting pool server 178.215.228.24 Feb 27 23:41:22 volumiosignature ntpd[754]: Soliciting pool server 51.68.141.5 Feb 27 23:41:22 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:22 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:22 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 27 23:41:22 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 27 23:41:22 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 27 23:41:22 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 27 23:41:22 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetBrowseSources Feb 27 23:41:22 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 23:41:23 volumiosignature ntpd[754]: Soliciting pool server 162.159.200.1 Feb 27 23:41:23 volumiosignature ntpd[754]: Soliciting pool server 2001:41d0:601:1100::649c Feb 27 23:41:24 volumiosignature ntpd[754]: receive: Unexpected origin timestamp 0xeb6b6894.147749e1 does not match aorg 0000000000.00000000 from server@194.177.4.2 xmt 0xeb6b6894.5d814b56 Feb 27 23:41:24 volumiosignature ntpd[754]: receive: Unexpected origin timestamp 0xeb6b6894.1473282c does not match aorg 0000000000.00000000 from server@195.46.37.22 xmt 0xeb6b6894.95754754 Feb 27 23:41:24 volumiosignature ntpd[754]: receive: Unexpected origin timestamp 0xeb6b6894.147003f8 does not match aorg 0000000000.00000000 from server@213.222.217.10 xmt 0xeb6b6894.956e9e5d Feb 27 23:41:24 volumiosignature ntpd[754]: receive: Unexpected origin timestamp 0xeb6b6894.14786029 does not match aorg 0000000000.00000000 from server@193.59.134.156 xmt 0xeb6b6894.95975982 Feb 27 23:41:24 volumiosignature ntpd[754]: receive: Unexpected origin timestamp 0xeb6b6894.1471f0a6 does not match aorg 0000000000.00000000 from server@212.127.78.21 xmt 0xeb6b6894.97d908f5 Feb 27 23:41:24 volumiosignature ntpd[754]: receive: Unexpected origin timestamp 0xeb6b6894.146aae67 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeb6b6894.976abd2a Feb 27 23:41:24 volumiosignature ntpd[754]: receive: Unexpected origin timestamp 0xeb6b6894.1474f299 does not match aorg 0000000000.00000000 from server@188.165.17.91 xmt 0xeb6b6894.980658ae Feb 27 23:41:24 volumiosignature volumio[965]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 27 23:41:24 volumiosignature volumio[965]: info: Completed starting MyVolumio Plugin Feb 27 23:41:24 volumiosignature volumio[965]: [Metrics] CommandRouter: 50s 42.25ms Feb 27 23:41:24 volumiosignature volumio[965]: info: CoreCommandRouter::volumiosetStartupVolume Feb 27 23:41:24 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:24 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:24 volumiosignature volumio[965]: info: CoreCommandRouter::Close All Modals sent Feb 27 23:41:24 volumiosignature volumio[965]: info: CoreCommandRouter::Close All Modals sent Feb 27 23:41:25 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 27 23:41:25 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 23:41:25 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 27 23:41:28 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:28 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:28 volumiosignature volumio[965]: info: Listing playlists Feb 27 23:41:28 volumiosignature volumio[965]: info: Listing playlists Feb 27 23:41:28 volumiosignature volumio[1343]: ....................................................................................................................................................................................++++ Feb 27 23:41:28 volumiosignature volumio[1343]: e is 65537 (0x010001) Feb 27 23:41:28 volumiosignature volumio[1343]: writing RSA key Feb 27 23:41:31 volumiosignature volumio[965]: info: BOOT COMPLETED Feb 27 23:41:32 volumiosignature sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 23:41:32 volumiosignature sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:32 volumiosignature sudo[1603]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:32 volumiosignature sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 23:41:32 volumiosignature sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:32 volumiosignature sudo[1605]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:32 volumiosignature volumio[965]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.160 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:32 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 27 23:41:32 volumiosignature volumio[965]: info: Received Get System Info Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 23:41:32 volumiosignature volumio[965]: info: Discovery: Getting this device information Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:32 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:32 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:32 volumiosignature volumio[965]: info: Listing playlists Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 27 23:41:32 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 27 23:41:33 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 27 23:41:33 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 23:41:33 volumiosignature volumio[965]: info: Received Get System Info Feb 27 23:41:33 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 23:41:33 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 23:41:33 volumiosignature volumio[965]: info: Discovery: Getting this device information Feb 27 23:41:33 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:33 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:34 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 23:41:35 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 23:41:35 volumiosignature volumio[965]: info: Received Get System Info Feb 27 23:41:35 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 23:41:35 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 23:41:35 volumiosignature volumio[965]: info: Discovery: Getting this device information Feb 27 23:41:35 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:35 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:35 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 23:41:38 volumiosignature volumio[965]: info: CoreCommandRouter::volumioGetState Feb 27 23:41:38 volumiosignature volumio[965]: info: CorePlayQueue::getTrack 0 Feb 27 23:41:39 volumiosignature volumio[965]: info: Starting Uninstall of plugin audio_interface - fusiondsp Feb 27 23:41:42 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 27 23:41:46 volumiosignature volumio[965]: info: Enabling plugin fusiondsp Feb 27 23:41:46 volumiosignature volumio[965]: info: Loading plugin "fusiondsp"... Feb 27 23:41:47 volumiosignature volumio[965]: info: Preparing to generate the ALSA configuration file Feb 27 23:41:47 volumiosignature volumio[965]: info: Asound.conf file unchanged, so no further update is needed Feb 27 23:41:47 volumiosignature volumio[965]: info: Output device has changed, restarting MPD Feb 27 23:41:47 volumiosignature sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 23:41:47 volumiosignature volumio[965]: info: Output device has changed, restarting Shairport Sync Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:47 volumiosignature sudo[1631]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:47 volumiosignature sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 23:41:47 volumiosignature sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:47 volumiosignature volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 23:41:47 volumiosignature volumio[965]: info: PLUGIN START: fusiondsp Feb 27 23:41:47 volumiosignature systemd[1]: Stopping Music Player Daemon... Feb 27 23:41:47 volumiosignature volumio[965]: info: Loading i18n strings for locale en Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: FusionDsp - mixtype--------------------- None Feb 27 23:41:47 volumiosignature systemd[1]: mpd.service: Succeeded. Feb 27 23:41:47 volumiosignature volumio[965]: info: Preparing to generate the ALSA configuration file Feb 27 23:41:47 volumiosignature systemd[1]: Stopped Music Player Daemon. Feb 27 23:41:47 volumiosignature volumio[965]: info: Done. Feb 27 23:41:47 volumiosignature volumio[965]: info: MPD Permissions set Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: Asound.conf file unchanged, so no further update is needed Feb 27 23:41:47 volumiosignature volumio[965]: info: Output device has changed, restarting MPD Feb 27 23:41:47 volumiosignature sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 23:41:47 volumiosignature volumio[965]: info: Output device has changed, restarting Shairport Sync Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:47 volumiosignature systemd[1]: Starting Music Player Daemon... Feb 27 23:41:47 volumiosignature sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:47 volumiosignature sudo[1645]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:47 volumiosignature sudo[1647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 23:41:47 volumiosignature sudo[1647]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:47 volumiosignature systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 27 23:41:47 volumiosignature systemd[1]: mpd.service: Succeeded. Feb 27 23:41:47 volumiosignature systemd[1]: Stopped Music Player Daemon. Feb 27 23:41:47 volumiosignature volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 23:41:47 volumiosignature systemd[1]: Starting Music Player Daemon... Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 23:41:47 volumiosignature volumio[965]: info: MPD Permissions set Feb 27 23:41:47 volumiosignature volumio[965]: info: Starting Shairport Sync Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature sudo[1655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 23:41:47 volumiosignature sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:47 volumiosignature volumio[965]: info: Starting Shairport Sync Feb 27 23:41:47 volumiosignature systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 23:41:47 volumiosignature systemd[1]: shairport-sync.service: Succeeded. Feb 27 23:41:47 volumiosignature systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 23:41:47 volumiosignature sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 23:41:47 volumiosignature sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:47 volumiosignature sudo[1653]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 27 23:41:47 volumiosignature sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:47 volumiosignature sudo[1653]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:47 volumiosignature systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 23:41:47 volumiosignature sudo[1655]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:47 volumiosignature systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 23:41:47 volumiosignature systemd[1]: shairport-sync.service: Succeeded. Feb 27 23:41:47 volumiosignature systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 23:41:47 volumiosignature systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 23:41:47 volumiosignature sudo[1662]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:48 volumiosignature volumio[965]: info: Shairport-Sync Started Feb 27 23:41:48 volumiosignature volumio[965]: info: Shairport-Sync Started Feb 27 23:41:49 volumiosignature volumio[965]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Feb 27 23:41:49 volumiosignature volumio[965]: info: camilladsp service started and running in background, instance 1 Feb 27 23:41:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 23:41:49 volumiosignature volumio[965]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 27 23:41:49 volumiosignature volumio[965]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Feb 27 23:41:49 volumiosignature volumio[965]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 27 23:41:49 volumiosignature volumio[965]: info: FusionDsp loaded Feb 27 23:41:49 volumiosignature volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 23:41:49 volumiosignature sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 27 23:41:49 volumiosignature sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 23:41:49 volumiosignature volumio[965]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 27 23:41:49 volumiosignature volumio[965]: info: Adding Signal Path Element [object Object] Feb 27 23:41:49 volumiosignature volumio[965]: info: Adding fusiondspeq DSP Signal Path Element Feb 27 23:41:49 volumiosignature sudo[1683]: pam_unix(sudo:session): session closed for user root Feb 27 23:41:49 volumiosignature volumio[965]: info: FusionDsp - ---- installed callbackRead Feb 27 23:41:49 volumiosignature volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 23:41:49 volumiosignature volumio[965]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Feb 27 23:41:49 volumiosignature volumio[965]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Feb 27 23:41:49 volumiosignature volumio[965]: at onErrorNT (internal/child_process.js:465:16) Feb 27 23:41:49 volumiosignature volumio[965]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Feb 27 23:41:49 volumiosignature volumio[965]: errno: -2, Feb 27 23:41:49 volumiosignature volumio[965]: code: 'ENOENT', Feb 27 23:41:49 volumiosignature volumio[965]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Feb 27 23:41:49 volumiosignature volumio[965]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Feb 27 23:41:49 volumiosignature volumio[965]: spawnargs: [ Feb 27 23:41:49 volumiosignature volumio[965]: '-p', Feb 27 23:41:49 volumiosignature volumio[965]: 9876, Feb 27 23:41:49 volumiosignature volumio[965]: '-o', Feb 27 23:41:49 volumiosignature volumio[965]: '/tmp/camilladsp.log', Feb 27 23:41:49 volumiosignature volumio[965]: '-l', Feb 27 23:41:49 volumiosignature volumio[965]: 'warn', Feb 27 23:41:49 volumiosignature volumio[965]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Feb 27 23:41:49 volumiosignature volumio[965]: ] Feb 27 23:41:49 volumiosignature volumio[965]: } Feb 27 23:41:49 volumiosignature volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 23:41:50 volumiosignature sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-27 23:40 Feb 27 23:41:50 volumiosignature sudo[1694]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"