Dec 20 17:14:00 volumio ntpd[1252]: CLOCK: time stepped by 0.189313 Dec 20 17:14:00 volumio ntpd[1252]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 20 17:14:00 volumio volumio[1396]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 20 17:14:00 volumio volumio[1396]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 20 17:14:00 volumio volumio[1396]: info: Streaming services startup Dec 20 17:14:00 volumio volumio[1396]: info: Starting Streaming Daemon Dec 20 17:14:00 volumio sudo[2090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 17:14:00 volumio sudo[2090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:00 volumio volumio[1396]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 20 17:14:00 volumio kernel: usb usb1-port1: over-current change #40 Dec 20 17:14:00 volumio sudo[2090]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:00 volumio volumio[1396]: error: Cannot start Volumio Streaming Daemon Dec 20 17:14:00 volumio volumio[1396]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 17:14:00 volumio volumio[1396]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 17:14:00 volumio volumio[1396]: info: Volumio called home Dec 20 17:14:00 volumio kernel: usb usb2-port1: over-current change #41 Dec 20 17:14:00 volumio kernel: usb usb4-port1: over-current change #41 Dec 20 17:14:00 volumio kernel: usb usb1-port2: over-current change #29 Dec 20 17:14:00 volumio volumio[1396]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 20 17:14:00 volumio kernel: usb usb3-port2: over-current change #11 Dec 20 17:14:01 volumio kernel: usb 3-2: new full-speed USB device number 27 using xhci-hcd Dec 20 17:14:01 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 17:14:01 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 17:14:01 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 17:14:01 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 17:14:01 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.0004/input/input9 Dec 20 17:14:01 volumio kernel: hid-multitouch 0003:1A86:E5E3.0004: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 17:14:01 volumio kernel: usb usb3-port1: over-current change #12 Dec 20 17:14:01 volumio volumio[1396]: info: MyVolumio token set successfully Dec 20 17:14:01 volumio volumio[1396]: info: MYVOLUMIO: Adding device Dec 20 17:14:01 volumio volumio[1396]: info: MYVOLUMIO: Evaluating Server Dec 20 17:14:02 volumio volumio[1396]: info: MyVolumio status changed Dec 20 17:14:02 volumio volumio[1396]: info: Streaming services startup Dec 20 17:14:02 volumio volumio[1396]: info: Starting Streaming Daemon Dec 20 17:14:02 volumio volumio[1396]: info: Removing browser output: myVolumio user plan is not superstar Dec 20 17:14:02 volumio volumio[1396]: info: Removing audio output: Dec 20 17:14:02 volumio volumio[1396]: info: Stoppping Tunnel 1 Dec 20 17:14:02 volumio sudo[2117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 17:14:02 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:02 volumio sudo[2119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 20 17:14:02 volumio sudo[2119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:02 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:02 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 17:14:02 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 17:14:02 volumio volumio[1396]: error: Cannot start Volumio Streaming Daemon Dec 20 17:14:02 volumio volumio[1396]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 17:14:02 volumio volumio[1396]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 17:14:02 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 17:14:02 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 17:14:02 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 17:14:02 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 17:14:02 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 17:14:02 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 17:14:02 volumio sudo[2119]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:02 volumio volumio[1396]: info: Remote SSH Stopped Dec 20 17:14:02 volumio volumio[1396]: info: Setting Geolocation for MyVolumio to as1 Dec 20 17:14:02 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:02 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:02 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:02 volumio volumio[1396]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 20 17:14:03 volumio volumio[1396]: info: Updating MyVolumio device info Dec 20 17:14:03 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:03 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:03 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:03 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 17:14:03 volumio volumio[1396]: info: Enabling plugin peppymeterbasic Dec 20 17:14:03 volumio volumio[1396]: info: Loading plugin "peppymeterbasic"... Dec 20 17:14:04 volumio volumio[1396]: info: Preparing to generate the ALSA configuration file Dec 20 17:14:04 volumio volumio[1396]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 20 17:14:04 volumio volumio[1396]: info: Reading ALSA contributions from plugins. Dec 20 17:14:04 volumio volumio[1396]: info: Asound.conf file written Dec 20 17:14:04 volumio sudo[2124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 20 17:14:04 volumio sudo[2124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:04 volumio sudo[2124]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 20 17:14:04 volumio volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 20 17:14:04 volumio volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 20 17:14:04 volumio volumio[1396]: info: Output device has changed, restarting MPD Dec 20 17:14:04 volumio volumio[1396]: info: Output device has changed, restarting Shairport Sync Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:04 volumio sudo[2130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 17:14:04 volumio sudo[2130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:04 volumio sudo[2130]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio kernel: usb usb1-port1: over-current change #41 Dec 20 17:14:04 volumio sudo[2132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 17:14:04 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:04 volumio volumio[1396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 17:14:04 volumio volumio[1396]: info: PLUGIN START: peppymeterbasic Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 17:14:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 20 17:14:04 volumio sudo[2140]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 20 17:14:04 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:04 volumio kernel: usb usb2-port1: over-current change #42 Dec 20 17:14:04 volumio kernel: usb usb4-port1: over-current change #42 Dec 20 17:14:04 volumio volumio[1396]: info: Preparing to generate the ALSA configuration file Dec 20 17:14:04 volumio volumio[1396]: info: Done. Dec 20 17:14:04 volumio volumio[1396]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 20 17:14:04 volumio volumio[1396]: info: Reading ALSA contributions from plugins. Dec 20 17:14:04 volumio volumio[1396]: info: MPD Permissions set Dec 20 17:14:04 volumio volumio[1396]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: Starting Shairport Sync Dec 20 17:14:04 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio volumio[1396]: info: snd_dummy loaded Dec 20 17:14:04 volumio volumio[1396]: info: Asound.conf file unchanged, so no further update is needed Dec 20 17:14:04 volumio volumio[1396]: info: Output device has changed, restarting MPD Dec 20 17:14:04 volumio sudo[2150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 17:14:04 volumio sudo[2150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:04 volumio volumio[1396]: info: Output device has changed, restarting Shairport Sync Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio (udev-worker)[565]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:04 volumio sudo[2152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 17:14:04 volumio sudo[2154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 17:14:04 volumio sudo[2154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:04 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:04 volumio volumio[1396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 17:14:04 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio volumio[1396]: info: MPD Permissions set Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 20 17:14:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 20 17:14:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 20 17:14:04 volumio systemd[1]: shairport-sync.service: Consumed 1.486s CPU time. Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:04 volumio volumio[1396]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 20 17:14:04 volumio volumio[1396]: info: Starting Shairport Sync Dec 20 17:14:04 volumio kernel: usb usb1-port2: over-current change #30 Dec 20 17:14:04 volumio sudo[2171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 17:14:04 volumio sudo[2171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 20 17:14:04 volumio sudo[2150]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 20 17:14:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 20 17:14:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 20 17:14:04 volumio volumio[1396]: info: Shairport-Sync Started Dec 20 17:14:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 20 17:14:04 volumio sudo[2171]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio volumio[1396]: info: Shairport-Sync Started Dec 20 17:14:04 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 20 17:14:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 20 17:14:04 volumio systemd[1]: mpd.service: Consumed 1.225s CPU time. Dec 20 17:14:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 20 17:14:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 20 17:14:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 20 17:14:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 20 17:14:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 20 17:14:04 volumio sudo[2176]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 17:14:04 volumio sudo[2176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 17:14:04 volumio sudo[2176]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio kernel: usb usb2-port1: over-current change #43 Dec 20 17:14:04 volumio kernel: usb usb4-port1: over-current change #43 Dec 20 17:14:04 volumio kernel: usb usb1-port1: over-current change #42 Dec 20 17:14:04 volumio mpd[2192]: 2025-12-20T17:14:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 17:14:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 20 17:14:04 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio sudo[2154]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:04 volumio volumio[1396]: error: MPD error: The expression evaluated to a falsy value: Dec 20 17:14:04 volumio volumio[1396]: assert.ok(self.idling) Dec 20 17:14:04 volumio volumio[1396]: error: The expression evaluated to a falsy value: Dec 20 17:14:04 volumio volumio[1396]: assert.ok(self.idling) Dec 20 17:14:04 volumio volumio[1396]: error: updateQueue error: null Dec 20 17:14:04 volumio kernel: usb usb3-port2: over-current change #12 Dec 20 17:14:04 volumio volumio[1396]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:14:05 volumio kernel: usb 3-2: USB disconnect, device number 27 Dec 20 17:14:05 volumio kernel: usb 3-2: new full-speed USB device number 31 using xhci-hcd Dec 20 17:14:05 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 17:14:05 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 17:14:05 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 17:14:05 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 17:14:05 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.0005/input/input10 Dec 20 17:14:05 volumio kernel: hid-multitouch 0003:1A86:E5E3.0005: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 17:14:05 volumio kernel: usb usb3-port1: over-current change #13 Dec 20 17:14:06 volumio volumio[1396]: info: MYVOLUMIO: Adding device Dec 20 17:14:06 volumio volumio[1396]: info: MYVOLUMIO: Evaluating Server Dec 20 17:14:07 volumio volumio[1396]: info: Setting Geolocation for MyVolumio to as1 Dec 20 17:14:07 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:07 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:07 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:07 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 17:14:07 volumio volumio[1396]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 20 17:14:07 volumio sudo[2230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 17:14:07 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:07 volumio sudo[2233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 17:14:07 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:07 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 20 17:14:07 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 20 17:14:07 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 20 17:14:07 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 20 17:14:07 volumio sudo[2230]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:07 volumio mpd_monitor.sh[2235]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 17:14:07 volumio volumio[1396]: info: Successfully started MPD Monitor Dec 20 17:14:07 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 20 17:14:07 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 20 17:14:07 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 20 17:14:07 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 20 17:14:07 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:07 volumio mpd_monitor.sh[2238]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 17:14:07 volumio volumio[1396]: info: Successfully started MPD Monitor Dec 20 17:14:08 volumio volumio[1396]: info: Updating MyVolumio device info Dec 20 17:14:08 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:08 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:08 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:08 volumio kernel: usb usb1-port1: over-current change #43 Dec 20 17:14:08 volumio kernel: usb usb2-port1: over-current change #44 Dec 20 17:14:08 volumio kernel: usb usb4-port1: over-current change #44 Dec 20 17:14:08 volumio kernel: usb usb1-port2: over-current change #31 Dec 20 17:14:08 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 20 17:14:08 volumio volumio[1396]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 20 17:14:09 volumio kernel: usb usb1-port1: over-current change #44 Dec 20 17:14:09 volumio kernel: usb usb2-port1: over-current change #45 Dec 20 17:14:09 volumio kernel: usb usb4-port1: over-current change #45 Dec 20 17:14:09 volumio kernel: usb usb3-port2: over-current change #13 Dec 20 17:14:09 volumio kernel: usb usb1-port2: over-current change #32 Dec 20 17:14:09 volumio kernel: usb 3-2: USB disconnect, device number 31 Dec 20 17:14:09 volumio kernel: usb 3-2: new full-speed USB device number 35 using xhci-hcd Dec 20 17:14:09 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 17:14:09 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 17:14:09 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 17:14:09 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 17:14:09 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.0006/input/input11 Dec 20 17:14:09 volumio kernel: hid-multitouch 0003:1A86:E5E3.0006: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 17:14:09 volumio kernel: usb usb3-port1: over-current change #14 Dec 20 17:14:10 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 20 17:14:11 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 20 17:14:11 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 17:14:11 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 17:14:11 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:11 volumio volumio[1396]: info: Upmpdcli Daemon Started Dec 20 17:14:11 volumio volumio[1396]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Dec 20 17:14:11 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Dec 20 17:14:11 volumio volumio[1396]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Dec 20 17:14:11 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 17:14:12 volumio volumio[1396]: --2025-12-20 17:14:11-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Dec 20 17:14:12 volumio volumio[1396]: Resolving github.com (github.com)... 20.200.245.247 Dec 20 17:14:12 volumio volumio[1396]: Connecting to github.com (github.com)|20.200.245.247|:443... connected. Dec 20 17:14:12 volumio volumio[1396]: HTTP request sent, awaiting response... 302 Found Dec 20 17:14:12 volumio volumio[1396]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Dec 20 17:14:12 volumio volumio[1396]: --2025-12-20 17:14:11-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Dec 20 17:14:12 volumio volumio[1396]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.109.133, 185.199.108.133, ... Dec 20 17:14:12 volumio volumio[1396]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. Dec 20 17:14:12 volumio volumio[1396]: HTTP request sent, awaiting response... 200 OK Dec 20 17:14:12 volumio volumio[1396]: Length: 362 [text/plain] Dec 20 17:14:12 volumio volumio[1396]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Dec 20 17:14:12 volumio volumio[1396]: 0K 100% 10.2M=0s Dec 20 17:14:12 volumio volumio[1396]: 2025-12-20 17:14:12 (10.2 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Dec 20 17:14:12 volumio volumio[1396]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:14:12 volumio volumio[1396]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:14:13 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 17:14:14 volumio volumio[1396]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::volumioGetState Dec 20 17:14:14 volumio volumio[1396]: info: CorePlayQueue::getTrack 0 Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 17:14:14 volumio volumio[1396]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 17:14:14 volumio volumio[1396]: info: Received Get System Version Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 17:14:14 volumio volumio[1396]: info: Received Get System Info Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 17:14:14 volumio volumio[1396]: info: Discovery: Getting this device information Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::volumioGetState Dec 20 17:14:14 volumio volumio[1396]: info: CorePlayQueue::getTrack 0 Dec 20 17:14:14 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 17:14:18 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 17:14:18 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:18 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 17:14:19 volumio volumio-remote-updater[1048]: Test mode disabled Dec 20 17:14:19 volumio volumio-remote-updater[1048]: Alpha mode disabled Dec 20 17:14:19 volumio volumio-remote-updater[1048]: Alpha legacy test mode disabled Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 20 17:14:19 volumio volumio[1396]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 17:14:19 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 17:14:20 volumio volumio[1396]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 20 17:14:20 volumio volumio[1396]: info: Completed starting MyVolumio Plugin Dec 20 17:14:20 volumio volumio[1396]: [Metrics] CommandRouter: 34s 398.83ms Dec 20 17:14:20 volumio volumio[1396]: info: CoreCommandRouter::volumiosetStartupVolume Dec 20 17:14:20 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 17:14:20 volumio volumio[1396]: info: VolumeController:: Setting startup Volume 70 Dec 20 17:14:20 volumio volumio[1396]: info: VolumeController::SetAlsaVolume70 Dec 20 17:14:20 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 17:14:20 volumio volumio[1396]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:14:20 volumio volumio[1396]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:14:20 volumio volumio[1396]: info: CoreStateMachine::pushState Dec 20 17:14:20 volumio volumio[1396]: info: CorePlayQueue::getTrack 0 Dec 20 17:14:20 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 17:14:20 volumio volumio[1396]: info: CoreCommandRouter::volumioPushState Dec 20 17:14:20 volumio volumio[1396]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 20 17:14:20 volumio sudo[2295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 20 17:14:20 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:20 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:20 volumio kernel: usb usb1-port1: over-current change #45 Dec 20 17:14:20 volumio kernel: usb usb3-port1: over-current change #15 Dec 20 17:14:20 volumio volumio[1396]: info: peppymeterbasic Daemon Stop Dec 20 17:14:20 volumio kernel: usb usb2-port1: over-current change #46 Dec 20 17:14:20 volumio kernel: usb usb4-port1: over-current change #46 Dec 20 17:14:20 volumio kernel: usb usb1-port2: over-current change #33 Dec 20 17:14:20 volumio volumio[1396]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Dec 20 17:14:20 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Dec 20 17:14:22 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 17:14:22 volumio volumio[1396]: --2025-12-20 17:14:20-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/1024x600-Gelo5-BASIC_121.zip Dec 20 17:14:22 volumio volumio[1396]: Resolving github.com (github.com)... 20.200.245.247 Dec 20 17:14:22 volumio volumio[1396]: Connecting to github.com (github.com)|20.200.245.247|:443... connected. Dec 20 17:14:22 volumio volumio[1396]: HTTP request sent, awaiting response... 302 Found Dec 20 17:14:22 volumio volumio[1396]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/Zipped-folders/1024x600-Gelo5-BASIC_121.zip [following] Dec 20 17:14:22 volumio volumio[1396]: --2025-12-20 17:14:21-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/Zipped-folders/1024x600-Gelo5-BASIC_121.zip Dec 20 17:14:22 volumio volumio[1396]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.110.133, 185.199.108.133, ... Dec 20 17:14:22 volumio volumio[1396]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. Dec 20 17:14:22 volumio volumio[1396]: HTTP request sent, awaiting response... 200 OK Dec 20 17:14:22 volumio volumio[1396]: Length: 7807998 (7.4M) [application/zip] Dec 20 17:14:22 volumio volumio[1396]: Saving to: ‘/tmp/1024x600-Gelo5-BASIC_121.zip’ Dec 20 17:14:22 volumio volumio[1396]: 0K .......... .......... .......... .......... .......... 0% 2.81M 3s Dec 20 17:14:22 volumio volumio[1396]: 50K .......... .......... .......... .......... .......... 1% 6.56M 2s Dec 20 17:14:22 volumio volumio[1396]: 100K .......... .......... .......... .......... .......... 1% 5.23M 2s Dec 20 17:14:22 volumio volumio[1396]: 150K .......... .......... .......... .......... .......... 2% 6.12M 2s Dec 20 17:14:22 volumio volumio[1396]: 200K .......... .......... .......... .......... .......... 3% 5.21M 2s Dec 20 17:14:22 volumio volumio[1396]: 250K .......... .......... .......... .......... .......... 3% 6.79M 1s Dec 20 17:14:22 volumio volumio[1396]: 300K .......... .......... .......... .......... .......... 4% 4.43M 1s Dec 20 17:14:22 volumio volumio[1396]: 350K .......... .......... .......... .......... .......... 5% 9.93M 1s Dec 20 17:14:22 volumio volumio[1396]: 400K .......... .......... .......... .......... .......... 5% 4.62M 1s Dec 20 17:14:22 volumio volumio[1396]: 450K .......... .......... .......... .......... .......... 6% 8.68M 1s Dec 20 17:14:22 volumio volumio[1396]: 500K .......... .......... .......... .......... .......... 7% 5.73M 1s Dec 20 17:14:22 volumio volumio[1396]: 550K .......... .......... .......... .......... .......... 7% 6.95M 1s Dec 20 17:14:22 volumio volumio[1396]: 600K .......... .......... .......... .......... .......... 8% 8.56M 1s Dec 20 17:14:22 volumio volumio[1396]: 650K .......... .......... .......... .......... .......... 9% 7.10M 1s Dec 20 17:14:22 volumio volumio[1396]: 700K .......... .......... .......... .......... .......... 9% 9.61M 1s Dec 20 17:14:22 volumio volumio[1396]: 750K .......... .......... .......... .......... .......... 10% 3.99M 1s Dec 20 17:14:22 volumio volumio[1396]: 800K .......... .......... .......... .......... .......... 11% 7.74M 1s Dec 20 17:14:22 volumio volumio[1396]: 850K .......... .......... .......... .......... .......... 11% 7.40M 1s Dec 20 17:14:22 volumio volumio[1396]: 900K .......... .......... .......... .......... .......... 12% 6.23M 1s Dec 20 17:14:22 volumio volumio[1396]: 950K .......... .......... .......... .......... .......... 13% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 1000K .......... .......... .......... .......... .......... 13% 3.10M 1s Dec 20 17:14:22 volumio volumio[1396]: 1050K .......... .......... .......... .......... .......... 14% 7.80M 1s Dec 20 17:14:22 volumio volumio[1396]: 1100K .......... .......... .......... .......... .......... 15% 11.9M 1s Dec 20 17:14:22 volumio volumio[1396]: 1150K .......... .......... .......... .......... .......... 15% 8.78M 1s Dec 20 17:14:22 volumio volumio[1396]: 1200K .......... .......... .......... .......... .......... 16% 4.12M 1s Dec 20 17:14:22 volumio volumio[1396]: 1250K .......... .......... .......... .......... .......... 17% 11.8M 1s Dec 20 17:14:22 volumio volumio[1396]: 1300K .......... .......... .......... .......... .......... 17% 8.26M 1s Dec 20 17:14:22 volumio volumio[1396]: 1350K .......... .......... .......... .......... .......... 18% 6.83M 1s Dec 20 17:14:22 volumio volumio[1396]: 1400K .......... .......... .......... .......... .......... 19% 9.47M 1s Dec 20 17:14:22 volumio volumio[1396]: 1450K .......... .......... .......... .......... .......... 19% 8.74M 1s Dec 20 17:14:22 volumio volumio[1396]: 1500K .......... .......... .......... .......... .......... 20% 7.72M 1s Dec 20 17:14:22 volumio volumio[1396]: 1550K .......... .......... .......... .......... .......... 20% 11.8M 1s Dec 20 17:14:22 volumio volumio[1396]: 1600K .......... .......... .......... .......... .......... 21% 5.20M 1s Dec 20 17:14:22 volumio volumio[1396]: 1650K .......... .......... .......... .......... .......... 22% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 1700K .......... .......... .......... .......... .......... 22% 7.59M 1s Dec 20 17:14:22 volumio volumio[1396]: 1750K .......... .......... .......... .......... .......... 23% 7.44M 1s Dec 20 17:14:22 volumio volumio[1396]: 1800K .......... .......... .......... .......... .......... 24% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 1850K .......... .......... .......... .......... .......... 24% 9.78M 1s Dec 20 17:14:22 volumio volumio[1396]: 1900K .......... .......... .......... .......... .......... 25% 10.6M 1s Dec 20 17:14:22 volumio volumio[1396]: 1950K .......... .......... .......... .......... .......... 26% 11.3M 1s Dec 20 17:14:22 volumio volumio[1396]: 2000K .......... .......... .......... .......... .......... 26% 8.45M 1s Dec 20 17:14:22 volumio volumio[1396]: 2050K .......... .......... .......... .......... .......... 27% 9.47M 1s Dec 20 17:14:22 volumio volumio[1396]: 2100K .......... .......... .......... .......... .......... 28% 11.9M 1s Dec 20 17:14:22 volumio volumio[1396]: 2150K .......... .......... .......... .......... .......... 28% 11.4M 1s Dec 20 17:14:22 volumio volumio[1396]: 2200K .......... .......... .......... .......... .......... 29% 11.4M 1s Dec 20 17:14:22 volumio volumio[1396]: 2250K .......... .......... .......... .......... .......... 30% 6.12M 1s Dec 20 17:14:22 volumio volumio[1396]: 2300K .......... .......... .......... .......... .......... 30% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 2350K .......... .......... .......... .......... .......... 31% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 2400K .......... .......... .......... .......... .......... 32% 5.78M 1s Dec 20 17:14:22 volumio volumio[1396]: 2450K .......... .......... .......... .......... .......... 32% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 2500K .......... .......... .......... .......... .......... 33% 10.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 2550K .......... .......... .......... .......... .......... 34% 10.8M 1s Dec 20 17:14:22 volumio volumio[1396]: 2600K .......... .......... .......... .......... .......... 34% 11.4M 1s Dec 20 17:14:22 volumio volumio[1396]: 2650K .......... .......... .......... .......... .......... 35% 11.7M 1s Dec 20 17:14:22 volumio volumio[1396]: 2700K .......... .......... .......... .......... .......... 36% 11.4M 1s Dec 20 17:14:22 volumio volumio[1396]: 2750K .......... .......... .......... .......... .......... 36% 10.8M 1s Dec 20 17:14:22 volumio volumio[1396]: 2800K .......... .......... .......... .......... .......... 37% 8.79M 1s Dec 20 17:14:22 volumio volumio[1396]: 2850K .......... .......... .......... .......... .......... 38% 11.8M 1s Dec 20 17:14:22 volumio volumio[1396]: 2900K .......... .......... .......... .......... .......... 38% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 2950K .......... .......... .......... .......... .......... 39% 9.23M 1s Dec 20 17:14:22 volumio volumio[1396]: 3000K .......... .......... .......... .......... .......... 40% 10.6M 1s Dec 20 17:14:22 volumio volumio[1396]: 3050K .......... .......... .......... .......... .......... 40% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 3100K .......... .......... .......... .......... .......... 41% 11.8M 1s Dec 20 17:14:22 volumio volumio[1396]: 3150K .......... .......... .......... .......... .......... 41% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 3200K .......... .......... .......... .......... .......... 42% 6.78M 1s Dec 20 17:14:22 volumio volumio[1396]: 3250K .......... .......... .......... .......... .......... 43% 11.8M 1s Dec 20 17:14:22 volumio volumio[1396]: 3300K .......... .......... .......... .......... .......... 43% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 3350K .......... .......... .......... .......... .......... 44% 11.6M 1s Dec 20 17:14:22 volumio volumio[1396]: 3400K .......... .......... .......... .......... .......... 45% 11.6M 1s Dec 20 17:14:22 volumio volumio[1396]: 3450K .......... .......... .......... .......... .......... 45% 11.5M 1s Dec 20 17:14:22 volumio volumio[1396]: 3500K .......... .......... .......... .......... .......... 46% 11.8M 1s Dec 20 17:14:22 volumio volumio[1396]: 3550K .......... .......... .......... .......... .......... 47% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 3600K .......... .......... .......... .......... .......... 47% 8.80M 0s Dec 20 17:14:22 volumio volumio[1396]: 3650K .......... .......... .......... .......... .......... 48% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 3700K .......... .......... .......... .......... .......... 49% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 3750K .......... .......... .......... .......... .......... 49% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 3800K .......... .......... .......... .......... .......... 50% 11.7M 0s Dec 20 17:14:22 volumio volumio[1396]: 3850K .......... .......... .......... .......... .......... 51% 11.4M 0s Dec 20 17:14:22 volumio volumio[1396]: 3900K .......... .......... .......... .......... .......... 51% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 3950K .......... .......... .......... .......... .......... 52% 11.9M 0s Dec 20 17:14:22 volumio volumio[1396]: 4000K .......... .......... .......... .......... .......... 53% 8.76M 0s Dec 20 17:14:22 volumio volumio[1396]: 4050K .......... .......... .......... .......... .......... 53% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 4100K .......... .......... .......... .......... .......... 54% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 4150K .......... .......... .......... .......... .......... 55% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 4200K .......... .......... .......... .......... .......... 55% 11.3M 0s Dec 20 17:14:22 volumio volumio[1396]: 4250K .......... .......... .......... .......... .......... 56% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 4300K .......... .......... .......... .......... .......... 57% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 4350K .......... .......... .......... .......... .......... 57% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 4400K .......... .......... .......... .......... .......... 58% 8.80M 0s Dec 20 17:14:22 volumio volumio[1396]: 4450K .......... .......... .......... .......... .......... 59% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 4500K .......... .......... .......... .......... .......... 59% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 4550K .......... .......... .......... .......... .......... 60% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 4600K .......... .......... .......... .......... .......... 60% 11.4M 0s Dec 20 17:14:22 volumio volumio[1396]: 4650K .......... .......... .......... .......... .......... 61% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 4700K .......... .......... .......... .......... .......... 62% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 4750K .......... .......... .......... .......... .......... 62% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 4800K .......... .......... .......... .......... .......... 63% 8.98M 0s Dec 20 17:14:22 volumio volumio[1396]: 4850K .......... .......... .......... .......... .......... 64% 11.4M 0s Dec 20 17:14:22 volumio volumio[1396]: 4900K .......... .......... .......... .......... .......... 64% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 4950K .......... .......... .......... .......... .......... 65% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 5000K .......... .......... .......... .......... .......... 66% 11.3M 0s Dec 20 17:14:22 volumio volumio[1396]: 5050K .......... .......... .......... .......... .......... 66% 11.9M 0s Dec 20 17:14:22 volumio volumio[1396]: 5100K .......... .......... .......... .......... .......... 67% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 5150K .......... .......... .......... .......... .......... 68% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 5200K .......... .......... .......... .......... .......... 68% 8.82M 0s Dec 20 17:14:22 volumio volumio[1396]: 5250K .......... .......... .......... .......... .......... 69% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 5300K .......... .......... .......... .......... .......... 70% 11.4M 0s Dec 20 17:14:22 volumio volumio[1396]: 5350K .......... .......... .......... .......... .......... 70% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 5400K .......... .......... .......... .......... .......... 71% 11.3M 0s Dec 20 17:14:22 volumio volumio[1396]: 5450K .......... .......... .......... .......... .......... 72% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 5500K .......... .......... .......... .......... .......... 72% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 5550K .......... .......... .......... .......... .......... 73% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 5600K .......... .......... .......... .......... .......... 74% 8.78M 0s Dec 20 17:14:22 volumio volumio[1396]: 5650K .......... .......... .......... .......... .......... 74% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 5700K .......... .......... .......... .......... .......... 75% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 5750K .......... .......... .......... .......... .......... 76% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 5800K .......... .......... .......... .......... .......... 76% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 5850K .......... .......... .......... .......... .......... 77% 11.5M 0s Dec 20 17:14:22 volumio kernel: usb usb1-port1: over-current change #46 Dec 20 17:14:22 volumio volumio[1396]: 5900K .......... .......... .......... .......... .......... 78% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 5950K .......... .......... .......... .......... .......... 78% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 6000K .......... .......... .......... .......... .......... 79% 8.83M 0s Dec 20 17:14:22 volumio volumio[1396]: 6050K .......... .......... .......... .......... .......... 80% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 6100K .......... .......... .......... .......... .......... 80% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 6150K .......... .......... .......... .......... .......... 81% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 6200K .......... .......... .......... .......... .......... 81% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 6250K .......... .......... .......... .......... .......... 82% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 6300K .......... .......... .......... .......... .......... 83% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 6350K .......... .......... .......... .......... .......... 83% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 6400K .......... .......... .......... .......... .......... 84% 8.80M 0s Dec 20 17:14:22 volumio volumio[1396]: 6450K .......... .......... .......... .......... .......... 85% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 6500K .......... .......... .......... .......... .......... 85% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 6550K .......... .......... .......... .......... .......... 86% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 6600K .......... .......... .......... .......... .......... 87% 11.3M 0s Dec 20 17:14:22 volumio volumio[1396]: 6650K .......... .......... .......... .......... .......... 87% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 6700K .......... .......... .......... .......... .......... 88% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 6750K .......... .......... .......... .......... .......... 89% 11.9M 0s Dec 20 17:14:22 volumio volumio[1396]: 6800K .......... .......... .......... .......... .......... 89% 8.70M 0s Dec 20 17:14:22 volumio volumio[1396]: 6850K .......... .......... .......... .......... .......... 90% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 6900K .......... .......... .......... .......... .......... 91% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 6950K .......... .......... .......... .......... .......... 91% 11.6M 0s Dec 20 17:14:22 volumio volumio[1396]: 7000K .......... .......... .......... .......... .......... 92% 11.3M 0s Dec 20 17:14:22 volumio volumio[1396]: 7050K .......... .......... .......... .......... .......... 93% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 7100K .......... .......... .......... .......... .......... 93% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 7150K .......... .......... .......... .......... .......... 94% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 7200K .......... .......... .......... .......... .......... 95% 9.00M 0s Dec 20 17:14:22 volumio volumio[1396]: 7250K .......... .......... .......... .......... .......... 95% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 7300K .......... .......... .......... .......... .......... 96% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 7350K .......... .......... .......... .......... .......... 97% 11.8M 0s Dec 20 17:14:22 volumio volumio[1396]: 7400K .......... .......... .......... .......... .......... 97% 11.3M 0s Dec 20 17:14:22 volumio volumio[1396]: 7450K .......... .......... .......... .......... .......... 98% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 7500K .......... .......... .......... .......... .......... 99% 11.9M 0s Dec 20 17:14:22 volumio volumio[1396]: 7550K .......... .......... .......... .......... .......... 99% 11.5M 0s Dec 20 17:14:22 volumio volumio[1396]: 7600K .......... .......... .... 100% 12.3M=0.8s Dec 20 17:14:22 volumio volumio[1396]: 2025-12-20 17:14:22 (9.40 MB/s) - ‘/tmp/1024x600-Gelo5-BASIC_121.zip’ saved [7807998/7807998] Dec 20 17:14:22 volumio sudo[2308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /data/INTERNAL/PeppyMeterBasic/Templates/ Dec 20 17:14:22 volumio sudo[2308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 17:14:22 volumio sudo[2308]: pam_unix(sudo:session): session closed for user root Dec 20 17:14:22 volumio volumio[1396]: info: PeppyMeterBasic ---message miniunzip -o /tmp/1024x600-Gelo5-BASIC_121.zip -d /data/INTERNAL/PeppyMeterBasic/Templates/ Dec 20 17:14:22 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 20 17:14:22 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 17:14:22 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 20 17:14:22 volumio kernel: usb usb2-port1: over-current change #47 Dec 20 17:14:22 volumio kernel: usb usb4-port1: over-current change #47 Dec 20 17:14:22 volumio volumio[1396]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:14:22 volumio volumio[1396]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:14:22 volumio kernel: usb usb1-port2: over-current change #34 Dec 20 17:14:22 volumio kernel: usb usb2-port1: over-current change #48 Dec 20 17:14:22 volumio kernel: usb usb4-port1: over-current change #48 Dec 20 17:14:22 volumio kernel: usb usb1-port1: over-current change #47 Dec 20 17:14:23 volumio kernel: usb usb3-port2: over-current change #14 Dec 20 17:14:23 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 20 17:14:24 volumio kernel: usb 3-2: USB disconnect, device number 35 Dec 20 17:14:24 volumio kernel: usb 3-2: new full-speed USB device number 43 using xhci-hcd Dec 20 17:14:24 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 17:14:24 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 17:14:24 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 17:14:24 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 17:14:24 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 17:14:24 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 17:14:24 volumio kernel: usb usb3-port1: over-current change #16 Dec 20 17:14:24 volumio kernel: usb usb1-port1: over-current change #48 Dec 20 17:14:25 volumio kernel: usb usb4-port1: over-current change #49 Dec 20 17:14:25 volumio kernel: usb usb2-port1: over-current change #49 Dec 20 17:14:25 volumio kernel: usb usb1-port2: over-current change #35 Dec 20 17:14:25 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 20 17:14:25 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 20 17:14:25 volumio kernel: usb usb1-port1: over-current change #49 Dec 20 17:14:25 volumio kernel: usb usb2-port1: over-current change #50 Dec 20 17:14:25 volumio kernel: usb usb4-port1: over-current change #50 Dec 20 17:14:25 volumio kernel: usb usb3-port2: over-current change #15 Dec 20 17:14:25 volumio kernel: usb usb1-port2: over-current change #36 Dec 20 17:14:25 volumio kernel: usb 3-2: USB disconnect, device number 43 Dec 20 17:14:26 volumio kernel: usb 3-2: new full-speed USB device number 45 using xhci-hcd Dec 20 17:14:26 volumio upmpdcli[2342]: writing RSA key Dec 20 17:14:26 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 17:14:26 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 17:14:26 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 17:14:26 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 17:14:26 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 17:14:26 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 17:14:26 volumio kernel: usb usb3-port1: over-current change #17 Dec 20 17:14:27 volumio volumio[1396]: info: BOOT COMPLETED Dec 20 17:14:28 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 17:14:30 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 17:14:54 volumio volumio[1396]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Dec 20 17:14:54 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Dec 20 17:14:54 volumio volumio[1396]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Dec 20 17:14:55 volumio volumio[1396]: --2025-12-20 17:14:54-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Dec 20 17:14:55 volumio volumio[1396]: Resolving github.com (github.com)... 20.200.245.247 Dec 20 17:14:55 volumio volumio[1396]: Connecting to github.com (github.com)|20.200.245.247|:443... connected. Dec 20 17:14:55 volumio volumio[1396]: HTTP request sent, awaiting response... 302 Found Dec 20 17:14:55 volumio volumio[1396]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Dec 20 17:14:55 volumio volumio[1396]: --2025-12-20 17:14:55-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Dec 20 17:14:55 volumio volumio[1396]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.108.133, 185.199.109.133, 185.199.110.133, ... Dec 20 17:14:55 volumio volumio[1396]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.108.133|:443... connected. Dec 20 17:14:55 volumio volumio[1396]: HTTP request sent, awaiting response... 200 OK Dec 20 17:14:55 volumio volumio[1396]: Length: 362 [text/plain] Dec 20 17:14:55 volumio volumio[1396]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Dec 20 17:14:55 volumio volumio[1396]: 0K 100% 19.5M=0s Dec 20 17:14:55 volumio volumio[1396]: 2025-12-20 17:14:55 (19.5 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Dec 20 17:14:55 volumio volumio[1396]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 17:14:55 volumio volumio[1396]: info: CoreCommandRouter::Close All Modals sent Dec 20 17:15:06 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 17:15:06 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 17:15:12 volumio volumio[1396]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 17:15:12 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 17:15:12 volumio volumio[1396]: info: CoreCommandRouter::volumioGetQueue Dec 20 17:15:12 volumio volumio[1396]: info: CoreStateMachine::getQueue Dec 20 17:15:12 volumio volumio[1396]: info: CorePlayQueue::getQueue Dec 20 17:15:13 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Dec 20 17:15:13 volumio volumio[1396]: info: Preload queue cleared Dec 20 17:15:14 volumio volumio[1396]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Dec 20 17:15:14 volumio volumio[1396]: info: Preload queue cleared Dec 20 17:15:14 volumio volumio[1396]: info: Preloading song: websbs/0 Dec 20 17:15:14 volumio volumio[1396]: info: Preloading song: websbs/1 Dec 20 17:15:14 volumio volumio[1396]: info: Preloading song: websbs/2 Dec 20 17:15:14 volumio volumio[1396]: info: Exploding uri websbs/0 in service personal_radio Dec 20 17:15:14 volumio volumio[1396]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 17:15:14 volumio volumio[1396]: TypeError: Cannot read properties of null (reading 'startsWith') Dec 20 17:15:14 volumio volumio[1396]: at /data/plugins/music_service/personal_radio/index.js:675:41 Dec 20 17:15:14 volumio volumio[1396]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 20 17:15:14 volumio volumio[1396]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 17:15:14 volumio sudo[2516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 17:14' Dec 20 17:15:14 volumio sudo[2516]: 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"