-- Logs begin at Thu 2025-07-03 16:59:42 CEST, end at Sat 2025-07-05 08:28:00 CEST. -- Jul 05 08:27:00 volumiozwpi3 systemd[1]: Started Samba SMB Daemon. Jul 05 08:27:00 volumiozwpi3 smbd[1021]: [2025/07/05 08:27:00.273757, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 05 08:27:00 volumiozwpi3 systemd[1]: Reached target Multi-User System. Jul 05 08:27:00 volumiozwpi3 smbd[1021]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 05 08:27:00 volumiozwpi3 systemd[1]: Reached target Graphical Interface. Jul 05 08:27:00 volumiozwpi3 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 05 08:27:00 volumiozwpi3 volumio-remote-updater[699]: [2025-07-05 08:27:00] [info] asio async_connect error: system:111 (Connection refused) Jul 05 08:27:00 volumiozwpi3 volumio-remote-updater[699]: [2025-07-05 08:27:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 05 08:27:00 volumiozwpi3 volumio-remote-updater[699]: [2025-07-05 08:27:00] [error] handle_connect error: Underlying Transport Error Jul 05 08:27:00 volumiozwpi3 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 05 08:27:00 volumiozwpi3 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 05 08:27:00 volumiozwpi3 systemd[1]: Startup finished in 25.735s (kernel) + 1min 29.952s (userspace) = 1min 55.688s. Jul 05 08:27:06 volumiozwpi3 volumio[974]: info: ------------------------------------------- Jul 05 08:27:06 volumiozwpi3 volumio[974]: info: ----- Volumio3 ---- Jul 05 08:27:06 volumiozwpi3 volumio[974]: info: ------------------------------------------- Jul 05 08:27:06 volumiozwpi3 volumio[974]: info: ----- System startup ---- Jul 05 08:27:06 volumiozwpi3 volumio[974]: info: ------------------------------------------- Jul 05 08:27:08 volumiozwpi3 ntpd[536]: Soliciting pool server 194.50.19.204 Jul 05 08:27:09 volumiozwpi3 ntpd[536]: Soliciting pool server 188.68.34.173 Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: MYVOLUMIO Environment detected Jul 05 08:27:10 volumiozwpi3 ntpd[536]: Soliciting pool server 162.159.200.1 Jul 05 08:27:10 volumiozwpi3 ntpd[536]: Soliciting pool server 130.61.133.198 Jul 05 08:27:10 volumiozwpi3 volumio-remote-updater[699]: [2025-07-05 08:27:10] [connect] Successful connection Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Plugin folders cleanup Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning into folder /volumio/app/plugins/ Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category audio_interface Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category miscellanea Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category music_service Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category plugins.json Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category system_controller Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category user_interface Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning into folder /data/plugins/ Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category audio_interface Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category system_controller Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Scanning category user_interface Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Plugin folders cleanup completed Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: ------------------------------------------- Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: ----- Core plugins startup ---- Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: ------------------------------------------- Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Adding plugin upnp to MyMusic Plugins Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Loading plugins from folder /data/plugins/ Jul 05 08:27:10 volumiozwpi3 volumio[974]: info: Loading plugin "system"... Jul 05 08:27:11 volumiozwpi3 volumio[974]: info: Loading plugin "appearance"... Jul 05 08:27:11 volumiozwpi3 ntpd[536]: Soliciting pool server 185.252.140.125 Jul 05 08:27:11 volumiozwpi3 ntpd[536]: Soliciting pool server 45.9.61.155 Jul 05 08:27:12 volumiozwpi3 ntpd[536]: Soliciting pool server 31.209.85.242 Jul 05 08:27:12 volumiozwpi3 ntpd[536]: Soliciting pool server 192.248.187.154 Jul 05 08:27:12 volumiozwpi3 ntpd[536]: Soliciting pool server 144.76.43.40 Jul 05 08:27:13 volumiozwpi3 ntpd[536]: Soliciting pool server 217.144.138.234 Jul 05 08:27:13 volumiozwpi3 ntpd[536]: Soliciting pool server 129.70.132.34 Jul 05 08:27:13 volumiozwpi3 ntpd[536]: Soliciting pool server 93.241.86.156 Jul 05 08:27:14 volumiozwpi3 ntpd[536]: Soliciting pool server 185.255.121.15 Jul 05 08:27:14 volumiozwpi3 ntpd[536]: Soliciting pool server 5.189.151.39 Jul 05 08:27:15 volumiozwpi3 ntpd[536]: Soliciting pool server 131.188.3.221 Jul 05 08:27:15 volumiozwpi3 ntpd[536]: Soliciting pool server 5.1.73.50 Jul 05 08:27:16 volumiozwpi3 ntpd[536]: Soliciting pool server 2001:8d8:1801:8156::1 Jul 05 08:27:18 volumiozwpi3 ntpd[536]: receive: Unexpected origin timestamp 0xec134444.5374a60d does not match aorg 0000000000.00000000 from server@217.144.138.234 xmt 0xec134446.85c4e0e6 Jul 05 08:27:18 volumiozwpi3 ntpd[536]: receive: Unexpected origin timestamp 0xec134444.537c463f does not match aorg 0000000000.00000000 from server@144.76.43.40 xmt 0xec134446.85e137ff Jul 05 08:27:18 volumiozwpi3 ntpd[536]: receive: Unexpected origin timestamp 0xec134444.53842c3c does not match aorg 0000000000.00000000 from server@185.252.140.125 xmt 0xec134446.85c7a376 Jul 05 08:27:18 volumiozwpi3 ntpd[536]: receive: Unexpected origin timestamp 0xec134444.538fc3fa does not match aorg 0000000000.00000000 from server@194.50.19.204 xmt 0xec134446.86bf1994 Jul 05 08:27:18 volumiozwpi3 ntpd[536]: receive: Unexpected origin timestamp 0xec134444.536da678 does not match aorg 0000000000.00000000 from server@93.241.86.156 xmt 0xec134446.86db4263 Jul 05 08:27:18 volumiozwpi3 volumio[974]: info: Loading plugin "network"... Jul 05 08:27:19 volumiozwpi3 volumio[974]: info: Refreshing Cached IP Addresses Jul 05 08:27:19 volumiozwpi3 volumio[974]: info: Loading plugin "services"... Jul 05 08:27:19 volumiozwpi3 volumio[974]: info: Loading plugin "alsa_controller"... Jul 05 08:27:19 volumiozwpi3 sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 08:27:19 volumiozwpi3 sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 08:27:19 volumiozwpi3 sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:19 volumiozwpi3 sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:19 volumiozwpi3 sudo[1091]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:19 volumiozwpi3 sudo[1093]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:19 volumiozwpi3 sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 08:27:19 volumiozwpi3 sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:19 volumiozwpi3 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 05 08:27:20 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 08:27:20 volumiozwpi3 volumio[974]: info: Loading plugin "wizard"... Jul 05 08:27:20 volumiozwpi3 volumio[974]: info: Loading plugin "networkfs"... Jul 05 08:27:20 volumiozwpi3 volumio[974]: info: Starting Udev Watcher for removable devices Jul 05 08:27:20 volumiozwpi3 sudo[1100]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:20 volumiozwpi3 sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=roland,password=FdnNg1nP?,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.20/Musik /mnt/NAS/NAS_Musik Jul 05 08:27:20 volumiozwpi3 sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:20 volumiozwpi3 sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=roland,password=FdnNg1nP?,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.20/Sprachkurse /mnt/NAS/Sprachkurse Jul 05 08:27:20 volumiozwpi3 sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: Ignoring mount for partition: boot Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: Ignoring mount for partition: volumio Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: Ignoring mount for partition: volumio_data Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: Loading plugin "volumio_command_line_client"... Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: Loading plugin "upnp"... Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: [1751696841268] Starting Upmpd Daemon Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: Loading plugin "my_music"... Jul 05 08:27:21 volumiozwpi3 volumio[974]: info: Loading plugin "mpd"... Jul 05 08:27:21 volumiozwpi3 kernel: Key type cifs.spnego registered Jul 05 08:27:21 volumiozwpi3 kernel: Key type cifs.idmap registered Jul 05 08:27:21 volumiozwpi3 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. Jul 05 08:27:21 volumiozwpi3 kernel: CIFS: Attempting to mount //192.168.178.20/Sprachkurse Jul 05 08:27:23 volumiozwpi3 sudo[1124]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:23 volumiozwpi3 kernel: CIFS: Attempting to mount //192.168.178.20/Musik Jul 05 08:27:23 volumiozwpi3 sudo[1122]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:24 volumiozwpi3 volumio[974]: info: Loading plugin "upnp_browser"... Jul 05 08:27:24 volumiozwpi3 systemd[1]: systemd-timedated.service: Succeeded. Jul 05 08:27:25 volumiozwpi3 volumio[974]: info: Loading plugin "alarm-clock"... Jul 05 08:27:27 volumiozwpi3 volumio[974]: info: Loading plugin "airplay_emulation"... Jul 05 08:27:27 volumiozwpi3 volumio[974]: info: Starting Shairport Sync Jul 05 08:27:27 volumiozwpi3 volumio[974]: info: Loading plugin "last_100"... Jul 05 08:27:27 volumiozwpi3 volumio[974]: info: Loading plugin "webradio"... Jul 05 08:27:27 volumiozwpi3 volumio-remote-updater[699]: [2025-07-05 08:27:27] [connect] Successful connection Jul 05 08:27:28 volumiozwpi3 volumio[974]: info: Loading plugin "i2s_dacs"... Jul 05 08:27:28 volumiozwpi3 volumio[974]: info: Loading plugin "volumiodiscovery"... Jul 05 08:27:28 volumiozwpi3 volumio[974]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 08:27:28 volumiozwpi3 volumio[974]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 08:27:28 volumiozwpi3 volumio[974]: *** WARNING *** For more information see Jul 05 08:27:28 volumiozwpi3 volumio[974]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 08:27:28 volumiozwpi3 volumio[974]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 08:27:28 volumiozwpi3 volumio[974]: *** WARNING *** For more information see Jul 05 08:27:28 volumiozwpi3 node[974]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 08:27:28 volumiozwpi3 node[974]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 08:27:28 volumiozwpi3 node[974]: *** WARNING *** For more information see Jul 05 08:27:28 volumiozwpi3 node[974]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 08:27:28 volumiozwpi3 node[974]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 08:27:28 volumiozwpi3 node[974]: *** WARNING *** For more information see Jul 05 08:27:28 volumiozwpi3 volumio[974]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 08:27:28 volumiozwpi3 volumio[974]: info: Discovery: Started advertising with name: VolumioZW_Pi3 Jul 05 08:27:28 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 08:27:28 volumiozwpi3 volumio[974]: info: Loading plugin "autostart"... Jul 05 08:27:30 volumiozwpi3 volumio[974]: info: Applying required configuration parameters for plugin autostart Jul 05 08:27:30 volumiozwpi3 volumio[974]: info: AutoStart - onVolumioStart - read config.json Jul 05 08:27:30 volumiozwpi3 volumio[974]: info: Loading plugin "outputs"... Jul 05 08:27:30 volumiozwpi3 volumio[974]: info: Loading plugin "albumart"... Jul 05 08:27:30 volumiozwpi3 volumio[974]: info: Plugin example_plugin is not enabled Jul 05 08:27:30 volumiozwpi3 volumio[974]: info: Loading plugin "inputs"... Jul 05 08:27:31 volumiozwpi3 volumio[974]: info: Loading plugin "updater_comm"... Jul 05 08:27:32 volumiozwpi3 volumio[974]: info: Plugin mpdemulation is not enabled Jul 05 08:27:32 volumiozwpi3 volumio[974]: info: Loading plugin "rest_api"... Jul 05 08:27:33 volumiozwpi3 volumio[974]: info: Loading plugin "websocket"... Jul 05 08:27:33 volumiozwpi3 volumio[974]: info: Starting Socket.io Server version 2.3.0 Jul 05 08:27:33 volumiozwpi3 volumio[974]: info: Loading plugin "fusiondsp"... Jul 05 08:27:34 volumiozwpi3 volumio[974]: Forking 1 albumart workers Jul 05 08:27:35 volumiozwpi3 volumio[974]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 08:27:36 volumiozwpi3 volumio[974]: info: Plugin backup_restore is not enabled Jul 05 08:27:36 volumiozwpi3 volumio[974]: info: Loading plugin "randomizer"... Jul 05 08:27:39 volumiozwpi3 volumio[974]: info: Applying required configuration parameters for plugin randomizer Jul 05 08:27:40 volumiozwpi3 volumio[974]: Starting albumart workers Jul 05 08:27:40 volumiozwpi3 volumio[974]: info: Loading i18n strings for locale de Jul 05 08:27:41 volumiozwpi3 volumio[974]: Updating browse sources language Jul 05 08:27:41 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 08:27:42 volumiozwpi3 volumio-remote-updater[699]: [2025-07-05 08:27:42] [connect] Successful connection Jul 05 08:27:42 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 08:27:42 volumiozwpi3 volumio[974]: info: CoreCommandRouter::initPlayerControls Jul 05 08:27:42 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 08:27:42 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 08:27:42 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 08:27:42 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 08:27:43 volumiozwpi3 volumio[974]: Express server listening on port 3000 Jul 05 08:27:43 volumiozwpi3 volumio[974]: [Metrics] WebUI: 38s 268.68ms Jul 05 08:27:43 volumiozwpi3 volumio[974]: info: CoreStateMachine::resetVolumioState Jul 05 08:27:43 volumiozwpi3 volumio[974]: info: CoreStateMachine::getcurrentVolume Jul 05 08:27:43 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 08:27:44 volumiozwpi3 sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 08:27:44 volumiozwpi3 sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:44 volumiozwpi3 sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 08:27:44 volumiozwpi3 sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:44 volumiozwpi3 sudo[1217]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:44 volumiozwpi3 sudo[1219]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:44 volumiozwpi3 volumio[974]: info: Volumio Network Manager: Network status updated: 2 Jul 05 08:27:46 volumiozwpi3 volumio-remote-updater[699]: [2025-07-05 08:27:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751696862 101 Jul 05 08:27:46 volumiozwpi3 volumio[974]: 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: 1 Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: Reloading queue from file Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreStateMachine::setRepeat false single undefined Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreStateMachine::pushState Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CorePlayQueue::getTrack 0 Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioPushState Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreStateMachine::setRandom false Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreStateMachine::pushState Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CorePlayQueue::getTrack 0 Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioPushState Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: Setting Device type: Raspberry PI Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: VolumeController:: Volume=100 Mute =false Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreStateMachine::pushState Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CorePlayQueue::getTrack 0 Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioPushState Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreStateMachine::updateTrackBlock Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CorePlayQueue::getTrackBlock Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: DAC script executed Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: Completed loading Core Plugins Jul 05 08:27:46 volumiozwpi3 volumio[974]: info: Preparing to generate the ALSA configuration file Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: Discovery: adding d9accbbd-6244-4207-b64e-4a8c52288dce Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: Discovery: Found device VolumioZW_Pi3 Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioGetState Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: CorePlayQueue::getTrack 0 Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: Reading ALSA contributions from plugins. Jul 05 08:27:47 volumiozwpi3 sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 05 08:27:47 volumiozwpi3 sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: Asound.conf file unchanged, so no further update is needed Jul 05 08:27:47 volumiozwpi3 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 05 08:27:47 volumiozwpi3 sudo[1242]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: Output device has changed, restarting MPD Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: Output device has changed, restarting Shairport Sync Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 08:27:47 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 08:27:47 volumiozwpi3 sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 08:27:48 volumiozwpi3 sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:48 volumiozwpi3 sudo[1247]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:48 volumiozwpi3 sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 08:27:48 volumiozwpi3 sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:48 volumiozwpi3 systemd[1]: Stopping Music Player Daemon... Jul 05 08:27:48 volumiozwpi3 volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 08:27:48 volumiozwpi3 volumio[974]: info: ___________ START PLUGINS ___________ Jul 05 08:27:48 volumiozwpi3 volumio[974]: info: ControllerMpd::onStart: Initializing MPD Jul 05 08:27:48 volumiozwpi3 volumio[974]: info: Creating MPD Configuration file Jul 05 08:27:48 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 08:27:48 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 08:27:48 volumiozwpi3 volumio[974]: info: [1751696868821] CoreMusicLibrary::Adding element Medienserver Jul 05 08:27:48 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 08:27:48 volumiozwpi3 sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 08:27:49 volumiozwpi3 sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:49 volumiozwpi3 systemd[1]: mpd.service: Succeeded. Jul 05 08:27:49 volumiozwpi3 systemd[1]: Stopped Music Player Daemon. Jul 05 08:27:49 volumiozwpi3 systemd[1]: Starting Music Player Daemon... Jul 05 08:27:49 volumiozwpi3 sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 08:27:49 volumiozwpi3 sudo[1257]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:49 volumiozwpi3 sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:49 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 08:27:49 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 08:27:49 volumiozwpi3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 08:27:49 volumiozwpi3 systemd[1]: mpd.service: Succeeded. Jul 05 08:27:49 volumiozwpi3 systemd[1]: Stopped Music Player Daemon. Jul 05 08:27:49 volumiozwpi3 systemd[1]: Starting Music Player Daemon... Jul 05 08:27:49 volumiozwpi3 volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 08:27:49 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 08:27:49 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: [1751696870189] CoreMusicLibrary::Adding element Last_100 Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: [1751696870219] CoreMusicLibrary::Adding element Webradio Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 08:27:50 volumiozwpi3 sudo[1265]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 08:27:50 volumiozwpi3 volumio[974]: info: Initializing BBC Radios Jul 05 08:27:50 volumiozwpi3 sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:50 volumiozwpi3 sudo[1265]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: AutoStart - onStart Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: Loading i18n strings for locale de Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: FusionDsp - mixtype--------------------- Hardware Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: [1751696871830] CoreMusicLibrary::Adding element Randomizer Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 08:27:51 volumiozwpi3 volumio[974]: Cannot find translation for source Randomizer Jul 05 08:27:51 volumiozwpi3 volumio[974]: info: Volumio Calling Home Jul 05 08:27:52 volumiozwpi3 volumio[974]: info: Preparing to generate the ALSA configuration file Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: AutoStart - Plugin is starting Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioGetQueue Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreStateMachine::getQueue Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CorePlayQueue::getQueue Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: AutoStart - start playing Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: AutoStart - start playing with no specific position Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreCommandRouter::volumioPlay Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreStateMachine::play index 0 Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreStateMachine::stop Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreStateMachine::play index undefined Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CorePlayQueue::getTrack 0 Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CoreStateMachine::startPlaybackTimer Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: CorePlayQueue::getTrack 0 Jul 05 08:27:53 volumiozwpi3 volumio[974]: info: [1751696873829] ControllerWebradio::clearAddPlayTrack Jul 05 08:27:53 volumiozwpi3 volumio[974]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 08:27:54 volumiozwpi3 volumio[974]: info: camilladsp service started and running in background, instance 1 Jul 05 08:27:54 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 08:27:54 volumiozwpi3 volumio[974]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 08:27:54 volumiozwpi3 volumio[974]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 08:27:54 volumiozwpi3 volumio[974]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 08:27:54 volumiozwpi3 volumio[974]: info: FusionDsp loaded Jul 05 08:27:54 volumiozwpi3 volumio[974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 08:27:54 volumiozwpi3 sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 08:27:54 volumiozwpi3 sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:27:54 volumiozwpi3 volumio[974]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 08:27:54 volumiozwpi3 volumio[974]: info: Adding Signal Path Element [object Object] Jul 05 08:27:54 volumiozwpi3 volumio[974]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 08:27:54 volumiozwpi3 volumio[974]: info: FusionDsp - ---- installed callbackRead Jul 05 08:27:54 volumiozwpi3 volumio[974]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 08:27:54 volumiozwpi3 sudo[1310]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:54 volumiozwpi3 volumio[974]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 08:27:54 volumiozwpi3 volumio[974]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 08:27:54 volumiozwpi3 volumio[974]: at onErrorNT (internal/child_process.js:465:16) Jul 05 08:27:54 volumiozwpi3 volumio[974]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 08:27:54 volumiozwpi3 volumio[974]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 05 08:27:54 volumiozwpi3 volumio[974]: at listOnTimeout (internal/timers.js:523:9) Jul 05 08:27:54 volumiozwpi3 volumio[974]: at processTimers (internal/timers.js:497:7) { Jul 05 08:27:54 volumiozwpi3 volumio[974]: errno: -2, Jul 05 08:27:54 volumiozwpi3 volumio[974]: code: 'ENOENT', Jul 05 08:27:54 volumiozwpi3 volumio[974]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 08:27:54 volumiozwpi3 volumio[974]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 08:27:54 volumiozwpi3 volumio[974]: spawnargs: [ Jul 05 08:27:54 volumiozwpi3 volumio[974]: '-p', Jul 05 08:27:54 volumiozwpi3 volumio[974]: 9876, Jul 05 08:27:54 volumiozwpi3 volumio[974]: '-o', Jul 05 08:27:54 volumiozwpi3 volumio[974]: '/tmp/camilladsp.log', Jul 05 08:27:54 volumiozwpi3 volumio[974]: '-l', Jul 05 08:27:54 volumiozwpi3 volumio[974]: 'warn', Jul 05 08:27:54 volumiozwpi3 volumio[974]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 08:27:54 volumiozwpi3 volumio[974]: ] Jul 05 08:27:54 volumiozwpi3 volumio[974]: } Jul 05 08:27:54 volumiozwpi3 volumio[974]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 08:27:58 volumiozwpi3 mpd_monitor.sh[353]: MPD Monitor Service: MPD Appears to be inactive, restarting Jul 05 08:27:58 volumiozwpi3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 08:27:58 volumiozwpi3 systemd[1]: mpd.service: Failed with result 'protocol'. Jul 05 08:27:58 volumiozwpi3 systemd[1]: Failed to start Music Player Daemon. Jul 05 08:27:59 volumiozwpi3 sudo[1260]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:59 volumiozwpi3 sudo[1249]: pam_unix(sudo:session): session closed for user root Jul 05 08:27:59 volumiozwpi3 systemd[1]: Starting Music Player Daemon... Jul 05 08:28:00 volumiozwpi3 sudo[1322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 08:28:00 volumiozwpi3 sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 08:28:00 volumiozwpi3 sudo[1322]: pam_unix(sudo:session): session closed for user root Jul 05 08:28:00 volumiozwpi3 sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 08:27 Jul 05 08:28:00 volumiozwpi3 sudo[1345]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"