May 25 13:46:15 volumio-fosiaudio-ht4s smbd[1036]: [2026/05/25 13:46:15.025254, 0] ../../source3/smbd/server.c:1741(main) May 25 13:46:15 volumio-fosiaudio-ht4s smbd[1036]: smbd version 4.17.8-Raspbian started. May 25 13:46:15 volumio-fosiaudio-ht4s smbd[1036]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: setdatetime-helper.service: Deactivated successfully. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: setdatetime-helper.service: Consumed 3.031s CPU time. May 25 13:46:15 volumio-fosiaudio-ht4s ntpd[898]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 25 13:46:15 volumio-fosiaudio-ht4s ntpd[898]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 25 13:46:15 volumio-fosiaudio-ht4s ntpd[898]: DNS: Pool taking: 192.46.211.253 May 25 13:46:15 volumio-fosiaudio-ht4s ntpd[898]: DNS: Pool taking: 223.178.214.252 May 25 13:46:15 volumio-fosiaudio-ht4s ntpd[898]: DNS: Pool taking: 103.136.36.100 May 25 13:46:15 volumio-fosiaudio-ht4s ntpd[898]: DNS: Pool taking: 172.236.180.15 May 25 13:46:15 volumio-fosiaudio-ht4s ntpd[898]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 25 13:46:15 volumio-fosiaudio-ht4s winbindd[983]: [2026/05/25 13:46:15.246235, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) May 25 13:46:15 volumio-fosiaudio-ht4s winbindd[983]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'VOLUMIO-FOSIAUDIO-HT4S' failed 'NT_STATUS_IO_TIMEOUT' May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 25 13:46:15 volumio-fosiaudio-ht4s nmbd[951]: [2026/05/25 13:46:15.294093, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) May 25 13:46:15 volumio-fosiaudio-ht4s nmbd[951]: register_name_response: Answer name <00> differs from question name <00>. May 25 13:46:15 volumio-fosiaudio-ht4s nmbd[951]: [2026/05/25 13:46:15.295347, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) May 25 13:46:15 volumio-fosiaudio-ht4s nmbd[951]: register_name_response: Answer name <03> differs from question name <03>. May 25 13:46:15 volumio-fosiaudio-ht4s nmbd[951]: [2026/05/25 13:46:15.296360, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) May 25 13:46:15 volumio-fosiaudio-ht4s nmbd[951]: register_name_response: Answer name <20> differs from question name <20>. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Started smbd.service - Samba SMB Daemon. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Reached target multi-user.target - Multi-User System. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Reached target graphical.target - Graphical Interface. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 25 13:46:15 volumio-fosiaudio-ht4s systemd[1]: Startup finished in 19.757s (kernel) + 22.001s (userspace) = 41.758s. May 25 13:46:16 volumio-fosiaudio-ht4s volumio-remote-updater[590]: [2026-05-25 13:46:16] [info] asio async_connect error: asio.system:111 (Connection refused) May 25 13:46:16 volumio-fosiaudio-ht4s volumio-remote-updater[590]: [2026-05-25 13:46:16] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 25 13:46:16 volumio-fosiaudio-ht4s volumio-remote-updater[590]: [2026-05-25 13:46:16] [error] handle_connect error: Connection refused May 25 13:46:17 volumio-fosiaudio-ht4s winbindd[1026]: [2026/05/25 13:46:17.711891, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) May 25 13:46:17 volumio-fosiaudio-ht4s winbindd[1026]: Could not write result May 25 13:46:19 volumio-fosiaudio-ht4s systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. May 25 13:46:19 volumio-fosiaudio-ht4s systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 13:46:19 volumio-fosiaudio-ht4s systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 13:46:19 volumio-fosiaudio-ht4s upmpdcli[1072]: Could not open config: /tmp/upmpdcli.conf May 25 13:46:19 volumio-fosiaudio-ht4s systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 25 13:46:19 volumio-fosiaudio-ht4s systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 25 13:46:20 volumio-fosiaudio-ht4s volumio[1000]: info: ------------------------------------------- May 25 13:46:20 volumio-fosiaudio-ht4s volumio[1000]: info: ----- Volumio3 ---- May 25 13:46:20 volumio-fosiaudio-ht4s volumio[1000]: info: ------------------------------------------- May 25 13:46:20 volumio-fosiaudio-ht4s volumio[1000]: info: ----- System startup ---- May 25 13:46:20 volumio-fosiaudio-ht4s volumio[1000]: info: ------------------------------------------- May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: MYVOLUMIO Environment detected May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Plugin folders cleanup May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning into folder /volumio/app/plugins/ May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning category audio_interface May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning category miscellanea May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning category music_service May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning category plugins.json May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning category system_controller May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning category user_interface May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning into folder /data/plugins/ May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Scanning category music_service May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Plugin folders cleanup completed May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: ------------------------------------------- May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: ----- Core plugins startup ---- May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: ------------------------------------------- May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugins from folder /volumio/app/plugins/ May 25 13:46:22 volumio-fosiaudio-ht4s volumio[1000]: info: Adding plugin upnp to MyMusic Plugins May 25 13:46:23 volumio-fosiaudio-ht4s volumio[1000]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 13:46:23 volumio-fosiaudio-ht4s volumio[1000]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 13:46:23 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugins from folder /data/plugins/ May 25 13:46:23 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "system"... May 25 13:46:23 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "appearance"... May 25 13:46:26 volumio-fosiaudio-ht4s volumio-remote-updater[590]: [2026-05-25 13:46:26] [connect] Successful connection May 25 13:46:26 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "network"... May 25 13:46:26 volumio-fosiaudio-ht4s volumio[1000]: info: Refreshing Cached IP Addresses May 25 13:46:26 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "services"... May 25 13:46:26 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "volumio5onboarding"... May 25 13:46:26 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "alsa_controller"... May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1086]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1088]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1094]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1086]: pam_unix(sudo:session): session closed for user root May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1094]: pam_unix(sudo:session): session closed for user root May 25 13:46:26 volumio-fosiaudio-ht4s sudo[1088]: pam_unix(sudo:session): session closed for user root May 25 13:46:26 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 13:46:26 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "wizard"... May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "networkfs"... May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Starting Udev Watcher for removable devices May 25 13:46:27 volumio-fosiaudio-ht4s sudo[1126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=amit,password=Suzuki@3886,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.208/Backup/Downloads/Music /mnt/NAS/QNAP May 25 13:46:27 volumio-fosiaudio-ht4s sudo[1126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Ignoring mount for partition: boot May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Ignoring mount for partition: volumio May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Ignoring mount for partition: volumio_data May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "volumio_command_line_client"... May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "upnp"... May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: [1779696987266] Starting Upmpd Daemon May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "my_music"... May 25 13:46:27 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "mpd"... May 25 13:46:27 volumio-fosiaudio-ht4s kernel: netfs: FS-Cache loaded May 25 13:46:27 volumio-fosiaudio-ht4s kernel: Key type cifs.spnego registered May 25 13:46:27 volumio-fosiaudio-ht4s kernel: Key type cifs.idmap registered May 25 13:46:27 volumio-fosiaudio-ht4s 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. May 25 13:46:27 volumio-fosiaudio-ht4s kernel: CIFS: Attempting to mount //192.168.0.208/Backup/Downloads/Music May 25 13:46:28 volumio-fosiaudio-ht4s kernel: cryptd: max_cpu_qlen set to 1000 May 25 13:46:28 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "upnp_browser"... May 25 13:46:28 volumio-fosiaudio-ht4s sudo[1126]: pam_unix(sudo:session): session closed for user root May 25 13:46:29 volumio-fosiaudio-ht4s systemd[1]: systemd-fsckd.service: Deactivated successfully. May 25 13:46:31 volumio-fosiaudio-ht4s volumio[1000]: info: Starting UPNP Browser May 25 13:46:31 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "alarm-clock"... May 25 13:46:31 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "airplay_emulation"... May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: Starting Shairport Sync May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "last_100"... May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "webradio"... May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "i2s_dacs"... May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "volumiodiscovery"... May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: *** WARNING *** For more information see May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 13:46:32 volumio-fosiaudio-ht4s node[1000]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: *** WARNING *** For more information see May 25 13:46:32 volumio-fosiaudio-ht4s node[1000]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 13:46:32 volumio-fosiaudio-ht4s node[1000]: *** WARNING *** For more information see May 25 13:46:32 volumio-fosiaudio-ht4s node[1000]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 13:46:32 volumio-fosiaudio-ht4s node[1000]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 13:46:32 volumio-fosiaudio-ht4s node[1000]: *** WARNING *** For more information see May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: Discovery: Started advertising with name: Volumio-Fosiaudio-HT4S May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 13:46:32 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "youtube2"... May 25 13:46:34 volumio-fosiaudio-ht4s systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 25 13:46:34 volumio-fosiaudio-ht4s systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 25 13:46:34 volumio-fosiaudio-ht4s systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 13:46:34 volumio-fosiaudio-ht4s systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 13:46:34 volumio-fosiaudio-ht4s upmpdcli[1188]: Could not open config: /tmp/upmpdcli.conf May 25 13:46:34 volumio-fosiaudio-ht4s systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 25 13:46:34 volumio-fosiaudio-ht4s systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 25 13:46:35 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "ytcr"... May 25 13:46:37 volumio-fosiaudio-ht4s systemd[1]: setdatetime-helper.service: Deactivated successfully. May 25 13:46:37 volumio-fosiaudio-ht4s systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 25 13:46:37 volumio-fosiaudio-ht4s systemd[1]: setdatetime-helper.service: Consumed 2.147s CPU time. May 25 13:46:37 volumio-fosiaudio-ht4s systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... May 25 13:46:37 volumio-fosiaudio-ht4s systemd[1]: e2scrub_all.service: Deactivated successfully. May 25 13:46:37 volumio-fosiaudio-ht4s systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. May 25 13:46:38 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "ytmusic"... May 25 13:46:41 volumio-fosiaudio-ht4s volumio-remote-updater[590]: [2026-05-25 13:46:41] [connect] Successful connection May 25 13:46:41 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "outputs"... May 25 13:46:41 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "albumart"... May 25 13:46:41 volumio-fosiaudio-ht4s volumio[1000]: info: Plugin example_plugin is not enabled May 25 13:46:41 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "inputs"... May 25 13:46:41 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "updater_comm"... May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: Plugin mpdemulation is not enabled May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "rest_api"... May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: Loading plugin "websocket"... May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: Starting Socket.io Server version 1.7.4 May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: Loading i18n strings for locale en May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: Updating browse sources language May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::initPlayerControls May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: Express server listening on port 3000 May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: [Metrics] WebUI: 24s 827.76ms May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::resetVolumioState May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::getcurrentVolume May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume May 25 13:46:43 volumio-fosiaudio-ht4s volumio[1215]: Forking 3 albumart workers May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1233]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 13:46:44 volumio-fosiaudio-ht4s volumio[1000]: info: Cannot read play queue from file May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1230]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1230]: pam_unix(sudo:session): session closed for user root May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1233]: pam_unix(sudo:session): session closed for user root May 25 13:46:44 volumio-fosiaudio-ht4s volumio[1000]: info: Volumio Network Manager: Network status updated: 1 May 25 13:46:44 volumio-fosiaudio-ht4s volumio[1000]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 25 13:46:44 volumio-fosiaudio-ht4s volumio[1000]: wlan0 Interface doesn't support scanning. May 25 13:46:44 volumio-fosiaudio-ht4s volumio[1000]: info: Cannot use regular scanning, forcing with ap-force May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1267]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:44 volumio-fosiaudio-ht4s sudo[1267]: pam_unix(sudo:session): session closed for user root May 25 13:46:44 volumio-fosiaudio-ht4s volumio[1000]: command failed: No such device (-19) May 25 13:46:44 volumio-fosiaudio-ht4s volumio[1000]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 25 13:46:44 volumio-fosiaudio-ht4s volumio[1000]: command failed: No such device (-19) May 25 13:46:45 volumio-fosiaudio-ht4s volumio[1000]: info: VolumeController:: Volume=79 Mute =false May 25 13:46:45 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::pushState May 25 13:46:45 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:46:45 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 13:46:45 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioPushState May 25 13:46:45 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::updateTrackBlock May 25 13:46:45 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrackBlock May 25 13:46:45 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume May 25 13:46:45 volumio-fosiaudio-ht4s volumio-remote-updater[590]: [2026-05-25 13:46:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779697001 101 May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: 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 May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::setRepeat null single undefined May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::pushState May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioPushState May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::setRandom null May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::pushState May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioPushState May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: Setting Device type: Raspberry PI May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: Completed loading Core Plugins May 25 13:46:46 volumio-fosiaudio-ht4s volumio[1000]: info: Preparing to generate the ALSA configuration file May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: VolumeController:: Volume=79 Mute =false May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::pushState May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioPushState May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: Discovery: adding 50f0182b-af6d-4bef-b176-b82e9cb42f02 May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: Discovery: Found device Volumio-Fosiaudio-HT4S May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioGetState May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:46:47 volumio-fosiaudio-ht4s sudo[1299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 25 13:46:47 volumio-fosiaudio-ht4s sudo[1299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: Asound.conf file unchanged, so no further update is needed May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: Output device has changed, restarting MPD May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: Output device has changed, restarting Shairport Sync May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: ___________ START PLUGINS ___________ May 25 13:46:47 volumio-fosiaudio-ht4s sudo[1302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 13:46:47 volumio-fosiaudio-ht4s sudo[1302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:47 volumio-fosiaudio-ht4s sudo[1302]: pam_unix(sudo:session): session closed for user root May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: ControllerMpd::onStart: Initializing MPD May 25 13:46:47 volumio-fosiaudio-ht4s volumio[1000]: info: Creating MPD Configuration file May 25 13:46:47 volumio-fosiaudio-ht4s sudo[1304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:48 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 13:46:48 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 13:46:48 volumio-fosiaudio-ht4s volumio[1000]: info: [1779697008108] CoreMusicLibrary::Adding element Media Servers May 25 13:46:48 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1313]: pam_unix(sudo:session): session closed for user root May 25 13:46:48 volumio-fosiaudio-ht4s volumio[1000]: info: UPNP Browser: Client initialized successfully May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: Starting mpd.service - Music Player Daemon... May 25 13:46:48 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:48 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 25 13:46:48 volumio-fosiaudio-ht4s sudo[1311]: pam_unix(sudo:session): session closed for user root May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: mpd.service: Deactivated successfully. May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: mpd.socket: Deactivated successfully. May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 13:46:48 volumio-fosiaudio-ht4s systemd[1]: Starting mpd.service - Music Player Daemon... May 25 13:46:49 volumio-fosiaudio-ht4s systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 25 13:46:49 volumio-fosiaudio-ht4s systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 25 13:46:49 volumio-fosiaudio-ht4s systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 25 13:46:49 volumio-fosiaudio-ht4s sudo[1299]: pam_unix(sudo:session): session closed for user root May 25 13:46:50 volumio-fosiaudio-ht4s sudo[1328]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 13:46:50 volumio-fosiaudio-ht4s sudo[1328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 13:46:50 volumio-fosiaudio-ht4s sudo[1330]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 25 13:46:50 volumio-fosiaudio-ht4s sudo[1328]: pam_unix(sudo:session): session closed for user root May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: [1779697011426] CoreMusicLibrary::Adding element Last_100 May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: [1779697011434] CoreMusicLibrary::Adding element Webradio May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 13:46:51 volumio-fosiaudio-ht4s volumio[1000]: info: Initializing BBC Radios May 25 13:46:53 volumio-fosiaudio-ht4s volumio[1232]: Starting albumart workers May 25 13:46:55 volumio-fosiaudio-ht4s volumio[1235]: Starting albumart workers May 25 13:46:57 volumio-fosiaudio-ht4s volumio[1234]: Starting albumart workers May 25 13:46:58 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 13:46:58 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:46:59 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 13:46:59 volumio-fosiaudio-ht4s volumio[1000]: info: [1779697019031] CoreMusicLibrary::Adding element YouTube2 May 25 13:46:59 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 13:46:59 volumio-fosiaudio-ht4s volumio[1000]: Cannot find translation for source YouTube2 May 25 13:46:59 volumio-fosiaudio-ht4s volumio[1000]: info: [ytcr] Data store TTL expired - clearing it... May 25 13:46:59 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:46:59.781+05:30 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 25 13:47:00 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 13:47:00 volumio-fosiaudio-ht4s volumio[1000]: info: [1779697020017] CoreMusicLibrary::Adding element YouTube Music May 25 13:47:00 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 13:47:00 volumio-fosiaudio-ht4s volumio[1000]: Cannot find translation for source YouTube2 May 25 13:47:00 volumio-fosiaudio-ht4s volumio[1000]: Cannot find translation for source YouTube Music May 25 13:47:00 volumio-fosiaudio-ht4s volumio[1000]: info: Volumio Calling Home May 25 13:47:04 volumio-fosiaudio-ht4s volumio[1000]: info: Discovery: this is already registered, 50f0182b-af6d-4bef-b176-b82e9cb42f02 May 25 13:47:04 volumio-fosiaudio-ht4s volumio[1000]: info: Discovery: Found device Volumio-Fosiaudio-HT4S May 25 13:47:04 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioGetState May 25 13:47:04 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:47:04 volumio-fosiaudio-ht4s volumio[1000]: info: MPD Permissions set May 25 13:47:04 volumio-fosiaudio-ht4s volumio[1000]: info: MPD Permissions set May 25 13:47:04 volumio-fosiaudio-ht4s volumio[1000]: info: Upmpdcli Daemon Started May 25 13:47:06 volumio-fosiaudio-ht4s volumio[1000]: 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: 2 May 25 13:47:06 volumio-fosiaudio-ht4s dbus-daemon[578]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.12' (uid=0 pid=1322 comm="/usr/bin/volumio5-onboarding") May 25 13:47:06 volumio-fosiaudio-ht4s volumio[1000]: info: Volumio called home May 25 13:47:06 volumio-fosiaudio-ht4s systemd[1]: Starting bluetooth.service - Bluetooth service... May 25 13:47:07 volumio-fosiaudio-ht4s bluetoothd[1404]: Bluetooth daemon 5.83 May 25 13:47:07 volumio-fosiaudio-ht4s kernel: Bluetooth: Core ver 2.22 May 25 13:47:07 volumio-fosiaudio-ht4s kernel: NET: Registered PF_BLUETOOTH protocol family May 25 13:47:07 volumio-fosiaudio-ht4s kernel: Bluetooth: HCI device and connection manager initialized May 25 13:47:07 volumio-fosiaudio-ht4s kernel: Bluetooth: HCI socket layer initialized May 25 13:47:07 volumio-fosiaudio-ht4s kernel: Bluetooth: L2CAP socket layer initialized May 25 13:47:07 volumio-fosiaudio-ht4s kernel: Bluetooth: SCO socket layer initialized May 25 13:47:07 volumio-fosiaudio-ht4s dbus-daemon[578]: [system] Successfully activated service 'org.bluez' May 25 13:47:07 volumio-fosiaudio-ht4s systemd[1]: Started bluetooth.service - Bluetooth service. May 25 13:47:07 volumio-fosiaudio-ht4s bluetoothd[1404]: Starting SDP server May 25 13:47:07 volumio-fosiaudio-ht4s bluetoothd[1404]: Excluding (cli) sap May 25 13:47:07 volumio-fosiaudio-ht4s bluetoothd[1404]: Excluding (cli) scanparam May 25 13:47:07 volumio-fosiaudio-ht4s bluetoothd[1404]: Excluding (cli) midi May 25 13:47:07 volumio-fosiaudio-ht4s bluetoothd[1404]: Excluding (cli) battery May 25 13:47:07 volumio-fosiaudio-ht4s kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 25 13:47:07 volumio-fosiaudio-ht4s kernel: Bluetooth: BNEP filters: protocol multicast May 25 13:47:07 volumio-fosiaudio-ht4s kernel: Bluetooth: BNEP socket layer initialized May 25 13:47:07 volumio-fosiaudio-ht4s bluetoothd[1404]: Bluetooth management interface 1.23 initialized May 25 13:47:07 volumio-fosiaudio-ht4s dbus-daemon[578]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.13' (uid=0 pid=1404 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") May 25 13:47:07 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:07.754+05:30 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" May 25 13:47:07 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:07.759+05:30 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" May 25 13:47:07 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:07.767+05:30 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" May 25 13:47:07 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:07.773+05:30 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" May 25 13:47:07 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:07.785+05:30 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" May 25 13:47:07 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:07.785+05:30 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" May 25 13:47:07 volumio-fosiaudio-ht4s systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: 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: 2 May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 25 13:47:08 volumio-fosiaudio-ht4s dbus-daemon[578]: [system] Successfully activated service 'org.freedesktop.hostname1' May 25 13:47:08 volumio-fosiaudio-ht4s systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:08 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: Received Get System Info May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: Discovery: Getting this device information May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioGetState May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:47:09 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 13:47:09 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:09.579+05:30 level=INFO msg="system info for d44a73c04453133d24591fb63d4f6c91" deviceName=Volumio-Fosiaudio-HT4S deviceVariant=volumio deviceModel= softwareVersion=4.119 May 25 13:47:09 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:09.646+05:30 level=INFO msg="bootstrapping state" hasInternet=true May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: Received Get System Info May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: Discovery: Getting this device information May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioGetState May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 13:47:10 volumio-fosiaudio-ht4s volumio-remote-updater[590]: Test mode disabled May 25 13:47:10 volumio-fosiaudio-ht4s volumio-remote-updater[590]: Alpha mode disabled May 25 13:47:10 volumio-fosiaudio-ht4s volumio-remote-updater[590]: Alpha legacy test mode disabled May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: Starting Shairport Sync May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: Starting Shairport Sync May 25 13:47:10 volumio-fosiaudio-ht4s volumio[1000]: info: Starting Shairport Sync May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 13:47:11 volumio-fosiaudio-ht4s volumio[1000]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 25 13:47:11 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: shairport-sync.service: Deactivated successfully. May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: shairport-sync.service: Consumed 2.989s CPU time. May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 13:47:11 volumio-fosiaudio-ht4s mpd[1331]: 2026-05-25T13:47:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1441]: pam_unix(sudo:session): session closed for user root May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1439]: pam_unix(sudo:session): session closed for user root May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: shairport-sync.service: Deactivated successfully. May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 13:47:11 volumio-fosiaudio-ht4s systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 13:47:11 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 13:47:11 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:11.809+05:30 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" May 25 13:47:11 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:11.812+05:30 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" May 25 13:47:11 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:11.812+05:30 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" May 25 13:47:11 volumio-fosiaudio-ht4s sudo[1444]: pam_unix(sudo:session): session closed for user root May 25 13:47:11 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 13:47:11 volumio-fosiaudio-ht4s volumio[1000]: info: Shairport-Sync Started May 25 13:47:11 volumio-fosiaudio-ht4s volumio[1000]: Error adding Membership: Error: addMembership EINVAL May 25 13:47:11 volumio-fosiaudio-ht4s volumio[1000]: info: Shairport-Sync Started May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioGetState May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:47:12 volumio-fosiaudio-ht4s systemd[1]: Started mpd.service - Music Player Daemon. May 25 13:47:12 volumio-fosiaudio-ht4s sudo[1304]: pam_unix(sudo:session): session closed for user root May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: Shairport-Sync Started May 25 13:47:12 volumio-fosiaudio-ht4s sudo[1316]: pam_unix(sudo:session): session closed for user root May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: Received Get System Info May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: Discovery: Getting this device information May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioGetState May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 13:47:12 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 13:47:12 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:12.667+05:30 level=INFO msg="enabling local network discovery" May 25 13:47:13 volumio-fosiaudio-ht4s volumio[1000]: error: MPD error: The expression evaluated to a falsy value: May 25 13:47:13 volumio-fosiaudio-ht4s volumio[1000]: assert.ok(self.idling) May 25 13:47:13 volumio-fosiaudio-ht4s volumio[1000]: error: The expression evaluated to a falsy value: May 25 13:47:13 volumio-fosiaudio-ht4s volumio[1000]: assert.ok(self.idling) May 25 13:47:13 volumio-fosiaudio-ht4s volumio[1000]: error: updateQueue error: null May 25 13:47:13 volumio-fosiaudio-ht4s volumio[1000]: info: MPD running with PID1331 May 25 13:47:13 volumio-fosiaudio-ht4s volumio[1000]: ,establishing connection May 25 13:47:13 volumio-fosiaudio-ht4s volumio[1000]: error: updateQueue error: null May 25 13:47:13 volumio-fosiaudio-ht4s volumio5-onboarding[1322]: time=2026-05-25T13:47:13.648+05:30 level=INFO msg="service successfully established" component=discovery/localnet May 25 13:47:15 volumio-fosiaudio-ht4s volumio[1000]: info: [yt-cast-receiver] DIAL server listening on port 8098 May 25 13:47:15 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: VolumeController:: Volume=79 Mute =false May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioGetState May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: CoreStateMachine::pushState May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: CorePlayQueue::getTrack 0 May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumioPushState May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: Completed starting Core Plugins May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: ------------------------------------------- May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: ----- MyVolumio plugins startup ---- May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: ------------------------------------------- May 25 13:47:16 volumio-fosiaudio-ht4s volumio[1000]: info: [MyVolumio PluginManager] Fetching plans data.... May 25 13:47:19 volumio-fosiaudio-ht4s volumio[1000]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 25 13:47:31 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 13:47:31 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:47:31 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 13:47:36 volumio-fosiaudio-ht4s volumio[1000]: Upnp client error: Error: This socket has been ended by the other party May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: error: MyVolumio Plugin failed to start in a timely fashion May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: [Metrics] CommandRouter: 76s 632.57ms May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::volumiosetStartupVolume May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::Close All Modals sent May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: info: CoreCommandRouter::Close All Modals sent May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: at new NodeError (node:internal/errors:405:5) May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: at assert (node:internal/assert:14:11) May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: at internalConnectMultiple (node:net:1118:3) May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: at listOnTimeout (node:internal/timers:575:11) May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: at process.processTimers (node:internal/timers:514:7) { May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: code: 'ERR_INTERNAL_ASSERTION' May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: } May 25 13:47:37 volumio-fosiaudio-ht4s volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 13:47:38 volumio-fosiaudio-ht4s systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 25 13:47:42 volumio-fosiaudio-ht4s upmpdcli[1537]: writing RSA key May 25 13:47:44 volumio-fosiaudio-ht4s sudo[1543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 13:46' May 25 13:47:44 volumio-fosiaudio-ht4s sudo[1543]: 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"