Apr 11 22:40:26 volumio ntpd[920]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 11 22:40:26 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 11 22:40:26 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 11 22:40:26 volumio systemd[1]: setdatetime-helper.service: Consumed 1.826s CPU time. Apr 11 22:40:26 volumio ntpd[920]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Apr 11 22:40:26 volumio ntpd[920]: DNS: Pool skipping: 203.32.26.46 Apr 11 22:40:26 volumio ntpd[920]: DNS: Pool skipping: 121.134.215.104 Apr 11 22:40:26 volumio ntpd[920]: DNS: Pool skipping: 158.247.202.103 Apr 11 22:40:26 volumio ntpd[920]: DNS: Pool skipping: 2401:c080:1c00:24a1:5400:5ff:fe04:720 Apr 11 22:40:26 volumio ntpd[920]: DNS: Pool skipping: 240b:400d:3:3300:aeda:71da:9779:d8f1 Apr 11 22:40:26 volumio ntpd[920]: DNS: Pool skipping: 2001:678:8::123 Apr 11 22:40:26 volumio ntpd[920]: DNS: Pool skipping: 240b:400d:3:3300:aeda:71da:9779:d4f1 Apr 11 22:40:26 volumio ntpd[920]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Apr 11 22:40:26 volumio smbd[1162]: [2026/04/11 22:40:26.312073, 0] ../../source3/smbd/server.c:1741(main) Apr 11 22:40:26 volumio smbd[1162]: smbd version 4.17.8-Raspbian started. Apr 11 22:40:26 volumio smbd[1162]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Apr 11 22:40:26 volumio sudo[1186]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 11 22:40:26 volumio sudo[1186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 22:40:26 volumio sudo[1186]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:26 volumio sudo[1190]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 11 22:40:26 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 22:40:27 volumio ntpd[920]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 11 22:40:27 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:27 volumio ntpd[920]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Apr 11 22:40:27 volumio ntpd[920]: DNS: Pool taking: 194.0.5.123 Apr 11 22:40:27 volumio ntpd[920]: DNS: Pool taking: 121.174.142.82 Apr 11 22:40:27 volumio ntpd[920]: DNS: Pool taking: 175.195.167.194 Apr 11 22:40:27 volumio ntpd[920]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Apr 11 22:40:27 volumio wireless.js[720]: WIRELESS.JS - INFO: Cleaning previous... Apr 11 22:40:27 volumio sudo[1194]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 11 22:40:27 volumio sudo[1194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 22:40:27 volumio sudo[1194]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:27 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 11 22:40:27 volumio wireless.js[720]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Apr 11 22:40:27 volumio wireless.js[720]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 18ms Apr 11 22:40:27 volumio wireless.js[720]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:a7:e6:e1) Apr 11 22:40:27 volumio sudo[1202]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 11 22:40:27 volumio sudo[1202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:40:27 volumio sudo[1202]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:27 volumio sudo[1210]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 11 22:40:27 volumio sudo[1210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:40:27 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Apr 11 22:40:28 volumio ntpd[920]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 11 22:40:28 volumio ntpd[920]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Apr 11 22:40:28 volumio ntpd[920]: DNS: Pool skipping: 203.32.26.46 Apr 11 22:40:28 volumio ntpd[920]: DNS: Pool skipping: 158.247.202.103 Apr 11 22:40:28 volumio ntpd[920]: DNS: Pool skipping: 121.174.142.82 Apr 11 22:40:28 volumio ntpd[920]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Apr 11 22:40:28 volumio sudo[1210]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:28 volumio wireless.js[720]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: KR Apr 11 22:40:28 volumio sudo[1219]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set KR Apr 11 22:40:28 volumio sudo[1219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:40:28 volumio sudo[1219]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:28 volumio wireless.js[720]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: KR Apr 11 22:40:28 volumio wireless.js[720]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Apr 11 22:40:28 volumio wireless.js[720]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Apr 11 22:40:28 volumio wireless.js[720]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Apr 11 22:40:28 volumio sudo[1230]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 11 22:40:28 volumio sudo[1230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 22:40:28 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:28 volumio sudo[1233]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 11 22:40:28 volumio sudo[1233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 22:40:28 volumio sudo[1233]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:28 volumio wpa_supplicant[1236]: Successfully initialized wpa_supplicant Apr 11 22:40:28 volumio wpa_supplicant[1239]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Apr 11 22:40:28 volumio wireless.js[720]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 33301ms Apr 11 22:40:28 volumio wireless.js[720]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Apr 11 22:40:29 volumio wireless.js[720]: WIRELESS.JS - INFO: Notified systemd about wireless ready Apr 11 22:40:29 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 11 22:40:29 volumio systemd[1]: Started wireless.service - Wireless Services. Apr 11 22:40:29 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Apr 11 22:40:29 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Apr 11 22:40:29 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Apr 11 22:40:29 volumio systemd[1]: Received SIGRTMIN+21 from PID 313 (plymouthd). Apr 11 22:40:29 volumio volumio-cpu-tweak[1258]: Setting RT Priority for mpd Apr 11 22:40:29 volumio volumio-cpu-tweak[1271]: pid 35's current scheduling policy: SCHED_OTHER Apr 11 22:40:29 volumio volumio-cpu-tweak[1271]: pid 35's current scheduling priority: 0 Apr 11 22:40:29 volumio volumio-cpu-tweak[1258]: Setting MPD Affinity Apr 11 22:40:29 volumio volumio-cpu-tweak[1272]: pid 3's current affinity mask: f Apr 11 22:40:29 volumio volumio-cpu-tweak[1258]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 11 22:40:29 volumio volumio-cpu-tweak[1258]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 11 22:40:29 volumio systemd[1]: Received SIGRTMIN+21 from PID 313 (plymouthd). Apr 11 22:40:29 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Apr 11 22:40:29 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Apr 11 22:40:29 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 11 22:40:29 volumio systemd[1]: Reached target getty.target - Login Prompts. Apr 11 22:40:29 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Apr 11 22:40:29 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Apr 11 22:40:29 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Apr 11 22:40:29 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Apr 11 22:40:29 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 11 22:40:29 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Apr 11 22:40:29 volumio systemd[1]: Startup finished in 19.554s (kernel) + 19.215s (userspace) = 38.769s. Apr 11 22:40:30 volumio volumio-remote-updater[719]: [2026-04-11 22:40:30] [info] asio async_connect error: asio.system:111 (Connection refused) Apr 11 22:40:30 volumio volumio-remote-updater[719]: [2026-04-11 22:40:30] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Apr 11 22:40:30 volumio volumio-remote-updater[719]: [2026-04-11 22:40:30] [error] handle_connect error: Connection refused Apr 11 22:40:33 volumio ntpd[920]: CLOCK: time stepped by 0.341274 Apr 11 22:40:33 volumio ntpd[920]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Apr 11 22:40:34 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Apr 11 22:40:34 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 11 22:40:34 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 11 22:40:34 volumio upmpdcli[1285]: Could not open config: /tmp/upmpdcli.conf Apr 11 22:40:34 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 11 22:40:34 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 11 22:40:34 volumio volumio[1256]: info: ------------------------------------------- Apr 11 22:40:34 volumio volumio[1256]: info: ----- Volumio3 ---- Apr 11 22:40:34 volumio volumio[1256]: info: ------------------------------------------- Apr 11 22:40:34 volumio volumio[1256]: info: ----- System startup ---- Apr 11 22:40:34 volumio volumio[1256]: info: ------------------------------------------- Apr 11 22:40:36 volumio volumio[1256]: info: MYVOLUMIO Environment detected Apr 11 22:40:36 volumio volumio[1256]: info: Plugin folders cleanup Apr 11 22:40:36 volumio volumio[1256]: info: Scanning into folder /volumio/app/plugins/ Apr 11 22:40:36 volumio volumio[1256]: info: Scanning category audio_interface Apr 11 22:40:36 volumio volumio[1256]: info: Scanning category miscellanea Apr 11 22:40:36 volumio volumio[1256]: info: Scanning category music_service Apr 11 22:40:36 volumio volumio[1256]: info: Scanning category plugins.json Apr 11 22:40:36 volumio volumio[1256]: info: Scanning category system_controller Apr 11 22:40:36 volumio volumio[1256]: info: Scanning category user_interface Apr 11 22:40:36 volumio volumio[1256]: info: Scanning into folder /data/plugins/ Apr 11 22:40:36 volumio volumio[1256]: info: Scanning category music_service Apr 11 22:40:36 volumio volumio[1256]: info: Scanning category system_hardware Apr 11 22:40:36 volumio volumio[1256]: info: Plugin folders cleanup completed Apr 11 22:40:36 volumio volumio[1256]: info: ------------------------------------------- Apr 11 22:40:36 volumio volumio[1256]: info: ----- Core plugins startup ---- Apr 11 22:40:36 volumio volumio[1256]: info: ------------------------------------------- Apr 11 22:40:36 volumio volumio[1256]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 22:40:36 volumio volumio[1256]: info: Adding plugin upnp to MyMusic Plugins Apr 11 22:40:36 volumio volumio[1256]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 22:40:36 volumio volumio[1256]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 22:40:36 volumio volumio[1256]: info: Loading plugins from folder /data/plugins/ Apr 11 22:40:36 volumio volumio[1256]: info: Loading plugin "system"... Apr 11 22:40:36 volumio volumio[1256]: info: Loading plugin "appearance"... Apr 11 22:40:39 volumio volumio[1256]: info: Loading plugin "network"... Apr 11 22:40:39 volumio volumio[1256]: info: Refreshing Cached IP Addresses Apr 11 22:40:39 volumio sudo[1295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 22:40:39 volumio sudo[1295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:40:39 volumio volumio[1256]: info: Loading plugin "services"... Apr 11 22:40:39 volumio sudo[1295]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:39 volumio volumio[1256]: info: Loading plugin "volumio5onboarding"... Apr 11 22:40:39 volumio sudo[1303]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 22:40:39 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:40:39 volumio volumio[1256]: info: Loading plugin "alsa_controller"... Apr 11 22:40:39 volumio sudo[1293]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 22:40:39 volumio sudo[1293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:40:39 volumio sudo[1293]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:39 volumio volumio[1256]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Apr 11 22:40:39 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 22:40:39 volumio volumio[1256]: info: Device 3 does not have any Mixer Control Available Apr 11 22:40:39 volumio volumio[1256]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 11 22:40:39 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 22:40:39 volumio volumio[1256]: info: Loading plugin "wizard"... Apr 11 22:40:39 volumio volumio[1256]: info: Loading plugin "networkfs"... Apr 11 22:40:39 volumio volumio[1256]: info: Starting Udev Watcher for removable devices Apr 11 22:40:39 volumio sudo[1338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nas,password=Nn11221122!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/nas2/MUSIC /mnt/NAS/나스음악 Apr 11 22:40:39 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:40:39 volumio sudo[1336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=nas,password=Nn11221122!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/nas2/MUSIC/Citypop /mnt/NAS/나스뮤직 Apr 11 22:40:39 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:40:39 volumio volumio[1256]: info: Ignoring mount for partition: boot Apr 11 22:40:39 volumio volumio[1256]: info: Ignoring mount for partition: volumio Apr 11 22:40:39 volumio volumio[1256]: info: Ignoring mount for partition: volumio_data Apr 11 22:40:39 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 22:40:39 volumio volumio[1256]: info: Loading plugin "pi_screen_setup"... Apr 11 22:40:39 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:40 volumio kernel: netfs: FS-Cache loaded Apr 11 22:40:40 volumio kernel: Key type cifs.spnego registered Apr 11 22:40:40 volumio kernel: Key type cifs.idmap registered Apr 11 22:40:40 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. Apr 11 22:40:40 volumio kernel: CIFS: Attempting to mount //192.168.0.10/nas2/MUSIC/Citypop Apr 11 22:40:40 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:40 volumio kernel: CIFS: Attempting to mount //192.168.0.10/nas2/MUSIC Apr 11 22:40:40 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Apr 11 22:40:41 volumio volumio-remote-updater[719]: [2026-04-11 22:40:41] [connect] Successful connection Apr 11 22:40:42 volumio volumio[1256]: info: Loading plugin "volumio_command_line_client"... Apr 11 22:40:42 volumio volumio[1256]: info: Loading plugin "upnp"... Apr 11 22:40:42 volumio volumio[1256]: info: [1775914842401] Starting Upmpd Daemon Apr 11 22:40:42 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 22:40:42 volumio volumio[1256]: info: Loading plugin "my_music"... Apr 11 22:40:42 volumio volumio[1256]: info: Loading plugin "mpd"... Apr 11 22:40:43 volumio volumio[1256]: info: Loading plugin "upnp_browser"... Apr 11 22:40:44 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 11 22:40:45 volumio volumio[1256]: info: Starting UPNP Browser Apr 11 22:40:45 volumio volumio[1256]: info: Loading plugin "alarm-clock"... Apr 11 22:40:46 volumio volumio[1256]: info: Loading plugin "airplay_emulation"... Apr 11 22:40:46 volumio volumio[1256]: info: Starting Shairport Sync Apr 11 22:40:46 volumio volumio[1256]: info: Loading plugin "last_100"... Apr 11 22:40:46 volumio volumio[1256]: info: Loading plugin "webradio"... Apr 11 22:40:46 volumio volumio[1256]: info: Loading plugin "i2s_dacs"... Apr 11 22:40:46 volumio volumio[1256]: info: Loading plugin "volumiodiscovery"... Apr 11 22:40:46 volumio volumio[1256]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 22:40:46 volumio volumio[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 22:40:46 volumio node[1256]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 22:40:46 volumio volumio[1256]: *** WARNING *** For more information see Apr 11 22:40:46 volumio volumio[1256]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 22:40:46 volumio volumio[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 22:40:46 volumio volumio[1256]: *** WARNING *** For more information see Apr 11 22:40:46 volumio node[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 22:40:46 volumio node[1256]: *** WARNING *** For more information see Apr 11 22:40:46 volumio node[1256]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 22:40:46 volumio node[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 22:40:46 volumio node[1256]: *** WARNING *** For more information see Apr 11 22:40:46 volumio volumio[1256]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 22:40:46 volumio volumio[1256]: info: Discovery: Started advertising with name: Volumio Apr 11 22:40:46 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 22:40:46 volumio volumio[1256]: info: Loading plugin "ytmusic"... Apr 11 22:40:48 volumio volumio[1256]: info: Loading plugin "gpio-buttons"... Apr 11 22:40:49 volumio volumio[1256]: gpiox_arm32 V2.2.1 Apr 11 22:40:49 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Apr 11 22:40:49 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 11 22:40:49 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 11 22:40:49 volumio upmpdcli[1396]: Could not open config: /tmp/upmpdcli.conf Apr 11 22:40:49 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 11 22:40:49 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 11 22:40:50 volumio volumio[1256]: info: gpio-buttons: Initialized Apr 11 22:40:50 volumio volumio[1256]: info: Loading plugin "outputs"... Apr 11 22:40:50 volumio volumio[1256]: info: Loading plugin "albumart"... Apr 11 22:40:50 volumio volumio[1256]: info: Plugin example_plugin is not enabled Apr 11 22:40:50 volumio volumio[1256]: info: Loading plugin "inputs"... Apr 11 22:40:50 volumio volumio[1256]: info: Loading plugin "updater_comm"... Apr 11 22:40:50 volumio volumio[1256]: info: Plugin mpdemulation is not enabled Apr 11 22:40:50 volumio volumio[1256]: info: Loading plugin "rest_api"... Apr 11 22:40:50 volumio volumio[1256]: info: Loading plugin "websocket"... Apr 11 22:40:50 volumio volumio[1256]: info: Starting Socket.io Server version 1.7.4 Apr 11 22:40:50 volumio volumio[1256]: info: Loading plugin "gpio_control"... Apr 11 22:40:51 volumio volumio[1256]: gpiox_arm32 V2.2.1 Apr 11 22:40:51 volumio volumio[1397]: Forking 3 albumart workers Apr 11 22:40:52 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 11 22:40:52 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 11 22:40:54 volumio volumio[1256]: info: Applying required configuration parameters for plugin gpio_control Apr 11 22:40:54 volumio volumio[1256]: info: Loading plugin "mpd_oled"... Apr 11 22:40:54 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 11 22:40:54 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 11 22:40:54 volumio systemd[1]: setdatetime-helper.service: Consumed 1.203s CPU time. Apr 11 22:40:56 volumio volumio-remote-updater[719]: [2026-04-11 22:40:56] [connect] Successful connection Apr 11 22:40:57 volumio volumio[1256]: info: Applying required configuration parameters for plugin mpd_oled Apr 11 22:40:57 volumio volumio[1256]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Apr 11 22:40:57 volumio volumio[1256]: info: Loading plugin "rotaryencoder2"... Apr 11 22:41:02 volumio volumio[1407]: Starting albumart workers Apr 11 22:41:03 volumio volumio[1408]: Starting albumart workers Apr 11 22:41:03 volumio volumio[1417]: Starting albumart workers Apr 11 22:41:04 volumio volumio[1256]: info: Loading i18n strings for locale ko Apr 11 22:41:04 volumio volumio[1256]: Updating browse sources language Apr 11 22:41:04 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 22:41:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Apr 11 22:41:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 11 22:41:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 11 22:41:05 volumio upmpdcli[1479]: Could not open config: /tmp/upmpdcli.conf Apr 11 22:41:05 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 11 22:41:05 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::initPlayerControls Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 22:41:06 volumio volumio[1256]: Express server listening on port 3000 Apr 11 22:41:06 volumio volumio[1256]: [Metrics] WebUI: 33s 110.67ms Apr 11 22:41:06 volumio volumio[1256]: info: CoreStateMachine::resetVolumioState Apr 11 22:41:06 volumio volumio[1256]: info: CoreStateMachine::getcurrentVolume Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 22:41:06 volumio volumio[1256]: info: CoreStateMachine::pushState Apr 11 22:41:06 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::volumioPushState Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 11 22:41:06 volumio volumio[1256]: info: CoreStateMachine::getcurrentVolume Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 22:41:06 volumio volumio[1256]: info: CoreStateMachine::pushState Apr 11 22:41:06 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:06 volumio volumio[1256]: info: CoreCommandRouter::volumioPushState Apr 11 22:41:07 volumio sudo[1484]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 22:41:07 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:07 volumio sudo[1486]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 22:41:07 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:07 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:07 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:07 volumio volumio[1256]: info: Volumio Network Manager: Network status updated: 1 Apr 11 22:41:08 volumio volumio[1256]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 11 22:41:08 volumio volumio[1256]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 11 22:41:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:09 volumio volumio[1256]: info: Reloading queue from file Apr 11 22:41:09 volumio volumio[1256]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 11 22:41:09 volumio volumio[1256]: info: Setting Device type: Raspberry PI Apr 11 22:41:09 volumio volumio[1256]: info: CoreStateMachine::setRepeat null single undefined Apr 11 22:41:09 volumio volumio[1256]: info: CoreStateMachine::pushState Apr 11 22:41:09 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:09 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:41:09 volumio volumio[1256]: info: CoreCommandRouter::volumioPushState Apr 11 22:41:09 volumio volumio[1256]: info: CoreStateMachine::setRandom null Apr 11 22:41:09 volumio volumio[1256]: info: CoreStateMachine::pushState Apr 11 22:41:09 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:09 volumio volumio[1256]: info: CoreCommandRouter::volumioPushState Apr 11 22:41:09 volumio volumio[1256]: info: Completed loading Core Plugins Apr 11 22:41:09 volumio volumio[1256]: info: Preparing to generate the ALSA configuration file Apr 11 22:41:09 volumio volumio[1256]: error: gpio-buttons: Socket connection error: undefined Apr 11 22:41:09 volumio volumio[1256]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 11 22:41:09 volumio volumio[1256]: info: Reading ALSA contributions from plugins. Apr 11 22:41:10 volumio volumio[1256]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.32 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 11 22:41:10 volumio sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 11 22:41:10 volumio sudo[1526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:10 volumio volumio[1256]: info: Discovery: adding 170d8fc3-7246-4949-a9ee-ee8e3056328a Apr 11 22:41:10 volumio volumio[1256]: info: Discovery: Found device Volumio Apr 11 22:41:10 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:10 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:10 volumio volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 11 22:41:10 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:10 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:10 volumio volumio[1256]: info: Discovery: this is already registered, 170d8fc3-7246-4949-a9ee-ee8e3056328a Apr 11 22:41:10 volumio volumio[1256]: info: Discovery: Found device Volumio Apr 11 22:41:10 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:10 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:10 volumio volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 11 22:41:10 volumio volumio[1256]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:41:10 volumio volumio[1256]: info: CoreStateMachine::getQueue Apr 11 22:41:10 volumio volumio[1256]: info: CorePlayQueue::getQueue Apr 11 22:41:10 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 11 22:41:10 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Apr 11 22:41:10 volumio volumio[1256]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Apr 11 22:41:10 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Apr 11 22:41:10 volumio volumio[1256]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Apr 11 22:41:10 volumio volumio[1256]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Apr 11 22:41:10 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Apr 11 22:41:11 volumio volumio-remote-updater[719]: [2026-04-11 22:41:11] [connect] Successful connection Apr 11 22:41:12 volumio volumio[1256]: info: Asound.conf file unchanged, so no further update is needed Apr 11 22:41:12 volumio volumio[1256]: info: Output device has changed, restarting MPD Apr 11 22:41:12 volumio volumio[1256]: info: Output device has changed, restarting Shairport Sync Apr 11 22:41:12 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:12 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:12 volumio sudo[1535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 22:41:12 volumio sudo[1537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 22:41:12 volumio sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:12 volumio sudo[1535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:12 volumio sudo[1535]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:12 volumio volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 22:41:12 volumio volumio[1256]: info: ___________ START PLUGINS ___________ Apr 11 22:41:12 volumio volumio[1256]: info: pi_screen_setup: Starting plugin Apr 11 22:41:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 22:41:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 22:41:12 volumio sudo[1545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 11 22:41:12 volumio volumio[1256]: info: pi_screen_setup: Loaded bundled presets v1.5.9 Apr 11 22:41:12 volumio volumio[1256]: info: pi_screen_setup: Active presets source: bundled, 231 presets loaded Apr 11 22:41:12 volumio volumio[1256]: info: pi_screen_setup: Detected model: Raspberry Pi 3 Model B Rev 1.2 Apr 11 22:41:12 volumio volumio[1256]: info: pi_screen_setup: Detected SoC: bcm2837 Apr 11 22:41:12 volumio sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:12 volumio volumio[1256]: info: pi_screen_setup: Detected RAM: 869 MB Apr 11 22:41:12 volumio volumio[1256]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 3","model_raw":"Raspberry Pi 3 Model B Rev 1.2","soc":"bcm2837","ram_mb":869,"hdmi_ports":1,"dsi_ports":["dsi0"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d","is_pi":true} Apr 11 22:41:12 volumio volumio[1256]: info: ControllerMpd::onStart: Initializing MPD Apr 11 22:41:12 volumio volumio[1256]: info: Creating MPD Configuration file Apr 11 22:41:13 volumio sudo[1547]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 22:41:13 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 22:41:13 volumio sudo[1550]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 11 22:41:13 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 22:41:13 volumio volumio[1256]: info: [1775914873166] CoreMusicLibrary::Adding element 미디어 서버 Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 22:41:13 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 11 22:41:13 volumio sudo[1545]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:13 volumio sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 22:41:13 volumio volumio[1256]: info: UPNP Browser: Client initialized successfully Apr 11 22:41:13 volumio sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 22:41:13 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:13 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:13 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:13 volumio volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:13 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 11 22:41:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 22:41:13 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 11 22:41:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 22:41:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 22:41:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 22:41:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 22:41:13 volumio volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 22:41:13 volumio volumio[1256]: info: [1775914873832] CoreMusicLibrary::Adding element Last_100 Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 22:41:13 volumio volumio[1256]: info: [1775914873870] CoreMusicLibrary::Adding element Webradio Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 22:41:13 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 22:41:14 volumio volumio[1256]: info: Initializing BBC Radios Apr 11 22:41:14 volumio sudo[1572]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 22:41:14 volumio sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 22:41:14 volumio sudo[1574]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 11 22:41:14 volumio sudo[1572]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:14 volumio volumio5-onboarding[1555]: time=2026-04-11T22:41:14.361+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 11 22:41:14 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 22:41:14 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:14 volumio volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 22:41:14 volumio volumio[1256]: info: [1775914874743] CoreMusicLibrary::Adding element YouTube Music Apr 11 22:41:14 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 22:41:14 volumio volumio[1256]: Cannot find translation for source YouTube Music Apr 11 22:41:14 volumio volumio[1256]: info: gpio-buttons: Creating GPIO triggers... Apr 11 22:41:15 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 11 22:41:15 volumio sudo[1595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Apr 11 22:41:15 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:15 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:15 volumio sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Apr 11 22:41:15 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:15 volumio volumio[1256]: info: Volumio Calling Home Apr 11 22:41:15 volumio sudo[1601]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 11 22:41:15 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:16 volumio volumio[1256]: info: gpio-buttons: Started successfully Apr 11 22:41:16 volumio volumio[1256]: info: Preparing to generate the ALSA configuration file Apr 11 22:41:16 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:16 volumio volumio[1256]: info: pi_screen_setup: Management server started on port 4567 Apr 11 22:41:19 volumio volumio[1256]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 11 22:41:19 volumio volumio[1256]: info: Reading ALSA contributions from plugins. Apr 11 22:41:19 volumio volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 11 22:41:19 volumio volumio[1256]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Apr 11 22:41:19 volumio volumio[1256]: info: MPD Permissions set Apr 11 22:41:19 volumio volumio[1256]: info: MPD Permissions set Apr 11 22:41:19 volumio volumio[1256]: 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: 8 Apr 11 22:41:19 volumio sudo[1607]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=23 relative_axis=true steps-per-period=1 Apr 11 22:41:19 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:19 volumio volumio[1256]: info: gpio-buttons: Socket reconnected after 1 attempts Apr 11 22:41:20 volumio volumio[1256]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Apr 11 22:41:20 volumio kernel: rotary-encoder rotary@16: gray Apr 11 22:41:20 volumio kernel: input: rotary@16 as /devices/platform/rotary@16/input/input6 Apr 11 22:41:20 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:20 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Apr 11 22:41:20 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 11 22:41:20 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 11 22:41:20 volumio sudo[1526]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:20 volumio volumio[1256]: 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: 8 Apr 11 22:41:20 volumio volumio[1256]: info: Received Get System Info Apr 11 22:41:20 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 22:41:20 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 22:41:20 volumio volumio[1256]: info: Discovery: Getting this device information Apr 11 22:41:20 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:20 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:20 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 22:41:20 volumio volumio5-onboarding[1555]: time=2026-04-11T22:41:20.499+09:00 level=INFO msg="system info for 43087668220f5e0d15d9f8f3b58812ad" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 11 22:41:20 volumio volumio5-onboarding[1555]: time=2026-04-11T22:41:20.541+09:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 11 22:41:21 volumio volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 11 22:41:21 volumio volumio[1256]: info: Volumio called home Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:21 volumio volumio[1256]: info: Received Get System Info Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 22:41:21 volumio volumio[1256]: info: Discovery: Getting this device information Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:21 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:21 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 22:41:22 volumio volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 11 22:41:22 volumio volumio[1256]: info: Upmpdcli Daemon Started Apr 11 22:41:23 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:23 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:23 volumio volumio[1256]: info: Starting Shairport Sync Apr 11 22:41:24 volumio volumio[1256]: info: Starting Shairport Sync Apr 11 22:41:24 volumio sudo[1661]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 11 22:41:24 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:24 volumio volumio[1256]: info: Starting Shairport Sync Apr 11 22:41:24 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:24 volumio sudo[1663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 22:41:24 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:24 volumio sudo[1668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 22:41:24 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:24 volumio sudo[1666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 22:41:24 volumio sudo[1666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 22:41:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 22:41:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 22:41:24 volumio systemd[1]: shairport-sync.service: Consumed 1.979s CPU time. Apr 11 22:41:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 22:41:24 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 22:41:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 22:41:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 22:41:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 22:41:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 22:41:24 volumio sudo[1666]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 22:41:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 22:41:24 volumio volumio[1256]: info: Asound.conf file unchanged, so no further update is needed Apr 11 22:41:24 volumio volumio[1256]: info: Output device has changed, restarting MPD Apr 11 22:41:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 22:41:24 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:25 volumio volumio[1256]: info: Output device has changed, restarting Shairport Sync Apr 11 22:41:25 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:25 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:25 volumio sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 11 22:41:25 volumio sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 22:41:25 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:25 volumio sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:25 volumio sudo[1690]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:25 volumio volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 22:41:25 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:25 volumio sudo[1706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 22:41:25 volumio sudo[1706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:25 volumio volumio[1256]: info: gpio-buttons: Socket connected to Volumio Apr 11 22:41:25 volumio systemd[1]: mpd.service: Deactivated successfully. Apr 11 22:41:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 22:41:25 volumio systemd[1]: mpd.service: Consumed 6.443s CPU time. Apr 11 22:41:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Apr 11 22:41:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 22:41:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 22:41:25 volumio volumio[1256]: info: Shairport-Sync Started Apr 11 22:41:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 22:41:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 22:41:25 volumio volumio[1256]: Error adding Membership: Error: addMembership EINVAL Apr 11 22:41:25 volumio volumio[1256]: info: Shairport-Sync Started Apr 11 22:41:25 volumio volumio[1256]: error: Upnp client error: Error: read ECONNRESET Apr 11 22:41:25 volumio sudo[1715]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 22:41:25 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 22:41:26 volumio sudo[1716]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 11 22:41:26 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:26 volumio volumio[1256]: error: Upnp client error: Error: connect ECONNRESET 127.0.0.1:6600 Apr 11 22:41:26 volumio volumio[1256]: info: MPD Permissions set Apr 11 22:41:26 volumio volumio[1256]: info: Shairport-Sync Started Apr 11 22:41:26 volumio volumio-remote-updater[719]: [2026-04-11 22:41:26] [connect] Successful connection Apr 11 22:41:26 volumio volumio[1256]: info: [ROTARYENCODER2] Socket connected to Volumio Apr 11 22:41:26 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:26 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:26 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:26 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:26 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:26 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:26 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:26 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:27 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:27 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:27 volumio volumio[1256]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 11 22:41:27 volumio volumio-remote-updater[719]: [2026-04-11 22:41:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1775914886 101 Apr 11 22:41:27 volumio volumio[1256]: 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: 12 Apr 11 22:41:27 volumio volumio[1256]: info: Starting Shairport Sync Apr 11 22:41:28 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:28 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:28 volumio sudo[1724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 22:41:28 volumio sudo[1724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:41:28 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:28 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:28 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 22:41:28 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 22:41:28 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 22:41:28 volumio systemd[1]: shairport-sync.service: Consumed 1.480s CPU time. Apr 11 22:41:28 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 22:41:28 volumio sudo[1724]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:28 volumio volumio[1256]: info: Shairport-Sync Started Apr 11 22:41:29 volumio mpd[1717]: 2026-04-11T22:41:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 22:41:30 volumio systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 22:41:30 volumio sudo[1706]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:30 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:30 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:30 volumio systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Apr 11 22:41:30 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Apr 11 22:41:30 volumio volumio[1256]: info: Completed starting Core Plugins Apr 11 22:41:30 volumio volumio[1256]: info: ------------------------------------------- Apr 11 22:41:30 volumio volumio[1256]: info: ----- MyVolumio plugins startup ---- Apr 11 22:41:30 volumio volumio[1256]: info: ------------------------------------------- Apr 11 22:41:30 volumio volumio[1256]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 22:41:31 volumio volumio[1256]: error: MPD error: The expression evaluated to a falsy value: Apr 11 22:41:31 volumio volumio[1256]: assert.ok(self.idling) Apr 11 22:41:31 volumio volumio[1256]: error: The expression evaluated to a falsy value: Apr 11 22:41:31 volumio volumio[1256]: assert.ok(self.idling) Apr 11 22:41:31 volumio volumio[1256]: info: MPD running with PID1717 Apr 11 22:41:31 volumio volumio[1256]: ,establishing connection Apr 11 22:41:31 volumio volumio[1256]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Apr 11 22:41:31 volumio volumio[1256]: error: MPD error: The expression evaluated to a falsy value: Apr 11 22:41:31 volumio volumio[1256]: assert.ok(self.idling) Apr 11 22:41:31 volumio volumio[1256]: error: The expression evaluated to a falsy value: Apr 11 22:41:31 volumio volumio[1256]: assert.ok(self.idling) Apr 11 22:41:31 volumio volumio[1256]: error: MPD error: The expression evaluated to a falsy value: Apr 11 22:41:31 volumio volumio[1256]: assert.ok(self.idling) Apr 11 22:41:31 volumio volumio[1256]: error: The expression evaluated to a falsy value: Apr 11 22:41:31 volumio volumio[1256]: assert.ok(self.idling) Apr 11 22:41:31 volumio volumio[1256]: error: updateQueue error: null Apr 11 22:41:31 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:31 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:31 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:31 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:31 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:31 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:32 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:32 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:32 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:32 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:32 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:32 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:32 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:32 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:33 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:33 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:33 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:33 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:33 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:33 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:33 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:33 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:33 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:33 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:34 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:34 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:34 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:34 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:34 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:34 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:34 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:34 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:35 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:35 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:35 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:35 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:35 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:35 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:36 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:36 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:38 volumio volumio[1256]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 11 22:41:48 volumio upmpdcli[1782]: writing RSA key Apr 11 22:41:50 volumio volumio5-onboarding[1555]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Apr 11 22:41:50 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 11 22:41:50 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 11 22:41:50 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Apr 11 22:41:50 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 11 22:41:50 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 11 22:41:50 volumio volumio5-onboarding[1794]: time=2026-04-11T22:41:50.827+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Apr 11 22:41:51 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 22:41:51 volumio volumio[1256]: error: MyVolumio Plugin failed to start in a timely fashion Apr 11 22:41:51 volumio volumio[1256]: [Metrics] CommandRouter: 77s 9.69ms Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::volumiosetStartupVolume Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::Close All Modals sent Apr 11 22:41:51 volumio volumio[1256]: info: CoreCommandRouter::Close All Modals sent Apr 11 22:41:51 volumio volumio[1256]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 22:41:52 volumio volumio[1256]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Apr 11 22:41:52 volumio volumio[1256]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Apr 11 22:41:52 volumio volumio[1256]: at new NodeError (node:internal/errors:405:5) Apr 11 22:41:52 volumio volumio[1256]: at assert (node:internal/assert:14:11) Apr 11 22:41:52 volumio volumio[1256]: at internalConnectMultiple (node:net:1118:3) Apr 11 22:41:52 volumio volumio[1256]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Apr 11 22:41:52 volumio volumio[1256]: at listOnTimeout (node:internal/timers:575:11) Apr 11 22:41:52 volumio volumio[1256]: at process.processTimers (node:internal/timers:514:7) { Apr 11 22:41:52 volumio volumio[1256]: code: 'ERR_INTERNAL_ASSERTION' Apr 11 22:41:52 volumio volumio[1256]: } Apr 11 22:41:52 volumio volumio[1256]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 22:42:00 volumio sudo[1834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 22:40' Apr 11 22:42:00 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 22:42:00 volumio volumio5-onboarding[1794]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:35010->127.0.0.1:3000: i/o timeout Apr 11 22:42:00 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 11 22:42:00 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 11 22:42:01 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Apr 11 22:42:01 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 11 22:42:01 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. 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"