-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-10-25 14:10:43 CEST. -- Oct 25 14:09:02 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 25 14:09:48 volumio ntpd[804]: Soliciting pool server 213.209.109.44 Oct 25 14:09:54 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Oct 25 14:10:07 volumio volumio[914]: info: CoreCommandRouter::volumioGetQueue Oct 25 14:10:07 volumio volumio[914]: info: CoreStateMachine::getQueue Oct 25 14:10:07 volumio volumio[914]: info: CorePlayQueue::getQueue Oct 25 14:10:16 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 25 14:10:16 volumio volumio[914]: info: Preload queue cleared Oct 25 14:10:29 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 25 14:10:30 volumio volumio[914]: info: Preload queue cleared Oct 25 14:10:37 volumio volumio[914]: info: Preload queue cleared Oct 25 14:10:37 volumio volumio[914]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::ClearQueue Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::stop Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 14:10:37 volumio volumio[914]: info: CorePlayQueue::clearPlayQueue Oct 25 14:10:37 volumio volumio[914]: info: CorePlayQueue::saveQueue Oct 25 14:10:37 volumio volumio[914]: info: CoreCommandRouter::volumioPushQueue Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::addQueueItems Oct 25 14:10:37 volumio volumio[914]: info: CorePlayQueue::addQueueItems Oct 25 14:10:37 volumio volumio[914]: info: Preload queue cleared Oct 25 14:10:37 volumio volumio[914]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012 Oct 25 14:10:37 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 25 14:10:37 volumio volumio[914]: info: CoreCommandRouter::volumioPushQueue Oct 25 14:10:37 volumio volumio[914]: info: CorePlayQueue::saveQueue Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::updateTrackBlock Oct 25 14:10:37 volumio volumio[914]: info: CorePlayQueue::getTrackBlock Oct 25 14:10:37 volumio volumio[914]: info: CoreCommandRouter::volumioPlay Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::play index 0 Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::stop Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::play index undefined Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 14:10:37 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:37 volumio volumio[914]: info: CoreStateMachine::startPlaybackTimer Oct 25 14:10:37 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:37 volumio volumio[914]: info: [1761394237773] ControllerWebradio::clearAddPlayTrack Oct 25 14:10:37 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 14:10:37 volumio volumio[914]: info: sendMpdCommand stop took 4 milliseconds Oct 25 14:10:37 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 14:10:37 volumio volumio[914]: info: Oct 25 14:10:37 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:37 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:37 volumio volumio[914]: info: sendMpdCommand clear took 3 milliseconds Oct 25 14:10:37 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Oct 25 14:10:37 volumio volumio[914]: info: Oct 25 14:10:37 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:37 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:37 volumio volumio[914]: error: updateQueue error: null Oct 25 14:10:37 volumio volumio[914]: info: ------------------------------ 8ms Oct 25 14:10:38 volumio volumio[914]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 1119 milliseconds Oct 25 14:10:38 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 14:10:38 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand play Oct 25 14:10:38 volumio volumio[914]: info: Oct 25 14:10:38 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:38 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:38 volumio volumio[914]: info: ------------------------------ 1124ms Oct 25 14:10:38 volumio volumio[914]: info: Oct 25 14:10:38 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:38 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:38 volumio volumio[914]: info: sendMpdCommand play took 7 milliseconds Oct 25 14:10:38 volumio volumio[914]: info: ------------------------------ 8ms Oct 25 14:10:38 volumio volumio[914]: info: ------------------------------ 3ms Oct 25 14:10:40 volumio volumio[914]: info: Oct 25 14:10:40 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:40 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:40 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:40 volumio volumio[914]: info: Oct 25 14:10:40 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:40 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:40 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 6 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 5 milliseconds Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:41 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:41 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 18 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 9 milliseconds Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: 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":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: 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":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus play Oct 25 14:10:41 volumio volumio[914]: info: Received an update from plugin. extracting info from payload Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 65ms Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 79ms Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:41 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 68ms Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 64 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 67ms Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 63 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 9ms Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 6 milliseconds Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: 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":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus play Oct 25 14:10:41 volumio volumio[914]: info: Received an update from plugin. extracting info from payload Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus play Oct 25 14:10:41 volumio volumio[914]: info: Received an update from plugin. extracting info from payload Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 138ms Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 126ms Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 59 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 58 milliseconds Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus play Oct 25 14:10:41 volumio volumio[914]: info: Received an update from plugin. extracting info from payload Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus play Oct 25 14:10:41 volumio volumio[914]: info: Received an update from plugin. extracting info from payload Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus play Oct 25 14:10:41 volumio volumio[914]: info: Received an update from plugin. extracting info from payload Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 190ms Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 189ms Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 130ms Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:41 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:41 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:41 volumio volumio[914]: info: Oct 25 14:10:41 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 7ms Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 5 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 5ms Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand status took 3 milliseconds Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 14:10:41 volumio volumio[914]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus play Oct 25 14:10:41 volumio volumio[914]: info: Received an update from plugin. extracting info from payload Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Hier lebt Rock!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Oct 25 14:10:41 volumio volumio[914]: verbose: CURRENT POSITION 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState stateService play Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::syncState currentStatus play Oct 25 14:10:41 volumio volumio[914]: info: Received an update from plugin. extracting info from payload Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 52ms Oct 25 14:10:41 volumio volumio[914]: info: ------------------------------ 50ms Oct 25 14:10:41 volumio volumio[914]: info: Preload queue cleared Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::ClearQueue Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::stop Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::stPlaybackTimer Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::updateTrackBlock Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrackBlock Oct 25 14:10:41 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:41 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 14:10:41 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::serviceStop Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:42 volumio volumio[914]: info: CoreCommandRouter::serviceStop Oct 25 14:10:42 volumio volumio[914]: info: [1761394242001] ControllerWebradio::stop Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::clearPlayQueue Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::saveQueue Oct 25 14:10:42 volumio volumio[914]: info: CoreCommandRouter::volumioPushQueue Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::addQueueItems Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::addQueueItems Oct 25 14:10:42 volumio volumio[914]: info: Preload queue cleared Oct 25 14:10:42 volumio volumio[914]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012 Oct 25 14:10:42 volumio volumio[914]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 25 14:10:42 volumio volumio[914]: info: CoreCommandRouter::volumioPushQueue Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::saveQueue Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::updateTrackBlock Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::getTrackBlock Oct 25 14:10:42 volumio volumio[914]: info: CoreCommandRouter::volumioPlay Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::play index 0 Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::stop Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::play index undefined Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::startPlaybackTimer Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:42 volumio volumio[914]: info: [1761394242027] ControllerWebradio::clearAddPlayTrack Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 14:10:42 volumio volumio[914]: info: Oct 25 14:10:42 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:42 volumio volumio[914]: info: sendMpdCommand stop took 31 milliseconds Oct 25 14:10:42 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:42 volumio volumio[914]: info: sendMpdCommand stop took 7 milliseconds Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 14:10:42 volumio volumio[914]: info: Oct 25 14:10:42 volumio volumio[914]: ---------------------------- MPD announces state update: player Oct 25 14:10:42 volumio volumio[914]: info: ControllerMpd::getState Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 25 14:10:42 volumio volumio[914]: info: Oct 25 14:10:42 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:42 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:42 volumio volumio[914]: info: sendMpdCommand status took 6 milliseconds Oct 25 14:10:42 volumio volumio[914]: info: sendMpdCommand clear took 4 milliseconds Oct 25 14:10:42 volumio volumio[914]: info: sendMpdCommand status took 3 milliseconds Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::parseState Oct 25 14:10:42 volumio volumio[914]: info: ControllerMpd::pushState Oct 25 14:10:42 volumio volumio[914]: info: CoreCommandRouter::servicePushState Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::pushState Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:42 volumio volumio[914]: info: CoreCommandRouter::volumioPushState Oct 25 14:10:42 volumio volumio[914]: info: CorePlayQueue::getTrack 0 Oct 25 14:10:42 volumio volumio[914]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 25 14:10:42 volumio volumio[914]: info: ------------------------------ 19ms Oct 25 14:10:42 volumio volumio[914]: info: Oct 25 14:10:42 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:42 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:42 volumio volumio[914]: error: updateQueue error: null Oct 25 14:10:42 volumio volumio[914]: info: ------------------------------ 20ms Oct 25 14:10:42 volumio volumio[914]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 25 14:10:42 volumio volumio[914]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 14:10:42 volumio volumio[914]: info: ------------------------------ 29ms Oct 25 14:10:42 volumio volumio[914]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 92 milliseconds Oct 25 14:10:42 volumio volumio[914]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 14:10:42 volumio volumio[914]: verbose: ControllerMpd::sendMpdCommand play Oct 25 14:10:42 volumio volumio[914]: info: Oct 25 14:10:42 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:42 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:42 volumio volumio[914]: info: ------------------------------ 82ms Oct 25 14:10:42 volumio volumio[914]: info: sendMpdCommand play took 2 milliseconds Oct 25 14:10:42 volumio volumio[914]: info: Oct 25 14:10:42 volumio volumio[914]: ---------------------------- MPD announces system playlist update Oct 25 14:10:42 volumio volumio[914]: info: Ignoring MPD Status Update Oct 25 14:10:42 volumio volumio[914]: info: ------------------------------ 5ms Oct 25 14:10:42 volumio volumio[914]: info: ------------------------------ 2ms Oct 25 14:10:43 volumio volumio[914]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 14:10:43 volumio volumio[914]: Error: connect ECONNREFUSED 0.0.0.0:443 Oct 25 14:10:43 volumio volumio[914]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 25 14:10:43 volumio volumio[914]: errno: -111, Oct 25 14:10:43 volumio volumio[914]: code: 'ECONNREFUSED', Oct 25 14:10:43 volumio volumio[914]: syscall: 'connect', Oct 25 14:10:43 volumio volumio[914]: address: '0.0.0.0', Oct 25 14:10:43 volumio volumio[914]: port: 443 Oct 25 14:10:43 volumio volumio[914]: } Oct 25 14:10:43 volumio volumio[914]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 14:10:43 volumio sudo[2359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 14:09 Oct 25 14:10:43 volumio sudo[2359]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"