-- Logs begin at Wed 2025-05-21 14:20:00 UTC, end at Wed 2025-05-21 23:01:45 UTC. -- May 21 23:00:00 volumio volumio[1184]: info: May 21 23:00:00 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:00 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:00 volumio volumio[1184]: info: May 21 23:00:00 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:00 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:00 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:00 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:00 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:00 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 23:00:00 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:00 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:00 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:00 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:00 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:00 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:00 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:00 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:00 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:00 volumio volumio[1184]: info: ------------------------------ 21ms May 21 23:00:00 volumio volumio[1184]: info: May 21 23:00:00 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:00 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:00 volumio volumio[1184]: info: May 21 23:00:00 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:00 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:00 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:00 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:00 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:00 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:00 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:00 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:00 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1252,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:00 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:00 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:00 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:00 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:00 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:00 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:00 volumio volumio[1184]: info: ------------------------------ 24ms May 21 23:00:01 volumio volumio[1184]: info: CoreCommandRouter::volumioNext May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::next May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:01 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:01 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::serviceStop May 21 23:00:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:01 volumio volumio[1184]: info: CoreCommandRouter::serviceStop May 21 23:00:01 volumio volumio[1184]: info: [1747868401039] ControllerWebradio::stop May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:01 volumio volumio[1184]: info: May 21 23:00:01 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:01 volumio volumio[1184]: info: sendMpdCommand stop took 17 milliseconds May 21 23:00:01 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:00:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:01 volumio volumio[1184]: info: [1747868401058] ControllerWebradio::clearAddPlayTrack May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:01 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:01 volumio volumio[1184]: info: sendMpdCommand status took 3 milliseconds May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:01 volumio volumio[1184]: info: sendMpdCommand stop took 2 milliseconds May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:00:01 volumio volumio[1184]: info: May 21 23:00:01 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:01 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:01 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:00:01 volumio volumio[1184]: info: sendMpdCommand clear took 1 milliseconds May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99543066" May 21 23:00:01 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:01 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:01 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 21 23:00:01 volumio volumio[1184]: info: ------------------------------ 16ms May 21 23:00:01 volumio volumio[1184]: error: updateQueue error: null May 21 23:00:01 volumio volumio[1184]: info: ------------------------------ 12ms May 21 23:00:01 volumio volumio[1184]: info: May 21 23:00:01 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:01 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:01 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99543066" took 246 milliseconds May 21 23:00:01 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:00:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:00:01 volumio volumio[1184]: info: ------------------------------ 5ms May 21 23:00:01 volumio volumio[1184]: info: sendMpdCommand play took 4 milliseconds May 21 23:00:02 volumio volumio[1184]: info: May 21 23:00:02 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:02 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:02 volumio volumio[1184]: info: May 21 23:00:02 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:02 volumio volumio[1184]: info: sendMpdCommand status took 5 milliseconds May 21 23:00:02 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:02 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:02 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:02 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:02 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:02 volumio volumio[1184]: 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 - 389/6000) Clube FM 100.5 - Ribeir?o Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:02 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:00:02 volumio volumio[1184]: info: ------------------------------ 8ms May 21 23:00:02 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:02 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:02 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:02 volumio volumio[1184]: 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 - 389/6000) Clube FM 100.5 - Ribeir?o Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:02 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:02 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:02 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:02 volumio volumio[1184]: info: ------------------------------ 19ms May 21 23:00:02 volumio volumio[1184]: info: May 21 23:00:02 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:02 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:02 volumio volumio[1184]: info: May 21 23:00:02 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:02 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:02 volumio volumio[1184]: info: ------------------------------ 4ms May 21 23:00:02 volumio volumio[1184]: info: sendMpdCommand status took 3 milliseconds May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:02 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:02 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:02 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:02 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:02 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:02 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:02 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:02 volumio volumio[1184]: info: ------------------------------ 21ms May 21 23:00:02 volumio volumio[1184]: info: May 21 23:00:02 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:02 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:02 volumio volumio[1184]: info: May 21 23:00:02 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:02 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:02 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:02 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:02 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:02 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:02 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:02 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:02 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1252,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:02 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:02 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:02 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:02 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:02 volumio volumio[1184]: info: ------------------------------ 28ms May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 23:00:04 volumio volumio[1184]: info: Discovery: Getting this device information May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::volumioNext May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::next May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:04 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::serviceStop May 21 23:00:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::serviceStop May 21 23:00:04 volumio volumio[1184]: info: [1747868404738] ControllerWebradio::stop May 21 23:00:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:04 volumio volumio[1184]: info: May 21 23:00:04 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:04 volumio volumio[1184]: info: sendMpdCommand stop took 19 milliseconds May 21 23:00:04 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:00:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:04 volumio volumio[1184]: info: [1747868404758] ControllerWebradio::clearAddPlayTrack May 21 23:00:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:04 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:04 volumio volumio[1184]: info: sendMpdCommand status took 2 milliseconds May 21 23:00:04 volumio volumio[1184]: info: sendMpdCommand stop took 1 milliseconds May 21 23:00:04 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:00:04 volumio volumio[1184]: info: May 21 23:00:04 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:04 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:04 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:04 volumio volumio[1184]: info: sendMpdCommand clear took 1 milliseconds May 21 23:00:04 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99543066" May 21 23:00:04 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:04 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:04 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:04 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 21 23:00:04 volumio volumio[1184]: info: ------------------------------ 13ms May 21 23:00:04 volumio volumio[1184]: error: updateQueue error: null May 21 23:00:04 volumio volumio[1184]: info: ------------------------------ 13ms May 21 23:00:05 volumio volumio[1184]: info: May 21 23:00:05 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:05 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:05 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99543066" took 242 milliseconds May 21 23:00:05 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:00:05 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:05 volumio volumio[1184]: info: sendMpdCommand play took 3 milliseconds May 21 23:00:05 volumio volumio[1184]: info: May 21 23:00:05 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:05 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:05 volumio volumio[1184]: info: May 21 23:00:05 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:05 volumio volumio[1184]: info: sendMpdCommand status took 3 milliseconds May 21 23:00:05 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:05 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:05 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:05 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:05 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:05 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:05 volumio volumio[1184]: 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 - 389/6000) Clube FM 100.5 - Ribeir?o Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:05 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:05 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:05 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:00:05 volumio volumio[1184]: info: ------------------------------ 5ms May 21 23:00:05 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:05 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:05 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:05 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:05 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:05 volumio volumio[1184]: 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 - 389/6000) Clube FM 100.5 - Ribeir?o Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:05 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:05 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:05 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:05 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:05 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:05 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:05 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:05 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:05 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:05 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:05 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:05 volumio volumio[1184]: info: ------------------------------ 19ms May 21 23:00:06 volumio volumio[1184]: info: May 21 23:00:06 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:06 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:06 volumio volumio[1184]: info: May 21 23:00:06 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:06 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:06 volumio volumio[1184]: info: ------------------------------ 7ms May 21 23:00:06 volumio volumio[1184]: info: sendMpdCommand status took 6 milliseconds May 21 23:00:06 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:06 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:06 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:06 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:06 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:06 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:06 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:06 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:06 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:06 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:06 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:06 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:06 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:06 volumio volumio[1184]: info: ------------------------------ 45ms May 21 23:00:06 volumio volumio[1184]: info: May 21 23:00:06 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:06 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:06 volumio volumio[1184]: info: May 21 23:00:06 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:06 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:06 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:06 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:06 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:06 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:06 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:06 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:06 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:06 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1252,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:06 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:06 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:06 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:06 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:06 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:06 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:06 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:06 volumio volumio[1184]: info: ------------------------------ 19ms May 21 23:00:07 volumio volumio[1184]: info: CoreCommandRouter::volumioNext May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::next May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:07 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:07 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:07 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:07 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::serviceStop May 21 23:00:07 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:07 volumio volumio[1184]: info: CoreCommandRouter::serviceStop May 21 23:00:07 volumio volumio[1184]: info: [1747868407471] ControllerWebradio::stop May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:07 volumio volumio[1184]: info: May 21 23:00:07 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:07 volumio volumio[1184]: info: sendMpdCommand stop took 17 milliseconds May 21 23:00:07 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:07 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:00:07 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:07 volumio volumio[1184]: info: [1747868407490] ControllerWebradio::clearAddPlayTrack May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:07 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:07 volumio volumio[1184]: info: sendMpdCommand status took 2 milliseconds May 21 23:00:07 volumio volumio[1184]: info: sendMpdCommand stop took 1 milliseconds May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:00:07 volumio volumio[1184]: info: May 21 23:00:07 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:07 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:07 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:07 volumio volumio[1184]: info: sendMpdCommand clear took 1 milliseconds May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99543066" May 21 23:00:07 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:07 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:07 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:07 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:07 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:07 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 21 23:00:07 volumio volumio[1184]: info: ------------------------------ 15ms May 21 23:00:07 volumio volumio[1184]: error: updateQueue error: null May 21 23:00:07 volumio volumio[1184]: info: ------------------------------ 12ms May 21 23:00:07 volumio volumio[1184]: info: May 21 23:00:07 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:07 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:07 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99543066" took 250 milliseconds May 21 23:00:07 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:00:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:00:07 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:07 volumio volumio[1184]: info: sendMpdCommand play took 1 milliseconds May 21 23:00:08 volumio volumio[1184]: info: May 21 23:00:08 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:08 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:08 volumio volumio[1184]: info: May 21 23:00:08 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:08 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds May 21 23:00:08 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:08 volumio volumio[1184]: info: sendMpdCommand status took 3 milliseconds May 21 23:00:08 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:08 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:08 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:08 volumio volumio[1184]: 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 - 389/6000) Clube FM 100.5 - Ribeir?o Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:08 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:00:08 volumio volumio[1184]: info: ------------------------------ 10ms May 21 23:00:08 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:08 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:08 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:08 volumio volumio[1184]: 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 - 389/6000) Clube FM 100.5 - Ribeir?o Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:08 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:08 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:08 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:08 volumio volumio[1184]: info: ------------------------------ 25ms May 21 23:00:08 volumio volumio[1184]: info: May 21 23:00:08 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:08 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:08 volumio volumio[1184]: info: May 21 23:00:08 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:08 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:08 volumio volumio[1184]: info: ------------------------------ 8ms May 21 23:00:08 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:08 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:08 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:08 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:08 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":512,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:08 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:08 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:08 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:08 volumio ntpd[1173]: 116.203.151.74 local addr 192.168.1.188 -> May 21 23:00:08 volumio ntpd[1173]: 193.182.111.142 local addr 192.168.1.188 -> May 21 23:00:08 volumio volumio[1184]: info: ------------------------------ 30ms May 21 23:00:08 volumio volumio[1184]: info: May 21 23:00:08 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:08 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:08 volumio volumio[1184]: info: May 21 23:00:08 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:08 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:08 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:08 volumio volumio[1184]: info: sendMpdCommand status took 5 milliseconds May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:08 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:08 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:08 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:08 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:08 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:08 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:08 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:08 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:08 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:08 volumio volumio[1184]: info: ------------------------------ 26ms May 21 23:00:10 volumio volumio[1184]: info: CoreCommandRouter::volumioNext May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::next May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:10 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:10 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:10 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::serviceStop May 21 23:00:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:10 volumio volumio[1184]: info: CoreCommandRouter::serviceStop May 21 23:00:10 volumio volumio[1184]: info: [1747868410223] ControllerWebradio::stop May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:10 volumio volumio[1184]: info: May 21 23:00:10 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:10 volumio volumio[1184]: info: sendMpdCommand stop took 16 milliseconds May 21 23:00:10 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:00:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:10 volumio volumio[1184]: info: [1747868410241] ControllerWebradio::clearAddPlayTrack May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:10 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:10 volumio volumio[1184]: info: sendMpdCommand status took 2 milliseconds May 21 23:00:10 volumio volumio[1184]: info: sendMpdCommand stop took 1 milliseconds May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:00:10 volumio volumio[1184]: info: May 21 23:00:10 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:10 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:10 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:10 volumio volumio[1184]: info: sendMpdCommand clear took 1 milliseconds May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99543066" May 21 23:00:10 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:10 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:10 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:10 volumio volumio[1184]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 21 23:00:10 volumio volumio[1184]: info: ------------------------------ 15ms May 21 23:00:10 volumio volumio[1184]: error: updateQueue error: null May 21 23:00:10 volumio volumio[1184]: info: ------------------------------ 14ms May 21 23:00:10 volumio volumio[1184]: info: May 21 23:00:10 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:10 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:10 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99543066" took 245 milliseconds May 21 23:00:10 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:00:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:00:10 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:10 volumio volumio[1184]: info: sendMpdCommand play took 2 milliseconds May 21 23:00:11 volumio volumio[1184]: info: May 21 23:00:11 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:11 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:11 volumio volumio[1184]: info: May 21 23:00:11 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:11 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds May 21 23:00:11 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:11 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:11 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:11 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:11 volumio volumio[1184]: 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 - 389/6000) Clube FM 100.5 - Ribeir?o Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:11 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:00:11 volumio volumio[1184]: info: ------------------------------ 9ms May 21 23:00:11 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:11 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:11 volumio volumio[1184]: 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 - 389/6000) Clube FM 100.5 - Ribeir?o Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:11 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:11 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:11 volumio volumio[1184]: info: ------------------------------ 22ms May 21 23:00:11 volumio volumio[1184]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20389%2F6000)%20Clube%20FM%20100.5%20-%20Ribeir%3Fo%20Preto%2FSP/8fb8236b-de48-459e-8373-ac4cf6733618.jpg' May 21 23:00:11 volumio volumio[1184]: info: May 21 23:00:11 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:11 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:11 volumio volumio[1184]: info: May 21 23:00:11 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:11 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:11 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:11 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:11 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:11 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:11 volumio volumio[1184]: 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":"128 Kbps","isStreaming":false,"title":"stream","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:11 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:11 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:11 volumio volumio[1184]: info: ------------------------------ 31ms May 21 23:00:11 volumio volumio[1184]: info: May 21 23:00:11 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:11 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:11 volumio volumio[1184]: info: May 21 23:00:11 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:11 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:11 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:11 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:11 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 23:00:11 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:11 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:11 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":717,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"Clube FM 100.5 - Ribeirão Preto/SP","album":null,"uri":"http://177.124.129.227:8069/stream","trackType":""} May 21 23:00:11 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:11 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:11 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:11 volumio volumio[1184]: info: ------------------------------ 29ms May 21 23:00:18 volumio volumio[1184]: info: Preload queue cleared May 21 23:00:18 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::ClearQueue May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:18 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:18 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::serviceStop May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:18 volumio volumio[1184]: info: CoreCommandRouter::serviceStop May 21 23:00:18 volumio volumio[1184]: info: [1747868418060] ControllerWebradio::stop May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:00:18 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::addQueueItems May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::addQueueItems May 21 23:00:18 volumio volumio[1184]: info: Preload queue cleared May 21 23:00:18 volumio volumio[1184]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1402383 May 21 23:00:18 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 21 23:00:18 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:00:18 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::play index 0 May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:00:18 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:18 volumio volumio[1184]: info: [1747868418076] ControllerWebradio::clearAddPlayTrack May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:00:18 volumio volumio[1184]: info: May 21 23:00:18 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:18 volumio volumio[1184]: info: sendMpdCommand stop took 21 milliseconds May 21 23:00:18 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:18 volumio volumio[1184]: info: sendMpdCommand stop took 5 milliseconds May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:00:18 volumio volumio[1184]: info: May 21 23:00:18 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:18 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:18 volumio volumio[1184]: info: sendMpdCommand status took 2 milliseconds May 21 23:00:18 volumio volumio[1184]: info: sendMpdCommand clear took 1 milliseconds May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1402383" May 21 23:00:18 volumio volumio[1184]: error: updateQueue error: null May 21 23:00:18 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:18 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:18 volumio volumio[1184]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 23:00:18 volumio volumio[1184]: info: ------------------------------ 4ms May 21 23:00:18 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1402383" took 243 milliseconds May 21 23:00:18 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:00:18 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:00:18 volumio volumio[1184]: info: May 21 23:00:18 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:18 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:18 volumio volumio[1184]: info: sendMpdCommand play took 2 milliseconds May 21 23:00:18 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:00:20 volumio volumio[1184]: info: May 21 23:00:20 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:20 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:20 volumio volumio[1184]: info: May 21 23:00:20 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:20 volumio volumio[1184]: info: sendMpdCommand status took 3 milliseconds May 21 23:00:20 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:20 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:20 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:20 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:20 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:20 volumio volumio[1184]: 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":"latino_128","artist":"1.FM - Absolute Pop Latino (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/latino_128","trackType":""} May 21 23:00:20 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:00:20 volumio volumio[1184]: info: ------------------------------ 9ms May 21 23:00:20 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:20 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:20 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:20 volumio volumio[1184]: 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":"latino_128","artist":"1.FM - Absolute Pop Latino (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/latino_128","trackType":""} May 21 23:00:20 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:20 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:20 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:20 volumio volumio[1184]: info: ------------------------------ 31ms May 21 23:00:20 volumio volumio[1184]: info: May 21 23:00:20 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:00:20 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:00:20 volumio volumio[1184]: info: May 21 23:00:20 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:00:20 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:00:20 volumio volumio[1184]: info: ------------------------------ 14ms May 21 23:00:20 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:00:20 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:00:20 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:00:20 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:00:20 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:00:20 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jombriel, DFZM, Jøtta - VITAMINA","artist":"1.FM - Absolute Pop Latino Radio","album":null,"uri":"http://185.33.21.112:80/latino_128","trackType":""} May 21 23:00:20 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:00:20 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:20 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:20 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:20 volumio volumio[1184]: info: ------------------------------ 17ms May 21 23:00:22 volumio volumio[1184]: info: VolumeController::SetAlsaVolume28 May 21 23:00:22 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:22 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:22 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:22 volumio volumio[1184]: info: VolumeController::SetAlsaVolume28 May 21 23:00:22 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:00:22 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:00:22 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:00:28 volumio volumio[1184]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 23:00:28 volumio volumio[1184]: info: Retrying Login Due to Network Error May 21 23:00:29 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:00:29 volumio volumio[1184]: info: Preload queue cleared May 21 23:00:34 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:00:34 volumio volumio[1184]: info: Preload queue cleared May 21 23:00:37 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:00:38 volumio volumio[1184]: info: Preload queue cleared May 21 23:00:38 volumio volumio[1184]: info: MyVolumio login type: Token May 21 23:00:39 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:00:39 volumio volumio[1184]: info: Preload queue cleared May 21 23:00:43 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:00:43 volumio volumio[1184]: info: READ May 21 23:00:43 volumio volumio[1184]: info: Preload queue cleared May 21 23:00:58 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:00:59 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:00 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::ClearQueue May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::serviceStop May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::serviceStop May 21 23:01:00 volumio volumio[1184]: info: [1747868460027] ControllerWebradio::stop May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::addQueueItems May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::addQueueItems May 21 23:01:00 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:00 volumio volumio[1184]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1547530 May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::play index 0 May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:00 volumio volumio[1184]: info: [1747868460043] ControllerWebradio::clearAddPlayTrack May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:01:00 volumio volumio[1184]: info: May 21 23:01:00 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand stop took 20 milliseconds May 21 23:01:00 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand stop took 5 milliseconds May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:01:00 volumio volumio[1184]: info: May 21 23:01:00 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:00 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand clear took 1 milliseconds May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1547530" May 21 23:01:00 volumio volumio[1184]: error: updateQueue error: null May 21 23:01:00 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:01:00 volumio volumio[1184]: info: May 21 23:01:00 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:00 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 239 milliseconds May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1547530" took 240 milliseconds May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:01:00 volumio volumio[1184]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 23:01:00 volumio volumio[1184]: info: ------------------------------ 244ms May 21 23:01:00 volumio volumio[1184]: info: ------------------------------ 3ms May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand play took 1 milliseconds May 21 23:01:00 volumio volumio[1184]: info: May 21 23:01:00 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:00 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:00 volumio volumio[1184]: info: May 21 23:01:00 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds May 21 23:01:00 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:00 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:00 volumio volumio[1184]: 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 - 9/500) Radio Stand By","album":null,"uri":"http://188.165.206.81:8010/stream","trackType":""} May 21 23:01:00 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:01:00 volumio volumio[1184]: info: ------------------------------ 9ms May 21 23:01:00 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:01:00 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:00 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:00 volumio volumio[1184]: 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 - 9/500) Radio Stand By","album":null,"uri":"http://188.165.206.81:8010/stream","trackType":""} May 21 23:01:00 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:01:00 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:00 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:00 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:00 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:00 volumio volumio[1184]: info: ------------------------------ 26ms May 21 23:01:01 volumio volumio[1184]: info: May 21 23:01:01 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:01 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:01 volumio volumio[1184]: info: May 21 23:01:01 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:01 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:01 volumio volumio[1184]: info: May 21 23:01:01 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:01 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:01 volumio volumio[1184]: info: ------------------------------ 7ms May 21 23:01:01 volumio volumio[1184]: info: sendMpdCommand status took 6 milliseconds May 21 23:01:01 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:01 volumio volumio[1184]: info: May 21 23:01:01 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:01 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:01 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:01:01 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:01 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:01 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:01 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":147,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Andrea Burla - Selezione Musicale 06","artist":"Radio Stand By","album":null,"uri":"http://188.165.206.81:8010/stream","trackType":""} May 21 23:01:01 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:01 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:01 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:01:01 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:01:01 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:01 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:01 volumio volumio[1184]: info: ------------------------------ 29ms May 21 23:01:01 volumio volumio[1184]: info: sendMpdCommand status took 21 milliseconds May 21 23:01:01 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:01 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:01 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 10 milliseconds May 21 23:01:01 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:01 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:01 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:01 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":363,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Andrea Burla - Selezione Musicale 06","artist":"Radio Stand By","album":null,"uri":"http://188.165.206.81:8010/stream","trackType":""} May 21 23:01:01 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:01 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:01 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:01:01 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:01:01 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:01 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:01 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:01 volumio volumio[1184]: info: ------------------------------ 51ms May 21 23:01:03 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:03 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::ClearQueue May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:03 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:03 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::serviceStop May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:03 volumio volumio[1184]: info: CoreCommandRouter::serviceStop May 21 23:01:03 volumio volumio[1184]: info: [1747868463928] ControllerWebradio::stop May 21 23:01:03 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:01:03 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::addQueueItems May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::addQueueItems May 21 23:01:03 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:03 volumio volumio[1184]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1471594 May 21 23:01:03 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 21 23:01:03 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:01:03 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::play index 0 May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:03 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:01:03 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:03 volumio volumio[1184]: info: [1747868463944] ControllerWebradio::clearAddPlayTrack May 21 23:01:03 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:01:03 volumio volumio[1184]: info: May 21 23:01:03 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:03 volumio volumio[1184]: info: sendMpdCommand stop took 20 milliseconds May 21 23:01:03 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:03 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:03 volumio volumio[1184]: info: sendMpdCommand stop took 5 milliseconds May 21 23:01:03 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:01:03 volumio volumio[1184]: info: May 21 23:01:03 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:03 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:03 volumio volumio[1184]: info: sendMpdCommand status took 2 milliseconds May 21 23:01:03 volumio volumio[1184]: info: sendMpdCommand clear took 2 milliseconds May 21 23:01:03 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:03 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:03 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1471594" May 21 23:01:03 volumio volumio[1184]: error: updateQueue error: null May 21 23:01:03 volumio volumio[1184]: info: ------------------------------ 4ms May 21 23:01:03 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 23:01:03 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:03 volumio volumio[1184]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 23:01:03 volumio volumio[1184]: info: ------------------------------ 7ms May 21 23:01:04 volumio volumio[1184]: info: May 21 23:01:04 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:04 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1471594" took 238 milliseconds May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:01:04 volumio volumio[1184]: info: ------------------------------ 1ms May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand play took 1 milliseconds May 21 23:01:04 volumio volumio[1184]: info: May 21 23:01:04 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:04 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:04 volumio volumio[1184]: info: May 21 23:01:04 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds May 21 23:01:04 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand status took 0 milliseconds May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:04 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:04 volumio volumio[1184]: 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 - 0/500) Radio Stand By","album":null,"uri":"http://188.165.206.81:8014/stream","trackType":""} May 21 23:01:04 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:01:04 volumio volumio[1184]: info: ------------------------------ 7ms May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:04 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:04 volumio volumio[1184]: 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 - 0/500) Radio Stand By","album":null,"uri":"http://188.165.206.81:8014/stream","trackType":""} May 21 23:01:04 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:01:04 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:04 volumio volumio[1184]: info: ------------------------------ 16ms May 21 23:01:04 volumio volumio[1184]: info: May 21 23:01:04 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:04 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:04 volumio volumio[1184]: info: May 21 23:01:04 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:04 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:04 volumio volumio[1184]: info: ------------------------------ 4ms May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:04 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:04 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio Stand By","album":null,"uri":"http://188.165.206.81:8014/stream","trackType":""} May 21 23:01:04 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:01:04 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:04 volumio volumio[1184]: info: ------------------------------ 17ms May 21 23:01:04 volumio volumio[1184]: info: May 21 23:01:04 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:04 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:04 volumio volumio[1184]: info: May 21 23:01:04 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:04 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:04 volumio volumio[1184]: info: ------------------------------ 11ms May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand status took 12 milliseconds May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:04 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:04 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:04 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:04 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:04 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":918,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Andrea Burla - Selezione Musicale 06","artist":"Radio Stand By","album":null,"uri":"http://188.165.206.81:8014/stream","trackType":""} May 21 23:01:04 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:01:04 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:04 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:04 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:04 volumio volumio[1184]: info: ------------------------------ 31ms May 21 23:01:06 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:06 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::ClearQueue May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:06 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::serviceStop May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:06 volumio volumio[1184]: info: CoreCommandRouter::serviceStop May 21 23:01:06 volumio volumio[1184]: info: [1747868466615] ControllerWebradio::stop May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:01:06 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::addQueueItems May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::addQueueItems May 21 23:01:06 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:06 volumio volumio[1184]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99591135 May 21 23:01:06 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 21 23:01:06 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:01:06 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::play index 0 May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:01:06 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:06 volumio volumio[1184]: info: [1747868466630] ControllerWebradio::clearAddPlayTrack May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:01:06 volumio volumio[1184]: info: May 21 23:01:06 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:06 volumio volumio[1184]: info: sendMpdCommand stop took 20 milliseconds May 21 23:01:06 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:06 volumio volumio[1184]: info: sendMpdCommand stop took 7 milliseconds May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:01:06 volumio volumio[1184]: info: May 21 23:01:06 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:06 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:06 volumio volumio[1184]: info: sendMpdCommand status took 1 milliseconds May 21 23:01:06 volumio volumio[1184]: info: sendMpdCommand clear took 1 milliseconds May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99591135" May 21 23:01:06 volumio volumio[1184]: error: updateQueue error: null May 21 23:01:06 volumio volumio[1184]: info: ------------------------------ 4ms May 21 23:01:06 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 4 milliseconds May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:06 volumio volumio[1184]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 23:01:06 volumio volumio[1184]: info: ------------------------------ 7ms May 21 23:01:06 volumio volumio[1184]: info: May 21 23:01:06 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:06 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:06 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99591135" took 240 milliseconds May 21 23:01:06 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:01:06 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:01:06 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:01:06 volumio volumio[1184]: info: sendMpdCommand play took 1 milliseconds May 21 23:01:07 volumio volumio[1184]: info: May 21 23:01:07 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:07 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clearerror May 21 23:01:07 volumio volumio[1184]: info: sendMpdCommand status took 8 milliseconds May 21 23:01:07 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:07 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:07 volumio volumio[1184]: info: sendMpdCommand clearerror took 1 milliseconds May 21 23:01:07 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:07 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:07 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:07 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:07 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:07 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:07 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:07 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:07 volumio volumio[1184]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 1/200) Radio Telexar","album":null,"uri":"https://37.59.37.173:43478/","trackType":"173:43478/"} May 21 23:01:07 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:07 volumio volumio[1184]: info: CoreStateMachine::syncState stateService stop May 21 23:01:07 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:01:07 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:07 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:07 volumio volumio[1184]: info: No code May 21 23:01:07 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:07 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:07 volumio volumio[1184]: info: ------------------------------ 41ms May 21 23:01:08 volumio volumio[1184]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 23:01:08 volumio volumio[1184]: info: Retrying Login Due to Network Error May 21 23:01:09 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:09 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::ClearQueue May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:09 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue May 21 23:01:09 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:01:09 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::addQueueItems May 21 23:01:09 volumio volumio[1184]: info: CorePlayQueue::addQueueItems May 21 23:01:09 volumio volumio[1184]: info: Preload queue cleared May 21 23:01:09 volumio volumio[1184]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99539838 May 21 23:01:09 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 21 23:01:09 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue May 21 23:01:09 volumio volumio[1184]: info: CorePlayQueue::saveQueue May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock May 21 23:01:09 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock May 21 23:01:09 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::play index 0 May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::stop May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::play index undefined May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:01:09 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:09 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer May 21 23:01:09 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:09 volumio volumio[1184]: info: [1747868469889] ControllerWebradio::clearAddPlayTrack May 21 23:01:09 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:01:09 volumio volumio[1184]: info: sendMpdCommand stop took 6 milliseconds May 21 23:01:09 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:01:09 volumio volumio[1184]: info: May 21 23:01:09 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:09 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:09 volumio volumio[1184]: info: sendMpdCommand clear took 4 milliseconds May 21 23:01:09 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99539838" May 21 23:01:09 volumio volumio[1184]: error: updateQueue error: null May 21 23:01:09 volumio volumio[1184]: info: ------------------------------ 1ms May 21 23:01:10 volumio volumio[1184]: info: May 21 23:01:10 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:10 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:10 volumio volumio[1184]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99539838" took 239 milliseconds May 21 23:01:10 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play May 21 23:01:10 volumio volumio[1184]: info: ------------------------------ 2ms May 21 23:01:10 volumio volumio[1184]: info: sendMpdCommand play took 1 milliseconds May 21 23:01:10 volumio volumio[1184]: info: May 21 23:01:10 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:10 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:10 volumio volumio[1184]: info: May 21 23:01:10 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:10 volumio volumio[1184]: info: sendMpdCommand status took 5 milliseconds May 21 23:01:10 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:10 volumio volumio[1184]: info: sendMpdCommand status took 2 milliseconds May 21 23:01:10 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:10 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:10 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:10 volumio volumio[1184]: 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 - 16/9999) WorldRadio.eu Prague","album":null,"uri":"http://95.216.36.232:8181/stream","trackType":""} May 21 23:01:10 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:10 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:10 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop May 21 23:01:10 volumio volumio[1184]: info: ------------------------------ 9ms May 21 23:01:10 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:10 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:10 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:10 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:10 volumio volumio[1184]: 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 - 16/9999) WorldRadio.eu Prague","album":null,"uri":"http://95.216.36.232:8181/stream","trackType":""} May 21 23:01:10 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:10 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:10 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:01:10 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:01:10 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:01:10 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:10 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:10 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:10 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:10 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:10 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:10 volumio volumio[1184]: info: ------------------------------ 22ms May 21 23:01:11 volumio volumio[1184]: info: May 21 23:01:11 volumio volumio[1184]: ---------------------------- MPD announces system playlist update May 21 23:01:11 volumio volumio[1184]: info: Ignoring MPD Status Update May 21 23:01:11 volumio volumio[1184]: info: May 21 23:01:11 volumio volumio[1184]: ---------------------------- MPD announces state update: player May 21 23:01:11 volumio volumio[1184]: info: ControllerMpd::getState May 21 23:01:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status May 21 23:01:11 volumio volumio[1184]: info: ------------------------------ 13ms May 21 23:01:11 volumio volumio[1184]: info: sendMpdCommand status took 12 milliseconds May 21 23:01:11 volumio volumio[1184]: verbose: ControllerMpd::parseState May 21 23:01:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:01:11 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:01:11 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo May 21 23:01:11 volumio volumio[1184]: info: ControllerMpd::pushState May 21 23:01:11 volumio volumio[1184]: info: CoreCommandRouter::servicePushState May 21 23:01:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 May 21 23:01:11 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1006,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Arif Mardin - Listen Here","artist":"WorldRadio.eu Prague","album":null,"uri":"http://95.216.36.232:8181/stream","trackType":""} May 21 23:01:11 volumio volumio[1184]: verbose: CURRENT POSITION 0 May 21 23:01:11 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play May 21 23:01:11 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play May 21 23:01:11 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload May 21 23:01:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:01:11 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:01:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:11 volumio volumio[1184]: info: CoreStateMachine::pushState May 21 23:01:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState May 21 23:01:11 volumio volumio[1184]: info: ------------------------------ 32ms May 21 23:01:18 volumio volumio[1184]: info: MyVolumio login type: Token May 21 23:01:44 volumio volumio[1184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 23:01:44 volumio volumio[1184]: Error: connect ETIMEDOUT 199.96.62.21:80 May 21 23:01:44 volumio volumio[1184]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 21 23:01:44 volumio volumio[1184]: errno: -110, May 21 23:01:44 volumio volumio[1184]: code: 'ETIMEDOUT', May 21 23:01:44 volumio volumio[1184]: syscall: 'connect', May 21 23:01:44 volumio volumio[1184]: address: '199.96.62.21', May 21 23:01:44 volumio volumio[1184]: port: 80 May 21 23:01:44 volumio volumio[1184]: } May 21 23:01:44 volumio volumio[1184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 23:01:45 volumio sudo[6834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 23:00 May 21 23:01:45 volumio sudo[6834]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"