Dec 22 14:00:32 volumio wireless.js[667]: WIRELESS.JS: Stopped aP Dec 22 14:00:32 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 22 14:00:32 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 22 14:00:32 volumio systemd[1]: setdatetime-helper.service: Consumed 1.989s CPU time. Dec 22 14:00:32 volumio sudo[1172]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 22 14:00:32 volumio sudo[1172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:32 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 22 14:00:32 volumio sudo[1172]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:32 volumio sudo[1177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 22 14:00:32 volumio sudo[1177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:32 volumio smbd[1160]: [2025/12/22 14:00:32.365751, 0] ../../source3/smbd/server.c:1741(main) Dec 22 14:00:32 volumio sudo[1177]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:32 volumio smbd[1160]: smbd version 4.17.8-Raspbian started. Dec 22 14:00:32 volumio smbd[1160]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 22 14:00:32 volumio sudo[1185]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 22 14:00:32 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:32 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:32 volumio winbindd[1141]: [2025/12/22 14:00:32.511798, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Dec 22 14:00:32 volumio winbindd[1141]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'VOLUMIO' failed 'NT_STATUS_IO_TIMEOUT' Dec 22 14:00:32 volumio sudo[1191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 22 14:00:32 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:32 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 22 14:00:33 volumio ntpd[1032]: IO: Listen normally on 3 eth0 192.168.1.70:123 Dec 22 14:00:33 volumio ntpd[1032]: IO: new interface(s) found: waking up resolver Dec 22 14:00:33 volumio ntpd[1032]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 22 14:00:33 volumio ntpd[1032]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 22 14:00:33 volumio ntpd[1032]: DNS: Pool skipping: 160.16.113.133 Dec 22 14:00:33 volumio ntpd[1032]: DNS: Pool skipping: 108.160.132.224 Dec 22 14:00:33 volumio ntpd[1032]: DNS: Pool skipping: 172.104.124.149 Dec 22 14:00:33 volumio ntpd[1032]: DNS: Pool skipping: 153.127.38.46 Dec 22 14:00:33 volumio ntpd[1032]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 22 14:00:34 volumio winbindd[1151]: [2025/12/22 14:00:34.189910, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) Dec 22 14:00:34 volumio winbindd[1151]: Could not write result Dec 22 14:00:34 volumio ntpd[1032]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 22 14:00:34 volumio ntpd[1032]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 22 14:00:34 volumio ntpd[1032]: DNS: Pool skipping: 162.159.200.1 Dec 22 14:00:34 volumio ntpd[1032]: DNS: Pool skipping: 45.77.20.103 Dec 22 14:00:34 volumio ntpd[1032]: DNS: Pool skipping: 160.16.113.133 Dec 22 14:00:34 volumio ntpd[1032]: DNS: Pool skipping: 5.104.87.198 Dec 22 14:00:34 volumio ntpd[1032]: DNS: Pool skipping: 2401:d560:0:105:1c00:daff:fe00:219 Dec 22 14:00:34 volumio ntpd[1032]: DNS: Pool skipping: 2a06:a880:3:bb1e::123 Dec 22 14:00:34 volumio ntpd[1032]: DNS: Pool skipping: 2606:4700:f1::1 Dec 22 14:00:34 volumio ntpd[1032]: DNS: Pool skipping: 2400:8902::2000:52ff:fee4:69d9 Dec 22 14:00:34 volumio ntpd[1032]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 22 14:00:35 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:35 volumio wireless.js[667]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: JP Dec 22 14:00:35 volumio sudo[1203]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 22 14:00:35 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:35 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:35 volumio sudo[1205]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Dec 22 14:00:35 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:35 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:35 volumio wireless.js[667]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: JP Dec 22 14:00:35 volumio ntpd[1032]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 22 14:00:35 volumio wireless.js[667]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 22 14:00:35 volumio ntpd[1032]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 22 14:00:35 volumio ntpd[1032]: DNS: Pool taking: 160.25.135.55 Dec 22 14:00:35 volumio ntpd[1032]: DNS: Pool taking: 172.105.192.74 Dec 22 14:00:35 volumio ntpd[1032]: DNS: Pool taking: 142.91.108.61 Dec 22 14:00:35 volumio ntpd[1032]: DNS: Pool taking: 129.250.35.250 Dec 22 14:00:35 volumio ntpd[1032]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 22 14:00:35 volumio wireless.js[667]: WIRELESS.JS: Notified systemd about wireless ready Dec 22 14:00:35 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 22 14:00:35 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 22 14:00:35 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 22 14:00:35 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Dec 22 14:00:35 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 22 14:00:35 volumio systemd[1]: Received SIGRTMIN+21 from PID 310 (plymouthd). Dec 22 14:00:35 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Dec 22 14:00:35 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Dec 22 14:00:35 volumio systemd[1]: Received SIGRTMIN+21 from PID 310 (n/a). Dec 22 14:00:35 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 22 14:00:35 volumio systemd[1]: Reached target getty.target - Login Prompts. Dec 22 14:00:35 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Dec 22 14:00:35 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Dec 22 14:00:35 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 22 14:00:35 volumio volumio-cpu-tweak[1215]: Setting RT Priority for mpd Dec 22 14:00:35 volumio volumio-cpu-tweak[1231]: pid 35's current scheduling policy: SCHED_OTHER Dec 22 14:00:35 volumio volumio-cpu-tweak[1231]: pid 35's current scheduling priority: 0 Dec 22 14:00:35 volumio volumio-cpu-tweak[1215]: Setting MPD Affinity Dec 22 14:00:35 volumio volumio-cpu-tweak[1232]: pid 3's current affinity mask: f Dec 22 14:00:35 volumio volumio-cpu-tweak[1215]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 22 14:00:35 volumio volumio-cpu-tweak[1215]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 22 14:00:35 volumio volumio-remote-updater[665]: [2025-12-22 14:00:35] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 22 14:00:35 volumio volumio-remote-updater[665]: [2025-12-22 14:00:35] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 22 14:00:35 volumio volumio-remote-updater[665]: [2025-12-22 14:00:35] [error] handle_connect error: Connection refused Dec 22 14:00:36 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 22 14:00:36 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 22 14:00:36 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 22 14:00:36 volumio systemd[1]: Startup finished in 14.337s (kernel) + 19.727s (userspace) = 34.065s. Dec 22 14:00:36 volumio ntpd[1032]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 22 14:00:36 volumio ntpd[1032]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 22 14:00:36 volumio ntpd[1032]: DNS: Pool taking: 43.229.16.149 Dec 22 14:00:36 volumio ntpd[1032]: DNS: Pool skipping: 162.159.200.1 Dec 22 14:00:36 volumio ntpd[1032]: DNS: Pool taking: 162.159.200.123 Dec 22 14:00:36 volumio ntpd[1032]: DNS: Pool skipping: 45.77.20.103 Dec 22 14:00:36 volumio ntpd[1032]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 22 14:00:39 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Dec 22 14:00:39 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 22 14:00:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 22 14:00:39 volumio upmpdcli[1237]: Could not open config: /tmp/upmpdcli.conf Dec 22 14:00:39 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 22 14:00:39 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 22 14:00:39 volumio volumio[1213]: info: ------------------------------------------- Dec 22 14:00:39 volumio volumio[1213]: info: ----- Volumio3 ---- Dec 22 14:00:39 volumio volumio[1213]: info: ------------------------------------------- Dec 22 14:00:39 volumio volumio[1213]: info: ----- System startup ---- Dec 22 14:00:39 volumio volumio[1213]: info: ------------------------------------------- Dec 22 14:00:41 volumio volumio[1213]: info: MYVOLUMIO Environment detected Dec 22 14:00:42 volumio ntpd[1032]: CLOCK: time stepped by 0.769296 Dec 22 14:00:42 volumio ntpd[1032]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 22 14:00:42 volumio volumio[1213]: info: Plugin folders cleanup Dec 22 14:00:42 volumio volumio[1213]: info: Scanning into folder /volumio/app/plugins/ Dec 22 14:00:42 volumio volumio[1213]: info: Scanning category audio_interface Dec 22 14:00:42 volumio volumio[1213]: info: Scanning category miscellanea Dec 22 14:00:42 volumio volumio[1213]: info: Scanning category music_service Dec 22 14:00:42 volumio volumio[1213]: info: Scanning category plugins.json Dec 22 14:00:42 volumio volumio[1213]: info: Scanning category system_controller Dec 22 14:00:42 volumio volumio[1213]: info: Scanning category user_interface Dec 22 14:00:42 volumio volumio[1213]: info: Scanning into folder /data/plugins/ Dec 22 14:00:42 volumio volumio[1213]: info: Plugin folders cleanup completed Dec 22 14:00:42 volumio volumio[1213]: info: ------------------------------------------- Dec 22 14:00:42 volumio volumio[1213]: info: ----- Core plugins startup ---- Dec 22 14:00:42 volumio volumio[1213]: info: ------------------------------------------- Dec 22 14:00:42 volumio volumio[1213]: info: Loading plugins from folder /volumio/app/plugins/ Dec 22 14:00:42 volumio volumio[1213]: info: Adding plugin upnp to MyMusic Plugins Dec 22 14:00:42 volumio volumio[1213]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 22 14:00:42 volumio volumio[1213]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 22 14:00:42 volumio volumio[1213]: info: Loading plugins from folder /data/plugins/ Dec 22 14:00:42 volumio volumio[1213]: info: Loading plugin "system"... Dec 22 14:00:42 volumio volumio[1213]: info: Loading plugin "appearance"... Dec 22 14:00:44 volumio volumio[1213]: info: Loading plugin "network"... Dec 22 14:00:44 volumio volumio[1213]: info: Refreshing Cached IP Addresses Dec 22 14:00:44 volumio sudo[1252]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 14:00:44 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:44 volumio sudo[1253]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 14:00:44 volumio sudo[1253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:44 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:44 volumio volumio[1213]: info: Loading plugin "services"... Dec 22 14:00:44 volumio volumio[1213]: info: Loading plugin "alsa_controller"... Dec 22 14:00:44 volumio sudo[1253]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:44 volumio sudo[1257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 14:00:44 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:44 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 14:00:44 volumio volumio[1213]: info: Loading plugin "wizard"... Dec 22 14:00:44 volumio volumio[1213]: info: Loading plugin "networkfs"... Dec 22 14:00:44 volumio volumio[1213]: info: Starting Udev Watcher for removable devices Dec 22 14:00:44 volumio volumio[1213]: info: Ignoring mount for partition: boot Dec 22 14:00:44 volumio volumio[1213]: info: Ignoring mount for partition: volumio Dec 22 14:00:44 volumio volumio[1213]: info: Ignoring mount for partition: volumio_data Dec 22 14:00:44 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 14:00:44 volumio volumio[1213]: info: Loading plugin "volumio_command_line_client"... Dec 22 14:00:44 volumio volumio[1213]: info: Loading plugin "upnp"... Dec 22 14:00:44 volumio volumio[1213]: info: [1766379644976] Starting Upmpd Daemon Dec 22 14:00:44 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 14:00:44 volumio volumio[1213]: info: Loading plugin "my_music"... Dec 22 14:00:44 volumio sudo[1282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=monchi,password=reik0san999,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/music /mnt/NAS/wanas Dec 22 14:00:44 volumio sudo[1282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:45 volumio volumio[1213]: info: Loading plugin "mpd"... Dec 22 14:00:45 volumio kernel: netfs: FS-Cache loaded Dec 22 14:00:45 volumio kernel: Key type cifs.spnego registered Dec 22 14:00:45 volumio kernel: Key type cifs.idmap registered Dec 22 14:00:45 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Dec 22 14:00:45 volumio kernel: CIFS: Attempting to mount //192.168.1.5/music Dec 22 14:00:45 volumio sudo[1282]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:46 volumio volumio[1213]: info: Loading plugin "upnp_browser"... Dec 22 14:00:46 volumio volumio-remote-updater[665]: [2025-12-22 14:00:46] [connect] Successful connection Dec 22 14:00:47 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:48 volumio volumio[1213]: info: Starting UPNP Browser Dec 22 14:00:48 volumio volumio[1213]: info: Loading plugin "alarm-clock"... Dec 22 14:00:48 volumio volumio[1213]: info: Loading plugin "airplay_emulation"... Dec 22 14:00:48 volumio volumio[1213]: info: Starting Shairport Sync Dec 22 14:00:48 volumio volumio[1213]: info: Loading plugin "last_100"... Dec 22 14:00:48 volumio volumio[1213]: info: Loading plugin "webradio"... Dec 22 14:00:49 volumio volumio[1213]: info: Loading plugin "i2s_dacs"... Dec 22 14:00:49 volumio volumio[1213]: info: Loading plugin "volumiodiscovery"... Dec 22 14:00:49 volumio volumio[1213]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 14:00:49 volumio volumio[1213]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 14:00:49 volumio volumio[1213]: *** WARNING *** For more information see Dec 22 14:00:49 volumio volumio[1213]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 14:00:49 volumio node[1213]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 22 14:00:49 volumio volumio[1213]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 14:00:49 volumio volumio[1213]: *** WARNING *** For more information see Dec 22 14:00:49 volumio node[1213]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 14:00:49 volumio node[1213]: *** WARNING *** For more information see Dec 22 14:00:49 volumio node[1213]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 22 14:00:49 volumio node[1213]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 22 14:00:49 volumio node[1213]: *** WARNING *** For more information see Dec 22 14:00:49 volumio volumio[1213]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 22 14:00:49 volumio volumio[1213]: info: Discovery: Started advertising with name: Volumio Dec 22 14:00:49 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 22 14:00:49 volumio volumio[1213]: info: Loading plugin "outputs"... Dec 22 14:00:49 volumio volumio[1213]: info: Loading plugin "albumart"... Dec 22 14:00:49 volumio volumio[1213]: info: Plugin example_plugin is not enabled Dec 22 14:00:49 volumio volumio[1213]: info: Loading plugin "inputs"... Dec 22 14:00:49 volumio volumio[1213]: info: Loading plugin "updater_comm"... Dec 22 14:00:49 volumio volumio[1213]: info: Plugin mpdemulation is not enabled Dec 22 14:00:49 volumio volumio[1213]: info: Loading plugin "rest_api"... Dec 22 14:00:49 volumio volumio[1213]: info: Loading plugin "websocket"... Dec 22 14:00:49 volumio volumio[1213]: info: Starting Socket.io Server version 1.7.4 Dec 22 14:00:49 volumio volumio[1213]: info: Loading i18n strings for locale ja Dec 22 14:00:49 volumio volumio[1213]: Updating browse sources language Dec 22 14:00:49 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::initPlayerControls Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:00:50 volumio volumio[1213]: Express server listening on port 3000 Dec 22 14:00:50 volumio volumio[1213]: [Metrics] WebUI: 11s 520.72ms Dec 22 14:00:50 volumio volumio[1321]: Forking 3 albumart workers Dec 22 14:00:50 volumio volumio[1213]: info: CoreStateMachine::resetVolumioState Dec 22 14:00:50 volumio volumio[1213]: info: CoreStateMachine::getcurrentVolume Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 14:00:50 volumio volumio[1213]: info: CoreStateMachine::pushState Dec 22 14:00:50 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:00:50 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Dec 22 14:00:50 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 22 14:00:50 volumio volumio[1213]: info: Cannot read play queue from file Dec 22 14:00:51 volumio volumio[1213]: info: Volumio Network Manager: Network status updated: 1 Dec 22 14:00:52 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 1 Dec 22 14:00:52 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 2 Dec 22 14:00:52 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:00:52 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 3 Dec 22 14:00:52 volumio volumio[1213]: info: CoreStateMachine::setRepeat null single undefined Dec 22 14:00:52 volumio volumio[1213]: info: CoreStateMachine::pushState Dec 22 14:00:52 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:00:52 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Dec 22 14:00:52 volumio volumio[1213]: info: CoreStateMachine::setRandom null Dec 22 14:00:52 volumio volumio[1213]: info: CoreStateMachine::pushState Dec 22 14:00:52 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:00:52 volumio volumio[1213]: info: CoreCommandRouter::volumioPushState Dec 22 14:00:53 volumio volumio[1213]: info: Setting Device type: Raspberry PI Dec 22 14:00:53 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 22 14:00:53 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 5 Dec 22 14:00:53 volumio volumio[1213]: info: Completed loading Core Plugins Dec 22 14:00:53 volumio volumio[1213]: info: Preparing to generate the ALSA configuration file Dec 22 14:00:53 volumio volumio[1213]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 22 14:00:53 volumio volumio[1213]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Dec 22 14:00:53 volumio volumio[1213]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 22 14:00:53 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 22 14:00:53 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 7 Dec 22 14:00:53 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 8 Dec 22 14:00:53 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 9 Dec 22 14:00:53 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 10 Dec 22 14:00:53 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 11 Dec 22 14:00:54 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:00:54 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:00:54 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 12 Dec 22 14:00:54 volumio volumio[1213]: info: Discovery: adding 60b35a74-f4bb-4e55-8c36-f38fbf974c03 Dec 22 14:00:54 volumio volumio[1213]: info: Discovery: Found device Volumio Dec 22 14:00:54 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:00:54 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:00:54 volumio volumio[1213]: info: Asound.conf file unchanged, so no further update is needed Dec 22 14:00:54 volumio volumio[1213]: info: Output device has changed, restarting MPD Dec 22 14:00:54 volumio volumio[1213]: info: Output device has changed, restarting Shairport Sync Dec 22 14:00:54 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:54 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:00:54 volumio sudo[1374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 14:00:54 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:54 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:54 volumio sudo[1377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 14:00:54 volumio volumio[1213]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 14:00:54 volumio volumio[1213]: info: ___________ START PLUGINS ___________ Dec 22 14:00:54 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:54 volumio volumio[1213]: info: ControllerMpd::onStart: Initializing MPD Dec 22 14:00:54 volumio volumio[1213]: info: Creating MPD Configuration file Dec 22 14:00:55 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 22 14:00:55 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:00:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 14:00:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 14:00:55 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 22 14:00:55 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 22 14:00:55 volumio volumio[1213]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 14:00:55 volumio volumio[1213]: info: [1766379655319] CoreMusicLibrary::Adding element DLNAサーバー Dec 22 14:00:55 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:00:55 volumio sudo[1397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 14:00:55 volumio upmpdcli[1402]: Could not open config: /tmp/upmpdcli.conf Dec 22 14:00:55 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 22 14:00:55 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 22 14:00:55 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:55 volumio volumio[1213]: info: UPNP Browser: Client initialized successfully Dec 22 14:00:55 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:55 volumio sudo[1401]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 14:00:55 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 14:00:55 volumio sudo[1405]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 22 14:00:55 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:55 volumio sudo[1400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 14:00:55 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:00:55 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:55 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:00:55 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 14:00:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 14:00:55 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 14:00:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 14:00:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 14:00:56 volumio volumio[1213]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 14:00:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:00:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:00:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 14:00:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 14:00:56 volumio sudo[1415]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 14:00:56 volumio sudo[1415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 14:00:56 volumio sudo[1418]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 22 14:00:56 volumio sudo[1415]: pam_unix(sudo:session): session closed for user root Dec 22 14:00:56 volumio volumio[1213]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 14:00:56 volumio volumio[1213]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 14:00:56 volumio volumio[1213]: info: [1766379656450] CoreMusicLibrary::Adding element Last_100 Dec 22 14:00:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:00:56 volumio volumio[1213]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 14:00:56 volumio volumio[1213]: info: [1766379656454] CoreMusicLibrary::Adding element Webradio Dec 22 14:00:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:00:56 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 14:00:56 volumio volumio[1213]: info: Initializing BBC Radios Dec 22 14:00:57 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 22 14:00:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 14:00:57 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:00:57 volumio volumio[1213]: info: Volumio Calling Home Dec 22 14:01:01 volumio volumio-remote-updater[665]: [2025-12-22 14:01:01] [connect] Successful connection Dec 22 14:01:03 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 13 Dec 22 14:01:04 volumio volumio[1213]: info: Received Get System Info Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 14:01:04 volumio volumio[1213]: info: Discovery: Getting this device information Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:01:04 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:01:04 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:01:04 volumio mpd[1419]: 2025-12-22T14:01:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:01:04 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:01:04 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 14:01:04 volumio volumio[1213]: info: Received Get System Info Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 14:01:04 volumio volumio[1213]: info: Discovery: Getting this device information Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:01:04 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:01:04 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:01:04 volumio volumio[1213]: info: Listing playlists Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::volumioGetQueue Dec 22 14:01:04 volumio volumio[1213]: info: CoreStateMachine::getQueue Dec 22 14:01:04 volumio volumio[1213]: info: CorePlayQueue::getQueue Dec 22 14:01:04 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 22 14:01:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 14:01:04 volumio volumio[1334]: Starting albumart workers Dec 22 14:01:04 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:04 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:04 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:05 volumio volumio[1335]: Starting albumart workers Dec 22 14:01:05 volumio sudo[1458]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 14:01:05 volumio sudo[1458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:01:05 volumio sudo[1458]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:05 volumio sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 14:01:05 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:01:05 volumio volumio[1336]: Starting albumart workers Dec 22 14:01:05 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 14 Dec 22 14:01:05 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 15 Dec 22 14:01:05 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:05 volumio volumio[1213]: info: Discovery: this is already registered, 60b35a74-f4bb-4e55-8c36-f38fbf974c03 Dec 22 14:01:05 volumio volumio[1213]: info: Discovery: Found device Volumio Dec 22 14:01:05 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 22 14:01:05 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 22 14:01:06 volumio volumio[1213]: info: Completed starting Core Plugins Dec 22 14:01:06 volumio volumio[1213]: info: ------------------------------------------- Dec 22 14:01:06 volumio volumio[1213]: info: ----- MyVolumio plugins startup ---- Dec 22 14:01:06 volumio volumio[1213]: info: ------------------------------------------- Dec 22 14:01:06 volumio volumio[1213]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 22 14:01:06 volumio volumio[1213]: info: MPD Permissions set Dec 22 14:01:06 volumio volumio[1213]: info: MPD Permissions set Dec 22 14:01:06 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 16 Dec 22 14:01:06 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 17 Dec 22 14:01:07 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 18 Dec 22 14:01:07 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 19 Dec 22 14:01:08 volumio volumio[1213]: error: MPD error: The expression evaluated to a falsy value: Dec 22 14:01:08 volumio volumio[1213]: assert.ok(self.idling) Dec 22 14:01:08 volumio volumio[1213]: error: The expression evaluated to a falsy value: Dec 22 14:01:08 volumio volumio[1213]: assert.ok(self.idling) Dec 22 14:01:08 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 20 Dec 22 14:01:08 volumio volumio[1213]: info: MPD running with PID1419 Dec 22 14:01:08 volumio volumio[1213]: ,establishing connection Dec 22 14:01:08 volumio volumio[1213]: info: Volumio called home Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 14:01:08 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 14:01:09 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 22 14:01:09 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 22 14:01:09 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 21 Dec 22 14:01:09 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 22 Dec 22 14:01:10 volumio volumio[1213]: error: updateQueue error: null Dec 22 14:01:10 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 22 14:01:10 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 22 14:01:10 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 23 Dec 22 14:01:10 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 22 14:01:10 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 24 Dec 22 14:01:10 volumio upmpdcli[1511]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.70 port 49152 IPV6 port 49152 Dec 22 14:01:10 volumio sudo[1521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 14:01:10 volumio sudo[1521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:01:10 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:10 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:10 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:10 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 14:01:11 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:11 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:11 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:11 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:11 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:11 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 14:01:11 volumio mpd_monitor.sh[1541]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 14:01:11 volumio sudo[1521]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:11 volumio sudo[1539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 14:01:11 volumio sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:01:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 14:01:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 14:01:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 14:01:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 14:01:11 volumio sudo[1539]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:11 volumio mpd_monitor.sh[1546]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 14:01:11 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 25 Dec 22 14:01:11 volumio volumio[1213]: error: updateQueue error: null Dec 22 14:01:11 volumio volumio[1213]: verbose: New Socket.io Connection to 192.168.1.70 from 192.168.0.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 26 Dec 22 14:01:12 volumio volumio[1213]: info: Successfully started MPD Monitor Dec 22 14:01:12 volumio volumio[1213]: info: Successfully started MPD Monitor Dec 22 14:01:12 volumio volumio[1213]: info: Starting Shairport Sync Dec 22 14:01:12 volumio volumio[1213]: info: Starting Shairport Sync Dec 22 14:01:12 volumio volumio[1213]: info: Starting Shairport Sync Dec 22 14:01:12 volumio sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 22 14:01:12 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:01:12 volumio sudo[1555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 14:01:12 volumio sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 14:01:12 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:01:12 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:01:12 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:12 volumio sudo[1558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 14:01:12 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:01:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 14:01:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 14:01:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 14:01:12 volumio systemd[1]: shairport-sync.service: Consumed 2.001s CPU time. Dec 22 14:01:12 volumio volumio[1213]: info: Upmpdcli Daemon Started Dec 22 14:01:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 14:01:12 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 14:01:13 volumio volumio[1213]: info: Shairport-Sync Started Dec 22 14:01:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 14:01:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 14:01:13 volumio volumio[1213]: Error adding Membership: Error: addMembership EINVAL Dec 22 14:01:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 14:01:13 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:13 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Dec 22 14:01:13 volumio volumio[1213]: info: Shairport-Sync Started Dec 22 14:01:13 volumio volumio[1213]: info: Shairport-Sync Started Dec 22 14:01:13 volumio volumio[1213]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 22 14:01:15 volumio volumio[1213]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Dec 22 14:01:16 volumio volumio-remote-updater[665]: [2025-12-22 14:01:16] [connect] Successful connection Dec 22 14:01:23 volumio volumio[1213]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:01:24 volumio volumio[1213]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 22 14:01:24 volumio volumio[1213]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 22 14:01:24 volumio volumio[1213]: at new NodeError (node:internal/errors:405:5) Dec 22 14:01:24 volumio volumio[1213]: at assert (node:internal/assert:14:11) Dec 22 14:01:24 volumio volumio[1213]: at internalConnectMultiple (node:net:1118:3) Dec 22 14:01:24 volumio volumio[1213]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 22 14:01:24 volumio volumio[1213]: at listOnTimeout (node:internal/timers:575:11) Dec 22 14:01:24 volumio volumio[1213]: at process.processTimers (node:internal/timers:514:7) { Dec 22 14:01:24 volumio volumio[1213]: code: 'ERR_INTERNAL_ASSERTION' Dec 22 14:01:24 volumio volumio[1213]: } Dec 22 14:01:24 volumio volumio[1213]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:01:31 volumio volumio-remote-updater[665]: [2025-12-22 14:01:31] [connect] Successful connection Dec 22 14:01:33 volumio sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 14:00' Dec 22 14:01:33 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"