Dec 20 14:18:34 volumio ntpd[1121]: CLOCK: time stepped by 1240552.152885 Dec 20 14:18:34 volumio ntpd[1121]: CLOCK: time changed from 2025-12-05 to 2025-12-20 Dec 20 14:18:34 volumio ntpd[1121]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 20 14:18:34 volumio volumio[1325]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 20 14:18:34 volumio volumio[1325]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 20 14:18:34 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:34 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:34 volumio volumio[1325]: info: Starting MyVolumio Remote Streaming Endpoints Dec 20 14:18:34 volumio volumio[1325]: info: MyVolumio not started Dec 20 14:18:34 volumio volumio[1325]: info: Initializing device activation check Dec 20 14:18:34 volumio volumio[1325]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 20 14:18:34 volumio volumio[1325]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 20 14:18:34 volumio kernel: usb usb1-port1: over-current change #31 Dec 20 14:18:34 volumio kernel: usb usb2-port1: over-current change #34 Dec 20 14:18:34 volumio kernel: usb usb4-port1: over-current change #34 Dec 20 14:18:34 volumio kernel: usb usb1-port2: over-current change #28 Dec 20 14:18:34 volumio volumio[1325]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 20 14:18:34 volumio volumio[1325]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 20 14:18:34 volumio volumio[1325]: info: Streaming services startup Dec 20 14:18:34 volumio volumio[1325]: info: Starting Streaming Daemon Dec 20 14:18:34 volumio sudo[1825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 14:18:34 volumio sudo[1825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:34 volumio volumio[1325]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 20 14:18:34 volumio volumio[1325]: info: Discovery: A device disappeared from network Dec 20 14:18:34 volumio volumio[1325]: info: Discovery: Device volumio disappeared from network Dec 20 14:18:34 volumio volumio[1325]: info: Discovery: A device disappeared from network Dec 20 14:18:34 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 20 14:18:34 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 20 14:18:34 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 20 14:18:34 volumio sudo[1825]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:34 volumio volumio[1325]: error: Cannot start Volumio Streaming Daemon Dec 20 14:18:34 volumio volumio[1325]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 14:18:34 volumio volumio[1325]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 14:18:34 volumio kernel: usb usb2-port1: over-current change #35 Dec 20 14:18:34 volumio kernel: usb usb4-port1: over-current change #35 Dec 20 14:18:34 volumio volumio[1325]: info: Volumio Network Manager: Network status updated: 1 Dec 20 14:18:34 volumio kernel: usb usb1-port1: over-current change #32 Dec 20 14:18:34 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 20 14:18:34 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 20 14:18:34 volumio volumio[1325]: info: Discovery: adding e8b5238d-21d3-4660-94ef-16a5379a84ef Dec 20 14:18:34 volumio volumio[1325]: info: Discovery: Found device Volumio Dec 20 14:18:34 volumio volumio[1325]: info: CoreCommandRouter::volumioGetState Dec 20 14:18:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:18:34 volumio volumio[1325]: info: Discovery: this is already registered, e8b5238d-21d3-4660-94ef-16a5379a84ef Dec 20 14:18:34 volumio volumio[1325]: info: Discovery: Found device Volumio Dec 20 14:18:34 volumio volumio[1325]: info: CoreCommandRouter::volumioGetState Dec 20 14:18:34 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:18:34 volumio kernel: usb usb1-port2: over-current change #29 Dec 20 14:18:35 volumio kernel: usb usb3-port2: over-current change #11 Dec 20 14:18:35 volumio kernel: usb 3-2: USB disconnect, device number 24 Dec 20 14:18:35 volumio kernel: usb 3-2: new full-speed USB device number 26 using xhci-hcd Dec 20 14:18:35 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 14:18:35 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 14:18:35 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 14:18:35 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 14:18:35 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.0007/input/input12 Dec 20 14:18:35 volumio kernel: hid-multitouch 0003:1A86:E5E3.0007: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 14:18:35 volumio kernel: usb usb3-port1: over-current change #12 Dec 20 14:18:36 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 20 14:18:37 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 14:18:37 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 20 14:18:38 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 20 14:18:38 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 14:18:38 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 14:18:38 volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:38 volumio volumio[1325]: info: Upmpdcli Daemon Started Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288553, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 20 14:18:39 volumio nmbd[1229]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288654, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 20 14:18:39 volumio nmbd[1229]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288675, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 20 14:18:39 volumio nmbd[1229]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288696, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 20 14:18:39 volumio nmbd[1229]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288709, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 20 14:18:39 volumio nmbd[1229]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288722, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 20 14:18:39 volumio nmbd[1229]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288735, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 20 14:18:39 volumio nmbd[1229]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288754, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 20 14:18:39 volumio nmbd[1229]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288766, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 20 14:18:39 volumio nmbd[1229]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288782, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 20 14:18:39 volumio nmbd[1229]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 20 14:18:39 volumio nmbd[1229]: [2025/12/20 05:18:39.288795, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 20 14:18:39 volumio nmbd[1229]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 20 14:18:39 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 14:18:42 volumio kernel: usb usb3-port1: over-current change #13 Dec 20 14:18:42 volumio kernel: usb usb1-port1: over-current change #33 Dec 20 14:18:42 volumio kernel: usb usb2-port1: over-current change #36 Dec 20 14:18:42 volumio kernel: usb usb4-port1: over-current change #36 Dec 20 14:18:42 volumio kernel: usb usb1-port2: over-current change #30 Dec 20 14:18:43 volumio kernel: usb usb1-port1: over-current change #34 Dec 20 14:18:43 volumio kernel: usb usb2-port1: over-current change #37 Dec 20 14:18:43 volumio kernel: usb usb4-port1: over-current change #37 Dec 20 14:18:43 volumio kernel: usb usb1-port2: over-current change #31 Dec 20 14:18:44 volumio kernel: usb usb3-port2: over-current change #12 Dec 20 14:18:44 volumio kernel: usb 3-2: USB disconnect, device number 26 Dec 20 14:18:44 volumio kernel: usb 3-2: new full-speed USB device number 32 using xhci-hcd Dec 20 14:18:44 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 14:18:44 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 14:18:44 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 14:18:44 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 14:18:44 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.0008/input/input13 Dec 20 14:18:44 volumio kernel: hid-multitouch 0003:1A86:E5E3.0008: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 14:18:44 volumio kernel: usb usb3-port1: over-current change #14 Dec 20 14:18:45 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 14:18:45 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:45 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 14:18:46 volumio volumio-remote-updater[930]: Test mode disabled Dec 20 14:18:46 volumio volumio-remote-updater[930]: Alpha mode disabled Dec 20 14:18:46 volumio volumio-remote-updater[930]: Alpha legacy test mode disabled Dec 20 14:18:46 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 20 14:18:46 volumio volumio[1325]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 20 14:18:46 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 20 14:18:46 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 14:18:47 volumio sudo[1950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 14:18:47 volumio sudo[1950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:47 volumio sudo[1950]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:47 volumio sudo[1952]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 14:18:47 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:47 volumio kernel: usb usb1-port1: over-current change #35 Dec 20 14:18:47 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:47 volumio volumio[1325]: verbose: New Socket.io Connection to 192.168.0.39 from 192.168.0.33 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 20 14:18:47 volumio kernel: usb usb4-port1: over-current change #38 Dec 20 14:18:47 volumio kernel: usb usb2-port1: over-current change #38 Dec 20 14:18:47 volumio kernel: usb usb1-port2: over-current change #32 Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 20 14:18:47 volumio kernel: usb usb4-port1: over-current change #39 Dec 20 14:18:47 volumio kernel: usb usb2-port1: over-current change #39 Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 20 14:18:47 volumio volumio[1325]: info: Received Get System Info Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 14:18:47 volumio volumio[1325]: info: Discovery: Getting this device information Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::volumioGetState Dec 20 14:18:47 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 14:18:47 volumio volumio-remote-updater[930]: Test mode disabled Dec 20 14:18:47 volumio volumio-remote-updater[930]: Alpha mode disabled Dec 20 14:18:47 volumio volumio-remote-updater[930]: Alpha legacy test mode disabled Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:47 volumio volumio[1325]: info: Retrieving Cloud Streaming UI Dec 20 14:18:47 volumio volumio[1325]: info: Getting Tidal Cloud Configuration Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:47 volumio volumio[1325]: info: Getting Qobuz Cloud Configuration Dec 20 14:18:47 volumio volumio[1325]: info: Asking plugin for UI Config Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:47 volumio volumio[1325]: info: Getting Spotify Cloud Configuration Dec 20 14:18:47 volumio volumio[1325]: info: Asking plugin for UI Config Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:47 volumio volumio[1325]: info: Saving Spotify Acccount Dec 20 14:18:47 volumio volumio[1325]: info: Got Tidal Cloud Configuration Dec 20 14:18:47 volumio volumio[1325]: info: Got it Dec 20 14:18:47 volumio volumio[1325]: info: Got it Dec 20 14:18:47 volumio volumio[1325]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 20 14:18:47 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:47 volumio volumio[1325]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 20 14:18:47 volumio kernel: usb usb1-port1: over-current change #36 Dec 20 14:18:47 volumio kernel: usb usb1-port2: over-current change #33 Dec 20 14:18:47 volumio kernel: usb usb4-port1: over-current change #40 Dec 20 14:18:47 volumio kernel: usb usb2-port1: over-current change #40 Dec 20 14:18:47 volumio kernel: usb usb1-port1: over-current change #37 Dec 20 14:18:48 volumio kernel: usb usb3-port2: over-current change #13 Dec 20 14:18:48 volumio volumio[1325]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 20 14:18:48 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 20 14:18:48 volumio kernel: usb 3-2: USB disconnect, device number 32 Dec 20 14:18:48 volumio kernel: usb 3-2: new full-speed USB device number 36 using xhci-hcd Dec 20 14:18:48 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 14:18:48 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 14:18:48 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 14:18:48 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 14:18:48 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.0009/input/input14 Dec 20 14:18:48 volumio kernel: hid-multitouch 0003:1A86:E5E3.0009: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 14:18:48 volumio kernel: usb usb3-port1: over-current change #15 Dec 20 14:18:48 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 20 14:18:48 volumio volumio[1325]: info: Loading i18n strings for locale ko Dec 20 14:18:48 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 20 14:18:48 volumio volumio[1325]: info: Setting timezone to Asia/Seoul Dec 20 14:18:48 volumio kernel: usb usb1-port1: over-current change #38 Dec 20 14:18:48 volumio sudo[1962]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 20 14:18:48 volumio sudo[1962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:48 volumio sudo[1962]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:48 volumio sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Seoul /etc/localtime Dec 20 14:18:48 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:48 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:48 volumio kernel: usb usb4-port1: over-current change #41 Dec 20 14:18:48 volumio kernel: usb usb2-port1: over-current change #41 Dec 20 14:18:48 volumio sudo[1971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 20 14:18:48 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:48 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Dec 20 14:18:48 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Dec 20 14:18:48 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Dec 20 14:18:48 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:48 volumio sudo[1975]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Seoul Dec 20 14:18:48 volumio sudo[1975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:48 volumio dbus-daemon[920]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=1976 comm="/usr/bin/timedatectl set-timezone Asia/Seoul") Dec 20 14:18:48 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 20 14:18:48 volumio dbus-daemon[920]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 20 14:18:48 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 20 14:18:48 volumio sudo[1975]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:48 volumio kernel: usb usb1-port2: over-current change #34 Dec 20 14:18:48 volumio volumio[1325]: Updating browse sources language Dec 20 14:18:48 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 14:18:49 volumio kernel: usb usb4-port1: over-current change #42 Dec 20 14:18:49 volumio kernel: usb usb2-port1: over-current change #42 Dec 20 14:18:49 volumio kernel: usb usb3-port2: over-current change #14 Dec 20 14:18:49 volumio kernel: usb usb1-port1: over-current change #39 Dec 20 14:18:49 volumio kernel: usb 3-2: USB disconnect, device number 36 Dec 20 14:18:49 volumio kernel: usb usb1-port2: over-current change #35 Dec 20 14:18:49 volumio volumio[1325]: info: Fetching Streaming Services browse cache Dec 20 14:18:49 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 14:18:49 volumio volumio[1325]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 20 14:18:49 volumio volumio[1325]: info: CoreCommandRouter::volumioGetState Dec 20 14:18:49 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:18:49 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 14:18:49 volumio kernel: usb 3-2: new full-speed USB device number 37 using xhci-hcd Dec 20 14:18:49 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 14:18:49 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 14:18:49 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 14:18:49 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 14:18:49 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.000A/input/input15 Dec 20 14:18:49 volumio kernel: hid-multitouch 0003:1A86:E5E3.000A: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 14:18:49 volumio kernel: usb usb3-port1: over-current change #16 Dec 20 14:18:50 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 20 14:18:50 volumio volumio[1325]: info: MyVolumio login type: Token Dec 20 14:18:50 volumio volumio[1325]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 20 14:18:51 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 14:18:51 volumio volumio[1325]: info: MyVolumio token set successfully Dec 20 14:18:51 volumio volumio[1325]: info: MYVOLUMIO: Adding device Dec 20 14:18:51 volumio volumio[1325]: info: MYVOLUMIO: Evaluating Server Dec 20 14:18:51 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 14:18:51 volumio volumio[1325]: info: MyVolumio status changed Dec 20 14:18:51 volumio volumio[1325]: info: Streaming services startup Dec 20 14:18:51 volumio volumio[1325]: info: Starting Streaming Daemon Dec 20 14:18:51 volumio volumio[1325]: info: Removing browser output: myVolumio user plan is not superstar Dec 20 14:18:51 volumio volumio[1325]: info: Removing audio output: Dec 20 14:18:51 volumio volumio[1325]: info: Stoppping Tunnel 1 Dec 20 14:18:51 volumio sudo[1999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 14:18:51 volumio sudo[1999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:51 volumio sudo[2001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 20 14:18:51 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:51 volumio kernel: usb usb1-port1: over-current change #40 Dec 20 14:18:51 volumio sudo[1999]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 20 14:18:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 20 14:18:51 volumio volumio[1325]: error: Cannot start Volumio Streaming Daemon Dec 20 14:18:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 20 14:18:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 20 14:18:51 volumio volumio[1325]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 14:18:51 volumio volumio[1325]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 14:18:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 20 14:18:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 20 14:18:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 20 14:18:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 20 14:18:51 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:51 volumio volumio[1325]: info: Remote SSH Stopped Dec 20 14:18:51 volumio kernel: usb usb2-port1: over-current change #43 Dec 20 14:18:51 volumio kernel: usb usb4-port1: over-current change #43 Dec 20 14:18:51 volumio kernel: usb usb1-port2: over-current change #36 Dec 20 14:18:52 volumio volumio[1325]: info: Setting Geolocation for MyVolumio to as1 Dec 20 14:18:52 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:52 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:52 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:52 volumio volumio[1325]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 20 14:18:52 volumio kernel: usb usb3-port2: over-current change #15 Dec 20 14:18:52 volumio kernel: usb 3-2: USB disconnect, device number 37 Dec 20 14:18:52 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 14:18:52 volumio kernel: usb 3-2: new full-speed USB device number 41 using xhci-hcd Dec 20 14:18:53 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 14:18:53 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 14:18:53 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 14:18:53 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 14:18:53 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.000B/input/input16 Dec 20 14:18:53 volumio kernel: hid-multitouch 0003:1A86:E5E3.000B: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 14:18:53 volumio kernel: usb usb3-port1: over-current change #17 Dec 20 14:18:53 volumio volumio[1325]: info: Updating MyVolumio device info Dec 20 14:18:53 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:53 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:53 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:53 volumio volumio[1325]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 14:18:54 volumio volumio[1325]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 20 14:18:54 volumio volumio[1325]: info: Completed starting MyVolumio Plugin Dec 20 14:18:54 volumio volumio[1325]: [Metrics] CommandRouter: 40s 723.38ms Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::volumiosetStartupVolume Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::Close All Modals sent Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::Close All Modals sent Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: info: Retrieving Cloud Streaming UI Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: info: Retrieving Cloud Streaming UI Dec 20 14:18:54 volumio volumio[1325]: info: Getting Tidal Cloud Configuration Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: info: Getting Qobuz Cloud Configuration Dec 20 14:18:54 volumio volumio[1325]: info: Asking plugin for UI Config Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: info: Getting Spotify Cloud Configuration Dec 20 14:18:54 volumio volumio[1325]: info: Asking plugin for UI Config Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: info: Saving Spotify Acccount Dec 20 14:18:54 volumio volumio[1325]: info: Got Tidal Cloud Configuration Dec 20 14:18:54 volumio volumio[1325]: info: Got it Dec 20 14:18:54 volumio volumio[1325]: info: Got it Dec 20 14:18:54 volumio volumio[1325]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 20 14:18:54 volumio volumio[1325]: info: Getting Tidal Cloud Configuration Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: info: Getting Qobuz Cloud Configuration Dec 20 14:18:54 volumio volumio[1325]: info: Asking plugin for UI Config Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: info: Getting Spotify Cloud Configuration Dec 20 14:18:54 volumio volumio[1325]: info: Asking plugin for UI Config Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: info: Saving Spotify Acccount Dec 20 14:18:54 volumio volumio[1325]: info: Got Tidal Cloud Configuration Dec 20 14:18:54 volumio volumio[1325]: info: Got it Dec 20 14:18:54 volumio volumio[1325]: info: Got it Dec 20 14:18:54 volumio volumio[1325]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 20 14:18:54 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:18:54 volumio volumio[1325]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 20 14:18:55 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 14:18:55 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 20 14:18:55 volumio volumio[1325]: info: Loading i18n strings for locale ko Dec 20 14:18:55 volumio volumio[1325]: Updating browse sources language Dec 20 14:18:55 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 14:18:55 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 20 14:18:55 volumio volumio[1325]: info: Setting timezone to Asia/Seoul Dec 20 14:18:55 volumio sudo[2022]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 20 14:18:55 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:55 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:55 volumio sudo[2027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Seoul /etc/localtime Dec 20 14:18:55 volumio sudo[2027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:55 volumio sudo[2027]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:55 volumio sudo[2031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 20 14:18:55 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:55 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:55 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 20 14:18:55 volumio sudo[2037]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Seoul Dec 20 14:18:55 volumio sudo[2037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:55 volumio sudo[2037]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:55 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 20 14:18:55 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 20 14:18:55 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 20 14:18:55 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 14:18:55 volumio sudo[2050]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 14:18:55 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:18:55 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 20 14:18:55 volumio volumio[1325]: info: Fetching Streaming Services browse cache Dec 20 14:18:56 volumio volumio[1325]: info: MYVOLUMIO: Adding device Dec 20 14:18:56 volumio volumio[1325]: info: MYVOLUMIO: Evaluating Server Dec 20 14:18:56 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 20 14:18:56 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 20 14:18:56 volumio volumio[1325]: info: Setting Geolocation for MyVolumio to as1 Dec 20 14:18:56 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:56 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:56 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:56 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 14:18:57 volumio volumio[1325]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 20 14:18:57 volumio volumio[1325]: info: Updating MyVolumio device info Dec 20 14:18:57 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:57 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:57 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:18:58 volumio upmpdcli[2091]: writing RSA key Dec 20 14:18:58 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Dec 20 14:18:58 volumio volumio[1325]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 20 14:18:59 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 20 14:19:01 volumio volumio[1325]: info: BOOT COMPLETED Dec 20 14:19:06 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 14:19:06 volumio volumio[1325]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 14:19:06 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 14:19:06 volumio volumio[1325]: info: Received Get System Version Dec 20 14:19:06 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 14:19:06 volumio volumio[1325]: info: Received Get System Info Dec 20 14:19:06 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 14:19:06 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 14:19:06 volumio volumio[1325]: info: Discovery: Getting this device information Dec 20 14:19:06 volumio volumio[1325]: info: CoreCommandRouter::volumioGetState Dec 20 14:19:06 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:19:06 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 14:19:07 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 20 14:19:07 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 20 14:19:07 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 20 14:19:07 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:07 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 14:19:13 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 20 14:19:14 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 20 14:19:14 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Dec 20 14:19:18 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 20 14:19:18 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 14:19:19 volumio volumio-remote-updater[930]: Test mode disabled Dec 20 14:19:19 volumio volumio-remote-updater[930]: Alpha mode disabled Dec 20 14:19:19 volumio volumio-remote-updater[930]: Alpha legacy test mode disabled Dec 20 14:19:19 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 20 14:19:19 volumio volumio[1325]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 20 14:19:19 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 20 14:19:22 volumio volumio[1325]: info: Preparing to save Alsa Options, stopping services first Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::volumioGetState Dec 20 14:19:22 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::volumioPause Dec 20 14:19:22 volumio volumio[1325]: info: CoreStateMachine::pause Dec 20 14:19:22 volumio volumio[1325]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"hifiberry-dacpluspro-pi5","label":"HiFiBerry DAC+ Pro [Pi5]"},"output_device":{"value":1,"label":"HiFiBerry DAC+ Pro [Pi5]"},"disallowPush":true} Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: Enabling I2S DAC: HiFiBerry DAC+ Pro [Pi5] Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio sudo[2194]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 20 14:19:22 volumio sudo[2194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio sudo[2194]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio volumio[1325]: info: No Overlays Loaded Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio sudo[2197]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus-pro Dec 20 14:19:22 volumio sudo[2197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio kernel: usb usb1-port1: over-current change #41 Dec 20 14:19:22 volumio kernel: usb usb3-port1: over-current change #18 Dec 20 14:19:22 volumio volumio[1325]: warn: Unable to locate the device HiFiBerry DAC+ Pro [Pi5] a reboot may be required. Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 14:19:22 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a4000/status Dec 20 14:19:22 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#address-cells Dec 20 14:19:22 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#size-cells Dec 20 14:19:22 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/status Dec 20 14:19:22 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/compatible Dec 20 14:19:22 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/i2s-controller Dec 20 14:19:22 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/status Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC+ Pro [Pi5] Dec 20 14:19:22 volumio kernel: usb usb2-port1: over-current change #44 Dec 20 14:19:22 volumio kernel: usb usb4-port1: over-current change #44 Dec 20 14:19:22 volumio sudo[2197]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio kernel: snd-rpi-hifiberry-dacplus soc@107c000000:sound: activating headphone amplifier Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 20 14:19:22 volumio volumio[1325]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC+ Pro [Pi5] Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 20 14:19:22 volumio volumio[1325]: info: Disabling external Volume Control Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 20 14:19:22 volumio volumio[1325]: info: Preparing to generate the ALSA configuration file Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 20 14:19:22 volumio kernel: tpa6130a2 1-0060: supply Vdd not found, using dummy regulator Dec 20 14:19:22 volumio volumio[1325]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Dec 20 14:19:22 volumio sudo[2239]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 14:19:22 volumio sudo[2239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio volumio[1325]: info: I2S Param [object Object] successfully enabled Dec 20 14:19:22 volumio volumio[1325]: info: VolumeController:: Volume=undefined Mute =false Dec 20 14:19:22 volumio volumio[1325]: info: CoreStateMachine::pushState Dec 20 14:19:22 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 14:19:22 volumio volumio[1325]: info: Getting Alsa Cards List without I2S DAC Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio sudo[2243]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 14:19:22 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio (udev-worker)[2214]: controlC2: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 2' failed with exit code 99. Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 20 14:19:22 volumio volumio[1325]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 20 14:19:22 volumio volumio[1325]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 20 14:19:22 volumio volumio[1325]: info: Cannot use regular scanning, forcing with ap-force Dec 20 14:19:22 volumio kernel: usb usb1-port2: over-current change #37 Dec 20 14:19:22 volumio sudo[2275]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 20 14:19:22 volumio sudo[2275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio sudo[2275]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio volumio[1325]: command failed: Device or resource busy (-16) Dec 20 14:19:22 volumio volumio[1325]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 20 14:19:22 volumio volumio[1325]: command failed: Device or resource busy (-16) Dec 20 14:19:22 volumio volumio[1325]: info: Asound.conf file written Dec 20 14:19:22 volumio sudo[2279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 20 14:19:22 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio volumio[1325]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 20 14:19:22 volumio volumio[1325]: No state is present for card vc4hdmi0 Dec 20 14:19:22 volumio volumio[1325]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 20 14:19:22 volumio volumio[1325]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 20 14:19:22 volumio volumio[1325]: Hardware is initialized using a generic method Dec 20 14:19:22 volumio volumio[1325]: No state is present for card vc4hdmi0 Dec 20 14:19:22 volumio volumio[1325]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 20 14:19:22 volumio volumio[1325]: No state is present for card vc4hdmi1 Dec 20 14:19:22 volumio volumio[1325]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 20 14:19:22 volumio volumio[1325]: Found hardware: "vc4-hdmi" "" "" "" "" Dec 20 14:19:22 volumio volumio[1325]: Hardware is initialized using a generic method Dec 20 14:19:22 volumio volumio[1325]: No state is present for card vc4hdmi1 Dec 20 14:19:22 volumio volumio[1325]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 20 14:19:22 volumio volumio[1325]: No state is present for card sndrpihifiberry Dec 20 14:19:22 volumio volumio[1325]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 20 14:19:22 volumio volumio[1325]: Found hardware: "HifiberryDacp" "" "" "" "" Dec 20 14:19:22 volumio volumio[1325]: Hardware is initialized using a generic method Dec 20 14:19:22 volumio volumio[1325]: No state is present for card sndrpihifiberry Dec 20 14:19:22 volumio volumio[1325]: info: Output device has changed, restarting MPD Dec 20 14:19:22 volumio volumio[1325]: info: Output device has changed, restarting Shairport Sync Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio sudo[2285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 14:19:22 volumio sudo[2285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio sudo[2285]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio sudo[2287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 14:19:22 volumio sudo[2287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio volumio[1325]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 14:19:22 volumio volumio[1325]: info: Output device has changed, restarting MPD Dec 20 14:19:22 volumio volumio[1325]: info: Output device has changed, restarting Shairport Sync Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio sudo[2295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 14:19:22 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 20 14:19:22 volumio sudo[2297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 14:19:22 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio volumio[1325]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 14:19:22 volumio volumio[1325]: info: MPD Permissions set Dec 20 14:19:22 volumio volumio[1325]: info: MPD Permissions set Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:22 volumio volumio[1325]: info: Starting Shairport Sync Dec 20 14:19:22 volumio volumio[1325]: info: Starting Shairport Sync Dec 20 14:19:22 volumio sudo[2316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 14:19:22 volumio sudo[2316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio kernel: usb usb4-port1: over-current change #45 Dec 20 14:19:22 volumio kernel: usb usb2-port1: over-current change #45 Dec 20 14:19:22 volumio sudo[2319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 14:19:22 volumio sudo[2319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 20 14:19:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 20 14:19:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 20 14:19:22 volumio systemd[1]: shairport-sync.service: Consumed 1.541s CPU time. Dec 20 14:19:22 volumio kernel: usb usb3-port2: over-current change #16 Dec 20 14:19:22 volumio kernel: usb usb1-port1: over-current change #42 Dec 20 14:19:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 20 14:19:22 volumio sudo[2316]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio volumio[1325]: info: Shairport-Sync Started Dec 20 14:19:22 volumio sudo[2319]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio volumio[1325]: info: Shairport-Sync Started Dec 20 14:19:22 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 20 14:19:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 20 14:19:22 volumio systemd[1]: mpd.service: Consumed 1.210s CPU time. Dec 20 14:19:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 20 14:19:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 20 14:19:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 20 14:19:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 20 14:19:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 20 14:19:22 volumio kernel: usb 3-2: USB disconnect, device number 41 Dec 20 14:19:22 volumio sudo[2338]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 14:19:22 volumio sudo[2338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 14:19:22 volumio sudo[2338]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:22 volumio kernel: usb 3-2: new full-speed USB device number 46 using xhci-hcd Dec 20 14:19:23 volumio mpd[2340]: 2025-12-20T14:19:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 14:19:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 20 14:19:23 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:23 volumio sudo[2287]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:23 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 14:19:23 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 14:19:23 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 14:19:23 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 14:19:23 volumio volumio[1325]: error: updateQueue error: null Dec 20 14:19:23 volumio volumio[1325]: error: updateQueue error: null Dec 20 14:19:23 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.000C/input/input17 Dec 20 14:19:23 volumio kernel: hid-multitouch 0003:1A86:E5E3.000C: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 14:19:23 volumio kernel: usb usb3-port1: over-current change #19 Dec 20 14:19:25 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 14:19:25 volumio sudo[2239]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 20 14:19:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 14:19:25 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 20 14:19:25 volumio volumio-remote-updater[930]: Test mode disabled Dec 20 14:19:25 volumio volumio-remote-updater[930]: Alpha mode disabled Dec 20 14:19:25 volumio volumio-remote-updater[930]: Alpha legacy test mode disabled Dec 20 14:19:25 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 20 14:19:26 volumio sudo[2361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 14:19:26 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:26 volumio sudo[2363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 14:19:26 volumio sudo[2363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:26 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 20 14:19:26 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 20 14:19:26 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 20 14:19:26 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 20 14:19:26 volumio sudo[2361]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:26 volumio mpd_monitor.sh[2366]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 14:19:26 volumio sudo[2363]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:26 volumio volumio[1325]: info: Successfully started MPD Monitor Dec 20 14:19:26 volumio volumio[1325]: info: Successfully started MPD Monitor Dec 20 14:19:26 volumio volumio[1325]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 20 14:19:26 volumio volumio[1325]: info: CoreStateMachine::getcurrentVolume Dec 20 14:19:26 volumio volumio[1325]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 14:19:26 volumio volumio[1325]: info: VolumeController:: Volume=100 Mute =false Dec 20 14:19:26 volumio volumio[1325]: info: CoreStateMachine::pushState Dec 20 14:19:26 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:19:26 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 14:19:26 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState Dec 20 14:19:26 volumio volumio[1325]: info: CoreStateMachine::updateTrackBlock Dec 20 14:19:26 volumio volumio[1325]: info: CorePlayQueue::getTrackBlock Dec 20 14:19:26 volumio volumio[1325]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 14:19:26 volumio volumio[1325]: info: VolumeController:: Volume=100 Mute =false Dec 20 14:19:26 volumio volumio[1325]: info: CoreStateMachine::pushState Dec 20 14:19:26 volumio volumio[1325]: info: CorePlayQueue::getTrack 0 Dec 20 14:19:26 volumio volumio[1325]: info: CoreCommandRouter::volumioPushState Dec 20 14:19:26 volumio volumio[1325]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 20 14:19:26 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 20 14:19:27 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 14:19:27 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 14:19:31 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Dec 20 14:19:31 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Dec 20 14:19:31 volumio sudo[2376]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 14:19:31 volumio sudo[2376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:31 volumio sudo[2376]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:31 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 20 14:19:31 volumio volumio[1325]: info: Saving new wireless network Dec 20 14:19:31 volumio sudo[2379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 20 14:19:31 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:31 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:31 volumio volumio[1325]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 20 14:19:31 volumio volumio[1325]: info: Discovery: Restarting Advertising due to device name change Dec 20 14:19:31 volumio sudo[2382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 20 14:19:31 volumio sudo[2382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:31 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 20 14:19:31 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 20 14:19:31 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 20 14:19:31 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 20 14:19:32 volumio wireless.js[2384]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 20 14:19:32 volumio wireless.js[2384]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 20 14:19:32 volumio wireless.js[2384]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 20 14:19:32 volumio wireless.js[2384]: WIRELESS.JS: Cleaning previous... Dec 20 14:19:32 volumio sudo[2402]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 20 14:19:32 volumio sudo[2402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 14:19:32 volumio sudo[2402]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:32 volumio sudo[2404]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 20 14:19:32 volumio sudo[2404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 14:19:32 volumio sudo[2404]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:32 volumio wireless.js[2384]: WIRELESS.JS: Stopped aP Dec 20 14:19:32 volumio sudo[2412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 20 14:19:32 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:32 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 14:19:32 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:32 volumio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 20 14:19:32 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:32 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:32 volumio sudo[2422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 20 14:19:32 volumio sudo[2422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:32 volumio sudo[2422]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:32 volumio sudo[2424]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 20 14:19:32 volumio sudo[2424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:32 volumio volumio[1325]: info: Discovery: A device disappeared from network Dec 20 14:19:32 volumio volumio[1325]: info: Discovery: Device volumio disappeared from network Dec 20 14:19:32 volumio volumio[1325]: info: Discovery: A device disappeared from network Dec 20 14:19:32 volumio sudo[2429]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 14:19:32 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:32 volumio sudo[2429]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:33 volumio sudo[2432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 14:19:33 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:33 volumio sudo[2432]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:34 volumio sudo[2449]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 14:19:34 volumio sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:34 volumio sudo[2449]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:35 volumio sudo[2424]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:35 volumio wireless.js[2384]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: US Dec 20 14:19:35 volumio sudo[2453]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 20 14:19:35 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:35 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:35 volumio sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 20 14:19:35 volumio sudo[2455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:35 volumio sudo[2455]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:35 volumio wireless.js[2384]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 20 14:19:35 volumio wireless.js[2384]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 20 14:19:35 volumio wireless.js[2384]: WIRELESS.JS: Notified systemd about wireless ready Dec 20 14:19:35 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 14:19:35 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 20 14:19:35 volumio sudo[2382]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:35 volumio sudo[2463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 14:19:35 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:35 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:36 volumio volumio[1325]: info: Discovery: Started advertising with name: Volumio Dec 20 14:19:36 volumio sudo[2467]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 14:19:36 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 14:19:36 volumio sudo[2467]: pam_unix(sudo:session): session closed for user root Dec 20 14:19:37 volumio volumio[1325]: compat.c: read() failed: Resource temporarily unavailable Dec 20 14:19:37 volumio volumio[1325]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 14:19:37 volumio volumio[1325]: Error: dns service error: unknown Dec 20 14:19:37 volumio volumio[1325]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 20 14:19:37 volumio volumio[1325]: errorCode: -65537 Dec 20 14:19:37 volumio volumio[1325]: } Dec 20 14:19:37 volumio volumio[1325]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 14:19:37 volumio sudo[2484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 14:18' Dec 20 14:19:37 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"