-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2024-08-26 10:06:57 UTC. -- Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::ClearQueue Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::stop Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:05:09 volumio volumio[2063]: verbose: UNSET VOLATILE Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::stPlaybackTimer Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::updateTrackBlock Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrackBlock Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::serviceStop Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::serviceStop Aug 26 10:05:09 volumio volumio[2063]: info: [1724666709455] ControllerWebradio::stop Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::clearPlayQueue Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::saveQueue Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::addQueueItems Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::addQueueItems Aug 26 10:05:09 volumio volumio[2063]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99596823 Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::saveQueue Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::updateTrackBlock Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrackBlock Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioPlay Aug 26 10:05:09 volumio volumio[2063]: verbose: UNSET VOLATILE Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::play index 0 Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::stop Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:05:09 volumio volumio[2063]: verbose: UNSET VOLATILE Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::play index undefined Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::startPlaybackTimer Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: [1724666709478] ControllerWebradio::clearAddPlayTrack Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand stop took 46 milliseconds Aug 26 10:05:09 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand stop took 25 milliseconds Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand status took 1 milliseconds Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:09 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:09 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:09 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:09 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand clear took 11 milliseconds Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand status took 8 milliseconds Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand status took 8 milliseconds Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand status took 6 milliseconds Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99596823" Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:09 volumio volumio[2063]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 26 10:05:09 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 26 10:05:09 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 26 10:05:09 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:09 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:09 volumio volumio[2063]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 30ms Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 36ms Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 34ms Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 34ms Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:09 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:09 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:09 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:09 volumio volumio[2063]: error: updateQueue error: null Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 35ms Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99596823" took 252 milliseconds Aug 26 10:05:09 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 10:05:09 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand play Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:09 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 228ms Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 227ms Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 227ms Aug 26 10:05:09 volumio volumio[2063]: info: sendMpdCommand play took 4 milliseconds Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:09 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:09 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:09 volumio volumio[2063]: info: Aug 26 10:05:09 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:09 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 7ms Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 5ms Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 3ms Aug 26 10:05:09 volumio volumio[2063]: info: ------------------------------ 2ms Aug 26 10:05:10 volumio volumio[2063]: info: Aug 26 10:05:10 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:10 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:10 volumio volumio[2063]: info: Aug 26 10:05:10 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:10 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:10 volumio volumio[2063]: info: Aug 26 10:05:10 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:10 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand status took 9 milliseconds Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand status took 9 milliseconds Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:10 volumio volumio[2063]: info: Aug 26 10:05:10 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:10 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand status took 19 milliseconds Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand clearerror took 13 milliseconds Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand clearerror took 12 milliseconds Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand status took 7 milliseconds Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:10 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:10 volumio volumio[2063]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 87/450) Nuestra Radio Internacional","album":null,"uri":"https://172.96.11.9:8026/stream","trackType":""} Aug 26 10:05:10 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::syncState stateService stop Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: No code Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:10 volumio volumio[2063]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 87/450) Nuestra Radio Internacional","album":null,"uri":"https://172.96.11.9:8026/stream","trackType":""} Aug 26 10:05:10 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::syncState stateService stop Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: No code Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: ------------------------------ 69ms Aug 26 10:05:10 volumio volumio[2063]: info: ------------------------------ 69ms Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand clearerror took 59 milliseconds Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 58 milliseconds Aug 26 10:05:10 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 57 milliseconds Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:10 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:10 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:10 volumio volumio[2063]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 87/450) Nuestra Radio Internacional","album":null,"uri":"https://172.96.11.9:8026/stream","trackType":""} Aug 26 10:05:10 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::syncState stateService stop Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: No code Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:10 volumio volumio[2063]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 87/450) Nuestra Radio Internacional","album":null,"uri":"https://172.96.11.9:8026/stream","trackType":""} Aug 26 10:05:10 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::syncState stateService stop Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: No code Aug 26 10:05:10 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:10 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:10 volumio volumio[2063]: info: ------------------------------ 121ms Aug 26 10:05:10 volumio volumio[2063]: info: ------------------------------ 108ms Aug 26 10:05:13 volumio volumio[2063]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::ClearQueue Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::stop Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:05:13 volumio volumio[2063]: verbose: UNSET VOLATILE Aug 26 10:05:13 volumio volumio[2063]: info: CorePlayQueue::clearPlayQueue Aug 26 10:05:13 volumio volumio[2063]: info: CorePlayQueue::saveQueue Aug 26 10:05:13 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:13 volumio volumio[2063]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::addQueueItems Aug 26 10:05:13 volumio volumio[2063]: info: CorePlayQueue::addQueueItems Aug 26 10:05:13 volumio volumio[2063]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599208 Aug 26 10:05:13 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 26 10:05:13 volumio volumio[2063]: info: CorePlayQueue::saveQueue Aug 26 10:05:13 volumio volumio[2063]: info: CoreCommandRouter::volumioPushQueue Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::updateTrackBlock Aug 26 10:05:13 volumio volumio[2063]: info: CorePlayQueue::getTrackBlock Aug 26 10:05:13 volumio volumio[2063]: info: CoreCommandRouter::volumioPlay Aug 26 10:05:13 volumio volumio[2063]: verbose: UNSET VOLATILE Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::play index 0 Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::stop Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:05:13 volumio volumio[2063]: verbose: UNSET VOLATILE Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::play index undefined Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 10:05:13 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::startPlaybackTimer Aug 26 10:05:13 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:13 volumio volumio[2063]: info: [1724666713153] ControllerWebradio::clearAddPlayTrack Aug 26 10:05:13 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 10:05:13 volumio volumio[2063]: info: sendMpdCommand stop took 4 milliseconds Aug 26 10:05:13 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 10:05:13 volumio volumio[2063]: info: Aug 26 10:05:13 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:13 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:13 volumio volumio[2063]: info: sendMpdCommand clear took 5 milliseconds Aug 26 10:05:13 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599208" Aug 26 10:05:13 volumio volumio[2063]: info: Aug 26 10:05:13 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:13 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:13 volumio volumio[2063]: info: Aug 26 10:05:13 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:13 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:13 volumio volumio[2063]: info: Aug 26 10:05:13 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:13 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:13 volumio volumio[2063]: error: updateQueue error: null Aug 26 10:05:13 volumio volumio[2063]: info: ------------------------------ 10ms Aug 26 10:05:13 volumio volumio[2063]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99599208" took 253 milliseconds Aug 26 10:05:13 volumio volumio[2063]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 10:05:13 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand play Aug 26 10:05:13 volumio volumio[2063]: info: Aug 26 10:05:13 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:13 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:13 volumio volumio[2063]: info: ------------------------------ 255ms Aug 26 10:05:13 volumio volumio[2063]: info: ------------------------------ 254ms Aug 26 10:05:13 volumio volumio[2063]: info: ------------------------------ 253ms Aug 26 10:05:13 volumio volumio[2063]: info: sendMpdCommand play took 3 milliseconds Aug 26 10:05:13 volumio volumio[2063]: info: Aug 26 10:05:13 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:13 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:13 volumio volumio[2063]: info: Aug 26 10:05:13 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:13 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:13 volumio volumio[2063]: info: Aug 26 10:05:13 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:13 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:13 volumio volumio[2063]: info: ------------------------------ 7ms Aug 26 10:05:13 volumio volumio[2063]: info: ------------------------------ 4ms Aug 26 10:05:13 volumio volumio[2063]: info: ------------------------------ 3ms Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:14 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand status took 16 milliseconds Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:14 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand status took 19 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand status took 17 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand status took 15 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:14 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:14 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Zcarias Ferreira - Si pudiera","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:05:14 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus stop Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 38ms Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:14 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:14 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:05:14 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:05:14 volumio volumio[2063]: info: Aug 26 10:05:14 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 27ms Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand status took 26 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 10ms Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand status took 9 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 8ms Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand status took 7 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 7ms Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand status took 5 milliseconds Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:14 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:14 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Zcarias Ferreira - Si pudiera","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:05:14 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:05:14 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:14 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:14 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Zcarias Ferreira - Si pudiera","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:05:14 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:05:14 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:14 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:14 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":582,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Zcarias Ferreira - Si pudiera","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:05:14 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:05:14 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 103ms Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 102ms Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 100ms Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 74 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 73 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 72 milliseconds Aug 26 10:05:14 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 73 milliseconds Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:14 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:14 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:14 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Zcarias Ferreira - Si pudiera","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:05:14 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:05:14 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:14 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:14 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Zcarias Ferreira - Si pudiera","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:05:14 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:05:14 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:14 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:14 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Zcarias Ferreira - Si pudiera","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:05:14 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:05:14 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:05:14 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:05:14 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Zcarias Ferreira - Si pudiera","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:05:14 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:05:14 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:14 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 167ms Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 149ms Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 148ms Aug 26 10:05:14 volumio volumio[2063]: info: ------------------------------ 147ms Aug 26 10:05:19 volumio volumio[2063]: info: VolumeController::SetAlsaVolume55 Aug 26 10:05:19 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:05:19 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:05:19 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:05:29 volumio nmbd[628]: [2024/08/26 10:05:29.179565, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 26 10:05:29 volumio nmbd[628]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.31.99 for name WORKGROUP<1d>. Aug 26 10:05:29 volumio nmbd[628]: This response was from IP 192.168.31.100, reporting an IP address of 192.168.31.100. Aug 26 10:06:44 volumio volumio[2063]: info: Aug 26 10:06:44 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:06:44 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:06:44 volumio volumio[2063]: info: Aug 26 10:06:44 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:06:44 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:06:44 volumio volumio[2063]: info: Aug 26 10:06:44 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:06:44 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:06:44 volumio volumio[2063]: info: Aug 26 10:06:44 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:06:44 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:06:44 volumio volumio[2063]: info: Aug 26 10:06:44 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:06:44 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:06:44 volumio volumio[2063]: info: Aug 26 10:06:44 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:06:44 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:06:44 volumio volumio[2063]: info: ------------------------------ 9ms Aug 26 10:06:44 volumio volumio[2063]: info: sendMpdCommand status took 8 milliseconds Aug 26 10:06:44 volumio volumio[2063]: info: ------------------------------ 8ms Aug 26 10:06:44 volumio volumio[2063]: info: sendMpdCommand status took 6 milliseconds Aug 26 10:06:44 volumio volumio[2063]: info: ------------------------------ 5ms Aug 26 10:06:44 volumio volumio[2063]: info: sendMpdCommand status took 4 milliseconds Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:06:44 volumio volumio[2063]: info: Aug 26 10:06:44 volumio volumio[2063]: ---------------------------- MPD announces system playlist update Aug 26 10:06:44 volumio volumio[2063]: info: Ignoring MPD Status Update Aug 26 10:06:44 volumio volumio[2063]: info: Aug 26 10:06:44 volumio volumio[2063]: ---------------------------- MPD announces state update: player Aug 26 10:06:44 volumio volumio[2063]: info: ControllerMpd::getState Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand status Aug 26 10:06:44 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 26 10:06:44 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 26 10:06:44 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 10:06:44 volumio volumio[2063]: info: ------------------------------ 4ms Aug 26 10:06:44 volumio volumio[2063]: info: sendMpdCommand status took 3 milliseconds Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::parseState Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 10:06:44 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:06:44 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:06:44 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91382,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Frank Reyes - Ando Buscando Mi Hembra","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:06:44 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:06:44 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:06:44 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:06:44 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:06:44 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91382,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Frank Reyes - Ando Buscando Mi Hembra","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:06:44 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:06:44 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:06:44 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:06:44 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:06:44 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91382,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Frank Reyes - Ando Buscando Mi Hembra","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:06:44 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:06:44 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:06:44 volumio volumio[2063]: info: ------------------------------ 79ms Aug 26 10:06:44 volumio volumio[2063]: info: ------------------------------ 78ms Aug 26 10:06:44 volumio volumio[2063]: info: ------------------------------ 77ms Aug 26 10:06:44 volumio volumio[2063]: info: sendMpdCommand playlistinfo took 59 milliseconds Aug 26 10:06:44 volumio volumio[2063]: verbose: ControllerMpd::parseTrackInfo Aug 26 10:06:44 volumio volumio[2063]: info: ControllerMpd::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::servicePushState Aug 26 10:06:44 volumio volumio[2063]: info: CorePlayQueue::getTrack 0 Aug 26 10:06:44 volumio volumio[2063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91382,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"168 Kbps","isStreaming":false,"title":"Frank Reyes - Ando Buscando Mi Hembra","artist":"Top Bachata Radio","album":null,"uri":"http://217.23.15.43:9002/stream","trackType":""} Aug 26 10:06:44 volumio volumio[2063]: verbose: CURRENT POSITION 0 Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::syncState stateService play Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::syncState currentStatus play Aug 26 10:06:44 volumio volumio[2063]: info: Received an update from plugin. extracting info from payload Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreStateMachine::pushState Aug 26 10:06:44 volumio volumio[2063]: info: CoreCommandRouter::volumioPushState Aug 26 10:06:44 volumio volumio[2063]: info: ------------------------------ 93ms Aug 26 10:06:56 volumio volumio[2063]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 10:06:56 volumio volumio[2063]: Error: connect ETIMEDOUT 128.242.240.155:80 Aug 26 10:06:56 volumio volumio[2063]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 26 10:06:56 volumio volumio[2063]: errno: -110, Aug 26 10:06:56 volumio volumio[2063]: code: 'ETIMEDOUT', Aug 26 10:06:56 volumio volumio[2063]: syscall: 'connect', Aug 26 10:06:56 volumio volumio[2063]: address: '128.242.240.155', Aug 26 10:06:56 volumio volumio[2063]: port: 80 Aug 26 10:06:56 volumio volumio[2063]: } Aug 26 10:06:56 volumio volumio[2063]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 10:06:57 volumio sudo[2310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 10:05 Aug 26 10:06:57 volumio sudo[2310]: 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"