-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2025-03-08 01:03:21 UTC. -- Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::ClearQueue Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::stop Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:02:07 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::stPlaybackTimer Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::updateTrackBlock Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::getTrackBlock Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::serviceStop Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::serviceStop Mar 08 01:02:07 volumio volumio[4205]: info: [1741395727283] ControllerWebradio::stop Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::clearPlayQueue Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::saveQueue Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::volumioPushQueue Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::addQueueItems Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::addQueueItems Mar 08 01:02:07 volumio volumio[4205]: info: Adding Item to queue: http://209.58.147.84:3428/stream Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::saveQueue Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::volumioPushQueue Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::updateTrackBlock Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::getTrackBlock Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::volumioPlay Mar 08 01:02:07 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::play index 0 Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::stop Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:02:07 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::play index undefined Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::startPlaybackTimer Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:07 volumio volumio[4205]: info: [1741395727331] ControllerWebradio::clearAddPlayTrack Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 01:02:07 volumio volumio[4205]: info: Mar 08 01:02:07 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:07 volumio volumio[4205]: info: sendMpdCommand stop took 62 milliseconds Mar 08 01:02:07 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:07 volumio volumio[4205]: info: sendMpdCommand stop took 16 milliseconds Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 01:02:07 volumio volumio[4205]: info: Mar 08 01:02:07 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:07 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:07 volumio volumio[4205]: info: Mar 08 01:02:07 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:07 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:07 volumio volumio[4205]: info: sendMpdCommand status took 14 milliseconds Mar 08 01:02:07 volumio volumio[4205]: info: sendMpdCommand clear took 13 milliseconds Mar 08 01:02:07 volumio volumio[4205]: info: sendMpdCommand status took 11 milliseconds Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand load "http://209.58.147.84:3428/stream" Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:07 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:07 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:07 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:07 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:07 volumio volumio[4205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 08 01:02:07 volumio volumio[4205]: info: ------------------------------ 40ms Mar 08 01:02:07 volumio volumio[4205]: info: Mar 08 01:02:07 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:07 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:07 volumio volumio[4205]: error: updateQueue error: null Mar 08 01:02:07 volumio volumio[4205]: info: ------------------------------ 37ms Mar 08 01:02:07 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 08 01:02:07 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:07 volumio volumio[4205]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 08 01:02:07 volumio volumio[4205]: info: ------------------------------ 56ms Mar 08 01:02:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand add "http://209.58.147.84:3428/stream" Mar 08 01:02:10 volumio volumio[4205]: error: updateQueue error: null Mar 08 01:02:10 volumio volumio[4205]: info: Mar 08 01:02:10 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:10 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:10 volumio volumio[4205]: info: ------------------------------ 3066ms Mar 08 01:02:10 volumio volumio[4205]: info: sendMpdCommand add "http://209.58.147.84:3428/stream" took 5 milliseconds Mar 08 01:02:10 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 01:02:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand play Mar 08 01:02:10 volumio volumio[4205]: info: Mar 08 01:02:10 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:10 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:10 volumio volumio[4205]: info: ------------------------------ 10ms Mar 08 01:02:10 volumio volumio[4205]: info: sendMpdCommand play took 7 milliseconds Mar 08 01:02:10 volumio volumio[4205]: info: ------------------------------ 5ms Mar 08 01:02:11 volumio volumio[4205]: info: Mar 08 01:02:11 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:11 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 08 01:02:11 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:11 volumio volumio[4205]: info: Mar 08 01:02:11 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:11 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:11 volumio volumio[4205]: info: sendMpdCommand status took 19 milliseconds Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:11 volumio volumio[4205]: info: Mar 08 01:02:11 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:11 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:11 volumio volumio[4205]: info: sendMpdCommand status took 21 milliseconds Mar 08 01:02:11 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:11 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:11 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:11 volumio volumio[4205]: 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":null,"album":null,"uri":"http://209.58.147.84:3428/stream","trackType":""} Mar 08 01:02:11 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus stop Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:11 volumio volumio[4205]: info: ------------------------------ 54ms Mar 08 01:02:11 volumio volumio[4205]: info: Mar 08 01:02:11 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:11 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:11 volumio volumio[4205]: info: sendMpdCommand status took 32 milliseconds Mar 08 01:02:11 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 08 01:02:11 volumio volumio[4205]: info: sendMpdCommand status took 4 milliseconds Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:11 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:11 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:11 volumio volumio[4205]: 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":null,"album":null,"uri":"http://209.58.147.84:3428/stream","trackType":""} Mar 08 01:02:11 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:11 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:11 volumio volumio[4205]: info: ------------------------------ 103ms Mar 08 01:02:11 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 55 milliseconds Mar 08 01:02:11 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 54 milliseconds Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:11 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:11 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:11 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:11 volumio volumio[4205]: 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":null,"album":null,"uri":"http://209.58.147.84:3428/stream","trackType":""} Mar 08 01:02:11 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:11 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:11 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:11 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:11 volumio volumio[4205]: 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":null,"album":null,"uri":"http://209.58.147.84:3428/stream","trackType":""} Mar 08 01:02:11 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:11 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:11 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:11 volumio volumio[4205]: info: ------------------------------ 155ms Mar 08 01:02:11 volumio volumio[4205]: info: ------------------------------ 127ms Mar 08 01:02:12 volumio volumio[4205]: info: Mar 08 01:02:12 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:12 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:12 volumio volumio[4205]: info: Mar 08 01:02:12 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:12 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:12 volumio volumio[4205]: info: Mar 08 01:02:12 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:12 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:12 volumio volumio[4205]: info: Mar 08 01:02:12 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:12 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:12 volumio volumio[4205]: info: Mar 08 01:02:12 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:12 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:12 volumio volumio[4205]: info: Mar 08 01:02:12 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:12 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:12 volumio volumio[4205]: info: ------------------------------ 16ms Mar 08 01:02:12 volumio volumio[4205]: info: sendMpdCommand status took 12 milliseconds Mar 08 01:02:12 volumio volumio[4205]: info: ------------------------------ 11ms Mar 08 01:02:12 volumio volumio[4205]: info: sendMpdCommand status took 8 milliseconds Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:12 volumio volumio[4205]: info: Mar 08 01:02:12 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:12 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:12 volumio volumio[4205]: info: Mar 08 01:02:12 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:12 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:12 volumio volumio[4205]: info: ------------------------------ 19ms Mar 08 01:02:12 volumio volumio[4205]: info: sendMpdCommand status took 16 milliseconds Mar 08 01:02:12 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 08 01:02:12 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 08 01:02:12 volumio volumio[4205]: info: ------------------------------ 10ms Mar 08 01:02:12 volumio volumio[4205]: info: sendMpdCommand status took 7 milliseconds Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:12 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:12 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:12 volumio volumio[4205]: 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":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Native Flute","album":null,"uri":"http://209.58.147.84:3428/stream","trackType":""} Mar 08 01:02:12 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:12 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:12 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:12 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:12 volumio volumio[4205]: 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":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Native Flute","album":null,"uri":"http://209.58.147.84:3428/stream","trackType":""} Mar 08 01:02:12 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:12 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:12 volumio volumio[4205]: info: ------------------------------ 99ms Mar 08 01:02:12 volumio volumio[4205]: info: ------------------------------ 95ms Mar 08 01:02:12 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 86 milliseconds Mar 08 01:02:12 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 84 milliseconds Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:12 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:12 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:12 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:12 volumio volumio[4205]: 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":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Native Flute","album":null,"uri":"http://209.58.147.84:3428/stream","trackType":""} Mar 08 01:02:12 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:12 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:12 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:12 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:12 volumio volumio[4205]: 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":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Native Flute","album":null,"uri":"http://209.58.147.84:3428/stream","trackType":""} Mar 08 01:02:12 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:12 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:12 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:12 volumio volumio[4205]: info: ------------------------------ 181ms Mar 08 01:02:12 volumio volumio[4205]: info: ------------------------------ 172ms Mar 08 01:02:14 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 01:02:21 volumio volumio[4205]: info: READ Mar 08 01:02:21 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 01:02:25 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 01:02:47 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 01:02:48 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::ClearQueue Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::stop Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:02:50 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::stPlaybackTimer Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::updateTrackBlock Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::getTrackBlock Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::serviceStop Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::serviceStop Mar 08 01:02:50 volumio volumio[4205]: info: [1741395770136] ControllerWebradio::stop Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::clearPlayQueue Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::saveQueue Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::volumioPushQueue Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::addQueueItems Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::addQueueItems Mar 08 01:02:50 volumio volumio[4205]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99364571 Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::saveQueue Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::volumioPushQueue Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::updateTrackBlock Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::getTrackBlock Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::volumioPlay Mar 08 01:02:50 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::play index 0 Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::stop Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:02:50 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::play index undefined Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::startPlaybackTimer Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:50 volumio volumio[4205]: info: [1741395770199] ControllerWebradio::clearAddPlayTrack Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 01:02:50 volumio volumio[4205]: info: Mar 08 01:02:50 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:50 volumio volumio[4205]: info: sendMpdCommand stop took 81 milliseconds Mar 08 01:02:50 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:50 volumio volumio[4205]: info: sendMpdCommand stop took 20 milliseconds Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 01:02:50 volumio volumio[4205]: info: Mar 08 01:02:50 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:50 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:50 volumio volumio[4205]: info: Mar 08 01:02:50 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:50 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:50 volumio volumio[4205]: info: sendMpdCommand status took 10 milliseconds Mar 08 01:02:50 volumio volumio[4205]: info: sendMpdCommand clear took 9 milliseconds Mar 08 01:02:50 volumio volumio[4205]: info: sendMpdCommand status took 6 milliseconds Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99364571" Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:50 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:50 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:50 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:50 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:50 volumio volumio[4205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 08 01:02:50 volumio volumio[4205]: info: ------------------------------ 34ms Mar 08 01:02:50 volumio volumio[4205]: info: Mar 08 01:02:50 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:50 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:50 volumio volumio[4205]: error: updateQueue error: null Mar 08 01:02:50 volumio volumio[4205]: info: ------------------------------ 42ms Mar 08 01:02:50 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 38 milliseconds Mar 08 01:02:50 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:50 volumio volumio[4205]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 08 01:02:50 volumio volumio[4205]: info: ------------------------------ 57ms Mar 08 01:02:51 volumio volumio[4205]: info: Mar 08 01:02:51 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:51 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:51 volumio volumio[4205]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99364571" took 1178 milliseconds Mar 08 01:02:51 volumio volumio[4205]: info: ------------------------------ 1153ms Mar 08 01:02:51 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 01:02:51 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand play Mar 08 01:02:51 volumio volumio[4205]: info: Mar 08 01:02:51 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:51 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:51 volumio volumio[4205]: info: ------------------------------ 14ms Mar 08 01:02:51 volumio volumio[4205]: info: sendMpdCommand play took 10 milliseconds Mar 08 01:02:51 volumio volumio[4205]: info: ------------------------------ 8ms Mar 08 01:02:53 volumio volumio[4205]: info: Mar 08 01:02:53 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:53 volumio volumio[4205]: info: Mar 08 01:02:53 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:53 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand status took 20 milliseconds Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:53 volumio volumio[4205]: info: Mar 08 01:02:53 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand status took 23 milliseconds Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: 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":"wdbf-lpfm","artist":"Deleted 657687","album":null,"uri":"http://listen.shoutcast.com/wdbf-lpfm","trackType":""} Mar 08 01:02:53 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus stop Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: ------------------------------ 59ms Mar 08 01:02:53 volumio volumio[4205]: info: Mar 08 01:02:53 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand status took 37 milliseconds Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand status took 5 milliseconds Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: 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":"wdbf-lpfm","artist":"Deleted 657687","album":null,"uri":"http://listen.shoutcast.com/wdbf-lpfm","trackType":""} Mar 08 01:02:53 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:53 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: ------------------------------ 101ms Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 47 milliseconds Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 46 milliseconds Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: 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":"wdbf-lpfm","artist":"Deleted 657687","album":null,"uri":"http://listen.shoutcast.com/wdbf-lpfm","trackType":""} Mar 08 01:02:53 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:53 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: 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":"wdbf-lpfm","artist":"Deleted 657687","album":null,"uri":"http://listen.shoutcast.com/wdbf-lpfm","trackType":""} Mar 08 01:02:53 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:53 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: ------------------------------ 160ms Mar 08 01:02:53 volumio volumio[4205]: info: ------------------------------ 127ms Mar 08 01:02:53 volumio volumio[4205]: info: Mar 08 01:02:53 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:53 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:53 volumio volumio[4205]: info: Mar 08 01:02:53 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:53 volumio volumio[4205]: info: Mar 08 01:02:53 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:02:53 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:02:53 volumio volumio[4205]: info: Mar 08 01:02:53 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:02:53 volumio volumio[4205]: info: ------------------------------ 12ms Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand status took 9 milliseconds Mar 08 01:02:53 volumio volumio[4205]: info: ------------------------------ 8ms Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand status took 6 milliseconds Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 01:02:53 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:53 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Cody Jinks - If You've Got the Money I've Got the Time - Cody Jinks Sings Lefty Frizzell","artist":"WDBF-lpFM","album":null,"uri":"http://listen.shoutcast.com/wdbf-lpfm","trackType":""} Mar 08 01:02:53 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:53 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:02:53 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:02:53 volumio volumio[4205]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Cody Jinks - If You've Got the Money I've Got the Time - Cody Jinks Sings Lefty Frizzell","artist":"WDBF-lpFM","album":null,"uri":"http://listen.shoutcast.com/wdbf-lpfm","trackType":""} Mar 08 01:02:53 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:02:53 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:02:53 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:02:53 volumio volumio[4205]: info: ------------------------------ 92ms Mar 08 01:02:53 volumio volumio[4205]: info: ------------------------------ 89ms Mar 08 01:03:05 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 01:03:08 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 01:03:09 volumio volumio[4205]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 01:03:09 volumio volumio[4205]: info: CoreStateMachine::ClearQueue Mar 08 01:03:09 volumio volumio[4205]: info: CoreStateMachine::stop Mar 08 01:03:09 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:03:09 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:03:09 volumio volumio[4205]: info: CoreStateMachine::stPlaybackTimer Mar 08 01:03:09 volumio volumio[4205]: info: CoreStateMachine::updateTrackBlock Mar 08 01:03:09 volumio volumio[4205]: info: CorePlayQueue::getTrackBlock Mar 08 01:03:09 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:09 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:09 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:03:09 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::serviceStop Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::serviceStop Mar 08 01:03:10 volumio volumio[4205]: info: [1741395790001] ControllerWebradio::stop Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::clearPlayQueue Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::saveQueue Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::volumioPushQueue Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::addQueueItems Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::addQueueItems Mar 08 01:03:10 volumio volumio[4205]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99602700 Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::saveQueue Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::volumioPushQueue Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::updateTrackBlock Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::getTrackBlock Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::volumioPlay Mar 08 01:03:10 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::play index 0 Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::stop Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:03:10 volumio volumio[4205]: verbose: UNSET VOLATILE Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::play index undefined Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::startPlaybackTimer Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:10 volumio volumio[4205]: info: [1741395790053] ControllerWebradio::clearAddPlayTrack Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 01:03:10 volumio volumio[4205]: info: sendMpdCommand stop took 92 milliseconds Mar 08 01:03:10 volumio volumio[4205]: info: Mar 08 01:03:10 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:03:10 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:03:10 volumio volumio[4205]: info: sendMpdCommand stop took 44 milliseconds Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 01:03:10 volumio volumio[4205]: info: Mar 08 01:03:10 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:03:10 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:03:10 volumio volumio[4205]: info: Mar 08 01:03:10 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:03:10 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:03:10 volumio volumio[4205]: info: sendMpdCommand status took 8 milliseconds Mar 08 01:03:10 volumio volumio[4205]: info: sendMpdCommand clear took 7 milliseconds Mar 08 01:03:10 volumio volumio[4205]: info: sendMpdCommand status took 5 milliseconds Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99602700" Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:03:10 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:03:10 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:03:10 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:10 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:10 volumio volumio[4205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 08 01:03:10 volumio volumio[4205]: info: ------------------------------ 31ms Mar 08 01:03:10 volumio volumio[4205]: info: Mar 08 01:03:10 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:03:10 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:03:10 volumio volumio[4205]: error: updateQueue error: null Mar 08 01:03:10 volumio volumio[4205]: info: ------------------------------ 32ms Mar 08 01:03:10 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 08 01:03:10 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:03:10 volumio volumio[4205]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 08 01:03:10 volumio volumio[4205]: info: ------------------------------ 44ms Mar 08 01:03:15 volumio volumio[4205]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99602700" took 5299 milliseconds Mar 08 01:03:15 volumio volumio[4205]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 01:03:15 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand play Mar 08 01:03:15 volumio volumio[4205]: info: Mar 08 01:03:15 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:03:15 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:03:15 volumio volumio[4205]: info: ------------------------------ 5282ms Mar 08 01:03:15 volumio volumio[4205]: info: Mar 08 01:03:15 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:03:15 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:03:15 volumio volumio[4205]: info: sendMpdCommand play took 9 milliseconds Mar 08 01:03:15 volumio volumio[4205]: info: ------------------------------ 8ms Mar 08 01:03:15 volumio volumio[4205]: info: ------------------------------ 11ms Mar 08 01:03:16 volumio volumio[4205]: info: Mar 08 01:03:16 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:03:16 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:03:16 volumio volumio[4205]: info: Mar 08 01:03:16 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:03:16 volumio volumio[4205]: info: Mar 08 01:03:16 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand status took 20 milliseconds Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand status took 22 milliseconds Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand status took 6 milliseconds Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: 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":"chanquina","artist":"(#1 - 9/512) Radio Chanquina","album":null,"uri":"http://190.102.225.114:8000/chanquina","trackType":""} Mar 08 01:03:16 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus stop Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: ------------------------------ 62ms Mar 08 01:03:16 volumio volumio[4205]: info: Mar 08 01:03:16 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand status took 6 milliseconds Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: 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":"chanquina","artist":"(#1 - 9/512) Radio Chanquina","album":null,"uri":"http://190.102.225.114:8000/chanquina","trackType":""} Mar 08 01:03:16 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:03:16 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: 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":"chanquina","artist":"(#1 - 9/512) Radio Chanquina","album":null,"uri":"http://190.102.225.114:8000/chanquina","trackType":""} Mar 08 01:03:16 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:03:16 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: ------------------------------ 133ms Mar 08 01:03:16 volumio volumio[4205]: info: ------------------------------ 124ms Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 95 milliseconds Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: 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":"chanquina","artist":"(#1 - 9/512) Radio Chanquina","album":null,"uri":"http://190.102.225.114:8000/chanquina","trackType":""} Mar 08 01:03:16 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:03:16 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: ------------------------------ 140ms Mar 08 01:03:16 volumio volumio[4205]: info: Mar 08 01:03:16 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:03:16 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:03:16 volumio volumio[4205]: info: Mar 08 01:03:16 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:03:16 volumio volumio[4205]: info: Mar 08 01:03:16 volumio volumio[4205]: ---------------------------- MPD announces system playlist update Mar 08 01:03:16 volumio volumio[4205]: info: Ignoring MPD Status Update Mar 08 01:03:16 volumio volumio[4205]: info: Mar 08 01:03:16 volumio volumio[4205]: ---------------------------- MPD announces state update: player Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::getState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand status Mar 08 01:03:16 volumio volumio[4205]: info: ------------------------------ 13ms Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand status took 10 milliseconds Mar 08 01:03:16 volumio volumio[4205]: info: ------------------------------ 9ms Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand status took 5 milliseconds Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseState Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 08 01:03:16 volumio volumio[4205]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:03:16 volumio volumio[4205]: verbose: ControllerMpd::parseTrackInfo Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: 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":"chanquina","artist":"Radio Chanquina","album":null,"uri":"http://190.102.225.114:8000/chanquina","trackType":""} Mar 08 01:03:16 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:03:16 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: ControllerMpd::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::servicePushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: 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":"chanquina","artist":"Radio Chanquina","album":null,"uri":"http://190.102.225.114:8000/chanquina","trackType":""} Mar 08 01:03:16 volumio volumio[4205]: verbose: CURRENT POSITION 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState stateService play Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::syncState currentStatus play Mar 08 01:03:16 volumio volumio[4205]: info: Received an update from plugin. extracting info from payload Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CorePlayQueue::getTrack 0 Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:16 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:16 volumio volumio[4205]: info: ------------------------------ 88ms Mar 08 01:03:16 volumio volumio[4205]: info: ------------------------------ 84ms Mar 08 01:03:20 volumio volumio[4205]: info: VolumeController::SetAlsaVolume65 Mar 08 01:03:20 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:20 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:03:20 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:20 volumio volumio[4205]: info: VolumeController::SetAlsaVolume75 Mar 08 01:03:20 volumio volumio[4205]: info: CoreStateMachine::pushState Mar 08 01:03:20 volumio volumio[4205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 01:03:20 volumio volumio[4205]: info: CoreCommandRouter::volumioPushState Mar 08 01:03:20 volumio volumio[4205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 01:03:20 volumio volumio[4205]: Error: connect ETIMEDOUT 128.242.240.91:80 Mar 08 01:03:20 volumio volumio[4205]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 08 01:03:20 volumio volumio[4205]: errno: -110, Mar 08 01:03:20 volumio volumio[4205]: code: 'ETIMEDOUT', Mar 08 01:03:20 volumio volumio[4205]: syscall: 'connect', Mar 08 01:03:20 volumio volumio[4205]: address: '128.242.240.91', Mar 08 01:03:20 volumio volumio[4205]: port: 80 Mar 08 01:03:20 volumio volumio[4205]: } Mar 08 01:03:20 volumio volumio[4205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 01:03:21 volumio sudo[4478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 01:02 Mar 08 01:03:21 volumio sudo[4478]: 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"