Aug 28 08:01:06 volumio-pino ntpd[920]: CLOCK: time stepped by 28.527074 Aug 28 08:01:06 volumio-pino ntpd[920]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 28 08:01:06 volumio-pino systemd[1]: Started smbd.service - Samba SMB Daemon. Aug 28 08:01:06 volumio-pino systemd[1]: Reached target multi-user.target - Multi-User System. Aug 28 08:01:06 volumio-pino systemd[1]: Reached target graphical.target - Graphical Interface. Aug 28 08:01:06 volumio-pino systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Aug 28 08:01:06 volumio-pino systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Aug 28 08:01:06 volumio-pino systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Aug 28 08:01:06 volumio-pino systemd[1]: Startup finished in 18.917s (kernel) + 38.943s (userspace) = 57.861s. Aug 28 08:01:07 volumio-pino dhcpcd[636]: timed out Aug 28 08:01:07 volumio-pino sh[630]: timed out Aug 28 08:01:07 volumio-pino sh[630]: main: control_stop: No such file or directory Aug 28 08:01:07 volumio-pino dhcpcd[636]: main: control_stop: No such file or directory Aug 28 08:01:07 volumio-pino dhcpcd[636]: dhcpcd exited Aug 28 08:01:07 volumio-pino sh[630]: dhcpcd exited Aug 28 08:01:07 volumio-pino sh[582]: ifup: failed to bring up eth0 Aug 28 08:01:07 volumio-pino systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 28 08:01:07 volumio-pino systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 28 08:01:09 volumio-pino systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 28 08:01:09 volumio-pino systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 28 08:01:09 volumio-pino systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 08:01:09 volumio-pino systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 08:01:09 volumio-pino upmpdcli[1292]: Could not open config: /tmp/upmpdcli.conf Aug 28 08:01:09 volumio-pino systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 08:01:09 volumio-pino systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 08:01:09 volumio-pino volumio[1136]: info: ------------------------------------------- Aug 28 08:01:09 volumio-pino volumio[1136]: info: ----- Volumio3 ---- Aug 28 08:01:09 volumio-pino volumio[1136]: info: ------------------------------------------- Aug 28 08:01:09 volumio-pino volumio[1136]: info: ----- System startup ---- Aug 28 08:01:09 volumio-pino volumio[1136]: info: ------------------------------------------- Aug 28 08:01:11 volumio-pino volumio-remote-updater[611]: [2026-08-28 08:01:11] [connect] Successful connection Aug 28 08:01:12 volumio-pino volumio[1136]: info: MYVOLUMIO Environment detected Aug 28 08:01:13 volumio-pino volumio[1136]: info: Plugin folders cleanup Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning into folder /volumio/app/plugins/ Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning category audio_interface Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning category miscellanea Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning category music_service Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning category plugins.json Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning category system_controller Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning category user_interface Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning into folder /data/plugins/ Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning category music_service Aug 28 08:01:13 volumio-pino volumio[1136]: info: Scanning category system_controller Aug 28 08:01:13 volumio-pino volumio[1136]: info: Plugin folders cleanup completed Aug 28 08:01:13 volumio-pino volumio[1136]: info: ------------------------------------------- Aug 28 08:01:13 volumio-pino volumio[1136]: info: ----- Core plugins startup ---- Aug 28 08:01:13 volumio-pino volumio[1136]: info: ------------------------------------------- Aug 28 08:01:13 volumio-pino volumio[1136]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 08:01:13 volumio-pino volumio[1136]: info: Adding plugin upnp to MyMusic Plugins Aug 28 08:01:13 volumio-pino volumio[1136]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 08:01:13 volumio-pino volumio[1136]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 08:01:13 volumio-pino volumio[1136]: info: Loading plugins from folder /data/plugins/ Aug 28 08:01:13 volumio-pino volumio[1136]: info: Loading plugin "system"... Aug 28 08:01:13 volumio-pino volumio[1136]: info: Loading plugin "appearance"... Aug 28 08:01:17 volumio-pino volumio[1136]: info: Loading plugin "network"... Aug 28 08:01:17 volumio-pino volumio[1136]: info: Refreshing Cached IP Addresses Aug 28 08:01:17 volumio-pino sudo[1317]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 08:01:17 volumio-pino sudo[1317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:01:17 volumio-pino sudo[1317]: pam_unix(sudo:session): session closed for user root Aug 28 08:01:17 volumio-pino sudo[1315]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 08:01:17 volumio-pino sudo[1315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:01:17 volumio-pino sudo[1315]: pam_unix(sudo:session): session closed for user root Aug 28 08:01:17 volumio-pino volumio[1136]: info: Loading plugin "services"... Aug 28 08:01:17 volumio-pino volumio[1136]: info: Loading plugin "volumio5onboarding"... Aug 28 08:01:17 volumio-pino sudo[1331]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 08:01:17 volumio-pino sudo[1331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:01:17 volumio-pino volumio[1136]: info: Loading plugin "alsa_controller"... Aug 28 08:01:18 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 08:01:18 volumio-pino volumio[1136]: info: Loading plugin "wizard"... Aug 28 08:01:18 volumio-pino volumio[1136]: info: Loading plugin "networkfs"... Aug 28 08:01:18 volumio-pino volumio[1136]: info: Starting Udev Watcher for removable devices Aug 28 08:01:18 volumio-pino sudo[1349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bucci,password=bucci1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.178.122/musica /mnt/NAS/Musica_NAS_Mini_UBUNTU Aug 28 08:01:18 volumio-pino sudo[1349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:01:18 volumio-pino volumio[1136]: info: Ignoring mount for partition: boot Aug 28 08:01:18 volumio-pino volumio[1136]: info: Ignoring mount for partition: volumio Aug 28 08:01:18 volumio-pino volumio[1136]: info: Ignoring mount for partition: volumio_data Aug 28 08:01:18 volumio-pino sudo[1351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.6/musica /mnt/NAS/PC_Pino Aug 28 08:01:18 volumio-pino sudo[1351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:01:18 volumio-pino kernel: netfs: FS-Cache loaded Aug 28 08:01:18 volumio-pino volumio[1136]: info: Mounting Device KINGSTON Aug 28 08:01:19 volumio-pino sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 28 08:01:19 volumio-pino sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:01:19 volumio-pino kernel: Key type cifs.spnego registered Aug 28 08:01:19 volumio-pino kernel: Key type cifs.idmap registered Aug 28 08:01:19 volumio-pino kernel: CIFS: Attempting to mount //192.168.178.122/musica Aug 28 08:01:19 volumio-pino 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. Aug 28 08:01:19 volumio-pino kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 28 08:01:19 volumio-pino kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 28 08:01:19 volumio-pino sudo[1367]: pam_unix(sudo:session): session closed for user root Aug 28 08:01:19 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 08:01:19 volumio-pino volumio[1136]: info: Loading plugin "volumio_command_line_client"... Aug 28 08:01:19 volumio-pino volumio[1136]: info: Loading plugin "upnp"... Aug 28 08:01:19 volumio-pino volumio[1136]: info: [1787896879391] Starting Upmpd Daemon Aug 28 08:01:19 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 08:01:19 volumio-pino volumio[1136]: info: Loading plugin "my_music"... Aug 28 08:01:19 volumio-pino volumio[1136]: info: Loading plugin "mpd"... Aug 28 08:01:20 volumio-pino volumio[1136]: info: Loading plugin "upnp_browser"... Aug 28 08:01:23 volumio-pino sudo[1331]: pam_unix(sudo:session): session closed for user root Aug 28 08:01:24 volumio-pino systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 28 08:01:24 volumio-pino systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 08:01:24 volumio-pino systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 08:01:24 volumio-pino upmpdcli[1384]: Could not open config: /tmp/upmpdcli.conf Aug 28 08:01:24 volumio-pino systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 08:01:24 volumio-pino systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 08:01:24 volumio-pino volumio[1136]: info: Starting UPNP Browser Aug 28 08:01:24 volumio-pino volumio[1136]: info: Loading plugin "alarm-clock"... Aug 28 08:01:25 volumio-pino volumio[1136]: info: Loading plugin "airplay_emulation"... Aug 28 08:01:25 volumio-pino volumio[1136]: info: Starting Shairport Sync Aug 28 08:01:25 volumio-pino volumio[1136]: info: Loading plugin "last_100"... Aug 28 08:01:25 volumio-pino volumio[1136]: info: Loading plugin "webradio"... Aug 28 08:01:25 volumio-pino volumio[1136]: info: Loading plugin "i2s_dacs"... Aug 28 08:01:25 volumio-pino volumio[1136]: info: Loading plugin "volumiodiscovery"... Aug 28 08:01:25 volumio-pino volumio[1136]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 08:01:25 volumio-pino node[1136]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 08:01:25 volumio-pino volumio[1136]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 08:01:25 volumio-pino volumio[1136]: *** WARNING *** For more information see Aug 28 08:01:25 volumio-pino volumio[1136]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 08:01:25 volumio-pino volumio[1136]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 08:01:25 volumio-pino volumio[1136]: *** WARNING *** For more information see Aug 28 08:01:25 volumio-pino node[1136]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 08:01:25 volumio-pino node[1136]: *** WARNING *** For more information see Aug 28 08:01:25 volumio-pino node[1136]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 08:01:25 volumio-pino node[1136]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 08:01:25 volumio-pino node[1136]: *** WARNING *** For more information see Aug 28 08:01:25 volumio-pino volumio[1136]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 08:01:25 volumio-pino volumio[1136]: info: Discovery: Started advertising with name: Volumio Pino Aug 28 08:01:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 08:01:26 volumio-pino volumio[1136]: info: Loading plugin "bandcamp"... Aug 28 08:01:26 volumio-pino volumio-remote-updater[611]: [2026-08-28 08:01:26] [connect] Successful connection Aug 28 08:01:26 volumio-pino systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 28 08:01:26 volumio-pino systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 28 08:01:26 volumio-pino systemd[1]: setdatetime-helper.service: Consumed 2.040s CPU time. Aug 28 08:01:29 volumio-pino kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 28 08:01:29 volumio-pino kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Aug 28 08:01:29 volumio-pino kernel: CIFS: Attempting to mount //192.168.1.6/musica Aug 28 08:01:29 volumio-pino sudo[1349]: pam_unix(sudo:session): session closed for user root Aug 28 08:01:31 volumio-pino volumio[1136]: info: Loading plugin "calmradio"... Aug 28 08:01:35 volumio-pino kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 28 08:01:35 volumio-pino kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 28 08:01:35 volumio-pino sudo[1351]: pam_unix(sudo:session): session closed for user root Aug 28 08:01:36 volumio-pino volumio[1136]: info: Loading plugin "jellyfin"... Aug 28 08:01:39 volumio-pino systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Aug 28 08:01:39 volumio-pino systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 08:01:39 volumio-pino systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 08:01:40 volumio-pino upmpdcli[1420]: Could not open config: /tmp/upmpdcli.conf Aug 28 08:01:40 volumio-pino systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 08:01:40 volumio-pino systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 08:01:40 volumio-pino volumio[1136]: info: Loading plugin "mixcloud"... Aug 28 08:01:41 volumio-pino volumio-remote-updater[611]: [2026-08-28 08:01:41] [connect] Successful connection Aug 28 08:01:42 volumio-pino volumio[1136]: info: Loading plugin "soundcloud"... Aug 28 08:01:44 volumio-pino volumio[1136]: info: Loading plugin "spop"... Aug 28 08:01:49 volumio-pino volumio[1136]: info: Plugin youtube2 is not enabled Aug 28 08:01:49 volumio-pino volumio[1136]: info: Plugin ytmusic is not enabled Aug 28 08:01:49 volumio-pino volumio[1136]: info: Loading plugin "outputs"... Aug 28 08:01:49 volumio-pino volumio[1136]: info: Loading plugin "albumart"... Aug 28 08:01:49 volumio-pino volumio[1136]: info: Plugin example_plugin is not enabled Aug 28 08:01:49 volumio-pino volumio[1136]: info: Loading plugin "inputs"... Aug 28 08:01:49 volumio-pino volumio[1136]: info: Loading plugin "updater_comm"... Aug 28 08:01:49 volumio-pino volumio[1136]: info: Plugin mpdemulation is not enabled Aug 28 08:01:49 volumio-pino volumio[1136]: info: Loading plugin "rest_api"... Aug 28 08:01:49 volumio-pino volumio[1136]: info: Loading plugin "websocket"... Aug 28 08:01:49 volumio-pino volumio[1136]: info: Starting Socket.io Server version 1.7.4 Aug 28 08:01:49 volumio-pino volumio[1136]: info: Plugin RoonBridge is not enabled Aug 28 08:01:49 volumio-pino volumio[1136]: info: Loading plugin "scheduledrestart"... Aug 28 08:01:51 volumio-pino volumio[1136]: info: Applying required configuration parameters for plugin scheduledrestart Aug 28 08:01:51 volumio-pino volumio[1136]: info: Loading i18n strings for locale it Aug 28 08:01:51 volumio-pino volumio[1136]: Updating browse sources language Aug 28 08:01:51 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:01:52 volumio-pino volumio[1435]: Forking 3 albumart workers Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::initPlayerControls Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 08:01:52 volumio-pino volumio[1136]: Express server listening on port 3000 Aug 28 08:01:52 volumio-pino volumio[1136]: [Metrics] WebUI: 45s 227.14ms Aug 28 08:01:53 volumio-pino volumio[1136]: info: CoreStateMachine::resetVolumioState Aug 28 08:01:53 volumio-pino volumio[1136]: info: CoreStateMachine::getcurrentVolume Aug 28 08:01:53 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 08:01:54 volumio-pino sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 08:01:54 volumio-pino sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:01:54 volumio-pino sudo[1479]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 08:01:54 volumio-pino sudo[1479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:01:54 volumio-pino sudo[1477]: pam_unix(sudo:session): session closed for user root Aug 28 08:01:54 volumio-pino sudo[1479]: pam_unix(sudo:session): session closed for user root Aug 28 08:01:54 volumio-pino volumio[1136]: info: Volumio Network Manager: Network status updated: 2 Aug 28 08:01:55 volumio-pino systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Aug 28 08:01:55 volumio-pino systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 08:01:55 volumio-pino systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 08:01:55 volumio-pino volumio[1136]: info: Cannot mount NAS PC_Pino at system boot, trial number 1 ,retrying in 5 seconds Aug 28 08:01:55 volumio-pino volumio[1136]: info: Cannot mount NAS Musica_NAS_Mini_UBUNTU at system boot, trial number 1 ,retrying in 5 seconds Aug 28 08:01:55 volumio-pino upmpdcli[1486]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.130 port 49152 IPV6 port 49152 Aug 28 08:01:55 volumio-pino upmpdcli[1486]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 Aug 28 08:01:55 volumio-pino upmpdcli[1486]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Aug 28 08:01:55 volumio-pino upmpdcli[1486]: :2:../src/main.cxx:732::MPD connection failed Aug 28 08:01:56 volumio-pino volumio-remote-updater[611]: [2026-08-28 08:01:56] [connect] Successful connection Aug 28 08:01:56 volumio-pino volumio[1136]: info: VolumeController:: Volume=96 Mute =false Aug 28 08:01:56 volumio-pino volumio[1136]: info: CoreStateMachine::pushState Aug 28 08:01:56 volumio-pino volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 28 08:01:56 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 08:01:56 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioPushState Aug 28 08:01:57 volumio-pino volumio[1136]: info: CoreStateMachine::updateTrackBlock Aug 28 08:01:57 volumio-pino volumio[1136]: info: CorePlayQueue::getTrackBlock Aug 28 08:01:57 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 08:01:57 volumio-pino upmpdcli[1486]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Aug 28 08:01:57 volumio-pino upmpdcli[1486]: :2:../src/main.cxx:732::MPD connection failed Aug 28 08:01:57 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 08:01:58 volumio-pino volumio[1136]: info: Reloading queue from file Aug 28 08:01:58 volumio-pino volumio[1136]: info: CoreStateMachine::setRepeat true single undefined Aug 28 08:01:58 volumio-pino volumio[1136]: info: CoreStateMachine::pushState Aug 28 08:01:58 volumio-pino volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 28 08:01:58 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 08:01:58 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioPushState Aug 28 08:01:58 volumio-pino volumio[1136]: info: CoreStateMachine::setRandom true Aug 28 08:01:58 volumio-pino volumio[1136]: info: CoreStateMachine::pushState Aug 28 08:01:58 volumio-pino volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 28 08:01:58 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 08:01:58 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioPushState Aug 28 08:01:58 volumio-pino volumio[1136]: info: Setting Device type: Raspberry PI Aug 28 08:01:59 volumio-pino volumio-remote-updater[611]: [2026-08-28 08:01:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787896916 101 Aug 28 08:01:59 volumio-pino volumio[1136]: 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: 1 Aug 28 08:01:59 volumio-pino volumio[1136]: info: Completed loading Core Plugins Aug 28 08:01:59 volumio-pino volumio[1136]: info: Preparing to generate the ALSA configuration file Aug 28 08:01:59 volumio-pino volumio[1136]: info: VolumeController:: Volume=96 Mute =false Aug 28 08:01:59 volumio-pino volumio[1136]: info: CoreStateMachine::pushState Aug 28 08:01:59 volumio-pino volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 28 08:01:59 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioPushState Aug 28 08:02:00 volumio-pino volumio[1136]: info: Discovery: adding ff5ef772-0350-4944-a84c-cac865a11fde Aug 28 08:02:00 volumio-pino volumio[1136]: info: Discovery: Found device Volumio Pino Aug 28 08:02:00 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 28 08:02:00 volumio-pino volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 28 08:02:00 volumio-pino sudo[1524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 28 08:02:00 volumio-pino sudo[1524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:00 volumio-pino volumio[1136]: info: Discovery: this is already registered, ff5ef772-0350-4944-a84c-cac865a11fde Aug 28 08:02:00 volumio-pino volumio[1136]: info: Discovery: Found device Volumio Pino Aug 28 08:02:00 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 28 08:02:00 volumio-pino volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 28 08:02:00 volumio-pino volumio[1136]: info: Asound.conf file unchanged, so no further update is needed Aug 28 08:02:00 volumio-pino volumio[1136]: info: Output device has changed, restarting MPD Aug 28 08:02:00 volumio-pino sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.6/musica /mnt/NAS/PC_Pino Aug 28 08:02:00 volumio-pino sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:00 volumio-pino sudo[1524]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:01 volumio-pino kernel: CIFS: Attempting to mount //192.168.1.6/musica Aug 28 08:02:01 volumio-pino volumio[1136]: info: Output device has changed, restarting Shairport Sync Aug 28 08:02:01 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:01 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 08:02:01 volumio-pino sudo[1529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bucci,password=bucci1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.178.122/musica /mnt/NAS/Musica_NAS_Mini_UBUNTU Aug 28 08:02:01 volumio-pino sudo[1529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:01 volumio-pino upmpdcli[1486]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Aug 28 08:02:01 volumio-pino upmpdcli[1486]: :2:../src/main.cxx:732::MPD connection failed Aug 28 08:02:01 volumio-pino sudo[1532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 08:02:01 volumio-pino sudo[1532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:01 volumio-pino sudo[1532]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:01 volumio-pino sudo[1536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 08:02:01 volumio-pino sudo[1536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:01 volumio-pino volumio[1136]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 08:02:01 volumio-pino volumio[1136]: info: ___________ START PLUGINS ___________ Aug 28 08:02:01 volumio-pino volumio[1136]: info: ControllerMpd::onStart: Initializing MPD Aug 28 08:02:01 volumio-pino volumio[1136]: info: Creating MPD Configuration file Aug 28 08:02:01 volumio-pino systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 08:02:01 volumio-pino systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 08:02:02 volumio-pino sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 28 08:02:02 volumio-pino sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:02 volumio-pino sudo[1549]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 08:02:02 volumio-pino sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 08:02:02 volumio-pino sudo[1554]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 28 08:02:02 volumio-pino sudo[1549]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:02 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 08:02:02 volumio-pino sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 08:02:02 volumio-pino sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:02 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 08:02:02 volumio-pino volumio[1136]: info: [1787896922510] CoreMusicLibrary::Adding element Server multimediali Aug 28 08:02:02 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:02:02 volumio-pino sudo[1551]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:02 volumio-pino volumio[1136]: info: UPNP Browser: Client initialized successfully Aug 28 08:02:02 volumio-pino systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 08:02:02 volumio-pino sudo[1547]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:03 volumio-pino sudo[1556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 08:02:03 volumio-pino sudo[1556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:03 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:03 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 08:02:03 volumio-pino volumio[1136]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 08:02:03 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:03 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 08:02:03 volumio-pino systemd[1]: mpd.service: Deactivated successfully. Aug 28 08:02:03 volumio-pino systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 08:02:03 volumio-pino systemd[1]: mpd.service: Consumed 1.084s CPU time. Aug 28 08:02:03 volumio-pino systemd[1]: mpd.socket: Deactivated successfully. Aug 28 08:02:03 volumio-pino systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 08:02:03 volumio-pino systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 08:02:03 volumio-pino systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 08:02:03 volumio-pino systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 08:02:04 volumio-pino volumio[1136]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 08:02:04 volumio-pino volumio5-onboarding[1558]: time=2026-08-28T08:02:04.182+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 28 08:02:04 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 08:02:04 volumio-pino volumio[1136]: info: [1787896924252] CoreMusicLibrary::Adding element Last_100 Aug 28 08:02:04 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:02:04 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 08:02:04 volumio-pino volumio[1136]: info: [1787896924367] CoreMusicLibrary::Adding element Webradio Aug 28 08:02:04 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:02:04 volumio-pino sudo[1574]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 08:02:04 volumio-pino sudo[1574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 08:02:04 volumio-pino sudo[1577]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 28 08:02:04 volumio-pino sudo[1574]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:04 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 08:02:04 volumio-pino volumio[1136]: info: Initializing BBC Radios Aug 28 08:02:05 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 08:02:05 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 08:02:06 volumio-pino volumio[1136]: info: [1787896926041] CoreMusicLibrary::Adding element Bandcamp Discover Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:02:06 volumio-pino volumio[1136]: Cannot find translation for source Bandcamp Discover Aug 28 08:02:06 volumio-pino volumio[1136]: info: Adding Calm Radio to Browse Sources Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 08:02:06 volumio-pino volumio[1136]: info: [1787896926174] CoreMusicLibrary::Adding element Calm Radio Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:02:06 volumio-pino volumio[1136]: Cannot find translation for source Bandcamp Discover Aug 28 08:02:06 volumio-pino volumio[1136]: Cannot find translation for source Calm Radio Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 08:02:06 volumio-pino volumio[1136]: info: Discovery: Getting this device information Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 28 08:02:06 volumio-pino volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 08:02:06 volumio-pino volumio[1136]: info: [1787896926834] CoreMusicLibrary::Adding element Jellyfin Aug 28 08:02:06 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:02:06 volumio-pino volumio[1136]: Cannot find translation for source Bandcamp Discover Aug 28 08:02:06 volumio-pino volumio[1136]: Cannot find translation for source Calm Radio Aug 28 08:02:06 volumio-pino volumio[1136]: Cannot find translation for source Jellyfin Aug 28 08:02:06 volumio-pino volumio[1136]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.1.130","id":"ff5ef772-0350-4944-a84c-cac865a11fde","name":"Volumio Pino","serviceName":"Volumio","state":{"albumart":"http://cdn-profiles.tunein.com/s47723/images/logoq.png?t=2","mute":false,"status":"stop","track":"1.FM Smooth Jazz","volume":96},"type":"device"} Aug 28 08:02:07 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 08:02:07 volumio-pino volumio[1136]: info: [1787896927204] CoreMusicLibrary::Adding element Mixcloud Aug 28 08:02:07 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source Bandcamp Discover Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source Calm Radio Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source Jellyfin Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source Mixcloud Aug 28 08:02:07 volumio-pino kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 28 08:02:07 volumio-pino kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 28 08:02:07 volumio-pino kernel: CIFS: Attempting to mount //192.168.178.122/musica Aug 28 08:02:07 volumio-pino sudo[1526]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:07 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 08:02:07 volumio-pino volumio[1136]: info: [1787896927521] CoreMusicLibrary::Adding element SoundCloud Aug 28 08:02:07 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source Bandcamp Discover Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source Calm Radio Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source Jellyfin Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source Mixcloud Aug 28 08:02:07 volumio-pino volumio[1136]: Cannot find translation for source SoundCloud Aug 28 08:02:08 volumio-pino volumio[1136]: info: Creating Spotify config file Aug 28 08:02:08 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:11 volumio-pino upmpdcli[1486]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Aug 28 08:02:11 volumio-pino upmpdcli[1486]: :2:../src/main.cxx:732::MPD connection failed Aug 28 08:02:13 volumio-pino volumio[1136]: info: Scheduling reboots with hours: 8, minutes: 0 in timezone: Europe/Rome Aug 28 08:02:13 volumio-pino volumio[1136]: info: Scheduling job with cron expression: 0 0 8 * * 0 Aug 28 08:02:14 volumio-pino volumio[1136]: info: Scheduling job with cron expression: 0 0 8 * * 1 Aug 28 08:02:14 volumio-pino volumio5-onboarding[1558]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:35136->127.0.0.1:3000: i/o timeout Aug 28 08:02:14 volumio-pino volumio[1136]: info: Scheduling job with cron expression: 0 0 8 * * 2 Aug 28 08:02:14 volumio-pino systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 28 08:02:14 volumio-pino systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 28 08:02:14 volumio-pino volumio[1136]: info: Scheduling job with cron expression: 0 0 8 * * 3 Aug 28 08:02:14 volumio-pino volumio[1136]: info: Scheduling job with cron expression: 0 0 8 * * 4 Aug 28 08:02:14 volumio-pino volumio[1136]: info: Scheduling job with cron expression: 0 0 8 * * 5 Aug 28 08:02:14 volumio-pino volumio[1136]: info: Scheduling job with cron expression: 0 0 8 * * 6 Aug 28 08:02:14 volumio-pino systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 28 08:02:14 volumio-pino systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 08:02:14 volumio-pino systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 08:02:14 volumio-pino volumio5-onboarding[1606]: time=2026-08-28T08:02:14.760+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 28 08:02:14 volumio-pino volumio[1136]: info: Volumio Calling Home Aug 28 08:02:15 volumio-pino volumio[1447]: Starting albumart workers Aug 28 08:02:16 volumio-pino mpd[1578]: 2026-08-28T08:02:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 08:02:16 volumio-pino systemd[1]: Started mpd.service - Music Player Daemon. Aug 28 08:02:16 volumio-pino sudo[1536]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:16 volumio-pino sudo[1556]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:16 volumio-pino volumio[1445]: Starting albumart workers Aug 28 08:02:17 volumio-pino volumio[1446]: Starting albumart workers Aug 28 08:02:17 volumio-pino kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 28 08:02:17 volumio-pino kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Aug 28 08:02:17 volumio-pino sudo[1529]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:18 volumio-pino volumio[1136]: info: MPD Permissions set Aug 28 08:02:18 volumio-pino volumio[1136]: info: Completed starting Core Plugins Aug 28 08:02:18 volumio-pino volumio[1136]: info: ------------------------------------------- Aug 28 08:02:18 volumio-pino volumio[1136]: info: ----- MyVolumio plugins startup ---- Aug 28 08:02:18 volumio-pino volumio[1136]: info: ------------------------------------------- Aug 28 08:02:18 volumio-pino volumio[1136]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 08:02:18 volumio-pino volumio[1136]: info: MPD Permissions set Aug 28 08:02:18 volumio-pino volumio[1136]: info: Cannot mount NAS Musica_NAS_Mini_UBUNTU at system boot, trial number 2 ,retrying in 5 seconds Aug 28 08:02:18 volumio-pino volumio[1136]: info: Cannot mount NAS PC_Pino at system boot, trial number 2 ,retrying in 5 seconds Aug 28 08:02:18 volumio-pino volumio[1136]: info: Upmpdcli Daemon Started Aug 28 08:02:19 volumio-pino volumio[1136]: info: MPD running with PID1578 Aug 28 08:02:19 volumio-pino volumio[1136]: ,establishing connection Aug 28 08:02:19 volumio-pino volumio[1136]: info: Volumio called home Aug 28 08:02:19 volumio-pino volumio[1136]: info: Spotify config file written Aug 28 08:02:20 volumio-pino sudo[1633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 28 08:02:20 volumio-pino sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:20 volumio-pino 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. Aug 28 08:02:20 volumio-pino 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. Aug 28 08:02:20 volumio-pino systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 28 08:02:20 volumio-pino go-librespot[1641]: go-librespot daemon starting... Aug 28 08:02:20 volumio-pino sudo[1633]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:21 volumio-pino go-librespot[1642]: time="2026-08-28T08:02:21+02:00" level=info msg="running go-librespot 0.6.2" Aug 28 08:02:21 volumio-pino go-librespot[1642]: time="2026-08-28T08:02:21+02:00" level=debug msg="app state loaded" Aug 28 08:02:21 volumio-pino go-librespot[1642]: time="2026-08-28T08:02:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 28 08:02:22 volumio-pino go-librespot[1642]: time="2026-08-28T08:02:22+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 28 08:02:22 volumio-pino go-librespot[1642]: time="2026-08-28T08:02:22+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 28 08:02:22 volumio-pino go-librespot[1642]: time="2026-08-28T08:02:22+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 28 08:02:22 volumio-pino go-librespot[1642]: time="2026-08-28T08:02:22+02:00" level=info msg="zeroconf server listening on port 41947" Aug 28 08:02:24 volumio-pino volumio5-onboarding[1606]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:39114->127.0.0.1:3000: i/o timeout Aug 28 08:02:24 volumio-pino systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 28 08:02:24 volumio-pino systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 28 08:02:25 volumio-pino volumio[1136]: error: MPD error: The expression evaluated to a falsy value: Aug 28 08:02:25 volumio-pino volumio[1136]: assert.ok(self.idling) Aug 28 08:02:25 volumio-pino volumio[1136]: error: The expression evaluated to a falsy value: Aug 28 08:02:25 volumio-pino volumio[1136]: assert.ok(self.idling) Aug 28 08:02:25 volumio-pino volumio[1136]: error: MPD error: The expression evaluated to a falsy value: Aug 28 08:02:25 volumio-pino volumio[1136]: assert.ok(self.idling) Aug 28 08:02:25 volumio-pino volumio[1136]: error: The expression evaluated to a falsy value: Aug 28 08:02:25 volumio-pino volumio[1136]: assert.ok(self.idling) Aug 28 08:02:25 volumio-pino systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Aug 28 08:02:25 volumio-pino systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 08:02:25 volumio-pino systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 08:02:25 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:25.215+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 28 08:02:25 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:25 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:25 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:25 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:25 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:25 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:25 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:25 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:25 volumio-pino sudo[1667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bucci,password=bucci1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.178.122/musica /mnt/NAS/Musica_NAS_Mini_UBUNTU Aug 28 08:02:25 volumio-pino sudo[1667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:26 volumio-pino sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.6/musica /mnt/NAS/PC_Pino Aug 28 08:02:26 volumio-pino sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 08:02:26 volumio-pino kernel: CIFS: Attempting to mount //192.168.178.122/musica Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 08:02:26 volumio-pino volumio[1136]: info: No need to fix Spotify hosts Aug 28 08:02:27 volumio-pino upmpdcli[1703]: writing RSA key Aug 28 08:02:27 volumio-pino volumio[1136]: error: error Aug 28 08:02:28 volumio-pino volumio[1136]: info: [jellyfin-poller] Polled http://192.168.1.6:8096: offline Aug 28 08:02:28 volumio-pino volumio[1136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 28 08:02:29 volumio-pino volumio[1136]: error: updateQueue error: null Aug 28 08:02:29 volumio-pino volumio[1136]: info: An error occurred while refreshing Spotify Token Error: Bad Request Aug 28 08:02:29 volumio-pino dbus-daemon[594]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.15' (uid=0 pid=1651 comm="/usr/bin/volumio5-onboarding") Aug 28 08:02:29 volumio-pino volumio[1136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 28 08:02:29 volumio-pino systemd[1]: Starting bluetooth.service - Bluetooth service... Aug 28 08:02:30 volumio-pino kernel: Bluetooth: Core ver 2.22 Aug 28 08:02:30 volumio-pino kernel: NET: Registered PF_BLUETOOTH protocol family Aug 28 08:02:30 volumio-pino kernel: Bluetooth: HCI device and connection manager initialized Aug 28 08:02:30 volumio-pino kernel: Bluetooth: HCI socket layer initialized Aug 28 08:02:30 volumio-pino kernel: Bluetooth: L2CAP socket layer initialized Aug 28 08:02:30 volumio-pino kernel: Bluetooth: SCO socket layer initialized Aug 28 08:02:30 volumio-pino bluetoothd[1710]: Bluetooth daemon 5.83 Aug 28 08:02:30 volumio-pino dbus-daemon[594]: [system] Successfully activated service 'org.bluez' Aug 28 08:02:30 volumio-pino systemd[1]: Started bluetooth.service - Bluetooth service. Aug 28 08:02:30 volumio-pino bluetoothd[1710]: Starting SDP server Aug 28 08:02:30 volumio-pino bluetoothd[1710]: Excluding (cli) sap Aug 28 08:02:30 volumio-pino bluetoothd[1710]: Excluding (cli) scanparam Aug 28 08:02:30 volumio-pino bluetoothd[1710]: Excluding (cli) midi Aug 28 08:02:30 volumio-pino bluetoothd[1710]: Excluding (cli) battery Aug 28 08:02:30 volumio-pino kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 28 08:02:30 volumio-pino kernel: Bluetooth: BNEP filters: protocol multicast Aug 28 08:02:30 volumio-pino kernel: Bluetooth: BNEP socket layer initialized Aug 28 08:02:30 volumio-pino bluetoothd[1710]: Bluetooth management interface 1.23 initialized Aug 28 08:02:30 volumio-pino dbus-daemon[594]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.16' (uid=0 pid=1710 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 28 08:02:30 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:30.696+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Aug 28 08:02:30 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:30.705+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Aug 28 08:02:30 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:30.711+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Aug 28 08:02:30 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:30.744+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Aug 28 08:02:30 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:30.761+02:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Aug 28 08:02:30 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:30.762+02:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Aug 28 08:02:30 volumio-pino systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 28 08:02:31 volumio-pino dbus-daemon[594]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 28 08:02:31 volumio-pino systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 28 08:02:36 volumio-pino kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 28 08:02:36 volumio-pino kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Aug 28 08:02:36 volumio-pino kernel: CIFS: Attempting to mount //192.168.1.6/musica Aug 28 08:02:36 volumio-pino sudo[1667]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:36 volumio-pino volumio[1136]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 28 08:02:36 volumio-pino volumio[1136]: info: Received Get System Info Aug 28 08:02:36 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 08:02:36 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 08:02:36 volumio-pino volumio[1136]: info: Discovery: Getting this device information Aug 28 08:02:36 volumio-pino volumio[1136]: info: CoreCommandRouter::volumioGetState Aug 28 08:02:36 volumio-pino volumio[1136]: info: CorePlayQueue::getTrack 0 Aug 28 08:02:36 volumio-pino volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 08:02:36 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:36.885+02:00 level=INFO msg="system info for 8aedd331fefebd75636e5b2201a654b8" deviceName="Volumio Pino" deviceVariant=volumio deviceModel= softwareVersion=4.119 Aug 28 08:02:36 volumio-pino volumio5-onboarding[1651]: time=2026-08-28T08:02:36.903+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 28 08:02:36 volumio-pino volumio[1136]: info: go-librespot daemon successfully initialized Aug 28 08:02:37 volumio-pino volumio[1136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 08:02:37 volumio-pino volumio[1136]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 28 08:02:37 volumio-pino volumio[1136]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 28 08:02:37 volumio-pino volumio[1136]: at new NodeError (node:internal/errors:405:5) Aug 28 08:02:37 volumio-pino volumio[1136]: at assert (node:internal/assert:14:11) Aug 28 08:02:37 volumio-pino volumio[1136]: at internalConnectMultiple (node:net:1118:3) Aug 28 08:02:37 volumio-pino volumio[1136]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 28 08:02:37 volumio-pino volumio[1136]: at listOnTimeout (node:internal/timers:575:11) Aug 28 08:02:37 volumio-pino volumio[1136]: at process.processTimers (node:internal/timers:514:7) { Aug 28 08:02:37 volumio-pino volumio[1136]: code: 'ERR_INTERNAL_ASSERTION' Aug 28 08:02:37 volumio-pino volumio[1136]: } Aug 28 08:02:37 volumio-pino volumio[1136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 08:02:42 volumio-pino kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 28 08:02:42 volumio-pino kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Aug 28 08:02:42 volumio-pino sudo[1669]: pam_unix(sudo:session): session closed for user root Aug 28 08:02:48 volumio-pino sudo[1760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 08:01' Aug 28 08:02:48 volumio-pino sudo[1760]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"