-- Logs begin at Tue 2024-09-17 23:06:54 CST, end at Sat 2024-10-19 18:22:19 CST. -- Oct 19 18:21:04 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 19 18:21:04 volumio volumio[1856]: info: TuneIn handleBrowseUri: tunein/best Oct 19 18:21:09 volumio volumio-remote-updater[616]: No test mode Oct 19 18:21:09 volumio volumio-remote-updater[616]: No alpha test mode Oct 19 18:21:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 19 18:21:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 18:21:15 volumio volumio[1856]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 19 18:21:15 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 19 18:21:36 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 19 18:21:36 volumio volumio[1856]: info: TuneIn handleBrowseUri: tunein/local Oct 19 18:21:38 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 19 18:21:39 volumio volumio[1856]: info: Preload queue cleared Oct 19 18:21:40 volumio volumio[1856]: info: Preload queue cleared Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::ClearQueue Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::stop Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::stPlaybackTimer Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::updateTrackBlock Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrackBlock Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrack 1 Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::serviceStop Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrack 1 Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::serviceStop Oct 19 18:21:40 volumio volumio[1856]: info: ControllerMpd::stop Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::clearPlayQueue Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::saveQueue Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::volumioPushQueue Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::addQueueItems Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::addQueueItems Oct 19 18:21:40 volumio volumio[1856]: info: Preload queue cleared Oct 19 18:21:40 volumio volumio[1856]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::volumioPushQueue Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::saveQueue Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::updateTrackBlock Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrackBlock Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::volumioPlay Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::play index 0 Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::stop Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::play index undefined Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::startPlaybackTimer Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:40 volumio volumio[1856]: info: [1729333300836] ControllerWebradio::clearAddPlayTrack Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:40 volumio volumio[1856]: info: Oct 19 18:21:40 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:40 volumio volumio[1856]: info: sendMpdCommand stop took 59 milliseconds Oct 19 18:21:40 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:40 volumio volumio[1856]: info: sendMpdCommand stop took 12 milliseconds Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 18:21:40 volumio volumio[1856]: info: Oct 19 18:21:40 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:40 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:40 volumio volumio[1856]: info: Oct 19 18:21:40 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:40 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:40 volumio volumio[1856]: info: Oct 19 18:21:40 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:40 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:40 volumio volumio[1856]: info: sendMpdCommand status took 12 milliseconds Oct 19 18:21:40 volumio volumio[1856]: info: sendMpdCommand clear took 10 milliseconds Oct 19 18:21:40 volumio volumio[1856]: info: sendMpdCommand status took 8 milliseconds Oct 19 18:21:40 volumio volumio[1856]: info: sendMpdCommand status took 6 milliseconds Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:40 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:40 volumio volumio[1856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 19 18:21:40 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:40 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:40 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:40 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:40 volumio volumio[1856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 19 18:21:40 volumio volumio[1856]: info: ------------------------------ 60ms Oct 19 18:21:40 volumio volumio[1856]: info: ------------------------------ 59ms Oct 19 18:21:40 volumio volumio[1856]: info: Oct 19 18:21:40 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:40 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:40 volumio volumio[1856]: info: Oct 19 18:21:40 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:40 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:40 volumio volumio[1856]: error: updateQueue error: null Oct 19 18:21:40 volumio volumio[1856]: info: ------------------------------ 70ms Oct 19 18:21:40 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 66 milliseconds Oct 19 18:21:40 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:21:40 volumio volumio[1856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 18:21:40 volumio volumio[1856]: info: ------------------------------ 87ms Oct 19 18:21:43 volumio volumio[1856]: info: Preload queue cleared Oct 19 18:21:43 volumio volumio[1856]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::ClearQueue Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::stop Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:43 volumio volumio[1856]: info: CorePlayQueue::clearPlayQueue Oct 19 18:21:43 volumio volumio[1856]: info: CorePlayQueue::saveQueue Oct 19 18:21:43 volumio volumio[1856]: info: CoreCommandRouter::volumioPushQueue Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::addQueueItems Oct 19 18:21:43 volumio volumio[1856]: info: CorePlayQueue::addQueueItems Oct 19 18:21:43 volumio volumio[1856]: info: Preload queue cleared Oct 19 18:21:43 volumio volumio[1856]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Oct 19 18:21:43 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 19 18:21:43 volumio volumio[1856]: info: CoreCommandRouter::volumioPushQueue Oct 19 18:21:43 volumio volumio[1856]: info: CorePlayQueue::saveQueue Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::updateTrackBlock Oct 19 18:21:43 volumio volumio[1856]: info: CorePlayQueue::getTrackBlock Oct 19 18:21:43 volumio volumio[1856]: info: CoreCommandRouter::volumioPlay Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::play index 0 Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::stop Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::play index undefined Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:43 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:43 volumio volumio[1856]: info: CoreStateMachine::startPlaybackTimer Oct 19 18:21:43 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:43 volumio volumio[1856]: info: [1729333303893] ControllerWebradio::clearAddPlayTrack Oct 19 18:21:43 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 3193 milliseconds Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand play Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 3145ms Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 3142ms Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand stop took 172 milliseconds Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand play took 12 milliseconds Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 12ms Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:44 volumio volumio[1856]: error: updateQueue error: null Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand clear took 10 milliseconds Oct 19 18:21:44 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 11ms Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 8ms Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:44 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:44 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:44 volumio volumio[1856]: error: updateQueue error: null Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 24ms Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand status took 20 milliseconds Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:44 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:44 volumio volumio[1856]: 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} Oct 19 18:21:44 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: No code Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 84ms Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 211 milliseconds Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand play Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 214ms Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand status took 213 milliseconds Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 212ms Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand status took 202 milliseconds Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:44 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:44 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:44 volumio volumio[1856]: 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} Oct 19 18:21:44 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: No code Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:44 volumio volumio[1856]: 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} Oct 19 18:21:44 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: No code Oct 19 18:21:44 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:44 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 330ms Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 328ms Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: Oct 19 18:21:44 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:44 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:44 volumio volumio[1856]: info: sendMpdCommand play took 134 milliseconds Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 133ms Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 11ms Oct 19 18:21:44 volumio volumio[1856]: info: ------------------------------ 9ms Oct 19 18:21:47 volumio volumio[1856]: info: CoreCommandRouter::volumioPlay Oct 19 18:21:47 volumio volumio[1856]: info: CoreStateMachine::play index undefined Oct 19 18:21:47 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:47 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:47 volumio volumio[1856]: info: CoreStateMachine::startPlaybackTimer Oct 19 18:21:47 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:47 volumio volumio[1856]: info: [1729333307757] ControllerWebradio::clearAddPlayTrack Oct 19 18:21:47 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:48 volumio volumio[1856]: info: CoreCommandRouter::volumioPlay Oct 19 18:21:48 volumio volumio[1856]: info: CoreStateMachine::play index undefined Oct 19 18:21:48 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:48 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:48 volumio volumio[1856]: info: CoreStateMachine::startPlaybackTimer Oct 19 18:21:48 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:48 volumio volumio[1856]: info: [1729333308742] ControllerWebradio::clearAddPlayTrack Oct 19 18:21:48 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:50 volumio volumio[1856]: info: Preload queue cleared Oct 19 18:21:50 volumio volumio[1856]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::ClearQueue Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::stop Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:50 volumio volumio[1856]: info: CorePlayQueue::clearPlayQueue Oct 19 18:21:50 volumio volumio[1856]: info: CorePlayQueue::saveQueue Oct 19 18:21:50 volumio volumio[1856]: info: CoreCommandRouter::volumioPushQueue Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::addQueueItems Oct 19 18:21:50 volumio volumio[1856]: info: CorePlayQueue::addQueueItems Oct 19 18:21:50 volumio volumio[1856]: info: Preload queue cleared Oct 19 18:21:50 volumio volumio[1856]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Oct 19 18:21:50 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 19 18:21:50 volumio volumio[1856]: info: CoreCommandRouter::volumioPushQueue Oct 19 18:21:50 volumio volumio[1856]: info: CorePlayQueue::saveQueue Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::updateTrackBlock Oct 19 18:21:50 volumio volumio[1856]: info: CorePlayQueue::getTrackBlock Oct 19 18:21:50 volumio volumio[1856]: info: CoreCommandRouter::volumioPlay Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::play index 0 Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::stop Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::play index undefined Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:50 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:50 volumio volumio[1856]: info: CoreStateMachine::startPlaybackTimer Oct 19 18:21:50 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:50 volumio volumio[1856]: info: [1729333310895] ControllerWebradio::clearAddPlayTrack Oct 19 18:21:50 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:52 volumio volumio[1856]: info: sendMpdCommand stop took 4908 milliseconds Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 18:21:52 volumio volumio[1856]: info: Oct 19 18:21:52 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:52 volumio volumio[1856]: info: Oct 19 18:21:52 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:52 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:52 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:52 volumio volumio[1856]: info: sendMpdCommand stop took 3929 milliseconds Oct 19 18:21:52 volumio volumio[1856]: info: sendMpdCommand stop took 1775 milliseconds Oct 19 18:21:52 volumio volumio[1856]: info: sendMpdCommand clear took 5 milliseconds Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 19 18:21:52 volumio volumio[1856]: info: Oct 19 18:21:52 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:52 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:52 volumio volumio[1856]: info: Oct 19 18:21:52 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:52 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:52 volumio volumio[1856]: error: updateQueue error: null Oct 19 18:21:52 volumio volumio[1856]: info: Oct 19 18:21:52 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:52 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:52 volumio volumio[1856]: info: Oct 19 18:21:52 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:52 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:52 volumio volumio[1856]: info: sendMpdCommand status took 15 milliseconds Oct 19 18:21:52 volumio volumio[1856]: info: ------------------------------ 16ms Oct 19 18:21:52 volumio volumio[1856]: info: sendMpdCommand clear took 13 milliseconds Oct 19 18:21:52 volumio volumio[1856]: info: sendMpdCommand clear took 13 milliseconds Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 19 18:21:52 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:52 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:52 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:52 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:52 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:52 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:52 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:52 volumio volumio[1856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 19 18:21:52 volumio volumio[1856]: info: ------------------------------ 48ms Oct 19 18:21:52 volumio volumio[1856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 211 milliseconds Oct 19 18:21:52 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand play Oct 19 18:21:52 volumio volumio[1856]: info: CoreCommandRouter::volumioPlay Oct 19 18:21:52 volumio volumio[1856]: info: CoreStateMachine::play index undefined Oct 19 18:21:52 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:52 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:52 volumio volumio[1856]: info: CoreStateMachine::startPlaybackTimer Oct 19 18:21:52 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:52 volumio volumio[1856]: info: [1729333312893] ControllerWebradio::clearAddPlayTrack Oct 19 18:21:52 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand status took 639 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand status took 637 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 635ms Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 634ms Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 631 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 630 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand play took 433 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand stop took 426 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 15ms Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 12ms Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand play Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand play Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:53 volumio volumio[1856]: 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} Oct 19 18:21:53 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: No code Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:53 volumio volumio[1856]: 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} Oct 19 18:21:53 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: No code Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 774ms Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 773ms Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:53 volumio volumio[1856]: error: updateQueue error: null Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 155ms Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 154ms Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand status took 147 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand play took 145 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand play took 145 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand clear took 145 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 17ms Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand status took 15 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 14ms Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand status took 11 milliseconds Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:53 volumio volumio[1856]: 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} Oct 19 18:21:53 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: No code Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:53 volumio volumio[1856]: 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} Oct 19 18:21:53 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: No code Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 137ms Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 135ms Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:53 volumio volumio[1856]: error: updateQueue error: null Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 166ms Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand status took 160 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 158 milliseconds Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:53 volumio volumio[1856]: 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} Oct 19 18:21:53 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: No code Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 232ms Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 384ms Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 232 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 111ms Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand status took 110 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 108ms Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand status took 107 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand play Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:53 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:53 volumio volumio[1856]: 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} Oct 19 18:21:53 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: No code Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:53 volumio volumio[1856]: 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} Oct 19 18:21:53 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: No code Oct 19 18:21:53 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:53 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 227ms Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 225ms Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: Oct 19 18:21:53 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:53 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 149ms Oct 19 18:21:53 volumio volumio[1856]: info: sendMpdCommand play took 143 milliseconds Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 8ms Oct 19 18:21:53 volumio volumio[1856]: info: ------------------------------ 5ms Oct 19 18:21:54 volumio volumio[1856]: info: CoreCommandRouter::volumioPlay Oct 19 18:21:54 volumio volumio[1856]: info: CoreStateMachine::play index undefined Oct 19 18:21:54 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:54 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:54 volumio volumio[1856]: info: CoreStateMachine::startPlaybackTimer Oct 19 18:21:54 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:54 volumio volumio[1856]: info: [1729333314350] ControllerWebradio::clearAddPlayTrack Oct 19 18:21:54 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:55 volumio volumio[1856]: info: CoreCommandRouter::volumioPlay Oct 19 18:21:55 volumio volumio[1856]: info: CoreStateMachine::play index undefined Oct 19 18:21:55 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 18:21:55 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:55 volumio volumio[1856]: info: CoreStateMachine::startPlaybackTimer Oct 19 18:21:55 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:55 volumio volumio[1856]: info: [1729333315245] ControllerWebradio::clearAddPlayTrack Oct 19 18:21:55 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand stop took 5034 milliseconds Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:59 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:59 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand stop took 4151 milliseconds Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand clear took 11 milliseconds Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:59 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:21:59 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:21:59 volumio volumio[1856]: error: updateQueue error: null Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:59 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand status took 14 milliseconds Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 22ms Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand clear took 13 milliseconds Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Oct 19 18:21:59 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:59 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:59 volumio volumio[1856]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 52ms Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 212 milliseconds Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand play Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:59 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:59 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:59 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:59 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:59 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:59 volumio volumio[1856]: info: Oct 19 18:21:59 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:21:59 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand status took 433 milliseconds Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand status took 431 milliseconds Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 428ms Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 425 milliseconds Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand play took 225 milliseconds Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 17ms Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 14ms Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 12ms Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 11ms Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 19 18:21:59 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand play Oct 19 18:21:59 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:59 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:59 volumio volumio[1856]: 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} Oct 19 18:21:59 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:59 volumio volumio[1856]: info: No code Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:59 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:59 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:21:59 volumio volumio[1856]: 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} Oct 19 18:21:59 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::syncState stateService stop Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:59 volumio volumio[1856]: info: No code Oct 19 18:21:59 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:21:59 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 575ms Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 574ms Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 164ms Oct 19 18:21:59 volumio volumio[1856]: info: ------------------------------ 163ms Oct 19 18:21:59 volumio volumio[1856]: info: sendMpdCommand play took 154 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 11 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 7 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 5 milliseconds Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 14 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 8 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 5 milliseconds Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: 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":"antenne","artist":"(#1 - 11819/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus stop Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: 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":"antenne","artist":"(#1 - 11819/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: 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":"antenne","artist":"(#1 - 11819/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 77ms Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 118ms Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 117ms Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 96 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 91 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 92 milliseconds Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: 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":"antenne","artist":"(#1 - 11819/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: 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":"antenne","artist":"(#1 - 11819/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: 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":"antenne","artist":"(#1 - 11819/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 242ms Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 234ms Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 234ms Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:03 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:03 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:03 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:03 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 41ms Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 39 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 33ms Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 32 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 30ms Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 29 milliseconds Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:03 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:03 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:03 volumio volumio[1856]: info: Oct 19 18:22:03 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 38ms Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 35 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 29ms Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 27 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 26ms Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand status took 23 milliseconds Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 211ms Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 203ms Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 201ms Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 174 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 172 milliseconds Oct 19 18:22:03 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 173 milliseconds Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1111,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1111,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:03 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:03 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1111,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:03 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:03 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:03 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 336ms Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 325ms Oct 19 18:22:03 volumio volumio[1856]: info: ------------------------------ 327ms Oct 19 18:22:04 volumio volumio[1856]: info: Oct 19 18:22:04 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:04 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:04 volumio volumio[1856]: info: Oct 19 18:22:04 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:04 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:04 volumio volumio[1856]: info: Oct 19 18:22:04 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:04 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:04 volumio volumio[1856]: info: Oct 19 18:22:04 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:04 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:04 volumio volumio[1856]: info: ------------------------------ 26ms Oct 19 18:22:04 volumio volumio[1856]: info: sendMpdCommand status took 24 milliseconds Oct 19 18:22:04 volumio volumio[1856]: info: ------------------------------ 24ms Oct 19 18:22:04 volumio volumio[1856]: info: sendMpdCommand status took 6 milliseconds Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:04 volumio volumio[1856]: info: Oct 19 18:22:04 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:04 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:04 volumio volumio[1856]: info: Oct 19 18:22:04 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:04 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:04 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 19 18:22:04 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 19 18:22:04 volumio volumio[1856]: info: ------------------------------ 6ms Oct 19 18:22:04 volumio volumio[1856]: info: sendMpdCommand status took 5 milliseconds Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:04 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:04 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:04 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:04 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:04 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:04 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:04 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:04 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:04 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:04 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:04 volumio volumio[1856]: info: ------------------------------ 128ms Oct 19 18:22:04 volumio volumio[1856]: info: ------------------------------ 126ms Oct 19 18:22:04 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 91 milliseconds Oct 19 18:22:04 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:04 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:04 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:04 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ava Max - Spot A Fake","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:04 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:04 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:04 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:04 volumio volumio[1856]: info: ------------------------------ 141ms Oct 19 18:22:09 volumio volumio[1856]: verbose: New Socket.io Connection to 192.168.2.198 from 192.168.2.140 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 5 Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::volumioGetState Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 18:22:09 volumio volumio[1856]: info: Received Get System Info Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:22:09 volumio volumio[1856]: info: Discovery: Getting this device information Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::volumioGetState Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::volumioGetState Oct 19 18:22:09 volumio volumio[1856]: info: Listing playlists Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:22:09 volumio volumio[1856]: info: CoreStateMachine::getQueue Oct 19 18:22:09 volumio volumio[1856]: info: CorePlayQueue::getQueue Oct 19 18:22:09 volumio volumio[1856]: info: Received Get System Info Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:22:09 volumio volumio[1856]: info: Discovery: Getting this device information Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::volumioGetState Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::volumioGetState Oct 19 18:22:09 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 18:22:10 volumio volumio[1856]: verbose: New Socket.io Connection to 192.168.2.198 from 192.168.2.140 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::volumioGetState Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 18:22:10 volumio volumio[1856]: info: Received Get System Info Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:22:10 volumio volumio[1856]: info: Discovery: Getting this device information Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::volumioGetState Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::volumioGetState Oct 19 18:22:10 volumio volumio[1856]: info: Listing playlists Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:22:10 volumio volumio[1856]: info: CoreStateMachine::getQueue Oct 19 18:22:10 volumio volumio[1856]: info: CorePlayQueue::getQueue Oct 19 18:22:10 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 19 18:22:13 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 19 18:22:15 volumio volumio[1856]: info: Oct 19 18:22:15 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:15 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:15 volumio volumio[1856]: info: Oct 19 18:22:15 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:15 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:15 volumio volumio[1856]: info: Oct 19 18:22:15 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:15 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:15 volumio volumio[1856]: info: Oct 19 18:22:15 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:15 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:15 volumio volumio[1856]: info: ------------------------------ 11ms Oct 19 18:22:15 volumio volumio[1856]: info: ------------------------------ 9ms Oct 19 18:22:15 volumio volumio[1856]: info: ------------------------------ 7ms Oct 19 18:22:15 volumio volumio[1856]: info: Oct 19 18:22:15 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:15 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:15 volumio volumio[1856]: info: Oct 19 18:22:15 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:15 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:15 volumio volumio[1856]: info: sendMpdCommand status took 9 milliseconds Oct 19 18:22:15 volumio volumio[1856]: info: sendMpdCommand status took 6 milliseconds Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:15 volumio volumio[1856]: info: sendMpdCommand status took 15 milliseconds Oct 19 18:22:15 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 19 18:22:15 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:15 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:15 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:15 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12729,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nickelback - How you remind me","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:15 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:15 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:15 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:15 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:15 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12729,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nickelback - How you remind me","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:15 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:15 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:15 volumio volumio[1856]: info: ------------------------------ 105ms Oct 19 18:22:15 volumio volumio[1856]: info: ------------------------------ 100ms Oct 19 18:22:15 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 19 18:22:15 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:15 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:15 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:15 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12729,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nickelback - How you remind me","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:15 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:15 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:15 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:15 volumio volumio[1856]: info: ------------------------------ 164ms Oct 19 18:22:15 volumio volumio[1856]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ANTENNE%20BAYERN/22a59814-0366-4485-ab4a-17df2d27ba25.jpg' Oct 19 18:22:17 volumio volumio[1856]: info: Oct 19 18:22:17 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:17 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:17 volumio volumio[1856]: info: Oct 19 18:22:17 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:17 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:17 volumio volumio[1856]: info: Oct 19 18:22:17 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:17 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:17 volumio volumio[1856]: info: Oct 19 18:22:17 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:17 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:17 volumio volumio[1856]: info: ------------------------------ 11ms Oct 19 18:22:17 volumio volumio[1856]: info: sendMpdCommand status took 8 milliseconds Oct 19 18:22:17 volumio volumio[1856]: info: ------------------------------ 7ms Oct 19 18:22:17 volumio volumio[1856]: info: sendMpdCommand status took 5 milliseconds Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:17 volumio volumio[1856]: info: Oct 19 18:22:17 volumio volumio[1856]: ---------------------------- MPD announces system playlist update Oct 19 18:22:17 volumio volumio[1856]: info: Ignoring MPD Status Update Oct 19 18:22:17 volumio volumio[1856]: info: Oct 19 18:22:17 volumio volumio[1856]: ---------------------------- MPD announces state update: player Oct 19 18:22:17 volumio volumio[1856]: info: ControllerMpd::getState Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:22:17 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 19 18:22:17 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 19 18:22:17 volumio volumio[1856]: info: ------------------------------ 7ms Oct 19 18:22:17 volumio volumio[1856]: info: sendMpdCommand status took 4 milliseconds Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::parseState Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 18:22:17 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:17 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:17 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nickelback - How you remind me","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:17 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:17 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:17 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:17 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:17 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nickelback - How you remind me","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:17 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:17 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:17 volumio volumio[1856]: info: ------------------------------ 112ms Oct 19 18:22:17 volumio volumio[1856]: info: ------------------------------ 108ms Oct 19 18:22:17 volumio volumio[1856]: info: sendMpdCommand playlistinfo took 79 milliseconds Oct 19 18:22:17 volumio volumio[1856]: verbose: ControllerMpd::parseTrackInfo Oct 19 18:22:17 volumio volumio[1856]: info: ControllerMpd::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::servicePushState Oct 19 18:22:17 volumio volumio[1856]: info: CorePlayQueue::getTrack 0 Oct 19 18:22:17 volumio volumio[1856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nickelback - How you remind me","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Oct 19 18:22:17 volumio volumio[1856]: verbose: CURRENT POSITION 0 Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::syncState stateService play Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::syncState currentStatus play Oct 19 18:22:17 volumio volumio[1856]: info: Received an update from plugin. extracting info from payload Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreStateMachine::pushState Oct 19 18:22:17 volumio volumio[1856]: info: CoreCommandRouter::volumioPushState Oct 19 18:22:17 volumio volumio[1856]: info: ------------------------------ 150ms Oct 19 18:22:18 volumio volumio[1856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 18:22:18 volumio volumio[1856]: Error: connect ETIMEDOUT 128.242.240.29:80 Oct 19 18:22:18 volumio volumio[1856]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 19 18:22:18 volumio volumio[1856]: errno: -110, Oct 19 18:22:18 volumio volumio[1856]: code: 'ETIMEDOUT', Oct 19 18:22:18 volumio volumio[1856]: syscall: 'connect', Oct 19 18:22:18 volumio volumio[1856]: address: '128.242.240.29', Oct 19 18:22:18 volumio volumio[1856]: port: 80 Oct 19 18:22:18 volumio volumio[1856]: } Oct 19 18:22:18 volumio volumio[1856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 18:22:19 volumio sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 18:21 Oct 19 18:22:19 volumio sudo[2219]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"