-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Fri 2025-10-31 00:19:06 KST. -- Oct 31 00:18:00 volumio volumio-remote-updater[609]: Error: No active session Oct 31 00:18:00 volumio volumio-remote-updater[609]: [2025-10-31 00:18:00] [info] asio async_connect error: system:111 (Connection refused) Oct 31 00:18:00 volumio volumio-remote-updater[609]: [2025-10-31 00:18:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 31 00:18:00 volumio volumio-remote-updater[609]: [2025-10-31 00:18:00] [error] handle_connect error: Underlying Transport Error Oct 31 00:18:00 volumio volumio-time-update[611]: volumio-time-update-util: Date not found in response Oct 31 00:18:00 volumio volumio-time-update[611]: volumio-time-update-util: Retrying in 5 seconds... Oct 31 00:18:00 volumio systemd[1]: Started Login Service. Oct 31 00:18:00 volumio systemd[1]: Started triggerhappy global hotkey daemon. Oct 31 00:18:00 volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Oct 31 00:18:00 volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Oct 31 00:18:00 volumio avahi-daemon[618]: Successfully called chroot(). Oct 31 00:18:00 volumio avahi-daemon[618]: Successfully dropped remaining capabilities. Oct 31 00:18:00 volumio systemd[1]: Failed to start dhcpcd on all interfaces. Oct 31 00:18:00 volumio wpa_supplicant[617]: Successfully initialized wpa_supplicant Oct 31 00:18:00 volumio systemd[1]: Started Save/Restore Sound Card State. Oct 31 00:18:00 volumio systemd[1]: hciuart.service: Succeeded. Oct 31 00:18:00 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Oct 31 00:18:00 volumio avahi-daemon[618]: No service file found in /etc/avahi/services. Oct 31 00:18:00 volumio avahi-daemon[618]: Network interface enumeration completed. Oct 31 00:18:00 volumio avahi-daemon[618]: Server startup complete. Host name is volumio.local. Local service cookie is 2564221174. Oct 31 00:18:00 volumio systemd[1]: systemd-rfkill.service: Succeeded. Oct 31 00:18:01 volumio volumio[610]: Could not open config: /tmp/upmpdcli.conf Oct 31 00:18:01 volumio systemd[1]: Started Raise network interfaces. Oct 31 00:18:01 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 31 00:18:01 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 31 00:18:01 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 31 00:18:01 volumio systemd[1]: Started WPA supplicant. Oct 31 00:18:01 volumio systemd[1]: Listening on mpd.socket. Oct 31 00:18:01 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Oct 31 00:18:01 volumio systemd[1]: Reached target Sound Card. Oct 31 00:18:01 volumio systemd[1]: Reached target Network. Oct 31 00:18:01 volumio systemd[1]: Starting Network Time Service... Oct 31 00:18:01 volumio systemd[1]: Reached target Network is Online. Oct 31 00:18:01 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Oct 31 00:18:01 volumio systemd[1]: Starting /etc/rc.local Compatibility... Oct 31 00:18:01 volumio systemd[1]: Starting Daily apt download activities... Oct 31 00:18:01 volumio systemd[1]: Starting Samba NMB Daemon... Oct 31 00:18:01 volumio systemd[1]: Starting Permit User Sessions... Oct 31 00:18:01 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 31 00:18:01 volumio systemd[1]: Starting Music Player Daemon... Oct 31 00:18:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 31 00:18:01 volumio systemd[1]: Started /etc/rc.local Compatibility. Oct 31 00:18:01 volumio bthelper[705]: Raspberry Pi BDADDR already set Oct 31 00:18:01 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Oct 31 00:18:01 volumio systemd[1]: Starting Bluetooth service... Oct 31 00:18:01 volumio systemd[1]: Started Permit User Sessions. Oct 31 00:18:01 volumio systemd[1]: Starting Hold until boot process finishes up... Oct 31 00:18:01 volumio loadcpufreq[621]: Loading cpufreq kernel modules...done (none). Oct 31 00:18:01 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 31 00:18:01 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 31 00:18:01 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 31 00:18:01 volumio ifplugd[709]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Oct 31 00:18:01 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 31 00:18:01 volumio systemd[1]: Started Hold until boot process finishes up. Oct 31 00:18:01 volumio systemd[1]: plymouth-quit.service: Succeeded. Oct 31 00:18:01 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Oct 31 00:18:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 177 (n/a). Oct 31 00:18:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 177 (n/a). Oct 31 00:18:02 volumio systemd[1]: Started Getty on tty1. Oct 31 00:18:02 volumio systemd[1]: Reached target Login Prompts. Oct 31 00:18:02 volumio ifplugd(eth0)[771]: ifplugd 0.28 initializing. Oct 31 00:18:02 volumio ifplugd(eth0)[771]: Using interface eth0/B8:27:EB:F7:AC:2D with driver (version: 6.6.62-v7+) Oct 31 00:18:02 volumio ifplugd(eth0)[771]: Using detection mode: SIOCETHTOOL Oct 31 00:18:02 volumio ifplugd(eth0)[771]: Initialization complete, link beat detected. Oct 31 00:18:02 volumio systemd[1]: iptables.service: Succeeded. Oct 31 00:18:02 volumio ifplugd(eth0)[771]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 31 00:18:02 volumio cpufrequtils[757]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 31 00:18:02 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 31 00:18:02 volumio ntpd[707]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 31 00:18:02 volumio ntpd[707]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Oct 31 00:18:02 volumio systemd[1]: Started Network Time Service. Oct 31 00:18:02 volumio ntpd[785]: proto: precision = 1.407 usec (-19) Oct 31 00:18:02 volumio ntpd[785]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 31 00:18:02 volumio ntpd[785]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 31 00:18:02 volumio ntpd[785]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 307 days ago Oct 31 00:18:02 volumio ntpd[785]: Listen and drop on 0 v6wildcard [::]:123 Oct 31 00:18:02 volumio ntpd[785]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 31 00:18:02 volumio ntpd[785]: Listen normally on 2 lo 127.0.0.1:123 Oct 31 00:18:02 volumio ntpd[785]: Listening on routing socket on fd #19 for interface updates Oct 31 00:18:02 volumio ntpd[785]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 31 00:18:02 volumio ntpd[785]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 31 00:18:02 volumio bluetoothd[750]: Bluetooth daemon 5.50 Oct 31 00:18:02 volumio bluetoothd[750]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 31 00:18:02 volumio ifplugd(eth0)[771]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Oct 31 00:18:02 volumio systemd[1]: Started Bluetooth service. Oct 31 00:18:02 volumio systemd[1]: Reached target Bluetooth. Oct 31 00:18:02 volumio bluetoothd[750]: Starting SDP server Oct 31 00:18:02 volumio bluetoothd[750]: Excluding (cli) sap Oct 31 00:18:02 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 31 00:18:02 volumio kernel: Bluetooth: BNEP filters: protocol multicast Oct 31 00:18:02 volumio kernel: Bluetooth: BNEP socket layer initialized Oct 31 00:18:02 volumio kernel: Bluetooth: MGMT ver 1.22 Oct 31 00:18:02 volumio dbus-daemon[616]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=750 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 31 00:18:02 volumio bluetoothd[750]: Bluetooth management interface 1.22 initialized Oct 31 00:18:02 volumio bluetoothd[750]: Failed to set privacy: Rejected (0x0b) Oct 31 00:18:02 volumio sudo[723]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 31 00:18:02 volumio sudo[723]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:02 volumio sudo[723]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 31 00:18:02 volumio sudo[723]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:03 volumio systemd[1]: Starting Hostname Service... Oct 31 00:18:03 volumio ntpd[785]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 31 00:18:03 volumio dbus-daemon[616]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 31 00:18:03 volumio systemd[1]: Started Hostname Service. Oct 31 00:18:04 volumio sh[501]: eth0: leased 192.168.29.144 for 86400 seconds Oct 31 00:18:04 volumio sh[501]: eth0: adding route to 192.168.29.0/24 Oct 31 00:18:04 volumio sh[501]: eth0: adding default route via 192.168.29.1 Oct 31 00:18:04 volumio dhcpcd[548]: eth0: leased 192.168.29.144 for 86400 seconds Oct 31 00:18:04 volumio avahi-daemon[618]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.29.144. Oct 31 00:18:04 volumio dhcpcd[548]: eth0: adding route to 192.168.29.0/24 Oct 31 00:18:04 volumio avahi-daemon[618]: New relevant interface eth0.IPv4 for mDNS. Oct 31 00:18:04 volumio dhcpcd[548]: eth0: adding default route via 192.168.29.1 Oct 31 00:18:04 volumio avahi-daemon[618]: Registering new address record for 192.168.29.144 on eth0.IPv4. Oct 31 00:18:04 volumio ntpd[785]: Soliciting pool server 175.210.18.47 Oct 31 00:18:04 volumio sh[501]: forked to background, child pid 840 Oct 31 00:18:04 volumio dhcpcd[548]: forked to background, child pid 840 Oct 31 00:18:04 volumio nmbd[736]: [2025/10/31 00:18:04.698303, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 31 00:18:04 volumio nmbd[736]: started asyncdns process 868 Oct 31 00:18:04 volumio nmbd[736]: [2025/10/31 00:18:04.716006, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 31 00:18:04 volumio nmbd[736]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 31 00:18:04 volumio systemd[1]: Started Samba NMB Daemon. Oct 31 00:18:04 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 31 00:18:04 volumio sh[501]: eth0=eth0 Oct 31 00:18:04 volumio ifplugd(eth0)[771]: client: ifup: interface eth0 already configured Oct 31 00:18:05 volumio ifplugd(eth0)[771]: Program executed successfully. Oct 31 00:18:05 volumio ifplugd[709]: start eth0...done. Oct 31 00:18:05 volumio systemd[1]: Started LSB: Brings up/down network automatically. Oct 31 00:18:05 volumio ntpd[785]: Listen normally on 3 eth0 192.168.29.144:123 Oct 31 00:18:05 volumio ntpd[785]: new interface(s) found: waking up resolver Oct 31 00:18:05 volumio volumio-remote-updater[609]: [2025-10-31 00:18:05] [info] asio async_connect error: system:111 (Connection refused) Oct 31 00:18:05 volumio volumio-remote-updater[609]: [2025-10-31 00:18:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 31 00:18:05 volumio volumio-remote-updater[609]: [2025-10-31 00:18:05] [error] handle_connect error: Underlying Transport Error Oct 31 00:18:05 volumio ntpd[785]: Soliciting pool server 158.247.202.103 Oct 31 00:18:05 volumio systemd[1]: apt-daily.service: Succeeded. Oct 31 00:18:05 volumio systemd[1]: Started Daily apt download activities. Oct 31 00:18:05 volumio wireless.js[595]: Cleaning previous... Oct 31 00:18:05 volumio winbindd[870]: [2025/10/31 00:18:05.788479, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 31 00:18:05 volumio winbindd[870]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 31 00:18:05 volumio winbindd[870]: [2025/10/31 00:18:05.836292, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 31 00:18:05 volumio winbindd[870]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 31 00:18:05 volumio volumio-time-update[611]: volumio-time-update-util: Fetching time from Volumio... Oct 31 00:18:05 volumio systemd[1]: Started Samba Winbind Daemon. Oct 31 00:18:05 volumio systemd[1]: Starting Samba SMB Daemon... Oct 31 00:18:05 volumio sudo[912]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 31 00:18:05 volumio sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:05 volumio sudo[912]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:06 volumio sudo[914]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 31 00:18:06 volumio sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:06 volumio ntpd[785]: Soliciting pool server 175.195.167.194 Oct 31 00:18:06 volumio volumio-time-update[611]: volumio-time-update-util: Setting system time to: 2025-10-31 00:18:31 Oct 31 00:18:06 volumio ntpd[785]: Soliciting pool server 175.210.18.47 Oct 31 00:18:06 volumio sudo[914]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:06 volumio bthelper[705]: Changing power off succeeded Oct 31 00:18:06 volumio bthelper[705]: [CHG] Controller B8:27:EB:5D:06:87 Powered: no Oct 31 00:18:06 volumio bthelper[705]: [CHG] Controller B8:27:EB:5D:06:87 Discovering: no Oct 31 00:18:06 volumio bthelper[705]: [CHG] Controller B8:27:EB:5D:06:87 Class: 0x00000000 Oct 31 00:18:06 volumio sudo[919]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-10-31 00:18:31 Oct 31 00:18:06 volumio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:06 volumio dbus-daemon[616]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=922 comm="timedatectl set-time 2025-10-31 00:18:31 ") Oct 31 00:18:06 volumio wireless.js[595]: Stopped aP Oct 31 00:18:06 volumio sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 31 00:18:06 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 31 00:18:06 volumio sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:06 volumio sudo[932]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:06 volumio systemd[1]: Starting Time & Date Service... Oct 31 00:18:06 volumio bthelper[705]: [CHG] Controller B8:27:EB:5D:06:87 Class: 0x0000041c Oct 31 00:18:06 volumio bthelper[705]: Changing power on succeeded Oct 31 00:18:07 volumio sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 31 00:18:07 volumio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:07 volumio sudo[938]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:07 volumio systemd[1]: Started Samba SMB Daemon. Oct 31 00:18:07 volumio smbd[915]: [2025/10/31 00:18:07.104492, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 31 00:18:07 volumio smbd[915]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 31 00:18:07 volumio sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 31 00:18:07 volumio sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:07 volumio sudo[946]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:07 volumio sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 31 00:18:07 volumio sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:07 volumio dbus-daemon[616]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 31 00:18:07 volumio systemd[1]: Started Time & Date Service. Oct 31 00:18:07 volumio ntpd[785]: Soliciting pool server 121.174.142.82 Oct 31 00:18:07 volumio ntpd[785]: Soliciting pool server 2001:678:8::123 Oct 31 00:18:31 volumio systemd-timedated[931]: Changed local time to Fri Oct 31 00:18:31 2025 Oct 31 00:18:31 volumio sudo[919]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:31 volumio volumio-time-update[611]: volumio-time-update-util: System time updated successfully. Oct 31 00:18:31 volumio systemd[1]: Started Volumio Time Update Utility. Oct 31 00:18:33 volumio mpd[799]: Oct 31 00:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 31 00:18:33 volumio sudo[950]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:33 volumio wireless.js[595]: SETTING APPROPRIATE REG DOMAIN: KR Oct 31 00:18:33 volumio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 31 00:18:33 volumio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:33 volumio sudo[955]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:33 volumio sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set KR Oct 31 00:18:33 volumio sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:33 volumio sudo[957]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:33 volumio wireless.js[595]: SUCCESSFULLY SET NEW REGDOMAIN: KR Oct 31 00:18:33 volumio sudo[964]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 31 00:18:33 volumio sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:34 volumio sudo[964]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:34 volumio sudo[966]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 31 00:18:34 volumio sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:34 volumio sudo[966]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:34 volumio avahi-daemon[618]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 31 00:18:34 volumio avahi-daemon[618]: New relevant interface wlan0.IPv4 for mDNS. Oct 31 00:18:34 volumio avahi-daemon[618]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Oct 31 00:18:34 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 31 00:18:34 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Oct 31 00:18:34 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Oct 31 00:18:34 volumio dnsmasq[972]: dnsmasq: syntax check OK. Oct 31 00:18:34 volumio hostapd[973]: Configuration file: /etc/hostapd/hostapd.conf Oct 31 00:18:34 volumio hostapd[973]: wlan0: Could not connect to kernel driver Oct 31 00:18:34 volumio hostapd[973]: Using interface wlan0 with hwaddr b8:27:eb:a2:f9:78 and ssid "Volumio-A8068" Oct 31 00:18:34 volumio dnsmasq[979]: started, version 2.80 cachesize 150 Oct 31 00:18:34 volumio dnsmasq[979]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Oct 31 00:18:34 volumio dnsmasq-dhcp[979]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Oct 31 00:18:34 volumio dnsmasq[979]: reading /etc/resolv.conf Oct 31 00:18:34 volumio dnsmasq[979]: using nameserver 192.168.29.1#53 Oct 31 00:18:34 volumio dnsmasq[979]: using nameserver 208.67.222.222#53 Oct 31 00:18:34 volumio dnsmasq[979]: using nameserver 208.67.220.220#53 Oct 31 00:18:34 volumio dnsmasq[979]: read /etc/hosts - 5 addresses Oct 31 00:18:35 volumio hostapd[973]: wlan0: interface state UNINITIALIZED->ENABLED Oct 31 00:18:35 volumio hostapd[973]: wlan0: AP-ENABLED Oct 31 00:18:35 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Oct 31 00:18:35 volumio iwconfig[987]: Error for wireless request "Set Power Management" (8B2C) : Oct 31 00:18:35 volumio iwconfig[987]: SET failed on device wlan0 ; Invalid argument. Oct 31 00:18:35 volumio systemd[1]: wireless.service: Succeeded. Oct 31 00:18:35 volumio systemd[1]: Started Wireless Services. Oct 31 00:18:35 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Oct 31 00:18:35 volumio systemd[1]: Reached target Host and Network Name Lookups. Oct 31 00:18:35 volumio systemd[1]: Started Volumio Backend Module. Oct 31 00:18:35 volumio systemd[1]: Started Volumio Cpu Tweaker. Oct 31 00:18:35 volumio systemd[1]: Reached target Multi-User System. Oct 31 00:18:35 volumio systemd[1]: Reached target Graphical Interface. Oct 31 00:18:35 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 31 00:18:35 volumio volumio-cpu-tweak[989]: Setting RT Priority for mpd Oct 31 00:18:35 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 31 00:18:35 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 31 00:18:35 volumio volumio-cpu-tweak[989]: Setting MPD Affinity Oct 31 00:18:35 volumio volumio-cpu-tweak[989]: pid 799's current affinity mask: f Oct 31 00:18:35 volumio volumio-cpu-tweak[989]: pid 799's new affinity mask: 3 Oct 31 00:18:35 volumio volumio-cpu-tweak[989]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 31 00:18:35 volumio volumio-cpu-tweak[989]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 31 00:18:35 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 31 00:18:36 volumio systemd[1]: Started Music Player Daemon. Oct 31 00:18:36 volumio systemd[1]: Startup finished in 14.489s (kernel) + 31.546s (userspace) = 46.035s. Oct 31 00:18:36 volumio mpd_monitor.sh[607]: MPD Monitor Service: MPD restarted due to no mpc output. Oct 31 00:18:36 volumio volumio-remote-updater[609]: [2025-10-31 00:18:36] [info] asio async_connect error: system:111 (Connection refused) Oct 31 00:18:36 volumio volumio-remote-updater[609]: [2025-10-31 00:18:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 31 00:18:36 volumio volumio-remote-updater[609]: [2025-10-31 00:18:36] [error] handle_connect error: Underlying Transport Error Oct 31 00:18:36 volumio ntpd[785]: Listen normally on 4 wlan0 192.168.211.1:123 Oct 31 00:18:36 volumio ntpd[785]: new interface(s) found: waking up resolver Oct 31 00:18:39 volumio ntpd[785]: receive: Unexpected origin timestamp 0xecae004d.fa77e03e does not match aorg 0000000000.00000000 from server@158.247.202.103 xmt 0xecae004f.d784f5fe Oct 31 00:18:41 volumio volumio[988]: info: ------------------------------------------- Oct 31 00:18:41 volumio volumio[988]: info: ----- Volumio3 ---- Oct 31 00:18:41 volumio volumio[988]: info: ------------------------------------------- Oct 31 00:18:41 volumio volumio[988]: info: ----- System startup ---- Oct 31 00:18:41 volumio volumio[988]: info: ------------------------------------------- Oct 31 00:18:43 volumio volumio[988]: info: MYVOLUMIO Environment detected Oct 31 00:18:44 volumio volumio[988]: info: Plugin folders cleanup Oct 31 00:18:44 volumio volumio[988]: info: Scanning into folder /volumio/app/plugins/ Oct 31 00:18:44 volumio volumio[988]: info: Scanning category audio_interface Oct 31 00:18:44 volumio volumio[988]: info: Scanning category miscellanea Oct 31 00:18:44 volumio volumio[988]: info: Scanning category music_service Oct 31 00:18:44 volumio volumio[988]: info: Scanning category plugins.json Oct 31 00:18:44 volumio volumio[988]: info: Scanning category system_controller Oct 31 00:18:44 volumio volumio[988]: info: Scanning category user_interface Oct 31 00:18:44 volumio volumio[988]: info: Scanning into folder /data/plugins/ Oct 31 00:18:44 volumio volumio[988]: info: Scanning category music_service Oct 31 00:18:44 volumio volumio[988]: info: Plugin folders cleanup completed Oct 31 00:18:44 volumio volumio[988]: info: ------------------------------------------- Oct 31 00:18:44 volumio volumio[988]: info: ----- Core plugins startup ---- Oct 31 00:18:44 volumio volumio[988]: info: ------------------------------------------- Oct 31 00:18:44 volumio volumio[988]: info: Loading plugins from folder /volumio/app/plugins/ Oct 31 00:18:44 volumio volumio[988]: info: Adding plugin upnp to MyMusic Plugins Oct 31 00:18:44 volumio volumio[988]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 31 00:18:44 volumio volumio[988]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 31 00:18:44 volumio volumio[988]: info: Loading plugins from folder /data/plugins/ Oct 31 00:18:44 volumio volumio[988]: info: Loading plugin "system"... Oct 31 00:18:44 volumio volumio[988]: info: Loading plugin "appearance"... Oct 31 00:18:46 volumio volumio[988]: info: Loading plugin "network"... Oct 31 00:18:46 volumio volumio[988]: info: Refreshing Cached IP Addresses Oct 31 00:18:47 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 31 00:18:47 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:47 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:47 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 31 00:18:47 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:47 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:47 volumio volumio[988]: info: Loading plugin "services"... Oct 31 00:18:47 volumio volumio[988]: info: Loading plugin "alsa_controller"... Oct 31 00:18:47 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 31 00:18:47 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:47 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 31 00:18:47 volumio volumio[988]: info: Loading plugin "wizard"... Oct 31 00:18:47 volumio volumio[988]: info: Loading plugin "networkfs"... Oct 31 00:18:47 volumio volumio[988]: info: Starting Udev Watcher for removable devices Oct 31 00:18:47 volumio volumio[988]: info: Ignoring mount for partition: boot Oct 31 00:18:47 volumio volumio[988]: info: Ignoring mount for partition: volumio Oct 31 00:18:47 volumio volumio[988]: info: Ignoring mount for partition: volumio_data Oct 31 00:18:47 volumio volumio[988]: error: Cannot associate FS Label, not mounting Oct 31 00:18:47 volumio volumio[988]: info: Mounting Device volumio1 Oct 31 00:18:47 volumio sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda5 /mnt/USB/volumio1 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 31 00:18:47 volumio sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:48 volumio volumio-remote-updater[609]: [2025-10-31 00:18:48] [connect] Successful connection Oct 31 00:18:49 volumio ntfs-3g[1058]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 31 00:18:49 volumio ntfs-3g[1058]: Mounted /dev/sda5 (Read-Write, label "volumio1", NTFS 3.1) Oct 31 00:18:49 volumio ntfs-3g[1058]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 31 00:18:49 volumio ntfs-3g[1058]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda5,blkdev,blksize=4096 Oct 31 00:18:49 volumio ntfs-3g[1058]: Global ownership and permissions enforced, configuration type 7 Oct 31 00:18:49 volumio sudo[1055]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:49 volumio volumio[988]: info: Mounting Device volumio2 Oct 31 00:18:49 volumio sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/volumio2 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 31 00:18:49 volumio sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:51 volumio ntfs-3g[1067]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 31 00:18:51 volumio ntfs-3g[1067]: Mounted /dev/sdb1 (Read-Write, label "volumio2", NTFS 3.1) Oct 31 00:18:51 volumio ntfs-3g[1067]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 31 00:18:51 volumio ntfs-3g[1067]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096 Oct 31 00:18:51 volumio ntfs-3g[1067]: Global ownership and permissions enforced, configuration type 7 Oct 31 00:18:51 volumio sudo[1064]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:51 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 31 00:18:51 volumio volumio[988]: info: Loading plugin "volumio_command_line_client"... Oct 31 00:18:51 volumio volumio[988]: info: Loading plugin "upnp"... Oct 31 00:18:51 volumio volumio[988]: info: [1761837531107] Starting Upmpd Daemon Oct 31 00:18:51 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 31 00:18:51 volumio volumio[988]: info: Loading plugin "my_music"... Oct 31 00:18:51 volumio volumio[988]: info: Loading plugin "mpd"... Oct 31 00:18:51 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:51 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 31 00:18:51 volumio volumio[988]: info: Loading plugin "upnp_browser"... Oct 31 00:18:52 volumio volumio[988]: info: Loading plugin "alarm-clock"... Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "airplay_emulation"... Oct 31 00:18:53 volumio volumio[988]: info: Starting Shairport Sync Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "last_100"... Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "webradio"... Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "i2s_dacs"... Oct 31 00:18:53 volumio volumio[988]: info: I2S DAC not set, start Auto-detection Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "volumiodiscovery"... Oct 31 00:18:53 volumio volumio[988]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 31 00:18:53 volumio volumio[988]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 31 00:18:53 volumio volumio[988]: *** WARNING *** For more information see Oct 31 00:18:53 volumio node[988]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 31 00:18:53 volumio volumio[988]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 31 00:18:53 volumio volumio[988]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 31 00:18:53 volumio volumio[988]: *** WARNING *** For more information see Oct 31 00:18:53 volumio node[988]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 31 00:18:53 volumio node[988]: *** WARNING *** For more information see Oct 31 00:18:53 volumio node[988]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 31 00:18:53 volumio node[988]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 31 00:18:53 volumio node[988]: *** WARNING *** For more information see Oct 31 00:18:53 volumio volumio[988]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 31 00:18:53 volumio volumio[988]: info: Discovery: Started advertising with name: Volumio Oct 31 00:18:53 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "outputs"... Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "albumart"... Oct 31 00:18:53 volumio volumio[988]: info: Plugin example_plugin is not enabled Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "inputs"... Oct 31 00:18:53 volumio volumio[988]: info: Loading plugin "updater_comm"... Oct 31 00:18:54 volumio volumio[988]: info: Plugin mpdemulation is not enabled Oct 31 00:18:54 volumio volumio[988]: info: Loading plugin "rest_api"... Oct 31 00:18:54 volumio volumio[988]: info: Loading plugin "websocket"... Oct 31 00:18:54 volumio volumio[988]: info: Starting Socket.io Server version 2.3.0 Oct 31 00:18:54 volumio volumio[988]: info: Loading plugin "personal_radio"... Oct 31 00:18:54 volumio volumio[988]: Forking 3 albumart workers Oct 31 00:18:55 volumio volumio[988]: info: ControllerPersonalRadio::constructor Oct 31 00:18:56 volumio volumio[988]: info: Loading i18n strings for locale ko Oct 31 00:18:56 volumio volumio[988]: Updating browse sources language Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 00:18:56 volumio volumio[988]: Starting albumart workers Oct 31 00:18:56 volumio volumio[988]: Starting albumart workers Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::initPlayerControls Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:56 volumio volumio[988]: Starting albumart workers Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 00:18:56 volumio volumio[988]: Express server listening on port 3000 Oct 31 00:18:56 volumio volumio[988]: [Metrics] WebUI: 16s 558.06ms Oct 31 00:18:56 volumio volumio[988]: info: CoreStateMachine::resetVolumioState Oct 31 00:18:56 volumio volumio[988]: info: CoreStateMachine::getcurrentVolume Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::volumioRetrievevolume Oct 31 00:18:56 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 00:18:57 volumio volumio[988]: info: Volumio Network Manager: Network status updated: 1 Oct 31 00:18:57 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.29.144 from 192.168.29.223 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 31 00:18:57 volumio volumio[988]: info: Reloading queue from file Oct 31 00:18:57 volumio volumio[988]: info: CoreStateMachine::setRepeat null single undefined Oct 31 00:18:57 volumio volumio[988]: info: CoreStateMachine::pushState Oct 31 00:18:57 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:18:57 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 00:18:57 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 31 00:18:57 volumio volumio[988]: info: CoreStateMachine::setRandom null Oct 31 00:18:57 volumio volumio[988]: info: CoreStateMachine::pushState Oct 31 00:18:57 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:18:57 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 31 00:18:57 volumio volumio[988]: info: Setting Device type: Raspberry PI Oct 31 00:18:57 volumio volumio[988]: info: VolumeController:: Volume=100 Mute =false Oct 31 00:18:57 volumio volumio[988]: info: CoreStateMachine::pushState Oct 31 00:18:57 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:18:57 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 00:18:57 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 31 00:18:57 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock Oct 31 00:18:57 volumio volumio[988]: info: CorePlayQueue::getTrackBlock Oct 31 00:18:57 volumio volumio[988]: info: CoreCommandRouter::volumioRetrievevolume Oct 31 00:18:57 volumio volumio[988]: info: Discovery: adding fce7ef04-e9ac-4d20-b1b9-8b209acdf8ce Oct 31 00:18:57 volumio volumio[988]: info: Discovery: Found device Volumio Oct 31 00:18:57 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Oct 31 00:18:57 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:18:58 volumio volumio[988]: info: Discovery: this is already registered, fce7ef04-e9ac-4d20-b1b9-8b209acdf8ce Oct 31 00:18:58 volumio volumio[988]: info: Discovery: Found device Volumio Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Oct 31 00:18:58 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:18:58 volumio volumio[988]: info: Completed loading Core Plugins Oct 31 00:18:58 volumio volumio[988]: info: Preparing to generate the ALSA configuration file Oct 31 00:18:58 volumio volumio[988]: info: VolumeController:: Volume=100 Mute =false Oct 31 00:18:58 volumio volumio[988]: info: CoreStateMachine::pushState Oct 31 00:18:58 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 31 00:18:58 volumio volumio[988]: info: Asound.conf file unchanged, so no further update is needed Oct 31 00:18:58 volumio volumio[988]: info: Output device has changed, restarting MPD Oct 31 00:18:58 volumio volumio[988]: info: Output device has changed, restarting Shairport Sync Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 00:18:58 volumio sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 31 00:18:58 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:58 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:58 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 31 00:18:58 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:58 volumio volumio[988]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 00:18:58 volumio volumio[988]: info: ___________ START PLUGINS ___________ Oct 31 00:18:58 volumio systemd[1]: Stopping Music Player Daemon... Oct 31 00:18:58 volumio volumio[988]: info: ControllerMpd::onStart: Initializing MPD Oct 31 00:18:58 volumio volumio[988]: info: Creating MPD Configuration file Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 31 00:18:58 volumio volumio[988]: info: [1761837538363] CoreMusicLibrary::Adding element 미디어 서버 Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 00:18:58 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 31 00:18:58 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:58 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 31 00:18:58 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 00:18:58 volumio volumio[988]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 00:18:58 volumio volumio[988]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 31 00:18:58 volumio volumio[988]: info: [1761837538603] CoreMusicLibrary::Adding element Last_100 Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 31 00:18:58 volumio volumio[988]: info: [1761837538610] CoreMusicLibrary::Adding element Webradio Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 31 00:18:58 volumio volumio[988]: info: Initializing BBC Radios Oct 31 00:18:58 volumio sudo[1162]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 31 00:18:58 volumio volumio[988]: info: [1761837538926] CoreMusicLibrary::Adding element 개인 라디오 Oct 31 00:18:58 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 00:18:58 volumio volumio[988]: Cannot find translation for source 개인 라디오 Oct 31 00:18:58 volumio volumio[988]: info: Volumio Calling Home Oct 31 00:18:58 volumio systemd[1]: mpd.service: Succeeded. Oct 31 00:18:58 volumio systemd[1]: Stopped Music Player Daemon. Oct 31 00:18:59 volumio systemd[1]: Starting Music Player Daemon... Oct 31 00:18:59 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 31 00:18:59 volumio volumio[988]: info: MPD Permissions set Oct 31 00:18:59 volumio volumio[988]: info: MPD Permissions set Oct 31 00:18:59 volumio sudo[1184]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 31 00:18:59 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:59 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:59 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 31 00:18:59 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:59 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:59 volumio sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 31 00:18:59 volumio sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:59 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio sudo[1195]: pam_unix(sudo:session): session closed for user root Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 00:18:59 volumio volumio[988]: info: Starting Shairport Sync Oct 31 00:18:59 volumio volumio[988]: info: Starting Shairport Sync Oct 31 00:18:59 volumio volumio[988]: info: Starting Shairport Sync Oct 31 00:18:59 volumio sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 00:18:59 volumio sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:59 volumio sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 00:18:59 volumio sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 00:18:59 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Oct 31 00:18:59 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:18:59 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:18:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 31 00:18:59 volumio sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:19:00 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.29.144 from 192.168.29.223 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 31 00:19:00 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 31 00:19:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 31 00:19:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 31 00:19:00 volumio sudo[1208]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:00 volumio volumio[988]: info: Volumio called home Oct 31 00:19:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 31 00:19:00 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 31 00:19:00 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 31 00:19:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 31 00:19:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 31 00:19:00 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:00 volumio sudo[1210]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:00 volumio volumio[988]: info: Shairport-Sync Started Oct 31 00:19:00 volumio volumio[988]: Error adding Membership: Error: addMembership EINVAL Oct 31 00:19:00 volumio volumio[988]: (node:988) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 31 00:19:00 volumio volumio[988]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 31 00:19:00 volumio volumio[988]: (node:988) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 31 00:19:00 volumio volumio[988]: info: Shairport-Sync Started Oct 31 00:19:00 volumio volumio[988]: info: Shairport-Sync Started Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::volumioGetVisibleSources Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Oct 31 00:19:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 31 00:19:00 volumio volumio[988]: info: Received Get System Info Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 00:19:00 volumio volumio[988]: info: Discovery: Getting this device information Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Oct 31 00:19:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Oct 31 00:19:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:19:00 volumio volumio[988]: info: Listing playlists Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 31 00:19:00 volumio volumio[988]: info: Received Get System Info Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 00:19:00 volumio volumio[988]: info: Discovery: Getting this device information Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Oct 31 00:19:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 31 00:19:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 31 00:19:01 volumio sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 31 00:19:01 volumio sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:19:01 volumio sudo[1222]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:01 volumio sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 31 00:19:01 volumio sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:19:01 volumio sudo[1224]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:01 volumio sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 31 00:19:01 volumio sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 00:19:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 31 00:19:01 volumio volumio[988]: info: Received Get System Info Oct 31 00:19:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 00:19:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 00:19:01 volumio volumio[988]: info: Discovery: Getting this device information Oct 31 00:19:01 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Oct 31 00:19:01 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 31 00:19:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 00:19:01 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 31 00:19:01 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:01 volumio volumio[988]: info: Upmpdcli Daemon Started Oct 31 00:19:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 31 00:19:02 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 31 00:19:02 volumio mpd[1191]: Oct 31 00:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 31 00:19:02 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 31 00:19:02 volumio systemd[1]: systemd-timedated.service: Succeeded. Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 31 00:19:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 31 00:19:03 volumio volumio-remote-updater[609]: [2025-10-31 00:19:03] [connect] Successful connection Oct 31 00:19:03 volumio volumio-remote-updater[609]: [2025-10-31 00:19:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761837543 101 Oct 31 00:19:03 volumio volumio[988]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Oct 31 00:19:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 31 00:19:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand rescan Oct 31 00:19:04 volumio volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 00:19:04 volumio volumio[988]: TypeError: Cannot read property 'then' of undefined Oct 31 00:19:04 volumio volumio[988]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Oct 31 00:19:04 volumio volumio[988]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1886:15) Oct 31 00:19:04 volumio volumio[988]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 31 00:19:04 volumio volumio[988]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1087:26) Oct 31 00:19:04 volumio volumio[988]: at Socket.emit (events.js:315:20) Oct 31 00:19:04 volumio volumio[988]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 31 00:19:04 volumio volumio[988]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 31 00:19:04 volumio volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 00:19:06 volumio systemd[1]: Started Music Player Daemon. Oct 31 00:19:06 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:06 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:06 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-31 00:18 Oct 31 00:19:06 volumio sudo[1242]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"