May 31 19:11:03 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:11:03 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:06 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:11:06 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:21 volumio2 kernel: usb 1-1.4: new full-speed USB device number 9 using xhci_hcd May 31 19:11:21 volumio2 kernel: usb 1-1.4: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.01 May 31 19:11:21 volumio2 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 31 19:11:21 volumio2 kernel: usb 1-1.4: Product: USB Receiver May 31 19:11:21 volumio2 kernel: usb 1-1.4: Manufacturer: Logitech May 31 19:11:21 volumio2 kernel: input: Logitech USB Receiver as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/0003:046D:C52B.0008/input/input10 May 31 19:11:21 volumio2 kernel: hid-generic 0003:046D:C52B.0008: input,hidraw7: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input0 May 31 19:11:21 volumio2 kernel: input: Logitech USB Receiver Mouse as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C52B.0009/input/input11 May 31 19:11:21 volumio2 kernel: input: Logitech USB Receiver Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C52B.0009/input/input12 May 31 19:11:21 volumio2 kernel: input: Logitech USB Receiver System Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C52B.0009/input/input13 May 31 19:11:21 volumio2 kernel: hid-generic 0003:046D:C52B.0009: input,hiddev102,hidraw8: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input1 May 31 19:11:21 volumio2 kernel: hid-generic 0003:046D:C52B.000A: hiddev103,hidraw9: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input2 May 31 19:11:22 volumio2 kernel: logitech-djreceiver 0003:046D:C52B.000A: hiddev102,hidraw7: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:01:00.0-1.4/input2 May 31 19:11:22 volumio2 kernel: input: Logitech Wireless Device PID:404d Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.000A/0003:046D:404D.000B/input/input15 May 31 19:11:22 volumio2 kernel: input: Logitech Wireless Device PID:404d Mouse as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.000A/0003:046D:404D.000B/input/input16 May 31 19:11:22 volumio2 kernel: hid-generic 0003:046D:404D.000B: input,hidraw8: USB HID v1.11 Keyboard [Logitech Wireless Device PID:404d] on usb-0000:01:00.0-1.4/input2:1 May 31 19:11:23 volumio2 kernel: input: Logitech K400 Plus as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.000A/0003:046D:404D.000B/input/input20 May 31 19:11:23 volumio2 kernel: logitech-hidpp-device 0003:046D:404D.000B: input,hidraw8: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-0000:01:00.0-1.4/input2:1 May 31 19:11:24 volumio2 kernel: logitech-hidpp-device 0003:046D:404D.000B: HID++ 4.1 device connected. May 31 19:11:25 volumio2 (udev-worker)[2085]: mouse5: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 31 19:11:25 volumio2 (udev-worker)[2078]: event5: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 31 19:11:25 volumio2 systemd-logind[629]: Watching system buttons on /dev/input/event5 (Logitech K400 Plus) May 31 19:11:28 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:11:28 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:38 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:11:38 volumio2 volumio[1062]: info: TuneIn handleBrowseUri: tunein/location May 31 19:11:38 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:42 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:11:42 volumio2 volumio[1062]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101216 May 31 19:11:42 volumio2 volumio[1062]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101216 May 31 19:11:42 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:43 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:11:43 volumio2 volumio[1062]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100354 May 31 19:11:43 volumio2 volumio[1062]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100354 May 31 19:11:43 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:43 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:45 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:45 volumio2 volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 19:11:45 volumio2 volumio[1062]: info: CoreStateMachine::ClearQueue May 31 19:11:45 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:45 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:45 volumio2 volumio[1062]: info: CorePlayQueue::clearPlayQueue May 31 19:11:45 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:45 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:45 volumio2 volumio[1062]: info: CoreStateMachine::addQueueItems May 31 19:11:45 volumio2 volumio[1062]: info: CorePlayQueue::addQueueItems May 31 19:11:45 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:45 volumio2 volumio[1062]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25670 May 31 19:11:45 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 19:11:46 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:46 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:46 volumio2 volumio[1062]: info: CoreStateMachine::updateTrackBlock May 31 19:11:46 volumio2 volumio[1062]: info: CorePlayQueue::getTrackBlock May 31 19:11:46 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPlay May 31 19:11:46 volumio2 volumio[1062]: info: CoreStateMachine::play index 0 May 31 19:11:46 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:46 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:46 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:46 volumio2 volumio[1062]: info: CoreStateMachine::play index undefined May 31 19:11:46 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:46 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:46 volumio2 volumio[1062]: info: CoreStateMachine::startPlaybackTimer May 31 19:11:46 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:46 volumio2 volumio[1062]: info: [1748715106264] ControllerWebradio::clearAddPlayTrack May 31 19:11:46 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:11:46 volumio2 volumio[1062]: info: sendMpdCommand stop took 14 milliseconds May 31 19:11:46 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear May 31 19:11:46 volumio2 volumio[1062]: info: May 31 19:11:46 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:46 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:46 volumio2 volumio[1062]: info: sendMpdCommand clear took 8 milliseconds May 31 19:11:46 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand load "https://rthk.hk/live3.m3u" May 31 19:11:46 volumio2 volumio[1062]: info: May 31 19:11:46 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:46 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:46 volumio2 volumio[1062]: info: May 31 19:11:46 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:46 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:46 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:46 volumio2 volumio[1062]: info: ------------------------------ 20ms May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand load "https://rthk.hk/live3.m3u" took 3933 milliseconds May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 3942ms May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 3940ms May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand play May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 36ms May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand play took 21 milliseconds May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 17ms May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 13ms May 31 19:11:50 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 31 19:11:50 volumio2 dbus-daemon[618]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.31' (uid=0 pid=2154 comm="timedatectl show --property=NTPSynchronized --valu") May 31 19:11:50 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::ClearQueue May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:50 volumio2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::clearPlayQueue May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::addQueueItems May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::addQueueItems May 31 19:11:50 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:50 volumio2 volumio[1062]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25670 May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::updateTrackBlock May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::getTrackBlock May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPlay May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::play index 0 May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::play index undefined May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::startPlaybackTimer May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:50 volumio2 volumio[1062]: info: [1748715110487] ControllerWebradio::clearAddPlayTrack May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:11:50 volumio2 dbus-daemon[618]: [system] Successfully activated service 'org.freedesktop.timedate1' May 31 19:11:50 volumio2 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 31 19:11:50 volumio2 setdatetime-helper.sh[2145]: Time is not synchronized. Attempting to sync... May 31 19:11:50 volumio2 mpd[1368]: player: played "https://stm.rthk.hk/radio3" May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand stop took 113 milliseconds May 31 19:11:50 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:50 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:50 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand status took 17 milliseconds May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand clear took 18 milliseconds May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand status took 13 milliseconds May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand status took 11 milliseconds May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand load "https://rthk.hk/live3.m3u" May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:50 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:50 volumio2 sudo[2163]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 31 May 2025 18:11:50 GMT#015' May 31 19:11:50 volumio2 sudo[2163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 19:11:50 volumio2 setdatetime-helper.sh[2164]: Sat May 31 19:11:50 +01 2025 May 31 19:11:50 volumio2 sudo[2163]: pam_unix(sudo:session): session closed for user root May 31 19:11:50 volumio2 systemd-journald[340]: Time jumped backwards, rotating. May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:50 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:11:50 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:50 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:50 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:50 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:11:50 volumio2 setdatetime-helper.sh[2145]: Time synchronized successfully. May 31 19:11:50 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 31 19:11:50 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ -543ms May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ -543ms May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ -543ms May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took -555 milliseconds May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:50 volumio2 volumio[1062]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ -522ms May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand load "https://rthk.hk/live3.m3u" took -130 milliseconds May 31 19:11:50 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 19:11:50 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand play May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 441ms May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 437ms May 31 19:11:50 volumio2 volumio[1062]: info: sendMpdCommand play took 9 milliseconds May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: info: May 31 19:11:50 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:50 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 11ms May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 9ms May 31 19:11:50 volumio2 volumio[1062]: info: ------------------------------ 8ms May 31 19:11:51 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:51 volumio2 volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 19:11:51 volumio2 volumio[1062]: info: CoreStateMachine::ClearQueue May 31 19:11:51 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:51 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:51 volumio2 volumio[1062]: info: CorePlayQueue::clearPlayQueue May 31 19:11:51 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:51 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:51 volumio2 volumio[1062]: info: CoreStateMachine::addQueueItems May 31 19:11:51 volumio2 volumio[1062]: info: CorePlayQueue::addQueueItems May 31 19:11:51 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:51 volumio2 volumio[1062]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s14481 May 31 19:11:51 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 19:11:51 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:11:51 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:11:51 volumio2 mpd[1368]: player: problems opening audio device while playing "https://stm.rthk.hk/radio3" May 31 19:11:51 volumio2 volumio[1062]: info: May 31 19:11:51 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:51 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:51 volumio2 volumio[1062]: info: May 31 19:11:51 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:51 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:51 volumio2 volumio[1062]: info: May 31 19:11:51 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:51 volumio2 volumio[1062]: info: May 31 19:11:51 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:51 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:51 volumio2 volumio[1062]: info: sendMpdCommand status took 33 milliseconds May 31 19:11:51 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:51 volumio2 volumio[1062]: info: sendMpdCommand status took 45 milliseconds May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:51 volumio2 volumio[1062]: info: May 31 19:11:51 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:51 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:51 volumio2 volumio[1062]: info: May 31 19:11:51 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:51 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:51 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:51 volumio2 volumio[1062]: info: sendMpdCommand status took 95 milliseconds May 31 19:11:51 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 74 milliseconds May 31 19:11:51 volumio2 volumio[1062]: info: sendMpdCommand status took 74 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 58 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 29 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 29 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand status took 19 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand status took 15 milliseconds May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:52 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio3","artist":null,"album":null,"uri":"https://stm.rthk.hk/radio3","trackType":"hk/radio3"} May 31 19:11:52 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:52 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio3","artist":null,"album":null,"uri":"https://stm.rthk.hk/radio3","trackType":"hk/radio3"} May 31 19:11:52 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:52 volumio2 volumio[1062]: info: ------------------------------ 184ms May 31 19:11:52 volumio2 volumio[1062]: info: ------------------------------ 180ms May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 114 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 114 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 119 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 118 milliseconds May 31 19:11:52 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 117 milliseconds May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:52 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:52 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio3","artist":null,"album":null,"uri":"https://stm.rthk.hk/radio3","trackType":"hk/radio3"} May 31 19:11:52 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:52 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio3","artist":null,"album":null,"uri":"https://stm.rthk.hk/radio3","trackType":"hk/radio3"} May 31 19:11:52 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:52 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio3","artist":null,"album":null,"uri":"https://stm.rthk.hk/radio3","trackType":"hk/radio3"} May 31 19:11:52 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:52 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio3","artist":null,"album":null,"uri":"https://stm.rthk.hk/radio3","trackType":"hk/radio3"} May 31 19:11:52 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:52 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:52 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:52 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:52 volumio2 volumio[1062]: info: ------------------------------ 276ms May 31 19:11:52 volumio2 volumio[1062]: info: ------------------------------ 276ms May 31 19:11:52 volumio2 volumio[1062]: info: ------------------------------ 202ms May 31 19:11:52 volumio2 volumio[1062]: info: ------------------------------ 196ms May 31 19:11:53 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:53 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::updateTrackBlock May 31 19:11:53 volumio2 volumio[1062]: info: CorePlayQueue::getTrackBlock May 31 19:11:53 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPlay May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::play index 0 May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::play index undefined May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:53 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::startPlaybackTimer May 31 19:11:53 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:53 volumio2 volumio[1062]: info: [1748715113440] ControllerWebradio::clearAddPlayTrack May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:11:53 volumio2 mpd[1368]: player: played "https://stm.rthk.hk/radio3" May 31 19:11:53 volumio2 volumio[1062]: info: May 31 19:11:53 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:53 volumio2 volumio[1062]: info: sendMpdCommand stop took 16 milliseconds May 31 19:11:53 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear May 31 19:11:53 volumio2 volumio[1062]: info: May 31 19:11:53 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:53 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:53 volumio2 volumio[1062]: info: May 31 19:11:53 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:53 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:53 volumio2 volumio[1062]: info: May 31 19:11:53 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:53 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:53 volumio2 volumio[1062]: info: sendMpdCommand status took 22 milliseconds May 31 19:11:53 volumio2 volumio[1062]: info: sendMpdCommand clear took 20 milliseconds May 31 19:11:53 volumio2 volumio[1062]: info: sendMpdCommand status took 18 milliseconds May 31 19:11:53 volumio2 volumio[1062]: info: sendMpdCommand status took 9 milliseconds May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand load "http://stm.rthk.hk/radiopth" May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:53 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:53 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:53 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:53 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:11:53 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:53 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:53 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:11:53 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:53 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:53 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:53 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:53 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:53 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:53 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:11:53 volumio2 volumio[1062]: info: ------------------------------ 95ms May 31 19:11:53 volumio2 volumio[1062]: info: ------------------------------ 95ms May 31 19:11:53 volumio2 volumio[1062]: info: May 31 19:11:53 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:53 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:53 volumio2 volumio[1062]: info: May 31 19:11:53 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:53 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:53 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:53 volumio2 volumio[1062]: info: ------------------------------ 97ms May 31 19:11:53 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 89 milliseconds May 31 19:11:53 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:53 volumio2 volumio[1062]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 19:11:53 volumio2 volumio[1062]: info: ------------------------------ 129ms May 31 19:11:54 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::ClearQueue May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::clearPlayQueue May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::addQueueItems May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::addQueueItems May 31 19:11:54 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:54 volumio2 volumio[1062]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s14481 May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::updateTrackBlock May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrackBlock May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPlay May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::play index 0 May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::play index undefined May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::startPlaybackTimer May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:54 volumio2 volumio[1062]: info: [1748715114531] ControllerWebradio::clearAddPlayTrack May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:11:54 volumio2 mpd[1368]: exception: No such playlist May 31 19:11:54 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:54 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://stm.rthk.hk/radiopth" May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 978ms May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 977ms May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand stop took 16 milliseconds May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand add "http://stm.rthk.hk/radiopth" took 10 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand clear took 9 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand play May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand load "http://stm.rthk.hk/radiopth" May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:54 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 26ms May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 26ms May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand play took 18 milliseconds May 31 19:11:54 volumio2 mpd[1368]: exception: No such playlist May 31 19:11:54 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:54 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://stm.rthk.hk/radiopth" May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 30ms May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 28ms May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand add "http://stm.rthk.hk/radiopth" took 18 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 19ms May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 20ms May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand play May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:54 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 18ms May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand play took 14 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 13ms May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 8ms May 31 19:11:54 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:11:54 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:11:54 volumio2 mpd[1368]: player: problems opening audio device while playing "http://stm.rthk.hk/radiopth" May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand status took 34 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand status took 30 milliseconds May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:54 volumio2 volumio[1062]: info: May 31 19:11:54 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand status took 69 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 57 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand status took 55 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 57 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 45 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 46 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand status took 36 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand status took 32 milliseconds May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:54 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:54 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:54 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:54 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 170ms May 31 19:11:54 volumio2 volumio[1062]: info: ------------------------------ 175ms May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 113 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 100 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 100 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 96 milliseconds May 31 19:11:54 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 95 milliseconds May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:54 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:54 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:54 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:54 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:54 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:54 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:54 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:54 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:54 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:54 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:54 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:54 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:54 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:55 volumio2 volumio[1062]: info: ------------------------------ 348ms May 31 19:11:55 volumio2 volumio[1062]: info: ------------------------------ 356ms May 31 19:11:55 volumio2 volumio[1062]: info: ------------------------------ 319ms May 31 19:11:55 volumio2 volumio[1062]: info: ------------------------------ 308ms May 31 19:11:55 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:55 volumio2 volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::ClearQueue May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:55 volumio2 volumio[1062]: info: CorePlayQueue::clearPlayQueue May 31 19:11:55 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:55 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::addQueueItems May 31 19:11:55 volumio2 volumio[1062]: info: CorePlayQueue::addQueueItems May 31 19:11:55 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:11:55 volumio2 volumio[1062]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s14481 May 31 19:11:55 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 19:11:55 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:11:55 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::updateTrackBlock May 31 19:11:55 volumio2 volumio[1062]: info: CorePlayQueue::getTrackBlock May 31 19:11:55 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPlay May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::play index 0 May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::play index undefined May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:11:55 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:55 volumio2 volumio[1062]: info: CoreStateMachine::startPlaybackTimer May 31 19:11:55 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:55 volumio2 volumio[1062]: info: [1748715115652] ControllerWebradio::clearAddPlayTrack May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:11:55 volumio2 mpd[1368]: player: played "http://stm.rthk.hk/radiopth" May 31 19:11:55 volumio2 volumio[1062]: info: May 31 19:11:55 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:55 volumio2 volumio[1062]: info: sendMpdCommand stop took 18 milliseconds May 31 19:11:55 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear May 31 19:11:55 volumio2 volumio[1062]: info: May 31 19:11:55 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:55 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:55 volumio2 volumio[1062]: info: sendMpdCommand status took 10 milliseconds May 31 19:11:55 volumio2 volumio[1062]: info: sendMpdCommand clear took 10 milliseconds May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand load "http://stm.rthk.hk/radiopth" May 31 19:11:55 volumio2 volumio[1062]: info: May 31 19:11:55 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:55 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:55 volumio2 volumio[1062]: info: May 31 19:11:55 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:55 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:55 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:11:55 volumio2 volumio[1062]: info: ------------------------------ 17ms May 31 19:11:55 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 12 milliseconds May 31 19:11:55 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:55 volumio2 volumio[1062]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 19:11:55 volumio2 volumio[1062]: info: ------------------------------ 28ms May 31 19:11:57 volumio2 mpd[1368]: exception: No such playlist May 31 19:11:57 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://stm.rthk.hk/radiopth" May 31 19:11:57 volumio2 volumio[1062]: info: sendMpdCommand status took 1357 milliseconds May 31 19:11:57 volumio2 volumio[1062]: info: sendMpdCommand status took 1356 milliseconds May 31 19:11:57 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:57 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:57 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:57 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:57 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:57 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:57 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:11:57 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:57 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:57 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:11:57 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:57 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:57 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:57 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:57 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:57 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:57 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:11:57 volumio2 volumio[1062]: info: ------------------------------ 1447ms May 31 19:11:57 volumio2 volumio[1062]: info: ------------------------------ 1446ms May 31 19:11:57 volumio2 volumio[1062]: info: May 31 19:11:57 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:57 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:57 volumio2 volumio[1062]: info: May 31 19:11:57 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:57 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:57 volumio2 volumio[1062]: info: May 31 19:11:57 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:57 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:57 volumio2 volumio[1062]: info: sendMpdCommand add "http://stm.rthk.hk/radiopth" took 105 milliseconds May 31 19:11:57 volumio2 volumio[1062]: info: ------------------------------ 13ms May 31 19:11:57 volumio2 volumio[1062]: info: ------------------------------ 13ms May 31 19:11:57 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 19:11:57 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand play May 31 19:11:57 volumio2 volumio[1062]: info: May 31 19:11:57 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:57 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:57 volumio2 volumio[1062]: info: May 31 19:11:57 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:11:57 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:11:57 volumio2 volumio[1062]: info: ------------------------------ 53ms May 31 19:11:57 volumio2 volumio[1062]: info: sendMpdCommand play took 44 milliseconds May 31 19:11:57 volumio2 volumio[1062]: info: ------------------------------ 44ms May 31 19:11:57 volumio2 volumio[1062]: info: ------------------------------ 39ms May 31 19:11:58 volumio2 volumio[1062]: info: May 31 19:11:58 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:58 volumio2 volumio[1062]: info: May 31 19:11:58 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:58 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:11:58 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:11:58 volumio2 mpd[1368]: player: problems opening audio device while playing "http://stm.rthk.hk/radiopth" May 31 19:11:58 volumio2 volumio[1062]: info: May 31 19:11:58 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:58 volumio2 volumio[1062]: info: May 31 19:11:58 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand status took 40 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:58 volumio2 volumio[1062]: info: May 31 19:11:58 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:58 volumio2 volumio[1062]: info: May 31 19:11:58 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand status took 102 milliseconds May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand status took 102 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 65 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand status took 64 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 63 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand status took 62 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand status took 38 milliseconds May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:58 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:58 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:58 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:58 volumio2 volumio[1062]: info: ------------------------------ 207ms May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 128 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 127 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 99 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 99 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 98 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 90 milliseconds May 31 19:11:58 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 89 milliseconds May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:58 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:58 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:58 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:58 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:58 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:58 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:58 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:58 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:58 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:58 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:58 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:58 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:58 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:58 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:11:58 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:11:58 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiopth","artist":null,"album":null,"uri":"http://stm.rthk.hk/radiopth","trackType":""} May 31 19:11:58 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:11:58 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:11:58 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:11:58 volumio2 volumio[1062]: info: ------------------------------ 493ms May 31 19:11:58 volumio2 volumio[1062]: info: ------------------------------ 493ms May 31 19:11:58 volumio2 volumio[1062]: info: ------------------------------ 502ms May 31 19:11:58 volumio2 volumio[1062]: info: ------------------------------ 462ms May 31 19:11:58 volumio2 volumio[1062]: info: ------------------------------ 460ms May 31 19:12:05 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:12:05 volumio2 volumio[1062]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100355 May 31 19:12:05 volumio2 volumio[1062]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100355 May 31 19:12:05 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:12:05 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:12:06 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:12:06 volumio2 volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 19:12:06 volumio2 volumio[1062]: info: CoreStateMachine::ClearQueue May 31 19:12:06 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:12:06 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:12:06 volumio2 volumio[1062]: info: CorePlayQueue::clearPlayQueue May 31 19:12:06 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:12:06 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:12:06 volumio2 volumio[1062]: info: CoreStateMachine::addQueueItems May 31 19:12:06 volumio2 volumio[1062]: info: CorePlayQueue::addQueueItems May 31 19:12:06 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:12:06 volumio2 volumio[1062]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s68502 May 31 19:12:06 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 19:12:07 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:12:07 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::updateTrackBlock May 31 19:12:07 volumio2 volumio[1062]: info: CorePlayQueue::getTrackBlock May 31 19:12:07 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPlay May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::play index 0 May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::play index undefined May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:12:07 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::startPlaybackTimer May 31 19:12:07 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:07 volumio2 volumio[1062]: info: [1748715127196] ControllerWebradio::clearAddPlayTrack May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:12:07 volumio2 mpd[1368]: player: played "http://stm.rthk.hk/radiopth" May 31 19:12:07 volumio2 volumio[1062]: info: May 31 19:12:07 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:07 volumio2 volumio[1062]: info: sendMpdCommand stop took 17 milliseconds May 31 19:12:07 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear May 31 19:12:07 volumio2 volumio[1062]: info: May 31 19:12:07 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:07 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:07 volumio2 volumio[1062]: info: May 31 19:12:07 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:07 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:07 volumio2 volumio[1062]: info: May 31 19:12:07 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:07 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:07 volumio2 volumio[1062]: info: sendMpdCommand status took 18 milliseconds May 31 19:12:07 volumio2 volumio[1062]: info: sendMpdCommand clear took 18 milliseconds May 31 19:12:07 volumio2 volumio[1062]: info: sendMpdCommand status took 10 milliseconds May 31 19:12:07 volumio2 volumio[1062]: info: sendMpdCommand status took 5 milliseconds May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand load "http://stream.zeno.fm/9qt2b4ndceruv" May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:07 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:07 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:07 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:07 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:12:07 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:07 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:07 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:12:07 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:07 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:07 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:07 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:07 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:07 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:07 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:12:07 volumio2 volumio[1062]: info: ------------------------------ 116ms May 31 19:12:07 volumio2 volumio[1062]: info: ------------------------------ 115ms May 31 19:12:07 volumio2 volumio[1062]: info: May 31 19:12:07 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:07 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:07 volumio2 volumio[1062]: info: May 31 19:12:07 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:07 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:07 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:12:07 volumio2 volumio[1062]: info: ------------------------------ 109ms May 31 19:12:07 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 108 milliseconds May 31 19:12:07 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:07 volumio2 volumio[1062]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 19:12:07 volumio2 volumio[1062]: info: ------------------------------ 145ms May 31 19:12:08 volumio2 mpd[1368]: exception: No such playlist May 31 19:12:08 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://stream.zeno.fm/9qt2b4ndceruv" May 31 19:12:08 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:12:08 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:12:08 volumio2 volumio[1062]: info: ------------------------------ 1516ms May 31 19:12:08 volumio2 volumio[1062]: info: ------------------------------ 1515ms May 31 19:12:08 volumio2 volumio[1062]: info: May 31 19:12:08 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:08 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:08 volumio2 volumio[1062]: info: sendMpdCommand add "http://stream.zeno.fm/9qt2b4ndceruv" took 3 milliseconds May 31 19:12:08 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 19:12:08 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand play May 31 19:12:08 volumio2 volumio[1062]: info: May 31 19:12:08 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:08 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:08 volumio2 volumio[1062]: info: May 31 19:12:08 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:08 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:08 volumio2 volumio[1062]: info: ------------------------------ 13ms May 31 19:12:08 volumio2 volumio[1062]: info: sendMpdCommand play took 13 milliseconds May 31 19:12:08 volumio2 volumio[1062]: info: ------------------------------ 2ms May 31 19:12:08 volumio2 volumio[1062]: info: ------------------------------ 1ms May 31 19:12:09 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:12:09 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:12:09 volumio2 mpd[1368]: player: problems opening audio device while playing "http://stream.zeno.fm/9qt2b4ndceruv" May 31 19:12:09 volumio2 volumio[1062]: info: May 31 19:12:09 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:09 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:09 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:09 volumio2 volumio[1062]: info: May 31 19:12:09 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:09 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:09 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:09 volumio2 volumio[1062]: info: May 31 19:12:09 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:09 volumio2 volumio[1062]: info: May 31 19:12:09 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:09 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:09 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:09 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:12:09 volumio2 volumio[1062]: info: sendMpdCommand status took 61 milliseconds May 31 19:12:09 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:09 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:09 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:12:09 volumio2 volumio[1062]: info: sendMpdCommand status took 58 milliseconds May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:10 volumio2 volumio[1062]: info: May 31 19:12:10 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:10 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:10 volumio2 volumio[1062]: info: May 31 19:12:10 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:10 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand status took 122 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 72 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand status took 73 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 72 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 43 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 39 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand status took 34 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand status took 26 milliseconds May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:10 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:10 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:10 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9qt2b4ndceruv","artist":null,"album":null,"uri":"http://stream.zeno.fm/9qt2b4ndceruv","trackType":""} May 31 19:12:10 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:10 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:10 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:10 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9qt2b4ndceruv","artist":null,"album":null,"uri":"http://stream.zeno.fm/9qt2b4ndceruv","trackType":""} May 31 19:12:10 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:10 volumio2 volumio[1062]: info: ------------------------------ 233ms May 31 19:12:10 volumio2 volumio[1062]: info: ------------------------------ 229ms May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 127 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 123 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 123 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 119 milliseconds May 31 19:12:10 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 118 milliseconds May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:10 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:10 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:10 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:10 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9qt2b4ndceruv","artist":null,"album":null,"uri":"http://stream.zeno.fm/9qt2b4ndceruv","trackType":""} May 31 19:12:10 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:10 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:10 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:10 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9qt2b4ndceruv","artist":null,"album":null,"uri":"http://stream.zeno.fm/9qt2b4ndceruv","trackType":""} May 31 19:12:10 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:10 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:10 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:10 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9qt2b4ndceruv","artist":null,"album":null,"uri":"http://stream.zeno.fm/9qt2b4ndceruv","trackType":""} May 31 19:12:10 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:10 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:10 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:10 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9qt2b4ndceruv","artist":null,"album":null,"uri":"http://stream.zeno.fm/9qt2b4ndceruv","trackType":""} May 31 19:12:10 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:10 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:10 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:10 volumio2 volumio[1062]: info: ------------------------------ 391ms May 31 19:12:10 volumio2 volumio[1062]: info: ------------------------------ 409ms May 31 19:12:10 volumio2 volumio[1062]: info: ------------------------------ 322ms May 31 19:12:10 volumio2 volumio[1062]: info: ------------------------------ 319ms May 31 19:12:11 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:12:11 volumio2 volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 19:12:11 volumio2 volumio[1062]: info: CoreStateMachine::ClearQueue May 31 19:12:11 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:12:11 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:12:11 volumio2 volumio[1062]: info: CorePlayQueue::clearPlayQueue May 31 19:12:11 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:12:11 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:12:11 volumio2 volumio[1062]: info: CoreStateMachine::addQueueItems May 31 19:12:11 volumio2 volumio[1062]: info: CorePlayQueue::addQueueItems May 31 19:12:11 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:12:11 volumio2 volumio[1062]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s336777 May 31 19:12:11 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 31 19:12:12 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushQueue May 31 19:12:12 volumio2 volumio[1062]: info: CorePlayQueue::saveQueue May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::updateTrackBlock May 31 19:12:12 volumio2 volumio[1062]: info: CorePlayQueue::getTrackBlock May 31 19:12:12 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPlay May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::play index 0 May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::stop May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::play index undefined May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 19:12:12 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::startPlaybackTimer May 31 19:12:12 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:12 volumio2 volumio[1062]: info: [1748715132125] ControllerWebradio::clearAddPlayTrack May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop May 31 19:12:12 volumio2 mpd[1368]: player: played "http://stream.zeno.fm/9qt2b4ndceruv" May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:12 volumio2 volumio[1062]: info: sendMpdCommand stop took 13 milliseconds May 31 19:12:12 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:12 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:12 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:12 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:12 volumio2 volumio[1062]: info: sendMpdCommand status took 21 milliseconds May 31 19:12:12 volumio2 volumio[1062]: info: sendMpdCommand clear took 24 milliseconds May 31 19:12:12 volumio2 volumio[1062]: info: sendMpdCommand status took 13 milliseconds May 31 19:12:12 volumio2 volumio[1062]: info: sendMpdCommand status took 14 milliseconds May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand load "https://cast3.asurahosting.com/proxy/santhar1/stream" May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:12 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:12 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:12 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:12 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:12:12 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:12 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:12 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:12:12 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:12 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:12 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:12 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:12 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:12 volumio2 volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 144ms May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 153ms May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:12 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:12 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:12 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 160ms May 31 19:12:12 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 152 milliseconds May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:12 volumio2 volumio[1062]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 181ms May 31 19:12:12 volumio2 mpd[1368]: exception: No such playlist May 31 19:12:12 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:12:12 volumio2 volumio[1062]: error: updateQueue error: null May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "https://cast3.asurahosting.com/proxy/santhar1/stream" May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 431ms May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 430ms May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:12 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:12 volumio2 volumio[1062]: info: sendMpdCommand add "https://cast3.asurahosting.com/proxy/santhar1/stream" took 5 milliseconds May 31 19:12:12 volumio2 volumio[1062]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 19:12:12 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand play May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:12 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:12 volumio2 volumio[1062]: info: May 31 19:12:12 volumio2 volumio[1062]: ---------------------------- MPD announces system playlist update May 31 19:12:12 volumio2 volumio[1062]: info: Ignoring MPD Status Update May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 11ms May 31 19:12:12 volumio2 volumio[1062]: info: sendMpdCommand play took 6 milliseconds May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 5ms May 31 19:12:12 volumio2 volumio[1062]: info: ------------------------------ 2ms May 31 19:12:13 volumio2 volumio[1062]: info: May 31 19:12:13 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:13 volumio2 volumio[1062]: info: May 31 19:12:13 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:13 volumio2 volumio[1062]: info: May 31 19:12:13 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:13 volumio2 volumio[1062]: info: May 31 19:12:13 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:13 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:12:13 volumio2 mpd[1368]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied May 31 19:12:13 volumio2 mpd[1368]: player: problems opening audio device while playing "https://cast3.asurahosting.com/proxy/santhar1/stream" May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand status took 7 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:13 volumio2 volumio[1062]: info: May 31 19:12:13 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:13 volumio2 volumio[1062]: info: May 31 19:12:13 volumio2 volumio[1062]: ---------------------------- MPD announces state update: player May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::getState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand status May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand status took 71 milliseconds May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand status took 71 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 66 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand status took 34 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 34 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand status took 30 milliseconds May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:13 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:13 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cast3.asurahosting.com/proxy/santhar1/stream","trackType":""} May 31 19:12:13 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:13 volumio2 volumio[1062]: info: ------------------------------ 152ms May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand status took 151 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 124 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand clearerror took 123 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 101 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 101 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 100 milliseconds May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 91 milliseconds May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseState May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:13 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:13 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cast3.asurahosting.com/proxy/santhar1/stream","trackType":""} May 31 19:12:13 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:13 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:13 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cast3.asurahosting.com/proxy/santhar1/stream","trackType":""} May 31 19:12:13 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:13 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:13 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cast3.asurahosting.com/proxy/santhar1/stream","trackType":""} May 31 19:12:13 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:13 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:13 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cast3.asurahosting.com/proxy/santhar1/stream","trackType":""} May 31 19:12:13 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:13 volumio2 volumio[1062]: info: ------------------------------ 365ms May 31 19:12:13 volumio2 volumio[1062]: info: ------------------------------ 364ms May 31 19:12:13 volumio2 volumio[1062]: info: ------------------------------ 363ms May 31 19:12:13 volumio2 volumio[1062]: info: ------------------------------ 326ms May 31 19:12:13 volumio2 volumio[1062]: info: sendMpdCommand playlistinfo took 203 milliseconds May 31 19:12:13 volumio2 volumio[1062]: verbose: ControllerMpd::parseTrackInfo May 31 19:12:13 volumio2 volumio[1062]: info: ControllerMpd::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::servicePushState May 31 19:12:13 volumio2 volumio[1062]: info: CorePlayQueue::getTrack 0 May 31 19:12:13 volumio2 volumio[1062]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cast3.asurahosting.com/proxy/santhar1/stream","trackType":""} May 31 19:12:13 volumio2 volumio[1062]: verbose: CURRENT POSITION 0 May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState stateService pause May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::syncState currentStatus stop May 31 19:12:13 volumio2 volumio[1062]: info: CoreStateMachine::pushState May 31 19:12:13 volumio2 volumio[1062]: info: CoreCommandRouter::volumioPushState May 31 19:12:13 volumio2 volumio[1062]: info: ------------------------------ 403ms May 31 19:12:19 volumio2 systemd[1]: systemd-timedated.service: Deactivated successfully. May 31 19:12:22 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:12:22 volumio2 volumio[1062]: info: Preload queue cleared May 31 19:12:28 volumio2 volumio[1062]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 19:12:29 volumio2 volumio[1062]: Use XMLElement.setAttribute instead May 31 19:12:29 volumio2 volumio[1062]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 19:12:29 volumio2 volumio[1062]: TypeError: children[i].attr(...).value is not a function May 31 19:12:29 volumio2 volumio[1062]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 May 31 19:12:29 volumio2 volumio[1062]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 31 19:12:29 volumio2 volumio[1062]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 19:12:31 volumio2 sudo[2283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-31 19:11' May 31 19:12:31 volumio2 sudo[2283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"