-- Logs begin at Wed 2025-11-05 12:08:03 CST, end at Wed 2025-11-05 12:54:31 CST. -- Nov 05 12:53:02 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:02 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:07 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 05 12:53:07 volumio volumio[1094]: info: Getting webradio selection Nov 05 12:53:07 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:07 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:08 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:12 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:12 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:15 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:15 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::stPlaybackTimer Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:53:15 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::serviceStop Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:15 volumio volumio[1094]: info: CoreCommandRouter::serviceStop Nov 05 12:53:15 volumio volumio[1094]: info: ControllerMpd::stop Nov 05 12:53:15 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:15 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:15 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:15 volumio volumio[1094]: info: Adding Item to queue: https://listen-msmn.sharp-stream.com/nme2.mp3 Nov 05 12:53:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:15 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:15 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:15 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:15 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:15 volumio volumio[1094]: info: [1762318395379] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:15 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:15 volumio volumio[1094]: info: Nov 05 12:53:15 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:53:15 volumio volumio[1094]: info: sendMpdCommand stop took 18 milliseconds Nov 05 12:53:15 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:53:15 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:53:15 volumio volumio[1094]: info: sendMpdCommand stop took 7 milliseconds Nov 05 12:53:15 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:53:15 volumio volumio[1094]: info: Nov 05 12:53:15 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:15 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:15 volumio volumio[1094]: info: sendMpdCommand status took 3 milliseconds Nov 05 12:53:15 volumio volumio[1094]: info: sendMpdCommand clear took 2 milliseconds Nov 05 12:53:15 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:53:15 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:53:15 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "https://listen-msmn.sharp-stream.com/nme2.mp3" Nov 05 12:53:15 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:53:15 volumio volumio[1094]: info: ------------------------------ 3ms Nov 05 12:53:15 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 05 12:53:15 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:53:15 volumio volumio[1094]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 05 12:53:15 volumio volumio[1094]: info: ------------------------------ 10ms Nov 05 12:53:17 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:17 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:17 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:17 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:17 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:17 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:17 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:17 volumio volumio[1094]: info: Adding Item to queue: https://listen-msmn.sharp-stream.com/nme2.mp3 Nov 05 12:53:17 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:17 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:17 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:17 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:17 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:17 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:17 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:17 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:17 volumio volumio[1094]: info: [1762318397425] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:17 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:18 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:18 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:19 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:19 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:19 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:19 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:19 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:19 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:19 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:19 volumio volumio[1094]: info: Adding Item to queue: https://onair.europaradiojazz.org/radio/8000/sd Nov 05 12:53:19 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:19 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:19 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:19 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:19 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:19 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:19 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:19 volumio volumio[1094]: info: [1762318399493] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:19 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:19 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "https://listen-msmn.sharp-stream.com/nme2.mp3" Nov 05 12:53:19 volumio volumio[1094]: info: sendMpdCommand stop took 2394 milliseconds Nov 05 12:53:19 volumio volumio[1094]: info: sendMpdCommand stop took 325 milliseconds Nov 05 12:53:19 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:53:19 volumio volumio[1094]: Upnp client error: Error: This socket has been ended by the other party Nov 05 12:53:19 volumio volumio[1094]: Upnp client error: Error: This socket has been ended by the other party Nov 05 12:53:19 volumio volumio[1094]: info: Nov 05 12:53:19 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:19 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:19 volumio volumio[1094]: info: Nov 05 12:53:19 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:19 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:19 volumio volumio[1094]: info: sendMpdCommand add "https://listen-msmn.sharp-stream.com/nme2.mp3" took 4 milliseconds Nov 05 12:53:19 volumio volumio[1094]: info: sendMpdCommand clear took 3 milliseconds Nov 05 12:53:19 volumio volumio[1094]: info: sendMpdCommand clear took 2 milliseconds Nov 05 12:53:19 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:19 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:19 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "https://listen-msmn.sharp-stream.com/nme2.mp3" Nov 05 12:53:19 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "https://onair.europaradiojazz.org/radio/8000/sd" Nov 05 12:53:19 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:53:19 volumio volumio[1094]: info: ------------------------------ 4ms Nov 05 12:53:19 volumio volumio[1094]: info: ------------------------------ 3ms Nov 05 12:53:19 volumio volumio[1094]: info: sendMpdCommand play took 2 milliseconds Nov 05 12:53:23 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "https://listen-msmn.sharp-stream.com/nme2.mp3" Nov 05 12:53:23 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:27 volumio volumio[1094]: Upnp client error: Error: This socket has been ended by the other party Nov 05 12:53:27 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "https://onair.europaradiojazz.org/radio/8000/sd" Nov 05 12:53:27 volumio volumio[1094]: Upnp client error: Error: This socket has been ended by the other party Nov 05 12:53:27 volumio volumio[1094]: info: Nov 05 12:53:27 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:27 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:27 volumio volumio[1094]: info: Nov 05 12:53:27 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:27 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:27 volumio volumio[1094]: info: sendMpdCommand add "https://listen-msmn.sharp-stream.com/nme2.mp3" took 4080 milliseconds Nov 05 12:53:27 volumio volumio[1094]: info: sendMpdCommand add "https://onair.europaradiojazz.org/radio/8000/sd" took 2 milliseconds Nov 05 12:53:27 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:27 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:27 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:27 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:27 volumio volumio[1094]: info: ------------------------------ 4ms Nov 05 12:53:27 volumio volumio[1094]: info: ------------------------------ 4ms Nov 05 12:53:27 volumio volumio[1094]: info: sendMpdCommand play took 4 milliseconds Nov 05 12:53:27 volumio volumio[1094]: info: sendMpdCommand play took 3 milliseconds Nov 05 12:53:28 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:28 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:28 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:28 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:28 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:28 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:28 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:28 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:28 volumio volumio[1094]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac Nov 05 12:53:28 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:28 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:28 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:28 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:28 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:28 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:28 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:28 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:28 volumio volumio[1094]: info: [1762318408784] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:28 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:28 volumio volumio[1094]: info: Nov 05 12:53:28 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:53:28 volumio volumio[1094]: info: sendMpdCommand stop took 13 milliseconds Nov 05 12:53:28 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:53:28 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:53:28 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:53:28 volumio volumio[1094]: info: Nov 05 12:53:28 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:28 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:28 volumio volumio[1094]: info: sendMpdCommand status took 3 milliseconds Nov 05 12:53:28 volumio volumio[1094]: info: sendMpdCommand clear took 3 milliseconds Nov 05 12:53:28 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:53:28 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:53:28 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:29 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:29 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:29 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:29 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:29 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:29 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:29 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:29 volumio volumio[1094]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac Nov 05 12:53:29 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:29 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:29 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:29 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:29 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:29 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:29 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:29 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:29 volumio volumio[1094]: info: [1762318409858] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:29 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:30 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:30 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:30 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:30 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:30 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:30 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:30 volumio volumio[1094]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac Nov 05 12:53:30 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:30 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:30 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:30 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:30 volumio volumio[1094]: info: [1762318410618] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:31 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:31 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:31 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:31 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:31 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:31 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:31 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:31 volumio volumio[1094]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac Nov 05 12:53:31 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:31 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:31 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:31 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:31 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:31 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:31 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:31 volumio volumio[1094]: info: [1762318411490] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:31 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:53:31 volumio volumio[1094]: info: ------------------------------ 2778ms Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2776 milliseconds Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:53:31 volumio volumio[1094]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 05 12:53:31 volumio volumio[1094]: info: ------------------------------ 2782ms Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand stop took 1721 milliseconds Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand stop took 962 milliseconds Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand stop took 89 milliseconds Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 2 milliseconds Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:53:31 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:31 volumio volumio[1094]: Upnp client error: Error: This socket has been ended by the other party Nov 05 12:53:31 volumio volumio[1094]: info: Nov 05 12:53:31 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:31 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:31 volumio volumio[1094]: info: Nov 05 12:53:31 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:31 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:31 volumio volumio[1094]: info: Nov 05 12:53:31 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:31 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand clear took 3 milliseconds Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand clear took 3 milliseconds Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand clear took 3 milliseconds Nov 05 12:53:31 volumio volumio[1094]: info: sendMpdCommand play took 4 milliseconds Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:31 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:31 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:53:31 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:53:31 volumio volumio[1094]: info: ------------------------------ 4ms Nov 05 12:53:31 volumio volumio[1094]: info: ------------------------------ 4ms Nov 05 12:53:31 volumio volumio[1094]: info: ------------------------------ 4ms Nov 05 12:53:32 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:32 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:32 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:32 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:32 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:32 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:32 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:32 volumio volumio[1094]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac Nov 05 12:53:32 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:32 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:32 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:32 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:32 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:32 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:32 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:32 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:32 volumio volumio[1094]: info: [1762318412634] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:32 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:33 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:33 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:33 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:36 volumio volumio[1094]: Upnp client error: Error: This socket has been ended by the other party Nov 05 12:53:36 volumio volumio[1094]: info: Nov 05 12:53:36 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:36 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:36 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:36 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:36 volumio volumio[1094]: info: sendMpdCommand stop took 3703 milliseconds Nov 05 12:53:36 volumio volumio[1094]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 3161 milliseconds Nov 05 12:53:36 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:53:36 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:36 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:36 volumio volumio[1094]: info: Nov 05 12:53:36 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:36 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:36 volumio volumio[1094]: info: Nov 05 12:53:36 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:36 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:36 volumio volumio[1094]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 4 milliseconds Nov 05 12:53:36 volumio volumio[1094]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 4 milliseconds Nov 05 12:53:36 volumio volumio[1094]: info: ------------------------------ 5ms Nov 05 12:53:36 volumio volumio[1094]: info: sendMpdCommand clear took 2 milliseconds Nov 05 12:53:36 volumio volumio[1094]: info: sendMpdCommand play took 3 milliseconds Nov 05 12:53:36 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:36 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:36 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:36 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:36 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:36 volumio volumio[1094]: Upnp client error: Error: This socket has been ended by the other party Nov 05 12:53:36 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:53:36 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:53:36 volumio volumio[1094]: info: ------------------------------ 4ms Nov 05 12:53:36 volumio volumio[1094]: info: ------------------------------ 5ms Nov 05 12:53:36 volumio volumio[1094]: info: sendMpdCommand play took 3 milliseconds Nov 05 12:53:37 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" Nov 05 12:53:37 volumio volumio[1094]: info: Nov 05 12:53:37 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:37 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:37 volumio volumio[1094]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 3 milliseconds Nov 05 12:53:37 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:37 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:37 volumio volumio[1094]: info: ------------------------------ 6ms Nov 05 12:53:37 volumio volumio[1094]: info: sendMpdCommand play took 3 milliseconds Nov 05 12:53:38 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:40 volumio volumio[1094]: info: Nov 05 12:53:40 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:53:40 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:53:40 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:53:40 volumio volumio[1094]: info: sendMpdCommand status took 112 milliseconds Nov 05 12:53:40 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:53:40 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:53:40 volumio volumio[1094]: info: Nov 05 12:53:40 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:40 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:40 volumio volumio[1094]: info: Nov 05 12:53:40 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:53:40 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:53:40 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:53:40 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 05 12:53:40 volumio volumio[1094]: info: ------------------------------ 10ms Nov 05 12:53:40 volumio volumio[1094]: info: sendMpdCommand status took 10 milliseconds Nov 05 12:53:40 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:53:40 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:53:40 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:53:40 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:53:40 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:53:40 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:40 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"60 Kbps","isStreaming":false,"title":"kexp64.aac","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Nov 05 12:53:40 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:53:40 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Nov 05 12:53:40 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus stop Nov 05 12:53:40 volumio volumio[1094]: info: ------------------------------ 129ms Nov 05 12:53:40 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 05 12:53:40 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:53:40 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:53:40 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:53:40 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:40 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"60 Kbps","isStreaming":false,"title":"kexp64.aac","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Nov 05 12:53:40 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:53:40 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Nov 05 12:53:40 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus play Nov 05 12:53:40 volumio volumio[1094]: info: Received an update from plugin. extracting info from payload Nov 05 12:53:40 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:53:40 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:40 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:53:40 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:53:40 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:53:40 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:53:40 volumio volumio[1094]: info: ------------------------------ 24ms Nov 05 12:53:43 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:43 volumio sudo[15475]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Nov 05 12:53:43 volumio sudo[15475]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 05 12:53:47 volumio volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 05 12:53:47 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:47 volumio sudo[15475]: pam_unix(sudo:session): session closed for user root Nov 05 12:53:48 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:48 volumio volumio[1094]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::ClearQueue Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::stPlaybackTimer Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:48 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:53:48 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::serviceStop Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:48 volumio volumio[1094]: info: CoreCommandRouter::serviceStop Nov 05 12:53:48 volumio volumio[1094]: info: [1762318428791] ControllerWebradio::stop Nov 05 12:53:48 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::clearPlayQueue Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:48 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::addQueueItems Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::addQueueItems Nov 05 12:53:48 volumio volumio[1094]: info: Preload queue cleared Nov 05 12:53:48 volumio volumio[1094]: info: Adding Item to queue: http://mscp3.live-streams.nl:8360/flac.flac Nov 05 12:53:48 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 05 12:53:48 volumio volumio[1094]: info: CoreCommandRouter::volumioPushQueue Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::saveQueue Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::updateTrackBlock Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::getTrackBlock Nov 05 12:53:48 volumio volumio[1094]: info: CoreCommandRouter::volumioPlay Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::play index 0 Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::stop Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:48 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:53:48 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:48 volumio volumio[1094]: info: [1762318428801] ControllerWebradio::clearAddPlayTrack Nov 05 12:53:48 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:53:49 volumio volumio[1094]: info: Nov 05 12:53:49 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:53:49 volumio volumio[1094]: info: sendMpdCommand stop took 432 milliseconds Nov 05 12:53:49 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:53:49 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:53:49 volumio volumio[1094]: info: sendMpdCommand stop took 422 milliseconds Nov 05 12:53:49 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:53:49 volumio volumio[1094]: info: Nov 05 12:53:49 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:49 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:49 volumio volumio[1094]: info: sendMpdCommand status took 4 milliseconds Nov 05 12:53:49 volumio volumio[1094]: info: sendMpdCommand clear took 4 milliseconds Nov 05 12:53:49 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:53:49 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:53:49 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8360/flac.flac" Nov 05 12:53:49 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:53:49 volumio volumio[1094]: info: ------------------------------ 5ms Nov 05 12:53:52 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2974 milliseconds Nov 05 12:53:52 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" Nov 05 12:53:52 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:53:52 volumio volumio[1094]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 05 12:53:52 volumio volumio[1094]: info: ------------------------------ 2983ms Nov 05 12:53:52 volumio volumio[1094]: info: Nov 05 12:53:52 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:53:52 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:53:52 volumio volumio[1094]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" took 2 milliseconds Nov 05 12:53:52 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:53:52 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:53:52 volumio volumio[1094]: Upnp client error: Error: This socket has been ended by the other party Nov 05 12:53:52 volumio volumio[1094]: info: ------------------------------ 3ms Nov 05 12:53:52 volumio volumio[1094]: info: sendMpdCommand play took 2 milliseconds Nov 05 12:53:52 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:53:52 volumio sudo[15542]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Nov 05 12:53:52 volumio sudo[15542]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 05 12:53:53 volumio volumio[1094]: info: Nov 05 12:53:53 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:53:53 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:53:53 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:53:53 volumio volumio[1094]: info: Nov 05 12:53:53 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:53:53 volumio volumio[1094]: info: sendMpdCommand status took 107 milliseconds Nov 05 12:53:53 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:53:53 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:53:53 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:53:53 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:53:53 volumio volumio[1094]: info: sendMpdCommand status took 3 milliseconds Nov 05 12:53:53 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:53:53 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:53:53 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 05 12:53:53 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 05 12:53:53 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:53:53 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:53:53 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:53:53 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:53:53 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:53 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Nov 05 12:53:53 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:53:53 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Nov 05 12:53:53 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus stop Nov 05 12:53:53 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:53:53 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:53:53 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:53 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Nov 05 12:53:53 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:53:53 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Nov 05 12:53:53 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus play Nov 05 12:53:53 volumio volumio[1094]: info: Received an update from plugin. extracting info from payload Nov 05 12:53:53 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:53:53 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:53:53 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:53:53 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:53:53 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:53:53 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:53:53 volumio volumio[1094]: info: ------------------------------ 134ms Nov 05 12:53:53 volumio volumio[1094]: info: ------------------------------ 31ms Nov 05 12:53:55 volumio volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 05 12:53:55 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:54:01 volumio volumio[1094]: info: Nov 05 12:54:01 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:54:01 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:54:01 volumio volumio[1094]: info: Nov 05 12:54:01 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:54:01 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:54:01 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:54:01 volumio volumio[1094]: info: ------------------------------ 2ms Nov 05 12:54:01 volumio volumio[1094]: info: sendMpdCommand status took 1 milliseconds Nov 05 12:54:01 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:54:01 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:54:01 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 05 12:54:01 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:54:01 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:54:01 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:54:01 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:01 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Nov 05 12:54:01 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:54:01 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Nov 05 12:54:01 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus play Nov 05 12:54:01 volumio volumio[1094]: info: Received an update from plugin. extracting info from payload Nov 05 12:54:01 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:01 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:01 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:01 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:01 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:01 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:01 volumio volumio[1094]: info: ------------------------------ 13ms Nov 05 12:54:01 volumio sudo[15542]: pam_unix(sudo:session): session closed for user root Nov 05 12:54:06 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:54:06 volumio sudo[15623]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Nov 05 12:54:06 volumio sudo[15623]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 05 12:54:08 volumio volumio[1094]: info: Nov 05 12:54:08 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:54:08 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:54:08 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:54:08 volumio volumio[1094]: info: sendMpdCommand status took 1 milliseconds Nov 05 12:54:08 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:54:08 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:54:08 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:54:08 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:08 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:08 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:08 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:08 volumio volumio[1094]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 05 12:54:08 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:54:08 volumio volumio[1094]: info: CoreStateMachine::syncState stateService stop Nov 05 12:54:08 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus play Nov 05 12:54:08 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:54:08 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:54:08 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:08 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:08 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:08 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:08 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:08 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:54:08 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:08 volumio volumio[1094]: info: [1762318448246] ControllerWebradio::clearAddPlayTrack Nov 05 12:54:08 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:54:08 volumio volumio[1094]: info: ------------------------------ 16ms Nov 05 12:54:08 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:08 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:08 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:08 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:08 volumio volumio[1094]: info: sendMpdCommand stop took 8 milliseconds Nov 05 12:54:08 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:54:08 volumio volumio[1094]: info: Nov 05 12:54:08 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:54:08 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:54:08 volumio volumio[1094]: info: sendMpdCommand clear took 9 milliseconds Nov 05 12:54:08 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8360/flac.flac" Nov 05 12:54:09 volumio volumio[1094]: error: updateQueue error: null Nov 05 12:54:09 volumio volumio[1094]: info: ------------------------------ 1011ms Nov 05 12:54:09 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" Nov 05 12:54:09 volumio volumio[1094]: info: Nov 05 12:54:09 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:54:09 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:54:09 volumio volumio[1094]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" took 6 milliseconds Nov 05 12:54:09 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 05 12:54:09 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand play Nov 05 12:54:09 volumio volumio[1094]: info: ------------------------------ 4ms Nov 05 12:54:09 volumio volumio[1094]: info: sendMpdCommand play took 2 milliseconds Nov 05 12:54:09 volumio volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 05 12:54:09 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:54:10 volumio volumio[1094]: info: Nov 05 12:54:10 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:54:10 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:54:10 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:54:10 volumio volumio[1094]: info: Nov 05 12:54:10 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:54:10 volumio volumio[1094]: info: sendMpdCommand status took 122 milliseconds Nov 05 12:54:10 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:54:10 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:54:10 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:54:10 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:54:10 volumio volumio[1094]: info: sendMpdCommand status took 2 milliseconds Nov 05 12:54:10 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 05 12:54:10 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:54:10 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:54:10 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:54:10 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:54:10 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:54:10 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:10 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Nov 05 12:54:10 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:54:10 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Nov 05 12:54:10 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus stop Nov 05 12:54:10 volumio volumio[1094]: info: ------------------------------ 127ms Nov 05 12:54:10 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 05 12:54:10 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:54:10 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:54:10 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:54:10 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:10 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Nov 05 12:54:10 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:54:10 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Nov 05 12:54:10 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus play Nov 05 12:54:10 volumio volumio[1094]: info: Received an update from plugin. extracting info from payload Nov 05 12:54:10 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:10 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:10 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:10 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:10 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:10 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:10 volumio volumio[1094]: info: ------------------------------ 19ms Nov 05 12:54:14 volumio sudo[15623]: pam_unix(sudo:session): session closed for user root Nov 05 12:54:15 volumio volumio[1094]: info: Nov 05 12:54:15 volumio volumio[1094]: ---------------------------- MPD announces system playlist update Nov 05 12:54:15 volumio volumio[1094]: info: Ignoring MPD Status Update Nov 05 12:54:15 volumio volumio[1094]: info: Nov 05 12:54:15 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:54:15 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:54:15 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:54:15 volumio volumio[1094]: info: ------------------------------ 2ms Nov 05 12:54:15 volumio volumio[1094]: info: sendMpdCommand status took 2 milliseconds Nov 05 12:54:15 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:54:15 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 05 12:54:15 volumio volumio[1094]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 05 12:54:15 volumio volumio[1094]: verbose: ControllerMpd::parseTrackInfo Nov 05 12:54:15 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:54:15 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:54:15 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:15 volumio volumio[1094]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Nov 05 12:54:15 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:54:15 volumio volumio[1094]: info: CoreStateMachine::syncState stateService play Nov 05 12:54:15 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus play Nov 05 12:54:15 volumio volumio[1094]: info: Received an update from plugin. extracting info from payload Nov 05 12:54:15 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:15 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:15 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:15 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:15 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:15 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:15 volumio volumio[1094]: info: ------------------------------ 18ms Nov 05 12:54:19 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:54:19 volumio sudo[15764]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Nov 05 12:54:19 volumio sudo[15764]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 05 12:54:22 volumio volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Nov 05 12:54:22 volumio volumio[1094]: info: CoreCommandRouter::volumioGetState Nov 05 12:54:30 volumio volumio[1094]: info: Nov 05 12:54:30 volumio volumio[1094]: ---------------------------- MPD announces state update: player Nov 05 12:54:30 volumio volumio[1094]: info: ControllerMpd::getState Nov 05 12:54:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand status Nov 05 12:54:30 volumio volumio[1094]: info: sendMpdCommand status took 1 milliseconds Nov 05 12:54:30 volumio volumio[1094]: verbose: ControllerMpd::parseState Nov 05 12:54:30 volumio volumio[1094]: info: ControllerMpd::pushState Nov 05 12:54:30 volumio volumio[1094]: info: CoreCommandRouter::servicePushState Nov 05 12:54:30 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:30 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:30 volumio volumio[1094]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 05 12:54:30 volumio volumio[1094]: verbose: CURRENT POSITION 0 Nov 05 12:54:30 volumio volumio[1094]: info: CoreStateMachine::syncState stateService stop Nov 05 12:54:30 volumio volumio[1094]: info: CoreStateMachine::syncState currentStatus play Nov 05 12:54:30 volumio volumio[1094]: info: CoreStateMachine::play index undefined Nov 05 12:54:30 volumio volumio[1094]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 05 12:54:30 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:30 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:30 volumio volumio[1094]: info: CoreStateMachine::startPlaybackTimer Nov 05 12:54:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:30 volumio volumio[1094]: info: [1762318470956] ControllerWebradio::clearAddPlayTrack Nov 05 12:54:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand stop Nov 05 12:54:30 volumio volumio[1094]: info: ------------------------------ 16ms Nov 05 12:54:30 volumio volumio[1094]: info: CoreStateMachine::pushState Nov 05 12:54:30 volumio volumio[1094]: info: CorePlayQueue::getTrack 0 Nov 05 12:54:30 volumio volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 05 12:54:30 volumio volumio[1094]: info: CoreCommandRouter::volumioPushState Nov 05 12:54:30 volumio volumio[1094]: info: sendMpdCommand stop took 8 milliseconds Nov 05 12:54:30 volumio volumio[1094]: verbose: ControllerMpd::sendMpdCommand clear Nov 05 12:54:30 volumio volumio[1094]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 12:54:30 volumio volumio[1094]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Nov 05 12:54:30 volumio volumio[1094]: at Object.readdirSync (fs.js:1048:3) Nov 05 12:54:30 volumio volumio[1094]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Nov 05 12:54:30 volumio volumio[1094]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Nov 05 12:54:30 volumio volumio[1094]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Nov 05 12:54:30 volumio volumio[1094]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Nov 05 12:54:30 volumio volumio[1094]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Nov 05 12:54:30 volumio volumio[1094]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Nov 05 12:54:30 volumio volumio[1094]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Nov 05 12:54:30 volumio volumio[1094]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Nov 05 12:54:30 volumio volumio[1094]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Nov 05 12:54:30 volumio volumio[1094]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Nov 05 12:54:30 volumio volumio[1094]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Nov 05 12:54:30 volumio volumio[1094]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Nov 05 12:54:30 volumio volumio[1094]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Nov 05 12:54:30 volumio volumio[1094]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Nov 05 12:54:30 volumio volumio[1094]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Nov 05 12:54:30 volumio volumio[1094]: errno: -20, Nov 05 12:54:30 volumio volumio[1094]: syscall: 'scandir', Nov 05 12:54:30 volumio volumio[1094]: code: 'ENOTDIR', Nov 05 12:54:30 volumio volumio[1094]: path: '/tmp/peppyrunning' Nov 05 12:54:30 volumio volumio[1094]: } Nov 05 12:54:30 volumio volumio[1094]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 12:54:31 volumio sudo[15851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-05 12:53 Nov 05 12:54:31 volumio sudo[15851]: 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="5d50cd80bebce60dddccd7f5e813d656e28ca4cd" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 21 Oct 2025 04:19:36 PM CEST" VOLUMIO_VERSION="3.866" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a531aa6bc68498be155aace11c795690"