Dec 20 18:09:00 volumio kernel: usb 3-2: new full-speed USB device number 8 using xhci-hcd Dec 20 18:09:00 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:00 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:00 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:00 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:00 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.0002/input/input7 Dec 20 18:09:00 volumio kernel: hid-multitouch 0003:1A86:E5E3.0002: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 18:09:00 volumio kernel: usb usb3-port1: over-current change #6 Dec 20 18:09:00 volumio ntpd[1206]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 18:09:00 volumio ntpd[1206]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 20 18:09:00 volumio ntpd[1206]: DNS: dns_check: DNS error: -11, System error Dec 20 18:09:00 volumio ntpd[1206]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Dec 20 18:09:01 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. Dec 20 18:09:01 volumio ntpd[1206]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 18:09:01 volumio ntpd[1206]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 20 18:09:01 volumio ntpd[1206]: DNS: dns_check: DNS error: -11, System error Dec 20 18:09:01 volumio ntpd[1206]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 20 18:09:01 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:01 volumio wireless.js[998]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: US Dec 20 18:09:01 volumio sudo[1377]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 20 18:09:01 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:01 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:01 volumio sudo[1379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 20 18:09:01 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:01 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:01 volumio wireless.js[998]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 20 18:09:01 volumio wireless.js[998]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 20 18:09:01 volumio wireless.js[998]: WIRELESS.JS: Notified systemd about wireless ready Dec 20 18:09:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 18:09:01 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 20 18:09:01 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 20 18:09:01 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Dec 20 18:09:01 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 20 18:09:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 401 (plymouthd). Dec 20 18:09:01 volumio volumio-cpu-tweak[1389]: Setting RT Priority for mpd Dec 20 18:09:01 volumio volumio-cpu-tweak[1403]: pid 35's current scheduling policy: SCHED_OTHER Dec 20 18:09:01 volumio volumio-cpu-tweak[1403]: pid 35's current scheduling priority: 0 Dec 20 18:09:01 volumio volumio-cpu-tweak[1389]: Setting MPD Affinity Dec 20 18:09:01 volumio volumio-cpu-tweak[1404]: pid 3's current affinity mask: f Dec 20 18:09:01 volumio volumio-cpu-tweak[1389]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 20 18:09:01 volumio volumio-cpu-tweak[1389]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Dec 20 18:09:01 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 20 18:09:01 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Dec 20 18:09:01 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Dec 20 18:09:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 401 (plymouthd). Dec 20 18:09:02 volumio systemd[1]: Started serial-getty@ttyAMA10.service - Serial Getty on ttyAMA10. Dec 20 18:09:02 volumio systemd[1]: Reached target getty.target - Login Prompts. Dec 20 18:09:02 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 18:09:02 volumio sh[925]: eth0: carrier acquired Dec 20 18:09:02 volumio dhcpcd[950]: eth0: carrier acquired Dec 20 18:09:02 volumio dhcpcd[1078]: eth0: carrier acquired Dec 20 18:09:02 volumio kernel: macb 1f00100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Dec 20 18:09:02 volumio sh[925]: eth0: IAID 67:04:16:3e Dec 20 18:09:02 volumio dhcpcd[1078]: eth0: IAID 67:04:16:3e Dec 20 18:09:02 volumio dhcpcd[950]: eth0: IAID 67:04:16:3e Dec 20 18:09:02 volumio dhcpcd[1078]: eth0: adding address fe80::bccf:c222:47c3:edf7 Dec 20 18:09:02 volumio dhcpcd[1078]: ipv6_addaddr1: Permission denied Dec 20 18:09:02 volumio dhcpcd[950]: eth0: adding address fe80::bccf:c222:47c3:edf7 Dec 20 18:09:02 volumio sh[925]: eth0: adding address fe80::bccf:c222:47c3:edf7 Dec 20 18:09:02 volumio sh[925]: ipv6_addaddr1: Permission denied Dec 20 18:09:02 volumio dhcpcd[950]: ipv6_addaddr1: Permission denied Dec 20 18:09:02 volumio sh[925]: eth0: soliciting a DHCP lease Dec 20 18:09:02 volumio dhcpcd[950]: eth0: soliciting a DHCP lease Dec 20 18:09:02 volumio dhcpcd[1078]: eth0: soliciting an IPv6 router Dec 20 18:09:02 volumio kernel: usb usb1-port1: over-current change #12 Dec 20 18:09:02 volumio volumio-remote-updater[996]: [2025-12-20 18:09:02] [connect] Successful connection Dec 20 18:09:02 volumio volumio[1387]: info: ------------------------------------------- Dec 20 18:09:02 volumio volumio[1387]: info: ----- Volumio3 ---- Dec 20 18:09:02 volumio volumio[1387]: info: ------------------------------------------- Dec 20 18:09:02 volumio volumio[1387]: info: ----- System startup ---- Dec 20 18:09:02 volumio kernel: usb usb2-port1: over-current change #14 Dec 20 18:09:02 volumio kernel: usb usb4-port1: over-current change #14 Dec 20 18:09:02 volumio volumio[1387]: info: ------------------------------------------- Dec 20 18:09:02 volumio kernel: usb usb1-port2: over-current change #12 Dec 20 18:09:02 volumio volumio[1387]: info: MYVOLUMIO Environment detected Dec 20 18:09:02 volumio kernel: usb usb1-port1: over-current change #13 Dec 20 18:09:02 volumio bash[1164]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 20 18:09:02 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 20 18:09:02 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 20 18:09:02 volumio kernel: usb usb4-port1: over-current change #15 Dec 20 18:09:02 volumio kernel: usb usb2-port1: over-current change #15 Dec 20 18:09:02 volumio volumio[1387]: info: Plugin folders cleanup Dec 20 18:09:02 volumio volumio[1387]: info: Scanning into folder /volumio/app/plugins/ Dec 20 18:09:02 volumio volumio[1387]: info: Scanning category audio_interface Dec 20 18:09:02 volumio volumio[1387]: info: Scanning category miscellanea Dec 20 18:09:02 volumio volumio[1387]: info: Scanning category music_service Dec 20 18:09:02 volumio volumio[1387]: info: Scanning category plugins.json Dec 20 18:09:02 volumio volumio[1387]: info: Scanning category system_controller Dec 20 18:09:02 volumio volumio[1387]: info: Scanning category user_interface Dec 20 18:09:02 volumio volumio[1387]: info: Scanning into folder /data/plugins/ Dec 20 18:09:02 volumio volumio[1387]: info: Scanning category music_service Dec 20 18:09:02 volumio volumio[1387]: info: Scanning category user_interface Dec 20 18:09:02 volumio volumio[1387]: info: Plugin folders cleanup completed Dec 20 18:09:02 volumio volumio[1387]: info: ------------------------------------------- Dec 20 18:09:02 volumio volumio[1387]: info: ----- Core plugins startup ---- Dec 20 18:09:02 volumio volumio[1387]: info: ------------------------------------------- Dec 20 18:09:02 volumio volumio[1387]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 18:09:02 volumio volumio[1387]: info: Adding plugin upnp to MyMusic Plugins Dec 20 18:09:02 volumio volumio[1387]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 18:09:02 volumio volumio[1387]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 18:09:02 volumio volumio[1387]: info: Loading plugins from folder /data/plugins/ Dec 20 18:09:02 volumio volumio[1387]: info: Loading plugin "system"... Dec 20 18:09:02 volumio volumio[1387]: info: Loading plugin "appearance"... Dec 20 18:09:02 volumio kernel: usb usb1-port2: over-current change #13 Dec 20 18:09:03 volumio ifplugd(eth0)[1245]: Link beat detected. Dec 20 18:09:03 volumio kernel: usb usb4-port1: over-current change #16 Dec 20 18:09:03 volumio kernel: usb usb2-port1: over-current change #16 Dec 20 18:09:03 volumio kernel: usb usb1-port1: over-current change #14 Dec 20 18:09:03 volumio sh[925]: eth0: soliciting an IPv6 router Dec 20 18:09:03 volumio dhcpcd[950]: eth0: soliciting an IPv6 router Dec 20 18:09:03 volumio ifplugd(eth0)[1245]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 20 18:09:03 volumio ifplugd(eth0)[1245]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 20 18:09:03 volumio kernel: usb usb3-port2: over-current change #6 Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "network"... Dec 20 18:09:03 volumio volumio[1387]: info: Refreshing Cached IP Addresses Dec 20 18:09:03 volumio sudo[1433]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 18:09:03 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:03 volumio sudo[1435]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 18:09:03 volumio sudo[1435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:03 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:03 volumio kernel: usb usb1-port1: over-current change #15 Dec 20 18:09:03 volumio sudo[1435]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "services"... Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "alsa_controller"... Dec 20 18:09:03 volumio sudo[1444]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 20 18:09:03 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:03 volumio kernel: usb usb2-port1: over-current change #17 Dec 20 18:09:03 volumio kernel: usb usb4-port1: over-current change #17 Dec 20 18:09:03 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "wizard"... Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "networkfs"... Dec 20 18:09:03 volumio volumio[1387]: info: Starting Udev Watcher for removable devices Dec 20 18:09:03 volumio volumio[1387]: info: Mounting Device issd Dec 20 18:09:03 volumio kernel: usb 3-2: USB disconnect, device number 8 Dec 20 18:09:03 volumio sudo[1472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/nvme0n1p1 /mnt/INTERNAL/ -o noatime Dec 20 18:09:03 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:03 volumio kernel: exFAT-fs (nvme0n1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 20 18:09:03 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:03 volumio volumio[1387]: info: Binding Internal Memory position Dec 20 18:09:03 volumio sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -o bind /mnt/INTERNAL /data/INTERNAL Dec 20 18:09:03 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:03 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:03 volumio volumio[1387]: info: Ignoring mount for partition: boot Dec 20 18:09:03 volumio volumio[1387]: info: Ignoring mount for partition: volumio Dec 20 18:09:03 volumio volumio[1387]: info: Ignoring mount for partition: volumio_data Dec 20 18:09:03 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "volumio_command_line_client"... Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "upnp"... Dec 20 18:09:03 volumio volumio[1387]: info: [1766221743453] Starting Upmpd Daemon Dec 20 18:09:03 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "my_music"... Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "mpd"... Dec 20 18:09:03 volumio kernel: usb usb1-port2: over-current change #14 Dec 20 18:09:03 volumio kernel: usb usb2-port1: over-current change #18 Dec 20 18:09:03 volumio kernel: usb usb4-port1: over-current change #18 Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "upnp_browser"... Dec 20 18:09:03 volumio kernel: usb usb1-port1: over-current change #16 Dec 20 18:09:03 volumio kernel: usb usb3-port1: over-current change #7 Dec 20 18:09:03 volumio dhcpcd[1078]: eth0: soliciting a DHCP lease Dec 20 18:09:03 volumio volumio[1387]: info: Starting UPNP Browser Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "alarm-clock"... Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "airplay_emulation"... Dec 20 18:09:03 volumio volumio[1387]: info: Starting Shairport Sync Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "last_100"... Dec 20 18:09:03 volumio volumio[1387]: info: Loading plugin "webradio"... Dec 20 18:09:04 volumio volumio[1387]: info: Loading plugin "i2s_dacs"... Dec 20 18:09:04 volumio volumio[1387]: info: Loading plugin "volumiodiscovery"... Dec 20 18:09:04 volumio volumio[1387]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 18:09:04 volumio volumio[1387]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 18:09:04 volumio volumio[1387]: *** WARNING *** For more information see Dec 20 18:09:04 volumio volumio[1387]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 18:09:04 volumio volumio[1387]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 18:09:04 volumio volumio[1387]: *** WARNING *** For more information see Dec 20 18:09:04 volumio node[1387]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 18:09:04 volumio node[1387]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 18:09:04 volumio node[1387]: *** WARNING *** For more information see Dec 20 18:09:04 volumio node[1387]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 18:09:04 volumio node[1387]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 18:09:04 volumio node[1387]: *** WARNING *** For more information see Dec 20 18:09:04 volumio volumio[1387]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 18:09:04 volumio volumio[1387]: info: Discovery: Started advertising with name: Volumio Dec 20 18:09:04 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 18:09:04 volumio volumio[1387]: info: Loading plugin "youtube2"... Dec 20 18:09:04 volumio kernel: usb usb1-port1: over-current change #17 Dec 20 18:09:04 volumio kernel: usb usb4-port1: over-current change #19 Dec 20 18:09:04 volumio kernel: usb usb2-port1: over-current change #19 Dec 20 18:09:04 volumio volumio[1387]: info: Loading plugin "now_playing"... Dec 20 18:09:04 volumio kernel: usb usb1-port2: over-current change #15 Dec 20 18:09:04 volumio volumio[1387]: info: Loading plugin "peppy_screensaver"... Dec 20 18:09:04 volumio kernel: usb usb3-port2: over-current change #7 Dec 20 18:09:05 volumio volumio[1387]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "outputs"... Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "albumart"... Dec 20 18:09:05 volumio volumio[1387]: info: Plugin example_plugin is not enabled Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "inputs"... Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "updater_comm"... Dec 20 18:09:05 volumio volumio[1387]: info: Plugin mpdemulation is not enabled Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "rest_api"... Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "websocket"... Dec 20 18:09:05 volumio volumio[1387]: info: Starting Socket.io Server version 1.7.4 Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "personal_radio"... Dec 20 18:09:05 volumio kernel: usb usb1-port1: over-current change #18 Dec 20 18:09:05 volumio volumio[1482]: Forking 3 albumart workers Dec 20 18:09:05 volumio kernel: usb usb2-port1: over-current change #20 Dec 20 18:09:05 volumio kernel: usb usb4-port1: over-current change #20 Dec 20 18:09:05 volumio kernel: usb usb1-port2: over-current change #16 Dec 20 18:09:05 volumio kernel: usb usb3-port1: over-current change #8 Dec 20 18:09:05 volumio kernel: usb usb2-port1: over-current change #21 Dec 20 18:09:05 volumio kernel: usb usb4-port1: over-current change #21 Dec 20 18:09:05 volumio kernel: usb usb1-port1: over-current change #19 Dec 20 18:09:05 volumio volumio[1493]: Starting albumart workers Dec 20 18:09:05 volumio volumio[1492]: Starting albumart workers Dec 20 18:09:05 volumio volumio[1494]: Starting albumart workers Dec 20 18:09:05 volumio volumio[1387]: info: ControllerPersonalRadio::constructor Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "peppymeterbasic"... Dec 20 18:09:05 volumio kernel: usb usb1-port2: over-current change #17 Dec 20 18:09:05 volumio kernel: usb usb3-port2: over-current change #8 Dec 20 18:09:05 volumio kernel: usb usb2-port1: over-current change #22 Dec 20 18:09:05 volumio kernel: usb usb4-port1: over-current change #22 Dec 20 18:09:05 volumio kernel: usb usb1-port1: over-current change #20 Dec 20 18:09:05 volumio volumio[1387]: info: Loading plugin "touch_display"... Dec 20 18:09:05 volumio volumio[1387]: info: Applying required configuration parameters for plugin touch_display Dec 20 18:09:05 volumio volumio[1387]: info: Loading i18n strings for locale ko Dec 20 18:09:05 volumio volumio[1387]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 20 18:09:05 volumio volumio[1387]: Updating browse sources language Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::initPlayerControls Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 18:09:05 volumio volumio[1387]: Express server listening on port 3000 Dec 20 18:09:05 volumio volumio[1387]: [Metrics] WebUI: 3s 581.01ms Dec 20 18:09:05 volumio volumio[1387]: info: CoreStateMachine::resetVolumioState Dec 20 18:09:05 volumio volumio[1387]: info: CoreStateMachine::getcurrentVolume Dec 20 18:09:05 volumio volumio[1387]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 18:09:05 volumio volumio[1387]: info: Volumio Network Manager: Network status updated: 0 Dec 20 18:09:05 volumio volumio-remote-updater[996]: [2025-12-20 18:09:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766221742 101 Dec 20 18:09:05 volumio volumio[1387]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Dec 20 18:09:06 volumio volumio[1387]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: Reloading queue from file Dec 20 18:09:06 volumio kernel: usb 3-2: new full-speed USB device number 13 using xhci-hcd Dec 20 18:09:06 volumio volumio[1387]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 20 18:09:06 volumio volumio[1387]: info: VolumeController:: Volume=69 Mute =false Dec 20 18:09:06 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:06 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:06 volumio volumio[1387]: info: CoreStateMachine::updateTrackBlock Dec 20 18:09:06 volumio volumio[1387]: info: CorePlayQueue::getTrackBlock Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 18:09:06 volumio volumio[1387]: info: Setting Device type: Raspberry PI Dec 20 18:09:06 volumio volumio[1387]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 20 18:09:06 volumio volumio[1387]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Dec 20 18:09:06 volumio volumio[1387]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 20 18:09:06 volumio volumio[1387]: info: CoreStateMachine::setRepeat null single undefined Dec 20 18:09:06 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:06 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:06 volumio volumio[1387]: info: CoreStateMachine::setRandom null Dec 20 18:09:06 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:06 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:06 volumio volumio[1387]: info: Completed loading Core Plugins Dec 20 18:09:06 volumio volumio[1387]: info: Preparing to generate the ALSA configuration file Dec 20 18:09:06 volumio volumio[1387]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 20 18:09:06 volumio volumio[1387]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 20 18:09:06 volumio volumio[1387]: info: Reading ALSA contributions from plugins. Dec 20 18:09:06 volumio volumio[1387]: info: Discovery: adding 5de38212-a978-443f-8ff4-7b3bdff52aa1 Dec 20 18:09:06 volumio volumio[1387]: info: Discovery: Found device Volumio Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:06 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:06 volumio volumio[1387]: info: VolumeController:: Volume=69 Mute =false Dec 20 18:09:06 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:06 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:06 volumio kernel: usb usb1-port1: over-current change #21 Dec 20 18:09:06 volumio volumio[1387]: info: Asound.conf file unchanged, so no further update is needed Dec 20 18:09:06 volumio volumio[1387]: info: Output device has changed, restarting MPD Dec 20 18:09:06 volumio volumio[1387]: info: Output device has changed, restarting Shairport Sync Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio sudo[1559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 18:09:06 volumio sudo[1559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 18:09:06 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1559]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio volumio[1387]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 18:09:06 volumio volumio[1387]: info: ___________ START PLUGINS ___________ Dec 20 18:09:06 volumio volumio[1387]: info: ControllerMpd::onStart: Initializing MPD Dec 20 18:09:06 volumio volumio[1387]: info: Creating MPD Configuration file Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 18:09:06 volumio volumio[1387]: info: [1766221746112] CoreMusicLibrary::Adding element 미디어 서버 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:06 volumio kernel: usb usb4-port1: over-current change #23 Dec 20 18:09:06 volumio kernel: usb usb2-port1: over-current change #23 Dec 20 18:09:06 volumio volumio[1387]: info: UPNP Browser: Client initialized successfully Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio sudo[1569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 18:09:06 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio sudo[1572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 18:09:06 volumio sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 20 18:09:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 20 18:09:06 volumio volumio[1387]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 18:09:06 volumio volumio[1387]: info: [1766221746137] CoreMusicLibrary::Adding element Last_100 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 18:09:06 volumio volumio[1387]: info: [1766221746138] CoreMusicLibrary::Adding element Webradio Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:06 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 20 18:09:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 20 18:09:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 20 18:09:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 20 18:09:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 18:09:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 20 18:09:06 volumio kernel: usb 3-2: Device not responding to setup address. Dec 20 18:09:06 volumio volumio[1387]: info: Initializing BBC Radios Dec 20 18:09:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 18:09:06 volumio volumio[1387]: info: [1766221746176] CoreMusicLibrary::Adding element YouTube2 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:06 volumio volumio[1387]: Cannot find translation for source YouTube2 Dec 20 18:09:06 volumio sudo[1585]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 18:09:06 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 18:09:06 volumio sudo[1601]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 20 18:09:06 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio volumio[1387]: info: [now-playing] ConfigUpdater: config is up to date. Dec 20 18:09:06 volumio volumio[1387]: info: Loading i18n strings for locale ko Dec 20 18:09:06 volumio volumio[1387]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 20 18:09:06 volumio volumio[1387]: info: /tmp/myfifo created Dec 20 18:09:06 volumio volumio[1387]: info: /tmp/myfifosa created Dec 20 18:09:06 volumio sudo[1609]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Dec 20 18:09:06 volumio sudo[1444]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio volumio[1387]: info: snd-dummy loaded Dec 20 18:09:06 volumio kernel: usb usb1-port2: over-current change #18 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio sudo[1630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl Dec 20 18:09:06 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio volumio[1387]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 18:09:06 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 18:09:06 volumio volumio[1387]: info: [1766221746320] CoreMusicLibrary::Adding element 개인 라디오 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:06 volumio volumio[1387]: Cannot find translation for source YouTube2 Dec 20 18:09:06 volumio volumio[1387]: Cannot find translation for source 개인 라디오 Dec 20 18:09:06 volumio kernel: usb usb4-port1: over-current change #24 Dec 20 18:09:06 volumio kernel: usb usb2-port1: over-current change #24 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 18:09:06 volumio volumio[1387]: info: Loading i18n strings for locale ko Dec 20 18:09:06 volumio volumio[1387]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 18:09:06 volumio sudo[1633]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 20 18:09:06 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio volumio[1387]: info: Volumio Calling Home Dec 20 18:09:06 volumio kernel: usb 3-2: Device not responding to setup address. Dec 20 18:09:06 volumio kernel: usb usb1-port1: over-current change #22 Dec 20 18:09:06 volumio sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Dec 20 18:09:06 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio volumio[1387]: info: Preparing to generate the ALSA configuration file Dec 20 18:09:06 volumio kernel: snd_aloop snd_aloop.0: cannot find the slot for index 7 (range 0-7), error: -16 Dec 20 18:09:06 volumio kernel: snd_aloop snd_aloop.0: probe with driver snd_aloop failed with error -16 Dec 20 18:09:06 volumio kernel: aloop: No loopback enabled Dec 20 18:09:06 volumio sudo[1643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 20 18:09:06 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 20 18:09:06 volumio sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 20 18:09:06 volumio sudo[1645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio volumio[1387]: info: [now-playing] Fetching API key... Dec 20 18:09:06 volumio systemd[1]: Reloading. Dec 20 18:09:06 volumio volumio[1387]: info: [now-playing] App is listening on port 4004. Dec 20 18:09:06 volumio volumio[1387]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 20 18:09:06 volumio volumio[1387]: Unhandled rejection Error: No sockets available, cannot start. Dec 20 18:09:06 volumio volumio[1387]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 20 18:09:06 volumio volumio[1387]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 20 18:09:06 volumio volumio[1387]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 20 18:09:06 volumio volumio[1387]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 20 18:09:06 volumio volumio[1387]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 20 18:09:06 volumio volumio[1387]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 20 18:09:06 volumio volumio[1387]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 20 18:09:06 volumio volumio[1387]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 20 18:09:06 volumio volumio[1387]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Dec 20 18:09:06 volumio volumio[1387]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 20 18:09:06 volumio volumio[1387]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 20 18:09:06 volumio volumio[1387]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 20 18:09:06 volumio volumio[1387]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 20 18:09:06 volumio volumio[1387]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 20 18:09:06 volumio volumio[1387]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 20 18:09:06 volumio volumio[1387]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 18:09:06 volumio volumio[1387]: info: ControllerPersonalRadio:fetchRadioUrl Error: FetchError: request to https://raw.githubusercontent.com/ChrisPanda/volumio-kradio-key/master/radiokey.json failed, reason: getaddrinfo EBUSY raw.githubusercontent.com Dec 20 18:09:06 volumio volumio[1387]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 20 18:09:06 volumio volumio[1387]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 20 18:09:06 volumio volumio[1387]: info: Reading ALSA contributions from plugins. Dec 20 18:09:06 volumio volumio[1387]: info: touch_display: No backlight interface detected. Dec 20 18:09:06 volumio volumio[1387]: error: [now-playing] Error fetching https://openweathermap.org: TypeError: fetch failed Dec 20 18:09:06 volumio volumio[1387]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Dec 20 18:09:06 volumio volumio[1387]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 20 18:09:06 volumio volumio[1387]: at async fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:25:26) Dec 20 18:09:06 volumio volumio[1387]: at async doGet (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:118:22) Dec 20 18:09:06 volumio volumio[1387]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): fetch failed Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:06 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:06 volumio volumio[1387]: info: MPD Permissions set Dec 20 18:09:06 volumio volumio[1387]: info: MPD Permissions set Dec 20 18:09:06 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio volumio[1387]: 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 18:09:06 volumio kernel: usb usb1-port2: over-current change #19 Dec 20 18:09:06 volumio volumio[1387]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio sudo[1653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Dec 20 18:09:06 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio sudo[1653]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio kernel: usb usb4-port1: over-current change #25 Dec 20 18:09:06 volumio kernel: usb usb2-port1: over-current change #25 Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: error: failed to load snd_dummy: Error: Command failed: /usr/bin/sudo /sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 20 18:09:06 volumio volumio[1387]: modprobe: ERROR: could not insert 'snd_aloop': No such device Dec 20 18:09:06 volumio volumio[1387]: info: touch_display: IframeKeyboardBridge extension installed successfully Dec 20 18:09:06 volumio kernel: usb 3-2: device not accepting address 13, error -71 Dec 20 18:09:06 volumio kernel: usb 3-2: WARN: invalid context state for evaluate context command. Dec 20 18:09:06 volumio volumio[1387]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 20 18:09:06 volumio volumio[1387]: info: Starting Shairport Sync Dec 20 18:09:06 volumio volumio[1387]: info: Starting Shairport Sync Dec 20 18:09:06 volumio sudo[1689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 20 18:09:06 volumio sudo[1687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 20 18:09:06 volumio sudo[1687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Dec 20 18:09:06 volumio sudo[1691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio volumio[1387]: info: Starting Shairport Sync Dec 20 18:09:06 volumio volumio[1387]: info: Starting Shairport Sync Dec 20 18:09:06 volumio sudo[1689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 18:09:06 volumio sudo[1696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1687]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio sudo[1691]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio sudo[1689]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio sudo[1693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 18:09:06 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 18:09:06 volumio sudo[1700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 18:09:06 volumio sudo[1701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio kernel: usb usb1-port1: over-current change #23 Dec 20 18:09:06 volumio volumio[1387]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 20 18:09:06 volumio volumio[1387]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Dec 20 18:09:06 volumio volumio[1387]: info: Asound.conf file unchanged, so no further update is needed Dec 20 18:09:06 volumio volumio[1387]: info: Output device has changed, restarting MPD Dec 20 18:09:06 volumio volumio[1387]: info: Output device has changed, restarting Shairport Sync Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 18:09:06 volumio sudo[1714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 18:09:06 volumio sudo[1708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 18:09:06 volumio sudo[1708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1708]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio sudo[1710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 18:09:06 volumio sudo[1710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio sudo[1710]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio volumio[1387]: info: MPD Permissions set Dec 20 18:09:06 volumio volumio[1387]: info: MPD Permissions set Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:06 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:06 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:06 volumio volumio[1387]: info: Starting Shairport Sync Dec 20 18:09:06 volumio sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 18:09:06 volumio sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio kernel: usb usb1-port2: over-current change #20 Dec 20 18:09:06 volumio kernel: usb usb4-port1: over-current change #26 Dec 20 18:09:06 volumio kernel: usb usb2-port1: over-current change #26 Dec 20 18:09:06 volumio kernel: usb usb1-port1: over-current change #24 Dec 20 18:09:06 volumio sudo[1647]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:06 volumio volumio[1387]: info: touch_display: systemctl daemon-reload succeeded. Dec 20 18:09:06 volumio sudo[1739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 20 18:09:06 volumio sudo[1739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:06 volumio systemd[1]: Reloading. Dec 20 18:09:06 volumio kernel: usb usb4-port1: over-current change #27 Dec 20 18:09:06 volumio kernel: usb usb2-port1: over-current change #27 Dec 20 18:09:06 volumio kernel: usb usb1-port2: over-current change #21 Dec 20 18:09:07 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 20 18:09:07 volumio kernel: usb usb1-port1: over-current change #25 Dec 20 18:09:07 volumio sudo[1759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 18:09:07 volumio sudo[1759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:07 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:07 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:07 volumio kernel: usb usb4-port1: over-current change #28 Dec 20 18:09:07 volumio kernel: usb usb2-port1: over-current change #28 Dec 20 18:09:07 volumio kernel: usb usb1-port2: over-current change #22 Dec 20 18:09:07 volumio kernel: usb usb3-port1: over-current change #9 Dec 20 18:09:07 volumio sudo[1645]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio volumio[1387]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 20 18:09:07 volumio kernel: usb usb4-port1: over-current change #29 Dec 20 18:09:07 volumio kernel: usb usb2-port1: over-current change #29 Dec 20 18:09:07 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 20 18:09:07 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Dec 20 18:09:07 volumio kernel: usb usb1-port1: over-current change #26 Dec 20 18:09:07 volumio sudo[1739]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio volumio[1387]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 20 18:09:07 volumio volumio[1387]: info: touch_display: Volumio Kiosk started. Dec 20 18:09:07 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 20 18:09:07 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:07 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:07 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 20 18:09:07 volumio systemd[1]: shairport-sync.service: Consumed 1.591s CPU time. Dec 20 18:09:07 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:07 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:07 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 20 18:09:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 20 18:09:07 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio volumio[1387]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 20 18:09:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 20 18:09:07 volumio volumio[1387]: info: touch_display: X display number found: Dec 20 18:09:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 20 18:09:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 20 18:09:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 20 18:09:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 20 18:09:07 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 20 18:09:07 volumio sudo[1696]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio sudo[1736]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio sudo[1701]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio volumio[1387]: info: Shairport-Sync Started Dec 20 18:09:07 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio sudo[1700]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio volumio[1387]: Error adding Membership: Error: addMembership EINVAL Dec 20 18:09:07 volumio volumio[1387]: info: Shairport-Sync Started Dec 20 18:09:07 volumio volumio[1387]: info: Shairport-Sync Started Dec 20 18:09:07 volumio volumio[1387]: info: Shairport-Sync Started Dec 20 18:09:07 volumio volumio[1387]: info: Shairport-Sync Started Dec 20 18:09:07 volumio kernel: usb usb1-port2: over-current change #23 Dec 20 18:09:07 volumio volumio[1387]: info: touch_display: X display number found: 0 Dec 20 18:09:07 volumio startx[1786]: X.Org X Server 1.21.1.7 Dec 20 18:09:07 volumio startx[1786]: X Protocol Version 11, Revision 0 Dec 20 18:09:07 volumio startx[1786]: Current Operating System: Linux volumio 6.12.47-v8+ #1904 SMP PREEMPT Mon Sep 15 13:10:52 BST 2025 aarch64 Dec 20 18:09:07 volumio startx[1786]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=2C:CF:67:04:16:3E vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=51924888-f01d-4cc4-9a45-06040d56145e uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no cfg80211.ieee80211_regdom=KR Dec 20 18:09:07 volumio startx[1786]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Dec 20 18:09:07 volumio startx[1786]: Current version of pixman: 0.44.0 Dec 20 18:09:07 volumio startx[1786]: Before reporting problems, check http://wiki.x.org Dec 20 18:09:07 volumio startx[1786]: to make sure that you have the latest version. Dec 20 18:09:07 volumio startx[1786]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 20 18:09:07 volumio startx[1786]: (++) from command line, (!!) notice, (II) informational, Dec 20 18:09:07 volumio startx[1786]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 20 18:09:07 volumio startx[1786]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Dec 20 18:09:07 2025 Dec 20 18:09:07 volumio sudo[1787]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 18:09:07 volumio sudo[1787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 18:09:07 volumio sudo[1805]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 20 18:09:07 volumio sudo[1787]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:07 volumio startx[1786]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 20 18:09:07 volumio startx[1786]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 20 18:09:07 volumio dhcpcd[1078]: eth0: offered 192.168.0.38 from 192.168.0.1 Dec 20 18:09:07 volumio dhcpcd[950]: eth0: offered 192.168.0.38 from 192.168.0.1 Dec 20 18:09:07 volumio sh[925]: eth0: offered 192.168.0.38 from 192.168.0.1 Dec 20 18:09:07 volumio kernel: usb usb4-port1: over-current change #30 Dec 20 18:09:07 volumio kernel: usb usb2-port1: over-current change #30 Dec 20 18:09:07 volumio sh[925]: eth0: probing address 192.168.0.38/24 Dec 20 18:09:07 volumio dhcpcd[950]: eth0: probing address 192.168.0.38/24 Dec 20 18:09:07 volumio dhcpcd[1078]: eth0: probing address 192.168.0.38/24 Dec 20 18:09:07 volumio volumio[1387]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 20 18:09:07 volumio kernel: usb usb3-port2: over-current change #9 Dec 20 18:09:07 volumio volumio[1387]: info: touch_display: X display number found: 0 Dec 20 18:09:07 volumio kernel: usb usb1-port1: over-current change #27 Dec 20 18:09:07 volumio kernel: usb usb1-port2: over-current change #24 Dec 20 18:09:07 volumio kernel: usb usb4-port1: over-current change #31 Dec 20 18:09:07 volumio kernel: usb usb2-port1: over-current change #31 Dec 20 18:09:07 volumio kernel: usb usb3-port1: over-current change #10 Dec 20 18:09:07 volumio kernel: usb usb1-port1: over-current change #28 Dec 20 18:09:07 volumio kernel: usb usb4-port1: over-current change #32 Dec 20 18:09:07 volumio kernel: usb usb2-port1: over-current change #32 Dec 20 18:09:08 volumio kernel: usb usb1-port2: over-current change #25 Dec 20 18:09:08 volumio kernel: usb usb1-port1: over-current change #29 Dec 20 18:09:08 volumio kernel: usb usb3-port2: over-current change #10 Dec 20 18:09:08 volumio kernel: usb usb4-port1: over-current change #33 Dec 20 18:09:08 volumio kernel: usb usb2-port1: over-current change #33 Dec 20 18:09:08 volumio mpd[1806]: 2025-12-20T18:09:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 18:09:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 20 18:09:08 volumio sudo[1759]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:08 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:08 volumio sudo[1714]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:08 volumio sudo[1572]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:08 volumio volumio[1387]: info: Completed starting Core Plugins Dec 20 18:09:08 volumio volumio[1387]: info: ------------------------------------------- Dec 20 18:09:08 volumio volumio[1387]: info: ----- MyVolumio plugins startup ---- Dec 20 18:09:08 volumio volumio[1387]: info: ------------------------------------------- Dec 20 18:09:08 volumio volumio[1387]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 20 18:09:08 volumio volumio[1387]: error: MPD error: The expression evaluated to a falsy value: Dec 20 18:09:08 volumio volumio[1387]: assert.ok(self.idling) Dec 20 18:09:08 volumio volumio[1387]: error: The expression evaluated to a falsy value: Dec 20 18:09:08 volumio volumio[1387]: assert.ok(self.idling) Dec 20 18:09:08 volumio kernel: usb usb1-port2: over-current change #26 Dec 20 18:09:08 volumio volumio[1387]: error: MPD error: The expression evaluated to a falsy value: Dec 20 18:09:08 volumio volumio[1387]: assert.ok(self.idling) Dec 20 18:09:08 volumio volumio[1387]: error: The expression evaluated to a falsy value: Dec 20 18:09:08 volumio volumio[1387]: assert.ok(self.idling) Dec 20 18:09:08 volumio volumio[1387]: info: MPD running with PID1806 Dec 20 18:09:08 volumio volumio[1387]: ,establishing connection Dec 20 18:09:08 volumio volumio[1387]: error: MPD error: The expression evaluated to a falsy value: Dec 20 18:09:08 volumio volumio[1387]: assert.ok(self.idling) Dec 20 18:09:08 volumio volumio[1387]: error: The expression evaluated to a falsy value: Dec 20 18:09:08 volumio volumio[1387]: assert.ok(self.idling) Dec 20 18:09:08 volumio volumio[1387]: error: MPD error: The expression evaluated to a falsy value: Dec 20 18:09:08 volumio volumio[1387]: assert.ok(self.idling) Dec 20 18:09:08 volumio volumio[1387]: error: The expression evaluated to a falsy value: Dec 20 18:09:08 volumio volumio[1387]: assert.ok(self.idling) Dec 20 18:09:08 volumio volumio[1387]: error: updateQueue error: null Dec 20 18:09:08 volumio kernel: usb usb4-port1: over-current change #34 Dec 20 18:09:08 volumio kernel: usb usb2-port1: over-current change #34 Dec 20 18:09:08 volumio kernel: usb usb1-port1: over-current change #30 Dec 20 18:09:08 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:08 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:08 volumio volumio[1387]: info: touch_display: Setting screensaver timeout to 20 seconds. Dec 20 18:09:08 volumio kernel: usb 3-2: new full-speed USB device number 15 using xhci-hcd Dec 20 18:09:08 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:08 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:08 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:08 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:08 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.0003/input/input8 Dec 20 18:09:08 volumio kernel: hid-multitouch 0003:1A86:E5E3.0003: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 18:09:08 volumio kernel: usb usb3-port1: over-current change #11 Dec 20 18:09:08 volumio kernel: usb usb1-port1: over-current change #31 Dec 20 18:09:08 volumio kernel: usb usb2-port1: over-current change #35 Dec 20 18:09:08 volumio kernel: usb usb4-port1: over-current change #35 Dec 20 18:09:09 volumio kernel: usb usb1-port2: over-current change #27 Dec 20 18:09:09 volumio kernel: usb usb3-port2: over-current change #11 Dec 20 18:09:09 volumio kernel: usb 3-2: USB disconnect, device number 15 Dec 20 18:09:09 volumio kernel: usb 3-2: new full-speed USB device number 16 using xhci-hcd Dec 20 18:09:09 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:09 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:09 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:09 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09: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.0004/input/input9 Dec 20 18:09:09 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 18:09:09 volumio kernel: usb usb3-port1: over-current change #12 Dec 20 18:09:11 volumio sudo[1991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 18:09:11 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:11 volumio sudo[1993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 18:09:11 volumio sudo[1993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:11 volumio kernel: usb usb1-port1: over-current change #32 Dec 20 18:09:11 volumio sudo[1996]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 18:09:11 volumio sudo[1996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:11 volumio sudo[2000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 18:09:11 volumio sudo[2000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 20 18:09:11 volumio kernel: usb usb2-port1: over-current change #36 Dec 20 18:09:11 volumio kernel: usb usb4-port1: over-current change #36 Dec 20 18:09:11 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:11 volumio mpd_monitor.sh[2001]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 18:09:11 volumio volumio[1387]: info: Successfully started MPD Monitor Dec 20 18:09:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 20 18:09:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 20 18:09:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 20 18:09:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 20 18:09:11 volumio sudo[1993]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:11 volumio mpd_monitor.sh[2006]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 18:09:11 volumio sudo[1996]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:11 volumio sudo[2000]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:11 volumio volumio[1387]: info: Successfully started MPD Monitor Dec 20 18:09:11 volumio volumio[1387]: info: Successfully started MPD Monitor Dec 20 18:09:11 volumio volumio[1387]: info: Successfully started MPD Monitor Dec 20 18:09:11 volumio kernel: usb usb1-port2: over-current change #28 Dec 20 18:09:11 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:11 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:11 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:11 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:11 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:11 volumio dhcpcd[1078]: eth0: leased 192.168.0.38 for 7200 seconds Dec 20 18:09:11 volumio avahi-daemon[975]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.38. Dec 20 18:09:11 volumio avahi-daemon[975]: New relevant interface eth0.IPv4 for mDNS. Dec 20 18:09:11 volumio avahi-daemon[975]: Registering new address record for 192.168.0.38 on eth0.IPv4. Dec 20 18:09:11 volumio dhcpcd[1078]: eth0: adding route to 192.168.0.0/24 Dec 20 18:09:11 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 20 18:09:11 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 20 18:09:11 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 20 18:09:11 volumio dhcpcd[1078]: eth0: adding default route via 192.168.0.1 Dec 20 18:09:11 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 20 18:09:11 volumio welcome[2045]: Resolved ip:[1] 192.168.0.38 Dec 20 18:09:11 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 20 18:09:11 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 20 18:09:11 volumio kernel: usb usb3-port2: over-current change #12 Dec 20 18:09:11 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 6 Dec 20 18:09:11 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 7 Dec 20 18:09:11 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 8 Dec 20 18:09:11 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 9 Dec 20 18:09:11 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 10 Dec 20 18:09:11 volumio kernel: usb usb1-port1: over-current change #33 Dec 20 18:09:12 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 11 Dec 20 18:09:12 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:12 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:09:12 volumio volumio[1387]: info: CoreStateMachine::getQueue Dec 20 18:09:12 volumio volumio[1387]: info: CorePlayQueue::getQueue Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Dec 20 18:09:12 volumio volumio[1387]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Dec 20 18:09:12 volumio volumio[1387]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Dec 20 18:09:12 volumio volumio[1387]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Dec 20 18:09:12 volumio volumio[1387]: info: [now-playing] Fetching API key... Dec 20 18:09:12 volumio kernel: usb usb2-port1: over-current change #37 Dec 20 18:09:12 volumio kernel: usb usb4-port1: over-current change #37 Dec 20 18:09:12 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 20 18:09:12 volumio kernel: usb 3-2: USB disconnect, device number 16 Dec 20 18:09:12 volumio kernel: usb usb1-port2: over-current change #29 Dec 20 18:09:12 volumio winbindd[2056]: [2025/12/20 18:09:12.160906, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 20 18:09:12 volumio winbindd[2056]: winbindd version 4.17.8-Raspbian started. Dec 20 18:09:12 volumio winbindd[2056]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 20 18:09:12 volumio winbindd[2056]: [2025/12/20 18:09:12.166507, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Dec 20 18:09:12 volumio winbindd[2056]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 20 18:09:12 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 20 18:09:12 volumio volumio[1387]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 20 18:09:12 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 20 18:09:12 volumio kernel: usb usb2-port1: over-current change #38 Dec 20 18:09:12 volumio kernel: usb usb4-port1: over-current change #38 Dec 20 18:09:12 volumio kernel: usb usb3-port1: over-current change #13 Dec 20 18:09:12 volumio kernel: usb usb1-port1: over-current change #34 Dec 20 18:09:12 volumio kernel: usb usb1-port2: over-current change #30 Dec 20 18:09:12 volumio smbd[2067]: [2025/12/20 18:09:12.402106, 0] ../../source3/smbd/server.c:1741(main) Dec 20 18:09:12 volumio smbd[2067]: smbd version 4.17.8-Raspbian started. Dec 20 18:09:12 volumio smbd[2067]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 20 18:09:12 volumio kernel: usb usb2-port1: over-current change #39 Dec 20 18:09:12 volumio kernel: usb usb4-port1: over-current change #39 Dec 20 18:09:12 volumio kernel: usb usb3-port2: over-current change #13 Dec 20 18:09:12 volumio kernel: usb usb1-port1: over-current change #35 Dec 20 18:09:12 volumio kernel: usb usb1-port2: over-current change #31 Dec 20 18:09:12 volumio kernel: usb usb2-port1: over-current change #40 Dec 20 18:09:12 volumio kernel: usb usb4-port1: over-current change #40 Dec 20 18:09:12 volumio volumio[1387]: info: Discovery: this is already registered, 5de38212-a978-443f-8ff4-7b3bdff52aa1 Dec 20 18:09:12 volumio volumio[1387]: info: Discovery: Found device Volumio Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:12 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:12 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 20 18:09:12 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Dec 20 18:09:12 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 18:09:12 volumio volumio[1387]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 20 18:09:12 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 18:09:12 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Dec 20 18:09:12 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Dec 20 18:09:12 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 20 18:09:12 volumio upmpdcli[2083]: Could not open config: /tmp/upmpdcli.conf Dec 20 18:09:12 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 20 18:09:12 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 20 18:09:12 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 20 18:09:12 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 20 18:09:12 volumio systemd[1]: Startup finished in 15.250s (kernel) + 17.413s (userspace) = 32.664s. Dec 20 18:09:12 volumio kernel: usb usb1-port1: over-current change #36 Dec 20 18:09:12 volumio kernel: usb usb3-port1: over-current change #14 Dec 20 18:09:12 volumio volumio[1387]: info: Listing playlists Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:09:12 volumio volumio[1387]: info: CoreStateMachine::getQueue Dec 20 18:09:12 volumio volumio[1387]: info: CorePlayQueue::getQueue Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:12 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:12 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:12 volumio ntpd[1206]: IO: Listen normally on 3 eth0 192.168.0.38:123 Dec 20 18:09:12 volumio ntpd[1206]: IO: new interface(s) found: waking up resolver Dec 20 18:09:12 volumio ntpd[1206]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 18:09:12 volumio sh[925]: eth0: leased 192.168.0.38 for 7200 seconds Dec 20 18:09:12 volumio dhcpcd[950]: eth0: leased 192.168.0.38 for 7200 seconds Dec 20 18:09:12 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 20 18:09:12 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 20 18:09:12 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 20 18:09:12 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 20 18:09:12 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 20 18:09:12 volumio sh[925]: forked to background, child pid 949 Dec 20 18:09:12 volumio sh[925]: eth0: adding route to 192.168.0.0/24 Dec 20 18:09:12 volumio sh[925]: eth0: adding default route via 192.168.0.1 Dec 20 18:09:12 volumio dhcpcd[950]: eth0: adding route to 192.168.0.0/24 Dec 20 18:09:12 volumio dhcpcd[950]: eth0: adding default route via 192.168.0.1 Dec 20 18:09:12 volumio kernel: usb usb4-port1: over-current change #41 Dec 20 18:09:12 volumio kernel: usb usb2-port1: over-current change #41 Dec 20 18:09:12 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 20 18:09:12 volumio kernel: usb usb1-port2: over-current change #32 Dec 20 18:09:12 volumio welcome[2105]: Resolved ip:[1] 192.168.0.38 Dec 20 18:09:12 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 20 18:09:12 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 20 18:09:12 volumio ntpd[1206]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 20 18:09:12 volumio ntpd[1206]: DNS: Pool taking: 194.0.5.123 Dec 20 18:09:12 volumio ntpd[1206]: DNS: Pool taking: 175.195.167.194 Dec 20 18:09:12 volumio ntpd[1206]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 20 18:09:12 volumio ifplugd(eth0)[1245]: client: ifup: interface eth0 already configured Dec 20 18:09:12 volumio sh[2137]: eth0=eth0 Dec 20 18:09:13 volumio kernel: usb usb1-port1: over-current change #37 Dec 20 18:09:13 volumio kernel: usb usb3-port2: over-current change #14 Dec 20 18:09:13 volumio ifplugd(eth0)[1245]: Program executed successfully. Dec 20 18:09:13 volumio volumio[1387]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 20 18:09:13 volumio kernel: usb usb1-port1: over-current change #38 Dec 20 18:09:13 volumio kernel: usb usb4-port1: over-current change #42 Dec 20 18:09:13 volumio kernel: usb usb2-port1: over-current change #42 Dec 20 18:09:13 volumio volumio[1387]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 20 18:09:13 volumio kernel: usb usb1-port2: over-current change #33 Dec 20 18:09:13 volumio kernel: usb usb3-port1: over-current change #15 Dec 20 18:09:13 volumio volumio[1387]: info: Volumio Network Manager: Network status updated: 1 Dec 20 18:09:13 volumio kernel: usb usb2-port1: over-current change #43 Dec 20 18:09:13 volumio kernel: usb usb4-port1: over-current change #43 Dec 20 18:09:13 volumio kernel: usb usb1-port1: over-current change #39 Dec 20 18:09:13 volumio sudo[2153]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 18:09:13 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:13 volumio sudo[2155]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 18:09:13 volumio sudo[2155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:13 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:13 volumio sudo[2155]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:13 volumio sudo[2158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 20 18:09:13 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:13 volumio volumio[1387]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 20 18:09:13 volumio kernel: usb usb1-port2: over-current change #34 Dec 20 18:09:13 volumio kernel: usb usb3-port2: over-current change #15 Dec 20 18:09:13 volumio kernel: usb usb4-port1: over-current change #44 Dec 20 18:09:13 volumio kernel: usb usb2-port1: over-current change #44 Dec 20 18:09:13 volumio kernel: usb usb1-port1: over-current change #40 Dec 20 18:09:13 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:13 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:13 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:09:13 volumio volumio[1387]: info: Listing playlists Dec 20 18:09:13 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 18:09:13 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 18:09:13 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 18:09:13 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 18:09:13 volumio ntpd[1206]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 18:09:13 volumio ntpd[1206]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 20 18:09:13 volumio ntpd[1206]: DNS: Pool taking: 221.151.118.78 Dec 20 18:09:13 volumio ntpd[1206]: DNS: Pool taking: 121.174.142.81 Dec 20 18:09:13 volumio ntpd[1206]: DNS: Pool taking: 240b:400d:3:3300:aeda:71da:9779:d8f1 Dec 20 18:09:13 volumio ntpd[1206]: DNS: Pool taking: 2401:c080:1c00:24a1:5400:5ff:fe04:720 Dec 20 18:09:13 volumio ntpd[1206]: DNS: Pool taking: 2001:678:8::123 Dec 20 18:09:13 volumio ntpd[1206]: DNS: Pool taking: 240b:400d:3:3300:aeda:71da:9779:d4f1 Dec 20 18:09:13 volumio ntpd[1206]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 20 18:09:13 volumio kernel: usb usb4-port1: over-current change #45 Dec 20 18:09:13 volumio kernel: usb usb2-port1: over-current change #45 Dec 20 18:09:13 volumio kernel: usb usb1-port2: over-current change #35 Dec 20 18:09:13 volumio kernel: usb usb3-port1: over-current change #16 Dec 20 18:09:13 volumio kernel: usb usb1-port1: over-current change #41 Dec 20 18:09:14 volumio kernel: usb usb4-port1: over-current change #46 Dec 20 18:09:14 volumio kernel: usb usb2-port1: over-current change #46 Dec 20 18:09:14 volumio kernel: usb usb1-port2: over-current change #36 Dec 20 18:09:14 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 14 Dec 20 18:09:14 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:14 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:09:14 volumio volumio[1387]: info: Listing playlists Dec 20 18:09:14 volumio volumio[1387]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:09:14 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:14 volumio volumio[1387]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:09:14 volumio volumio[1387]: info: CoreStateMachine::getQueue Dec 20 18:09:14 volumio volumio[1387]: info: CorePlayQueue::getQueue Dec 20 18:09:14 volumio ntpd[1206]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 18:09:14 volumio ntpd[1206]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 20 18:09:14 volumio ntpd[1206]: DNS: Pool taking: 203.32.26.46 Dec 20 18:09:14 volumio ntpd[1206]: DNS: Pool taking: 27.102.137.217 Dec 20 18:09:14 volumio ntpd[1206]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 20 18:09:15 volumio volumio[1387]: info: [now-playing] OpenWeatherMap API key obtained. Dec 20 18:09:15 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 18:09:15 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:15 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:15 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:15 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:15 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:15 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:15 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:15 volumio kernel: usb usb1-port1: over-current change #42 Dec 20 18:09:15 volumio kernel: usb usb4-port1: over-current change #47 Dec 20 18:09:15 volumio kernel: usb usb2-port1: over-current change #47 Dec 20 18:09:15 volumio kernel: usb usb1-port2: over-current change #37 Dec 20 18:09:15 volumio kernel: usb usb1-port1: over-current change #43 Dec 20 18:09:15 volumio ntpd[1206]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 20 18:09:15 volumio ntpd[1206]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 20 18:09:15 volumio ntpd[1206]: DNS: Pool taking: 121.174.142.82 Dec 20 18:09:15 volumio ntpd[1206]: DNS: Pool taking: 158.247.202.103 Dec 20 18:09:15 volumio ntpd[1206]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 20 18:09:15 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 20 18:09:16 volumio kernel: usb usb3-port2: over-current change #16 Dec 20 18:09:16 volumio volumio[1387]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=078666b915fb561fe7c761a01d2a8e2d" http://updates.volumio.org/downloader-v1/track-device Dec 20 18:09:16 volumio volumio[1387]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 20 18:09:16 volumio volumio[1387]: Dload Upload Total Spent Left Speed Dec 20 18:09:16 volumio volumio[1387]: [132B blob data] Dec 20 18:09:16 volumio volumio[1387]: retrying in 5 seconds, trial 0 Dec 20 18:09:16 volumio volumio[1387]: info: Volumio Calling Home Dec 20 18:09:16 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 18:09:16 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:16 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:16 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:16 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:16 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:16 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:16 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:16 volumio kernel: usb 3-2: new full-speed USB device number 21 using xhci-hcd Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 20 18:09:16 volumio volumio[1387]: info: Adding plugin bluetooth to MyMusic Plugins Dec 20 18:09:16 volumio volumio[1387]: info: Adding plugin multiroom to MyMusic Plugins Dec 20 18:09:16 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:16 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:16 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:16 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:16 volumio volumio[1387]: info: Adding plugin metavolumio to MyMusic Plugins Dec 20 18:09:16 volumio volumio[1387]: info: Adding plugin cd_controller to MyMusic Plugins Dec 20 18:09:16 volumio volumio[1387]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 20 18:09:16 volumio volumio[1387]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 20 18:09:16 volumio volumio[1387]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 20 18:09:16 volumio volumio[1387]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 20 18:09:16 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 18:09:16 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 18:09:16 volumio kernel: usb usb3-port1: over-current change #17 Dec 20 18:09:17 volumio volumio[1387]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 20 18:09:17 volumio volumio[1387]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 20 18:09:17 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:17 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:17 volumio volumio[1387]: info: Starting MyVolumio Remote Streaming Endpoints Dec 20 18:09:17 volumio volumio[1387]: info: MyVolumio login type: Token Dec 20 18:09:17 volumio volumio[1387]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 20 18:09:17 volumio volumio[1387]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 20 18:09:17 volumio volumio[1387]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 20 18:09:17 volumio volumio[1387]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 20 18:09:17 volumio volumio[1387]: info: Streaming services startup Dec 20 18:09:17 volumio volumio[1387]: info: Starting Streaming Daemon Dec 20 18:09:17 volumio sudo[2170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 18:09:17 volumio sudo[2170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:17 volumio volumio[1387]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 20 18:09:17 volumio kernel: usb usb1-port1: over-current change #44 Dec 20 18:09:17 volumio sudo[2170]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:17 volumio volumio[1387]: error: Cannot start Volumio Streaming Daemon Dec 20 18:09:17 volumio volumio[1387]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 18:09:17 volumio volumio[1387]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 18:09:17 volumio volumio[1387]: info: Volumio called home Dec 20 18:09:17 volumio kernel: usb usb2-port1: over-current change #48 Dec 20 18:09:17 volumio kernel: usb usb4-port1: over-current change #48 Dec 20 18:09:17 volumio kernel: usb usb1-port2: over-current change #38 Dec 20 18:09:17 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 20 18:09:17 volumio volumio[1387]: info: MyVolumio login type: Token Dec 20 18:09:18 volumio kernel: usb usb3-port2: over-current change #17 Dec 20 18:09:18 volumio volumio[1387]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 20 18:09:18 volumio kernel: usb 3-2: USB disconnect, device number 21 Dec 20 18:09:18 volumio kernel: usb 3-2: new full-speed USB device number 23 using xhci-hcd Dec 20 18:09:18 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:18 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:18 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:18 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:18 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 18:09:18 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 18:09:18 volumio kernel: usb usb3-port1: over-current change #18 Dec 20 18:09:19 volumio volumio[1387]: info: MyVolumio token set successfully Dec 20 18:09:19 volumio volumio[1387]: info: MYVOLUMIO: Adding device Dec 20 18:09:19 volumio volumio[1387]: info: MYVOLUMIO: Evaluating Server Dec 20 18:09:19 volumio volumio[1387]: info: MyVolumio status changed Dec 20 18:09:19 volumio volumio[1387]: info: Streaming services startup Dec 20 18:09:19 volumio volumio[1387]: info: Starting Streaming Daemon Dec 20 18:09:19 volumio volumio[1387]: info: Removing browser output: myVolumio user plan is not superstar Dec 20 18:09:19 volumio volumio[1387]: info: Removing audio output: Dec 20 18:09:19 volumio volumio[1387]: info: Stoppping Tunnel 1 Dec 20 18:09:19 volumio sudo[2198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 18:09:19 volumio sudo[2198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:19 volumio sudo[2200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 20 18:09:19 volumio sudo[2200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:19 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 18:09:19 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 18:09:19 volumio sudo[2198]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:19 volumio volumio[1387]: error: Cannot start Volumio Streaming Daemon Dec 20 18:09:19 volumio volumio[1387]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 20 18:09:19 volumio volumio[1387]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 20 18:09:19 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 18:09:19 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 18:09:19 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 18:09:19 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 18:09:19 volumio sudo[2200]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:19 volumio volumio[1387]: info: Remote SSH Stopped Dec 20 18:09:20 volumio volumio[1387]: info: Setting Geolocation for MyVolumio to as1 Dec 20 18:09:20 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:20 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:20 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:20 volumio volumio[1387]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 20 18:09:20 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 18:09:20 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:20 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:20 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:20 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:20 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:20 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:20 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:20 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 18:09:21 volumio volumio[1387]: info: Updating MyVolumio device info Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 18:09:21 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:21 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:21 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:21 volumio volumio[1387]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 18:09:21 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:21 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:21 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:21 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:22 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 18:09:23 volumio sshd[2210]: Accepted password for volumio from 192.168.0.33 port 53588 ssh2 Dec 20 18:09:23 volumio sshd[2210]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Dec 20 18:09:23 volumio systemd-logind[992]: New session 1 of user volumio. Dec 20 18:09:23 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Dec 20 18:09:23 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 20 18:09:23 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 20 18:09:23 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Dec 20 18:09:23 volumio (systemd)[2229]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Dec 20 18:09:24 volumio systemd[2229]: Queued start job for default target default.target. Dec 20 18:09:24 volumio systemd[2229]: Created slice app.slice - User Application Slice. Dec 20 18:09:24 volumio systemd[2229]: Reached target paths.target - Paths. Dec 20 18:09:24 volumio systemd[2229]: Reached target timers.target - Timers. Dec 20 18:09:24 volumio systemd[2229]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 20 18:09:24 volumio systemd[2229]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Dec 20 18:09:24 volumio systemd[2229]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 20 18:09:24 volumio systemd[2229]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 20 18:09:24 volumio systemd[2229]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 20 18:09:24 volumio systemd[2229]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 20 18:09:24 volumio systemd[2229]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 20 18:09:24 volumio systemd[2229]: Reached target sockets.target - Sockets. Dec 20 18:09:24 volumio systemd[2229]: Reached target basic.target - Basic System. Dec 20 18:09:24 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Dec 20 18:09:24 volumio systemd[1]: Started session-1.scope - Session 1 of User volumio. Dec 20 18:09:24 volumio systemd[2229]: Started mpris-proxy.service - Bluetooth mpris proxy. Dec 20 18:09:24 volumio systemd[2229]: Reached target default.target - Main User Target. Dec 20 18:09:24 volumio systemd[2229]: Startup finished in 242ms. Dec 20 18:09:24 volumio systemd[2229]: Created slice session.slice - User Core Session Slice. Dec 20 18:09:24 volumio systemd[2229]: Starting dbus.service - D-Bus User Message Bus... Dec 20 18:09:24 volumio systemd[2229]: Started dbus.service - D-Bus User Message Bus. Dec 20 18:09:24 volumio sshd[2210]: pam_env(sshd:session): deprecated reading of user environment enabled Dec 20 18:09:24 volumio volumio[1387]: info: MYVOLUMIO: Adding device Dec 20 18:09:24 volumio volumio[1387]: info: MYVOLUMIO: Evaluating Server Dec 20 18:09:24 volumio volumio[1387]: info: Setting Geolocation for MyVolumio to as1 Dec 20 18:09:24 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:24 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:24 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:25 volumio volumio[1387]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 20 18:09:25 volumio kernel: usb usb3-port1: over-current change #19 Dec 20 18:09:25 volumio kernel: usb usb1-port1: over-current change #45 Dec 20 18:09:25 volumio kernel: usb usb2-port1: over-current change #49 Dec 20 18:09:25 volumio kernel: usb usb4-port1: over-current change #49 Dec 20 18:09:25 volumio kernel: usb usb1-port2: over-current change #39 Dec 20 18:09:25 volumio volumio[1387]: info: Updating MyVolumio device info Dec 20 18:09:25 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:25 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:25 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:26 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 20 18:09:26 volumio volumio[1387]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 20 18:09:26 volumio kernel: usb usb1-port1: over-current change #46 Dec 20 18:09:26 volumio kernel: usb usb4-port1: over-current change #50 Dec 20 18:09:26 volumio kernel: usb usb2-port1: over-current change #50 Dec 20 18:09:26 volumio kernel: usb usb1-port2: over-current change #40 Dec 20 18:09:27 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 20 18:09:27 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 20 18:09:27 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 18:09:27 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 20 18:09:27 volumio sudo[2158]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:27 volumio volumio[1387]: info: Upmpdcli Daemon Started Dec 20 18:09:27 volumio kernel: usb usb3-port2: over-current change #18 Dec 20 18:09:27 volumio kernel: usb 3-2: USB disconnect, device number 23 Dec 20 18:09:28 volumio kernel: usb 3-2: new full-speed USB device number 29 using xhci-hcd Dec 20 18:09:28 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:28 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:28 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:28 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:28 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 18:09:28 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 18:09:28 volumio kernel: usb usb3-port1: over-current change #20 Dec 20 18:09:30 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 18:09:32 volumio volumio[1387]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:32 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:32 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 18:09:32 volumio kernel: usb usb3-port1: over-current change #21 Dec 20 18:09:32 volumio kernel: usb usb1-port1: over-current change #47 Dec 20 18:09:32 volumio kernel: usb usb2-port1: over-current change #51 Dec 20 18:09:32 volumio kernel: usb usb4-port1: over-current change #51 Dec 20 18:09:32 volumio kernel: usb usb1-port2: over-current change #41 Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 18:09:32 volumio volumio[1387]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 18:09:32 volumio volumio[1387]: info: Received Get System Version Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 18:09:32 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:32 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:32 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:32 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:33 volumio upmpdcli[2298]: writing RSA key Dec 20 18:09:34 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 18:09:36 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 18:09:36 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:36 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 18:09:36 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 18:09:36 volumio kernel: usb usb3-port2: over-current change #19 Dec 20 18:09:36 volumio kernel: usb 3-2: USB disconnect, device number 29 Dec 20 18:09:36 volumio volumio-remote-updater[996]: Test mode disabled Dec 20 18:09:36 volumio volumio-remote-updater[996]: Alpha mode disabled Dec 20 18:09:36 volumio volumio-remote-updater[996]: Alpha legacy test mode disabled Dec 20 18:09:36 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 20 18:09:36 volumio kernel: usb 3-2: new full-speed USB device number 38 using xhci-hcd Dec 20 18:09:36 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:36 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:36 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:36 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:36 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 18:09:36 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 18:09:37 volumio volumio[1387]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 18:09:37 volumio volumio[1387]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 20 18:09:37 volumio volumio[1387]: info: Completed starting MyVolumio Plugin Dec 20 18:09:37 volumio volumio[1387]: [Metrics] CommandRouter: 34s 999.00ms Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::volumiosetStartupVolume Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 18:09:37 volumio volumio[1387]: info: VolumeController:: Setting startup Volume 70 Dec 20 18:09:37 volumio volumio[1387]: info: VolumeController::SetAlsaVolume70 Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::Close All Modals sent Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::Close All Modals sent Dec 20 18:09:37 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:37 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:09:37 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:37 volumio kernel: usb usb3-port1: over-current change #22 Dec 20 18:09:37 volumio kernel: usb usb1-port1: over-current change #48 Dec 20 18:09:37 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 20 18:09:37 volumio volumio[1387]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 20 18:09:37 volumio volumio[1387]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for Loopback Dec 20 18:09:37 volumio volumio[1387]: aplay: main:831: audio open error: No such device Dec 20 18:09:37 volumio sudo[2329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 20 18:09:37 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:37 volumio kernel: usb usb4-port1: over-current change #52 Dec 20 18:09:37 volumio kernel: usb usb2-port1: over-current change #52 Dec 20 18:09:37 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:37 volumio volumio[1387]: info: peppymeterbasic Daemon Stop Dec 20 18:09:37 volumio kernel: usb usb1-port2: over-current change #42 Dec 20 18:09:37 volumio kernel: usb usb2-port1: over-current change #53 Dec 20 18:09:37 volumio kernel: usb usb4-port1: over-current change #53 Dec 20 18:09:37 volumio kernel: usb usb1-port1: over-current change #49 Dec 20 18:09:37 volumio kernel: usb usb3-port2: over-current change #20 Dec 20 18:09:37 volumio kernel: usb 3-2: USB disconnect, device number 38 Dec 20 18:09:38 volumio kernel: usb 3-2: new full-speed USB device number 39 using xhci-hcd Dec 20 18:09:38 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:38 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:38 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:38 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:38 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 18:09:38 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 18:09:38 volumio kernel: usb usb3-port1: over-current change #23 Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 20 18:09:38 volumio sudo[2332]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 18:09:38 volumio sudo[2332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:38 volumio sudo[2334]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 18:09:38 volumio sudo[2334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:38 volumio sudo[2332]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:38 volumio sudo[2334]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:38 volumio kernel: usb usb1-port1: over-current change #50 Dec 20 18:09:38 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 15 Dec 20 18:09:38 volumio kernel: usb usb2-port1: over-current change #54 Dec 20 18:09:38 volumio kernel: usb usb4-port1: over-current change #54 Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:38 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 18:09:38 volumio kernel: usb usb3-port2: over-current change #21 Dec 20 18:09:38 volumio kernel: usb usb1-port2: over-current change #43 Dec 20 18:09:38 volumio sudo[2338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 18:09:38 volumio sudo[2338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:38 volumio sudo[2340]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 18:09:38 volumio sudo[2340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:38 volumio sudo[2338]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:38 volumio sudo[2340]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:38 volumio volumio[1387]: verbose: New Socket.io Connection to 192.168.0.38 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: 15 Dec 20 18:09:38 volumio kernel: usb usb2-port1: over-current change #55 Dec 20 18:09:38 volumio kernel: usb usb4-port1: over-current change #55 Dec 20 18:09:38 volumio kernel: usb 3-2: USB disconnect, device number 39 Dec 20 18:09:38 volumio kernel: usb usb1-port1: over-current change #51 Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:38 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:09:38 volumio volumio[1387]: info: Listing playlists Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 18:09:38 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 18:09:39 volumio kernel: usb usb1-port1: over-current change #52 Dec 20 18:09:39 volumio kernel: usb usb2-port1: over-current change #56 Dec 20 18:09:39 volumio kernel: usb usb4-port1: over-current change #56 Dec 20 18:09:39 volumio kernel: usb usb1-port2: over-current change #44 Dec 20 18:09:39 volumio kernel: usb usb3-port1: over-current change #24 Dec 20 18:09:40 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 18:09:40 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:40 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:40 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:40 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:40 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:40 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:40 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:41 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 18:09:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 18:09:41 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:41 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:41 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:41 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:42 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 18:09:42 volumio kernel: usb usb3-port2: over-current change #22 Dec 20 18:09:43 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 20 18:09:43 volumio kernel: usb 3-2: new full-speed USB device number 44 using xhci-hcd Dec 20 18:09:43 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:43 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:43 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:43 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:43 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 18:09:43 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 18:09:44 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 20 18:09:44 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 20 18:09:44 volumio volumio[1387]: info: BOOT COMPLETED Dec 20 18:09:44 volumio volumio[1387]: info: CoreCommandRouter::volumioPlay Dec 20 18:09:44 volumio volumio[1387]: info: CoreStateMachine::play index undefined Dec 20 18:09:44 volumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:09:44 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:44 volumio volumio[1387]: info: CoreStateMachine::startPlaybackTimer Dec 20 18:09:44 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:44 volumio volumio[1387]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%229DzNPOJI3R4%22%2C%22playlistId%22%3A%22RDCLAK5uy_nzPpTsyJWc4ryEf0xRUcByDVVyKpvq7Fw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22beabadoobee%20-%20Winter%20Wonderland%22%2C%22artist%22%3A%22Beabadoobee%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F9DzNPOJI3R4%2Fhqdefault.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGAFwAcABBvABAfgB1AaAAuADigIMCAAQARg7IGUoUTAP%26rs%3DAOn4CLDkbrtA9Z42PFeqIxn5uL1qRJc85Q%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%229DzNPOJI3R4%22%2C%22playlistId%22%3A%22RDCLAK5uy_nzPpTsyJWc4ryEf0xRUcByDVVyKpvq7Fw%22%2C%22params%22%3A%22OAE%253D%22%2C%22index%22%3A0%7D%7D%7D Dec 20 18:09:44 volumio kernel: usb usb1-port1: over-current change #53 Dec 20 18:09:44 volumio kernel: usb usb3-port1: over-current change #25 Dec 20 18:09:44 volumio kernel: usb usb2-port1: over-current change #57 Dec 20 18:09:44 volumio kernel: usb usb4-port1: over-current change #57 Dec 20 18:09:44 volumio kernel: usb usb1-port2: over-current change #45 Dec 20 18:09:44 volumio kernel: usb usb2-port1: over-current change #58 Dec 20 18:09:44 volumio kernel: usb usb4-port1: over-current change #58 Dec 20 18:09:44 volumio kernel: usb usb1-port1: over-current change #54 Dec 20 18:09:44 volumio kernel: usb usb3-port2: over-current change #23 Dec 20 18:09:45 volumio kernel: usb usb1-port2: over-current change #46 Dec 20 18:09:45 volumio kernel: usb 3-2: USB disconnect, device number 44 Dec 20 18:09:45 volumio kernel: usb usb2-port1: over-current change #59 Dec 20 18:09:45 volumio kernel: usb usb4-port1: over-current change #59 Dec 20 18:09:45 volumio kernel: usb usb1-port1: over-current change #55 Dec 20 18:09:45 volumio kernel: usb usb3-port1: over-current change #26 Dec 20 18:09:45 volumio kernel: usb usb4-port1: over-current change #60 Dec 20 18:09:45 volumio kernel: usb usb2-port1: over-current change #60 Dec 20 18:09:45 volumio kernel: usb usb1-port2: over-current change #47 Dec 20 18:09:45 volumio kernel: usb usb1-port1: over-current change #56 Dec 20 18:09:45 volumio kernel: usb usb1-port1: over-current change #57 Dec 20 18:09:45 volumio kernel: usb usb2-port1: over-current change #61 Dec 20 18:09:45 volumio kernel: usb usb4-port1: over-current change #61 Dec 20 18:09:45 volumio kernel: usb usb1-port2: over-current change #48 Dec 20 18:09:45 volumio kernel: usb usb1-port1: over-current change #58 Dec 20 18:09:46 volumio kernel: usb usb1-port1: over-current change #59 Dec 20 18:09:46 volumio kernel: usb usb2-port1: over-current change #62 Dec 20 18:09:46 volumio kernel: usb usb4-port1: over-current change #62 Dec 20 18:09:46 volumio kernel: usb usb1-port2: over-current change #49 Dec 20 18:09:46 volumio kernel: usb usb4-port1: over-current change #63 Dec 20 18:09:46 volumio kernel: usb usb2-port1: over-current change #63 Dec 20 18:09:46 volumio kernel: usb usb1-port1: over-current change #60 Dec 20 18:09:46 volumio kernel: usb usb3-port2: over-current change #24 Dec 20 18:09:46 volumio kernel: usb usb1-port2: over-current change #50 Dec 20 18:09:46 volumio kernel: usb usb2-port1: over-current change #64 Dec 20 18:09:46 volumio kernel: usb usb4-port1: over-current change #64 Dec 20 18:09:46 volumio volumio[1387]: info: [youtube2] Innertube support service: Deno not installed or otherwise failed to start: Command failed: npx --no-install --yes deno --version Dec 20 18:09:46 volumio volumio[1387]: info: [youtube2] Innertube support service: Start service with Node Dec 20 18:09:46 volumio kernel: usb usb1-port1: over-current change #61 Dec 20 18:09:46 volumio kernel: usb 3-2: new full-speed USB device number 46 using xhci-hcd Dec 20 18:09:46 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:46 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:46 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:46 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:46 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 18:09:46 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 18:09:46 volumio kernel: usb usb3-port1: over-current change #27 Dec 20 18:09:47 volumio kernel: usb usb1-port1: over-current change #62 Dec 20 18:09:47 volumio kernel: usb usb2-port1: over-current change #65 Dec 20 18:09:47 volumio kernel: usb usb4-port1: over-current change #65 Dec 20 18:09:47 volumio kernel: usb usb1-port2: over-current change #51 Dec 20 18:09:47 volumio kernel: usb usb2-port1: over-current change #66 Dec 20 18:09:47 volumio kernel: usb usb4-port1: over-current change #66 Dec 20 18:09:47 volumio kernel: usb usb1-port1: over-current change #63 Dec 20 18:09:47 volumio volumio[1387]: info: [youtube2] Innertube support service: result: {"status":"started","server":{"address":"127.0.0.1","port":37133}} Dec 20 18:09:47 volumio volumio[1387]: info: [youtube2] Innertube support service running at http://127.0.0.1:37133 Dec 20 18:09:48 volumio kernel: usb usb1-port1: over-current change #64 Dec 20 18:09:48 volumio kernel: usb usb2-port1: over-current change #67 Dec 20 18:09:48 volumio kernel: usb usb4-port1: over-current change #67 Dec 20 18:09:48 volumio kernel: usb usb3-port2: over-current change #25 Dec 20 18:09:48 volumio kernel: usb usb1-port2: over-current change #52 Dec 20 18:09:48 volumio kernel: usb 3-2: USB disconnect, device number 46 Dec 20 18:09:48 volumio kernel: usb usb1-port1: over-current change #65 Dec 20 18:09:48 volumio kernel: usb usb2-port1: over-current change #68 Dec 20 18:09:48 volumio kernel: usb usb4-port1: over-current change #68 Dec 20 18:09:48 volumio kernel: usb usb1-port2: over-current change #53 Dec 20 18:09:48 volumio kernel: usb usb3-port1: over-current change #28 Dec 20 18:09:48 volumio kernel: usb usb4-port1: over-current change #69 Dec 20 18:09:48 volumio kernel: usb usb2-port1: over-current change #69 Dec 20 18:09:48 volumio kernel: usb usb1-port1: over-current change #66 Dec 20 18:09:48 volumio kernel: usb usb1-port2: over-current change #54 Dec 20 18:09:48 volumio kernel: usb usb3-port2: over-current change #26 Dec 20 18:09:48 volumio kernel: usb usb4-port1: over-current change #70 Dec 20 18:09:48 volumio kernel: usb usb2-port1: over-current change #70 Dec 20 18:09:49 volumio kernel: usb usb1-port1: over-current change #67 Dec 20 18:09:49 volumio kernel: usb usb1-port2: over-current change #55 Dec 20 18:09:49 volumio kernel: usb usb4-port1: over-current change #71 Dec 20 18:09:49 volumio kernel: usb usb2-port1: over-current change #71 Dec 20 18:09:49 volumio kernel: usb usb3-port1: over-current change #29 Dec 20 18:09:49 volumio kernel: usb usb1-port1: over-current change #68 Dec 20 18:09:49 volumio kernel: usb usb4-port1: over-current change #72 Dec 20 18:09:49 volumio kernel: usb usb2-port1: over-current change #72 Dec 20 18:09:49 volumio kernel: usb usb1-port2: over-current change #56 Dec 20 18:09:49 volumio kernel: usb usb3-port2: over-current change #27 Dec 20 18:09:49 volumio kernel: usb usb1-port1: over-current change #69 Dec 20 18:09:49 volumio kernel: usb usb4-port1: over-current change #73 Dec 20 18:09:49 volumio kernel: usb usb2-port1: over-current change #73 Dec 20 18:09:49 volumio kernel: usb usb1-port2: over-current change #57 Dec 20 18:09:49 volumio kernel: usb usb3-port1: over-current change #30 Dec 20 18:09:49 volumio kernel: usb usb4-port1: over-current change #74 Dec 20 18:09:49 volumio kernel: usb usb2-port1: over-current change #74 Dec 20 18:09:49 volumio kernel: usb usb1-port1: over-current change #70 Dec 20 18:09:49 volumio kernel: usb usb1-port2: over-current change #58 Dec 20 18:09:50 volumio kernel: usb usb4-port1: over-current change #75 Dec 20 18:09:50 volumio kernel: usb usb2-port1: over-current change #75 Dec 20 18:09:50 volumio kernel: usb usb1-port1: over-current change #71 Dec 20 18:09:50 volumio kernel: usb usb3-port2: over-current change #28 Dec 20 18:09:50 volumio kernel: usb usb4-port1: over-current change #76 Dec 20 18:09:50 volumio kernel: usb usb2-port1: over-current change #76 Dec 20 18:09:50 volumio kernel: usb usb1-port2: over-current change #59 Dec 20 18:09:50 volumio kernel: usb usb1-port1: over-current change #72 Dec 20 18:09:50 volumio kernel: usb 3-2: new full-speed USB device number 48 using xhci-hcd Dec 20 18:09:50 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:50 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:50 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:50 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:50 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 18:09:50 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 18:09:50 volumio kernel: usb usb3-port1: over-current change #31 Dec 20 18:09:50 volumio kernel: usb usb1-port1: over-current change #73 Dec 20 18:09:50 volumio kernel: usb usb2-port1: over-current change #77 Dec 20 18:09:50 volumio kernel: usb usb4-port1: over-current change #77 Dec 20 18:09:50 volumio kernel: usb usb1-port2: over-current change #60 Dec 20 18:09:51 volumio kernel: usb usb3-port2: over-current change #29 Dec 20 18:09:51 volumio volumio[1387]: info: [youtube2] Obtained session PO token using visitorData (expires in 43199 seconds) Dec 20 18:09:51 volumio kernel: usb usb2-port1: over-current change #78 Dec 20 18:09:51 volumio kernel: usb usb4-port1: over-current change #78 Dec 20 18:09:51 volumio kernel: usb usb1-port1: over-current change #74 Dec 20 18:09:51 volumio kernel: usb 3-2: USB disconnect, device number 48 Dec 20 18:09:51 volumio kernel: usb 3-2: new full-speed USB device number 49 using xhci-hcd Dec 20 18:09:51 volumio kernel: usb usb1-port1: over-current change #75 Dec 20 18:09:51 volumio kernel: usb usb2-port1: over-current change #79 Dec 20 18:09:51 volumio kernel: usb usb4-port1: over-current change #79 Dec 20 18:09:51 volumio kernel: usb usb1-port2: over-current change #61 Dec 20 18:09:51 volumio kernel: usb usb2-port1: over-current change #80 Dec 20 18:09:51 volumio kernel: usb usb4-port1: over-current change #80 Dec 20 18:09:51 volumio kernel: usb usb1-port1: over-current change #76 Dec 20 18:09:51 volumio kernel: usb usb1-port2: over-current change #62 Dec 20 18:09:51 volumio kernel: usb usb4-port1: over-current change #81 Dec 20 18:09:51 volumio kernel: usb usb2-port1: over-current change #81 Dec 20 18:09:51 volumio kernel: usb usb1-port1: over-current change #77 Dec 20 18:09:52 volumio kernel: usb usb1-port2: over-current change #63 Dec 20 18:09:52 volumio kernel: usb usb2-port1: over-current change #82 Dec 20 18:09:52 volumio kernel: usb usb4-port1: over-current change #82 Dec 20 18:09:52 volumio kernel: usb usb3-port1: over-current change #32 Dec 20 18:09:52 volumio kernel: usb usb1-port1: over-current change #78 Dec 20 18:09:52 volumio volumio[1387]: info: [youtube2] Going to refresh session PO token in 43099 seconds Dec 20 18:09:52 volumio volumio[1387]: info: [ytmusic] Obtained PO token for video #9DzNPOJI3R4: MlVe7Zk8VOk_gl7TxrIwBAXL_1w6i56X7UbAzXZqhwYF2lvsQRoVIaaSaAOYLnxq2ImjnXsM39TD42B_vDX8-7bH6FNjmwHoZe4FeTfITx7FD-q2r0a5 Dec 20 18:09:52 volumio volumio[1387]: info: [ytmusic] Obtained PO token for video #9DzNPOJI3R4: MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63 Dec 20 18:09:53 volumio volumio[1387]: info: [youtube2] VideoModel.getInfo(9DzNPOJI3R4): validating stream URL "https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00"... Dec 20 18:09:53 volumio volumio[1387]: info: [youtube2] VideoModel.getInfo(9DzNPOJI3R4): stream validated in 0.051s. Dec 20 18:09:53 volumio volumio[1387]: info: [youtube2] (AutoplayManager) Added volumioStateListener Dec 20 18:09:53 volumio volumio[1387]: info: [youtube2] (AutoplayManager) Enabled Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand stop took 1 milliseconds Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand clear Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand clear took 1 milliseconds Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand addid "https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube" Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand addid "https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube" took 2 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 2ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 1ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 2ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 2ms Dec 20 18:09:53 volumio volumio[1387]: verbose: MPD COMMAND [object Object] Dec 20 18:09:53 volumio volumio[1387]: verbose: MPD COMMAND [object Object] Dec 20 18:09:53 volumio volumio[1387]: verbose: MPD COMMAND [object Object] Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 4ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 4ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 3ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 2ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 2ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 2ms Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand play Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:09:53 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 3ms Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand play took 2 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 2ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 2ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 1ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 1ms Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 18 milliseconds Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 20 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 19 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 19 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 19 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus stop Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 23ms Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: Dec 20 18:09:53 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 4 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 2 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 2 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 1 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand status took 1 milliseconds Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: [youtube2] (AutoplayManager) Added mpdStateListener Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio kernel: usb usb1-port1: over-current change #79 Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 58ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 58ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 58ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 58ms Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 20 18:09:53 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:09:53 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:09:53 volumio volumio[1387]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"beabadoobee - Winter Wonderland","artist":"beabadoobeeVEVO","album":"YouTube","uri":"https://rr7---sn-3u-bh2sl.googlevideo.com/videoplayback?expire=1766243392&ei=4GdGaZbcN5mDvcAPtYOMaQ&ip=175.196.255.174&id=o-ADKkTHn7gIZNs7iDDadMaoPuUOfJTE228LTC9nuPnQPk&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=100&met=1766221792%2C&mh=YZ&mm=31%2C26&mn=sn-3u-bh2sl%2Csn-npoeenl7&ms=au%2Conr&mv=m&mvi=7&pl=17&rms=au%2Cau&initcwndbps=4210000&bui=AYUSA3BOIP_t4AeLzDvpi3h-9SRh6CiE3D_mkCnsLOYGGP9DFhMwMON-eg4GuuxHDA19muVKUE-M0fge&spc=wH4Qq1IIaEX2WHb5f1kFNArpPBGCtmNpah40dQz4YgNqtkLyuSRqgA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=k_LZ6rIUXvwcri01B5aQX-8R&rqh=1&gir=yes&clen=2999138&dur=183.261&lmt=1714522454549695&mt=1766221257&fvip=3&keepalive=yes&fexp=51552689%2C51565116%2C51565682%2C51580968&c=WEB_EMBEDDED_PLAYER&sefc=1&txp=5308224&n=gEekrgqnUTAskw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgdrmG32Gu1hkfEI-KS-GeSVjWctoda-3xs_R-7CuAKyMCIQCO6oS13QINh8dHyxKfZ6i4eZlZtQnl6_ppbaEKUctDRQ%3D%3D&sig=AJfQdSswRQIgR_E9kp6hiPsUroVqmADF0gomBQqlQaXDc0GyqDcPa5gCIQDO9sxnVhqD_HrDp3NX0HJGUCKCfIYwAhNQ5zhRODNDbw%3D%3D&pot=MlXeG1jekXnFTd4lB1D1lP8Ef6r7aVsHF4lAO7eIQpb_FdsagPjQsVxd6PVZzLn6IkYja7ruGkQ5LOCJfdc5a0wIaKWiecR4nyF-jqg5SsyHqL5OCx63&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Dec 20 18:09:53 volumio volumio[1387]: verbose: CURRENT POSITION 0 Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState stateService play Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:09:53 volumio volumio[1387]: info: Received an update from plugin. extracting info from payload Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:09:53 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:09:53 volumio kernel: usb usb4-port1: over-current change #83 Dec 20 18:09:53 volumio kernel: usb usb2-port1: over-current change #83 Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 91ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 87ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 87ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 88ms Dec 20 18:09:53 volumio volumio[1387]: info: ------------------------------ 87ms Dec 20 18:09:53 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:53 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio kernel: usb usb1-port2: over-current change #64 Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 20 18:09:54 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio sudo[2516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 20 18:09:54 volumio kernel: usb usb2-port1: over-current change #84 Dec 20 18:09:54 volumio kernel: usb usb4-port1: over-current change #84 Dec 20 18:09:54 volumio sudo[2521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio sudo[2485]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2496]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2490]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2499]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2493]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2506]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio kernel: usb usb1-port1: over-current change #80 Dec 20 18:09:54 volumio sudo[2532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 20 18:09:54 volumio sudo[2532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio sudo[2503]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2508]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio sudo[2524]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2526]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2532]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio sudo[2521]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2516]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio volumio[1387]: info: peppymeterbasic Daemon Started Dec 20 18:09:54 volumio kernel: usb usb1-port2: over-current change #65 Dec 20 18:09:54 volumio kernel: usb usb4-port1: over-current change #85 Dec 20 18:09:54 volumio kernel: usb usb2-port1: over-current change #85 Dec 20 18:09:54 volumio kernel: usb usb1-port1: over-current change #81 Dec 20 18:09:54 volumio kernel: usb usb3-port2: over-current change #30 Dec 20 18:09:55 volumio kernel: usb 3-2: new full-speed USB device number 52 using xhci-hcd Dec 20 18:09:55 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:55 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:55 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:55 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:55 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.000D/input/input18 Dec 20 18:09:55 volumio kernel: hid-multitouch 0003:1A86:E5E3.000D: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 18:09:55 volumio kernel: usb usb3-port1: over-current change #33 Dec 20 18:09:56 volumio volumio[2542]: libpng warning: iCCP: known incorrect sRGB profile Dec 20 18:09:56 volumio volumio[2542]: libpng warning: iCCP: known incorrect sRGB profile Dec 20 18:09:57 volumio kernel: usb usb3-port1: attempt power cycle Dec 20 18:09:57 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 18:09:57 volumio volumio[1387]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 18:09:57 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 18:09:57 volumio volumio[1387]: info: Received Get System Version Dec 20 18:09:57 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 18:09:57 volumio volumio[1387]: info: Received Get System Info Dec 20 18:09:57 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:09:57 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:09:57 volumio volumio[1387]: info: Discovery: Getting this device information Dec 20 18:09:57 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Dec 20 18:09:57 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:09:59 volumio kernel: usb usb3-port1: Cannot enable. Maybe the USB cable is bad? Dec 20 18:09:59 volumio kernel: usb usb3-port1: unable to enumerate USB device Dec 20 18:09:59 volumio kernel: usb 3-2: USB disconnect, device number 52 Dec 20 18:09:59 volumio kernel: usb 3-2: new full-speed USB device number 57 using xhci-hcd Dec 20 18:09:59 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Dec 20 18:09:59 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Dec 20 18:09:59 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Dec 20 18:09:59 volumio kernel: usb 3-2: Manufacturer: wch.cn Dec 20 18:09:59 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.000E/input/input19 Dec 20 18:09:59 volumio kernel: hid-multitouch 0003:1A86:E5E3.000E: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Dec 20 18:10:02 volumio volumio[1387]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:10:02 volumio volumio[1387]: info: CoreStateMachine::getQueue Dec 20 18:10:02 volumio volumio[1387]: info: CorePlayQueue::getQueue Dec 20 18:10:02 volumio volumio[1387]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:10:02 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:10:04 volumio volumio[1387]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Dec 20 18:10:04 volumio volumio[1387]: Traceback (most recent call last): Dec 20 18:10:04 volumio volumio[1387]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Dec 20 18:10:04 volumio volumio[1387]: from volumio_albumart import AlbumartAnimator Dec 20 18:10:04 volumio volumio[1387]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Dec 20 18:10:04 volumio volumio[1387]: import socketio Dec 20 18:10:04 volumio volumio[1387]: ModuleNotFoundError: No module named 'socketio' Dec 20 18:10:12 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Dec 20 18:10:12 volumio volumio[1387]: info: Preload queue cleared Dec 20 18:10:14 volumio volumio[1387]: info: Preload queue cleared Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::ClearQueue Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::stop Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::stPlaybackTimer Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::updateTrackBlock Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrackBlock Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::serviceStop Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::serviceStop Dec 20 18:10:14 volumio volumio[1387]: info: [youtube2] (AutoplayManager) Removed mpdStateListener Dec 20 18:10:14 volumio volumio[1387]: info: [youtube2] (AutoplayManager) Removed volumioStateListener Dec 20 18:10:14 volumio volumio[1387]: info: [youtube2] (AutoplayManager) Disabled Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::stop Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::clearPlayQueue Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::saveQueue Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioPushQueue Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::addQueueItems Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::addQueueItems Dec 20 18:10:14 volumio volumio[1387]: info: Preload queue cleared Dec 20 18:10:14 volumio volumio[1387]: info: Adding Item to queue: kradio/mbc Dec 20 18:10:14 volumio volumio[1387]: info: Exploding uri kradio/mbc in service personal_radio Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioPushQueue Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::saveQueue Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::updateTrackBlock Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrackBlock Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioPlay Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::play index 0 Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::stop Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::play index undefined Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::startPlaybackTimer Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 18:10:14 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand stop took 51 milliseconds Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand stop took 17 milliseconds Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand clear Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces state update: player Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::getState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:10:14 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand status took 3 milliseconds Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand clear took 3 milliseconds Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand status took 2 milliseconds Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand status took 2 milliseconds Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand status took 1 milliseconds Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand status took 1 milliseconds Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::sendMpdCommand add "undefined" Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::parseState Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:10:14 volumio sudo[2652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 20 18:10:14 volumio sudo[2652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Dec 20 18:10:14 volumio volumio[1387]: info: ControllerMpd::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::servicePushState Dec 20 18:10:14 volumio volumio[1387]: info: CoreStateMachine::pushState Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: CoreCommandRouter::volumioPushState Dec 20 18:10:14 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Dec 20 18:10:14 volumio volumio[1387]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Dec 20 18:10:14 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 37ms Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 36ms Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 36ms Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 35ms Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:10:14 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:10:14 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:10:14 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:10:14 volumio volumio[1387]: info: Dec 20 18:10:14 volumio volumio[1387]: ---------------------------- MPD announces system playlist update Dec 20 18:10:14 volumio volumio[1387]: info: Ignoring MPD Status Update Dec 20 18:10:14 volumio volumio[1387]: error: updateQueue error: null Dec 20 18:10:14 volumio volumio[1387]: error: updateQueue error: null Dec 20 18:10:14 volumio volumio[1387]: error: updateQueue error: null Dec 20 18:10:14 volumio volumio[1387]: error: updateQueue error: null Dec 20 18:10:14 volumio volumio[1387]: error: updateQueue error: null Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 47ms Dec 20 18:10:14 volumio volumio[1387]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 20 18:10:14 volumio volumio[1387]: error: [ControllerPersonalRadio::clearAddPlayTrack] Error= [50@0] {add} No such directory {"stack":"Error: [50@0] {add} No such directory\n at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17)\n at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)\n at Socket.emit (node:events:514:28)\n at addChunk (node:internal/streams/readable:343:12)\n at readableAddChunk (node:internal/streams/readable:312:11)\n at Readable.push (node:internal/streams/readable:253:10)\n at Pipe.onStreamRead (node:internal/stream_base_commons:190:23)"} Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 15ms Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 14ms Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 14ms Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 14ms Dec 20 18:10:14 volumio volumio[1387]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:10:14 volumio volumio[1387]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 20 18:10:14 volumio volumio[1387]: info: ------------------------------ 55ms Dec 20 18:10:14 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 20 18:10:14 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 20 18:10:14 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 20 18:10:14 volumio sudo[2657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 20 18:10:14 volumio sudo[2657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:10:14 volumio volumio[1387]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 20 18:10:14 volumio sudo[2659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 20 18:10:14 volumio sudo[2659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:10:14 volumio sudo[2662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 20 18:10:14 volumio sudo[2662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:10:14 volumio volumio[1387]: info: touch_display: Setting screensaver timeout to 20 seconds. Dec 20 18:10:14 volumio sudo[2665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 20 18:10:14 volumio sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 18:10:14 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 20 18:10:14 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 20 18:10:14 volumio systemd[1]: peppymeterbasic.service: Consumed 1.336s CPU time. Dec 20 18:10:14 volumio sudo[2659]: pam_unix(sudo:session): session closed for user root Dec 20 18:10:14 volumio sudo[2652]: pam_unix(sudo:session): session closed for user root Dec 20 18:10:14 volumio volumio[1387]: info: peppymeterbasic Daemon Stop Dec 20 18:10:14 volumio volumio[1387]: info: peppymeterbasic Daemon Stop Dec 20 18:10:14 volumio sudo[2665]: pam_unix(sudo:session): session closed for user root Dec 20 18:10:14 volumio sudo[2662]: pam_unix(sudo:session): session closed for user root Dec 20 18:10:14 volumio sudo[2657]: pam_unix(sudo:session): session closed for user root Dec 20 18:10:14 volumio volumio[1387]: info: peppymeterbasic Daemon Stop Dec 20 18:10:14 volumio volumio[1387]: info: peppymeterbasic Daemon Stop Dec 20 18:10:14 volumio volumio[1387]: info: peppymeterbasic Daemon Stop Dec 20 18:10:14 volumio volumio[1387]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Dec 20 18:10:14 volumio volumio[1387]: Traceback (most recent call last): Dec 20 18:10:14 volumio volumio[1387]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Dec 20 18:10:14 volumio volumio[1387]: from volumio_albumart import AlbumartAnimator Dec 20 18:10:14 volumio volumio[1387]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Dec 20 18:10:14 volumio volumio[1387]: import socketio Dec 20 18:10:14 volumio volumio[1387]: ModuleNotFoundError: No module named 'socketio' Dec 20 18:10:17 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Dec 20 18:10:17 volumio volumio[1387]: info: Preload queue cleared Dec 20 18:10:17 volumio volumio[1387]: info: Preloading song: webmbc/0 Dec 20 18:10:17 volumio volumio[1387]: info: Preloading song: webmbc/1 Dec 20 18:10:17 volumio volumio[1387]: info: Preloading song: webmbc/2 Dec 20 18:10:17 volumio volumio[1387]: info: Exploding uri webmbc/0 in service personal_radio Dec 20 18:10:17 volumio volumio[1387]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 18:10:17 volumio volumio[1387]: TypeError: Cannot read properties of null (reading 'startsWith') Dec 20 18:10:17 volumio volumio[1387]: at /data/plugins/music_service/personal_radio/index.js:675:41 Dec 20 18:10:17 volumio volumio[1387]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 20 18:10:17 volumio volumio[1387]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 18:10:17 volumio sudo[2690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 18:09' Dec 20 18:10:17 volumio sudo[2690]: 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"