-- Logs begin at Thu 2025-05-15 13:39:42 MST, end at Thu 2025-05-15 13:51:26 MST. -- May 15 13:50:01 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri May 15 13:50:01 volumio volumio[1100]: info: [bandcamp-browse] browseUri: bandcamp/discover@startIndex=0@category=0@sortBy=top@location=0@time=-1@size=60@genre=electronic@select=category May 15 13:50:01 volumio volumio[1100]: info: Preload queue cleared May 15 13:50:05 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri May 15 13:50:05 volumio volumio[1100]: info: [bandcamp-browse] browseUri: bandcamp/discover@startIndex=0@category=1@sortBy=top@location=0@time=-1@size=60@genre=electronic May 15 13:50:06 volumio volumio[1100]: info: Preload queue cleared May 15 13:50:06 volumio volumio[1100]: info: Preload queue cleared May 15 13:50:09 volumio kernel: usb usb1-port1: over-current change #101 May 15 13:50:09 volumio kernel: usb usb3-port1: over-current change #140 May 15 13:50:09 volumio kernel: usb usb2-port1: over-current change #181 May 15 13:50:09 volumio kernel: usb usb4-port1: over-current change #181 May 15 13:50:09 volumio kernel: usb 1-1: USB disconnect, device number 104 May 15 13:50:09 volumio kernel: usb usb3-port2: over-current change #115 May 15 13:50:09 volumio kernel: usb usb2-port1: over-current change #182 May 15 13:50:09 volumio kernel: usb usb4-port1: over-current change #182 May 15 13:50:10 volumio kernel: usb usb3-port1: over-current change #141 May 15 13:50:10 volumio kernel: usb usb1-port2: over-current change #84 May 15 13:50:10 volumio kernel: usb 1-2: USB disconnect, device number 105 May 15 13:50:10 volumio kernel: usb 1-2: new full-speed USB device number 106 using xhci-hcd May 15 13:50:10 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:50:11 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:50:11 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:50:11 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:50:11 volumio kernel: logitech-djreceiver 0003:046D:C52B.0155: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:50:11 volumio kernel: usb usb1-port1: over-current change #102 May 15 13:50:11 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.0155/0003:046D:404D.0156/input/input165 May 15 13:50:11 volumio kernel: logitech-hidpp-device 0003:046D:404D.0156: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:50:11 volumio kernel: usb 1-1: new full-speed USB device number 107 using xhci-hcd May 15 13:50:11 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:50:11 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:50:11 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:50:11 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:50:11 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:50:11 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.0157/input/input166 May 15 13:50:11 volumio kernel: hid-multitouch 0003:27C0:0859.0157: input,hiddev97,hidraw2: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:50:11 volumio kernel: hid-generic 0003:27C0:0859.0158: hiddev98,hidraw3: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:50:11 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.0159/input/input167 May 15 13:50:11 volumio kernel: hid-generic 0003:27C0:0859.0159: input,hidraw4: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:50:11 volumio systemd-udevd[4660]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:11 volumio systemd-udevd[4652]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:11 volumio systemd-udevd[4651]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:11 volumio systemd-udevd[4661]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:11 volumio systemd-logind[866]: Watching system buttons on /dev/input/event0 (Logitech K400 Plus) May 15 13:50:12 volumio systemd-udevd[4653]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:12 volumio systemd-udevd[4647]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:23 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri May 15 13:50:23 volumio volumio[1100]: info: [jellyfin-browse] browseUri: jellyfin May 15 13:50:23 volumio volumio[1100]: info: Preload queue cleared May 15 13:50:36 volumio kernel: usb usb1-port1: over-current change #103 May 15 13:50:36 volumio kernel: usb usb3-port1: over-current change #142 May 15 13:50:36 volumio kernel: usb usb2-port1: over-current change #183 May 15 13:50:36 volumio kernel: usb usb4-port1: over-current change #183 May 15 13:50:36 volumio kernel: usb 1-1: USB disconnect, device number 107 May 15 13:50:36 volumio kernel: usb usb3-port2: over-current change #116 May 15 13:50:36 volumio kernel: usb 1-1: new full-speed USB device number 108 using xhci-hcd May 15 13:50:36 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:50:36 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:50:36 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:50:36 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:50:36 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:50:36 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.015A/input/input168 May 15 13:50:36 volumio kernel: hid-multitouch 0003:27C0:0859.015A: input,hiddev97,hidraw2: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:50:36 volumio kernel: hid-generic 0003:27C0:0859.015B: hiddev98,hidraw3: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:50:36 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.015C/input/input169 May 15 13:50:36 volumio kernel: hid-generic 0003:27C0:0859.015C: input,hidraw4: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:50:36 volumio kernel: usb usb1-port2: over-current change #85 May 15 13:50:37 volumio kernel: usb 1-2: USB disconnect, device number 106 May 15 13:50:37 volumio kernel: usb 1-2: new full-speed USB device number 109 using xhci-hcd May 15 13:50:37 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:50:37 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:50:37 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:50:37 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:50:37 volumio kernel: logitech-djreceiver 0003:046D:C52B.015F: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:50:37 volumio kernel: usb usb1-port1: over-current change #104 May 15 13:50:37 volumio kernel: usb usb3-port1: over-current change #143 May 15 13:50:37 volumio kernel: usb usb2-port1: over-current change #184 May 15 13:50:37 volumio kernel: usb usb4-port1: over-current change #184 May 15 13:50:38 volumio systemd-udevd[4718]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:38 volumio systemd-udevd[4722]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:38 volumio kernel: usb 1-1: USB disconnect, device number 108 May 15 13:50:38 volumio kernel: usb usb3-port2: over-current change #117 May 15 13:50:38 volumio startx[1406]: (EE) client bug: Invalid path /dev/input/event2 May 15 13:50:38 volumio systemd-udevd[4725]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:38 volumio kernel: usb 1-1: new full-speed USB device number 110 using xhci-hcd May 15 13:50:38 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri May 15 13:50:38 volumio volumio[1100]: info: [soundcloud] browseUri: soundcloud May 15 13:50:38 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:50:38 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:50:38 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:50:38 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:50:38 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:50:38 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.0161/input/input170 May 15 13:50:38 volumio kernel: hid-multitouch 0003:27C0:0859.0161: input,hiddev97,hidraw1: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:50:38 volumio kernel: hid-generic 0003:27C0:0859.0162: hiddev98,hidraw2: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:50:38 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.0163/input/input171 May 15 13:50:38 volumio kernel: hid-generic 0003:27C0:0859.0163: input,hidraw3: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:50:38 volumio kernel: usb usb1-port2: over-current change #86 May 15 13:50:38 volumio kernel: usb 1-2: USB disconnect, device number 109 May 15 13:50:43 volumio kernel: usb usb3-port1: over-current change #144 May 15 13:50:43 volumio kernel: usb usb2-port1: over-current change #185 May 15 13:50:43 volumio kernel: usb usb4-port1: over-current change #185 May 15 13:50:43 volumio kernel: usb usb3-port2: over-current change #118 May 15 13:50:43 volumio kernel: usb usb2-port1: over-current change #186 May 15 13:50:43 volumio kernel: usb usb4-port1: over-current change #186 May 15 13:50:43 volumio kernel: usb usb3-port1: over-current change #145 May 15 13:50:43 volumio kernel: usb usb3-port2: over-current change #119 May 15 13:50:43 volumio kernel: usb usb2-port1: over-current change #187 May 15 13:50:43 volumio kernel: usb usb4-port1: over-current change #187 May 15 13:50:43 volumio kernel: usb usb3-port1: over-current change #146 May 15 13:50:43 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.015F/0003:046D:404D.0160/input/input172 May 15 13:50:43 volumio kernel: logitech-hidpp-device 0003:046D:404D.0160: input,hidraw4: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:50:43 volumio volumio[1100]: error: [soundcloud] Failed to get top featured tracks in root view: 404 - Not Found FetchError: 404 - Not Found May 15 13:50:43 volumio volumio[1100]: at SoundCloud._SoundCloud_validateFetchResponse (/data/plugins/music_service/soundcloud/node_modules/soundcloud-fetch/dist/cjs/lib/SoundCloud.js:402:11) May 15 13:50:43 volumio volumio[1100]: at SoundCloud._SoundCloud_fetchEndpoint (/data/plugins/music_service/soundcloud/node_modules/soundcloud-fetch/dist/cjs/lib/SoundCloud.js:379:97) May 15 13:50:43 volumio volumio[1100]: at runMicrotasks () May 15 13:50:43 volumio volumio[1100]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 15 13:50:43 volumio volumio[1100]: at async SoundCloud._SoundCloud_fetchCollection (/data/plugins/music_service/soundcloud/node_modules/soundcloud-fetch/dist/cjs/lib/SoundCloud.js:367:18) May 15 13:50:43 volumio volumio[1100]: at async Cache.getOrSet (/data/plugins/music_service/soundcloud/dist/lib/util/Cache.js:77:23) May 15 13:50:43 volumio volumio[1100]: at async TrackModel._BaseModel_doLoopFetch (/data/plugins/music_service/soundcloud/dist/lib/model/BaseModel.js:112:25) May 15 13:50:43 volumio volumio[1100]: at async TrackViewHandler.browse (/data/plugins/music_service/soundcloud/dist/lib/controller/browse/view-handlers/TrackViewHandler.js:48:22) May 15 13:50:43 volumio volumio[1100]: at async RootViewHandler._RootViewHandler_getTopFeaturedTracks (/data/plugins/music_service/soundcloud/dist/lib/controller/browse/view-handlers/RootViewHandler.js:145:22) May 15 13:50:43 volumio volumio[1100]: at async Promise.all (index 1) May 15 13:50:43 volumio volumio[1100]: at async RootViewHandler.browse (/data/plugins/music_service/soundcloud/dist/lib/controller/browse/view-handlers/RootViewHandler.js:29:30) May 15 13:50:43 volumio volumio[1100]: at async BrowseController.browseUri (/data/plugins/music_service/soundcloud/dist/lib/controller/browse/BrowseController.js:33:20) May 15 13:50:43 volumio kernel: usb usb1-port1: over-current change #105 May 15 13:50:43 volumio kernel: usb 1-1: USB disconnect, device number 110 May 15 13:50:43 volumio kernel: usb 1-1: new full-speed USB device number 111 using xhci-hcd May 15 13:50:44 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:50:44 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:50:44 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:50:44 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:50:44 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:50:44 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.0164/input/input173 May 15 13:50:44 volumio kernel: hid-multitouch 0003:27C0:0859.0164: input,hiddev96,hidraw0: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:50:44 volumio kernel: hid-generic 0003:27C0:0859.0165: hiddev97,hidraw1: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:50:44 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.0166/input/input174 May 15 13:50:44 volumio kernel: hid-generic 0003:27C0:0859.0166: input,hidraw2: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:50:44 volumio kernel: usb usb1-port2: over-current change #87 May 15 13:50:44 volumio kernel: usb 1-2: new full-speed USB device number 112 using xhci-hcd May 15 13:50:44 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:50:44 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:50:44 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:50:44 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:50:44 volumio kernel: logitech-djreceiver 0003:046D:C52B.0169: hiddev98,hidraw3: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:50:44 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.0169/0003:046D:404D.016A/input/input175 May 15 13:50:45 volumio kernel: logitech-hidpp-device 0003:046D:404D.016A: input,hidraw4: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:50:45 volumio systemd-udevd[4720]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:45 volumio systemd-udevd[4722]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:45 volumio systemd-udevd[4721]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:45 volumio systemd-udevd[4718]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:45 volumio systemd-udevd[4723]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:45 volumio systemd-logind[866]: Watching system buttons on /dev/input/event2 (Logitech K400 Plus) May 15 13:50:45 volumio systemd-udevd[4751]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:45 volumio systemd-udevd[4727]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:50:46 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 13:50:46 volumio volumio[1100]: info: Preload queue cleared May 15 13:50:50 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 13:50:50 volumio volumio[1100]: info: Preload queue cleared May 15 13:50:51 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 13:50:52 volumio volumio[1100]: info: Preload queue cleared May 15 13:50:54 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 13:50:54 volumio volumio[1100]: info: Preload queue cleared May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:50:54 volumio volumio[1100]: info: Preloading song: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:50:54 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 in service upnp_browser May 15 13:50:55 volumio volumio[1100]: info: Exploding uri upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 in service upnp_browser May 15 13:50:58 volumio kernel: usb usb1-port1: over-current change #106 May 15 13:50:58 volumio kernel: usb usb3-port1: over-current change #147 May 15 13:50:58 volumio kernel: usb usb4-port1: over-current change #188 May 15 13:50:58 volumio kernel: usb usb2-port1: over-current change #188 May 15 13:50:58 volumio kernel: usb 1-1: USB disconnect, device number 111 May 15 13:50:59 volumio kernel: usb usb3-port2: over-current change #120 May 15 13:50:59 volumio kernel: usb usb2-port1: over-current change #189 May 15 13:50:59 volumio kernel: usb usb4-port1: over-current change #189 May 15 13:50:59 volumio kernel: usb usb3-port1: over-current change #148 May 15 13:50:59 volumio kernel: usb usb1-port2: over-current change #88 May 15 13:50:59 volumio kernel: usb 1-2: USB disconnect, device number 112 May 15 13:50:59 volumio kernel: usb 1-2: new full-speed USB device number 113 using xhci-hcd May 15 13:51:00 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:51:00 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:51:00 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:51:00 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:51:00 volumio kernel: logitech-djreceiver 0003:046D:C52B.016D: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:51:00 volumio kernel: usb usb1-port1: over-current change #107 May 15 13:51:00 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.016D/0003:046D:404D.016E/input/input176 May 15 13:51:00 volumio kernel: logitech-hidpp-device 0003:046D:404D.016E: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:51:00 volumio kernel: usb 1-1: new full-speed USB device number 114 using xhci-hcd May 15 13:51:00 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:51:00 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:51:00 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:51:00 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:51:00 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:51:00 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.016F/input/input177 May 15 13:51:00 volumio kernel: hid-multitouch 0003:27C0:0859.016F: input,hiddev97,hidraw2: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:51:00 volumio kernel: hid-generic 0003:27C0:0859.0170: hiddev98,hidraw3: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:51:00 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.0171/input/input178 May 15 13:51:00 volumio kernel: hid-generic 0003:27C0:0859.0171: input,hidraw4: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:51:00 volumio systemd-udevd[4837]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:00 volumio systemd-udevd[4836]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:00 volumio systemd-udevd[4840]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:01 volumio systemd-udevd[4848]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:01 volumio systemd-logind[866]: Watching system buttons on /dev/input/event0 (Logitech K400 Plus) May 15 13:51:01 volumio systemd-udevd[4838]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:01 volumio systemd-udevd[4835]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:02 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::ClearQueue May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::stop May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::clearPlayQueue May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::addQueueItems May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::addQueueItems May 15 13:51:02 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPlay May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::play index 10 May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::addQueueItems May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::addQueueItems May 15 13:51:02 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:02 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:51:02 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::stop May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::play index undefined May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::getTrack 10 May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::getTrack 10 May 15 13:51:02 volumio volumio[1100]: info: [1747342262745] ControllerUPNPBrowser::clearAddPlayTrack May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand stop took 7 milliseconds May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear May 15 13:51:02 volumio volumio[1100]: info: May 15 13:51:02 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:02 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand clear took 1 milliseconds May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.210:8200/MediaItems/109.wav" May 15 13:51:02 volumio volumio[1100]: info: May 15 13:51:02 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:02 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:02 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:02 volumio volumio[1100]: info: ------------------------------ 3ms May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/109.wav" May 15 13:51:02 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:02 volumio volumio[1100]: info: May 15 13:51:02 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:02 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:02 volumio volumio[1100]: info: ------------------------------ 4ms May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/109.wav" took 1 milliseconds May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand play May 15 13:51:02 volumio volumio[1100]: info: May 15 13:51:02 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:02 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:02 volumio volumio[1100]: info: ------------------------------ 2ms May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand play took 1 milliseconds May 15 13:51:02 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:02 volumio volumio[1100]: info: May 15 13:51:02 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 15 13:51:02 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:02 volumio volumio[1100]: info: May 15 13:51:02 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 15 13:51:02 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand status took 3 milliseconds May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand status took 7 milliseconds May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand clearerror took 4 milliseconds May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 15 13:51:02 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::getTrack 10 May 15 13:51:02 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"109.wav","artist":null,"album":null,"uri":"http://192.168.0.210:8200/MediaItems/109.wav","trackType":"wav"} May 15 13:51:02 volumio volumio[1100]: verbose: CURRENT POSITION 10 May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:02 volumio volumio[1100]: info: No code May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:02 volumio volumio[1100]: info: ------------------------------ 25ms May 15 13:51:02 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status May 15 13:51:02 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:02 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand clearerror took 24 milliseconds May 15 13:51:02 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 23 milliseconds May 15 13:51:02 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 15 13:51:02 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:02 volumio kernel: usb usb1-port1: over-current change #108 May 15 13:51:02 volumio kernel: usb usb3-port1: over-current change #149 May 15 13:51:02 volumio volumio[1100]: info: CorePlayQueue::getTrack 10 May 15 13:51:02 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"109.wav","artist":null,"album":null,"uri":"http://192.168.0.210:8200/MediaItems/109.wav","trackType":"wav"} May 15 13:51:02 volumio volumio[1100]: verbose: CURRENT POSITION 10 May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:02 volumio volumio[1100]: info: No code May 15 13:51:02 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:02 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:02 volumio volumio[1100]: info: ------------------------------ 45ms May 15 13:51:02 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:02 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:02 volumio kernel: usb usb2-port1: over-current change #190 May 15 13:51:02 volumio kernel: usb usb4-port1: over-current change #190 May 15 13:51:02 volumio kernel: usb 1-1: USB disconnect, device number 114 May 15 13:51:03 volumio kernel: usb usb3-port2: over-current change #121 May 15 13:51:03 volumio kernel: usb usb2-port1: over-current change #191 May 15 13:51:03 volumio kernel: usb usb4-port1: over-current change #191 May 15 13:51:03 volumio kernel: usb usb3-port1: over-current change #150 May 15 13:51:03 volumio kernel: usb usb1-port2: over-current change #89 May 15 13:51:03 volumio kernel: usb 1-2: USB disconnect, device number 113 May 15 13:51:03 volumio kernel: usb 1-2: new full-speed USB device number 115 using xhci-hcd May 15 13:51:03 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:51:04 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:51:04 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:51:04 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:51:04 volumio kernel: logitech-djreceiver 0003:046D:C52B.0174: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:51:04 volumio kernel: usb usb1-port1: over-current change #109 May 15 13:51:04 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.0174/0003:046D:404D.0175/input/input179 May 15 13:51:04 volumio kernel: logitech-hidpp-device 0003:046D:404D.0175: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:51:04 volumio kernel: usb 1-1: new full-speed USB device number 116 using xhci-hcd May 15 13:51:04 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:51:04 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:51:04 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:51:04 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:51:04 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:51:04 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.0176/input/input180 May 15 13:51:04 volumio kernel: hid-multitouch 0003:27C0:0859.0176: input,hiddev97,hidraw2: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:51:04 volumio kernel: hid-generic 0003:27C0:0859.0177: hiddev98,hidraw3: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:51:04 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.0178/input/input181 May 15 13:51:04 volumio kernel: hid-generic 0003:27C0:0859.0178: input,hidraw4: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:51:04 volumio systemd-udevd[4848]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:04 volumio systemd-udevd[4840]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:04 volumio systemd-udevd[4838]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:05 volumio systemd-udevd[4837]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:05 volumio systemd-logind[866]: Watching system buttons on /dev/input/event0 (Logitech K400 Plus) May 15 13:51:05 volumio systemd-udevd[4839]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:05 volumio systemd-udevd[4835]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:05 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::ClearQueue May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::clearPlayQueue May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::addQueueItems May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::addQueueItems May 15 13:51:05 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPlay May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::play index 8 May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::addQueueItems May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::addQueueItems May 15 13:51:05 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:51:05 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:51:05 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::play index undefined May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: [1747342265215] ControllerUPNPBrowser::clearAddPlayTrack May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 9 May 15 13:51:05 volumio volumio[1100]: info: Prefetching next song May 15 13:51:05 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/108.pcm" May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:05 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand stop took 3 milliseconds May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/108.pcm" took 2 milliseconds May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:05 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:05 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces state update: options May 15 13:51:05 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand clear took 1 milliseconds May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:05 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.210:8200/MediaItems/107.pcm" May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:05 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces state update: options May 15 13:51:05 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:05 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand status took 1 milliseconds May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:05 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 13:51:05 volumio volumio[1100]: verbose: CURRENT POSITION 8 May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: No code May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 17ms May 15 13:51:05 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/107.pcm" May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 16ms May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand status took 16 milliseconds May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:05 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 15 13:51:05 volumio volumio[1100]: verbose: CURRENT POSITION 8 May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: No code May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 30ms May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:05 volumio kernel: usb usb1-port1: over-current change #110 May 15 13:51:05 volumio kernel: usb usb3-port1: over-current change #151 May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:05 volumio sudo[4893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:05 volumio sudo[4893]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:05 volumio sudo[4896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:05 volumio sudo[4893]: pam_unix(sudo:session): session closed for user root May 15 13:51:05 volumio sudo[4896]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:05 volumio kernel: usb usb4-port1: over-current change #192 May 15 13:51:05 volumio kernel: usb usb2-port1: over-current change #192 May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:05 volumio sudo[4900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:05 volumio sudo[4900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:05 volumio sudo[4896]: pam_unix(sudo:session): session closed for user root May 15 13:51:05 volumio sudo[4900]: pam_unix(sudo:session): session closed for user root May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:05 volumio sudo[4903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:05 volumio sudo[4903]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:05 volumio sudo[4903]: pam_unix(sudo:session): session closed for user root May 15 13:51:05 volumio sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:05 volumio sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:05 volumio sudo[4909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:05 volumio sudo[4909]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:05 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/107.pcm" took 106 milliseconds May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand play May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:05 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:05 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 5ms May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand play took 4 milliseconds May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 4ms May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 15 13:51:05 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:05 volumio sudo[4906]: pam_unix(sudo:session): session closed for user root May 15 13:51:05 volumio volumio[1100]: info: May 15 13:51:05 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 15 13:51:05 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:05 volumio sudo[4909]: pam_unix(sudo:session): session closed for user root May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand status took 8 milliseconds May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 13:51:05 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand status took 8 milliseconds May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand clearerror took 4 milliseconds May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 4 milliseconds May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 15 13:51:05 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"107.pcm","artist":null,"album":null,"uri":"http://192.168.0.210:8200/MediaItems/107.pcm","trackType":"pcm"} May 15 13:51:05 volumio volumio[1100]: verbose: CURRENT POSITION 8 May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: No code May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 24ms May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:05 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand clearerror took 14 milliseconds May 15 13:51:05 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 14 milliseconds May 15 13:51:05 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 15 13:51:05 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:05 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"107.pcm","artist":null,"album":null,"uri":"http://192.168.0.210:8200/MediaItems/107.pcm","trackType":"pcm"} May 15 13:51:05 volumio volumio[1100]: verbose: CURRENT POSITION 8 May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: No code May 15 13:51:05 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:05 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:05 volumio volumio[1100]: info: ------------------------------ 34ms May 15 13:51:05 volumio kernel: usb usb3-port2: over-current change #122 May 15 13:51:05 volumio kernel: usb 1-1: USB disconnect, device number 116 May 15 13:51:05 volumio kernel: usb usb4-port1: over-current change #193 May 15 13:51:05 volumio kernel: usb usb2-port1: over-current change #193 May 15 13:51:05 volumio kernel: usb usb3-port1: over-current change #152 May 15 13:51:05 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:05 volumio kernel: usb usb3-port2: over-current change #123 May 15 13:51:05 volumio kernel: usb usb1-port2: over-current change #90 May 15 13:51:05 volumio kernel: usb usb4-port1: over-current change #194 May 15 13:51:05 volumio kernel: usb usb2-port1: over-current change #194 May 15 13:51:05 volumio kernel: usb usb3-port1: over-current change #153 May 15 13:51:05 volumio kernel: usb 1-2: USB disconnect, device number 115 May 15 13:51:06 volumio kernel: usb 1-2: new full-speed USB device number 117 using xhci-hcd May 15 13:51:06 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::ClearQueue May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::stop May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::clearPlayQueue May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::addQueueItems May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::addQueueItems May 15 13:51:06 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPlay May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::play index 6 May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::addQueueItems May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::addQueueItems May 15 13:51:06 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:51:06 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:51:06 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::stop May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::play index undefined May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 6 May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 6 May 15 13:51:06 volumio volumio[1100]: info: [1747342266438] ControllerUPNPBrowser::clearAddPlayTrack May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand stop took 3 milliseconds May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand clear took 1 milliseconds May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.210:8200/MediaItems/105.pcm" May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 6 May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 7 May 15 13:51:06 volumio volumio[1100]: info: Prefetching next song May 15 13:51:06 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/106.pcm" May 15 13:51:06 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:51:06 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:51:06 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:51:06 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:51:06 volumio kernel: logitech-djreceiver 0003:046D:C52B.017B: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:51:06 volumio kernel: usb usb1-port1: over-current change #111 May 15 13:51:06 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.017B/0003:046D:404D.017C/input/input182 May 15 13:51:06 volumio kernel: logitech-hidpp-device 0003:046D:404D.017C: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/105.pcm" May 15 13:51:06 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 389ms May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/106.pcm" took 362 milliseconds May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/105.pcm" took 2 milliseconds May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand play May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 0ms May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand play took 1 milliseconds May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 0ms May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 15 13:51:06 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 15 13:51:06 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand status took 3 milliseconds May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand status took 7 milliseconds May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand clearerror took 6 milliseconds May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 5 milliseconds May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand clearerror took 2 milliseconds May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 2 milliseconds May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 15 13:51:06 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 6 May 15 13:51:06 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"105.pcm","artist":null,"album":null,"uri":"http://192.168.0.210:8200/MediaItems/105.pcm","trackType":"pcm"} May 15 13:51:06 volumio volumio[1100]: verbose: CURRENT POSITION 6 May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:06 volumio volumio[1100]: info: No code May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:06 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 6 May 15 13:51:06 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"105.pcm","artist":null,"album":null,"uri":"http://192.168.0.210:8200/MediaItems/105.pcm","trackType":"pcm"} May 15 13:51:06 volumio volumio[1100]: verbose: CURRENT POSITION 6 May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:06 volumio volumio[1100]: info: No code May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:06 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 37ms May 15 13:51:06 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status May 15 13:51:06 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:06 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:06 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:06 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:06 volumio kernel: usb usb3-port1: over-current change #154 May 15 13:51:06 volumio kernel: usb usb4-port1: over-current change #195 May 15 13:51:06 volumio kernel: usb usb2-port1: over-current change #195 May 15 13:51:06 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 7 May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 7 May 15 13:51:06 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:06 volumio volumio[1100]: info: Prefetching next song May 15 13:51:06 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/107.pcm" May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/107.pcm" took 1 milliseconds May 15 13:51:06 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:06 volumio volumio[1100]: info: May 15 13:51:06 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:06 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:06 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:06 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:07 volumio kernel: usb usb3-port2: over-current change #124 May 15 13:51:07 volumio kernel: usb usb1-port2: over-current change #91 May 15 13:51:07 volumio kernel: usb usb4-port1: over-current change #196 May 15 13:51:07 volumio kernel: usb usb2-port1: over-current change #196 May 15 13:51:07 volumio kernel: usb usb3-port1: over-current change #155 May 15 13:51:07 volumio kernel: usb 1-2: USB disconnect, device number 117 May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 9 May 15 13:51:07 volumio volumio[1100]: info: Prefetching next song May 15 13:51:07 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/108.pcm" May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:07 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/108.pcm" took 0 milliseconds May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:07 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 0ms May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:07 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:07 volumio sudo[4917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:07 volumio sudo[4917]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:07 volumio sudo[4917]: pam_unix(sudo:session): session closed for user root May 15 13:51:07 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:07 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::ClearQueue May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::stop May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::clearPlayQueue May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::addQueueItems May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::addQueueItems May 15 13:51:07 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$A May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$B May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPlay May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::play index 1 May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::addQueueItems May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::addQueueItems May 15 13:51:07 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$C May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$D May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$E May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$F May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$10 May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$11 May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$12 May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$1$13 May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$2 May 15 13:51:07 volumio volumio[1100]: info: Adding Item to queue: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:51:07 volumio volumio[1100]: info: Using cached record of: upnp/http://192.168.0.210:8200/ctl/ContentDir@1$FF0$64$0$0$0$0$3 May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::stop May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushQueue May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::saveQueue May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::play index undefined May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::updateTrackBlock May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrackBlock May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 1 May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 1 May 15 13:51:07 volumio volumio[1100]: info: [1747342267582] ControllerUPNPBrowser::clearAddPlayTrack May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand stop took 3 milliseconds May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:07 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand clear took 0 milliseconds May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.210:8200/MediaItems/100.pcm" May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:07 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:07 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:07 volumio volumio[1100]: error: updateQueue error: null May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/100.pcm" May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 3ms May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:07 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/100.pcm" took 0 milliseconds May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand play May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:07 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand play took 1 milliseconds May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 15 13:51:07 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 15 13:51:07 volumio volumio[1100]: info: ControllerMpd::getState May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 1 May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 2 May 15 13:51:07 volumio volumio[1100]: info: Prefetching next song May 15 13:51:07 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/101.pcm" May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand status took 3 milliseconds May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand clearerror May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand status took 4 milliseconds May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/101.pcm" took 4 milliseconds May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::parseState May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:07 volumio kernel: usb usb3-port1: over-current change #156 May 15 13:51:07 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand clearerror took 6 milliseconds May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand clearerror took 5 milliseconds May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 13:51:07 volumio volumio[1100]: info: sendMpdCommand consume 1 took 3 milliseconds May 15 13:51:07 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 15 13:51:07 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 1 May 15 13:51:07 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"100.pcm","artist":null,"album":null,"uri":"http://192.168.0.210:8200/MediaItems/100.pcm","trackType":"pcm"} May 15 13:51:07 volumio volumio[1100]: verbose: CURRENT POSITION 1 May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:07 volumio volumio[1100]: info: No code May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:07 volumio volumio[1100]: info: ControllerMpd::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:07 volumio volumio[1100]: info: CorePlayQueue::getTrack 1 May 15 13:51:07 volumio volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"100.pcm","artist":null,"album":null,"uri":"http://192.168.0.210:8200/MediaItems/100.pcm","trackType":"pcm"} May 15 13:51:07 volumio volumio[1100]: verbose: CURRENT POSITION 1 May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::syncState stateService stop May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus stop May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:07 volumio volumio[1100]: info: No code May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:07 volumio kernel: usb usb2-port1: over-current change #197 May 15 13:51:07 volumio kernel: usb 1-2: new full-speed USB device number 118 using xhci-hcd May 15 13:51:07 volumio kernel: usb usb4-port1: over-current change #197 May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 35ms May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 34ms May 15 13:51:07 volumio volumio[1100]: info: May 15 13:51:07 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:07 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:07 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status May 15 13:51:07 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:07 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:07 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:07 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:07 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status undefined May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 34ms May 15 13:51:07 volumio volumio[1100]: info: ------------------------------ 5ms May 15 13:51:07 volumio kernel: usb usb3-port2: over-current change #125 May 15 13:51:07 volumio kernel: usb usb2-port1: over-current change #198 May 15 13:51:07 volumio kernel: usb usb4-port1: over-current change #198 May 15 13:51:07 volumio kernel: usb usb3-port1: over-current change #157 May 15 13:51:07 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:07 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:07 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status May 15 13:51:08 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:08 volumio volumio[1100]: info: CorePlayQueue::getTrack 2 May 15 13:51:08 volumio volumio[1100]: info: CorePlayQueue::getTrack 2 May 15 13:51:08 volumio volumio[1100]: info: CorePlayQueue::getTrack 3 May 15 13:51:08 volumio volumio[1100]: info: Prefetching next song May 15 13:51:08 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:08 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/102.pcm" May 15 13:51:08 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/102.pcm" took 1 milliseconds May 15 13:51:08 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:08 volumio volumio[1100]: info: May 15 13:51:08 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:08 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:08 volumio volumio[1100]: info: sendMpdCommand consume 1 took 0 milliseconds May 15 13:51:08 volumio volumio[1100]: info: May 15 13:51:08 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:08 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:08 volumio volumio[1100]: info: ------------------------------ 2ms May 15 13:51:08 volumio volumio[1100]: info: ------------------------------ 0ms May 15 13:51:08 volumio kernel: usb usb1-port1: over-current change #112 May 15 13:51:08 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:08 volumio volumio[1100]: info: CorePlayQueue::getTrack 3 May 15 13:51:08 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:08 volumio volumio[1100]: info: CorePlayQueue::getTrack 3 May 15 13:51:08 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:08 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:08 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:08 volumio sudo[4925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:08 volumio sudo[4925]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:08 volumio sudo[4925]: pam_unix(sudo:session): session closed for user root May 15 13:51:08 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:08 volumio kernel: usb usb3-port1: over-current change #158 May 15 13:51:08 volumio volumio[1100]: info: CorePlayQueue::getTrack 3 May 15 13:51:08 volumio volumio[1100]: info: CorePlayQueue::getTrack 4 May 15 13:51:08 volumio volumio[1100]: info: Prefetching next song May 15 13:51:08 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:08 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/103.pcm" May 15 13:51:08 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/103.pcm" took 0 milliseconds May 15 13:51:08 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:08 volumio volumio[1100]: info: May 15 13:51:08 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:08 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:08 volumio volumio[1100]: info: sendMpdCommand consume 1 took 0 milliseconds May 15 13:51:08 volumio volumio[1100]: info: May 15 13:51:08 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:08 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:08 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:08 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:08 volumio kernel: usb usb2-port1: over-current change #199 May 15 13:51:08 volumio kernel: usb usb4-port1: over-current change #199 May 15 13:51:08 volumio kernel: usb usb3-port2: over-current change #126 May 15 13:51:09 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:09 volumio volumio[1100]: info: CorePlayQueue::getTrack 4 May 15 13:51:09 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:09 volumio volumio[1100]: info: CorePlayQueue::getTrack 4 May 15 13:51:09 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:09 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:09 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:09 volumio sudo[4928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:09 volumio sudo[4928]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:09 volumio sudo[4928]: pam_unix(sudo:session): session closed for user root May 15 13:51:09 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:09 volumio volumio[1100]: info: CorePlayQueue::getTrack 4 May 15 13:51:09 volumio volumio[1100]: info: CorePlayQueue::getTrack 5 May 15 13:51:09 volumio volumio[1100]: info: Prefetching next song May 15 13:51:09 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:09 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/104.pcm" May 15 13:51:09 volumio volumio[1100]: info: May 15 13:51:09 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:09 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:09 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/104.pcm" took 0 milliseconds May 15 13:51:09 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:09 volumio volumio[1100]: info: May 15 13:51:09 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:09 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:09 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:09 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:09 volumio volumio[1100]: info: ------------------------------ 0ms May 15 13:51:09 volumio kernel: usb usb1-port2: over-current change #92 May 15 13:51:09 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:09 volumio volumio[1100]: info: CorePlayQueue::getTrack 4 May 15 13:51:09 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:09 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:09 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:09 volumio sudo[4931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:09 volumio sudo[4931]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:09 volumio sudo[4931]: pam_unix(sudo:session): session closed for user root May 15 13:51:09 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:09 volumio kernel: usb 1-2: new full-speed USB device number 120 using xhci-hcd May 15 13:51:10 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:51:10 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:51:10 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:51:10 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:51:10 volumio kernel: logitech-djreceiver 0003:046D:C52B.017F: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:51:10 volumio kernel: usb usb1-port1: over-current change #113 May 15 13:51:10 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.017F/0003:046D:404D.0180/input/input183 May 15 13:51:10 volumio kernel: logitech-hidpp-device 0003:046D:404D.0180: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:51:10 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:10 volumio volumio[1100]: info: CorePlayQueue::getTrack 5 May 15 13:51:10 volumio kernel: usb 1-1: new full-speed USB device number 121 using xhci-hcd May 15 13:51:10 volumio volumio[1100]: info: CorePlayQueue::getTrack 5 May 15 13:51:10 volumio volumio[1100]: info: CorePlayQueue::getTrack 6 May 15 13:51:10 volumio volumio[1100]: info: Prefetching next song May 15 13:51:10 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:10 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/105.pcm" May 15 13:51:10 volumio volumio[1100]: info: May 15 13:51:10 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:10 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:10 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/105.pcm" took 2 milliseconds May 15 13:51:10 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:10 volumio volumio[1100]: info: May 15 13:51:10 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:10 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:10 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:10 volumio volumio[1100]: info: sendMpdCommand consume 1 took 0 milliseconds May 15 13:51:10 volumio volumio[1100]: info: ------------------------------ 0ms May 15 13:51:10 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:51:10 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:51:10 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:51:10 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:51:10 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:51:10 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.0181/input/input184 May 15 13:51:10 volumio kernel: hid-multitouch 0003:27C0:0859.0181: input,hiddev97,hidraw2: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:51:10 volumio kernel: hid-generic 0003:27C0:0859.0182: hiddev98,hidraw3: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:51:10 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.0183/input/input185 May 15 13:51:10 volumio kernel: hid-generic 0003:27C0:0859.0183: input,hidraw4: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:51:10 volumio systemd-udevd[4835]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:11 volumio systemd-udevd[4838]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:11 volumio systemd-udevd[4848]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:11 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 13:51:11 volumio systemd-udevd[4937]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:11 volumio systemd-logind[866]: Watching system buttons on /dev/input/event0 (Logitech K400 Plus) May 15 13:51:11 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:11 volumio volumio[1100]: info: CorePlayQueue::getTrack 5 May 15 13:51:11 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:11 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:11 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:11 volumio sudo[4950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:11 volumio sudo[4950]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:11 volumio sudo[4950]: pam_unix(sudo:session): session closed for user root May 15 13:51:11 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:11 volumio systemd-udevd[4841]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:11 volumio systemd-udevd[4839]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:11 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:11 volumio kernel: usb usb1-port1: over-current change #114 May 15 13:51:11 volumio kernel: usb usb3-port1: over-current change #159 May 15 13:51:11 volumio kernel: usb usb2-port1: over-current change #200 May 15 13:51:11 volumio kernel: usb usb4-port1: over-current change #200 May 15 13:51:11 volumio kernel: usb 1-1: USB disconnect, device number 121 May 15 13:51:12 volumio kernel: usb usb3-port2: over-current change #127 May 15 13:51:12 volumio kernel: usb usb2-port1: over-current change #201 May 15 13:51:12 volumio kernel: usb usb4-port1: over-current change #201 May 15 13:51:12 volumio kernel: usb usb3-port1: over-current change #160 May 15 13:51:12 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:12 volumio volumio[1100]: info: CorePlayQueue::getTrack 6 May 15 13:51:12 volumio kernel: usb usb1-port2: over-current change #93 May 15 13:51:12 volumio volumio[1100]: info: CorePlayQueue::getTrack 6 May 15 13:51:12 volumio volumio[1100]: info: CorePlayQueue::getTrack 7 May 15 13:51:12 volumio volumio[1100]: info: Prefetching next song May 15 13:51:12 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:12 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/106.pcm" May 15 13:51:12 volumio volumio[1100]: info: May 15 13:51:12 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:12 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:12 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/106.pcm" took 1 milliseconds May 15 13:51:12 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:12 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:12 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:12 volumio volumio[1100]: info: May 15 13:51:12 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:12 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:12 volumio volumio[1100]: info: ------------------------------ 0ms May 15 13:51:12 volumio kernel: usb usb3-port2: over-current change #128 May 15 13:51:12 volumio kernel: usb usb2-port1: over-current change #202 May 15 13:51:12 volumio kernel: usb usb4-port1: over-current change #202 May 15 13:51:12 volumio kernel: usb 1-2: USB disconnect, device number 120 May 15 13:51:12 volumio kernel: usb usb3-port1: over-current change #161 May 15 13:51:12 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:12 volumio volumio[1100]: info: CorePlayQueue::getTrack 7 May 15 13:51:12 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:12 volumio volumio[1100]: info: CorePlayQueue::getTrack 7 May 15 13:51:12 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:12 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:12 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:12 volumio sudo[4954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:12 volumio sudo[4954]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:12 volumio sudo[4954]: pam_unix(sudo:session): session closed for user root May 15 13:51:12 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:12 volumio volumio[1100]: info: CorePlayQueue::getTrack 7 May 15 13:51:12 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:12 volumio volumio[1100]: info: Prefetching next song May 15 13:51:12 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:12 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/107.pcm" May 15 13:51:12 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/107.pcm" took 0 milliseconds May 15 13:51:12 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:12 volumio volumio[1100]: info: May 15 13:51:12 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:12 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:12 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:12 volumio volumio[1100]: info: May 15 13:51:12 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:12 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:12 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:12 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:12 volumio kernel: usb 1-2: new full-speed USB device number 122 using xhci-hcd May 15 13:51:12 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:51:13 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:51:13 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:51:13 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:51:13 volumio kernel: logitech-djreceiver 0003:046D:C52B.0186: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:51:13 volumio kernel: usb usb1-port1: over-current change #115 May 15 13:51:13 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:13 volumio volumio[1100]: info: CorePlayQueue::getTrack 7 May 15 13:51:13 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:13 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:13 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:13 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.0186/0003:046D:404D.0187/input/input186 May 15 13:51:13 volumio sudo[4958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:13 volumio sudo[4958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:13 volumio sudo[4958]: pam_unix(sudo:session): session closed for user root May 15 13:51:13 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:13 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:13 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:13 volumio kernel: usb usb3-port1: over-current change #162 May 15 13:51:13 volumio kernel: usb usb4-port1: over-current change #203 May 15 13:51:13 volumio kernel: usb usb2-port1: over-current change #203 May 15 13:51:13 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:13 volumio volumio[1100]: info: CorePlayQueue::getTrack 9 May 15 13:51:13 volumio volumio[1100]: info: Prefetching next song May 15 13:51:13 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:13 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/108.pcm" May 15 13:51:13 volumio volumio[1100]: info: May 15 13:51:13 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:13 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:13 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/108.pcm" took 0 milliseconds May 15 13:51:13 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:13 volumio volumio[1100]: info: May 15 13:51:13 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:13 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:13 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:13 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:13 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:13 volumio kernel: logitech-hidpp-device 0003:046D:404D.0187: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:51:13 volumio kernel: usb usb3-port2: over-current change #129 May 15 13:51:13 volumio kernel: usb usb4-port1: over-current change #204 May 15 13:51:13 volumio kernel: usb usb2-port1: over-current change #204 May 15 13:51:13 volumio kernel: usb usb1-port2: over-current change #94 May 15 13:51:13 volumio kernel: usb usb3-port1: over-current change #163 May 15 13:51:13 volumio kernel: usb 1-2: USB disconnect, device number 122 May 15 13:51:13 volumio kernel: usb usb3-port2: over-current change #130 May 15 13:51:13 volumio kernel: usb usb2-port1: over-current change #205 May 15 13:51:13 volumio kernel: usb usb4-port1: over-current change #205 May 15 13:51:13 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:13 volumio volumio[1100]: info: CorePlayQueue::getTrack 8 May 15 13:51:13 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:13 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:13 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:13 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:13 volumio sudo[4961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:13 volumio sudo[4961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:13 volumio sudo[4961]: pam_unix(sudo:session): session closed for user root May 15 13:51:14 volumio kernel: usb 1-2: new full-speed USB device number 123 using xhci-hcd May 15 13:51:14 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:51:14 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:51:14 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:51:14 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:51:14 volumio kernel: logitech-djreceiver 0003:046D:C52B.018A: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:51:14 volumio kernel: usb usb1-port1: over-current change #116 May 15 13:51:14 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.018A/0003:046D:404D.018B/input/input187 May 15 13:51:14 volumio kernel: logitech-hidpp-device 0003:046D:404D.018B: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:51:14 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:14 volumio volumio[1100]: info: CorePlayQueue::getTrack 9 May 15 13:51:14 volumio volumio[1100]: info: CorePlayQueue::getTrack 9 May 15 13:51:14 volumio volumio[1100]: info: CorePlayQueue::getTrack 10 May 15 13:51:14 volumio volumio[1100]: info: Prefetching next song May 15 13:51:14 volumio volumio[1100]: info: Doing Prefetch in UPNP May 15 13:51:14 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.210:8200/MediaItems/109.wav" May 15 13:51:14 volumio volumio[1100]: info: May 15 13:51:14 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:14 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:14 volumio volumio[1100]: info: sendMpdCommand add "http://192.168.0.210:8200/MediaItems/109.wav" took 1 milliseconds May 15 13:51:14 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 15 13:51:14 volumio volumio[1100]: info: May 15 13:51:14 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 15 13:51:14 volumio volumio[1100]: info: Ignoring MPD Status Update May 15 13:51:14 volumio volumio[1100]: info: ------------------------------ 2ms May 15 13:51:14 volumio volumio[1100]: info: sendMpdCommand consume 1 took 1 milliseconds May 15 13:51:14 volumio volumio[1100]: info: ------------------------------ 1ms May 15 13:51:14 volumio kernel: usb 1-1: new full-speed USB device number 124 using xhci-hcd May 15 13:51:14 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:51:15 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:51:15 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:51:15 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:51:15 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:51:15 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.018C/input/input188 May 15 13:51:15 volumio kernel: hid-multitouch 0003:27C0:0859.018C: input,hiddev97,hidraw2: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:51:15 volumio kernel: hid-generic 0003:27C0:0859.018D: hiddev98,hidraw3: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:51:15 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.018E/input/input189 May 15 13:51:15 volumio kernel: hid-generic 0003:27C0:0859.018E: input,hidraw4: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:51:15 volumio kernel: usb usb1-port2: over-current change #95 May 15 13:51:15 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:15 volumio volumio[1100]: info: CorePlayQueue::getTrack 9 May 15 13:51:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:15 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:15 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:15 volumio sudo[4982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:15 volumio sudo[4982]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:15 volumio sudo[4982]: pam_unix(sudo:session): session closed for user root May 15 13:51:15 volumio kernel: usb usb1-port1: over-current change #117 May 15 13:51:15 volumio kernel: usb usb3-port1: over-current change #164 May 15 13:51:15 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:15 volumio kernel: usb usb2-port1: over-current change #206 May 15 13:51:15 volumio kernel: usb usb4-port1: over-current change #206 May 15 13:51:15 volumio systemd-udevd[4840]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:15 volumio systemd-udevd[4839]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:15 volumio kernel: usb 1-1: USB disconnect, device number 124 May 15 13:51:15 volumio kernel: usb usb3-port2: over-current change #131 May 15 13:51:15 volumio kernel: usb usb2-port1: over-current change #207 May 15 13:51:15 volumio kernel: usb usb4-port1: over-current change #207 May 15 13:51:15 volumio startx[1406]: (EE) client bug: Invalid path /dev/input/event1 May 15 13:51:15 volumio startx[1406]: (EE) client bug: Invalid path /dev/input/event2 May 15 13:51:15 volumio systemd-udevd[4848]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:15 volumio systemd-udevd[4835]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:15 volumio kernel: usb usb3-port1: over-current change #165 May 15 13:51:15 volumio kernel: usb usb1-port2: over-current change #96 May 15 13:51:15 volumio kernel: usb usb3-port2: over-current change #132 May 15 13:51:15 volumio kernel: usb usb2-port1: over-current change #208 May 15 13:51:15 volumio kernel: usb usb4-port1: over-current change #208 May 15 13:51:15 volumio kernel: usb 1-2: USB disconnect, device number 123 May 15 13:51:16 volumio kernel: usb usb3-port1: over-current change #166 May 15 13:51:16 volumio kernel: usb usb2-port1: over-current change #209 May 15 13:51:16 volumio kernel: usb usb4-port1: over-current change #209 May 15 13:51:16 volumio kernel: usb usb3-port2: over-current change #133 May 15 13:51:16 volumio kernel: usb usb3-port1: over-current change #167 May 15 13:51:16 volumio kernel: usb usb1-port1: over-current change #118 May 15 13:51:16 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 15 13:51:16 volumio volumio[1100]: info: CorePlayQueue::getTrack 10 May 15 13:51:16 volumio kernel: usb usb3-port1: over-current change #168 May 15 13:51:16 volumio kernel: usb usb2-port1: over-current change #210 May 15 13:51:16 volumio kernel: usb usb4-port1: over-current change #210 May 15 13:51:16 volumio kernel: usb usb3-port2: over-current change #134 May 15 13:51:16 volumio kernel: usb usb1-port2: over-current change #97 May 15 13:51:16 volumio volumio[1100]: info: CoreStateMachine::pushState May 15 13:51:16 volumio volumio[1100]: info: CorePlayQueue::getTrack 10 May 15 13:51:16 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 13:51:16 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 15 13:51:16 volumio volumio[1100]: info: PeppySpectrum ---peppyspectrum status stop May 15 13:51:16 volumio sudo[4993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 15 13:51:16 volumio sudo[4993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:16 volumio sudo[4993]: pam_unix(sudo:session): session closed for user root May 15 13:51:16 volumio volumio[1100]: info: peppyspectrum Daemon Stop May 15 13:51:16 volumio kernel: usb 1-2: new full-speed USB device number 125 using xhci-hcd May 15 13:51:17 volumio kernel: usb 1-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 May 15 13:51:17 volumio kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 15 13:51:17 volumio kernel: usb 1-2: Product: USB Receiver May 15 13:51:17 volumio kernel: usb 1-2: Manufacturer: Logitech May 15 13:51:17 volumio kernel: logitech-djreceiver 0003:046D:C52B.0191: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.0-2/input2 May 15 13:51:17 volumio kernel: usb usb1-port1: over-current change #119 May 15 13:51:17 volumio kernel: input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2/1-2:1.2/0003:046D:C52B.0191/0003:046D:404D.0192/input/input190 May 15 13:51:17 volumio kernel: logitech-hidpp-device 0003:046D:404D.0192: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.0-2/input2:1 May 15 13:51:17 volumio kernel: usb 1-1: new full-speed USB device number 126 using xhci-hcd May 15 13:51:17 volumio kernel: usb 1-1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 May 15 13:51:17 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 15 13:51:17 volumio kernel: usb 1-1: Product: TouchScreen May 15 13:51:17 volumio kernel: usb 1-1: Manufacturer: wch.cn May 15 13:51:17 volumio kernel: usb 1-1: SerialNumber: 9LQ0172005164 May 15 13:51:17 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.0/0003:27C0:0859.0193/input/input191 May 15 13:51:17 volumio kernel: hid-multitouch 0003:27C0:0859.0193: input,hiddev97,hidraw2: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input0 May 15 13:51:17 volumio kernel: hid-generic 0003:27C0:0859.0194: hiddev98,hidraw3: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input1 May 15 13:51:17 volumio kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-1/1-1:1.2/0003:27C0:0859.0195/input/input192 May 15 13:51:17 volumio kernel: hid-generic 0003:27C0:0859.0195: input,hidraw4: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.0-1/input2 May 15 13:51:18 volumio systemd-udevd[4836]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:18 volumio systemd-udevd[4833]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:18 volumio systemd-udevd[4995]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:18 volumio systemd-udevd[4832]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:18 volumio systemd-logind[866]: Watching system buttons on /dev/input/event0 (Logitech K400 Plus) May 15 13:51:18 volumio systemd-udevd[4838]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:18 volumio systemd-udevd[4839]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 15 13:51:20 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 13:51:21 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:23 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 13:51:23 volumio volumio[1100]: info: Preload queue cleared May 15 13:51:26 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 13:51:26 volumio volumio[1100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 13:51:26 volumio volumio[1100]: TypeError: Cannot read property 'length' of undefined May 15 13:51:26 volumio volumio[1100]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 15 13:51:26 volumio volumio[1100]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 15 13:51:26 volumio volumio[1100]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 15 13:51:26 volumio volumio[1100]: at Parser.emit (events.js:315:20) May 15 13:51:26 volumio volumio[1100]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 15 13:51:26 volumio volumio[1100]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 15 13:51:26 volumio volumio[1100]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 15 13:51:26 volumio volumio[1100]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 15 13:51:26 volumio volumio[1100]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 15 13:51:26 volumio volumio[1100]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 15 13:51:26 volumio volumio[1100]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 15 13:51:26 volumio volumio[1100]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 15 13:51:26 volumio volumio[1100]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 15 13:51:26 volumio volumio[1100]: at IncomingMessage.emit (events.js:327:22) May 15 13:51:26 volumio volumio[1100]: at endReadableNT (internal/streams/readable.js:1327:12) May 15 13:51:26 volumio volumio[1100]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 15 13:51:26 volumio volumio[1100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 13:51:26 volumio kernel: usb usb3-port1: over-current change #169 May 15 13:51:26 volumio kernel: usb usb1-port1: over-current change #120 May 15 13:51:26 volumio sudo[5036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 13:50 May 15 13:51:26 volumio sudo[5036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 13:51:26 volumio kernel: usb usb4-port1: over-current change #211 May 15 13:51:26 volumio kernel: usb usb2-port1: over-current change #211 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"