-- Logs begin at Mon 2025-06-30 05:56:58 CST, end at Mon 2025-06-30 06:05:51 CST. -- Jun 30 06:04:44 volumio kernel: usb 1-1.2: new full-speed USB device number 10 using ehci-pci Jun 30 06:04:45 volumio kernel: usb 1-1.2: New USB device found, idVendor=222a, idProduct=0001, bcdDevice= 0.02 Jun 30 06:04:45 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jun 30 06:04:45 volumio kernel: usb 1-1.2: Product: ILITEK-TP Jun 30 06:04:45 volumio kernel: usb 1-1.2: Manufacturer: ILITEK Jun 30 06:04:45 volumio kernel: input: ILITEK ILITEK-TP Touchscreen as /devices/pci0000:00/0000:00:1d.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:222A:0001.0006/input/input22 Jun 30 06:04:45 volumio kernel: input: ILITEK ILITEK-TP Mouse as /devices/pci0000:00/0000:00:1d.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:222A:0001.0006/input/input24 Jun 30 06:04:45 volumio kernel: hid-generic 0003:222A:0001.0006: input,hiddev3,hidraw5: USB HID v1.10 Mouse [ILITEK ILITEK-TP] on usb-0000:00:1d.0-1.2/input0 Jun 30 06:04:46 volumio kernel: input: ILITEK ILITEK-TP as /devices/pci0000:00/0000:00:1d.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:222A:0001.0006/input/input25 Jun 30 06:04:46 volumio kernel: input: ILITEK ILITEK-TP Mouse as /devices/pci0000:00/0000:00:1d.0/usb1/1-1/1-1.2/1-1.2:1.0/0003:222A:0001.0006/input/input27 Jun 30 06:04:46 volumio kernel: hid-multitouch 0003:222A:0001.0006: input,hiddev3,hidraw5: USB HID v1.10 Mouse [ILITEK ILITEK-TP] on usb-0000:00:1d.0-1.2/input0 Jun 30 06:04:47 volumio systemd-udevd[2948]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 06:04:47 volumio systemd-udevd[2951]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 06:04:47 volumio systemd-udevd[2949]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 06:04:47 volumio systemd-udevd[2947]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 06:04:47 volumio systemd-udevd[2941]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 30 06:04:50 volumio sudo[2736]: pam_unix(sudo:session): session closed for user root Jun 30 06:04:55 volumio volumio[893]: info: Preload queue cleared Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::ClearQueue Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::stop Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::stPlaybackTimer Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::serviceStop Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::serviceStop Jun 30 06:04:55 volumio volumio[893]: info: [1751234695114] ControllerWebradio::stop Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::clearPlayQueue Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::saveQueue Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::addQueueItems Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::addQueueItems Jun 30 06:04:55 volumio volumio[893]: info: Preload queue cleared Jun 30 06:04:55 volumio volumio[893]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151 Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::saveQueue Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPlay Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::play index 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::stop Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::play index undefined Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::startPlaybackTimer Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: [1751234695134] ControllerWebradio::clearAddPlayTrack Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand stop took 48 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand stop took 28 milliseconds Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:55 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand status took 6 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand clear took 6 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand status took 5 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand status took 4 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand status took 2 milliseconds Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151" Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 27ms Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 25ms Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 25ms Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:55 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:55 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:55 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:55 volumio volumio[893]: error: updateQueue error: null Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 27ms Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:04:55 volumio volumio[893]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 35ms Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151" took 535 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand play Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:55 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 515ms Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 516ms Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 514ms Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:55 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:55 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:55 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand play took 5 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 4ms Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 3ms Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 2ms Jun 30 06:04:55 volumio volumio[893]: info: ------------------------------ 1ms Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:55 volumio volumio[893]: info: Jun 30 06:04:55 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand status took 9 milliseconds Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand status took 9 milliseconds Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand status took 9 milliseconds Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand status took 24 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand clearerror took 18 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand clearerror took 18 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand clearerror took 17 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 30 06:04:55 volumio volumio[893]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:04:55 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:04:55 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: No code Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:04:55 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: No code Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:55 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:04:55 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:55 volumio volumio[893]: info: No code Jun 30 06:04:55 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:55 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:56 volumio volumio[893]: info: ------------------------------ 96ms Jun 30 06:04:56 volumio volumio[893]: info: ------------------------------ 96ms Jun 30 06:04:56 volumio volumio[893]: info: ------------------------------ 95ms Jun 30 06:04:56 volumio volumio[893]: info: sendMpdCommand clearerror took 102 milliseconds Jun 30 06:04:56 volumio volumio[893]: info: sendMpdCommand playlistinfo took 100 milliseconds Jun 30 06:04:56 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:04:56 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:56 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:56 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:56 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:56 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:56 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:04:56 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:04:56 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:04:56 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:04:56 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:56 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:56 volumio volumio[893]: info: No code Jun 30 06:04:56 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:56 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:56 volumio volumio[893]: info: ------------------------------ 149ms Jun 30 06:04:59 volumio volumio[893]: info: Preload queue cleared Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::ClearQueue Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::stop Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::clearPlayQueue Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::saveQueue Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::addQueueItems Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::addQueueItems Jun 30 06:04:59 volumio volumio[893]: info: Preload queue cleared Jun 30 06:04:59 volumio volumio[893]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151 Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::saveQueue Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::volumioPlay Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::play index 0 Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::stop Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::play index undefined Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::startPlaybackTimer Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:59 volumio volumio[893]: info: [1751234699506] ControllerWebradio::clearAddPlayTrack Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand stop took 5 milliseconds Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand clear took 0 milliseconds Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151" Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:59 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:59 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:59 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:59 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151" took 238 milliseconds Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand play Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:59 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:59 volumio volumio[893]: info: ------------------------------ 239ms Jun 30 06:04:59 volumio volumio[893]: info: ------------------------------ 239ms Jun 30 06:04:59 volumio volumio[893]: info: ------------------------------ 238ms Jun 30 06:04:59 volumio volumio[893]: info: ------------------------------ 237ms Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:59 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:59 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:04:59 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand play took 7 milliseconds Jun 30 06:04:59 volumio volumio[893]: info: ------------------------------ 8ms Jun 30 06:04:59 volumio volumio[893]: info: ------------------------------ 5ms Jun 30 06:04:59 volumio volumio[893]: info: ------------------------------ 5ms Jun 30 06:04:59 volumio volumio[893]: info: ------------------------------ 3ms Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:59 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:59 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:59 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:59 volumio volumio[893]: info: Jun 30 06:04:59 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:04:59 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand status took 10 milliseconds Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand status took 9 milliseconds Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand status took 9 milliseconds Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand status took 23 milliseconds Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand clearerror took 17 milliseconds Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand clearerror took 16 milliseconds Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand clearerror took 15 milliseconds Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 30 06:04:59 volumio volumio[893]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:04:59 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:04:59 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:59 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:04:59 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:59 volumio volumio[893]: info: No code Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:59 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:04:59 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:04:59 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:04:59 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:04:59 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:04:59 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:00 volumio volumio[893]: info: No code Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:00 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:00 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:00 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:05:00 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:00 volumio volumio[893]: info: No code Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:00 volumio volumio[893]: info: ------------------------------ 104ms Jun 30 06:05:00 volumio volumio[893]: info: ------------------------------ 104ms Jun 30 06:05:00 volumio volumio[893]: info: ------------------------------ 103ms Jun 30 06:05:00 volumio volumio[893]: info: sendMpdCommand clearerror took 108 milliseconds Jun 30 06:05:00 volumio volumio[893]: info: sendMpdCommand playlistinfo took 106 milliseconds Jun 30 06:05:00 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:00 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:00 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:00 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:05:00 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:00 volumio volumio[893]: info: No code Jun 30 06:05:00 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:00 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:00 volumio volumio[893]: info: ------------------------------ 157ms Jun 30 06:05:01 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:01 volumio sudo[3018]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jun 30 06:05:01 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:02 volumio volumio[893]: info: Preload queue cleared Jun 30 06:05:02 volumio volumio[893]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::ClearQueue Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::stop Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:02 volumio volumio[893]: info: CorePlayQueue::clearPlayQueue Jun 30 06:05:02 volumio volumio[893]: info: CorePlayQueue::saveQueue Jun 30 06:05:02 volumio volumio[893]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::addQueueItems Jun 30 06:05:02 volumio volumio[893]: info: CorePlayQueue::addQueueItems Jun 30 06:05:02 volumio volumio[893]: info: Preload queue cleared Jun 30 06:05:02 volumio volumio[893]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151 Jun 30 06:05:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 30 06:05:02 volumio volumio[893]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:05:02 volumio volumio[893]: info: CorePlayQueue::saveQueue Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Jun 30 06:05:02 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Jun 30 06:05:02 volumio volumio[893]: info: CoreCommandRouter::volumioPlay Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::play index 0 Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::stop Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::play index undefined Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:02 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:02 volumio volumio[893]: info: CoreStateMachine::startPlaybackTimer Jun 30 06:05:02 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:02 volumio volumio[893]: info: [1751234702853] ControllerWebradio::clearAddPlayTrack Jun 30 06:05:02 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 06:05:02 volumio volumio[893]: info: sendMpdCommand stop took 2 milliseconds Jun 30 06:05:02 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 06:05:02 volumio volumio[893]: info: sendMpdCommand clear took 0 milliseconds Jun 30 06:05:02 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151" Jun 30 06:05:02 volumio volumio[893]: info: Jun 30 06:05:02 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:05:02 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:05:02 volumio volumio[893]: info: Jun 30 06:05:02 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:05:02 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:05:02 volumio volumio[893]: info: Jun 30 06:05:02 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:05:02 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:05:02 volumio volumio[893]: info: Jun 30 06:05:02 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:05:02 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542151" took 237 milliseconds Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand play Jun 30 06:05:03 volumio volumio[893]: info: Jun 30 06:05:03 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:05:03 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 238ms Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 238ms Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 237ms Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 236ms Jun 30 06:05:03 volumio volumio[893]: info: Jun 30 06:05:03 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:05:03 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:05:03 volumio volumio[893]: info: Jun 30 06:05:03 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:05:03 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:05:03 volumio volumio[893]: info: Jun 30 06:05:03 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jun 30 06:05:03 volumio volumio[893]: info: Ignoring MPD Status Update Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand play took 4 milliseconds Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 5ms Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 3ms Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 2ms Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 4ms Jun 30 06:05:03 volumio volumio[893]: info: Jun 30 06:05:03 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:05:03 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:03 volumio volumio[893]: info: Jun 30 06:05:03 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:05:03 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand status took 5 milliseconds Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:03 volumio volumio[893]: info: Jun 30 06:05:03 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:05:03 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:03 volumio volumio[893]: info: Jun 30 06:05:03 volumio volumio[893]: ---------------------------- MPD announces state update: player Jun 30 06:05:03 volumio volumio[893]: info: ControllerMpd::getState Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand status took 11 milliseconds Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand clearerror took 7 milliseconds Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand status took 6 milliseconds Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:03 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:03 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:05:03 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: No code Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 33ms Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand status took 38 milliseconds Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand clearerror took 34 milliseconds Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::parseState Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:03 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:03 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:05:03 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: No code Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:03 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:05:03 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: No code Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 92ms Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 87ms Jun 30 06:05:03 volumio volumio[893]: info: sendMpdCommand playlistinfo took 48 milliseconds Jun 30 06:05:03 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:03 volumio volumio[893]: info: ControllerMpd::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:03 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 0/999) Golden Grooves Radio Network","album":null,"uri":"http://141.94.242.114:5080/stream","trackType":""} Jun 30 06:05:03 volumio volumio[893]: verbose: CURRENT POSITION 0 Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: No code Jun 30 06:05:03 volumio volumio[893]: info: CoreStateMachine::pushState Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:03 volumio volumio[893]: info: ------------------------------ 112ms Jun 30 06:05:03 volumio volumio[893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 6 Jun 30 06:05:03 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:05 volumio volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 06:05:05 volumio volumio[893]: Error: connect ETIMEDOUT 54.234.18.200:80 Jun 30 06:05:05 volumio volumio[893]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 30 06:05:05 volumio volumio[893]: errno: -110, Jun 30 06:05:05 volumio volumio[893]: code: 'ETIMEDOUT', Jun 30 06:05:05 volumio volumio[893]: syscall: 'connect', Jun 30 06:05:05 volumio volumio[893]: address: '54.234.18.200', Jun 30 06:05:05 volumio volumio[893]: port: 80 Jun 30 06:05:05 volumio volumio[893]: } Jun 30 06:05:05 volumio volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 06:05:05 volumio sudo[3055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 06:04 Jun 30 06:05:05 volumio sudo[3055]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:05 volumio sudo[3055]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:07 volumio volumio-remote-updater[516]: [2025-06-30 06:05:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 30 06:05:07 volumio volumio-remote-updater[516]: [2025-06-30 06:05:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 30 06:05:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 30 06:05:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 30 06:05:07 volumio systemd[1]: Started dynamicswap service. Jun 30 06:05:07 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 30 06:05:07 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 30 06:05:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 30 06:05:07 volumio systemd[1]: Stopped Volumio Backend Module. Jun 30 06:05:07 volumio systemd[1]: Started Volumio Backend Module. Jun 30 06:05:07 volumio systemd[1]: Started dynamicswap service. Jun 30 06:05:07 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 30 06:05:08 volumio volumio[3113]: info: ------------------------------------------- Jun 30 06:05:08 volumio volumio[3113]: info: ----- Volumio3 ---- Jun 30 06:05:08 volumio volumio[3113]: info: ------------------------------------------- Jun 30 06:05:08 volumio volumio[3113]: info: ----- System startup ---- Jun 30 06:05:08 volumio volumio[3113]: info: ------------------------------------------- Jun 30 06:05:08 volumio volumio[3113]: info: MYVOLUMIO Environment detected Jun 30 06:05:08 volumio volumio[3113]: info: Plugin folders cleanup Jun 30 06:05:08 volumio volumio[3113]: info: Scanning into folder /volumio/app/plugins/ Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category audio_interface Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category miscellanea Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category music_service Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category plugins.json Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category system_controller Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category user_interface Jun 30 06:05:08 volumio volumio[3113]: info: Scanning into folder /data/plugins/ Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category music_service Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category system_controller Jun 30 06:05:08 volumio volumio[3113]: info: Scanning category user_interface Jun 30 06:05:08 volumio volumio[3113]: info: Plugin folders cleanup completed Jun 30 06:05:08 volumio volumio[3113]: info: ------------------------------------------- Jun 30 06:05:08 volumio volumio[3113]: info: ----- Core plugins startup ---- Jun 30 06:05:08 volumio volumio[3113]: info: ------------------------------------------- Jun 30 06:05:08 volumio volumio[3113]: info: Loading plugins from folder /volumio/app/plugins/ Jun 30 06:05:08 volumio volumio[3113]: info: Adding plugin upnp to MyMusic Plugins Jun 30 06:05:08 volumio volumio[3113]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 30 06:05:08 volumio volumio[3113]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 30 06:05:08 volumio volumio[3113]: info: Loading plugins from folder /data/plugins/ Jun 30 06:05:08 volumio volumio[3113]: info: Loading plugin "system"... Jun 30 06:05:08 volumio volumio[3113]: info: Loading plugin "appearance"... Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "network"... Jun 30 06:05:09 volumio volumio[3113]: info: Refreshing Cached IP Addresses Jun 30 06:05:09 volumio sudo[3140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 06:05:09 volumio sudo[3140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:09 volumio sudo[3140]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:09 volumio sudo[3142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 06:05:09 volumio sudo[3142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "services"... Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "alsa_controller"... Jun 30 06:05:09 volumio sudo[3142]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:09 volumio sudo[3145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 30 06:05:09 volumio sudo[3145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:09 volumio sudo[3145]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:09 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "wizard"... Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "networkfs"... Jun 30 06:05:09 volumio volumio[3113]: info: Starting Udev Watcher for removable devices Jun 30 06:05:09 volumio volumio[3113]: info: Ignoring mount for partition: boot Jun 30 06:05:09 volumio volumio[3113]: info: Ignoring mount for partition: volumio Jun 30 06:05:09 volumio volumio[3113]: info: Ignoring mount for partition: volumio_data Jun 30 06:05:09 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "volumio_command_line_client"... Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "upnp"... Jun 30 06:05:09 volumio volumio[3113]: info: [1751234709646] Starting Upmpd Daemon Jun 30 06:05:09 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "my_music"... Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "mpd"... Jun 30 06:05:09 volumio volumio[3113]: info: Loading plugin "upnp_browser"... Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "alarm-clock"... Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "airplay_emulation"... Jun 30 06:05:10 volumio volumio[3113]: info: Starting Shairport Sync Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "last_100"... Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "webradio"... Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "i2s_dacs"... Jun 30 06:05:10 volumio volumio[3113]: info: I2S DAC not set, start Auto-detection Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "volumiodiscovery"... Jun 30 06:05:10 volumio volumio[3113]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 30 06:05:10 volumio volumio[3113]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 06:05:10 volumio volumio[3113]: *** WARNING *** For more information see Jun 30 06:05:10 volumio volumio[3113]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 30 06:05:10 volumio volumio[3113]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 06:05:10 volumio volumio[3113]: *** WARNING *** For more information see Jun 30 06:05:10 volumio node[3113]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 30 06:05:10 volumio node[3113]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 06:05:10 volumio node[3113]: *** WARNING *** For more information see Jun 30 06:05:10 volumio node[3113]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 30 06:05:10 volumio node[3113]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 30 06:05:10 volumio node[3113]: *** WARNING *** For more information see Jun 30 06:05:10 volumio volumio[3113]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 30 06:05:10 volumio volumio[3113]: info: Discovery: Started advertising with name: Volumio Jun 30 06:05:10 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "autostart"... Jun 30 06:05:10 volumio volumio[3113]: info: Applying required configuration parameters for plugin autostart Jun 30 06:05:10 volumio volumio[3113]: info: AutoStart - onVolumioStart - read config.json Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "peppy_screensaver"... Jun 30 06:05:10 volumio volumio[3113]: info: Applying required configuration parameters for plugin peppy_screensaver Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "outputs"... Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "albumart"... Jun 30 06:05:10 volumio volumio[3113]: info: Plugin example_plugin is not enabled Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "inputs"... Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "updater_comm"... Jun 30 06:05:10 volumio volumio[3113]: info: Plugin mpdemulation is not enabled Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "rest_api"... Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "websocket"... Jun 30 06:05:10 volumio volumio[3113]: Forking 3 albumart workers Jun 30 06:05:10 volumio volumio[3113]: info: Loading plugin "RoonBridge"... Jun 30 06:05:11 volumio volumio[3113]: info: Applying required configuration parameters for plugin RoonBridge Jun 30 06:05:11 volumio volumio[3113]: info: Loading i18n strings for locale zh Jun 30 06:05:11 volumio volumio[3113]: Updating browse sources language Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::initPlayerControls Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: Express server listening on port 3000 Jun 30 06:05:11 volumio volumio[3113]: [Metrics] WebUI: 3s 111.41ms Jun 30 06:05:11 volumio volumio[3113]: info: Setting Device type: x86 Jun 30 06:05:11 volumio volumio[3113]: info: CoreStateMachine::resetVolumioState Jun 30 06:05:11 volumio volumio[3113]: info: CoreStateMachine::getcurrentVolume Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::volumioRetrievevolume Jun 30 06:05:11 volumio volumio[3113]: Starting albumart workers Jun 30 06:05:11 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:11 volumio volumio[3113]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 30 06:05:11 volumio volumio[3113]: info: Completed loading Core Plugins Jun 30 06:05:11 volumio volumio[3113]: info: Preparing to generate the ALSA configuration file Jun 30 06:05:11 volumio volumio[3113]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 30 06:05:11 volumio volumio[3113]: wlan0 Interface doesn't support scanning : Operation not supported Jun 30 06:05:11 volumio volumio[3113]: info: Cannot use regular scanning, forcing with ap-force Jun 30 06:05:11 volumio volumio[3113]: Starting albumart workers Jun 30 06:05:11 volumio sudo[3184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 30 06:05:11 volumio sudo[3184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:11 volumio volumio[3113]: Starting albumart workers Jun 30 06:05:12 volumio volumio-remote-updater[516]: [2025-06-30 06:05:12] [connect] Successful connection Jun 30 06:05:12 volumio sudo[3184]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:12 volumio volumio[3113]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 30 06:05:12 volumio volumio[3113]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 30 06:05:12 volumio volumio[3113]: info: Reading ALSA contributions from plugins. Jun 30 06:05:12 volumio volumio[3113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 1 Jun 30 06:05:12 volumio volumio[3113]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 2 Jun 30 06:05:12 volumio volumio[3113]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 3 Jun 30 06:05:12 volumio volumio[3113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jun 30 06:05:12 volumio volumio-remote-updater[516]: [2025-06-30 06:05:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751234712 101 Jun 30 06:05:12 volumio volumio[3113]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Jun 30 06:05:12 volumio volumio[3113]: info: Reloading queue from file Jun 30 06:05:12 volumio volumio[3113]: info: VolumeController:: Volume=36 Mute =false Jun 30 06:05:12 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:12 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:12 volumio volumio[3113]: info: CoreStateMachine::updateTrackBlock Jun 30 06:05:12 volumio volumio[3113]: info: CorePlayQueue::getTrackBlock Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::volumioRetrievevolume Jun 30 06:05:12 volumio volumio[3113]: info: CoreStateMachine::setRepeat true single undefined Jun 30 06:05:12 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:12 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:12 volumio volumio[3113]: info: CoreStateMachine::setRandom null Jun 30 06:05:12 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:12 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:12 volumio volumio[3113]: info: VolumeController:: Volume=36 Mute =false Jun 30 06:05:12 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:12 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:12 volumio volumio[3113]: info: Asound.conf file unchanged, so no further update is needed Jun 30 06:05:12 volumio volumio[3113]: info: Output device has changed, restarting MPD Jun 30 06:05:12 volumio sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 30 06:05:12 volumio sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:12 volumio sudo[3222]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:12 volumio volumio[3113]: info: Output device has changed, restarting Shairport Sync Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:12 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:12 volumio sudo[3018]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:12 volumio sudo[3225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 30 06:05:12 volumio sudo[3225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio systemd[1]: Stopping Music Player Daemon... Jun 30 06:05:13 volumio volumio[3113]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 06:05:13 volumio volumio[3113]: info: ___________ START PLUGINS ___________ Jun 30 06:05:13 volumio volumio[3113]: info: ControllerMpd::onStart: Initializing MPD Jun 30 06:05:13 volumio volumio[3113]: info: Creating MPD Configuration file Jun 30 06:05:13 volumio sudo[3232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 30 06:05:13 volumio sudo[3232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 06:05:13 volumio volumio[3113]: info: [1751234713059] CoreMusicLibrary::Adding element 媒体服务器 Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 06:05:13 volumio sudo[3232]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:13 volumio sudo[3235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 30 06:05:13 volumio sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 06:05:13 volumio volumio[3113]: info: [1751234713143] CoreMusicLibrary::Adding element Last_100 Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 30 06:05:13 volumio volumio[3113]: info: [1751234713145] CoreMusicLibrary::Adding element Webradio Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 06:05:13 volumio volumio[3113]: info: AutoStart - onStart Jun 30 06:05:13 volumio volumio[3113]: info: Loading i18n strings for locale zh Jun 30 06:05:13 volumio volumio[3113]: info: /tmp/myfifo created Jun 30 06:05:13 volumio volumio[3113]: info: /tmp/myfifosa created Jun 30 06:05:13 volumio sudo[3248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jun 30 06:05:13 volumio sudo[3248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio sudo[3248]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:13 volumio volumio[3113]: info: snd-dummy loaded Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:13 volumio systemd[1]: mpd.service: Succeeded. Jun 30 06:05:13 volumio systemd[1]: Stopped Music Player Daemon. Jun 30 06:05:13 volumio volumio[3113]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 30 06:05:13 volumio systemd[1]: Starting Music Player Daemon... Jun 30 06:05:13 volumio volumio[3113]: info: Volumio Calling Home Jun 30 06:05:13 volumio sudo[3257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jun 30 06:05:13 volumio sudo[3257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio sudo[3257]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:13 volumio volumio[3113]: info: Discovery: adding a99547a7-f380-4a98-b83c-927c021eec55 Jun 30 06:05:13 volumio volumio[3113]: info: Discovery: Found device Volumio Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:13 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:13 volumio volumio[3113]: info: Discovery: this is already registered, a99547a7-f380-4a98-b83c-927c021eec55 Jun 30 06:05:13 volumio volumio[3113]: info: Discovery: Found device Volumio Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:13 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:13 volumio volumio[3113]: info: MPD Permissions set Jun 30 06:05:13 volumio volumio[3113]: info: MPD Permissions set Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:13 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:13 volumio volumio[3113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:13 volumio volumio[3113]: info: Starting Shairport Sync Jun 30 06:05:13 volumio volumio[3113]: info: Starting Shairport Sync Jun 30 06:05:13 volumio sudo[3278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 06:05:13 volumio volumio[3113]: info: Starting Shairport Sync Jun 30 06:05:13 volumio sudo[3278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio volumio[3113]: info: Starting Shairport Sync Jun 30 06:05:13 volumio sudo[3280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 06:05:13 volumio sudo[3280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 30 06:05:13 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 30 06:05:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 30 06:05:13 volumio sudo[3283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 06:05:13 volumio sudo[3283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 30 06:05:13 volumio sudo[3287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 30 06:05:13 volumio sudo[3287]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:13 volumio sudo[3278]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 30 06:05:13 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 30 06:05:13 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 30 06:05:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 30 06:05:13 volumio volumio[3113]: info: Shairport-Sync Started Jun 30 06:05:13 volumio volumio[3113]: Error adding Membership: Error: addMembership EINVAL Jun 30 06:05:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 30 06:05:13 volumio sudo[3283]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:13 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:13 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:13 volumio sudo[3280]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:13 volumio sudo[3287]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:13 volumio volumio[3113]: info: Shairport-Sync Started Jun 30 06:05:13 volumio volumio[3113]: info: Shairport-Sync Started Jun 30 06:05:13 volumio volumio[3113]: info: Shairport-Sync Started Jun 30 06:05:14 volumio volumio[3113]: info: Volumio called home Jun 30 06:05:14 volumio mpd[3255]: Jun 30 06:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 30 06:05:14 volumio systemd[1]: Started Music Player Daemon. Jun 30 06:05:14 volumio sudo[3235]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:14 volumio sudo[3225]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:14 volumio volumio[3113]: info: Completed starting Core Plugins Jun 30 06:05:14 volumio volumio[3113]: info: ------------------------------------------- Jun 30 06:05:14 volumio volumio[3113]: info: ----- MyVolumio plugins startup ---- Jun 30 06:05:14 volumio volumio[3113]: info: ------------------------------------------- Jun 30 06:05:14 volumio volumio[3113]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 30 06:05:14 volumio volumio[3113]: error: MPD error: The expression evaluated to a falsy value: Jun 30 06:05:14 volumio volumio[3113]: assert.ok(self.idling) Jun 30 06:05:14 volumio volumio[3113]: error: The expression evaluated to a falsy value: Jun 30 06:05:14 volumio volumio[3113]: assert.ok(self.idling) Jun 30 06:05:14 volumio volumio[3113]: error: updateQueue error: null Jun 30 06:05:14 volumio volumio[3113]: info: MPD running with PID3255 Jun 30 06:05:14 volumio volumio[3113]: ,establishing connection Jun 30 06:05:14 volumio volumio[3113]: error: updateQueue error: null Jun 30 06:05:14 volumio volumio[3113]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 6 Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::volumioGetVisibleSources Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:15 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:15 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 06:05:15 volumio volumio[3113]: info: Received Get System Info Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 06:05:15 volumio volumio[3113]: info: Discovery: Getting this device information Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:15 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:15 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:15 volumio volumio[3113]: info: Listing playlists Jun 30 06:05:15 volumio volumio[3113]: info: CoreCommandRouter::volumioGetQueue Jun 30 06:05:15 volumio volumio[3113]: info: CoreStateMachine::getQueue Jun 30 06:05:15 volumio volumio[3113]: info: CorePlayQueue::getQueue Jun 30 06:05:19 volumio volumio[3113]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 30 06:05:19 volumio sudo[3298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 06:05:19 volumio sudo[3298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:19 volumio sudo[3298]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:19 volumio sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 06:05:19 volumio sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:19 volumio sudo[3301]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:19 volumio sudo[3304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 30 06:05:19 volumio sudo[3304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:19 volumio sudo[3304]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:19 volumio volumio[3113]: info: Upmpdcli Daemon Started Jun 30 06:05:21 volumio volumio[3113]: info: Preload queue cleared Jun 30 06:05:21 volumio volumio[3113]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::ClearQueue Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::stop Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:21 volumio volumio[3113]: info: CorePlayQueue::clearPlayQueue Jun 30 06:05:21 volumio volumio[3113]: info: CorePlayQueue::saveQueue Jun 30 06:05:21 volumio volumio[3113]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::addQueueItems Jun 30 06:05:21 volumio volumio[3113]: info: CorePlayQueue::addQueueItems Jun 30 06:05:21 volumio volumio[3113]: info: Preload queue cleared Jun 30 06:05:21 volumio volumio[3113]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99610629 Jun 30 06:05:21 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 30 06:05:21 volumio volumio[3113]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:05:21 volumio volumio[3113]: info: CorePlayQueue::saveQueue Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::updateTrackBlock Jun 30 06:05:21 volumio volumio[3113]: info: CorePlayQueue::getTrackBlock Jun 30 06:05:21 volumio volumio[3113]: info: CoreCommandRouter::volumioPlay Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::play index 0 Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::stop Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::play index undefined Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:21 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:21 volumio volumio[3113]: info: CoreStateMachine::startPlaybackTimer Jun 30 06:05:21 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:21 volumio volumio[3113]: info: [1751234721856] ControllerWebradio::clearAddPlayTrack Jun 30 06:05:21 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 06:05:21 volumio volumio[3113]: info: sendMpdCommand stop took 5 milliseconds Jun 30 06:05:21 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 06:05:21 volumio volumio[3113]: info: Jun 30 06:05:21 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:21 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:21 volumio volumio[3113]: info: sendMpdCommand clear took 2 milliseconds Jun 30 06:05:21 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99610629" Jun 30 06:05:21 volumio volumio[3113]: info: Jun 30 06:05:21 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:21 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:21 volumio volumio[3113]: info: Jun 30 06:05:21 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:21 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:21 volumio volumio[3113]: error: updateQueue error: null Jun 30 06:05:21 volumio volumio[3113]: info: ------------------------------ 5ms Jun 30 06:05:22 volumio volumio[3113]: info: Jun 30 06:05:22 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:22 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:22 volumio volumio[3113]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99610629" took 854 milliseconds Jun 30 06:05:22 volumio volumio[3113]: info: ------------------------------ 854ms Jun 30 06:05:22 volumio volumio[3113]: info: ------------------------------ 853ms Jun 30 06:05:22 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 06:05:22 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand play Jun 30 06:05:22 volumio volumio[3113]: info: Jun 30 06:05:22 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:22 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:22 volumio volumio[3113]: info: Jun 30 06:05:22 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:22 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:22 volumio volumio[3113]: info: ------------------------------ 8ms Jun 30 06:05:22 volumio volumio[3113]: info: sendMpdCommand play took 4 milliseconds Jun 30 06:05:22 volumio volumio[3113]: info: ------------------------------ 4ms Jun 30 06:05:22 volumio volumio[3113]: info: ------------------------------ 3ms Jun 30 06:05:23 volumio volumio[3113]: info: AutoStart - Plugin is starting Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::volumioGetQueue Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::getQueue Jun 30 06:05:23 volumio volumio[3113]: info: CorePlayQueue::getQueue Jun 30 06:05:23 volumio volumio[3113]: info: AutoStart - start playing Jun 30 06:05:23 volumio volumio[3113]: info: AutoStart - start playing with no specific position Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::volumioPlay Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::play index 0 Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::stop Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::play index undefined Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:23 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::startPlaybackTimer Jun 30 06:05:23 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:23 volumio volumio[3113]: info: [1751234723158] ControllerWebradio::clearAddPlayTrack Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 06:05:23 volumio volumio[3113]: info: Jun 30 06:05:23 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:23 volumio volumio[3113]: info: sendMpdCommand stop took 4 milliseconds Jun 30 06:05:23 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 06:05:23 volumio volumio[3113]: info: Jun 30 06:05:23 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:23 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:23 volumio volumio[3113]: info: Jun 30 06:05:23 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:23 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:23 volumio volumio[3113]: info: sendMpdCommand status took 12 milliseconds Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:23 volumio volumio[3113]: info: Jun 30 06:05:23 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:23 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:23 volumio volumio[3113]: info: sendMpdCommand clear took 14 milliseconds Jun 30 06:05:23 volumio volumio[3113]: info: sendMpdCommand status took 13 milliseconds Jun 30 06:05:23 volumio volumio[3113]: info: sendMpdCommand status took 12 milliseconds Jun 30 06:05:23 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99610629" Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:23 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:23 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:23 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:23 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:23 volumio volumio[3113]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 30 06:05:23 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:23 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:23 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:23 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:23 volumio volumio[3113]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 30 06:05:23 volumio volumio[3113]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 30 06:05:23 volumio volumio[3113]: info: ------------------------------ 30ms Jun 30 06:05:23 volumio volumio[3113]: info: ------------------------------ 28ms Jun 30 06:05:23 volumio volumio[3113]: info: ------------------------------ 32ms Jun 30 06:05:23 volumio volumio[3113]: info: Jun 30 06:05:23 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:23 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:23 volumio volumio[3113]: info: Jun 30 06:05:23 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:23 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:23 volumio volumio[3113]: error: updateQueue error: null Jun 30 06:05:23 volumio volumio[3113]: info: ------------------------------ 20ms Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 30 06:05:23 volumio volumio[3113]: info: Adding plugin bluetooth to MyMusic Plugins Jun 30 06:05:23 volumio volumio[3113]: info: Adding plugin multiroom to MyMusic Plugins Jun 30 06:05:23 volumio volumio[3113]: info: Adding plugin metavolumio to MyMusic Plugins Jun 30 06:05:23 volumio volumio[3113]: info: Adding plugin cd_controller to MyMusic Plugins Jun 30 06:05:23 volumio volumio[3113]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 30 06:05:23 volumio volumio[3113]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:23 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:23 volumio volumio[3113]: info: Starting MyVolumio Remote Streaming Endpoints Jun 30 06:05:23 volumio volumio[3113]: info: MyVolumio not started Jun 30 06:05:23 volumio volumio[3113]: info: Initializing device activation check Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 30 06:05:23 volumio volumio[3113]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 30 06:05:24 volumio volumio[3113]: info: Starting Streaming Service Transparent Proxy Jun 30 06:05:24 volumio volumio[3113]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 30 06:05:24 volumio volumio[3113]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 30 06:05:24 volumio volumio[3113]: info: Streaming services startup Jun 30 06:05:24 volumio volumio[3113]: info: Starting Streaming Daemon Jun 30 06:05:24 volumio sudo[3324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 30 06:05:24 volumio sudo[3324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:24 volumio sudo[3324]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:24 volumio volumio[3113]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 30 06:05:24 volumio volumio[3113]: info: Jun 30 06:05:24 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:24 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:24 volumio volumio[3113]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99610629" took 1281 milliseconds Jun 30 06:05:24 volumio volumio[3113]: info: ------------------------------ 1265ms Jun 30 06:05:24 volumio volumio[3113]: info: ------------------------------ 1264ms Jun 30 06:05:24 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 06:05:24 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand play Jun 30 06:05:24 volumio volumio[3113]: info: Jun 30 06:05:24 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:24 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:24 volumio volumio[3113]: info: Jun 30 06:05:24 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:24 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:24 volumio volumio[3113]: error: Cannot start Volumio Streaming Daemon Jun 30 06:05:24 volumio volumio[3113]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 30 06:05:24 volumio volumio[3113]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 30 06:05:24 volumio volumio[3113]: info: ------------------------------ 23ms Jun 30 06:05:24 volumio volumio[3113]: info: sendMpdCommand play took 23 milliseconds Jun 30 06:05:24 volumio volumio[3113]: info: ------------------------------ 22ms Jun 30 06:05:24 volumio volumio[3113]: info: ------------------------------ 21ms Jun 30 06:05:24 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:24 volumio volumio[3113]: STREAMING PROXY: Starting server on port 3245 Jun 30 06:05:24 volumio volumio[3113]: Node JS runtime: 14 Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 18 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 17 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 16 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 5 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 3 milliseconds Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/8000) CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/8000) CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:25 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/8000) CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:25 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 45ms Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 59ms Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 59ms Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 38 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/8000) CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:25 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/8000) CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:25 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 70ms Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 67ms Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 48 milliseconds Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/8000) CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:25 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 100ms Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:25 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:25 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 3ms Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 2 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 2ms Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:25 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:25 volumio volumio[3113]: info: Jun 30 06:05:25 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 4 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:25 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 18ms Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 14ms Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand status took 15 milliseconds Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:25 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 30ms Jun 30 06:05:25 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 30 06:05:25 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:25 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:25 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:25 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Post-War Hits","album":null,"uri":"http://216.235.95.100:16428/stream","trackType":""} Jun 30 06:05:25 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:25 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:25 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:25 volumio volumio[3113]: info: ------------------------------ 49ms Jun 30 06:05:34 volumio volumio[3113]: info: Saving local item radio-favourites Jun 30 06:05:34 volumio volumio[3113]: info: Pushing Favourites {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99610629","favourite":false} Jun 30 06:05:35 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:35 volumio sudo[3341]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jun 30 06:05:35 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 06:05:37 volumio volumio[3113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 7 Jun 30 06:05:37 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:38 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 06:05:38 volumio volumio[3113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Jun 30 06:05:38 volumio volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 30 06:05:42 volumio sudo[3341]: pam_unix(sudo:session): session closed for user root Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::volumioGetBrowseSources Jun 30 06:05:43 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 06:05:44 volumio volumio[3113]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 30 06:05:44 volumio volumio[3113]: info: Completed starting MyVolumio Plugin Jun 30 06:05:44 volumio volumio[3113]: info: BOOT COMPLETED Jun 30 06:05:44 volumio volumio[3113]: [Metrics] CommandRouter: 36s 33.62ms Jun 30 06:05:44 volumio volumio[3113]: info: CoreCommandRouter::volumiosetStartupVolume Jun 30 06:05:44 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 30 06:05:44 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 30 06:05:44 volumio volumio[3113]: info: CoreCommandRouter::Close All Modals sent Jun 30 06:05:44 volumio volumio[3113]: info: CoreCommandRouter::Close All Modals sent Jun 30 06:05:44 volumio volumio[3113]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 30 06:05:44 volumio volumio[3113]: aplay: main:828: audio open error: Device or resource busy Jun 30 06:05:45 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 30 06:05:45 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 30 06:05:45 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 30 06:05:47 volumio volumio[3113]: info: Preload queue cleared Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::ClearQueue Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::stop Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::stPlaybackTimer Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::updateTrackBlock Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrackBlock Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::serviceStop Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::serviceStop Jun 30 06:05:47 volumio volumio[3113]: info: [1751234747164] ControllerWebradio::stop Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::clearPlayQueue Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::saveQueue Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::addQueueItems Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::addQueueItems Jun 30 06:05:47 volumio volumio[3113]: info: Preload queue cleared Jun 30 06:05:47 volumio volumio[3113]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555432 Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::volumioPushQueue Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::saveQueue Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::updateTrackBlock Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrackBlock Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::volumioPlay Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::play index 0 Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::stop Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::play index undefined Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::startPlaybackTimer Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:47 volumio volumio[3113]: info: [1751234747181] ControllerWebradio::clearAddPlayTrack Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand stop Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand stop took 54 milliseconds Jun 30 06:05:47 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand stop took 39 milliseconds Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand clear Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:47 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:47 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:47 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand status took 6 milliseconds Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand clear took 6 milliseconds Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand status took 4 milliseconds Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand status took 3 milliseconds Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555432" Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:47 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:47 volumio volumio[3113]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 30 06:05:47 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:47 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:47 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:47 volumio volumio[3113]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 25ms Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 25ms Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:47 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:47 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:47 volumio volumio[3113]: error: updateQueue error: null Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 27ms Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:47 volumio volumio[3113]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 38ms Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:47 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555432" took 246 milliseconds Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 226ms Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 225ms Jun 30 06:05:47 volumio volumio[3113]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 06:05:47 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand play Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:47 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:47 volumio volumio[3113]: info: Jun 30 06:05:47 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:47 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 8ms Jun 30 06:05:47 volumio volumio[3113]: info: sendMpdCommand play took 5 milliseconds Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 7ms Jun 30 06:05:47 volumio volumio[3113]: info: ------------------------------ 6ms Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 5 milliseconds Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 8 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 6 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/1000) Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus stop Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 15ms Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 11 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 4 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 3 milliseconds Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/1000) Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:49 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/1000) Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:49 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 62ms Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 61ms Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 44 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 44 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/1000) Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:49 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/1000) Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:49 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","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":"(#1 - 1/1000) Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:49 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 111ms Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 104ms Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 102ms Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:49 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:49 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces system playlist update Jun 30 06:05:49 volumio volumio[3113]: info: Ignoring MPD Status Update Jun 30 06:05:49 volumio volumio[3113]: info: Jun 30 06:05:49 volumio volumio[3113]: ---------------------------- MPD announces state update: player Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::getState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 7ms Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 6 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 5ms Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 4 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 3ms Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand status took 3 milliseconds Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseState Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 30 06:05:49 volumio volumio[3113]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:49 volumio volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Nick En Simon - Vaarwel Verleden","artist":"Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:49 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Nick En Simon - Vaarwel Verleden","artist":"Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:49 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: ControllerMpd::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::servicePushState Jun 30 06:05:49 volumio volumio[3113]: info: CorePlayQueue::getTrack 0 Jun 30 06:05:49 volumio volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Nick En Simon - Vaarwel Verleden","artist":"Radio Almelo","album":null,"uri":"https://www.stream4you.nl:8050/stream","trackType":""} Jun 30 06:05:49 volumio volumio[3113]: verbose: CURRENT POSITION 0 Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 30 06:05:49 volumio volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreStateMachine::pushState Jun 30 06:05:49 volumio volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 58ms Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 58ms Jun 30 06:05:49 volumio volumio[3113]: info: ------------------------------ 58ms Jun 30 06:05:51 volumio volumio[3113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 06:05:51 volumio volumio[3113]: Error: connect ECONNREFUSED 47.88.58.234:80 Jun 30 06:05:51 volumio volumio[3113]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 30 06:05:51 volumio volumio[3113]: errno: -111, Jun 30 06:05:51 volumio volumio[3113]: code: 'ECONNREFUSED', Jun 30 06:05:51 volumio volumio[3113]: syscall: 'connect', Jun 30 06:05:51 volumio volumio[3113]: address: '47.88.58.234', Jun 30 06:05:51 volumio volumio[3113]: port: 80 Jun 30 06:05:51 volumio volumio[3113]: } Jun 30 06:05:51 volumio volumio[3113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 06:05:51 volumio sudo[3384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 06:04 Jun 30 06:05:51 volumio sudo[3384]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"