-- Logs begin at Sun 2024-04-07 09:05:27 EEST, end at Mon 2024-04-08 12:08:01 EEST. -- Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::ClearQueue Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stPlaybackTimer Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::updateTrackBlock Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrackBlock Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::serviceStop Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::serviceStop Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: [1712567221052] ControllerWebradio::stop Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::clearPlayQueue Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::addQueueItems Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::addQueueItems Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497990 Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::updateTrackBlock Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrackBlock Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPlay Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index 0 Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index undefined Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::startPlaybackTimer Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioGetVisibleSources Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: [1712567221061] ControllerWebradio::clearAddPlayTrack Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:07:01 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:01 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: sendMpdCommand stop took 14 milliseconds Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: sendMpdCommand stop took 5 milliseconds Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:01 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 4 milliseconds Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: sendMpdCommand clear took 3 milliseconds Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497990" Apr 08 12:07:01 volumio-pi4-aux volumio[759]: error: updateQueue error: null Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: ------------------------------ 3ms Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:01 volumio-pi4-aux volumio[759]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: ------------------------------ 8ms Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:01 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497990" took 101 milliseconds Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 08 12:07:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand play Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:01 volumio-pi4-aux volumio[759]: info: sendMpdCommand play took 2 milliseconds Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:02 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:02 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: 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":"90er-hits","artist":"(#1 - 104/500000) ANTENNE BAYERN 90er Hits (Germany)","album":null,"uri":"http://stream.antenne.de:80/90er-hits","trackType":""} Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus stop Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ------------------------------ 7ms Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: 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":"90er-hits","artist":"(#1 - 104/500000) ANTENNE BAYERN 90er Hits (Germany)","album":null,"uri":"http://stream.antenne.de:80/90er-hits","trackType":""} Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ------------------------------ 15ms Apr 08 12:07:02 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:02 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:02 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ------------------------------ 3ms Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lenny Kravitz - Are you gonna go my way","artist":"ANTENNE BAYERN 90er Hits","album":null,"uri":"http://stream.antenne.de:80/90er-hits","trackType":""} Apr 08 12:07:02 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:02 volumio-pi4-aux volumio[759]: info: ------------------------------ 17ms Apr 08 12:07:02 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:02 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:03 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:03 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:03 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 1 milliseconds Apr 08 12:07:03 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:03 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 12:07:03 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:03 volumio-pi4-aux volumio[759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2235,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lenny Kravitz - Are you gonna go my way","artist":"ANTENNE BAYERN 90er Hits","album":null,"uri":"http://stream.antenne.de:80/90er-hits","trackType":""} Apr 08 12:07:03 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:03 volumio-pi4-aux volumio[759]: info: ------------------------------ 16ms Apr 08 12:07:03 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:03 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::ClearQueue Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stPlaybackTimer Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::updateTrackBlock Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrackBlock Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::serviceStop Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::serviceStop Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: [1712567230837] ControllerWebradio::stop Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::clearPlayQueue Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::addQueueItems Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::addQueueItems Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509444 Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::updateTrackBlock Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrackBlock Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPlay Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index 0 Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index undefined Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::startPlaybackTimer Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioGetVisibleSources Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: [1712567230853] ControllerWebradio::clearAddPlayTrack Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:07:10 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:10 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: sendMpdCommand stop took 21 milliseconds Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: sendMpdCommand stop took 6 milliseconds Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:10 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 4 milliseconds Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: sendMpdCommand clear took 4 milliseconds Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509444" Apr 08 12:07:10 volumio-pi4-aux volumio[759]: error: updateQueue error: null Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:10 volumio-pi4-aux volumio[759]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: ------------------------------ 7ms Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:10 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509444" took 101 milliseconds Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 08 12:07:10 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand play Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:10 volumio-pi4-aux volumio[759]: info: sendMpdCommand play took 1 milliseconds Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:11 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:11 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 3 milliseconds Apr 08 12:07:11 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: sendMpdCommand clearerror took 1 milliseconds Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 12:07:11 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:11 volumio-pi4-aux volumio[759]: 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":"radio.mp3","artist":"(#1 - 37/1000) Best 90s Dance","album":null,"uri":"https://c6.auracast.net:8090/radio.mp3","trackType":"mp3"} Apr 08 12:07:11 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService stop Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus stop Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: No code Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:11 volumio-pi4-aux volumio[759]: info: ------------------------------ 22ms Apr 08 12:07:11 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:11 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:11 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::ClearQueue Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CorePlayQueue::clearPlayQueue Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::addQueueItems Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CorePlayQueue::addQueueItems Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509444 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::updateTrackBlock Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrackBlock Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPlay Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index 0 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index undefined Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::startPlaybackTimer Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioGetVisibleSources Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: [1712567234154] ControllerWebradio::clearAddPlayTrack Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: sendMpdCommand stop took 2 milliseconds Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:14 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: sendMpdCommand clear took 1 milliseconds Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509444" Apr 08 12:07:14 volumio-pi4-aux volumio[759]: error: updateQueue error: null Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:14 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509444" took 101 milliseconds Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand play Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: sendMpdCommand play took 1 milliseconds Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:14 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: sendMpdCommand clearerror took 1 milliseconds Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: 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":"radio.mp3","artist":"(#1 - 31/1000) Best 90s Dance","album":null,"uri":"https://c6.auracast.net:8090/radio.mp3","trackType":"mp3"} Apr 08 12:07:14 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService stop Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus stop Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: No code Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:14 volumio-pi4-aux volumio[759]: info: ------------------------------ 19ms Apr 08 12:07:14 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:14 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::ClearQueue Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::clearPlayQueue Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::addQueueItems Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::addQueueItems Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509442 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::updateTrackBlock Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrackBlock Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPlay Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index 0 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index undefined Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::startPlaybackTimer Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioGetVisibleSources Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: [1712567235394] ControllerWebradio::clearAddPlayTrack Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: sendMpdCommand stop took 2 milliseconds Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:15 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: sendMpdCommand clear took 1 milliseconds Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509442" Apr 08 12:07:15 volumio-pi4-aux volumio[759]: error: updateQueue error: null Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:15 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509442" took 101 milliseconds Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand play Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: sendMpdCommand play took 1 milliseconds Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:15 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:15 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 1 milliseconds Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 1 milliseconds Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: 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":"radio.mp3","artist":"(#1 - 26/750) Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus stop Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: 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":"radio.mp3","artist":"(#1 - 26/750) Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:15 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:15 volumio-pi4-aux volumio[759]: info: ------------------------------ 11ms Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ------------------------------ 13ms Apr 08 12:07:16 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:16 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:16 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:16 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:16 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ------------------------------ 4ms Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ------------------------------ 3ms Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La Bouche - Sweet Dreams (Ola ola e)","artist":"Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ------------------------------ 14ms Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La Bouche - Sweet Dreams (Ola ola e)","artist":"Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:16 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:16 volumio-pi4-aux volumio[759]: info: ------------------------------ 19ms Apr 08 12:07:16 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:16 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:32 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioGetState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: Executing endpoint metavolumio Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: Executing endpoint metavolumio Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioStop Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stPlaybackTimer Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreStateMachine::updateTrackBlock Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrackBlock Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreStateMachine::serviceStop Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::serviceStop Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: [1712567254299] ControllerWebradio::stop Apr 08 12:07:34 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:07:34 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:34 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: sendMpdCommand stop took 11 milliseconds Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:34 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 08 12:07:34 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 08 12:07:34 volumio-pi4-aux volumio[759]: info: ------------------------------ 9ms Apr 08 12:07:34 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:36 volumio-pi4-aux volumio[759]: info: Executing endpoint metavolumio Apr 08 12:07:36 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 08 12:07:36 volumio-pi4-aux volumio[759]: info: Executing endpoint metavolumio Apr 08 12:07:36 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 08 12:07:36 volumio-pi4-aux volumio[759]: error: Failed request for metavolumio API Apr 08 12:07:37 volumio-pi4-aux volumio[759]: info: MRS: STARTING volumioStreaming Apr 08 12:07:37 volumio-pi4-aux sudo[6473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream100.ts /tmp/hls/stream101.ts /tmp/hls/stream102.ts /tmp/hls/stream103.ts /tmp/hls/stream104.ts /tmp/hls/stream105.ts /tmp/hls/stream106.ts /tmp/hls/stream107.ts /tmp/hls/stream108.ts /tmp/hls/stream109.ts /tmp/hls/stream11.ts /tmp/hls/stream110.ts /tmp/hls/stream111.ts /tmp/hls/stream112.ts /tmp/hls/stream113.ts /tmp/hls/stream114.ts /tmp/hls/stream115.ts /tmp/hls/stream116.ts /tmp/hls/stream117.ts /tmp/hls/stream118.ts /tmp/hls/stream119.ts /tmp/hls/stream12.ts /tmp/hls/stream120.ts /tmp/hls/stream121.ts /tmp/hls/stream122.ts /tmp/hls/stream123.ts /tmp/hls/stream124.ts /tmp/hls/stream125.ts /tmp/hls/stream126.ts /tmp/hls/stream127.ts /tmp/hls/stream128.ts /tmp/hls/stream129.ts /tmp/hls/stream13.ts /tmp/hls/stream130.ts /tmp/hls/stream131.ts /tmp/hls/stream132.ts /tmp/hls/stream133.ts Apr 08 12:07:37 volumio-pi4-aux sudo[6473]: volumio : (command continued) /tmp/hls/stream134.ts /tmp/hls/stream135.ts /tmp/hls/stream136.ts /tmp/hls/stream137.ts /tmp/hls/stream138.ts /tmp/hls/stream139.ts /tmp/hls/stream14.ts /tmp/hls/stream140.ts /tmp/hls/stream141.ts /tmp/hls/stream142.ts /tmp/hls/stream143.ts /tmp/hls/stream144.ts /tmp/hls/stream145.ts /tmp/hls/stream146.ts /tmp/hls/stream147.ts /tmp/hls/stream148.ts /tmp/hls/stream149.ts /tmp/hls/stream15.ts /tmp/hls/stream150.ts /tmp/hls/stream151.ts /tmp/hls/stream152.ts /tmp/hls/stream153.ts /tmp/hls/stream154.ts /tmp/hls/stream155.ts /tmp/hls/stream156.ts /tmp/hls/stream157.ts /tmp/hls/stream158.ts /tmp/hls/stream159.ts /tmp/hls/stream16.ts /tmp/hls/stream160.ts /tmp/hls/stream161.ts /tmp/hls/stream162.ts /tmp/hls/stream163.ts /tmp/hls/stream164.ts /tmp/hls/stream165.ts /tmp/hls/stream166.ts /tmp/hls/stream167.ts /tmp/hls/stream168.ts /tmp/hls/stream169.ts /tmp/hls/stream17.ts /tmp/hls/stream170.ts /tmp/hls/stream171.ts Apr 08 12:07:37 volumio-pi4-aux sudo[6473]: volumio : (command continued) /tmp/hls/stream172.ts /tmp/hls/stream173.ts /tmp/hls/stream174.ts /tmp/hls/stream175.ts /tmp/hls/stream176.ts /tmp/hls/stream177.ts /tmp/hls/stream178.ts /tmp/hls/stream179.ts /tmp/hls/stream18.ts /tmp/hls/stream180.ts /tmp/hls/stream181.ts /tmp/hls/stream182.ts /tmp/hls/stream183.ts /tmp/hls/stream184.ts /tmp/hls/stream185.ts /tmp/hls/stream186.ts /tmp/hls/stream187.ts /tmp/hls/stream188.ts /tmp/hls/stream189.ts /tmp/hls/stream19.ts /tmp/hls/stream190.ts /tmp/hls/stream191.ts /tmp/hls/stream192.ts /tmp/hls/stream193.ts /tmp/hls/stream194.ts /tmp/hls/stream195.ts /tmp/hls/stream196.ts /tmp/hls/stream197.ts /tmp/hls/stream198.ts /tmp/hls/stream199.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream200.ts /tmp/hls/stream201.ts /tmp/hls/stream202.ts /tmp/hls/stream203.ts /tmp/hls/stream204.ts /tmp/hls/stream205.ts /tmp/hls/stream206.ts /tmp/hls/stream207.ts /tmp/hls/stream208.ts /tmp/hls/stream209.ts Apr 08 12:07:37 volumio-pi4-aux sudo[6473]: volumio : (command continued) /tmp/hls/stream21.ts /tmp/hls/stream210.ts /tmp/hls/stream211.ts /tmp/hls/stream212.ts /tmp/hls/stream213.ts /tmp/hls/stream214.ts /tmp/hls/stream215.ts /tmp/hls/stream216.ts /tmp/hls/stream217.ts /tmp/hls/stream218.ts /tmp/hls/stream219.ts /tmp/hls/stream22.ts /tmp/hls/stream220.ts /tmp/hls/stream221.ts /tmp/hls/stream222.ts /tmp/hls/stream223.ts /tmp/hls/stream224.ts /tmp/hls/stream225.ts /tmp/hls/stream226.ts /tmp/hls/stream227.ts /tmp/hls/stream228.ts /tmp/hls/stream229.ts /tmp/hls/stream23.ts /tmp/hls/stream230.ts /tmp/hls/stream231.ts /tmp/hls/stream232.ts /tmp/hls/stream233.ts /tmp/hls/stream234.ts /tmp/hls/stream235.ts /tmp/hls/stream236.ts /tmp/hls/stream237.ts /tmp/hls/stream238.ts /tmp/hls/stream239.ts /tmp/hls/stream24.ts /tmp/hls/stream240.ts /tmp/hls/stream241.ts /tmp/hls/stream242.ts /tmp/hls/stream243.ts /tmp/hls/stream244.ts /tmp/hls/stream245.ts /tmp/hls/stream246.ts /tmp/hls/stream25.ts Apr 08 12:07:37 volumio-pi4-aux sudo[6473]: volumio : (command continued) /tmp/hls/stream26.ts /tmp/hls/stream27.ts /tmp/hls/stream28.ts /tmp/hls/stream29.ts /tmp/hls/stream3.ts /tmp/hls/stream30.ts /tmp/hls/stream31.ts /tmp/hls/stream32.ts /tmp/hls/stream33.ts /tmp/hls/stream34.ts /tmp/hls/stream35.ts /tmp/hls/stream36.ts /tmp/hls/stream37.ts /tmp/hls/stream38.ts /tmp/hls/stream39.ts /tmp/hls/stream4.ts /tmp/hls/stream40.ts /tmp/hls/stream41.ts /tmp/hls/stream42.ts /tmp/hls/stream43.ts /tmp/hls/stream44.ts /tmp/hls/stream45.ts /tmp/hls/stream46.ts /tmp/hls/stream47.ts /tmp/hls/stream48.ts /tmp/hls/stream49.ts /tmp/hls/stream5.ts /tmp/hls/stream50.ts /tmp/hls/stream51.ts /tmp/hls/stream52.ts /tmp/hls/stream53.ts /tmp/hls/stream54.ts /tmp/hls/stream55.ts /tmp/hls/stream56.ts /tmp/hls/stream57.ts /tmp/hls/stream58.ts /tmp/hls/stream59.ts /tmp/hls/stream6.ts /tmp/hls/stream60.ts /tmp/hls/stream61.ts /tmp/hls/stream62.ts /tmp/hls/stream63.ts /tmp/hls/stream64.ts /tmp/hls/stream65.ts Apr 08 12:07:37 volumio-pi4-aux sudo[6473]: volumio : (command continued) /tmp/hls/stream66.ts /tmp/hls/stream67.ts /tmp/hls/stream68.ts /tmp/hls/stream69.ts /tmp/hls/stream7.ts /tmp/hls/stream70.ts /tmp/hls/stream71.ts /tmp/hls/stream72.ts /tmp/hls/stream73.ts /tmp/hls/stream74.ts /tmp/hls/stream75.ts /tmp/hls/stream76.ts /tmp/hls/stream77.ts /tmp/hls/stream78.ts /tmp/hls/stream79.ts /tmp/hls/stream8.ts /tmp/hls/stream80.ts /tmp/hls/stream81.ts /tmp/hls/stream82.ts /tmp/hls/stream83.ts /tmp/hls/stream84.ts /tmp/hls/stream85.ts /tmp/hls/stream86.ts /tmp/hls/stream87.ts /tmp/hls/stream88.ts /tmp/hls/stream89.ts /tmp/hls/stream9.ts /tmp/hls/stream90.ts /tmp/hls/stream91.ts /tmp/hls/stream92.ts /tmp/hls/stream93.ts /tmp/hls/stream94.ts /tmp/hls/stream95.ts /tmp/hls/stream96.ts /tmp/hls/stream97.ts /tmp/hls/stream98.ts /tmp/hls/stream99.ts Apr 08 12:07:37 volumio-pi4-aux sudo[6473]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 12:07:37 volumio-pi4-aux sudo[6473]: pam_unix(sudo:session): session closed for user root Apr 08 12:07:37 volumio-pi4-aux sudo[6476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Apr 08 12:07:37 volumio-pi4-aux sudo[6476]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 12:07:37 volumio-pi4-aux systemd[1]: Stopping VolumioStreamingService... Apr 08 12:07:37 volumio-pi4-aux systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Apr 08 12:07:37 volumio-pi4-aux systemd[1]: volumioStreaming.service: Succeeded. Apr 08 12:07:37 volumio-pi4-aux systemd[1]: Stopped VolumioStreamingService. Apr 08 12:07:37 volumio-pi4-aux volumio[759]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 12:07:37 volumio-pi4-aux volumio[759]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 08 12:07:37 volumio-pi4-aux systemd[1]: Started VolumioStreamingService. Apr 08 12:07:37 volumio-pi4-aux sudo[6476]: pam_unix(sudo:session): session closed for user root Apr 08 12:07:37 volumio-pi4-aux volumio[759]: info: MRS: volumioStreaming STARTED Apr 08 12:07:37 volumio-pi4-aux sudo[6480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 08 12:07:37 volumio-pi4-aux sudo[6480]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 12:07:37 volumio-pi4-aux sudo[6480]: pam_unix(sudo:session): session closed for user root Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPlay Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CoreStateMachine::play index undefined Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CoreStateMachine::startPlaybackTimer Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioGetVisibleSources Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: [1712567259822] ControllerWebradio::clearAddPlayTrack Apr 08 12:07:39 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: sendMpdCommand stop took 0 milliseconds Apr 08 12:07:39 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:39 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: sendMpdCommand clear took 0 milliseconds Apr 08 12:07:39 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509442" Apr 08 12:07:39 volumio-pi4-aux volumio[759]: error: updateQueue error: null Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:39 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99509442" took 101 milliseconds Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 08 12:07:39 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand play Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: ------------------------------ 2ms Apr 08 12:07:39 volumio-pi4-aux volumio[759]: info: sendMpdCommand play took 1 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:40 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:40 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: 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":"radio.mp3","artist":"(#1 - 26/750) Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus stop Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ------------------------------ 5ms Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: 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":"radio.mp3","artist":"(#1 - 26/750) Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ------------------------------ 17ms Apr 08 12:07:40 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:40 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:40 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:40 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:40 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ------------------------------ 8ms Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 1 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ------------------------------ 3ms Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 2 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La Bouche - Sweet Dreams (Ola ola e)","artist":"Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ------------------------------ 13ms Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La Bouche - Sweet Dreams (Ola ola e)","artist":"Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:40 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:40 volumio-pi4-aux volumio[759]: info: ------------------------------ 21ms Apr 08 12:07:40 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:40 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:42 volumio-pi4-aux volumio[759]: info: Executing endpoint metavolumio Apr 08 12:07:42 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 08 12:07:42 volumio-pi4-aux volumio[759]: info: Executing endpoint metavolumio Apr 08 12:07:42 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:49 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces system playlist update Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: Ignoring MPD Status Update Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: Apr 08 12:07:49 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: ControllerMpd::getState Apr 08 12:07:49 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand status Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: ------------------------------ 3ms Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: sendMpdCommand status took 1 milliseconds Apr 08 12:07:49 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseState Apr 08 12:07:49 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 08 12:07:49 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::parseTrackInfo Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: ControllerMpd::pushState Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::servicePushState Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:07:49 volumio-pi4-aux volumio[759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10486,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bell Book & Candle - Rescue Me","artist":"Best 90s","album":null,"uri":"https://c4.auracast.net:8050/radio.mp3","trackType":"mp3"} Apr 08 12:07:49 volumio-pi4-aux volumio[759]: verbose: CURRENT POSITION 0 Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState stateService play Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreStateMachine::syncState currentStatus play Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: Received an update from plugin. extracting info from payload Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:07:49 volumio-pi4-aux volumio[759]: info: ------------------------------ 12ms Apr 08 12:07:49 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:49 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:07:53 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 12:07:53 volumio-pi4-aux volumio[759]: info: CURURI: music-library Apr 08 12:07:53 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:56 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 12:07:56 volumio-pi4-aux volumio[759]: info: CURURI: music-library/NAS Apr 08 12:07:56 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:07:57 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 12:07:57 volumio-pi4-aux volumio[759]: info: CURURI: music-library/NAS/TrueNAS Apr 08 12:07:57 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::ClearQueue Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stop Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::stPlaybackTimer Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::updateTrackBlock Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrackBlock Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::pushState Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushState Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output update for this device Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: MRS: Pushing multiroomSync output Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::serviceStop Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::getTrack 0 Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::serviceStop Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: [1712567281578] ControllerWebradio::stop Apr 08 12:08:01 volumio-pi4-aux volumio[759]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::clearPlayQueue Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::saveQueue Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreCommandRouter::volumioPushQueue Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CoreStateMachine::addQueueItems Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: CorePlayQueue::addQueueItems Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: Preload queue cleared Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: Adding Item to queue: music-library/NAS/TrueNAS/Pink Floyd (1973) - The Dark Side Of The Moon [Harvest Records UK] Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: Exploding uri music-library/NAS/TrueNAS/Pink Floyd (1973) - The Dark Side Of The Moon [Harvest Records UK] in service mpd Apr 08 12:08:01 volumio-pi4-aux volumio[759]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: Apr 08 12:08:01 volumio-pi4-aux volumio[759]: ---------------------------- MPD announces state update: player Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: ALBUMART /albumart?cacheid=677&web=Pink%20Floyd/The%20Dark%20Side%20Of%20The%20Moon%20%5BHarvest%20Records%20GT%20Britain%20A5B5%5D/mega&path=%2Fmnt%2FNAS%2FTrueNAS%2FPink%20Floyd%20(1973)%20-%20The%20Dark%20Side%20Of%20The%20Moon%20%5BHarvest%20Records%20UK%5D%2FPink%20Floyd%20-%20The%20Dark%20Side%20Of%20The%20Moon%20%5BHarvest%20Records%20GT%20Britain%20A5B5%5D.cue&metadata=false Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: URI /mnt/NAS/TrueNAS/Pink Floyd (1973) - The Dark Side Of The Moon [Harvest Records UK]/Pink Floyd - The Dark Side Of The Moon [Harvest Records GT Britain A5B5].cue Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: ALBUMART /albumart?cacheid=677&web=Pink%20Floyd/The%20Dark%20Side%20Of%20The%20Moon%20%5BHarvest%20Records%20GT%20Britain%20A5B5%5D/mega&path=%2Fmnt%2FNAS%2FTrueNAS%2FPink%20Floyd%20(1973)%20-%20The%20Dark%20Side%20Of%20The%20Moon%20%5BHarvest%20Records%20UK%5D%2FPink%20Floyd%20-%20The%20Dark%20Side%20Of%20The%20Moon%20%5BHarvest%20Records%20GT%20Britain%20A5B5%5D.cue&metadata=false Apr 08 12:08:01 volumio-pi4-aux volumio[759]: info: URI /mnt/NAS/TrueNAS/Pink Floyd (1973) - The Dark Side Of The Moon [Harvest Records UK]/Pink Floyd - The Dark Side Of The Moon [Harvest Records GT Britain A5B5].cue Apr 08 12:08:01 volumio-pi4-aux volumio[759]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 12:08:01 volumio-pi4-aux volumio[759]: Error: Unable to resolve or reject the same promise twice Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at Socket.emit (events.js:315:20) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at addChunk (internal/streams/readable.js:309:12) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 08 12:08:01 volumio-pi4-aux volumio[759]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 12:08:01 volumio-pi4-aux sudo[6535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 12:07 Apr 08 12:08:01 volumio-pi4-aux sudo[6535]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"