Dec 30 09:44:17 volumio-elettra ntpd[1035]: CLOCK: time stepped by 34.823476 Dec 30 09:44:17 volumio-elettra ntpd[1035]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Scanning category miscellanea Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Scanning category music_service Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Scanning category plugins.json Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Scanning category system_controller Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Scanning category user_interface Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Scanning into folder /data/plugins/ Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Scanning category music_service Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Plugin folders cleanup completed Dec 30 09:44:17 volumio-elettra volumio[1203]: info: ------------------------------------------- Dec 30 09:44:17 volumio-elettra volumio[1203]: info: ----- Core plugins startup ---- Dec 30 09:44:17 volumio-elettra volumio[1203]: info: ------------------------------------------- Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Loading plugins from folder /volumio/app/plugins/ Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: Pool taking: 185.157.229.254 Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: Pool taking: 31.14.133.122 Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: Pool taking: 93.94.88.50 Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: Pool taking: 172.232.209.103 Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: Pool taking: 2a01:e11:1004:9d61::1 Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: Pool taking: 2600:3c0b::f03c:94ff:fe16:7f5a Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: Pool taking: 2603:c027:2:c801:1979::1 Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: Pool taking: 2a00:6d41:10:1194::7 Dec 30 09:44:17 volumio-elettra ntpd[1035]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Adding plugin upnp to MyMusic Plugins Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Loading plugins from folder /data/plugins/ Dec 30 09:44:17 volumio-elettra volumio[1203]: info: Loading plugin "system"... Dec 30 09:44:18 volumio-elettra volumio[1203]: info: Loading plugin "appearance"... Dec 30 09:44:18 volumio-elettra ntpd[1035]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 30 09:44:20 volumio-elettra volumio-remote-updater[660]: [2025-12-30 09:44:20] [connect] Successful connection Dec 30 09:44:20 volumio-elettra volumio[1203]: info: Loading plugin "network"... Dec 30 09:44:20 volumio-elettra volumio[1203]: info: Refreshing Cached IP Addresses Dec 30 09:44:20 volumio-elettra sudo[1253]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 09:44:20 volumio-elettra sudo[1253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:20 volumio-elettra sudo[1253]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:20 volumio-elettra sudo[1255]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 09:44:20 volumio-elettra sudo[1255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:20 volumio-elettra sudo[1255]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Loading plugin "services"... Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Loading plugin "alsa_controller"... Dec 30 09:44:21 volumio-elettra sudo[1260]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 30 09:44:21 volumio-elettra sudo[1260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:21 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Loading plugin "wizard"... Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Loading plugin "networkfs"... Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Starting Udev Watcher for removable devices Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Ignoring mount for partition: boot Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Ignoring mount for partition: volumio Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Ignoring mount for partition: volumio_data Dec 30 09:44:21 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Loading plugin "volumio_command_line_client"... Dec 30 09:44:21 volumio-elettra sudo[1289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=gipus,password=el5trA,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft '//192.9.100.62/Macintosh RAID\\Music' /mnt/NAS/Music Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Loading plugin "upnp"... Dec 30 09:44:21 volumio-elettra sudo[1289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:21 volumio-elettra volumio[1203]: info: [1767084261678] Starting Upmpd Daemon Dec 30 09:44:21 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Loading plugin "my_music"... Dec 30 09:44:21 volumio-elettra volumio[1203]: info: Loading plugin "mpd"... Dec 30 09:44:21 volumio-elettra kernel: netfs: FS-Cache loaded Dec 30 09:44:22 volumio-elettra kernel: Key type cifs.spnego registered Dec 30 09:44:22 volumio-elettra kernel: Key type cifs.idmap registered Dec 30 09:44:22 volumio-elettra 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. Dec 30 09:44:22 volumio-elettra kernel: CIFS: Attempting to mount //192.9.100.62/Macintosh RAID/Music Dec 30 09:44:22 volumio-elettra sudo[1289]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:22 volumio-elettra volumio[1203]: info: Loading plugin "upnp_browser"... Dec 30 09:44:23 volumio-elettra sudo[1260]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:23 volumio-elettra ntpd[1035]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 30 09:44:23 volumio-elettra ntpd[1035]: DNS: Pool taking: 195.32.70.195 Dec 30 09:44:23 volumio-elettra ntpd[1035]: DNS: Pool taking: 185.19.184.35 Dec 30 09:44:23 volumio-elettra ntpd[1035]: DNS: Pool skipping: 185.157.229.254 Dec 30 09:44:23 volumio-elettra ntpd[1035]: DNS: Pool skipping: 162.159.200.1 Dec 30 09:44:23 volumio-elettra ntpd[1035]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 30 09:44:24 volumio-elettra systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 30 09:44:24 volumio-elettra ntpd[1035]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 30 09:44:25 volumio-elettra volumio[1203]: info: Starting UPNP Browser Dec 30 09:44:25 volumio-elettra volumio[1203]: info: Loading plugin "alarm-clock"... Dec 30 09:44:25 volumio-elettra volumio[1203]: info: Loading plugin "airplay_emulation"... Dec 30 09:44:25 volumio-elettra volumio[1203]: info: Starting Shairport Sync Dec 30 09:44:25 volumio-elettra volumio[1203]: info: Loading plugin "last_100"... Dec 30 09:44:25 volumio-elettra volumio[1203]: info: Loading plugin "webradio"... Dec 30 09:44:26 volumio-elettra volumio[1203]: info: Loading plugin "i2s_dacs"... Dec 30 09:44:26 volumio-elettra volumio[1203]: info: Loading plugin "volumiodiscovery"... Dec 30 09:44:26 volumio-elettra volumio[1203]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 09:44:26 volumio-elettra volumio[1203]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 09:44:26 volumio-elettra volumio[1203]: *** WARNING *** For more information see Dec 30 09:44:26 volumio-elettra volumio[1203]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 09:44:26 volumio-elettra volumio[1203]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 09:44:26 volumio-elettra node[1203]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 09:44:26 volumio-elettra volumio[1203]: *** WARNING *** For more information see Dec 30 09:44:26 volumio-elettra node[1203]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 09:44:26 volumio-elettra node[1203]: *** WARNING *** For more information see Dec 30 09:44:26 volumio-elettra node[1203]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 09:44:26 volumio-elettra node[1203]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 09:44:26 volumio-elettra node[1203]: *** WARNING *** For more information see Dec 30 09:44:26 volumio-elettra volumio[1203]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 30 09:44:26 volumio-elettra volumio[1203]: info: Discovery: Started advertising with name: Volumio-elettra Dec 30 09:44:26 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 09:44:26 volumio-elettra volumio[1203]: info: Loading plugin "spop"... Dec 30 09:44:29 volumio-elettra systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 30 09:44:29 volumio-elettra systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Loading plugin "outputs"... Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Loading plugin "albumart"... Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Plugin example_plugin is not enabled Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Loading plugin "inputs"... Dec 30 09:44:29 volumio-elettra systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Loading plugin "updater_comm"... Dec 30 09:44:29 volumio-elettra upmpdcli[1336]: Could not open config: /tmp/upmpdcli.conf Dec 30 09:44:29 volumio-elettra systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 30 09:44:29 volumio-elettra systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Plugin mpdemulation is not enabled Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Loading plugin "rest_api"... Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Loading plugin "websocket"... Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Starting Socket.io Server version 1.7.4 Dec 30 09:44:29 volumio-elettra volumio[1203]: info: Loading i18n strings for locale it Dec 30 09:44:29 volumio-elettra volumio[1203]: Updating browse sources language Dec 30 09:44:29 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 09:44:29 volumio-elettra ntpd[1035]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 30 09:44:29 volumio-elettra ntpd[1035]: DNS: Pool skipping: 89.46.74.148 Dec 30 09:44:29 volumio-elettra ntpd[1035]: DNS: Pool taking: 93.94.88.51 Dec 30 09:44:29 volumio-elettra ntpd[1035]: DNS: Pool skipping: 95.110.254.234 Dec 30 09:44:29 volumio-elettra ntpd[1035]: DNS: Pool taking: 85.199.214.99 Dec 30 09:44:29 volumio-elettra ntpd[1035]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::initPlayerControls Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: Express server listening on port 3000 Dec 30 09:44:30 volumio-elettra volumio[1203]: [Metrics] WebUI: 15s 711.80ms Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreStateMachine::resetVolumioState Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreStateMachine::getcurrentVolume Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 09:44:30 volumio-elettra volumio[1203]: info: Volumio Network Manager: Network status updated: 1 Dec 30 09:44:30 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 1 Dec 30 09:44:30 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 2 Dec 30 09:44:30 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 3 Dec 30 09:44:30 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 09:44:30 volumio-elettra volumio[1203]: info: Reloading queue from file Dec 30 09:44:31 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 30 09:44:31 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 30 09:44:31 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 30 09:44:31 volumio-elettra volumio[1203]: info: VolumeController:: Volume=100 Mute =false Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreStateMachine::pushState Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioPushState Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreStateMachine::updateTrackBlock Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrackBlock Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreStateMachine::setRepeat null single undefined Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreStateMachine::pushState Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioPushState Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreStateMachine::setRandom null Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreStateMachine::pushState Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:31 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioPushState Dec 30 09:44:31 volumio-elettra volumio[1203]: info: Setting Device type: Raspberry PI Dec 30 09:44:31 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 7 Dec 30 09:44:31 volumio-elettra volumio[1203]: info: Completed loading Core Plugins Dec 30 09:44:31 volumio-elettra volumio[1203]: info: Preparing to generate the ALSA configuration file Dec 30 09:44:31 volumio-elettra volumio[1203]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 30 09:44:31 volumio-elettra volumio[1203]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Dec 30 09:44:31 volumio-elettra volumio[1203]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 30 09:44:31 volumio-elettra systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 30 09:44:31 volumio-elettra bash[1019]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 30 09:44:31 volumio-elettra systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 30 09:44:31 volumio-elettra systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 30 09:44:31 volumio-elettra systemd[1]: setdatetime-helper.service: Consumed 1.926s CPU time. Dec 30 09:44:31 volumio-elettra systemd[1]: Reached target multi-user.target - Multi-User System. Dec 30 09:44:31 volumio-elettra systemd[1]: Reached target graphical.target - Graphical Interface. Dec 30 09:44:31 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 8 Dec 30 09:44:31 volumio-elettra systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 30 09:44:31 volumio-elettra systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 30 09:44:31 volumio-elettra systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 30 09:44:31 volumio-elettra systemd[1]: Startup finished in 15.913s (kernel) + 40.819s (userspace) = 56.733s. Dec 30 09:44:31 volumio-elettra sudo[1381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 09:44:31 volumio-elettra sudo[1381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:31 volumio-elettra sudo[1383]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 09:44:31 volumio-elettra sudo[1383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:31 volumio-elettra sudo[1381]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:32 volumio-elettra sudo[1383]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:32 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 9 Dec 30 09:44:32 volumio-elettra volumio[1203]: info: VolumeController:: Volume=100 Mute =false Dec 30 09:44:32 volumio-elettra volumio[1203]: info: CoreStateMachine::pushState Dec 30 09:44:32 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:32 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioPushState Dec 30 09:44:32 volumio-elettra volumio[1337]: Forking 3 albumart workers Dec 30 09:44:32 volumio-elettra volumio[1203]: info: Asound.conf file written Dec 30 09:44:32 volumio-elettra sudo[1417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 30 09:44:32 volumio-elettra sudo[1417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:32 volumio-elettra sudo[1417]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:32 volumio-elettra volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 09:44:32 volumio-elettra volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 09:44:32 volumio-elettra volumio[1203]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 30 09:44:32 volumio-elettra volumio[1203]: info: Output device has changed, restarting MPD Dec 30 09:44:33 volumio-elettra volumio[1203]: info: Output device has changed, restarting Shairport Sync Dec 30 09:44:33 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:33 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 09:44:33 volumio-elettra sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 09:44:33 volumio-elettra sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:33 volumio-elettra sudo[1426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 09:44:33 volumio-elettra sudo[1426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:33 volumio-elettra sudo[1424]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:33 volumio-elettra volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 09:44:33 volumio-elettra volumio[1203]: info: ___________ START PLUGINS ___________ Dec 30 09:44:33 volumio-elettra volumio[1203]: info: ControllerMpd::onStart: Initializing MPD Dec 30 09:44:33 volumio-elettra volumio[1203]: info: Creating MPD Configuration file Dec 30 09:44:33 volumio-elettra systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 30 09:44:33 volumio-elettra systemd[1]: Starting mpd.service - Music Player Daemon... Dec 30 09:44:33 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 09:44:33 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 09:44:33 volumio-elettra volumio[1203]: info: [1767084273862] CoreMusicLibrary::Adding element Server multimediali Dec 30 09:44:33 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 09:44:33 volumio-elettra sudo[1440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 09:44:33 volumio-elettra sudo[1440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:34 volumio-elettra sudo[1438]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 09:44:34 volumio-elettra sudo[1438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 30 09:44:34 volumio-elettra sudo[1442]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 30 09:44:34 volumio-elettra volumio[1203]: info: UPNP Browser: Client initialized successfully Dec 30 09:44:34 volumio-elettra sudo[1438]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:34 volumio-elettra sudo[1437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 09:44:34 volumio-elettra sudo[1437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:34 volumio-elettra systemd[1]: mpd.service: Deactivated successfully. Dec 30 09:44:34 volumio-elettra systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 30 09:44:34 volumio-elettra systemd[1]: mpd.socket: Deactivated successfully. Dec 30 09:44:34 volumio-elettra sudo[1437]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:34 volumio-elettra systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 30 09:44:34 volumio-elettra systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 30 09:44:34 volumio-elettra systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 30 09:44:34 volumio-elettra systemd[1]: Starting mpd.service - Music Player Daemon... Dec 30 09:44:34 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:34 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 09:44:34 volumio-elettra sudo[1446]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 09:44:34 volumio-elettra sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 30 09:44:34 volumio-elettra sudo[1451]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 30 09:44:34 volumio-elettra sudo[1446]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:34 volumio-elettra volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 09:44:34 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:34 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 09:44:35 volumio-elettra volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 09:44:35 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 09:44:35 volumio-elettra volumio[1203]: info: [1767084275102] CoreMusicLibrary::Adding element Last_100 Dec 30 09:44:35 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 09:44:35 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 30 09:44:35 volumio-elettra volumio[1203]: info: [1767084275115] CoreMusicLibrary::Adding element Webradio Dec 30 09:44:35 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 09:44:35 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 09:44:35 volumio-elettra volumio[1203]: info: Initializing BBC Radios Dec 30 09:44:35 volumio-elettra volumio-remote-updater[660]: [2025-12-30 09:44:35] [connect] Successful connection Dec 30 09:44:35 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 09:44:35 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 09:44:35 volumio-elettra volumio[1203]: info: Creating Spotify config file Dec 30 09:44:35 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:39 volumio-elettra systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 30 09:44:39 volumio-elettra volumio[1203]: info: Volumio Calling Home Dec 30 09:44:42 volumio-elettra mpd[1452]: 2025-12-30T09:44:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 30 09:44:42 volumio-elettra systemd[1]: Started mpd.service - Music Player Daemon. Dec 30 09:44:42 volumio-elettra sudo[1440]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:42 volumio-elettra sudo[1426]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:44 volumio-elettra volumio[1203]: info: Discovery: adding c2ca8abf-d6ea-44f7-ab13-b94db53e1ee0 Dec 30 09:44:44 volumio-elettra volumio[1203]: info: Discovery: Found device Volumio-elettra Dec 30 09:44:44 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:44 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:44 volumio-elettra volumio[1203]: info: Discovery: this is already registered, c2ca8abf-d6ea-44f7-ab13-b94db53e1ee0 Dec 30 09:44:44 volumio-elettra volumio[1203]: info: Discovery: Found device Volumio-elettra Dec 30 09:44:44 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:44 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:44 volumio-elettra sudo[1506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 30 09:44:44 volumio-elettra sudo[1506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:44 volumio-elettra systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 30 09:44:44 volumio-elettra systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 30 09:44:44 volumio-elettra systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 30 09:44:44 volumio-elettra sudo[1506]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:45 volumio-elettra volumio[1203]: info: Completed starting Core Plugins Dec 30 09:44:45 volumio-elettra volumio[1203]: info: ------------------------------------------- Dec 30 09:44:45 volumio-elettra volumio[1203]: info: ----- MyVolumio plugins startup ---- Dec 30 09:44:45 volumio-elettra volumio[1203]: info: ------------------------------------------- Dec 30 09:44:45 volumio-elettra volumio[1203]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 30 09:44:45 volumio-elettra volumio[1203]: info: MPD Permissions set Dec 30 09:44:45 volumio-elettra volumio[1203]: info: MPD Permissions set Dec 30 09:44:45 volumio-elettra volumio[1203]: info: Upmpdcli Daemon Started Dec 30 09:44:45 volumio-elettra volumio[1203]: info: Spotify config file written Dec 30 09:44:45 volumio-elettra sudo[1524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 30 09:44:45 volumio-elettra sudo[1524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:46 volumio-elettra systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 09:44:46 volumio-elettra systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 09:44:46 volumio-elettra systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 30 09:44:46 volumio-elettra go-librespot[1526]: go-librespot daemon starting... Dec 30 09:44:46 volumio-elettra sudo[1524]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:46 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:46+01:00" level=info msg="running go-librespot 0.4.0" Dec 30 09:44:46 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:46+01:00" level=debug msg="app state loaded" Dec 30 09:44:46 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 30 09:44:47 volumio-elettra volumio[1386]: Starting albumart workers Dec 30 09:44:47 volumio-elettra volumio[1387]: Starting albumart workers Dec 30 09:44:47 volumio-elettra volumio[1203]: error: MPD error: The expression evaluated to a falsy value: Dec 30 09:44:47 volumio-elettra volumio[1203]: assert.ok(self.idling) Dec 30 09:44:47 volumio-elettra volumio[1203]: error: The expression evaluated to a falsy value: Dec 30 09:44:47 volumio-elettra volumio[1203]: assert.ok(self.idling) Dec 30 09:44:47 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 10 Dec 30 09:44:47 volumio-elettra volumio[1203]: info: MPD running with PID1452 Dec 30 09:44:47 volumio-elettra volumio[1203]: ,establishing connection Dec 30 09:44:47 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:47 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:47 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:47 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:47 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:47 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:47 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:47 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1388]: Starting albumart workers Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 09:44:48 volumio-elettra volumio[1203]: info: No need to fix Spotify hosts Dec 30 09:44:48 volumio-elettra volumio[1203]: error: updateQueue error: null Dec 30 09:44:48 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 11 Dec 30 09:44:48 volumio-elettra volumio[1203]: info: Volumio called home Dec 30 09:44:49 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 30 09:44:49 volumio-elettra sudo[1544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 09:44:49 volumio-elettra sudo[1544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:49 volumio-elettra volumio[1203]: error: updateQueue error: null Dec 30 09:44:49 volumio-elettra sudo[1546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 30 09:44:49 volumio-elettra sudo[1546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:49 volumio-elettra systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 30 09:44:49 volumio-elettra mpd_monitor.sh[1549]: MPD Monitor Service: Starting MPD Monitor Service Dec 30 09:44:49 volumio-elettra sudo[1544]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:49 volumio-elettra systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 30 09:44:49 volumio-elettra systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 30 09:44:49 volumio-elettra systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 30 09:44:49 volumio-elettra systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 30 09:44:49 volumio-elettra sudo[1546]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:49 volumio-elettra mpd_monitor.sh[1552]: MPD Monitor Service: Starting MPD Monitor Service Dec 30 09:44:50 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 13 Dec 30 09:44:50 volumio-elettra volumio[1203]: info: Starting Shairport Sync Dec 30 09:44:50 volumio-elettra volumio[1203]: info: Starting Shairport Sync Dec 30 09:44:50 volumio-elettra volumio-remote-updater[660]: [2025-12-30 09:44:50] [connect] Successful connection Dec 30 09:44:50 volumio-elettra volumio[1203]: info: Starting Shairport Sync Dec 30 09:44:50 volumio-elettra sudo[1558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 09:44:50 volumio-elettra sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:51 volumio-elettra sudo[1560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 09:44:51 volumio-elettra sudo[1560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:51 volumio-elettra sudo[1561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 09:44:51 volumio-elettra sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 09:44:51 volumio-elettra systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 30 09:44:51 volumio-elettra systemd[1]: shairport-sync.service: Deactivated successfully. Dec 30 09:44:51 volumio-elettra systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 09:44:51 volumio-elettra systemd[1]: shairport-sync.service: Consumed 1.993s CPU time. Dec 30 09:44:51 volumio-elettra volumio[1203]: info: Successfully started MPD Monitor Dec 30 09:44:51 volumio-elettra volumio[1203]: info: Successfully started MPD Monitor Dec 30 09:44:51 volumio-elettra systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 09:44:51 volumio-elettra sudo[1560]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:51 volumio-elettra sudo[1558]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:51 volumio-elettra systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 30 09:44:51 volumio-elettra systemd[1]: shairport-sync.service: Deactivated successfully. Dec 30 09:44:51 volumio-elettra systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 09:44:51 volumio-elettra systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 09:44:51 volumio-elettra sudo[1561]: pam_unix(sudo:session): session closed for user root Dec 30 09:44:51 volumio-elettra volumio[1203]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 30 09:44:51 volumio-elettra volumio[1203]: SPOTIFY: BQDNhkteplMdwKpRQW8n5fXkzC7z1v9-GmIeG28vPqxleQIEevBrak4EuwACbd0SSC0_Q-mFw3SBHsb_rl9Ch6tDs_G9Tre1i2uSIRmyId1enmHDOQHonIZAkvV6DYZWNFyhh5iOuFojd3MdBNO8hRQnIA9AfpERvyuWvXL-1a88NVsR4aNnPCyExBmc2B7krh9xOHhijK0RGHiLbmKLpDeUQK-q8LpHNVn0HroX4zhxiD5Uwl52Czaicgg8584Ptj0jY6fWja1rSN7V4J5Nzt8rKr0jy7MnmWmNwp_X16FeStdedg7AH58oqLhV4MpcVW7jmaPJWcORwBISMHTAOm6W9wEPBBatQ2VORig7i-YMDFTS Dec 30 09:44:51 volumio-elettra volumio[1203]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 30 09:44:51 volumio-elettra volumio[1203]: info: New Spotify access token = BQDNhkteplMdwKpRQW8n5fXkzC7z1v9-GmIeG28vPqxleQIEevBrak4EuwACbd0SSC0_Q-mFw3SBHsb_rl9Ch6tDs_G9Tre1i2uSIRmyId1enmHDOQHonIZAkvV6DYZWNFyhh5iOuFojd3MdBNO8hRQnIA9AfpERvyuWvXL-1a88NVsR4aNnPCyExBmc2B7krh9xOHhijK0RGHiLbmKLpDeUQK-q8LpHNVn0HroX4zhxiD5Uwl52Czaicgg8584Ptj0jY6fWja1rSN7V4J5Nzt8rKr0jy7MnmWmNwp_X16FeStdedg7AH58oqLhV4MpcVW7jmaPJWcORwBISMHTAOm6W9wEPBBatQ2VORig7i-YMDFTS Dec 30 09:44:51 volumio-elettra volumio[1203]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 30 09:44:52 volumio-elettra volumio[1203]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 30 09:44:52 volumio-elettra volumio-remote-updater[660]: [2025-12-30 09:44:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1767084290 101 Dec 30 09:44:52 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 14 Dec 30 09:44:52 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 30 09:44:52 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:52+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 30 09:44:52 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:52+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 30 09:44:52 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:52+01:00" level=info msg="zeroconf server listening on port 34071" Dec 30 09:44:52 volumio-elettra volumio[1203]: info: Shairport-Sync Started Dec 30 09:44:52 volumio-elettra volumio[1203]: Error adding Membership: Error: addMembership EINVAL Dec 30 09:44:52 volumio-elettra volumio[1203]: info: Shairport-Sync Started Dec 30 09:44:52 volumio-elettra volumio[1203]: info: go-librespot daemon successfully initialized Dec 30 09:44:52 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 15 Dec 30 09:44:53 volumio-elettra volumio[1203]: info: Shairport-Sync Started Dec 30 09:44:55 volumio-elettra volumio[1203]: info: Initializing connection to go-librespot Websocket Dec 30 09:44:57 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:57+01:00" level=debug msg="new websocket client" Dec 30 09:44:57 volumio-elettra volumio[1203]: info: Connection to go-librespot Websocket established Dec 30 09:44:57 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:57 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:57 volumio-elettra go-librespot[1527]: time="2025-12-30T09:44:57+01:00" level=debug msg="obtained new client token: AABbTyK7fwqF2toFQXBIs9WzEt8i6Pz8rWUg+rC+KNmAl9IBcv/1SjOuPsM9oNX6cPCgobKKAnIhJa/RIpQFLIXDprYCtK/VPvZV21P1dYL+7NbvJuC5cJGZ6ou4E+kOlzAhhLtE8J227MT4h9bQajGJVaLLG8rXflyJKGV3rZqwRpi47aTilggt3B5WObrvACYEfaxsqRCru9Sc7U3qgEOYjdJjZwFWbN1yTxeWYIkNaccdnOHRDw0=" Dec 30 09:44:57 volumio-elettra volumio[1203]: verbose: New Socket.io Connection to 192.9.100.64 from 192.9.100.62 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 16 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Received Get System Info Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Discovery: Getting this device information Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Received Get System Info Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Discovery: Getting this device information Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Listing playlists Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Received Get System Info Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Discovery: Getting this device information Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Received Get System Info Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Discovery: Getting this device information Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:58 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:58 volumio-elettra volumio[1203]: info: Listing playlists Dec 30 09:44:59 volumio-elettra volumio[1203]: info: Received Get System Info Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 09:44:59 volumio-elettra volumio[1203]: info: Discovery: Getting this device information Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 09:44:59 volumio-elettra volumio[1203]: info: Received Get System Info Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 09:44:59 volumio-elettra volumio[1203]: info: Discovery: Getting this device information Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:59 volumio-elettra volumio[1203]: info: Listing playlists Dec 30 09:44:59 volumio-elettra volumio[1203]: info: Received Get System Info Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 09:44:59 volumio-elettra volumio[1203]: info: Discovery: Getting this device information Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 30 09:44:59 volumio-elettra volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 30 09:45:00 volumio-elettra volumio[1203]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 09:45:00 volumio-elettra volumio[1203]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 30 09:45:00 volumio-elettra volumio[1203]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 30 09:45:00 volumio-elettra volumio[1203]: at new NodeError (node:internal/errors:405:5) Dec 30 09:45:00 volumio-elettra volumio[1203]: at assert (node:internal/assert:14:11) Dec 30 09:45:00 volumio-elettra volumio[1203]: at internalConnectMultiple (node:net:1118:3) Dec 30 09:45:00 volumio-elettra volumio[1203]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 30 09:45:00 volumio-elettra volumio[1203]: at listOnTimeout (node:internal/timers:575:11) Dec 30 09:45:00 volumio-elettra volumio[1203]: at process.processTimers (node:internal/timers:514:7) { Dec 30 09:45:00 volumio-elettra volumio[1203]: code: 'ERR_INTERNAL_ASSERTION' Dec 30 09:45:00 volumio-elettra volumio[1203]: } Dec 30 09:45:00 volumio-elettra volumio[1203]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 09:45:02 volumio-elettra go-librespot[1527]: time="2025-12-30T09:45:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 30 09:45:02 volumio-elettra go-librespot[1527]: time="2025-12-30T09:45:02+01:00" level=debug msg="completed keyexchange" Dec 30 09:45:02 volumio-elettra go-librespot[1527]: time="2025-12-30T09:45:02+01:00" level=debug msg="completed challenge" Dec 30 09:45:02 volumio-elettra go-librespot[1527]: time="2025-12-30T09:45:02+01:00" level=info msg="authenticated AP" username="gi*us" Dec 30 09:45:08 volumio-elettra go-librespot[1527]: time="2025-12-30T09:45:08+01:00" level=info msg="authenticated Login5" username="gi*us" Dec 30 09:45:08 volumio-elettra go-librespot[1527]: time="2025-12-30T09:45:08+01:00" level=debug msg="initializing zeroconf session" username="gi*us" Dec 30 09:45:10 volumio-elettra sudo[1664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-30 09:44' Dec 30 09:45:10 volumio-elettra sudo[1664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"