-- Logs begin at Fri 2025-09-05 17:05:22 HKT, end at Thu 2025-10-30 20:58:07 HKT. -- Oct 30 20:57:42 volumio--pi systemd-timedated[1027]: Changed local time to Thu Oct 30 20:57:42 2025 Oct 30 20:57:42 volumio--pi sudo[1022]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:42 volumio--pi volumio-time-update[580]: volumio-time-update-util: System time updated successfully. Oct 30 20:57:42 volumio--pi systemd[1]: Started Volumio Time Update Utility. Oct 30 20:57:42 volumio--pi systemd[1]: Starting Daily apt download activities... Oct 30 20:57:42 volumio--pi wireless.js[565]: trying... Oct 30 20:57:42 volumio--pi sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 20:57:42 volumio--pi sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:57:42 volumio--pi sudo[1034]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:42 volumio--pi volumio-remote-updater[595]: [2025-10-30 20:57:42] [info] asio async_connect error: system:111 (Connection refused) Oct 30 20:57:42 volumio--pi volumio-remote-updater[595]: [2025-10-30 20:57:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 30 20:57:42 volumio--pi volumio-remote-updater[595]: [2025-10-30 20:57:42] [error] handle_connect error: Underlying Transport Error Oct 30 20:57:43 volumio--pi wireless.js[565]: trying... Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.267570, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.33, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.269276, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.33, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.269542, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.136, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.269737, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (5) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.136, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.269927, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (6) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.33, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.270120, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (7) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.33, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.270332, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (8) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.33, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.270597, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (9) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.33, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.270799, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (10) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.136, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.271015, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (11) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.136, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi nmbd[787]: [2025/10/30 20:57:43.271206, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:57:43 volumio--pi nmbd[787]: query_name_response: Multiple (12) responses received for a query on subnet 192.168.0.12 for name WORKGROUP<1d>. Oct 30 20:57:43 volumio--pi nmbd[787]: This response was from IP 192.168.0.33, reporting an IP address of 192.168.0.136. Oct 30 20:57:43 volumio--pi sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 20:57:43 volumio--pi sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:57:43 volumio--pi sudo[1041]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:43 volumio--pi dhcpcd[990]: wlan0: leased 192.168.0.130 for 3600 seconds Oct 30 20:57:43 volumio--pi avahi-daemon[593]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.130. Oct 30 20:57:43 volumio--pi dhcpcd[990]: wlan0: adding route to 192.168.0.0/24 Oct 30 20:57:43 volumio--pi dhcpcd[990]: wlan0: adding default route via 192.168.0.1 Oct 30 20:57:43 volumio--pi avahi-daemon[593]: New relevant interface wlan0.IPv4 for mDNS. Oct 30 20:57:43 volumio--pi avahi-daemon[593]: Registering new address record for 192.168.0.130 on wlan0.IPv4. Oct 30 20:57:44 volumio--pi wireless.js[565]: trying... Oct 30 20:57:44 volumio--pi sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 20:57:44 volumio--pi sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:57:44 volumio--pi sudo[1078]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:44 volumio--pi wireless.js[565]: Connected to: ----Home Oct 30 20:57:44 volumio--pi wireless.js[565]: ---- Oct 30 20:57:44 volumio--pi sudo[1081]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 20:57:44 volumio--pi sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:57:44 volumio--pi sudo[1081]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:44 volumio--pi wireless.js[565]: ... joined AP, wlan0 IPv4 is 192.168.0.130, ipV6 is undefined Oct 30 20:57:44 volumio--pi wireless.js[565]: It's done! AP Oct 30 20:57:44 volumio--pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 30 20:57:44 volumio--pi systemd[1]: Started Wireless Services. Oct 30 20:57:44 volumio--pi systemd[1]: Started Volumio Backend Module. Oct 30 20:57:44 volumio--pi systemd[1]: Started Volumio Cpu Tweaker. Oct 30 20:57:44 volumio--pi systemd[1]: Reached target Multi-User System. Oct 30 20:57:44 volumio--pi systemd[1]: Reached target Graphical Interface. Oct 30 20:57:44 volumio--pi systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 30 20:57:44 volumio--pi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 30 20:57:44 volumio--pi systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 30 20:57:44 volumio--pi volumio-cpu-tweak[1087]: Setting RT Priority for mpd Oct 30 20:57:44 volumio--pi volumio-cpu-tweak[1087]: Setting MPD Affinity Oct 30 20:57:44 volumio--pi volumio-cpu-tweak[1087]: pid 812's current affinity mask: f Oct 30 20:57:44 volumio--pi volumio-cpu-tweak[1087]: pid 812's new affinity mask: 3 Oct 30 20:57:44 volumio--pi volumio-cpu-tweak[1087]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 30 20:57:44 volumio--pi volumio-cpu-tweak[1087]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 30 20:57:44 volumio--pi systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 30 20:57:44 volumio--pi ntpd[804]: Listen normally on 4 wlan0 192.168.0.130:123 Oct 30 20:57:44 volumio--pi ntpd[804]: new interface(s) found: waking up resolver Oct 30 20:57:46 volumio--pi systemd[1]: apt-daily.service: Succeeded. Oct 30 20:57:46 volumio--pi systemd[1]: Started Daily apt download activities. Oct 30 20:57:47 volumio--pi systemd[1]: Starting Daily apt upgrade and clean activities... Oct 30 20:57:47 volumio--pi volumio[1086]: info: ------------------------------------------- Oct 30 20:57:47 volumio--pi volumio[1086]: info: ----- Volumio3 ---- Oct 30 20:57:47 volumio--pi volumio[1086]: info: ------------------------------------------- Oct 30 20:57:47 volumio--pi volumio[1086]: info: ----- System startup ---- Oct 30 20:57:47 volumio--pi volumio[1086]: info: ------------------------------------------- Oct 30 20:57:48 volumio--pi systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 30 20:57:48 volumio--pi systemd[1]: Started Daily apt upgrade and clean activities. Oct 30 20:57:48 volumio--pi systemd[1]: Startup finished in 17.784s (kernel) + 25.320s (userspace) = 43.105s. Oct 30 20:57:49 volumio--pi volumio[1086]: info: MYVOLUMIO Environment detected Oct 30 20:57:49 volumio--pi volumio[1086]: info: Plugin folders cleanup Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning into folder /volumio/app/plugins/ Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category audio_interface Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category miscellanea Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category music_service Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category plugins.json Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category system_controller Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category user_interface Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning into folder /data/plugins/ Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category audio_interface Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category music_service Oct 30 20:57:49 volumio--pi volumio[1086]: info: Scanning category system_controller Oct 30 20:57:49 volumio--pi volumio[1086]: info: Plugin folders cleanup completed Oct 30 20:57:49 volumio--pi volumio[1086]: info: ------------------------------------------- Oct 30 20:57:49 volumio--pi volumio[1086]: info: ----- Core plugins startup ---- Oct 30 20:57:49 volumio--pi volumio[1086]: info: ------------------------------------------- Oct 30 20:57:49 volumio--pi volumio[1086]: info: Loading plugins from folder /volumio/app/plugins/ Oct 30 20:57:49 volumio--pi volumio[1086]: info: Adding plugin upnp to MyMusic Plugins Oct 30 20:57:49 volumio--pi volumio[1086]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 30 20:57:49 volumio--pi volumio[1086]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 30 20:57:49 volumio--pi volumio[1086]: info: Loading plugins from folder /data/plugins/ Oct 30 20:57:49 volumio--pi volumio[1086]: info: Loading plugin "system"... Oct 30 20:57:49 volumio--pi volumio[1086]: info: Loading plugin "appearance"... Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "network"... Oct 30 20:57:51 volumio--pi volumio[1086]: info: Refreshing Cached IP Addresses Oct 30 20:57:51 volumio--pi sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 20:57:51 volumio--pi sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:57:51 volumio--pi sudo[1195]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:51 volumio--pi sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 20:57:51 volumio--pi sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:57:51 volumio--pi sudo[1197]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "services"... Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "alsa_controller"... Oct 30 20:57:51 volumio--pi sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 30 20:57:51 volumio--pi sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:57:51 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "wizard"... Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "networkfs"... Oct 30 20:57:51 volumio--pi volumio[1086]: info: Starting Udev Watcher for removable devices Oct 30 20:57:51 volumio--pi sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=york,password=Sp25086441,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.33/Music /mnt/NAS/Songs_on_HP Oct 30 20:57:51 volumio--pi sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:57:51 volumio--pi volumio[1086]: info: Ignoring mount for partition: boot Oct 30 20:57:51 volumio--pi volumio[1086]: info: Ignoring mount for partition: volumio Oct 30 20:57:51 volumio--pi volumio[1086]: info: Ignoring mount for partition: volumio_data Oct 30 20:57:51 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "volumio_command_line_client"... Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "upnp"... Oct 30 20:57:51 volumio--pi volumio[1086]: info: [1761829071547] Starting Upmpd Daemon Oct 30 20:57:51 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "my_music"... Oct 30 20:57:51 volumio--pi volumio[1086]: info: Loading plugin "mpd"... Oct 30 20:57:51 volumio--pi kernel: Key type cifs.spnego registered Oct 30 20:57:51 volumio--pi kernel: Key type cifs.idmap registered Oct 30 20:57:51 volumio--pi 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. Oct 30 20:57:51 volumio--pi kernel: CIFS: Attempting to mount //192.168.0.33/Music Oct 30 20:57:51 volumio--pi kernel: cryptd: max_cpu_qlen set to 1000 Oct 30 20:57:52 volumio--pi sudo[1206]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:52 volumio--pi volumio[1086]: info: Loading plugin "upnp_browser"... Oct 30 20:57:52 volumio--pi sudo[1223]: pam_unix(sudo:session): session closed for user root Oct 30 20:57:52 volumio--pi volumio[1086]: info: Loading plugin "alarm-clock"... Oct 30 20:57:52 volumio--pi volumio[1086]: info: Loading plugin "airplay_emulation"... Oct 30 20:57:52 volumio--pi volumio[1086]: info: Starting Shairport Sync Oct 30 20:57:52 volumio--pi volumio[1086]: info: Loading plugin "last_100"... Oct 30 20:57:52 volumio--pi volumio[1086]: info: Loading plugin "webradio"... Oct 30 20:57:52 volumio--pi volumio-remote-updater[595]: [2025-10-30 20:57:52] [connect] Successful connection Oct 30 20:57:53 volumio--pi volumio[1086]: info: Loading plugin "i2s_dacs"... Oct 30 20:57:53 volumio--pi volumio[1086]: info: I2S DAC not set, start Auto-detection Oct 30 20:57:53 volumio--pi volumio[1086]: info: Loading plugin "volumiodiscovery"... Oct 30 20:57:53 volumio--pi volumio[1086]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 20:57:53 volumio--pi volumio[1086]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 20:57:53 volumio--pi volumio[1086]: *** WARNING *** For more information see Oct 30 20:57:53 volumio--pi node[1086]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 20:57:53 volumio--pi volumio[1086]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 20:57:53 volumio--pi volumio[1086]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 20:57:53 volumio--pi volumio[1086]: *** WARNING *** For more information see Oct 30 20:57:53 volumio--pi node[1086]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 20:57:53 volumio--pi node[1086]: *** WARNING *** For more information see Oct 30 20:57:53 volumio--pi node[1086]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 20:57:53 volumio--pi node[1086]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 20:57:53 volumio--pi node[1086]: *** WARNING *** For more information see Oct 30 20:57:53 volumio--pi volumio[1086]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 30 20:57:53 volumio--pi volumio[1086]: info: Discovery: Started advertising with name: Volumio -Pi Oct 30 20:57:53 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 20:57:53 volumio--pi volumio[1086]: info: Loading plugin "squeezelite_mc"... Oct 30 20:57:55 volumio--pi volumio[1086]: info: Loading plugin "youtube2"... Oct 30 20:57:56 volumio--pi volumio[1086]: info: Loading plugin "ytcr"... Oct 30 20:57:57 volumio--pi systemd[1]: systemd-fsckd.service: Succeeded. Oct 30 20:58:00 volumio--pi volumio[1086]: info: Loading plugin "outputs"... Oct 30 20:58:00 volumio--pi volumio[1086]: info: Loading plugin "albumart"... Oct 30 20:58:00 volumio--pi volumio[1086]: info: Plugin example_plugin is not enabled Oct 30 20:58:00 volumio--pi volumio[1086]: info: Loading plugin "inputs"... Oct 30 20:58:00 volumio--pi volumio[1086]: info: Loading plugin "updater_comm"... Oct 30 20:58:00 volumio--pi volumio[1086]: info: Plugin mpdemulation is not enabled Oct 30 20:58:00 volumio--pi volumio[1086]: info: Loading plugin "rest_api"... Oct 30 20:58:00 volumio--pi volumio[1086]: info: Loading plugin "websocket"... Oct 30 20:58:00 volumio--pi volumio[1086]: info: Starting Socket.io Server version 2.3.0 Oct 30 20:58:00 volumio--pi volumio[1086]: info: Loading plugin "fusiondsp"... Oct 30 20:58:00 volumio--pi volumio[1086]: Forking 3 albumart workers Oct 30 20:58:01 volumio--pi volumio[1086]: info: Applying required configuration parameters for plugin fusiondsp Oct 30 20:58:01 volumio--pi volumio[1086]: info: Plugin mpdoutput is not enabled Oct 30 20:58:01 volumio--pi volumio[1086]: info: Plugin lms is not enabled Oct 30 20:58:01 volumio--pi volumio[1086]: info: Loading plugin "minidlna"... Oct 30 20:58:01 volumio--pi volumio[1086]: info: Applying required configuration parameters for plugin minidlna Oct 30 20:58:02 volumio--pi volumio[1086]: Starting albumart workers Oct 30 20:58:02 volumio--pi volumio[1086]: info: Loading plugin "backup_restore"... Oct 30 20:58:02 volumio--pi volumio[1086]: Starting albumart workers Oct 30 20:58:02 volumio--pi volumio[1086]: Starting albumart workers Oct 30 20:58:02 volumio--pi systemd[1]: systemd-hostnamed.service: Succeeded. Oct 30 20:58:02 volumio--pi volumio[1086]: info: Applying required configuration parameters for plugin backup_restore Oct 30 20:58:02 volumio--pi volumio[1086]: info: Loading i18n strings for locale en Oct 30 20:58:02 volumio--pi volumio[1086]: Updating browse sources language Oct 30 20:58:02 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::initPlayerControls Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: Express server listening on port 3000 Oct 30 20:58:03 volumio--pi volumio[1086]: [Metrics] WebUI: 16s 232.09ms Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::resetVolumioState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::getcurrentVolume Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:03 volumio--pi sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 20:58:03 volumio--pi sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:03 volumio--pi sudo[1340]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:03 volumio--pi sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 20:58:03 volumio--pi sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:03 volumio--pi sudo[1342]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:03 volumio--pi volumio[1086]: info: Volumio Network Manager: Network status updated: 3 Oct 30 20:58:03 volumio--pi volumio[1086]: info: VolumeController:: Volume=86 Mute =false Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::pushState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioPushState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::updateTrackBlock Oct 30 20:58:03 volumio--pi volumio[1086]: info: CorePlayQueue::getTrackBlock Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 20:58:03 volumio--pi volumio[1086]: info: Reloading queue from file Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::setRepeat null single undefined Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::pushState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioPushState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::setRandom null Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::pushState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioPushState Oct 30 20:58:03 volumio--pi volumio[1086]: info: Setting Device type: Raspberry PI Oct 30 20:58:03 volumio--pi volumio[1086]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 30 20:58:03 volumio--pi volumio[1086]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Oct 30 20:58:03 volumio--pi volumio[1086]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 30 20:58:03 volumio--pi volumio[1086]: info: VolumeController:: Volume=86 Mute =false Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreStateMachine::pushState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioPushState Oct 30 20:58:03 volumio--pi sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 30 20:58:03 volumio--pi sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:03 volumio--pi volumio[1086]: info: Discovery: adding 7695e705-009b-40a8-bc09-0cec236f5e6a Oct 30 20:58:03 volumio--pi volumio[1086]: info: Discovery: Found device Volumio -Pi Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioGetState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: Discovery: this is already registered, 7695e705-009b-40a8-bc09-0cec236f5e6a Oct 30 20:58:03 volumio--pi volumio[1086]: info: Discovery: Found device Volumio -Pi Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioGetState Oct 30 20:58:03 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: Completed loading Core Plugins Oct 30 20:58:03 volumio--pi volumio[1086]: info: Preparing to generate the ALSA configuration file Oct 30 20:58:03 volumio--pi volumio[1086]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 30 20:58:03 volumio--pi volumio[1086]: info: Reading ALSA contributions from plugins. Oct 30 20:58:03 volumio--pi volumio[1086]: info: Asound.conf file unchanged, so no further update is needed Oct 30 20:58:03 volumio--pi volumio[1086]: info: Output device has changed, restarting MPD Oct 30 20:58:03 volumio--pi systemd[1]: Started UPnP Renderer front-end to MPD. Oct 30 20:58:03 volumio--pi sudo[1355]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:03 volumio--pi volumio[1086]: info: Output device has changed, restarting Shairport Sync Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:03 volumio--pi sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 20:58:03 volumio--pi sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:03 volumio--pi sudo[1359]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:03 volumio--pi sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 20:58:03 volumio--pi sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:03 volumio--pi systemd[1]: Stopping Music Player Daemon... Oct 30 20:58:03 volumio--pi volumio[1086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: ___________ START PLUGINS ___________ Oct 30 20:58:03 volumio--pi volumio[1086]: info: ControllerMpd::onStart: Initializing MPD Oct 30 20:58:03 volumio--pi volumio[1086]: info: Creating MPD Configuration file Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 20:58:03 volumio--pi volumio[1086]: info: [1761829083824] CoreMusicLibrary::Adding element Media Servers Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 20:58:03 volumio--pi sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 20:58:03 volumio--pi sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:03 volumio--pi sudo[1368]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:03 volumio--pi sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 20:58:03 volumio--pi sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:03 volumio--pi systemd[1]: mpd.service: Succeeded. Oct 30 20:58:03 volumio--pi systemd[1]: Stopped Music Player Daemon. Oct 30 20:58:03 volumio--pi volumio[1086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:03 volumio--pi volumio[1086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 20:58:03 volumio--pi volumio[1086]: info: [1761829083988] CoreMusicLibrary::Adding element Last_100 Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 20:58:03 volumio--pi volumio[1086]: info: [1761829083991] CoreMusicLibrary::Adding element Webradio Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 20:58:03 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 20:58:03 volumio--pi systemd[1]: Starting Music Player Daemon... Oct 30 20:58:04 volumio--pi volumio[1086]: info: Initializing BBC Radios Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: [squeezelite_mc] Starting proxy server... Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 20:58:04 volumio--pi volumio[1086]: info: [1761829084104] CoreMusicLibrary::Adding element YouTube2 Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 20:58:04 volumio--pi volumio[1086]: Cannot find translation for source YouTube2 Oct 30 20:58:04 volumio--pi sudo[1376]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 20:58:04 volumio--pi sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:04 volumio--pi sudo[1376]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:04 volumio--pi volumio[1086]: info: Loading i18n strings for locale en Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: FusionDsp - mixtype--------------------- Hardware Oct 30 20:58:04 volumio--pi volumio[1086]: info: Loading i18n strings for locale en Oct 30 20:58:04 volumio--pi volumio[1086]: info: Volumio Calling Home Oct 30 20:58:04 volumio--pi volumio[1086]: info: [squeezelite_mc] Proxy server started on port 46155 Oct 30 20:58:04 volumio--pi volumio[1086]: info: Preparing to generate the ALSA configuration file Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 20:58:04 volumio--pi volumio[1086]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 30 20:58:04 volumio--pi volumio[1086]: info: Reading ALSA contributions from plugins. Oct 30 20:58:04 volumio--pi volumio[1086]: info: minidlna: Found miniDLNA version 1.2.1 Oct 30 20:58:04 volumio--pi volumio[1086]: info: minidlna: Starting minidlna.service Oct 30 20:58:04 volumio--pi volumio[1086]: info: MPD Permissions set Oct 30 20:58:04 volumio--pi volumio[1086]: info: Upmpdcli Daemon Started Oct 30 20:58:04 volumio--pi sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 30 20:58:04 volumio--pi sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:04 volumio--pi volumio[1086]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:04 volumio--pi systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 30 20:58:04 volumio--pi sudo[1399]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:04 volumio--pi volumio[1086]: info: VolumeController:: Volume=86 Mute =false Oct 30 20:58:04 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioGetState Oct 30 20:58:05 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreStateMachine::pushState Oct 30 20:58:05 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioPushState Oct 30 20:58:05 volumio--pi volumio[1086]: info: [squeezelite_mc] Server discovery started Oct 30 20:58:05 volumio--pi volumio[1086]: info: [squeezelite_mc] Player finder started Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:58:05 volumio--pi volumio[1086]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 30 20:58:05 volumio--pi volumio[1086]: info: minidlna: systemctl start minidlna.service succeeded. Oct 30 20:58:05 volumio--pi volumio[1086]: info: Volumio called home Oct 30 20:58:05 volumio--pi volumio[1086]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE","S32_LE","SPECIAL","DSD_U32_BE"] Oct 30 20:58:05 volumio--pi volumio[1086]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio--pi","dsdFormat":"DSD_U32_BE","card":"5","mixerType":"Hardware","mixer":"HU300 HiFi 2.0 "} Oct 30 20:58:05 volumio--pi volumio[1086]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Oct 30 20:58:05 volumio--pi volumio[1086]: info: Starting Shairport Sync Oct 30 20:58:05 volumio--pi volumio[1086]: info: Starting Shairport Sync Oct 30 20:58:05 volumio--pi sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 20:58:05 volumio--pi sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi volumio[1086]: info: Starting Shairport Sync Oct 30 20:58:05 volumio--pi sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 20:58:05 volumio--pi sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 20:58:05 volumio--pi sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::volumioGetState Oct 30 20:58:05 volumio--pi volumio[1086]: info: CorePlayQueue::getTrack 0 Oct 30 20:58:05 volumio--pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 20:58:05 volumio--pi systemd[1]: shairport-sync.service: Succeeded. Oct 30 20:58:05 volumio--pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 20:58:05 volumio--pi volumio[1086]: info: Asound.conf file unchanged, so no further update is needed Oct 30 20:58:05 volumio--pi volumio[1086]: info: Output device has changed, restarting MPD Oct 30 20:58:05 volumio--pi sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Oct 30 20:58:05 volumio--pi sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi volumio[1086]: info: Output device has changed, restarting Shairport Sync Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:05 volumio--pi sudo[1416]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:05 volumio--pi sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 20:58:05 volumio--pi sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 20:58:05 volumio--pi sudo[1433]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:05 volumio--pi sudo[1418]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:05 volumio--pi sudo[1425]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:05 volumio--pi sudo[1422]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:05 volumio--pi sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 20:58:05 volumio--pi sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi volumio[1086]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 20:58:05 volumio--pi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 30 20:58:05 volumio--pi systemd[1]: mpd.service: Succeeded. Oct 30 20:58:05 volumio--pi systemd[1]: Stopped Music Player Daemon. Oct 30 20:58:05 volumio--pi volumio[1086]: info: MPD Permissions set Oct 30 20:58:05 volumio--pi volumio[1086]: info: Shairport-Sync Started Oct 30 20:58:05 volumio--pi volumio[1086]: Error adding Membership: Error: addMembership EINVAL Oct 30 20:58:05 volumio--pi volumio[1086]: info: Shairport-Sync Started Oct 30 20:58:05 volumio--pi volumio[1086]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:05 volumio--pi systemd[1]: Starting Music Player Daemon... Oct 30 20:58:05 volumio--pi sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Oct 30 20:58:05 volumio--pi sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:58:05 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:05 volumio--pi volumio[1086]: info: Starting Shairport Sync Oct 30 20:58:05 volumio--pi sudo[1447]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:05 volumio--pi sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 20:58:05 volumio--pi sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 20:58:05 volumio--pi systemd[1]: shairport-sync.service: Succeeded. Oct 30 20:58:05 volumio--pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 20:58:05 volumio--pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 20:58:05 volumio--pi sudo[1454]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:05 volumio--pi sudo[1443]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 20:58:05 volumio--pi sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:05 volumio--pi sudo[1443]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:06 volumio--pi volumio[1086]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Oct 30 20:58:06 volumio--pi volumio[1086]: info: Shairport-Sync Started Oct 30 20:58:06 volumio--pi sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Oct 30 20:58:06 volumio--pi sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:06 volumio--pi sudo[1463]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:06 volumio--pi volumio[1086]: info: camilladsp service started and running in background, instance 1 Oct 30 20:58:06 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:58:06 volumio--pi volumio[1086]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 30 20:58:06 volumio--pi volumio[1086]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 30 20:58:06 volumio--pi volumio[1086]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 30 20:58:06 volumio--pi volumio[1086]: info: FusionDsp loaded Oct 30 20:58:06 volumio--pi volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 20:58:06 volumio--pi sudo[1475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 30 20:58:06 volumio--pi sudo[1475]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:58:06 volumio--pi volumio[1086]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 30 20:58:06 volumio--pi volumio[1086]: info: Adding Signal Path Element [object Object] Oct 30 20:58:06 volumio--pi volumio[1086]: info: Adding fusiondspeq DSP Signal Path Element Oct 30 20:58:06 volumio--pi volumio[1086]: info: FusionDsp - ---- installed callbackRead Oct 30 20:58:06 volumio--pi volumio[1086]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 20:58:06 volumio--pi volumio[1086]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 30 20:58:06 volumio--pi volumio[1086]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 30 20:58:06 volumio--pi volumio[1086]: at onErrorNT (internal/child_process.js:465:16) Oct 30 20:58:06 volumio--pi volumio[1086]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 30 20:58:06 volumio--pi volumio[1086]: errno: -2, Oct 30 20:58:06 volumio--pi volumio[1086]: code: 'ENOENT', Oct 30 20:58:06 volumio--pi volumio[1086]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 30 20:58:06 volumio--pi volumio[1086]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 30 20:58:06 volumio--pi volumio[1086]: spawnargs: [ Oct 30 20:58:06 volumio--pi volumio[1086]: '-p', Oct 30 20:58:06 volumio--pi volumio[1086]: 9876, Oct 30 20:58:06 volumio--pi volumio[1086]: '-o', Oct 30 20:58:06 volumio--pi volumio[1086]: '/tmp/camilladsp.log', Oct 30 20:58:06 volumio--pi volumio[1086]: '-l', Oct 30 20:58:06 volumio--pi volumio[1086]: 'warn', Oct 30 20:58:06 volumio--pi volumio[1086]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 30 20:58:06 volumio--pi volumio[1086]: ] Oct 30 20:58:06 volumio--pi volumio[1086]: } Oct 30 20:58:06 volumio--pi volumio[1086]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 20:58:06 volumio--pi systemd[1]: Started FusionDsp Daemon. Oct 30 20:58:06 volumio--pi sudo[1475]: pam_unix(sudo:session): session closed for user root Oct 30 20:58:07 volumio--pi sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-30 20:57 Oct 30 20:58:07 volumio--pi sudo[1487]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"