-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2024-11-26 10:54:19 CST. -- Nov 26 10:53:00 volumio volumio[1007]: info: Nov 26 10:53:00 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:00 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:00 volumio volumio[1007]: info: Nov 26 10:53:00 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:00 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:00 volumio volumio[1007]: info: Nov 26 10:53:00 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:00 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:00 volumio volumio[1007]: info: Nov 26 10:53:00 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:00 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:00 volumio volumio[1007]: info: Nov 26 10:53:00 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:00 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:00 volumio volumio[1007]: info: Nov 26 10:53:00 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:00 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:00 volumio volumio[1007]: info: ------------------------------ 3ms Nov 26 10:53:00 volumio volumio[1007]: info: sendMpdCommand status took 3 milliseconds Nov 26 10:53:00 volumio volumio[1007]: info: ------------------------------ 3ms Nov 26 10:53:00 volumio volumio[1007]: info: sendMpdCommand status took 3 milliseconds Nov 26 10:53:00 volumio volumio[1007]: info: ------------------------------ 2ms Nov 26 10:53:00 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:00 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 10:53:00 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 10:53:00 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:00 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:00 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:00 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2686,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"Etkin - Bir Senden Başka","artist":"Arabesk Turk","album":null,"uri":"https://panel.radyomhd.com:8000/","trackType":"com:8000/"} Nov 26 10:53:00 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:00 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:00 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:00 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:00 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2686,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"Etkin - Bir Senden Başka","artist":"Arabesk Turk","album":null,"uri":"https://panel.radyomhd.com:8000/","trackType":"com:8000/"} Nov 26 10:53:00 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:00 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:00 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:00 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:00 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2686,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"Etkin - Bir Senden Başka","artist":"Arabesk Turk","album":null,"uri":"https://panel.radyomhd.com:8000/","trackType":"com:8000/"} Nov 26 10:53:00 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:00 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:00 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:00 volumio volumio[1007]: info: ------------------------------ 19ms Nov 26 10:53:00 volumio volumio[1007]: info: ------------------------------ 18ms Nov 26 10:53:00 volumio volumio[1007]: info: ------------------------------ 17ms Nov 26 10:53:20 volumio volumio[1007]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Nov 26 10:53:20 volumio volumio[1007]: info: Retrying Login Due to Network Error Nov 26 10:53:30 volumio volumio[1007]: info: MyVolumio login type: Token Nov 26 10:53:47 volumio volumio[1007]: info: Preload queue cleared Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::ClearQueue Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::stop Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::stPlaybackTimer Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::updateTrackBlock Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrackBlock Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::serviceStop Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::serviceStop Nov 26 10:53:47 volumio volumio[1007]: info: [1732589627209] ControllerWebradio::stop Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::clearPlayQueue Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::saveQueue Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::volumioPushQueue Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::addQueueItems Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::addQueueItems Nov 26 10:53:47 volumio volumio[1007]: info: Preload queue cleared Nov 26 10:53:47 volumio volumio[1007]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580206 Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::volumioPushQueue Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::saveQueue Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::updateTrackBlock Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrackBlock Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::volumioPlay Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::play index 0 Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::stop Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::play index undefined Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::startPlaybackTimer Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:47 volumio volumio[1007]: info: [1732589627215] ControllerWebradio::clearAddPlayTrack Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand stop Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand stop took 30 milliseconds Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:47 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand stop took 25 milliseconds Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand clear Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:47 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:47 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:47 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand clear took 2 milliseconds Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand status took 1 milliseconds Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand status took 1 milliseconds Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580206" Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:47 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:47 volumio volumio[1007]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 26 10:53:47 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:47 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:47 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:47 volumio volumio[1007]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 9ms Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 8ms Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:47 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:47 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:47 volumio volumio[1007]: error: updateQueue error: null Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 9ms Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:47 volumio volumio[1007]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 12ms Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99580206" took 733 milliseconds Nov 26 10:53:47 volumio volumio[1007]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 26 10:53:47 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand play Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:47 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 728ms Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 727ms Nov 26 10:53:47 volumio volumio[1007]: info: sendMpdCommand play took 2 milliseconds Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:47 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:47 volumio volumio[1007]: info: Nov 26 10:53:47 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:47 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 4ms Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 2ms Nov 26 10:53:47 volumio volumio[1007]: info: ------------------------------ 1ms Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:52 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand status took 15 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand status took 8 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand status took 7 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 3ms Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:52 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:52 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:52 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus stop Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 19ms Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:52 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:52 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:52 volumio volumio[1007]: info: Nov 26 10:53:52 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 2ms Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 2ms Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:52 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:52 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:52 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:52 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:52 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:52 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":598,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:52 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:52 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:52 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:52 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:52 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:52 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 26ms Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 25ms Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 20ms Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 26 10:53:52 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:52 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:52 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:52 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:52 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:52 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:52 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:52 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:52 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:52 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:52 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 27ms Nov 26 10:53:52 volumio volumio[1007]: info: ------------------------------ 26ms Nov 26 10:53:53 volumio volumio[1007]: info: Nov 26 10:53:53 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:53 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:53 volumio volumio[1007]: info: Nov 26 10:53:53 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:53 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:53 volumio volumio[1007]: info: Nov 26 10:53:53 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:53 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:53 volumio volumio[1007]: info: Nov 26 10:53:53 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:53 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:53 volumio volumio[1007]: info: Nov 26 10:53:53 volumio volumio[1007]: ---------------------------- MPD announces system playlist update Nov 26 10:53:53 volumio volumio[1007]: info: Ignoring MPD Status Update Nov 26 10:53:53 volumio volumio[1007]: info: Nov 26 10:53:53 volumio volumio[1007]: ---------------------------- MPD announces state update: player Nov 26 10:53:53 volumio volumio[1007]: info: ControllerMpd::getState Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:53 volumio volumio[1007]: info: ------------------------------ 5ms Nov 26 10:53:53 volumio volumio[1007]: info: sendMpdCommand status took 3 milliseconds Nov 26 10:53:53 volumio volumio[1007]: info: ------------------------------ 3ms Nov 26 10:53:53 volumio volumio[1007]: info: sendMpdCommand status took 2 milliseconds Nov 26 10:53:53 volumio volumio[1007]: info: ------------------------------ 6ms Nov 26 10:53:53 volumio volumio[1007]: info: sendMpdCommand status took 6 milliseconds Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::parseState Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:53 volumio volumio[1007]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 10:53:53 volumio volumio[1007]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:53 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:53 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:53 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1968,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:53 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:53 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:53 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:53 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:53 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1968,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:53 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:53 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:53 volumio volumio[1007]: info: ControllerMpd::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::servicePushState Nov 26 10:53:53 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:53 volumio volumio[1007]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1968,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"93 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"Rádio Bandeirantes Campinas","album":null,"uri":"https://stm23.xcast.com.br:11284/","trackType":"br:11284/"} Nov 26 10:53:53 volumio volumio[1007]: verbose: CURRENT POSITION 0 Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::syncState currentStatus play Nov 26 10:53:53 volumio volumio[1007]: info: Received an update from plugin. extracting info from payload Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreStateMachine::pushState Nov 26 10:53:53 volumio volumio[1007]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:53 volumio volumio[1007]: info: ------------------------------ 31ms Nov 26 10:53:53 volumio volumio[1007]: info: ------------------------------ 29ms Nov 26 10:53:53 volumio volumio[1007]: info: ------------------------------ 28ms Nov 26 10:54:00 volumio volumio[1007]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Nov 26 10:54:00 volumio volumio[1007]: info: Retrying Login Due to Network Error Nov 26 10:54:10 volumio volumio[1007]: info: MyVolumio login type: Token Nov 26 10:54:13 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 26 10:54:13 volumio volumio[1007]: info: CURURI: music-library Nov 26 10:54:13 volumio volumio[1007]: info: Preload queue cleared Nov 26 10:54:18 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 26 10:54:18 volumio volumio[1007]: info: Preload queue cleared Nov 26 10:54:19 volumio volumio[1007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:54:19 volumio volumio[1007]: Error: connect ETIMEDOUT 98.159.108.58:80 Nov 26 10:54:19 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 26 10:54:19 volumio volumio[1007]: errno: -110, Nov 26 10:54:19 volumio volumio[1007]: code: 'ETIMEDOUT', Nov 26 10:54:19 volumio volumio[1007]: syscall: 'connect', Nov 26 10:54:19 volumio volumio[1007]: address: '98.159.108.58', Nov 26 10:54:19 volumio volumio[1007]: port: 80 Nov 26 10:54:19 volumio volumio[1007]: } Nov 26 10:54:19 volumio volumio[1007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:54:19 volumio sudo[23188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 10:53 Nov 26 10:54:19 volumio sudo[23188]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"