-- Logs begin at Mon 2026-02-09 08:56:34 JST, end at Mon 2026-02-09 09:41:38 JST. -- Feb 09 09:40:18 volumiopi systemd-timedated[839]: Changed local time to Mon Feb 9 09:40:18 2026 Feb 09 09:40:18 volumiopi sudo[829]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:18 volumiopi volumio-time-update[555]: volumio-time-update-util: System time updated successfully. Feb 09 09:40:18 volumiopi systemd[1]: Started Volumio Time Update Utility. Feb 09 09:40:18 volumiopi sudo[845]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:18 volumiopi wireless.js[548]: Stopped aP Feb 09 09:40:21 volumiopi nmbd[681]: [2026/02/09 09:40:21.267963, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 09 09:40:21 volumiopi nmbd[681]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Feb 09 09:40:21 volumiopi nmbd[681]: This response was from IP 192.168.1.247, reporting an IP address of 192.168.1.247. Feb 09 09:40:21 volumiopi nmbd[681]: [2026/02/09 09:40:21.268597, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 09 09:40:21 volumiopi nmbd[681]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Feb 09 09:40:21 volumiopi nmbd[681]: This response was from IP 192.168.1.16, reporting an IP address of 192.168.1.247. Feb 09 09:40:21 volumiopi nmbd[681]: [2026/02/09 09:40:21.269783, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 09 09:40:21 volumiopi nmbd[681]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Feb 09 09:40:21 volumiopi nmbd[681]: This response was from IP 192.168.1.247, reporting an IP address of 192.168.1.247. Feb 09 09:40:21 volumiopi nmbd[681]: [2026/02/09 09:40:21.270434, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 09 09:40:21 volumiopi nmbd[681]: query_name_response: Multiple (5) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Feb 09 09:40:21 volumiopi nmbd[681]: This response was from IP 192.168.1.16, reporting an IP address of 192.168.1.247. Feb 09 09:40:21 volumiopi nmbd[681]: [2026/02/09 09:40:21.271229, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 09 09:40:21 volumiopi nmbd[681]: query_name_response: Multiple (6) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Feb 09 09:40:21 volumiopi nmbd[681]: This response was from IP 192.168.1.247, reporting an IP address of 192.168.1.247. Feb 09 09:40:21 volumiopi sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 09 09:40:21 volumiopi sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:21 volumiopi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 09 09:40:21 volumiopi sudo[856]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:21 volumiopi sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 09 09:40:21 volumiopi sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:21 volumiopi smbd[834]: [2026/02/09 09:40:21.383399, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 09 09:40:21 volumiopi smbd[834]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 09 09:40:21 volumiopi systemd[1]: Started Samba SMB Daemon. Feb 09 09:40:21 volumiopi sudo[861]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:21 volumiopi sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 09 09:40:21 volumiopi sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:21 volumiopi sudo[870]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:21 volumiopi sudo[872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 09 09:40:21 volumiopi sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:21 volumiopi nmbd[681]: [2026/02/09 09:40:21.976445, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 09 09:40:21 volumiopi nmbd[681]: query_name_response: Multiple (7) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Feb 09 09:40:21 volumiopi nmbd[681]: This response was from IP 192.168.1.247, reporting an IP address of 192.168.1.247. Feb 09 09:40:21 volumiopi nmbd[681]: [2026/02/09 09:40:21.977166, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 09 09:40:21 volumiopi nmbd[681]: query_name_response: Multiple (8) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Feb 09 09:40:21 volumiopi nmbd[681]: This response was from IP 192.168.1.247, reporting an IP address of 192.168.1.247. Feb 09 09:40:21 volumiopi nmbd[681]: [2026/02/09 09:40:21.977594, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 09 09:40:21 volumiopi nmbd[681]: query_name_response: Multiple (9) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Feb 09 09:40:21 volumiopi nmbd[681]: This response was from IP 192.168.1.16, reporting an IP address of 192.168.1.247. Feb 09 09:40:23 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:23] [info] asio async_connect error: system:111 (Connection refused) Feb 09 09:40:23 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 09 09:40:23 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:23] [error] handle_connect error: Underlying Transport Error Feb 09 09:40:24 volumiopi sudo[872]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:24 volumiopi wireless.js[548]: SETTING APPROPRIATE REG DOMAIN: JP Feb 09 09:40:24 volumiopi sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 09 09:40:24 volumiopi sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:24 volumiopi sudo[877]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:24 volumiopi sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Feb 09 09:40:24 volumiopi sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:24 volumiopi sudo[879]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:24 volumiopi wireless.js[548]: SUCCESSFULLY SET NEW REGDOMAIN: JP Feb 09 09:40:24 volumiopi wireless.js[548]: Start wireless flow Feb 09 09:40:24 volumiopi wireless.js[548]: Stopped hotspot (if there).. Feb 09 09:40:24 volumiopi sudo[884]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 09 09:40:24 volumiopi sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:24 volumiopi sudo[884]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:24 volumiopi sudo[886]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 09 09:40:24 volumiopi sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:25 volumiopi sudo[886]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:25 volumiopi wireless.js[548]: DHCP IP Feb 09 09:40:25 volumiopi wireless.js[548]: Start ap Feb 09 09:40:25 volumiopi wpa_supplicant[888]: Successfully initialized wpa_supplicant Feb 09 09:40:25 volumiopi sudo[889]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 09 09:40:25 volumiopi sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:25 volumiopi dhcpcd[890]: dev: loaded udev Feb 09 09:40:26 volumiopi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 09 09:40:26 volumiopi dhcpcd-run-hooks[899]: wlan0: starting wpa_supplicant Feb 09 09:40:26 volumiopi wpa_supplicant[888]: ctrl_iface exists and seems to be in use - cannot override it Feb 09 09:40:26 volumiopi wpa_supplicant[888]: Delete '/var/run/wpa_supplicant/wlan0' manually if it is not used anymore Feb 09 09:40:26 volumiopi wpa_supplicant[888]: Failed to initialize control interface '/var/run/wpa_supplicant'. You may have another wpa_supplicant process already running or the file was left by an unclean termination of wpa_supplicant in which case you will need to manually remove this file before starting wpa_supplicant again. Feb 09 09:40:26 volumiopi dhcpcd[890]: wlan0: connected to Access Point `' Feb 09 09:40:26 volumiopi wireless.js[548]: trying... Feb 09 09:40:26 volumiopi wpa_supplicant[888]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Feb 09 09:40:26 volumiopi dhcpcd[890]: DUID 00:01:00:01:2f:b6:20:2f:b8:27:eb:7d:1f:d3 Feb 09 09:40:26 volumiopi dhcpcd[890]: eth0: IAID eb:7d:1f:d3 Feb 09 09:40:26 volumiopi dhcpcd[890]: eth0: adding address fe80::773:3982:debf:987a Feb 09 09:40:26 volumiopi dhcpcd[890]: ipv6_addaddr1: Permission denied Feb 09 09:40:26 volumiopi dhcpcd[890]: wlan0: waiting for carrier Feb 09 09:40:26 volumiopi sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:26 volumiopi sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:26 volumiopi sudo[916]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:26 volumiopi dhcpcd[890]: eth0: soliciting an IPv6 router Feb 09 09:40:27 volumiopi dhcpcd[890]: eth0: soliciting a DHCP lease Feb 09 09:40:27 volumiopi dhcpcd[890]: eth0: offered 192.168.1.153 from 192.168.1.10 Feb 09 09:40:27 volumiopi dhcpcd[890]: eth0: leased 192.168.1.153 for 583 seconds Feb 09 09:40:27 volumiopi dhcpcd[890]: eth0: adding route to 192.168.1.0/24 Feb 09 09:40:27 volumiopi dhcpcd[890]: eth0: adding default route via 192.168.1.1 Feb 09 09:40:27 volumiopi dhcpcd[890]: forked to background, child pid 943 Feb 09 09:40:27 volumiopi wireless.js[548]: trying... Feb 09 09:40:27 volumiopi sudo[889]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:27 volumiopi sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:27 volumiopi sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:27 volumiopi sudo[945]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:28 volumiopi wireless.js[548]: trying... Feb 09 09:40:28 volumiopi sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:28 volumiopi sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:28 volumiopi sudo[948]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:29 volumiopi wireless.js[548]: trying... Feb 09 09:40:29 volumiopi sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:29 volumiopi sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:29 volumiopi sudo[951]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:30 volumiopi wireless.js[548]: trying... Feb 09 09:40:30 volumiopi sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:30 volumiopi sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:30 volumiopi sudo[954]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:31 volumiopi wireless.js[548]: trying... Feb 09 09:40:31 volumiopi sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:31 volumiopi sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:31 volumiopi sudo[957]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:32 volumiopi wireless.js[548]: trying... Feb 09 09:40:32 volumiopi sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:32 volumiopi sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:32 volumiopi sudo[960]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:33 volumiopi wireless.js[548]: trying... Feb 09 09:40:33 volumiopi sudo[963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:33 volumiopi sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:33 volumiopi sudo[963]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:33 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:33] [info] asio async_connect error: system:111 (Connection refused) Feb 09 09:40:33 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 09 09:40:33 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:33] [error] handle_connect error: Underlying Transport Error Feb 09 09:40:34 volumiopi wireless.js[548]: trying... Feb 09 09:40:34 volumiopi sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:34 volumiopi sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:34 volumiopi sudo[966]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:35 volumiopi wireless.js[548]: trying... Feb 09 09:40:35 volumiopi sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:35 volumiopi sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:35 volumiopi sudo[969]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:36 volumiopi wireless.js[548]: trying... Feb 09 09:40:36 volumiopi sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:36 volumiopi sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:36 volumiopi sudo[972]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:37 volumiopi systemd[1]: systemd-fsckd.service: Succeeded. Feb 09 09:40:37 volumiopi wireless.js[548]: trying... Feb 09 09:40:37 volumiopi sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:37 volumiopi sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:37 volumiopi sudo[975]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:38 volumiopi wireless.js[548]: trying... Feb 09 09:40:38 volumiopi sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:38 volumiopi sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:38 volumiopi sudo[978]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:39 volumiopi wireless.js[548]: trying... Feb 09 09:40:39 volumiopi sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:39 volumiopi sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:39 volumiopi sudo[981]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:40 volumiopi wireless.js[548]: trying... Feb 09 09:40:40 volumiopi sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:40 volumiopi sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:40 volumiopi sudo[984]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:41 volumiopi wireless.js[548]: trying... Feb 09 09:40:41 volumiopi sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:41 volumiopi sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:41 volumiopi sudo[987]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:42 volumiopi wireless.js[548]: trying... Feb 09 09:40:42 volumiopi sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:42 volumiopi sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:42 volumiopi sudo[990]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:43 volumiopi wireless.js[548]: trying... Feb 09 09:40:43 volumiopi sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:43 volumiopi sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:43 volumiopi sudo[993]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:43 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:43] [info] asio async_connect error: system:111 (Connection refused) Feb 09 09:40:43 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:43] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 09 09:40:43 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:43] [error] handle_connect error: Underlying Transport Error Feb 09 09:40:44 volumiopi wireless.js[548]: trying... Feb 09 09:40:44 volumiopi sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:44 volumiopi sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:44 volumiopi sudo[996]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:45 volumiopi wireless.js[548]: trying... Feb 09 09:40:45 volumiopi sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 09 09:40:45 volumiopi sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:40:45 volumiopi sudo[999]: pam_unix(sudo:session): session closed for user root Feb 09 09:40:46 volumiopi wireless.js[548]: Overtime, starting plan B Feb 09 09:40:46 volumiopi systemd[1]: Started Wireless Services. Feb 09 09:40:47 volumiopi systemd[1]: Started Volumio Backend Module. Feb 09 09:40:47 volumiopi systemd[1]: Started Volumio Cpu Tweaker. Feb 09 09:40:47 volumiopi systemd[1]: Reached target Multi-User System. Feb 09 09:40:47 volumiopi systemd[1]: Reached target Graphical Interface. Feb 09 09:40:47 volumiopi systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 09 09:40:47 volumiopi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 09 09:40:47 volumiopi volumio-cpu-tweak[1005]: Setting RT Priority for mpd Feb 09 09:40:47 volumiopi systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 09 09:40:47 volumiopi systemd[1]: Startup finished in 16.946s (kernel) + 47.880s (userspace) = 1min 4.826s. Feb 09 09:40:47 volumiopi volumio-cpu-tweak[1005]: pid 35's current scheduling policy: SCHED_OTHER Feb 09 09:40:47 volumiopi volumio-cpu-tweak[1005]: pid 35's current scheduling priority: 0 Feb 09 09:40:47 volumiopi volumio-cpu-tweak[1005]: Setting MPD Affinity Feb 09 09:40:47 volumiopi volumio-cpu-tweak[1005]: pid 3's current affinity mask: f Feb 09 09:40:47 volumiopi volumio-cpu-tweak[1005]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 09 09:40:47 volumiopi volumio-cpu-tweak[1005]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 09 09:40:47 volumiopi systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 09 09:40:48 volumiopi systemd[1]: systemd-timedated.service: Succeeded. Feb 09 09:40:53 volumiopi volumio-remote-updater[547]: [2026-02-09 09:40:53] [connect] Successful connection Feb 09 09:40:56 volumiopi volumio[1004]: info: ------------------------------------------- Feb 09 09:40:56 volumiopi volumio[1004]: info: ----- Volumio3 ---- Feb 09 09:40:56 volumiopi volumio[1004]: info: ------------------------------------------- Feb 09 09:40:56 volumiopi volumio[1004]: info: ----- System startup ---- Feb 09 09:40:56 volumiopi volumio[1004]: info: ------------------------------------------- Feb 09 09:40:58 volumiopi volumio[1004]: info: MYVOLUMIO Environment detected Feb 09 09:40:58 volumiopi volumio[1004]: info: Plugin folders cleanup Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning into folder /volumio/app/plugins/ Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category audio_interface Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category miscellanea Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category music_service Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category plugins.json Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category system_controller Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category user_interface Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning into folder /data/plugins/ Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category audio_interface Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category music_service Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category system_controller Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category system_hardware Feb 09 09:40:58 volumiopi volumio[1004]: info: Scanning category user_interface Feb 09 09:40:58 volumiopi volumio[1004]: info: Plugin folders cleanup completed Feb 09 09:40:58 volumiopi volumio[1004]: info: ------------------------------------------- Feb 09 09:40:58 volumiopi volumio[1004]: info: ----- Core plugins startup ---- Feb 09 09:40:58 volumiopi volumio[1004]: info: ------------------------------------------- Feb 09 09:40:58 volumiopi volumio[1004]: info: Loading plugins from folder /volumio/app/plugins/ Feb 09 09:40:58 volumiopi volumio[1004]: info: Adding plugin upnp to MyMusic Plugins Feb 09 09:40:58 volumiopi volumio[1004]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 09 09:40:58 volumiopi volumio[1004]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 09 09:40:58 volumiopi volumio[1004]: info: Loading plugins from folder /data/plugins/ Feb 09 09:40:58 volumiopi volumio[1004]: info: Loading plugin "system"... Feb 09 09:40:59 volumiopi volumio[1004]: info: Loading plugin "ampswitch"... Feb 09 09:40:59 volumiopi volumio[1004]: info: Applying required configuration parameters for plugin ampswitch Feb 09 09:41:02 volumiopi volumio[1004]: info: Loading plugin "appearance"... Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "network"... Feb 09 09:41:05 volumiopi volumio[1004]: info: Refreshing Cached IP Addresses Feb 09 09:41:05 volumiopi sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 09:41:05 volumiopi sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:05 volumiopi sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 09:41:05 volumiopi sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:05 volumiopi sudo[1041]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:05 volumiopi sudo[1039]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "services"... Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "alsa_controller"... Feb 09 09:41:05 volumiopi sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 09 09:41:05 volumiopi sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:05 volumiopi sudo[1050]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:05 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "wizard"... Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "networkfs"... Feb 09 09:41:05 volumiopi volumio[1004]: info: Starting Udev Watcher for removable devices Feb 09 09:41:05 volumiopi volumio[1004]: info: Ignoring mount for partition: boot Feb 09 09:41:05 volumiopi volumio[1004]: info: Ignoring mount for partition: volumio Feb 09 09:41:05 volumiopi volumio[1004]: info: Ignoring mount for partition: volumio_data Feb 09 09:41:05 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "volumio_command_line_client"... Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "upnp"... Feb 09 09:41:05 volumiopi volumio[1004]: info: [1770597665691] Starting Upmpd Daemon Feb 09 09:41:05 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "my_music"... Feb 09 09:41:05 volumiopi volumio[1004]: info: Loading plugin "mpd"... Feb 09 09:41:06 volumiopi volumio[1004]: info: Loading plugin "upnp_browser"... Feb 09 09:41:07 volumiopi volumio[1004]: info: Starting UPNP Browser Feb 09 09:41:07 volumiopi volumio[1004]: info: Loading plugin "alarm-clock"... Feb 09 09:41:07 volumiopi volumio[1004]: info: Loading plugin "airplay_emulation"... Feb 09 09:41:08 volumiopi volumio[1004]: info: Starting Shairport Sync Feb 09 09:41:08 volumiopi volumio[1004]: info: Loading plugin "last_100"... Feb 09 09:41:08 volumiopi volumio[1004]: info: Loading plugin "webradio"... Feb 09 09:41:08 volumiopi volumio[1004]: info: Loading plugin "i2s_dacs"... Feb 09 09:41:08 volumiopi volumio[1004]: info: Loading plugin "volumiodiscovery"... Feb 09 09:41:08 volumiopi volumio[1004]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 09 09:41:08 volumiopi volumio[1004]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 09 09:41:08 volumiopi volumio[1004]: *** WARNING *** For more information see Feb 09 09:41:08 volumiopi node[1004]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 09 09:41:08 volumiopi volumio[1004]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 09 09:41:08 volumiopi volumio[1004]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 09 09:41:08 volumiopi volumio[1004]: *** WARNING *** For more information see Feb 09 09:41:08 volumiopi node[1004]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 09 09:41:08 volumiopi node[1004]: *** WARNING *** For more information see Feb 09 09:41:08 volumiopi node[1004]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 09 09:41:08 volumiopi node[1004]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 09 09:41:08 volumiopi node[1004]: *** WARNING *** For more information see Feb 09 09:41:08 volumiopi volumio[1004]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 09 09:41:08 volumiopi volumio-remote-updater[547]: [2026-02-09 09:41:08] [connect] Successful connection Feb 09 09:41:08 volumiopi volumio[1004]: info: Discovery: Started advertising with name: VolumioPi Feb 09 09:41:08 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 09 09:41:08 volumiopi volumio[1004]: info: Loading plugin "jellyfin"... Feb 09 09:41:13 volumiopi volumio[1004]: info: Plugin autostart is not enabled Feb 09 09:41:13 volumiopi volumio[1004]: info: Loading plugin "outputs"... Feb 09 09:41:13 volumiopi volumio[1004]: info: Loading plugin "albumart"... Feb 09 09:41:13 volumiopi volumio[1004]: info: Plugin example_plugin is not enabled Feb 09 09:41:13 volumiopi volumio[1004]: info: Loading plugin "inputs"... Feb 09 09:41:13 volumiopi volumio[1004]: info: Loading plugin "updater_comm"... Feb 09 09:41:17 volumiopi ntpd[716]: Soliciting pool server 142.91.108.61 Feb 09 09:41:17 volumiopi volumio[1004]: Forking 3 albumart workers Feb 09 09:41:17 volumiopi volumio[1004]: info: Plugin mpdemulation is not enabled Feb 09 09:41:17 volumiopi volumio[1004]: info: Loading plugin "rest_api"... Feb 09 09:41:17 volumiopi volumio[1004]: info: Loading plugin "websocket"... Feb 09 09:41:17 volumiopi volumio[1004]: info: Starting Socket.io Server version 2.3.0 Feb 09 09:41:17 volumiopi volumio[1004]: info: Loading plugin "fusiondsp"... Feb 09 09:41:18 volumiopi volumio[1004]: info: Applying required configuration parameters for plugin fusiondsp Feb 09 09:41:19 volumiopi volumio[1004]: Starting albumart workers Feb 09 09:41:19 volumiopi volumio[1004]: Starting albumart workers Feb 09 09:41:19 volumiopi volumio[1004]: Starting albumart workers Feb 09 09:41:20 volumiopi volumio[1004]: info: Loading plugin "mpdoutput"... Feb 09 09:41:20 volumiopi ntpd[716]: Soliciting pool server 142.91.105.55 Feb 09 09:41:20 volumiopi volumio[1004]: info: Loading plugin "lms"... Feb 09 09:41:20 volumiopi volumio[1004]: info: Loading plugin "backup_restore"... Feb 09 09:41:21 volumiopi ntpd[716]: Soliciting pool server 129.250.35.251 Feb 09 09:41:21 volumiopi ntpd[716]: Soliciting pool server 167.179.119.205 Feb 09 09:41:21 volumiopi volumio[1004]: info: Applying required configuration parameters for plugin backup_restore Feb 09 09:41:21 volumiopi volumio[1004]: info: Loading plugin "audiophonicsonoff"... Feb 09 09:41:22 volumiopi volumio[1004]: info: Applying required configuration parameters for plugin audiophonicsonoff Feb 09 09:41:22 volumiopi volumio[1004]: info: Audiophonics on/off initiated Feb 09 09:41:22 volumiopi volumio[1004]: info: Loading plugin "Systeminfo"... Feb 09 09:41:22 volumiopi ntpd[716]: Soliciting pool server 45.77.20.103 Feb 09 09:41:22 volumiopi ntpd[716]: Soliciting pool server 129.250.35.250 Feb 09 09:41:23 volumiopi volumio[1004]: info: Loading plugin "ferrum_streaming_control_technology"... Feb 09 09:41:23 volumiopi ntpd[716]: Soliciting pool server 165.140.142.8 Feb 09 09:41:23 volumiopi ntpd[716]: Soliciting pool server 43.229.16.149 Feb 09 09:41:23 volumiopi ntpd[716]: Soliciting pool server 162.159.200.123 Feb 09 09:41:23 volumiopi volumio-remote-updater[547]: [2026-02-09 09:41:23] [connect] Successful connection Feb 09 09:41:23 volumiopi volumio[1004]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Feb 09 09:41:23 volumiopi volumio[1004]: info: Loading plugin "randomizer"... Feb 09 09:41:24 volumiopi ntpd[716]: Soliciting pool server 160.16.113.133 Feb 09 09:41:24 volumiopi ntpd[716]: Soliciting pool server 2001:678:8::123 Feb 09 09:41:24 volumiopi ntpd[716]: Soliciting pool server 110.4.129.114 Feb 09 09:41:25 volumiopi ntpd[716]: Soliciting pool server 85.121.50.82 Feb 09 09:41:27 volumiopi volumio[1004]: info: Applying required configuration parameters for plugin randomizer Feb 09 09:41:29 volumiopi ntpd[716]: receive: Unexpected origin timestamp 0xed33abb7.5b2d6cd2 does not match aorg 0000000000.00000000 from server@129.250.35.251 xmt 0xed33abb9.43fed83f Feb 09 09:41:29 volumiopi volumio[1004]: info: Plugin touch_display is not enabled Feb 09 09:41:29 volumiopi volumio[1004]: info: Loading i18n strings for locale ja Feb 09 09:41:29 volumiopi volumio[1004]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Feb 09 09:41:29 volumiopi volumio[1004]: Updating browse sources language Feb 09 09:41:29 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 09:41:29 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 09:41:29 volumiopi volumio[1004]: info: CoreCommandRouter::initPlayerControls Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: Express server listening on port 3000 Feb 09 09:41:30 volumiopi volumio[1004]: [Metrics] WebUI: 35s 848.68ms Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreStateMachine::resetVolumioState Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreStateMachine::getcurrentVolume Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::volumioRetrievevolume Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreStateMachine::pushState Feb 09 09:41:30 volumiopi volumio[1004]: info: CorePlayQueue::getTrack 0 Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::volumioPushState Feb 09 09:41:30 volumiopi sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 09:41:30 volumiopi sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:30 volumiopi sudo[1187]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:30 volumiopi sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 09:41:30 volumiopi sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:30 volumiopi sudo[1189]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:30 volumiopi volumio[1004]: info: Volumio Network Manager: Network status updated: 1 Feb 09 09:41:30 volumiopi volumio[1004]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 09 09:41:30 volumiopi volumio[1004]: wlan0 Interface doesn't support scanning : Network is down Feb 09 09:41:30 volumiopi volumio[1004]: info: Cannot use regular scanning, forcing with ap-force Feb 09 09:41:30 volumiopi sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 09 09:41:30 volumiopi sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:30 volumiopi sudo[1193]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:30 volumiopi volumio[1004]: command failed: Network is down (-100) Feb 09 09:41:30 volumiopi volumio[1004]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 09 09:41:30 volumiopi volumio[1004]: command failed: Network is down (-100) Feb 09 09:41:30 volumiopi volumio[1004]: verbose: New Socket.io Connection to 192.168.1.153:3000 from 192.168.1.98 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 09 09:41:30 volumiopi volumio[1004]: verbose: New Socket.io Connection to 192.168.1.153:3000 from 192.168.1.98 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 09 09:41:30 volumiopi volumio[1004]: verbose: New Socket.io Connection to 192.168.1.153:3000 from 192.168.1.98 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 09 09:41:30 volumiopi volumio[1004]: verbose: New Socket.io Connection to 192.168.1.153:3000 from 192.168.1.98 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 09 09:41:30 volumiopi volumio[1004]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 09:41:30 volumiopi volumio[1004]: info: Reloading queue from file Feb 09 09:41:30 volumiopi volumio[1004]: info: Setting Device type: Raspberry PI Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreStateMachine::setRepeat null single undefined Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreStateMachine::pushState Feb 09 09:41:30 volumiopi volumio[1004]: info: CorePlayQueue::getTrack 0 Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::volumioPushState Feb 09 09:41:30 volumiopi volumio[1004]: info: CorePlayQueue::getTrack 0 Feb 09 09:41:30 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 09 09:41:30 volumiopi volumio[1004]: info: Completed loading Core Plugins Feb 09 09:41:30 volumiopi volumio[1004]: info: Preparing to generate the ALSA configuration file Feb 09 09:41:31 volumiopi volumio[1004]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 09 09:41:31 volumiopi volumio[1004]: info: Reading ALSA contributions from plugins. Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::volumioGetState Feb 09 09:41:31 volumiopi volumio[1004]: info: CorePlayQueue::getTrack 0 Feb 09 09:41:31 volumiopi sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 09 09:41:31 volumiopi sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:31 volumiopi volumio[1004]: info: Discovery: adding 312eef85-7f60-4bdb-9865-815eb2763960 Feb 09 09:41:31 volumiopi volumio[1004]: info: Discovery: Found device Volumio Feb 09 09:41:31 volumiopi volumio[1004]: info: Discovery: Connecting to remote: 192.168.1.225 Feb 09 09:41:31 volumiopi volumio[1004]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 09 09:41:31 volumiopi volumio[1004]: info: Discovery: adding 1d893b2a-4274-4198-a883-178309294d29 Feb 09 09:41:31 volumiopi volumio[1004]: info: Discovery: Found device VolumioPi Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::volumioGetState Feb 09 09:41:31 volumiopi volumio[1004]: info: CorePlayQueue::getTrack 0 Feb 09 09:41:31 volumiopi systemd[1]: Started UPnP Renderer front-end to MPD. Feb 09 09:41:31 volumiopi sudo[1202]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:31 volumiopi volumio[1004]: info: Discovery: Connected to remote: 192.168.1.225 Feb 09 09:41:31 volumiopi volumio[1004]: info: Upmpdcli Daemon Started Feb 09 09:41:31 volumiopi volumio[1004]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 09 09:41:31 volumiopi volumio[1004]: info: Asound.conf file unchanged, so no further update is needed Feb 09 09:41:31 volumiopi volumio[1004]: info: Output device has changed, restarting MPD Feb 09 09:41:31 volumiopi volumio[1004]: info: Output device has changed, restarting Shairport Sync Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 09:41:31 volumiopi sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 09 09:41:31 volumiopi sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:31 volumiopi sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 09 09:41:31 volumiopi sudo[1206]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:31 volumiopi sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:31 volumiopi volumio[1004]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 09:41:31 volumiopi volumio[1004]: info: ___________ START PLUGINS ___________ Feb 09 09:41:31 volumiopi systemd[1]: Listening on mpd.socket. Feb 09 09:41:31 volumiopi systemd[1]: Starting Music Player Daemon... Feb 09 09:41:31 volumiopi volumio[1004]: info: ControllerMpd::onStart: Initializing MPD Feb 09 09:41:31 volumiopi volumio[1004]: info: Creating MPD Configuration file Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 09:41:31 volumiopi volumio[1004]: info: [1770597691908] CoreMusicLibrary::Adding element DLNAサーバー Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 09:41:31 volumiopi sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 09 09:41:31 volumiopi sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:31 volumiopi sudo[1229]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:31 volumiopi sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 09 09:41:31 volumiopi volumio[1004]: info: UPNP Browser: Client initialized successfully Feb 09 09:41:31 volumiopi sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:31 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 09:41:31 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 09 09:41:31 volumiopi systemd[1]: mpd.service: Succeeded. Feb 09 09:41:31 volumiopi systemd[1]: Stopped Music Player Daemon. Feb 09 09:41:32 volumiopi volumio[1004]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 09:41:32 volumiopi systemd[1]: Starting Music Player Daemon... Feb 09 09:41:32 volumiopi volumio[1004]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 09:41:32 volumiopi volumio[1004]: info: [1770597692143] CoreMusicLibrary::Adding element Last_100 Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 09:41:32 volumiopi volumio[1004]: info: [1770597692152] CoreMusicLibrary::Adding element Webradio Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 09:41:32 volumiopi volumio[1004]: info: Initializing BBC Radios Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 09:41:32 volumiopi sudo[1237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 09 09:41:32 volumiopi sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 09:41:32 volumiopi sudo[1237]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 09 09:41:32 volumiopi volumio[1004]: info: Discovery: Getting this device information Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::volumioGetState Feb 09 09:41:32 volumiopi sudo[1237]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:32 volumiopi volumio[1004]: info: CorePlayQueue::getTrack 0 Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 09:41:32 volumiopi volumio[1004]: info: [1770597692331] CoreMusicLibrary::Adding element Jellyfin Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 09:41:32 volumiopi volumio[1004]: Cannot find translation for source Jellyfin Feb 09 09:41:32 volumiopi volumio[1004]: info: [jellyfin] Initialized plugin with device info: {"id":"1d893b2a-4274-4198-a883-178309294d29","host":"http://192.168.1.153","name":"VolumioPi","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":100,"mute":false,"artist":"Thomas Bergersen & Two Steps from Hell","track":"To Glory","albumart":"http://192.168.1.153/albumart?cacheid=811&web=Thomas%20Bergersen%20%26%20Two%20Steps%20from%20Hell/Invincible/extralarge&path=%2FUSB%2F764E-2A5D%2FAmazon%20Music%2FTwo%20Steps%20From%20Hell%2FInvincible&metadata=false"}} Feb 09 09:41:32 volumiopi volumio[1004]: info: Loading i18n strings for locale ja Feb 09 09:41:32 volumiopi volumio[1004]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:32 volumiopi volumio[1004]: info: FusionDsp - mixtype--------------------- None Feb 09 09:41:32 volumiopi sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 09 09:41:32 volumiopi sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:32 volumiopi volumio[1004]: info: Configuring GPIO pins Feb 09 09:41:32 volumiopi systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Feb 09 09:41:32 volumiopi volumio[1004]: info: Soft shutdown GPIO 540 binding... OK Feb 09 09:41:32 volumiopi volumio[1004]: info: Hardware button GPIO 553 binding... OK Feb 09 09:41:32 volumiopi systemd[1]: Starting Logitech Media Server Daemon... Feb 09 09:41:32 volumiopi volumio[1004]: info: Boot OK GPIO 558 binding... OK Feb 09 09:41:32 volumiopi fsct-plugin[1004]: Starting service tasks Feb 09 09:41:32 volumiopi fsct-plugin[1004]: Service tasks started successfully Feb 09 09:41:32 volumiopi fsct-plugin[1004]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Feb 09 09:41:32 volumiopi fsct-plugin[1004]: Failed to initialize device 0424:2514: BOS descriptor not available, unsupported usb version 2.00 Feb 09 09:41:32 volumiopi fsct-plugin[1004]: Failed to initialize device 0424:2514: BOS descriptor not available, unsupported usb version 2.00 Feb 09 09:41:32 volumiopi fsct-plugin[1004]: Failed to initialize device 0424:7800: IO error -> Permission denied (os error 13) Feb 09 09:41:32 volumiopi systemd[1]: Started Logitech Media Server Daemon. Feb 09 09:41:32 volumiopi sudo[1254]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:32 volumiopi systemd[1282]: logitechmediaserver.service: Failed to execute command: No such file or directory Feb 09 09:41:32 volumiopi systemd[1282]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Feb 09 09:41:32 volumiopi systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Feb 09 09:41:32 volumiopi systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 09:41:32 volumiopi volumio[1004]: info: [1770597692717] CoreMusicLibrary::Adding element Randomizer Feb 09 09:41:32 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 09:41:32 volumiopi volumio[1004]: Cannot find translation for source Jellyfin Feb 09 09:41:32 volumiopi volumio[1004]: Cannot find translation for source Randomizer Feb 09 09:41:32 volumiopi volumio[1004]: info: Volumio Calling Home Feb 09 09:41:32 volumiopi volumio[1004]: info: Preparing to generate the ALSA configuration file Feb 09 09:41:33 volumiopi volumio[1004]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 09 09:41:33 volumiopi volumio[1004]: info: Reading ALSA contributions from plugins. Feb 09 09:41:33 volumiopi volumio[1004]: info: logitechmediaserver started Feb 09 09:41:33 volumiopi volumio[1004]: info: MPD Permissions set Feb 09 09:41:33 volumiopi volumio[1004]: info: MPD Permissions set Feb 09 09:41:33 volumiopi volumio[1004]: info: CoreCommandRouter::volumioGetState Feb 09 09:41:33 volumiopi volumio[1004]: info: CorePlayQueue::getTrack 0 Feb 09 09:41:33 volumiopi volumio[1004]: info: FSCT Started Feb 09 09:41:35 volumiopi volumio[1004]: info: Volumio called home Feb 09 09:41:36 volumiopi volumio[1004]: info: camilladsp service started and running in background, instance 1 Feb 09 09:41:36 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 09:41:36 volumiopi volumio[1004]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 09 09:41:36 volumiopi volumio[1004]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Feb 09 09:41:36 volumiopi volumio[1004]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Feb 09 09:41:36 volumiopi volumio[1004]: info: FusionDsp loaded Feb 09 09:41:36 volumiopi volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 09:41:36 volumiopi sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 09 09:41:36 volumiopi sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 09:41:36 volumiopi volumio[1004]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 09 09:41:36 volumiopi volumio[1004]: info: Adding Signal Path Element [object Object] Feb 09 09:41:36 volumiopi volumio[1004]: info: Adding fusiondspeq DSP Signal Path Element Feb 09 09:41:36 volumiopi volumio[1004]: info: FusionDsp - ---- installed callbackRead Feb 09 09:41:36 volumiopi volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 09:41:36 volumiopi volumio[1004]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Feb 09 09:41:36 volumiopi volumio[1004]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Feb 09 09:41:36 volumiopi volumio[1004]: at onErrorNT (internal/child_process.js:465:16) Feb 09 09:41:36 volumiopi volumio[1004]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 09 09:41:36 volumiopi volumio[1004]: at runNextTicks (internal/process/task_queues.js:62:3) Feb 09 09:41:36 volumiopi volumio[1004]: at listOnTimeout (internal/timers.js:523:9) Feb 09 09:41:36 volumiopi volumio[1004]: at processTimers (internal/timers.js:497:7) { Feb 09 09:41:36 volumiopi volumio[1004]: errno: -2, Feb 09 09:41:36 volumiopi volumio[1004]: code: 'ENOENT', Feb 09 09:41:36 volumiopi volumio[1004]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Feb 09 09:41:36 volumiopi volumio[1004]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Feb 09 09:41:36 volumiopi volumio[1004]: spawnargs: [ Feb 09 09:41:36 volumiopi volumio[1004]: '-p', Feb 09 09:41:36 volumiopi volumio[1004]: 9876, Feb 09 09:41:36 volumiopi volumio[1004]: '-o', Feb 09 09:41:36 volumiopi volumio[1004]: '/tmp/camilladsp.log', Feb 09 09:41:36 volumiopi volumio[1004]: '-l', Feb 09 09:41:36 volumiopi volumio[1004]: 'warn', Feb 09 09:41:36 volumiopi volumio[1004]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Feb 09 09:41:36 volumiopi volumio[1004]: ] Feb 09 09:41:36 volumiopi volumio[1004]: } Feb 09 09:41:36 volumiopi volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 09:41:36 volumiopi systemd[1]: Started FusionDsp Daemon. Feb 09 09:41:36 volumiopi sudo[1298]: pam_unix(sudo:session): session closed for user root Feb 09 09:41:38 volumiopi volumio[1301]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Feb 09 09:41:38 volumiopi systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Feb 09 09:41:38 volumiopi systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Feb 09 09:41:38 volumiopi sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-09 09:40 Feb 09 09:41:38 volumiopi sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"