-- Logs begin at Thu 2019-02-14 05:12:00 EST, end at Thu 2025-04-17 13:27:39 EDT. -- Apr 17 13:27:15 volumio wireless.js[596]: trying... Apr 17 13:27:15 volumio ntpd[773]: receive: Unexpected origin timestamp 0xeb9548b3.108bb578 does not match aorg 0000000000.00000000 from server@5.78.62.36 xmt 0xebabb873.4969d09b Apr 17 13:27:15 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 13:27:15 volumio systemd[1]: Starting Daily apt download activities... Apr 17 13:27:15 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:15 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:16 volumio wireless.js[596]: trying... Apr 17 13:27:16 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 13:27:16 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:16 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:16 volumio dhcpcd[998]: wlan0: leased 192.168.1.39 for 86400 seconds Apr 17 13:27:16 volumio avahi-daemon[611]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.39. Apr 17 13:27:16 volumio dhcpcd[998]: wlan0: adding route to 192.168.1.0/24 Apr 17 13:27:16 volumio dhcpcd[998]: wlan0: adding default route via 192.168.1.1 Apr 17 13:27:16 volumio avahi-daemon[611]: New relevant interface wlan0.IPv4 for mDNS. Apr 17 13:27:16 volumio avahi-daemon[611]: Registering new address record for 192.168.1.39 on wlan0.IPv4. Apr 17 13:27:17 volumio wireless.js[596]: trying... Apr 17 13:27:17 volumio sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 13:27:17 volumio sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:17 volumio sudo[1071]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:17 volumio wireless.js[596]: Connected to: ----Beach House Apr 17 13:27:17 volumio wireless.js[596]: ---- Apr 17 13:27:17 volumio sudo[1074]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 13:27:17 volumio sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:17 volumio sudo[1074]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:17 volumio wireless.js[596]: ... joined AP, wlan0 IPv4 is 192.168.1.39, ipV6 is undefined Apr 17 13:27:17 volumio wireless.js[596]: It's done! AP Apr 17 13:27:17 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 17 13:27:17 volumio systemd[1]: Started Wireless Services. Apr 17 13:27:17 volumio systemd[1]: Started Volumio Backend Module. Apr 17 13:27:17 volumio systemd[1]: Started Volumio Cpu Tweaker. Apr 17 13:27:17 volumio volumio-cpu-tweak[1080]: Setting RT Priority for mpd Apr 17 13:27:17 volumio volumio-cpu-tweak[1080]: Setting MPD Affinity Apr 17 13:27:17 volumio volumio-cpu-tweak[1080]: pid 805's current affinity mask: f Apr 17 13:27:17 volumio volumio-cpu-tweak[1080]: pid 805's new affinity mask: 3 Apr 17 13:27:17 volumio volumio-cpu-tweak[1080]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 17 13:27:17 volumio volumio-cpu-tweak[1080]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 17 13:27:17 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 17 13:27:18 volumio ntpd[773]: Listen normally on 4 wlan0 192.168.1.39:123 Apr 17 13:27:18 volumio ntpd[773]: new interface(s) found: waking up resolver Apr 17 13:27:22 volumio volumio[1079]: info: ------------------------------------------- Apr 17 13:27:22 volumio volumio[1079]: info: ----- Volumio3 ---- Apr 17 13:27:22 volumio volumio[1079]: info: ------------------------------------------- Apr 17 13:27:22 volumio volumio[1079]: info: ----- System startup ---- Apr 17 13:27:22 volumio volumio[1079]: info: ------------------------------------------- Apr 17 13:27:23 volumio volumio-remote-updater[610]: [2025-04-17 13:27:23] [connect] Successful connection Apr 17 13:27:24 volumio volumio[1079]: info: MYVOLUMIO Environment detected Apr 17 13:27:25 volumio systemd[1]: apt-daily.service: Succeeded. Apr 17 13:27:25 volumio systemd[1]: Started Daily apt download activities. Apr 17 13:27:25 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Apr 17 13:27:25 volumio volumio[1079]: info: Plugin folders cleanup Apr 17 13:27:25 volumio volumio[1079]: info: Scanning into folder /volumio/app/plugins/ Apr 17 13:27:25 volumio volumio[1079]: info: Scanning category audio_interface Apr 17 13:27:25 volumio volumio[1079]: info: Scanning category miscellanea Apr 17 13:27:25 volumio volumio[1079]: info: Scanning category music_service Apr 17 13:27:25 volumio volumio[1079]: info: Scanning category plugins.json Apr 17 13:27:25 volumio volumio[1079]: info: Scanning category system_controller Apr 17 13:27:25 volumio volumio[1079]: info: Scanning category user_interface Apr 17 13:27:25 volumio volumio[1079]: info: Scanning into folder /data/plugins/ Apr 17 13:27:25 volumio volumio[1079]: info: Scanning category audio_interface Apr 17 13:27:25 volumio volumio[1079]: info: Plugin folders cleanup completed Apr 17 13:27:25 volumio volumio[1079]: info: ------------------------------------------- Apr 17 13:27:25 volumio volumio[1079]: info: ----- Core plugins startup ---- Apr 17 13:27:25 volumio volumio[1079]: info: ------------------------------------------- Apr 17 13:27:25 volumio volumio[1079]: info: Loading plugins from folder /volumio/app/plugins/ Apr 17 13:27:25 volumio volumio[1079]: info: Adding plugin upnp to MyMusic Plugins Apr 17 13:27:25 volumio volumio[1079]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 17 13:27:25 volumio volumio[1079]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 17 13:27:25 volumio volumio[1079]: info: Loading plugins from folder /data/plugins/ Apr 17 13:27:25 volumio volumio[1079]: info: Loading plugin "system"... Apr 17 13:27:25 volumio volumio[1079]: info: Loading plugin "appearance"... Apr 17 13:27:28 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "network"... Apr 17 13:27:28 volumio volumio[1079]: info: Refreshing Cached IP Addresses Apr 17 13:27:28 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 13:27:28 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:28 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:28 volumio sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 13:27:28 volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:28 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "services"... Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "alsa_controller"... Apr 17 13:27:28 volumio sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 17 13:27:28 volumio sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:28 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "wizard"... Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "networkfs"... Apr 17 13:27:28 volumio volumio[1079]: info: Starting Udev Watcher for removable devices Apr 17 13:27:28 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Chris,password=qNa9W$6#3w,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.194/music /mnt/NAS/NolanVault Apr 17 13:27:28 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:28 volumio volumio[1079]: info: Ignoring mount for partition: boot Apr 17 13:27:28 volumio volumio[1079]: info: Ignoring mount for partition: volumio Apr 17 13:27:28 volumio volumio[1079]: info: Ignoring mount for partition: volumio_data Apr 17 13:27:28 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "volumio_command_line_client"... Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "upnp"... Apr 17 13:27:28 volumio volumio[1079]: info: [1744910848917] Starting Upmpd Daemon Apr 17 13:27:28 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "my_music"... Apr 17 13:27:28 volumio volumio[1079]: info: Loading plugin "mpd"... Apr 17 13:27:29 volumio kernel: Key type cifs.spnego registered Apr 17 13:27:29 volumio kernel: Key type cifs.idmap registered Apr 17 13:27:29 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. Apr 17 13:27:29 volumio kernel: CIFS: Attempting to mount //192.168.1.194/music Apr 17 13:27:29 volumio sudo[1140]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:29 volumio kernel: cryptd: max_cpu_qlen set to 1000 Apr 17 13:27:29 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:30 volumio volumio[1079]: info: Loading plugin "upnp_browser"... Apr 17 13:27:30 volumio volumio[1079]: info: Loading plugin "alarm-clock"... Apr 17 13:27:31 volumio volumio[1079]: info: Loading plugin "airplay_emulation"... Apr 17 13:27:31 volumio volumio[1079]: info: Starting Shairport Sync Apr 17 13:27:31 volumio volumio[1079]: info: Loading plugin "last_100"... Apr 17 13:27:31 volumio volumio[1079]: info: Loading plugin "webradio"... Apr 17 13:27:31 volumio volumio[1079]: info: Loading plugin "i2s_dacs"... Apr 17 13:27:31 volumio volumio[1079]: info: I2S DAC not set, start Auto-detection Apr 17 13:27:31 volumio volumio[1079]: info: Loading plugin "volumiodiscovery"... Apr 17 13:27:31 volumio volumio[1079]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 13:27:31 volumio volumio[1079]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 13:27:31 volumio node[1079]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 13:27:31 volumio volumio[1079]: *** WARNING *** For more information see Apr 17 13:27:31 volumio volumio[1079]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 13:27:31 volumio volumio[1079]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 13:27:31 volumio volumio[1079]: *** WARNING *** For more information see Apr 17 13:27:31 volumio node[1079]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 13:27:31 volumio node[1079]: *** WARNING *** For more information see Apr 17 13:27:31 volumio node[1079]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 13:27:31 volumio node[1079]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 13:27:31 volumio node[1079]: *** WARNING *** For more information see Apr 17 13:27:31 volumio volumio[1079]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 17 13:27:31 volumio volumio[1079]: info: Discovery: Started advertising with name: Volumio Apr 17 13:27:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 13:27:32 volumio volumio[1079]: info: Loading plugin "outputs"... Apr 17 13:27:32 volumio volumio[1079]: info: Loading plugin "albumart"... Apr 17 13:27:32 volumio volumio[1079]: info: Plugin example_plugin is not enabled Apr 17 13:27:32 volumio volumio[1079]: info: Loading plugin "inputs"... Apr 17 13:27:32 volumio volumio[1079]: info: Loading plugin "updater_comm"... Apr 17 13:27:32 volumio dbus-daemon[619]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Apr 17 13:27:32 volumio volumio-time-update[632]: Failed to set time: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Apr 17 13:27:32 volumio sudo[873]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:32 volumio volumio-time-update[632]: volumio-time-update-util: Error setting time. Apr 17 13:27:32 volumio volumio-time-update[632]: volumio-time-update-util: Retrying in 5 seconds... Apr 17 13:27:32 volumio volumio[1079]: info: Plugin mpdemulation is not enabled Apr 17 13:27:32 volumio volumio[1079]: info: Loading plugin "rest_api"... Apr 17 13:27:32 volumio volumio[1079]: info: Loading plugin "websocket"... Apr 17 13:27:32 volumio volumio[1079]: info: Starting Socket.io Server version 2.3.0 Apr 17 13:27:32 volumio volumio[1079]: info: Loading plugin "fusiondsp"... Apr 17 13:27:32 volumio volumio[1079]: Forking 3 albumart workers Apr 17 13:27:33 volumio volumio[1079]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Apr 17 13:27:34 volumio volumio[1079]: info: Loading i18n strings for locale en Apr 17 13:27:34 volumio volumio[1079]: Updating browse sources language Apr 17 13:27:34 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 13:27:34 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 17 13:27:34 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 17 13:27:34 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::initPlayerControls Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: Express server listening on port 3000 Apr 17 13:27:35 volumio volumio[1079]: [Metrics] WebUI: 14s 167.96ms Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::resetVolumioState Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::getcurrentVolume Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 13:27:35 volumio volumio[1079]: info: Volumio Network Manager: Network status updated: 3 Apr 17 13:27:35 volumio volumio[1079]: Starting albumart workers Apr 17 13:27:35 volumio volumio[1079]: verbose: New Socket.io Connection to 192.168.1.39 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 1 Apr 17 13:27:35 volumio volumio[1079]: verbose: New Socket.io Connection to 192.168.1.39 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 2 Apr 17 13:27:35 volumio volumio[1079]: Starting albumart workers Apr 17 13:27:35 volumio volumio[1079]: verbose: New Socket.io Connection to 192.168.1.39 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 3 Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::pushState Apr 17 13:27:35 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::updateTrackBlock Apr 17 13:27:35 volumio volumio[1079]: info: CorePlayQueue::getTrackBlock Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 13:27:35 volumio volumio[1079]: Starting albumart workers Apr 17 13:27:35 volumio volumio[1079]: info: Reloading queue from file Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::setRepeat null single undefined Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::pushState Apr 17 13:27:35 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::setRandom null Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::pushState Apr 17 13:27:35 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Apr 17 13:27:35 volumio volumio[1079]: info: Setting Device type: Raspberry PI Apr 17 13:27:35 volumio volumio[1079]: info: CoreStateMachine::pushState Apr 17 13:27:35 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Apr 17 13:27:35 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Apr 17 13:27:35 volumio volumio[1079]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 17 13:27:35 volumio volumio[1079]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Apr 17 13:27:35 volumio volumio[1079]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 17 13:27:36 volumio volumio[1079]: info: Discovery: adding 4ed50518-b105-4c7e-ad0a-88fe71358224 Apr 17 13:27:36 volumio volumio[1079]: info: Discovery: Found device Volumio Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Apr 17 13:27:36 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Apr 17 13:27:36 volumio volumio[1079]: info: Discovery: this is already registered, 4ed50518-b105-4c7e-ad0a-88fe71358224 Apr 17 13:27:36 volumio volumio[1079]: info: Discovery: Found device Volumio Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Apr 17 13:27:36 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Apr 17 13:27:36 volumio volumio[1079]: info: Completed loading Core Plugins Apr 17 13:27:36 volumio volumio[1079]: info: Preparing to generate the ALSA configuration file Apr 17 13:27:36 volumio volumio[1079]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 17 13:27:36 volumio volumio[1079]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 13:27:36 volumio volumio[1079]: info: Reading ALSA contributions from plugins. Apr 17 13:27:36 volumio volumio[1079]: info: Asound.conf file unchanged, so no further update is needed Apr 17 13:27:36 volumio volumio[1079]: info: Output device has changed, restarting MPD Apr 17 13:27:36 volumio volumio[1079]: info: Output device has changed, restarting Shairport Sync Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 13:27:36 volumio sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 13:27:36 volumio sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:36 volumio sudo[1253]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:36 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 13:27:36 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:36 volumio volumio[1079]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 13:27:36 volumio volumio[1079]: info: ___________ START PLUGINS ___________ Apr 17 13:27:36 volumio volumio[1079]: info: ControllerMpd::onStart: Initializing MPD Apr 17 13:27:36 volumio volumio[1079]: info: Creating MPD Configuration file Apr 17 13:27:36 volumio systemd[1]: Stopping Music Player Daemon... Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 13:27:36 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 13:27:36 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:36 volumio volumio[1079]: info: [1744910856539] CoreMusicLibrary::Adding element Media Servers Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 13:27:36 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 13:27:36 volumio systemd[1]: mpd.service: Succeeded. Apr 17 13:27:36 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 13:27:36 volumio sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 13:27:36 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:36 volumio systemd[1]: Starting Music Player Daemon... Apr 17 13:27:36 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 17 13:27:36 volumio volumio[1079]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 13:27:36 volumio systemd[1]: mpd.service: Succeeded. Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 13:27:36 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 13:27:36 volumio systemd[1]: Starting Music Player Daemon... Apr 17 13:27:36 volumio volumio[1079]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 13:27:36 volumio volumio[1079]: info: [1744910856989] CoreMusicLibrary::Adding element Last_100 Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 13:27:36 volumio volumio[1079]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 13:27:36 volumio volumio[1079]: info: [1744910856996] CoreMusicLibrary::Adding element Webradio Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 13:27:37 volumio volumio[1079]: info: Initializing BBC Radios Apr 17 13:27:37 volumio sudo[1271]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 17 13:27:37 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:37 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 13:27:37 volumio volumio[1079]: info: Loading i18n strings for locale en Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:37 volumio volumio[1079]: info: FusionDsp - mixtype--------------------- Software Apr 17 13:27:37 volumio volumio[1079]: info: Volumio Calling Home Apr 17 13:27:37 volumio volumio[1079]: info: Preparing to generate the ALSA configuration file Apr 17 13:27:37 volumio volumio-time-update[632]: volumio-time-update-util: Fetching time from Volumio... Apr 17 13:27:37 volumio volumio[1079]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 17 13:27:37 volumio volumio[1079]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 13:27:37 volumio volumio[1079]: info: Reading ALSA contributions from plugins. Apr 17 13:27:37 volumio volumio[1079]: info: MPD Permissions set Apr 17 13:27:37 volumio volumio[1079]: info: MPD Permissions set Apr 17 13:27:37 volumio volumio-time-update[632]: volumio-time-update-util: Setting system time to: 2025-04-17 13:27:37 Apr 17 13:27:37 volumio sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 13:27:37 volumio sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:37 volumio sudo[1323]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-04-17 13:27:37 Apr 17 13:27:37 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:37 volumio sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 13:27:37 volumio sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:38 volumio sudo[1319]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:38 volumio sudo[1322]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:38 volumio dbus-daemon[619]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=1325 comm="timedatectl set-time 2025-04-17 13:27:37 ") Apr 17 13:27:38 volumio systemd[1]: Starting Time & Date Service... Apr 17 13:27:38 volumio volumio[1079]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 13:27:38 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:38 volumio dbus-daemon[619]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 17 13:27:38 volumio systemd[1]: Started Time & Date Service. Apr 17 13:27:37 volumio systemd-timedated[1327]: Changed local time to Thu Apr 17 13:27:37 2025 Apr 17 13:27:37 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:37 volumio volumio-time-update[632]: volumio-time-update-util: System time updated successfully. Apr 17 13:27:37 volumio systemd[1]: Started Volumio Time Update Utility. Apr 17 13:27:37 volumio systemd[1]: Reached target Multi-User System. Apr 17 13:27:37 volumio systemd[1]: Reached target Graphical Interface. Apr 17 13:27:37 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 17 13:27:37 volumio volumio[1079]: info: Volumio called home Apr 17 13:27:37 volumio volumio[1079]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Apr 17 13:27:37 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 17 13:27:37 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 17 13:27:37 volumio volumio[1079]: info: Starting Shairport Sync Apr 17 13:27:37 volumio volumio[1079]: info: Starting Shairport Sync Apr 17 13:27:37 volumio volumio[1079]: info: Starting Shairport Sync Apr 17 13:27:37 volumio sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 13:27:37 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:37 volumio sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 13:27:37 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 13:27:37 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 13:27:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 13:27:37 volumio sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 13:27:37 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Apr 17 13:27:37 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Apr 17 13:27:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 13:27:37 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:37 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:37 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:37 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 13:27:37 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:37 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:37 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 13:27:37 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:37 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:37 volumio volumio[1079]: info: Shairport-Sync Started Apr 17 13:27:37 volumio volumio[1079]: Error adding Membership: Error: addMembership EINVAL Apr 17 13:27:37 volumio volumio[1079]: info: Shairport-Sync Started Apr 17 13:27:37 volumio volumio[1079]: info: Shairport-Sync Started Apr 17 13:27:37 volumio volumio[1079]: info: camilladsp service started and running in background, instance 1 Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 13:27:37 volumio volumio[1079]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Apr 17 13:27:37 volumio volumio[1079]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 17 13:27:37 volumio volumio[1079]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Apr 17 13:27:37 volumio volumio[1079]: info: FusionDsp loaded Apr 17 13:27:37 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 13:27:37 volumio sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 17 13:27:37 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 13:27:38 volumio volumio[1079]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 17 13:27:38 volumio volumio[1079]: info: Adding Signal Path Element [object Object] Apr 17 13:27:38 volumio volumio[1079]: info: Adding fusiondspeq DSP Signal Path Element Apr 17 13:27:38 volumio volumio[1079]: info: FusionDsp - ---- installed callbackRead Apr 17 13:27:38 volumio volumio[1079]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 13:27:38 volumio volumio[1079]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Apr 17 13:27:38 volumio volumio[1079]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 17 13:27:38 volumio volumio[1079]: at onErrorNT (internal/child_process.js:465:16) Apr 17 13:27:38 volumio volumio[1079]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 17 13:27:38 volumio volumio[1079]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 17 13:27:38 volumio volumio[1079]: at listOnTimeout (internal/timers.js:523:9) Apr 17 13:27:38 volumio volumio[1079]: at processTimers (internal/timers.js:497:7) { Apr 17 13:27:38 volumio volumio[1079]: errno: -13, Apr 17 13:27:38 volumio volumio[1079]: code: 'EACCES', Apr 17 13:27:38 volumio volumio[1079]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 13:27:38 volumio volumio[1079]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 13:27:38 volumio volumio[1079]: spawnargs: [ Apr 17 13:27:38 volumio volumio[1079]: '-p', Apr 17 13:27:38 volumio volumio[1079]: 9876, Apr 17 13:27:38 volumio volumio[1079]: '-o', Apr 17 13:27:38 volumio volumio[1079]: '/tmp/camilladsp.log', Apr 17 13:27:38 volumio volumio[1079]: '-l', Apr 17 13:27:38 volumio volumio[1079]: 'warn', Apr 17 13:27:38 volumio volumio[1079]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 17 13:27:38 volumio volumio[1079]: ] Apr 17 13:27:38 volumio volumio[1079]: } Apr 17 13:27:38 volumio volumio[1079]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 13:27:38 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:38 volumio volumio-remote-updater[610]: [2025-04-17 13:27:38] [connect] Successful connection Apr 17 13:27:39 volumio mpd[1291]: Apr 17 13:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 17 13:27:39 volumio systemd[1]: Started Music Player Daemon. Apr 17 13:27:39 volumio systemd[1]: Startup finished in 16.232s (kernel) + 47.927s (userspace) = 1min 4.159s. Apr 17 13:27:39 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:39 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Apr 17 13:27:39 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-17 13:26 Apr 17 13:27:39 volumio sudo[1392]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"