-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-06-15 09:16:12 CEST. -- Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:06 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:07 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:08 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:09 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:09 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:09 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:20 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 09:15:20 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 09:15:20 volumio volumio[850]: info: Discovery: Getting this device information Jun 15 09:15:20 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jun 15 09:15:20 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 09:15:30 volumio volumio[850]: info: CALLMETHOD: audio_interface fusiondsp moresettings Jun 15 09:15:30 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , moresettings Jun 15 09:15:30 volumio volumio[850]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 15 09:15:30 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:30 volumio volumio[850]: info: CoreCommandRouter::Close All Modals sent Jun 15 09:15:30 volumio volumio[850]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 15 09:15:30 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:31 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:46 volumio volumio[850]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jun 15 09:15:46 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jun 15 09:15:46 volumio volumio[850]: info: FusionDsp - value distance L------- 0 R 0 Jun 15 09:15:46 volumio volumio[850]: info: FusionDsp - no delay needed Jun 15 09:15:46 volumio volumio[850]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 15 09:15:46 volumio volumio[850]: info: FusionDsp - crossfeed bauer Jun 15 09:15:46 volumio volumio[850]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 15 09:15:47 volumio volumio[850]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 09:15:47 volumio volumio[850]: info: CoreCommandRouter::Close All Modals sent Jun 15 09:15:47 volumio volumio[850]: info: FusionDsp - no preset used Jun 15 09:15:47 volumio volumio[850]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Jun 15 09:15:50 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 09:15:50 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 09:15:50 volumio volumio[850]: info: Discovery: Getting this device information Jun 15 09:15:50 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jun 15 09:15:50 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 09:16:04 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 09:16:04 volumio volumio[850]: info: Received Get System Info Jun 15 09:16:04 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 09:16:04 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 09:16:04 volumio volumio[850]: info: Discovery: Getting this device information Jun 15 09:16:04 volumio volumio[850]: info: CoreCommandRouter::volumioGetState Jun 15 09:16:04 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 09:16:07 volumio volumio[850]: info: Executing endpoint metavolumio Jun 15 09:16:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 09:16:07 volumio volumio[850]: info: Executing endpoint metavolumio Jun 15 09:16:07 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 09:16:10 volumio volumio[850]: info: Preload queue cleared Jun 15 09:16:10 volumio volumio[850]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::ClearQueue Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::stop Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::stPlaybackTimer Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::pushState Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::getTrack 6 Jun 15 09:16:10 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 09:16:10 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::serviceStop Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::getTrack 6 Jun 15 09:16:10 volumio volumio[850]: info: CoreCommandRouter::serviceStop Jun 15 09:16:10 volumio volumio[850]: info: [1749971770959] ControllerTidal::stop Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 09:16:10 volumio volumio[850]: info: ControllerMpd::stop Jun 15 09:16:10 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::clearPlayQueue Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::saveQueue Jun 15 09:16:10 volumio volumio[850]: info: CoreCommandRouter::volumioPushQueue Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::addQueueItems Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::addQueueItems Jun 15 09:16:10 volumio volumio[850]: info: Preload queue cleared Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280190 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280190 Jun 15 09:16:10 volumio volumio[850]: info: CoreCommandRouter::volumioPushQueue Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::saveQueue Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Jun 15 09:16:10 volumio volumio[850]: info: CoreCommandRouter::volumioPlay Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::play index 0 Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 09:16:10 volumio volumio[850]: info: CoreStateMachine::addQueueItems Jun 15 09:16:10 volumio volumio[850]: info: CorePlayQueue::addQueueItems Jun 15 09:16:10 volumio volumio[850]: info: Preload queue cleared Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280191 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280191 Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280192 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280192 Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280193 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280193 Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280194 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280194 Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280195 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280195 Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280196 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280196 Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280197 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280197 Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280198 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280198 Jun 15 09:16:10 volumio volumio[850]: info: Adding Item to queue: tidal://song/57280199 Jun 15 09:16:10 volumio volumio[850]: info: Using cached record of: tidal://song/57280199 Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::stop Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::volumioPushQueue Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::saveQueue Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::play index undefined Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::updateTrackBlock Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrackBlock Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::startPlaybackTimer Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: info: [1749971771008] ControllerTidal::clearAddPlayTrack Jun 15 09:16:11 volumio volumio[850]: info: Getting stream with soundQuality HI_RES Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 09:16:11 volumio volumio[850]: info: [ASDebug] CurState: stop PrevState: play Jun 15 09:16:11 volumio volumio[850]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 15 09:16:11 volumio volumio[850]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand stop took 126 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand stop took 78 milliseconds Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio sudo[5818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 15 09:16:11 volumio sudo[5818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jun 15 09:16:11 volumio volumio[850]: info: Ignoring MPD Status Update Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 11 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand clear took 10 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 10 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 6 milliseconds Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/57280190&soundQuality=HI_RES" Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::pushState Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::pushState Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::pushState Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::pushState Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 15 09:16:11 volumio systemd[1]: Stopping peppymeterbasic Daemon... Jun 15 09:16:11 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 35ms Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 33ms Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jun 15 09:16:11 volumio volumio[850]: info: Ignoring MPD Status Update Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jun 15 09:16:11 volumio volumio[850]: info: Ignoring MPD Status Update Jun 15 09:16:11 volumio volumio[850]: error: updateQueue error: null Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jun 15 09:16:11 volumio volumio[850]: info: Ignoring MPD Status Update Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 70ms Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand playlistinfo took 66 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/57280190&soundQuality=HI_RES" took 66 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 42ms Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 41ms Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand play Jun 15 09:16:11 volumio volumio[850]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 88ms Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jun 15 09:16:11 volumio volumio[850]: info: Ignoring MPD Status Update Jun 15 09:16:11 volumio volumio[850]: STREAMING PROXY: Handling url /?data=tidal://song/57280190&soundQuality=HI_RES Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces system playlist update Jun 15 09:16:11 volumio volumio[850]: info: Ignoring MPD Status Update Jun 15 09:16:11 volumio volumio[850]: info: [ASDebug] CurState: stop PrevState: play Jun 15 09:16:11 volumio volumio[850]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 15 09:16:11 volumio volumio[850]: info: [ASDebug] CurState: stop PrevState: play Jun 15 09:16:11 volumio volumio[850]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 15 09:16:11 volumio systemd[1]: peppymeterbasic.service: Succeeded. Jun 15 09:16:11 volumio systemd[1]: Stopped peppymeterbasic Daemon. Jun 15 09:16:11 volumio volumio[850]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 15 09:16:11 volumio sudo[5818]: pam_unix(sudo:session): session closed for user root Jun 15 09:16:11 volumio volumio[850]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 15 09:16:11 volumio sudo[5823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 15 09:16:11 volumio sudo[5823]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 99ms Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand play took 94 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 91ms Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 90ms Jun 15 09:16:11 volumio sudo[5826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 15 09:16:11 volumio sudo[5826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 09:16:11 volumio volumio[850]: info: peppymeterbasic Daemon Stop Jun 15 09:16:11 volumio volumio[850]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 15 09:16:11 volumio volumio[850]: info: Executing endpoint getStreamUrltidal Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 09:16:11 volumio sudo[5823]: pam_unix(sudo:session): session closed for user root Jun 15 09:16:11 volumio volumio[850]: info: peppymeterbasic Daemon Stop Jun 15 09:16:11 volumio sudo[5826]: pam_unix(sudo:session): session closed for user root Jun 15 09:16:11 volumio volumio[850]: info: peppymeterbasic Daemon Stop Jun 15 09:16:11 volumio volumio[850]: info: getStreamUrl took 402 milliseconds Jun 15 09:16:11 volumio volumio[850]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic4YTZmYjgzMmQ2OTczYWE1YzQ5ZDQ4Mjc3Y2VhNzk5Zl82MS5tcDQ/0.flac?token=1749975153~MDI5MWNkZTY0OGIzZjI4YmY3OGRiZTk4ZGQyNjdmY2MzOTk1YzI5Mg== Jun 15 09:16:11 volumio volumio[850]: STREAMING PROXY: Response: 200, length: 33158449 Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio volumio[850]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 15 09:16:11 volumio volumio[850]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 15 09:16:11 volumio volumio[850]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 15 09:16:11 volumio volumio[850]: info: FusionDsp - crossfeed bauer Jun 15 09:16:11 volumio volumio[850]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 15 09:16:11 volumio volumio[850]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 15 09:16:11 volumio volumio[850]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 15 09:16:11 volumio volumio[850]: info: FusionDsp - crossfeed bauer Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 29 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 26 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 26 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio volumio[850]: info: Jun 15 09:16:11 volumio volumio[850]: ---------------------------- MPD announces state update: player Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::getState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand status Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 24 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 24 milliseconds Jun 15 09:16:11 volumio volumio[850]: info: sendMpdCommand status took 18 milliseconds Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseTrackInfo Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::parseState Jun 15 09:16:11 volumio volumio[850]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::pushState Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57280190&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/57280190&soundQuality=HI_RES","trackType":"tidal"} Jun 15 09:16:11 volumio volumio[850]: verbose: CURRENT POSITION 0 Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus stop Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::pushState Jun 15 09:16:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"505 Kbps","isStreaming":false,"title":"57280190&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/57280190&soundQuality=HI_RES","trackType":"tidal"} Jun 15 09:16:11 volumio volumio[850]: verbose: CURRENT POSITION 0 Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jun 15 09:16:11 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::pushState Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::pushState Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jun 15 09:16:11 volumio volumio[850]: info: ControllerMpd::pushState Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::servicePushState Jun 15 09:16:11 volumio volumio[850]: info: CorePlayQueue::getTrack 0 Jun 15 09:16:11 volumio volumio[850]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":304,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"907 Kbps","isStreaming":false,"title":"57280190&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/57280190&soundQuality=HI_RES","trackType":"tidal"} Jun 15 09:16:11 volumio volumio[850]: verbose: CURRENT POSITION 0 Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::syncState stateService play Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::syncState currentStatus play Jun 15 09:16:11 volumio volumio[850]: info: Received an update from plugin. extracting info from payload Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::pushState Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jun 15 09:16:11 volumio volumio[850]: info: CoreStateMachine::pushState Jun 15 09:16:11 volumio volumio[850]: info: CoreCommandRouter::volumioPushState Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 156ms Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 234ms Jun 15 09:16:11 volumio volumio[850]: info: ------------------------------ 235ms Jun 15 09:16:11 volumio volumio[850]: info: [ASDebug] Togle GPIO: OFF Jun 15 09:16:12 volumio volumio[850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 09:16:12 volumio volumio[850]: Error: ESPIPE: invalid seek, write Jun 15 09:16:12 volumio volumio[850]: at Object.writeSync (fs.js:711:3) Jun 15 09:16:12 volumio volumio[850]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jun 15 09:16:12 volumio volumio[850]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Jun 15 09:16:12 volumio volumio[850]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Jun 15 09:16:12 volumio volumio[850]: at listOnTimeout (internal/timers.js:554:17) Jun 15 09:16:12 volumio volumio[850]: at processTimers (internal/timers.js:497:7) { Jun 15 09:16:12 volumio volumio[850]: errno: -29, Jun 15 09:16:12 volumio volumio[850]: syscall: 'write', Jun 15 09:16:12 volumio volumio[850]: code: 'ESPIPE' Jun 15 09:16:12 volumio volumio[850]: } Jun 15 09:16:12 volumio volumio[850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 09:16:12 volumio volumio[850]: STREAMING PROXY: Client dropped request, destroying Jun 15 09:16:12 volumio sudo[5841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 09:15 Jun 15 09:16:12 volumio sudo[5841]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"