-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-05-12 11:10:36 CEST. -- May 12 11:09:02 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 11:09:02 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 11:09:02 volumio volumio[1156]: info: Discovery: Getting this device information May 12 11:09:02 volumio volumio[1156]: info: CoreCommandRouter::volumioGetState May 12 11:09:02 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:02 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 11:09:13 volumio volumio[1156]: info: Preload queue cleared May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::ClearQueue May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::stop May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::stPlaybackTimer May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::updateTrackBlock May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrackBlock May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::serviceStop May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::serviceStop May 12 11:09:13 volumio volumio[1156]: info: ControllerMpd::stop May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::clearPlayQueue May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::saveQueue May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushQueue May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::addQueueItems May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::addQueueItems May 12 11:09:13 volumio volumio[1156]: info: Preload queue cleared May 12 11:09:13 volumio volumio[1156]: info: Adding Item to queue: webeighties/9 May 12 11:09:13 volumio volumio[1156]: info: Exploding uri webeighties/9 in service 80s80s May 12 11:09:13 volumio volumio[1156]: info: [1747040953385] [80s80s] explodeUri: webeighties/9 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushQueue May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::saveQueue May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::updateTrackBlock May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrackBlock May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPlay May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::play index 0 May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::stop May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::play index undefined May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::startPlaybackTimer May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: [1747040953450] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/100/mp3-192/volumio May 12 11:09:13 volumio volumio[1156]: info: [1747040953451] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=111&count=2 May 12 11:09:13 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:13 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:13 volumio volumio[1156]: info: May 12 11:09:13 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:13 volumio volumio[1156]: info: sendMpdCommand stop took 135 milliseconds May 12 11:09:13 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:13 volumio sudo[2961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:13 volumio sudo[2961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music3/v4/c7/4a/92/c74a92a4-404e-d4b9-7ab2-e2a125f5116a/DIG_180032.jpg/600x600bb.jpg","name":"Cyber People - Void Vision","title":"Void Vision","artist":"80s80s Italo Disco","album":"","streaming":true,"disableUiControls":true,"duration":"221","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 12 11:09:13 volumio volumio[1156]: verbose: CURRENT POSITION 0 May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::syncState stateService stop May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::syncState currentStatus stop May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:13 volumio volumio[1156]: info: No code May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:13 volumio sudo[2961]: pam_unix(sudo:session): session closed for user root May 12 11:09:13 volumio volumio[1156]: info: May 12 11:09:13 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:13 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:13 volumio volumio[1156]: info: May 12 11:09:13 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:13 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:13 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:13 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:13 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:13 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:13 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:13 volumio sudo[2964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:13 volumio sudo[2964]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:13 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:13 volumio sudo[2967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:13 volumio sudo[2967]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:13 volumio volumio[1156]: info: sendMpdCommand status took 236 milliseconds May 12 11:09:13 volumio volumio[1156]: info: sendMpdCommand status took 123 milliseconds May 12 11:09:13 volumio volumio[1156]: info: sendMpdCommand status took 120 milliseconds May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:13 volumio sudo[2964]: pam_unix(sudo:session): session closed for user root May 12 11:09:13 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:13 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 7 milliseconds May 12 11:09:13 volumio sudo[2970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:13 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 8 milliseconds May 12 11:09:13 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 9 milliseconds May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:13 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:13 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:13 volumio sudo[2970]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:13 volumio sudo[2967]: pam_unix(sudo:session): session closed for user root May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:13 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:13 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:13 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:13 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:13 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:13 volumio sudo[2970]: pam_unix(sudo:session): session closed for user root May 12 11:09:13 volumio volumio[1156]: info: ------------------------------ 344ms May 12 11:09:13 volumio volumio[1156]: info: ------------------------------ 230ms May 12 11:09:13 volumio volumio[1156]: info: ------------------------------ 228ms May 12 11:09:13 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:13 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:13 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:13 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:13 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:13 volumio sudo[2973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:13 volumio sudo[2973]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:13 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:13 volumio sudo[2976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:13 volumio sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:13 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:13 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:13 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:13 volumio sudo[2979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:13 volumio sudo[2979]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:14 volumio sudo[2973]: pam_unix(sudo:session): session closed for user root May 12 11:09:14 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:14 volumio sudo[2976]: pam_unix(sudo:session): session closed for user root May 12 11:09:14 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:14 volumio sudo[2979]: pam_unix(sudo:session): session closed for user root May 12 11:09:14 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:14 volumio volumio[1156]: info: [1747040954127] [80s80s] received new event containing 2 songs. May 12 11:09:14 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop May 12 11:09:14 volumio volumio[1156]: info: sendMpdCommand stop took 2 milliseconds May 12 11:09:14 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear May 12 11:09:14 volumio volumio[1156]: info: May 12 11:09:14 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:14 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:14 volumio volumio[1156]: info: sendMpdCommand clear took 3 milliseconds May 12 11:09:14 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand consume 1 May 12 11:09:14 volumio volumio[1156]: info: May 12 11:09:14 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:14 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:14 volumio volumio[1156]: info: May 12 11:09:14 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:14 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:14 volumio volumio[1156]: error: updateQueue error: null May 12 11:09:14 volumio volumio[1156]: error: updateQueue error: null May 12 11:09:14 volumio volumio[1156]: error: updateQueue error: null May 12 11:09:14 volumio volumio[1156]: info: ------------------------------ 12ms May 12 11:09:14 volumio volumio[1156]: info: sendMpdCommand consume 1 took 11 milliseconds May 12 11:09:14 volumio volumio[1156]: info: ------------------------------ 12ms May 12 11:09:14 volumio volumio[1156]: info: ------------------------------ 10ms May 12 11:09:14 volumio volumio[1156]: info: [1747040954151] [80s80s] adding url: http://streams.80s80s.de/100/mp3-192/volumio May 12 11:09:14 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/100/mp3-192/volumio" May 12 11:09:14 volumio volumio[1156]: info: May 12 11:09:14 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:14 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:14 volumio volumio[1156]: info: sendMpdCommand add "http://streams.80s80s.de/100/mp3-192/volumio" took 4 milliseconds May 12 11:09:14 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand play May 12 11:09:14 volumio volumio[1156]: info: May 12 11:09:14 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:14 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:14 volumio volumio[1156]: info: May 12 11:09:14 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:14 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:14 volumio volumio[1156]: info: ------------------------------ 32ms May 12 11:09:14 volumio volumio[1156]: info: sendMpdCommand play took 7 milliseconds May 12 11:09:14 volumio volumio[1156]: info: ------------------------------ 5ms May 12 11:09:14 volumio volumio[1156]: info: ------------------------------ 4ms May 12 11:09:14 volumio volumio[1156]: info: [1747040954189] [80s80s] Pushing the next song state: Prince - THUNDER May 12 11:09:14 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:14 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:14 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:14 volumio volumio[1156]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-prince-cover.png","name":"Prince - THUNDER","title":"THUNDER","artist":"80s80s Prince","album":"","streaming":true,"disableUiControls":true,"duration":"339","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 12 11:09:14 volumio volumio[1156]: verbose: CURRENT POSITION 0 May 12 11:09:14 volumio volumio[1156]: info: CoreStateMachine::syncState stateService play May 12 11:09:14 volumio volumio[1156]: info: CoreStateMachine::syncState currentStatus stop May 12 11:09:14 volumio volumio[1156]: info: [1747040954191] [80s80s] PlayNextTrack API delay: 30 May 12 11:09:14 volumio volumio[1156]: info: [1747040954197] [80s80s] Setting timer to: 339000 milliseconds. May 12 11:09:14 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:14 volumio volumio[1156]: info: CorePlayQueue::getTrack 1 May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 12 11:09:15 volumio volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 May 12 11:09:15 volumio volumio[1156]: error: FusionDsp - File size not found in array! May 12 11:09:15 volumio volumio[1156]: info: FusionDsp - Nulleq applied May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 21 milliseconds May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 21 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 20 milliseconds May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 13 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 12 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 11 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 11 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 10 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 8 milliseconds May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 43ms May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 41ms May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 40ms May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 7 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 6 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 5 milliseconds May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 27ms May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 22ms May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 22ms May 12 11:09:15 volumio volumio[1156]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:15 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:15 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:15 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 15ms May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 12 milliseconds May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 11ms May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 9 milliseconds May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 8ms May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 10 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 4 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 25ms May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 22ms May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 19ms May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:15 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:15 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:15 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:15 volumio volumio[1156]: info: May 12 11:09:15 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 8ms May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 7 milliseconds May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 6ms May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 6 milliseconds May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 5ms May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand status took 3 milliseconds May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 2 milliseconds May 12 11:09:15 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:15 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:15 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:15 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 18ms May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 16ms May 12 11:09:15 volumio volumio[1156]: info: ------------------------------ 13ms May 12 11:09:32 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 11:09:32 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 11:09:32 volumio volumio[1156]: info: Discovery: Getting this device information May 12 11:09:32 volumio volumio[1156]: info: CoreCommandRouter::volumioGetState May 12 11:09:32 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:32 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::volumioNext May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::next May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::stop May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::stPlaybackTimer May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::updateTrackBlock May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrackBlock May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::serviceStop May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::serviceStop May 12 11:09:36 volumio volumio[1156]: info: ControllerMpd::stop May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop May 12 11:09:36 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:36 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:36 volumio sudo[3026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:36 volumio sudo[3026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:36 volumio volumio[1156]: info: May 12 11:09:36 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:36 volumio volumio[1156]: info: sendMpdCommand stop took 98 milliseconds May 12 11:09:36 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-prince-cover.png","name":"Prince - THUNDER","title":"THUNDER","artist":"80s80s Prince","album":"","streaming":true,"disableUiControls":true,"duration":"339","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 12 11:09:36 volumio volumio[1156]: verbose: CURRENT POSITION 0 May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::syncState stateService stop May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::syncState currentStatus stop May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:36 volumio volumio[1156]: info: No code May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:36 volumio sudo[3026]: pam_unix(sudo:session): session closed for user root May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::play index undefined May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 1 May 12 11:09:36 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:36 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:36 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:36 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:36 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:36 volumio sudo[3029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:36 volumio sudo[3029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:36 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:36 volumio sudo[3032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:36 volumio sudo[3032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:36 volumio sudo[3029]: pam_unix(sudo:session): session closed for user root May 12 11:09:36 volumio volumio[1156]: info: sendMpdCommand status took 219 milliseconds May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:36 volumio volumio[1156]: info: May 12 11:09:36 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:36 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:36 volumio volumio[1156]: info: May 12 11:09:36 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:36 volumio sudo[3034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:36 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:36 volumio sudo[3034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:36 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:36 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 16 milliseconds May 12 11:09:36 volumio volumio[1156]: info: sendMpdCommand status took 15 milliseconds May 12 11:09:36 volumio volumio[1156]: info: sendMpdCommand status took 5 milliseconds May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:36 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:36 volumio sudo[3032]: pam_unix(sudo:session): session closed for user root May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:36 volumio volumio[1156]: info: ------------------------------ 295ms May 12 11:09:36 volumio sudo[3034]: pam_unix(sudo:session): session closed for user root May 12 11:09:36 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:36 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 49 milliseconds May 12 11:09:36 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 48 milliseconds May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:36 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:36 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:36 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:36 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:36 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:36 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:36 volumio volumio[1156]: info: ------------------------------ 112ms May 12 11:09:36 volumio volumio[1156]: info: ------------------------------ 111ms May 12 11:09:36 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:36 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:36 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:36 volumio sudo[3038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:36 volumio sudo[3038]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:36 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:36 volumio sudo[3041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:36 volumio sudo[3041]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:36 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:36 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:36 volumio sudo[3044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:36 volumio sudo[3044]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:36 volumio sudo[3038]: pam_unix(sudo:session): session closed for user root May 12 11:09:36 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:36 volumio sudo[3041]: pam_unix(sudo:session): session closed for user root May 12 11:09:36 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:36 volumio sudo[3044]: pam_unix(sudo:session): session closed for user root May 12 11:09:36 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:40 volumio volumio[1156]: info: CoreCommandRouter::volumioSeek May 12 11:09:40 volumio volumio[1156]: info: CoreStateMachine::seek May 12 11:09:40 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:40 volumio volumio[1156]: info: TRACKBLOCK {"service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"eighties","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-prince-cover.png","uri":"http://streams.80s80s.de/100/mp3-192/volumio","name":"Prince - THUNDER","duration":"339","artist":"80s80s Prince","album":"","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 12 11:09:40 volumio volumio[1156]: info: CoreStateMachine::startPlaybackTimer May 12 11:09:40 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:40 volumio volumio[1156]: info: [1747040980711] [80s80s] seek to 32000 May 12 11:09:40 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:09:40 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:40 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:09:40 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:09:40 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:09:40 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:09:40 volumio sudo[3069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:09:40 volumio sudo[3069]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:09:40 volumio sudo[3069]: pam_unix(sudo:session): session closed for user root May 12 11:09:40 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:09:44 volumio volumio[1156]: info: CoreCommandRouter::volumioPlay May 12 11:09:44 volumio volumio[1156]: info: CoreStateMachine::play index undefined May 12 11:09:44 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:09:44 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:44 volumio volumio[1156]: info: CoreStateMachine::startPlaybackTimer May 12 11:09:44 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:44 volumio volumio[1156]: info: [1747040984319] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/100/mp3-192/volumio May 12 11:09:44 volumio volumio[1156]: info: [1747040984319] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=111&count=2 May 12 11:09:44 volumio volumio[1156]: info: [1747040984517] [80s80s] received new event containing 2 songs. May 12 11:09:44 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop May 12 11:09:44 volumio volumio[1156]: info: sendMpdCommand stop took 2 milliseconds May 12 11:09:44 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand clear May 12 11:09:44 volumio volumio[1156]: info: May 12 11:09:44 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:44 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:44 volumio volumio[1156]: info: sendMpdCommand clear took 2 milliseconds May 12 11:09:44 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand consume 1 May 12 11:09:44 volumio volumio[1156]: info: May 12 11:09:44 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:44 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:44 volumio volumio[1156]: info: May 12 11:09:44 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:44 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:44 volumio volumio[1156]: error: updateQueue error: null May 12 11:09:44 volumio volumio[1156]: error: updateQueue error: null May 12 11:09:44 volumio volumio[1156]: error: updateQueue error: null May 12 11:09:44 volumio volumio[1156]: info: ------------------------------ 6ms May 12 11:09:44 volumio volumio[1156]: info: sendMpdCommand consume 1 took 4 milliseconds May 12 11:09:44 volumio volumio[1156]: info: ------------------------------ 3ms May 12 11:09:44 volumio volumio[1156]: info: ------------------------------ 2ms May 12 11:09:44 volumio volumio[1156]: info: [1747040984528] [80s80s] adding url: http://streams.80s80s.de/100/mp3-192/volumio May 12 11:09:44 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/100/mp3-192/volumio" May 12 11:09:44 volumio volumio[1156]: info: May 12 11:09:44 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:44 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:44 volumio volumio[1156]: info: sendMpdCommand add "http://streams.80s80s.de/100/mp3-192/volumio" took 1 milliseconds May 12 11:09:44 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand play May 12 11:09:44 volumio volumio[1156]: info: May 12 11:09:44 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:44 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:44 volumio volumio[1156]: info: May 12 11:09:44 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:44 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:44 volumio volumio[1156]: info: ------------------------------ 20ms May 12 11:09:44 volumio volumio[1156]: info: sendMpdCommand play took 9 milliseconds May 12 11:09:44 volumio volumio[1156]: info: ------------------------------ 7ms May 12 11:09:44 volumio volumio[1156]: info: ------------------------------ 5ms May 12 11:09:44 volumio volumio[1156]: info: [1747040984551] [80s80s] Pushing the next song state: Prince - THUNDER May 12 11:09:44 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:44 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:44 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:44 volumio volumio[1156]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-prince-cover.png","name":"Prince - THUNDER","title":"THUNDER","artist":"80s80s Prince","album":"","streaming":true,"disableUiControls":true,"duration":"339","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 12 11:09:44 volumio volumio[1156]: verbose: CURRENT POSITION 0 May 12 11:09:44 volumio volumio[1156]: info: CoreStateMachine::syncState stateService play May 12 11:09:44 volumio volumio[1156]: info: CoreStateMachine::syncState currentStatus stop May 12 11:09:44 volumio volumio[1156]: info: [1747040984554] [80s80s] PlayNextTrack API delay: 30 May 12 11:09:44 volumio volumio[1156]: info: [1747040984556] [80s80s] Setting timer to: 339000 milliseconds. May 12 11:09:44 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:44 volumio volumio[1156]: info: CorePlayQueue::getTrack 1 May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 12 11:09:46 volumio volumio[1156]: info: FusionDsp - ---- read samplerate from file: 44100 May 12 11:09:46 volumio volumio[1156]: error: FusionDsp - File size not found in array! May 12 11:09:46 volumio volumio[1156]: info: FusionDsp - Nulleq applied May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 22 milliseconds May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 22 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 19 milliseconds May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 7 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 6 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 6 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 6 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 4 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 2 milliseconds May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 44ms May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 42ms May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 40ms May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 12 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 10 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 14 milliseconds May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 40ms May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 37ms May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 35ms May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:46 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:46 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:46 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 13ms May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 12 milliseconds May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 11ms May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 8 milliseconds May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 15ms May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 12 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 8 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 6 milliseconds May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 28ms May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 23ms May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 21ms May 12 11:09:46 volumio volumio[1156]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:46 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:46 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces system playlist update May 12 11:09:46 volumio volumio[1156]: info: Ignoring MPD Status Update May 12 11:09:46 volumio volumio[1156]: info: May 12 11:09:46 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 12ms May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 8 milliseconds May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 7ms May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 7 milliseconds May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 6ms May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand status took 4 milliseconds May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 11:09:46 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:09:46 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:09:46 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:09:46 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 20ms May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 16ms May 12 11:09:46 volumio volumio[1156]: info: ------------------------------ 14ms May 12 11:10:01 volumio volumio[1156]: info: CoreCommandRouter::volumioPlay May 12 11:10:01 volumio volumio[1156]: info: CoreStateMachine::play index undefined May 12 11:10:01 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:10:01 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:02 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 11:10:02 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 11:10:02 volumio volumio[1156]: info: Discovery: Getting this device information May 12 11:10:02 volumio volumio[1156]: info: CoreCommandRouter::volumioGetState May 12 11:10:02 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:02 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 11:10:18 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri May 12 11:10:18 volumio volumio[1156]: info: [1747041018108] [80s80s] handleBrowseUri curUri: 80s80s/nineties May 12 11:10:18 volumio volumio[1156]: info: [1747041018108] [80s80s] getRadioContent url: nineties May 12 11:10:18 volumio volumio[1156]: info: Preload queue cleared May 12 11:10:19 volumio volumio[1156]: info: Preload queue cleared May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioReplaceandPlayItems May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::ClearQueue May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::stop May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::stPlaybackTimer May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::updateTrackBlock May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrackBlock May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::serviceStop May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::serviceStop May 12 11:10:19 volumio volumio[1156]: info: ControllerMpd::stop May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand stop May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::clearPlayQueue May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::saveQueue May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioPushQueue May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::addQueueItems May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::addQueueItems May 12 11:10:19 volumio volumio[1156]: info: Preload queue cleared May 12 11:10:19 volumio volumio[1156]: info: Adding Item to queue: webnineties/1 May 12 11:10:19 volumio volumio[1156]: info: Exploding uri webnineties/1 in service 80s80s May 12 11:10:19 volumio volumio[1156]: info: [1747041019647] [80s80s] explodeUri: webnineties/1 May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioPushQueue May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::saveQueue May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::updateTrackBlock May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrackBlock May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioPlay May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::play index 0 May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::stop May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::play index undefined May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::startPlaybackTimer May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: info: [1747041019701] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/grunge/mp3-192/volumio May 12 11:10:19 volumio volumio[1156]: info: [1747041019702] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=253&count=2 May 12 11:10:19 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:10:19 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:10:19 volumio volumio[1156]: info: May 12 11:10:19 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:10:19 volumio volumio[1156]: info: sendMpdCommand stop took 108 milliseconds May 12 11:10:19 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:10:19 volumio sudo[3183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:10:19 volumio sudo[3183]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"/albumart?sourceicon=music_service/80s80s/images/80s80s-prince-cover.png","name":"Prince - THUNDER","title":"THUNDER","artist":"80s80s Prince","album":"","streaming":true,"disableUiControls":true,"duration":"339","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 12 11:10:19 volumio volumio[1156]: verbose: CURRENT POSITION 0 May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::syncState stateService stop May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::syncState currentStatus stop May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:10:19 volumio volumio[1156]: info: No code May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:10:19 volumio sudo[3183]: pam_unix(sudo:session): session closed for user root May 12 11:10:19 volumio volumio[1156]: info: May 12 11:10:19 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:10:19 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:10:19 volumio volumio[1156]: info: May 12 11:10:19 volumio volumio[1156]: ---------------------------- MPD announces state update: player May 12 11:10:19 volumio volumio[1156]: info: ControllerMpd::getState May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand status May 12 11:10:19 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:10:19 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:10:19 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:10:19 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:10:19 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:10:19 volumio sudo[3187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:10:19 volumio sudo[3187]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:10:19 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:10:19 volumio sudo[3190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:10:19 volumio sudo[3190]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:10:19 volumio volumio[1156]: info: sendMpdCommand status took 216 milliseconds May 12 11:10:19 volumio volumio[1156]: info: sendMpdCommand status took 120 milliseconds May 12 11:10:19 volumio sudo[3187]: pam_unix(sudo:session): session closed for user root May 12 11:10:19 volumio volumio[1156]: info: sendMpdCommand status took 119 milliseconds May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::parseState May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 11:10:19 volumio sudo[3193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:10:19 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:10:19 volumio sudo[3193]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:10:19 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 9 milliseconds May 12 11:10:19 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 8 milliseconds May 12 11:10:19 volumio volumio[1156]: info: sendMpdCommand playlistinfo took 8 milliseconds May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:10:19 volumio volumio[1156]: verbose: ControllerMpd::parseTrackInfo May 12 11:10:19 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:10:19 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:10:19 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 11:10:19 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:10:20 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:20 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:10:20 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:10:20 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:10:20 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:10:20 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:20 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:10:20 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:20 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:10:20 volumio volumio[1156]: info: ControllerMpd::pushState May 12 11:10:20 volumio volumio[1156]: info: CoreCommandRouter::servicePushState May 12 11:10:20 volumio volumio[1156]: info: CoreStateMachine::pushState May 12 11:10:20 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:20 volumio volumio[1156]: info: CoreCommandRouter::volumioPushState May 12 11:10:20 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:20 volumio volumio[1156]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 12 11:10:20 volumio sudo[3190]: pam_unix(sudo:session): session closed for user root May 12 11:10:20 volumio sudo[3193]: pam_unix(sudo:session): session closed for user root May 12 11:10:20 volumio volumio[1156]: info: ------------------------------ 323ms May 12 11:10:20 volumio volumio[1156]: info: ------------------------------ 226ms May 12 11:10:20 volumio volumio[1156]: info: ------------------------------ 225ms May 12 11:10:20 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:10:20 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:10:20 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:10:20 volumio volumio[1156]: info: [ASDebug] CurState: stop PrevState: stop May 12 11:10:20 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:10:20 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:10:20 volumio sudo[3196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:10:20 volumio volumio[1156]: info: PeppyMeterBasic ---peppymeterbasic status stop May 12 11:10:20 volumio sudo[3196]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:10:20 volumio sudo[3198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:10:20 volumio sudo[3198]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:10:20 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:10:20 volumio sudo[3202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 12 11:10:20 volumio sudo[3202]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 11:10:20 volumio sudo[3196]: pam_unix(sudo:session): session closed for user root May 12 11:10:20 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:10:20 volumio sudo[3198]: pam_unix(sudo:session): session closed for user root May 12 11:10:20 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:10:20 volumio sudo[3202]: pam_unix(sudo:session): session closed for user root May 12 11:10:20 volumio volumio[1156]: info: peppymeterbasic Daemon Stop May 12 11:10:32 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 11:10:32 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 11:10:32 volumio volumio[1156]: info: Discovery: Getting this device information May 12 11:10:32 volumio volumio[1156]: info: CoreCommandRouter::volumioGetState May 12 11:10:32 volumio volumio[1156]: info: CorePlayQueue::getTrack 0 May 12 11:10:32 volumio volumio[1156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 11:10:35 volumio volumio[1156]: info: [1747041035361] [80s80s] Failed to query api, status code: 504 May 12 11:10:35 volumio volumio[1156]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 11:10:35 volumio volumio[1156]: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/80s80s/fake-data.json' May 12 11:10:35 volumio volumio[1156]: at Object.openSync (fs.js:476:3) May 12 11:10:35 volumio volumio[1156]: at Object.readFileSync (fs.js:377:35) May 12 11:10:35 volumio volumio[1156]: at ClientRequest. (/data/plugins/music_service/80s80s/index.js:485:23) May 12 11:10:35 volumio volumio[1156]: at Object.onceWrapper (events.js:422:26) May 12 11:10:35 volumio volumio[1156]: at ClientRequest.emit (events.js:315:20) May 12 11:10:35 volumio volumio[1156]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) May 12 11:10:35 volumio volumio[1156]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) May 12 11:10:35 volumio volumio[1156]: at Socket.socketOnData (_http_client.js:509:22) May 12 11:10:35 volumio volumio[1156]: at Socket.emit (events.js:315:20) May 12 11:10:35 volumio volumio[1156]: at addChunk (internal/streams/readable.js:309:12) May 12 11:10:35 volumio volumio[1156]: at readableAddChunk (internal/streams/readable.js:284:9) May 12 11:10:35 volumio volumio[1156]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 12 11:10:35 volumio volumio[1156]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) { May 12 11:10:35 volumio volumio[1156]: errno: -2, May 12 11:10:35 volumio volumio[1156]: syscall: 'open', May 12 11:10:35 volumio volumio[1156]: code: 'ENOENT', May 12 11:10:35 volumio volumio[1156]: path: '/data/plugins/music_service/80s80s/fake-data.json' May 12 11:10:35 volumio volumio[1156]: } May 12 11:10:35 volumio volumio[1156]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 11:10:36 volumio sudo[3241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 11:09 May 12 11:10:36 volumio sudo[3241]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"