-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sun 2025-03-09 14:31:42 CST. -- Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:30:00 volumio3 volumio[1636]: info: CorePlayQueue::clearPlayQueue Mar 09 14:30:00 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::addQueueItems Mar 09 14:30:00 volumio3 volumio[1636]: info: CorePlayQueue::addQueueItems Mar 09 14:30:00 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:30:00 volumio3 volumio[1636]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540837 Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:30:00 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:30:00 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPlay Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::play index 0 Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::play index undefined Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:00 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::startPlaybackTimer Mar 09 14:30:00 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:00 volumio3 volumio[1636]: info: [1741501800038] ControllerWebradio::clearAddPlayTrack Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:30:00 volumio3 volumio[1636]: info: Mar 09 14:30:00 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand stop took 70 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand stop took 33 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 14:30:00 volumio3 volumio[1636]: info: Mar 09 14:30:00 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:00 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand status took 5 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand clear took 4 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540837" Mar 09 14:30:00 volumio3 volumio[1636]: error: updateQueue error: null Mar 09 14:30:00 volumio3 volumio[1636]: info: ------------------------------ 10ms Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:00 volumio3 volumio[1636]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 09 14:30:00 volumio3 volumio[1636]: info: ------------------------------ 17ms Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540837" took 254 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand play Mar 09 14:30:00 volumio3 volumio[1636]: info: Mar 09 14:30:00 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:00 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand play took 2 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: info: ------------------------------ 3ms Mar 09 14:30:00 volumio3 volumio[1636]: info: Mar 09 14:30:00 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:00 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand status took 13 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand clearerror took 3 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 09 14:30:00 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:00 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:00 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:00 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"Qfm Jazz","album":null,"uri":"http://51.91.82.7:5092/stream","trackType":""} Mar 09 14:30:00 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService stop Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus stop Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:00 volumio3 volumio[1636]: info: No code Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:00 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:00 volumio3 volumio[1636]: info: ------------------------------ 85ms Mar 09 14:30:03 volumio3 ntpd[658]: 84.16.67.12 local addr 192.168.1.105 -> Mar 09 14:30:06 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::ClearQueue Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:06 volumio3 volumio[1636]: info: CorePlayQueue::clearPlayQueue Mar 09 14:30:06 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::addQueueItems Mar 09 14:30:06 volumio3 volumio[1636]: info: CorePlayQueue::addQueueItems Mar 09 14:30:06 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:30:06 volumio3 volumio[1636]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1319410 Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:30:06 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:30:06 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPlay Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::play index 0 Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::play index undefined Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:06 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:06 volumio3 volumio[1636]: info: CoreStateMachine::startPlaybackTimer Mar 09 14:30:06 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:06 volumio3 volumio[1636]: info: [1741501806953] ControllerWebradio::clearAddPlayTrack Mar 09 14:30:06 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:30:06 volumio3 volumio[1636]: info: sendMpdCommand stop took 6 milliseconds Mar 09 14:30:06 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 14:30:06 volumio3 volumio[1636]: info: Mar 09 14:30:06 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:06 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:06 volumio3 volumio[1636]: info: sendMpdCommand clear took 2 milliseconds Mar 09 14:30:06 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1319410" Mar 09 14:30:06 volumio3 volumio[1636]: error: updateQueue error: null Mar 09 14:30:06 volumio3 volumio[1636]: info: ------------------------------ 3ms Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1319410" took 256 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand play Mar 09 14:30:07 volumio3 volumio[1636]: info: Mar 09 14:30:07 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:07 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand play took 2 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: info: ------------------------------ 2ms Mar 09 14:30:07 volumio3 volumio[1636]: info: Mar 09 14:30:07 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:07 volumio3 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Mar 09 14:30:07 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand status took 16 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:07 volumio3 volumio[1636]: info: Mar 09 14:30:07 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:07 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand status took 2 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:07 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:07 volumio3 volumio[1636]: 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":"212.7.194.63:8525","artist":"(#1 - 55/350) Deepinradio_Official","album":null,"uri":"http://212.7.194.63:8525","trackType":"63:8525"} Mar 09 14:30:07 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus stop Mar 09 14:30:07 volumio3 volumio[1636]: info: ------------------------------ 27ms Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:07 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:07 volumio3 volumio[1636]: 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":"212.7.194.63:8525","artist":"(#1 - 55/350) Deepinradio_Official","album":null,"uri":"http://212.7.194.63:8525","trackType":"63:8525"} Mar 09 14:30:07 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:30:07 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:07 volumio3 volumio[1636]: info: ------------------------------ 57ms Mar 09 14:30:07 volumio3 volumio[1636]: info: Mar 09 14:30:07 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:07 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:07 volumio3 volumio[1636]: info: Mar 09 14:30:07 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:07 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:07 volumio3 volumio[1636]: info: ------------------------------ 6ms Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand status took 3 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:07 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:07 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"212.7.194.63:8525","artist":"Deepinradio_Official","album":null,"uri":"http://212.7.194.63:8525","trackType":"63:8525"} Mar 09 14:30:07 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:30:07 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:07 volumio3 volumio[1636]: info: ------------------------------ 59ms Mar 09 14:30:07 volumio3 volumio[1636]: info: Mar 09 14:30:07 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:07 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:07 volumio3 volumio[1636]: info: Mar 09 14:30:07 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:07 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:07 volumio3 volumio[1636]: info: ------------------------------ 4ms Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand status took 2 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:07 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 09 14:30:07 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:07 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:07 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1188,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Alexander Bollinger - Bringing Life (Original Mix)","artist":"Deepinradio_Official","album":null,"uri":"http://212.7.194.63:8525","trackType":"63:8525"} Mar 09 14:30:07 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:30:07 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:07 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:08 volumio3 volumio[1636]: info: ------------------------------ 65ms Mar 09 14:30:16 volumio3 volumio[1636]: info: Saving local item radio-favourites Mar 09 14:30:16 volumio3 volumio[1636]: info: Pushing Favourites {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1319410","favourite":false} Mar 09 14:30:35 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::ClearQueue Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::stPlaybackTimer Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::serviceStop Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreCommandRouter::serviceStop Mar 09 14:30:35 volumio3 volumio[1636]: info: [1741501835748] ControllerWebradio::stop Mar 09 14:30:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::clearPlayQueue Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::addQueueItems Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::addQueueItems Mar 09 14:30:35 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:30:35 volumio3 volumio[1636]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1814839 Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPlay Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::play index 0 Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::play index undefined Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:35 volumio3 volumio[1636]: info: CoreStateMachine::startPlaybackTimer Mar 09 14:30:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:35 volumio3 volumio[1636]: info: [1741501835775] ControllerWebradio::clearAddPlayTrack Mar 09 14:30:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:30:35 volumio3 volumio[1636]: info: Mar 09 14:30:35 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:35 volumio3 volumio[1636]: info: sendMpdCommand stop took 113 milliseconds Mar 09 14:30:35 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:35 volumio3 volumio[1636]: info: sendMpdCommand stop took 87 milliseconds Mar 09 14:30:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 14:30:35 volumio3 volumio[1636]: info: Mar 09 14:30:35 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:35 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:35 volumio3 volumio[1636]: info: sendMpdCommand status took 4 milliseconds Mar 09 14:30:35 volumio3 volumio[1636]: info: sendMpdCommand clear took 2 milliseconds Mar 09 14:30:35 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1814839" Mar 09 14:30:35 volumio3 volumio[1636]: error: updateQueue error: null Mar 09 14:30:35 volumio3 volumio[1636]: info: ------------------------------ 5ms Mar 09 14:30:35 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 09 14:30:35 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:35 volumio3 volumio[1636]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 09 14:30:35 volumio3 volumio[1636]: info: ------------------------------ 11ms Mar 09 14:30:36 volumio3 volumio[1636]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1814839" took 256 milliseconds Mar 09 14:30:36 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 09 14:30:36 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand play Mar 09 14:30:36 volumio3 volumio[1636]: info: sendMpdCommand play took 0 milliseconds Mar 09 14:30:52 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::ClearQueue Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:52 volumio3 volumio[1636]: info: CorePlayQueue::clearPlayQueue Mar 09 14:30:52 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::addQueueItems Mar 09 14:30:52 volumio3 volumio[1636]: info: CorePlayQueue::addQueueItems Mar 09 14:30:52 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:30:52 volumio3 volumio[1636]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99455576 Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:30:52 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:30:52 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPlay Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::play index 0 Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::play index undefined Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:30:52 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::startPlaybackTimer Mar 09 14:30:52 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:52 volumio3 volumio[1636]: info: [1741501852139] ControllerWebradio::clearAddPlayTrack Mar 09 14:30:52 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:30:52 volumio3 volumio[1636]: info: sendMpdCommand stop took 5 milliseconds Mar 09 14:30:52 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 14:30:52 volumio3 volumio[1636]: info: Mar 09 14:30:52 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:52 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:52 volumio3 volumio[1636]: info: sendMpdCommand clear took 1 milliseconds Mar 09 14:30:52 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99455576" Mar 09 14:30:52 volumio3 volumio[1636]: error: updateQueue error: null Mar 09 14:30:52 volumio3 volumio[1636]: info: ------------------------------ 4ms Mar 09 14:30:52 volumio3 volumio[1636]: info: Mar 09 14:30:52 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:52 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:52 volumio3 volumio[1636]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99455576" took 254 milliseconds Mar 09 14:30:52 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 09 14:30:52 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand play Mar 09 14:30:52 volumio3 volumio[1636]: info: ------------------------------ 4ms Mar 09 14:30:52 volumio3 volumio[1636]: info: sendMpdCommand play took 2 milliseconds Mar 09 14:30:52 volumio3 volumio[1636]: info: Mar 09 14:30:52 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:52 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:52 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:52 volumio3 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Mar 09 14:30:53 volumio3 volumio[1636]: info: Mar 09 14:30:53 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:53 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:53 volumio3 volumio[1636]: info: Mar 09 14:30:53 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:53 volumio3 volumio[1636]: info: sendMpdCommand status took 18 milliseconds Mar 09 14:30:53 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:53 volumio3 volumio[1636]: info: ------------------------------ 5ms Mar 09 14:30:53 volumio3 volumio[1636]: info: sendMpdCommand status took 2 milliseconds Mar 09 14:30:53 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:53 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:53 volumio3 volumio[1636]: 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":"64 Kbps","isStreaming":false,"title":"","artist":"Punjab Rocks Radio","album":null,"uri":"http://192.187.125.218:9988/","trackType":"218:9988/"} Mar 09 14:30:53 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus stop Mar 09 14:30:53 volumio3 volumio[1636]: info: ------------------------------ 26ms Mar 09 14:30:53 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:53 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:53 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"","artist":"Punjab Rocks Radio","album":null,"uri":"http://192.187.125.218:9988/","trackType":"218:9988/"} Mar 09 14:30:53 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:30:53 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:53 volumio3 volumio[1636]: info: ------------------------------ 34ms Mar 09 14:30:53 volumio3 volumio[1636]: info: Mar 09 14:30:53 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:30:53 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:30:53 volumio3 volumio[1636]: info: Mar 09 14:30:53 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:30:53 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:30:53 volumio3 volumio[1636]: info: ------------------------------ 3ms Mar 09 14:30:53 volumio3 volumio[1636]: info: sendMpdCommand status took 2 milliseconds Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:30:53 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 09 14:30:53 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:30:53 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:30:53 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1188,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Punjab Rocks Radio - Branda Da Craze - Romie Gill","artist":"Punjab Rocks Radio","album":null,"uri":"http://192.187.125.218:9988/","trackType":"218:9988/"} Mar 09 14:30:53 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:30:53 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:30:53 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:30:53 volumio3 volumio[1636]: info: ------------------------------ 38ms Mar 09 14:31:08 volumio3 ntpd[658]: 162.159.200.123 local addr 192.168.1.105 -> Mar 09 14:31:22 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::ClearQueue Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::stPlaybackTimer Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::serviceStop Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreCommandRouter::serviceStop Mar 09 14:31:22 volumio3 volumio[1636]: info: [1741501882327] ControllerWebradio::stop Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::clearPlayQueue Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::addQueueItems Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::addQueueItems Mar 09 14:31:22 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:31:22 volumio3 volumio[1636]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99547809 Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPlay Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::play index 0 Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::play index undefined Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::startPlaybackTimer Mar 09 14:31:22 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:22 volumio3 volumio[1636]: info: [1741501882352] ControllerWebradio::clearAddPlayTrack Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:31:22 volumio3 volumio[1636]: info: sendMpdCommand stop took 89 milliseconds Mar 09 14:31:22 volumio3 volumio[1636]: info: Mar 09 14:31:22 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:22 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:22 volumio3 volumio[1636]: info: sendMpdCommand stop took 66 milliseconds Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 14:31:22 volumio3 volumio[1636]: info: Mar 09 14:31:22 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:31:22 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:31:22 volumio3 volumio[1636]: info: sendMpdCommand status took 3 milliseconds Mar 09 14:31:22 volumio3 volumio[1636]: info: sendMpdCommand clear took 2 milliseconds Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99547809" Mar 09 14:31:22 volumio3 volumio[1636]: error: updateQueue error: null Mar 09 14:31:22 volumio3 volumio[1636]: info: ------------------------------ 5ms Mar 09 14:31:22 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:22 volumio3 volumio[1636]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 09 14:31:22 volumio3 volumio[1636]: info: ------------------------------ 10ms Mar 09 14:31:22 volumio3 volumio[1636]: info: Mar 09 14:31:22 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:31:22 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:31:22 volumio3 volumio[1636]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99547809" took 262 milliseconds Mar 09 14:31:22 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 09 14:31:22 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand play Mar 09 14:31:22 volumio3 volumio[1636]: info: ------------------------------ 4ms Mar 09 14:31:22 volumio3 volumio[1636]: info: sendMpdCommand play took 2 milliseconds Mar 09 14:31:23 volumio3 volumio[1636]: info: Mar 09 14:31:23 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:23 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:23 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:23 volumio3 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Mar 09 14:31:23 volumio3 volumio[1636]: info: Mar 09 14:31:23 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:23 volumio3 volumio[1636]: info: sendMpdCommand status took 15 milliseconds Mar 09 14:31:23 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:23 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:23 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:23 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:23 volumio3 volumio[1636]: info: sendMpdCommand status took 2 milliseconds Mar 09 14:31:23 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 14:31:23 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:23 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:23 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:23 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:31:23 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:23 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 77/250) Classic Hits 109 - The 70s and 80s","album":null,"uri":"http://144.217.79.175:6982/stream","trackType":""} Mar 09 14:31:23 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus stop Mar 09 14:31:23 volumio3 volumio[1636]: info: ------------------------------ 27ms Mar 09 14:31:23 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 09 14:31:23 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:23 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:31:23 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:23 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 77/250) Classic Hits 109 - The 70s and 80s","album":null,"uri":"http://144.217.79.175:6982/stream","trackType":""} Mar 09 14:31:23 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:31:23 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:23 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:23 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:23 volumio3 volumio[1636]: info: ------------------------------ 38ms Mar 09 14:31:24 volumio3 volumio[1636]: info: Mar 09 14:31:24 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:31:24 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:31:24 volumio3 volumio[1636]: info: Mar 09 14:31:24 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:24 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:24 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:24 volumio3 volumio[1636]: info: Mar 09 14:31:24 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:31:24 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:31:24 volumio3 volumio[1636]: info: Mar 09 14:31:24 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:24 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:24 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:24 volumio3 volumio[1636]: info: ------------------------------ 6ms Mar 09 14:31:24 volumio3 volumio[1636]: info: sendMpdCommand status took 5 milliseconds Mar 09 14:31:24 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:24 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:24 volumio3 volumio[1636]: info: ------------------------------ 6ms Mar 09 14:31:24 volumio3 volumio[1636]: info: sendMpdCommand status took 5 milliseconds Mar 09 14:31:24 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 14:31:24 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:24 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:24 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:24 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:24 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pointer Sisters - Automatic","artist":"Classic Hits 109 - The 70s and 80s","album":null,"uri":"http://144.217.79.175:6982/stream","trackType":""} Mar 09 14:31:24 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:31:24 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:24 volumio3 volumio[1636]: info: ------------------------------ 39ms Mar 09 14:31:24 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 09 14:31:24 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:24 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:24 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pointer Sisters - Automatic","artist":"Classic Hits 109 - The 70s and 80s","album":null,"uri":"http://144.217.79.175:6982/stream","trackType":""} Mar 09 14:31:24 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:31:24 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:24 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:24 volumio3 volumio[1636]: info: ------------------------------ 67ms Mar 09 14:31:35 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::ClearQueue Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::stPlaybackTimer Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::serviceStop Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::serviceStop Mar 09 14:31:35 volumio3 volumio[1636]: info: [1741501895035] ControllerWebradio::stop Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::clearPlayQueue Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::addQueueItems Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::addQueueItems Mar 09 14:31:35 volumio3 volumio[1636]: info: Preload queue cleared Mar 09 14:31:35 volumio3 volumio[1636]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1857193 Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushQueue Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::saveQueue Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::updateTrackBlock Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrackBlock Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPlay Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::play index 0 Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::stop Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::play index undefined Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::startPlaybackTimer Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:35 volumio3 volumio[1636]: info: [1741501895063] ControllerWebradio::clearAddPlayTrack Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand stop Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand stop took 71 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: info: Mar 09 14:31:35 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:35 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand stop took 46 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand clear Mar 09 14:31:35 volumio3 volumio[1636]: info: Mar 09 14:31:35 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:31:35 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand status took 4 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand clear took 3 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1857193" Mar 09 14:31:35 volumio3 volumio[1636]: error: updateQueue error: null Mar 09 14:31:35 volumio3 volumio[1636]: info: ------------------------------ 5ms Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:35 volumio3 volumio[1636]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 09 14:31:35 volumio3 volumio[1636]: info: ------------------------------ 11ms Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1857193" took 261 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand play Mar 09 14:31:35 volumio3 volumio[1636]: info: Mar 09 14:31:35 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:31:35 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand play took 2 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: info: ------------------------------ 2ms Mar 09 14:31:35 volumio3 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Mar 09 14:31:35 volumio3 volumio[1636]: info: Mar 09 14:31:35 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:35 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:35 volumio3 volumio[1636]: info: Mar 09 14:31:35 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand status took 15 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand status took 2 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:35 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:35 volumio3 volumio[1636]: 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":"89.238.227.6:8344","artist":"(#1 - 78/3000) Radio Romania Resita","album":null,"uri":"http://89.238.227.6:8344","trackType":"6:8344"} Mar 09 14:31:35 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus stop Mar 09 14:31:35 volumio3 volumio[1636]: info: ------------------------------ 35ms Mar 09 14:31:35 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 09 14:31:35 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:35 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:35 volumio3 volumio[1636]: 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":"89.238.227.6:8344","artist":"(#1 - 78/3000) Radio Romania Resita","album":null,"uri":"http://89.238.227.6:8344","trackType":"6:8344"} Mar 09 14:31:35 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:31:35 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:35 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:35 volumio3 volumio[1636]: info: ------------------------------ 56ms Mar 09 14:31:36 volumio3 volumio[1636]: info: Mar 09 14:31:36 volumio3 volumio[1636]: ---------------------------- MPD announces system playlist update Mar 09 14:31:36 volumio3 volumio[1636]: info: Ignoring MPD Status Update Mar 09 14:31:36 volumio3 volumio[1636]: info: Mar 09 14:31:36 volumio3 volumio[1636]: ---------------------------- MPD announces state update: player Mar 09 14:31:36 volumio3 volumio[1636]: info: ControllerMpd::getState Mar 09 14:31:36 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand status Mar 09 14:31:36 volumio3 volumio[1636]: info: ------------------------------ 12ms Mar 09 14:31:36 volumio3 volumio[1636]: info: sendMpdCommand status took 2 milliseconds Mar 09 14:31:36 volumio3 volumio[1636]: verbose: ControllerMpd::parseState Mar 09 14:31:36 volumio3 volumio[1636]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 09 14:31:36 volumio3 volumio[1636]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 09 14:31:36 volumio3 volumio[1636]: verbose: ControllerMpd::parseTrackInfo Mar 09 14:31:36 volumio3 volumio[1636]: info: ControllerMpd::pushState Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreCommandRouter::servicePushState Mar 09 14:31:36 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:36 volumio3 volumio[1636]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"89.238.227.6:8344","artist":"Radio Romania Resita","album":null,"uri":"http://89.238.227.6:8344","trackType":"6:8344"} Mar 09 14:31:36 volumio3 volumio[1636]: verbose: CURRENT POSITION 0 Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreStateMachine::syncState stateService play Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreStateMachine::syncState currentStatus play Mar 09 14:31:36 volumio3 volumio[1636]: info: Received an update from plugin. extracting info from payload Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:36 volumio3 volumio[1636]: info: CorePlayQueue::getTrack 0 Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreStateMachine::pushState Mar 09 14:31:36 volumio3 volumio[1636]: info: CoreCommandRouter::volumioPushState Mar 09 14:31:36 volumio3 volumio[1636]: info: ------------------------------ 47ms Mar 09 14:31:41 volumio3 volumio[1636]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 14:31:41 volumio3 volumio[1636]: Error: connect ETIMEDOUT 159.138.20.20:80 Mar 09 14:31:41 volumio3 volumio[1636]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 09 14:31:41 volumio3 volumio[1636]: errno: -110, Mar 09 14:31:41 volumio3 volumio[1636]: code: 'ETIMEDOUT', Mar 09 14:31:41 volumio3 volumio[1636]: syscall: 'connect', Mar 09 14:31:41 volumio3 volumio[1636]: address: '159.138.20.20', Mar 09 14:31:41 volumio3 volumio[1636]: port: 80 Mar 09 14:31:41 volumio3 volumio[1636]: } Mar 09 14:31:41 volumio3 volumio[1636]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 14:31:42 volumio3 sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 14:30 Mar 09 14:31:42 volumio3 sudo[1861]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"