-- Logs begin at Wed 2024-11-20 18:29:39 CET, end at Wed 2024-11-20 19:35:48 CET. -- Nov 20 19:35:37 volumio systemd-timedated[990]: Changed local time to Wed Nov 20 19:35:37 2024 Nov 20 19:35:37 volumio sudo[988]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:37 volumio volumio-time-update[642]: volumio-time-update-util: System time updated successfully. Nov 20 19:35:37 volumio systemd[1]: Started Volumio Time Update Utility. Nov 20 19:35:37 volumio volumio[958]: info: MYVOLUMIO Environment detected Nov 20 19:35:38 volumio volumio[958]: info: Plugin folders cleanup Nov 20 19:35:38 volumio volumio[958]: info: Scanning into folder /volumio/app/plugins/ Nov 20 19:35:38 volumio volumio[958]: info: Scanning category audio_interface Nov 20 19:35:38 volumio volumio[958]: info: Scanning category miscellanea Nov 20 19:35:38 volumio volumio[958]: info: Scanning category music_service Nov 20 19:35:38 volumio volumio[958]: info: Scanning category plugins.json Nov 20 19:35:38 volumio volumio[958]: info: Scanning category system_controller Nov 20 19:35:38 volumio volumio[958]: info: Scanning category user_interface Nov 20 19:35:38 volumio volumio[958]: info: Scanning into folder /data/plugins/ Nov 20 19:35:38 volumio volumio[958]: info: Scanning category audio_interface Nov 20 19:35:38 volumio volumio[958]: info: Plugin folders cleanup completed Nov 20 19:35:38 volumio volumio[958]: info: ------------------------------------------- Nov 20 19:35:38 volumio volumio[958]: info: ----- Core plugins startup ---- Nov 20 19:35:38 volumio volumio[958]: info: ------------------------------------------- Nov 20 19:35:38 volumio volumio[958]: info: Loading plugins from folder /volumio/app/plugins/ Nov 20 19:35:38 volumio volumio[958]: info: Adding plugin upnp to MyMusic Plugins Nov 20 19:35:38 volumio volumio[958]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 20 19:35:38 volumio volumio[958]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 20 19:35:38 volumio volumio[958]: info: Loading plugins from folder /data/plugins/ Nov 20 19:35:38 volumio volumio[958]: info: Loading plugin "system"... Nov 20 19:35:38 volumio volumio[958]: info: Loading plugin "appearance"... Nov 20 19:35:38 volumio nmbd[748]: [2024/11/20 19:35:38.220471, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 20 19:35:38 volumio systemd[1]: Started Samba NMB Daemon. Nov 20 19:35:38 volumio nmbd[748]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 20 19:35:38 volumio systemd[1]: Starting Samba Winbind Daemon... Nov 20 19:35:38 volumio winbindd[1000]: [2024/11/20 19:35:38.666449, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 20 19:35:38 volumio winbindd[1000]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 20 19:35:38 volumio winbindd[1000]: [2024/11/20 19:35:38.695709, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 20 19:35:38 volumio systemd[1]: Started Samba Winbind Daemon. Nov 20 19:35:38 volumio winbindd[1000]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 20 19:35:38 volumio systemd[1]: Starting Samba SMB Daemon... Nov 20 19:35:39 volumio smbd[1005]: [2024/11/20 19:35:39.370800, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 20 19:35:39 volumio systemd[1]: Started Samba SMB Daemon. Nov 20 19:35:39 volumio systemd[1]: Reached target Multi-User System. Nov 20 19:35:39 volumio systemd[1]: Reached target Graphical Interface. Nov 20 19:35:39 volumio smbd[1005]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 20 19:35:39 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 20 19:35:39 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 20 19:35:39 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 20 19:35:39 volumio systemd[1]: Startup finished in 16.220s (kernel) + 28.761s (userspace) = 44.981s. Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "network"... Nov 20 19:35:40 volumio volumio[958]: info: Refreshing Cached IP Addresses Nov 20 19:35:40 volumio sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 20 19:35:40 volumio sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:40 volumio sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 20 19:35:40 volumio sudo[1015]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:40 volumio sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:40 volumio sudo[1017]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "services"... Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "alsa_controller"... Nov 20 19:35:40 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 20 19:35:40 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:40 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "wizard"... Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "networkfs"... Nov 20 19:35:40 volumio volumio[958]: info: Starting Udev Watcher for removable devices Nov 20 19:35:40 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Media,password=Musiclove1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.43/music /mnt/NAS/Hardy Nov 20 19:35:40 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:40 volumio volumio[958]: info: Ignoring mount for partition: boot Nov 20 19:35:40 volumio volumio[958]: info: Ignoring mount for partition: volumio Nov 20 19:35:40 volumio volumio[958]: info: Ignoring mount for partition: volumio_data Nov 20 19:35:40 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "volumio_command_line_client"... Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "upnp"... Nov 20 19:35:40 volumio volumio[958]: info: [1732127740596] Starting Upmpd Daemon Nov 20 19:35:40 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "my_music"... Nov 20 19:35:40 volumio volumio[958]: info: Loading plugin "mpd"... Nov 20 19:35:40 volumio kernel: Key type cifs.spnego registered Nov 20 19:35:40 volumio kernel: Key type cifs.idmap registered Nov 20 19:35:40 volumio 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. Nov 20 19:35:40 volumio kernel: CIFS: Attempting to mount //192.168.178.43/music Nov 20 19:35:41 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:41 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:41 volumio volumio[958]: info: Loading plugin "upnp_browser"... Nov 20 19:35:41 volumio volumio[958]: info: Loading plugin "alarm-clock"... Nov 20 19:35:41 volumio volumio[958]: info: Loading plugin "airplay_emulation"... Nov 20 19:35:41 volumio volumio[958]: info: Starting Shairport Sync Nov 20 19:35:41 volumio volumio[958]: info: Loading plugin "last_100"... Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "webradio"... Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "i2s_dacs"... Nov 20 19:35:42 volumio volumio[958]: info: I2S DAC not set, start Auto-detection Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "volumiodiscovery"... Nov 20 19:35:42 volumio volumio[958]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 20 19:35:42 volumio volumio[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 20 19:35:42 volumio volumio[958]: *** WARNING *** For more information see Nov 20 19:35:42 volumio volumio[958]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 20 19:35:42 volumio volumio[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 20 19:35:42 volumio volumio[958]: *** WARNING *** For more information see Nov 20 19:35:42 volumio node[958]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 20 19:35:42 volumio node[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 20 19:35:42 volumio node[958]: *** WARNING *** For more information see Nov 20 19:35:42 volumio node[958]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 20 19:35:42 volumio node[958]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 20 19:35:42 volumio node[958]: *** WARNING *** For more information see Nov 20 19:35:42 volumio volumio[958]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 20 19:35:42 volumio volumio[958]: info: Discovery: Started advertising with name: Volumio Nov 20 19:35:42 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "outputs"... Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "albumart"... Nov 20 19:35:42 volumio volumio[958]: info: Plugin example_plugin is not enabled Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "inputs"... Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "updater_comm"... Nov 20 19:35:42 volumio volumio[958]: info: Plugin mpdemulation is not enabled Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "rest_api"... Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "websocket"... Nov 20 19:35:42 volumio volumio[958]: info: Starting Socket.io Server version 2.3.0 Nov 20 19:35:42 volumio volumio[958]: info: Loading plugin "fusiondsp"... Nov 20 19:35:42 volumio volumio[958]: Forking 3 albumart workers Nov 20 19:35:43 volumio volumio[958]: info: Applying required configuration parameters for plugin fusiondsp Nov 20 19:35:43 volumio volumio[958]: info: Loading i18n strings for locale de Nov 20 19:35:43 volumio volumio[958]: Updating browse sources language Nov 20 19:35:43 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 19:35:43 volumio volumio-remote-updater[644]: [2024-11-20 19:35:43] [connect] Successful connection Nov 20 19:35:43 volumio volumio[958]: Starting albumart workers Nov 20 19:35:43 volumio volumio[958]: Starting albumart workers Nov 20 19:35:44 volumio volumio[958]: Starting albumart workers Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::initPlayerControls Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 19:35:44 volumio volumio[958]: Express server listening on port 3000 Nov 20 19:35:44 volumio volumio[958]: [Metrics] WebUI: 8s 693.48ms Nov 20 19:35:44 volumio volumio[958]: info: CoreStateMachine::resetVolumioState Nov 20 19:35:44 volumio volumio[958]: info: CoreStateMachine::getcurrentVolume Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::volumioRetrievevolume Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:44 volumio volumio[958]: info: Volumio Network Manager: Network status updated: 2 Nov 20 19:35:44 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.178.69 from 192.168.178.31 UA: Mozilla/5.0 (Linux; Android 15; Pixel 7 Pro Build/AP3A.241105.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 20 19:35:44 volumio volumio-remote-updater[644]: [2024-11-20 19:35:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732127743 101 Nov 20 19:35:44 volumio volumio[958]: 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: 2 Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Nov 20 19:35:44 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 20 19:35:44 volumio volumio[958]: info: Discovery: Getting this device information Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Nov 20 19:35:44 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 20 19:35:44 volumio volumio[958]: info: Reloading queue from file Nov 20 19:35:44 volumio volumio[958]: info: VolumeController:: Volume=100 Mute =false Nov 20 19:35:44 volumio volumio[958]: info: CoreStateMachine::pushState Nov 20 19:35:44 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Nov 20 19:35:44 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Nov 20 19:35:44 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::volumioRetrievevolume Nov 20 19:35:44 volumio volumio[958]: info: CoreStateMachine::setRepeat null single undefined Nov 20 19:35:44 volumio volumio[958]: info: CoreStateMachine::pushState Nov 20 19:35:44 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Nov 20 19:35:44 volumio volumio[958]: info: CoreStateMachine::setRandom null Nov 20 19:35:44 volumio volumio[958]: info: CoreStateMachine::pushState Nov 20 19:35:44 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:44 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Nov 20 19:35:44 volumio volumio[958]: info: Setting Device type: Raspberry PI Nov 20 19:35:44 volumio volumio[958]: info: Listing playlists Nov 20 19:35:44 volumio volumio[958]: info: Listing playlists Nov 20 19:35:44 volumio volumio[958]: info: Completed loading Core Plugins Nov 20 19:35:45 volumio volumio[958]: info: Preparing to generate the ALSA configuration file Nov 20 19:35:45 volumio volumio[958]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 20 19:35:45 volumio volumio[958]: info: Reading ALSA contributions from plugins. Nov 20 19:35:45 volumio volumio[958]: info: VolumeController:: Volume=100 Mute =false Nov 20 19:35:45 volumio volumio[958]: info: CoreStateMachine::pushState Nov 20 19:35:45 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Nov 20 19:35:45 volumio volumio[958]: info: Asound.conf file unchanged, so no further update is needed Nov 20 19:35:45 volumio volumio[958]: info: Output device has changed, restarting MPD Nov 20 19:35:45 volumio volumio[958]: info: Output device has changed, restarting Shairport Sync Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:45 volumio sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 20 19:35:45 volumio sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:45 volumio sudo[1135]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:45 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 20 19:35:45 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:45 volumio systemd[1]: Stopping Music Player Daemon... Nov 20 19:35:45 volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 20 19:35:45 volumio volumio[958]: info: ___________ START PLUGINS ___________ Nov 20 19:35:45 volumio volumio[958]: info: ControllerMpd::onStart: Initializing MPD Nov 20 19:35:45 volumio volumio[958]: info: Creating MPD Configuration file Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 20 19:35:45 volumio volumio[958]: info: [1732127745182] CoreMusicLibrary::Adding element Medienserver Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 19:35:45 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 20 19:35:45 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:45 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:45 volumio sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 20 19:35:45 volumio sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:45 volumio systemd[1]: mpd.service: Succeeded. Nov 20 19:35:45 volumio systemd[1]: Stopped Music Player Daemon. Nov 20 19:35:45 volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:45 volumio systemd[1]: Starting Music Player Daemon... Nov 20 19:35:45 volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 20 19:35:45 volumio volumio[958]: info: [1732127745365] CoreMusicLibrary::Adding element Last_100 Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 20 19:35:45 volumio volumio[958]: info: [1732127745369] CoreMusicLibrary::Adding element Webradio Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 20 19:35:45 volumio volumio[958]: info: Initializing BBC Radios Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: Loading i18n strings for locale de Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: FusionDsp - mixtype--------------------- Hardware Nov 20 19:35:45 volumio volumio[958]: info: Volumio Calling Home Nov 20 19:35:45 volumio sudo[1152]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 20 19:35:45 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:45 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:45 volumio volumio[958]: info: Preparing to generate the ALSA configuration file Nov 20 19:35:45 volumio volumio[958]: info: Discovery: adding f894648e-c8ed-4d08-be24-5d4e8b32e9a6 Nov 20 19:35:45 volumio volumio[958]: info: Discovery: Found device Volumio Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Nov 20 19:35:45 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:45 volumio volumio[958]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 20 19:35:45 volumio volumio[958]: info: Reading ALSA contributions from plugins. Nov 20 19:35:45 volumio volumio[958]: info: MPD Permissions set Nov 20 19:35:45 volumio volumio[958]: info: MPD Permissions set Nov 20 19:35:45 volumio volumio[958]: info: Volumio called home Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 20 19:35:45 volumio volumio[958]: info: Discovery: Getting this device information Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Nov 20 19:35:45 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 20 19:35:45 volumio volumio[958]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.178.69:3000 from 192.168.178.31 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Nov 20 19:35:46 volumio volumio[958]: info: Starting Shairport Sync Nov 20 19:35:46 volumio volumio[958]: info: Starting Shairport Sync Nov 20 19:35:46 volumio volumio[958]: info: Starting Shairport Sync Nov 20 19:35:46 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 20 19:35:46 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:46 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 20 19:35:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 20 19:35:46 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 20 19:35:46 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 20 19:35:46 volumio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 20 19:35:46 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Nov 20 19:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 20 19:35:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 20 19:35:46 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Nov 20 19:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Nov 20 19:35:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 20 19:35:46 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 20 19:35:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 20 19:35:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 20 19:35:46 volumio volumio-remote-updater[644]: No test mode Nov 20 19:35:46 volumio volumio-remote-updater[644]: No alpha test mode Nov 20 19:35:46 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:46 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:46 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 20 19:35:46 volumio volumio[958]: info: Shairport-Sync Started Nov 20 19:35:46 volumio volumio[958]: Error adding Membership: Error: addMembership EINVAL Nov 20 19:35:46 volumio volumio[958]: info: Asound.conf file unchanged, so no further update is needed Nov 20 19:35:46 volumio volumio[958]: info: Output device has changed, restarting MPD Nov 20 19:35:46 volumio volumio[958]: info: Output device has changed, restarting Shairport Sync Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:46 volumio sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 20 19:35:46 volumio sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:46 volumio sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 20 19:35:46 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:46 volumio sudo[1196]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:46 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 20 19:35:46 volumio volumio[958]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 20 19:35:46 volumio systemd[1]: mpd.service: Succeeded. Nov 20 19:35:46 volumio systemd[1]: Stopped Music Player Daemon. Nov 20 19:35:46 volumio volumio[958]: info: Shairport-Sync Started Nov 20 19:35:46 volumio volumio[958]: info: Shairport-Sync Started Nov 20 19:35:46 volumio volumio[958]: info: MPD Permissions set Nov 20 19:35:46 volumio systemd[1]: Starting Music Player Daemon... Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:46 volumio volumio[958]: info: Starting Shairport Sync Nov 20 19:35:46 volumio volumio[958]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 20 19:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 20 19:35:46 volumio sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 20 19:35:46 volumio sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 20 19:35:46 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 20 19:35:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 20 19:35:46 volumio sudo[1204]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 20 19:35:46 volumio sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:46 volumio sudo[1204]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 20 19:35:46 volumio sudo[1212]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:46 volumio volumio[958]: info: Shairport-Sync Started Nov 20 19:35:47 volumio volumio[958]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 20 19:35:47 volumio volumio[958]: info: camilladsp service started and running in background, instance 1 Nov 20 19:35:47 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 20 19:35:47 volumio volumio[958]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Nov 20 19:35:47 volumio volumio[958]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 20 19:35:47 volumio volumio[958]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Nov 20 19:35:47 volumio volumio[958]: info: FusionDsp loaded Nov 20 19:35:47 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 20 19:35:47 volumio sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 20 19:35:47 volumio sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:47 volumio volumio[958]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 20 19:35:47 volumio volumio[958]: info: Adding Signal Path Element [object Object] Nov 20 19:35:47 volumio volumio[958]: info: Adding fusiondspeq DSP Signal Path Element Nov 20 19:35:47 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root Nov 20 19:35:47 volumio volumio[958]: info: FusionDsp - ---- installed callbackRead Nov 20 19:35:47 volumio volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 19:35:47 volumio volumio[958]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Nov 20 19:35:47 volumio volumio[958]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 20 19:35:47 volumio volumio[958]: at onErrorNT (internal/child_process.js:465:16) Nov 20 19:35:47 volumio volumio[958]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 20 19:35:47 volumio volumio[958]: errno: -2, Nov 20 19:35:47 volumio volumio[958]: code: 'ENOENT', Nov 20 19:35:47 volumio volumio[958]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 20 19:35:47 volumio volumio[958]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 20 19:35:47 volumio volumio[958]: spawnargs: [ Nov 20 19:35:47 volumio volumio[958]: '-p', Nov 20 19:35:47 volumio volumio[958]: 9876, Nov 20 19:35:47 volumio volumio[958]: '-o', Nov 20 19:35:47 volumio volumio[958]: '/tmp/camilladsp.log', Nov 20 19:35:47 volumio volumio[958]: '-l', Nov 20 19:35:47 volumio volumio[958]: 'warn', Nov 20 19:35:47 volumio volumio[958]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 20 19:35:47 volumio volumio[958]: ] Nov 20 19:35:47 volumio volumio[958]: } Nov 20 19:35:47 volumio volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 19:35:48 volumio sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-20 19:34 Nov 20 19:35:48 volumio sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 19:35:48 volumio mpd[1217]: Nov 20 19:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"