Apr 27 14:23:14 volumio-86 volumio[1341]: verbose: New Socket.io Connection to 192.168.1.96 from 192.168.1.50 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 11 Apr 27 14:23:14 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetState Apr 27 14:23:15 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 14:23:15 volumio-86 volumio[1341]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 27 14:23:15 volumio-86 volumio[1341]: info: Received Get System Info Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 14:23:15 volumio-86 volumio[1341]: info: Discovery: Getting this device information Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetState Apr 27 14:23:15 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetState Apr 27 14:23:15 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:15 volumio-86 volumio[1341]: info: Listing playlists Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 27 14:23:15 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetState Apr 27 14:23:15 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:16 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 14:23:16 volumio-86 volumio[1341]: info: Received Get System Info Apr 27 14:23:16 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 14:23:16 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 14:23:16 volumio-86 volumio[1341]: info: Discovery: Getting this device information Apr 27 14:23:16 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetState Apr 27 14:23:16 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:16 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 14:23:16 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 27 14:23:17 volumio-86 volumio[1341]: info: Executing endpoint metavolumio Apr 27 14:23:17 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 14:23:18 volumio-86 volumio[1341]: info: Received Get System Info Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 14:23:18 volumio-86 volumio[1341]: info: Discovery: Getting this device information Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetState Apr 27 14:23:18 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPlay Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreStateMachine::play index undefined Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 14:23:18 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreStateMachine::startPlaybackTimer Apr 27 14:23:18 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 14:23:18 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 27 14:23:18 volumio-86 volumio[1341]: info: [1745763798342] ControllerWebradio::clearAddPlayTrack Apr 27 14:23:18 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 14:23:18 volumio-86 volumio[1341]: info: sendMpdCommand stop took 1 milliseconds Apr 27 14:23:18 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand clear Apr 27 14:23:18 volumio-86 volumio[1341]: info: Apr 27 14:23:18 volumio-86 volumio[1341]: ---------------------------- MPD announces system playlist update Apr 27 14:23:18 volumio-86 volumio[1341]: info: Ignoring MPD Status Update Apr 27 14:23:18 volumio-86 volumio[1341]: info: sendMpdCommand clear took 1 milliseconds Apr 27 14:23:18 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand load "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" Apr 27 14:23:18 volumio-86 volumio[1341]: error: updateQueue error: null Apr 27 14:23:18 volumio-86 volumio[1341]: info: ------------------------------ 2ms Apr 27 14:23:19 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" Apr 27 14:23:19 volumio-86 volumio[1341]: info: Apr 27 14:23:19 volumio-86 volumio[1341]: ---------------------------- MPD announces system playlist update Apr 27 14:23:19 volumio-86 volumio[1341]: info: Ignoring MPD Status Update Apr 27 14:23:19 volumio-86 volumio[1341]: info: sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" took 0 milliseconds Apr 27 14:23:19 volumio-86 volumio[1341]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 27 14:23:19 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand play Apr 27 14:23:19 volumio-86 volumio[1341]: info: ------------------------------ 1ms Apr 27 14:23:19 volumio-86 volumio[1341]: info: sendMpdCommand play took 0 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: info: Apr 27 14:23:20 volumio-86 volumio[1341]: ---------------------------- MPD announces state update: player Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::getState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Apr 27 14:23:20 volumio-86 volumio[1341]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 27 14:23:20 volumio-86 volumio[1341]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 27 14:23:20 volumio-86 volumio[1341]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 14:23:20 volumio-86 volumio[1341]: info: Apr 27 14:23:20 volumio-86 volumio[1341]: ---------------------------- MPD announces state update: player Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand status took 11 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::getState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand status took 2 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:20 volumio-86 volumio[1341]: 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":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: CURRENT POSITION 0 Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState stateService play Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState currentStatus stop Apr 27 14:23:20 volumio-86 volumio[1341]: info: ------------------------------ 14ms Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:20 volumio-86 volumio[1341]: 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":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: CURRENT POSITION 0 Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState stateService play Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState currentStatus play Apr 27 14:23:20 volumio-86 volumio[1341]: info: Received an update from plugin. extracting info from payload Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:20 volumio-86 volumio[1341]: info: ------------------------------ 24ms Apr 27 14:23:20 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:20 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:20 volumio-86 sudo[2627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:20 volumio-86 sudo[2627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:20 volumio-86 sudo[2629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:20 volumio-86 sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:20 volumio-86 volumio[1341]: info: Apr 27 14:23:20 volumio-86 volumio[1341]: ---------------------------- MPD announces system playlist update Apr 27 14:23:20 volumio-86 volumio[1341]: info: Ignoring MPD Status Update Apr 27 14:23:20 volumio-86 volumio[1341]: info: Apr 27 14:23:20 volumio-86 volumio[1341]: ---------------------------- MPD announces state update: player Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::getState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Apr 27 14:23:20 volumio-86 volumio[1341]: info: Apr 27 14:23:20 volumio-86 volumio[1341]: ---------------------------- MPD announces system playlist update Apr 27 14:23:20 volumio-86 volumio[1341]: info: Ignoring MPD Status Update Apr 27 14:23:20 volumio-86 volumio[1341]: info: Apr 27 14:23:20 volumio-86 volumio[1341]: ---------------------------- MPD announces state update: player Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::getState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Apr 27 14:23:20 volumio-86 volumio[1341]: info: ------------------------------ 4ms Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand status took 3 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: info: ------------------------------ 2ms Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand status took 2 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LOREDANA - SURPRISE","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: CURRENT POSITION 0 Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState stateService play Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState currentStatus play Apr 27 14:23:20 volumio-86 volumio[1341]: info: Received an update from plugin. extracting info from payload Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1232,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LOREDANA - SURPRISE","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: CURRENT POSITION 0 Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState stateService play Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState currentStatus play Apr 27 14:23:20 volumio-86 volumio[1341]: info: Received an update from plugin. extracting info from payload Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:20 volumio-86 volumio[1341]: info: ------------------------------ 34ms Apr 27 14:23:20 volumio-86 volumio[1341]: info: ------------------------------ 32ms Apr 27 14:23:20 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:20 volumio-86 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Apr 27 14:23:20 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:20 volumio-86 sudo[2627]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:20 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:20 volumio-86 sudo[2629]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:20 volumio-86 sudo[2635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:20 volumio-86 sudo[2635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:20 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:20 volumio-86 sudo[2637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:20 volumio-86 sudo[2637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:20 volumio-86 sudo[2643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:20 volumio-86 sudo[2639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:20 volumio-86 sudo[2639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:20 volumio-86 sudo[2643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:20 volumio-86 sudo[2637]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:20 volumio-86 volumio[1341]: info: Apr 27 14:23:20 volumio-86 volumio[1341]: ---------------------------- MPD announces system playlist update Apr 27 14:23:20 volumio-86 volumio[1341]: info: Ignoring MPD Status Update Apr 27 14:23:20 volumio-86 volumio[1341]: info: Apr 27 14:23:20 volumio-86 volumio[1341]: ---------------------------- MPD announces state update: player Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::getState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Apr 27 14:23:20 volumio-86 sudo[2635]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:20 volumio-86 sudo[2639]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:20 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:20 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:20 volumio-86 sudo[2643]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:20 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:20 volumio-86 volumio[1341]: info: ------------------------------ 4ms Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand status took 3 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseState Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:20 volumio-86 volumio[1341]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Apr 27 14:23:20 volumio-86 volumio[1341]: info: ControllerMpd::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1357,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LOREDANA - SURPRISE","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Apr 27 14:23:20 volumio-86 volumio[1341]: verbose: CURRENT POSITION 0 Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState stateService play Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::syncState currentStatus play Apr 27 14:23:20 volumio-86 volumio[1341]: info: Received an update from plugin. extracting info from payload Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:20 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:20 volumio-86 volumio[1341]: info: ------------------------------ 22ms Apr 27 14:23:20 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:20 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:20 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:20 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:20 volumio-86 sudo[2647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:20 volumio-86 sudo[2647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:20 volumio-86 sudo[2649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:20 volumio-86 sudo[2649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:20 volumio-86 sudo[2647]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:20 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:20 volumio-86 sudo[2649]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:20 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:21 volumio-86 volumio[1341]: info: Apr 27 14:23:21 volumio-86 volumio[1341]: ---------------------------- MPD announces system playlist update Apr 27 14:23:21 volumio-86 volumio[1341]: info: Ignoring MPD Status Update Apr 27 14:23:21 volumio-86 volumio[1341]: info: Apr 27 14:23:21 volumio-86 volumio[1341]: ---------------------------- MPD announces state update: player Apr 27 14:23:21 volumio-86 volumio[1341]: info: ControllerMpd::getState Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Apr 27 14:23:21 volumio-86 volumio[1341]: info: ------------------------------ 1ms Apr 27 14:23:21 volumio-86 volumio[1341]: info: sendMpdCommand status took 1 milliseconds Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: ControllerMpd::parseState Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 14:23:21 volumio-86 volumio[1341]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Apr 27 14:23:21 volumio-86 volumio[1341]: info: ControllerMpd::pushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2729,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LOREDANA - SURPRISE","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: CURRENT POSITION 0 Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreStateMachine::syncState stateService play Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreStateMachine::syncState currentStatus play Apr 27 14:23:21 volumio-86 volumio[1341]: info: Received an update from plugin. extracting info from payload Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:21 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:21 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:21 volumio-86 volumio[1341]: info: ------------------------------ 18ms Apr 27 14:23:21 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:21 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:21 volumio-86 sudo[2656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:21 volumio-86 sudo[2656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:21 volumio-86 sudo[2658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:21 volumio-86 sudo[2658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:21 volumio-86 sudo[2656]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:21 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:21 volumio-86 sudo[2658]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:21 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:21 volumio-86 kernel: python3[2634]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Apr 27 14:23:21 volumio-86 volumio[1341]: info: Apr 27 14:23:21 volumio-86 volumio[1341]: ---------------------------- MPD announces system playlist update Apr 27 14:23:21 volumio-86 volumio[1341]: info: Ignoring MPD Status Update Apr 27 14:23:21 volumio-86 volumio[1341]: info: Apr 27 14:23:21 volumio-86 volumio[1341]: ---------------------------- MPD announces state update: player Apr 27 14:23:21 volumio-86 volumio[1341]: info: ControllerMpd::getState Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Apr 27 14:23:21 volumio-86 volumio[1341]: info: ------------------------------ 1ms Apr 27 14:23:21 volumio-86 volumio[1341]: info: sendMpdCommand status took 2 milliseconds Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: ControllerMpd::parseState Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 14:23:21 volumio-86 volumio[1341]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Apr 27 14:23:21 volumio-86 volumio[1341]: info: ControllerMpd::pushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"LOREDANA - SURPRISE","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Apr 27 14:23:21 volumio-86 volumio[1341]: verbose: CURRENT POSITION 0 Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreStateMachine::syncState stateService play Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreStateMachine::syncState currentStatus play Apr 27 14:23:21 volumio-86 volumio[1341]: info: Received an update from plugin. extracting info from payload Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:21 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:21 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:21 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:21 volumio-86 volumio[1341]: info: ------------------------------ 18ms Apr 27 14:23:21 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:21 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 27 14:23:21 volumio-86 sudo[2670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:21 volumio-86 sudo[2672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 27 14:23:21 volumio-86 sudo[2670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:21 volumio-86 sudo[2672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:21 volumio-86 sudo[2672]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:21 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:21 volumio-86 sudo[2670]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:21 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Started Apr 27 14:23:22 volumio-86 volumio[1341]: info: Executing endpoint metavolumio Apr 27 14:23:22 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 14:23:22 volumio-86 volumio[1341]: info: Executing endpoint metavolumio Apr 27 14:23:22 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 14:23:23 volumio-86 volumio[1341]: info: Executing endpoint metavolumio Apr 27 14:23:23 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 14:23:23 volumio-86 volumio[1341]: info: Executing endpoint metavolumio Apr 27 14:23:23 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioStop Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreStateMachine::stop Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreStateMachine::stPlaybackTimer Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreStateMachine::updateTrackBlock Apr 27 14:23:24 volumio-86 volumio[1341]: info: CorePlayQueue::getTrackBlock Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:24 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:24 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:24 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreStateMachine::serviceStop Apr 27 14:23:24 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreCommandRouter::serviceStop Apr 27 14:23:24 volumio-86 volumio[1341]: info: [1745763804489] ControllerWebradio::stop Apr 27 14:23:24 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 14:23:24 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 27 14:23:24 volumio-86 volumio[1341]: info: Apr 27 14:23:24 volumio-86 volumio[1341]: ---------------------------- MPD announces state update: player Apr 27 14:23:24 volumio-86 volumio[1341]: info: sendMpdCommand stop took 14 milliseconds Apr 27 14:23:24 volumio-86 volumio[1341]: info: ControllerMpd::getState Apr 27 14:23:24 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Apr 27 14:23:24 volumio-86 sudo[2677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 27 14:23:24 volumio-86 sudo[2677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:24 volumio-86 volumio[1341]: info: sendMpdCommand status took 1 milliseconds Apr 27 14:23:24 volumio-86 volumio[1341]: verbose: ControllerMpd::parseState Apr 27 14:23:24 volumio-86 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 14:23:24 volumio-86 volumio[1341]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 27 14:23:24 volumio-86 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Apr 27 14:23:24 volumio-86 volumio[1341]: info: ControllerMpd::pushState Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:24 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:24 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:24 volumio-86 volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:24 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:24 volumio-86 volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 27 14:23:24 volumio-86 systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Apr 27 14:23:24 volumio-86 volumio[1341]: info: ------------------------------ 9ms Apr 27 14:23:24 volumio-86 volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 27 14:23:24 volumio-86 sudo[2680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 27 14:23:24 volumio-86 sudo[2680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:24 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 14:23:24 volumio-86 systemd[1]: peppymeterbasic.service: Deactivated successfully. Apr 27 14:23:24 volumio-86 systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Apr 27 14:23:24 volumio-86 systemd[1]: peppymeterbasic.service: Consumed 1.070s CPU time. Apr 27 14:23:24 volumio-86 sudo[2677]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:24 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Stop Apr 27 14:23:24 volumio-86 sudo[2680]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:24 volumio-86 volumio[1341]: info: peppymeterbasic Daemon Stop Apr 27 14:23:26 volumio-86 volumio[1341]: info: Executing endpoint metavolumio Apr 27 14:23:26 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 14:23:26 volumio-86 volumio[1341]: info: Executing endpoint metavolumio Apr 27 14:23:26 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 27 14:23:32 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 14:23:32 volumio-86 volumio[1341]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 27 14:23:32 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 27 14:23:32 volumio-86 volumio[1341]: info: Received Get System Version Apr 27 14:23:32 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 14:23:32 volumio-86 volumio[1341]: info: Received Get System Info Apr 27 14:23:32 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 14:23:32 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 14:23:32 volumio-86 volumio[1341]: info: Discovery: Getting this device information Apr 27 14:23:32 volumio-86 volumio[1341]: info: CoreCommandRouter::volumioGetState Apr 27 14:23:32 volumio-86 volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:32 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 14:23:38 volumio-86 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 14:23:38 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 27 14:23:38 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 27 14:23:38 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 27 14:23:38 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 27 14:23:38 volumio-86 volumio[1341]: info: Received Get System Version Apr 27 14:23:38 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 14:23:47 volumio-86 volumio[1341]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Apr 27 14:23:47 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 27 14:23:47 volumio-86 volumio[1341]: info: System name has changed, restarting Shairport Sync Apr 27 14:23:47 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 14:23:47 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 14:23:47 volumio-86 volumio[1341]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 14:23:47 volumio-86 volumio[1341]: info: Updating MyVolumio device info Apr 27 14:23:47 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 14:23:47 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 14:23:47 volumio-86 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 14:23:47 volumio-86 vtcs[2224]: [2025-04-27 14:23:47.705] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 27 14:23:47 volumio-86 vtcs[2224]: [2025-04-27 14:23:47.709] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 27 14:23:47 volumio-86 volumio[1341]: info: Discovery: Restarting Advertising due to device name change Apr 27 14:23:47 volumio-86 sudo[2746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 27 14:23:47 volumio-86 sudo[2746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:47 volumio-86 sudo[2748]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 27 14:23:47 volumio-86 sudo[2748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:47 volumio-86 sudo[2751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 27 14:23:47 volumio-86 sudo[2748]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:47 volumio-86 sudo[2751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:47 volumio-86 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 27 14:23:47 volumio-86 systemd[1]: vtcs.service: Killing process 2231 (vtcs) with signal SIGKILL. Apr 27 14:23:47 volumio-86 systemd[1]: vtcs.service: Deactivated successfully. Apr 27 14:23:47 volumio-86 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 27 14:23:47 volumio-86 volumio[1341]: info: Starting Shairport Sync Apr 27 14:23:47 volumio-86 sudo[2755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 27 14:23:47 volumio-86 sudo[2755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:47 volumio-86 sudo[2757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 14:23:47 volumio-86 sudo[2757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:47 volumio-86 sudo[2755]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:47 volumio-86 volumio[1341]: info: Permissions for /etc/hosts set Apr 27 14:23:47 volumio-86 sudo[2751]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:47 volumio-86 sudo[2746]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:47 volumio-86 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 27 14:23:47 volumio-86 volumio[1341]: info: Hostname now is volumio-86x Apr 27 14:23:47 volumio-86 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 27 14:23:47 volumio-86 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 27 14:23:47 volumio-86 systemd[1]: shairport-sync.service: Consumed 3.609s CPU time. Apr 27 14:23:47 volumio-86 sudo[2763]: volumio : unable to resolve host volumio-86: System error Apr 27 14:23:47 volumio-86x systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 27 14:23:47 volumio-86x volumio[1341]: info: New hostname set Apr 27 14:23:47 volumio-86x volumio[1341]: info: Permissions for /etc/avahi/services/volumio.service Apr 27 14:23:47 volumio-86x volumio[1341]: info: Avahi name changed to volumio-86x Apr 27 14:23:47 volumio-86x sudo[2766]: volumio : unable to resolve host volumio-86: System error Apr 27 14:23:47 volumio-86x sudo[2763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio-86x Apr 27 14:23:47 volumio-86x sudo[2763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:47 volumio-86x sudo[2766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Apr 27 14:23:47 volumio-86x sudo[2766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:47 volumio-86x sudo[2763]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:47 volumio-86x sudo[2766]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:47 volumio-86x avahi-daemon[875]: Files changed, reloading. Apr 27 14:23:47 volumio-86x avahi-daemon[875]: Service group file /services/volumio.service changed, reloading. Apr 27 14:23:47 volumio-86x sudo[2757]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:47 volumio-86x volumio[1341]: info: Shairport-Sync Started Apr 27 14:23:48 volumio-86x volumio[1341]: info: Successfully Updated MyVolumio device Apr 27 14:23:48 volumio-86x avahi-daemon[875]: Service "Volumio-86x" (/services/volumio.service) successfully established. Apr 27 14:23:48 volumio-86x volumio[1341]: info: Discovery: A device disappeared from network Apr 27 14:23:48 volumio-86x volumio[1341]: info: Discovery: Device volumio-86 disappeared from network Apr 27 14:23:48 volumio-86x volumio[1341]: info: Discovery: A device disappeared from network Apr 27 14:23:50 volumio-86x volumio[1341]: info: TidalConnect service stoped! Apr 27 14:23:50 volumio-86x volumio[1341]: info: TidalConnect service stoped! Apr 27 14:23:50 volumio-86x volumio[1341]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 27 14:23:50 volumio-86x volumio[1341]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 27 14:23:50 volumio-86x sudo[2793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 27 14:23:50 volumio-86x sudo[2793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:50 volumio-86x systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 27 14:23:50 volumio-86x sudo[2793]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:50 volumio-86x volumio[1341]: info: Executing endpoint tc_getconfig Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 27 14:23:50 volumio-86x vtcs[2796]: STARTING TidalConnect services, version: 1.5.0.45 Apr 27 14:23:50 volumio-86x vtcs[2796]: STARTED TidalConnect services. Apr 27 14:23:50 volumio-86x volumio[1341]: info: Executing endpoint tc_connect Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 27 14:23:50 volumio-86x volumio[1341]: info: Connecting to TidalConnect Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:50 volumio-86x volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:50 volumio-86x volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:50 volumio-86x volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:50 volumio-86x volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:50 volumio-86x volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreCommandRouter::servicePushState Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreStateMachine::pushState Apr 27 14:23:50 volumio-86x volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:50 volumio-86x volumio[1341]: info: CoreCommandRouter::volumioPushState Apr 27 14:23:50 volumio-86x volumio[1341]: info: MRS: Pushing multiroomSync output update for this device Apr 27 14:23:50 volumio-86x volumio[1341]: info: MRS: Pushing multiroomSync output Apr 27 14:23:50 volumio-86x volumio[1341]: info: CorePlayQueue::getTrack 0 Apr 27 14:23:50 volumio-86x volumio[1341]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Apr 27 14:23:50 volumio-86x volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 27 14:23:50 volumio-86x volumio[1341]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 27 14:23:50 volumio-86x sudo[2813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 27 14:23:50 volumio-86x sudo[2813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:50 volumio-86x sudo[2815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 27 14:23:50 volumio-86x sudo[2815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 27 14:23:50 volumio-86x sudo[2815]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:50 volumio-86x volumio[1341]: info: peppymeterbasic Daemon Stop Apr 27 14:23:50 volumio-86x sudo[2813]: pam_unix(sudo:session): session closed for user root Apr 27 14:23:50 volumio-86x volumio[1341]: info: peppymeterbasic Daemon Stop Apr 27 14:23:52 volumio-86x volumio[1341]: info: Discovery: Started advertising with name: Volumio-86x Apr 27 14:23:53 volumio-86x volumio[1341]: compat.c: read() failed: Resource temporarily unavailable Apr 27 14:23:53 volumio-86x volumio[1341]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 14:23:53 volumio-86x volumio[1341]: Error: dns service error: unknown Apr 27 14:23:53 volumio-86x volumio[1341]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Apr 27 14:23:53 volumio-86x volumio[1341]: errorCode: -65537 Apr 27 14:23:53 volumio-86x volumio[1341]: } Apr 27 14:23:53 volumio-86x volumio[1341]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 14:23:53 volumio-86x sudo[2835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-27 14:22' Apr 27 14:23:53 volumio-86x sudo[2835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8a3b6fcf0dbd7d42207332f6d209906dd2daab56" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="0da3792b433b4daf69b90315de2841f333f7f716" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Apr 17 14:34:00 UTC 2025" VOLUMIO_VERSION="0.056" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="5c20cd0f908e7561e67fb76bafe5400c"